Parent Log: http://ci.aztec-labs.com/7e8907a1cec7b80a Command: 8b0396ebc770da7f:ISOLATE=1:NAME=e2e_keys yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_keys.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/2760296d0584d8606a53f65ef256646fbbf5971a Env: REF_NAME=gh-readonly-queue/next/pr-14684-ddbade1b2c8d0690dec00fc56a87f75bf4b84a04 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Wed Jun 4 00:28:00 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14684_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/c393731fea71056e 00:28:00 +++ id -u 00:28:00 +++ id -g 00:28:00 ++ docker run -d --name e2e_keys --net=none --cpuset-cpus=0-127 --cpus=2 --memory=8g --user 1000:1000 -v/home/aztec-dev:/home/aztec-dev --mount type=tmpfs,target=/tmp,tmpfs-size=1g --workdir /home/aztec-dev/aztec-packages -e HOME -e VERBOSE -e GIT_CONFIG_GLOBAL=/home/aztec-dev/aztec-packages/build-images/src/home/.gitconfig -e FORCE_COLOR=true -e CPUS -e MEM aztecprotocol/build:3.0 /bin/bash -c 'timeout -v 600s bash -c '\''yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_keys.test.ts'\''' 00:28:00 + cid=6b73891d0032581a81af25c91b58ca4b70731b75cb72e326882c126a37ed86bb 00:28:00 + set +x 00:28:01 {"level":25,"time":1748996881404,"pid":12,"hostname":"6b73891d0032","module":"logger","msg":"Logger initialized with level verbose"} 00:28:08 [00:28:08.127] INFO: e2e:e2e_keys Logging metrics to log/unknown.jsonl 00:28:09 [00:28:09.019] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-KIPwSE/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 00:28:09 [00:28:09.079] INFO: e2e:e2e_keys Calling anvil_setBlockTimestampInterval with params: [12] 00:28:09 [00:28:09.111] WARN: e2e:e2e_keys Set block interval to 12 00:28:09 [00:28:09.112] VERBOSE: e2e:e2e_keys Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 00:28:09 [00:28:09.186] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 00:28:09 [00:28:09.198] VERBOSE: e2e:e2e_keys Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 00:28:09 [00:28:09.216] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 00:28:09 [00:28:09.224] VERBOSE: e2e:e2e_keys Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 00:28:09 [00:28:09.278] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x15a910452a602ec46131d746d66411db81d7fc72b90362a00ee4d18cc6abe51b {"gasLimit":3064716,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 00:28:09 [00:28:09.286] VERBOSE: e2e:e2e_keys Deployed GSE at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 00:28:09 [00:28:09.312] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x263f559e4ac0e4916749f1cfef6c86ab177d4ed50b4e9dd5b714b2907c3ea485 {"gasLimit":1014492,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 00:28:09 [00:28:09.319] VERBOSE: e2e:e2e_keys Deployed Registry at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 00:28:09 [00:28:09.352] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x20a9b0b462f62bc3be8ca34c505c34cf2a4c1358e5584da2d561d72abc96aed1 {"gasLimit":1945066,"maxFeePerGas":"3.602461376","maxPriorityFeePerGas":"2.48832"} 00:28:09 [00:28:09.360] VERBOSE: e2e:e2e_keys Deployed GovernanceProposer at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 00:28:09 [00:28:09.380] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x28a12a8c995db07c5f5cafec9b82f038e63dc5e97dcd98daa9cfc9f4a5ab8de7 {"gasLimit":3043143,"maxFeePerGas":"3.968706922","maxPriorityFeePerGas":"2.985984"} 00:28:09 [00:28:09.385] VERBOSE: e2e:e2e_keys Deployed Governance at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 00:28:09 [00:28:09.472] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f {"gasLimit":55548,"maxFeePerGas":"4.456337397","maxPriorityFeePerGas":"3.5831808"} 00:28:09 [00:28:09.472] VERBOSE: e2e:e2e_keys Set governance on GSE in 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f 00:28:09 [00:28:09.498] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x5d35a155eec0ed0c7deff752275abe3c6d5f448014ad234660feb820ef8620df {"gasLimit":391705,"maxFeePerGas":"5.082281352","maxPriorityFeePerGas":"4.29981696"} 00:28:09 [00:28:09.504] VERBOSE: e2e:e2e_keys Deployed CoinIssuer at 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 00:28:09 [00:28:09.504] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 00:28:09 [00:28:09.504] INFO: e2e:e2e_keys Waiting for 7 transactions to be mined... 00:28:09 [00:28:09.541] INFO: e2e:e2e_keys All transactions mined successfully 00:28:09 [00:28:09.560] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed {"gasLimit":56804,"maxFeePerGas":"5.84473853","maxPriorityFeePerGas":"5.159780352"} 00:28:09 [00:28:09.561] VERBOSE: e2e:e2e_keys Added coin issuer 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 as minter on fee asset in 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed 00:28:09 [00:28:09.592] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x8e92b552cc623d62987e85e1df7c096f9eae4836710a4f62deed3668e8d02fa6 {"gasLimit":56661,"maxFeePerGas":"6.792938035","maxPriorityFeePerGas":"6.191736422"} 00:28:09 [00:28:09.608] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x2c50b6224c5aaa3d953e1f1029dcd0ea319c195a3237972ff224e8c29a1434cd {"gasLimit":339691,"maxFeePerGas":"7.956372276","maxPriorityFeePerGas":"7.430083706"} 00:28:09 [00:28:09.615] VERBOSE: e2e:e2e_keys Deployed FeeAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 00:28:09 [00:28:09.629] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c {"gasLimit":56804,"maxFeePerGas":"9.376810034","maxPriorityFeePerGas":"8.916100447"} 00:28:09 [00:28:09.630] VERBOSE: e2e:e2e_keys Added fee asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on fee asset in 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c 00:28:09 [00:28:09.652] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x3bf548a99420047e1164bf2dfa64c5fc4ed32845f7019722b8db00da065120d2 {"gasLimit":1089256,"maxFeePerGas":"11.103528222","maxPriorityFeePerGas":"10.699320536"} 00:28:09 [00:28:09.657] VERBOSE: e2e:e2e_keys Deployed StakingAssetHandler at 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 00:28:09 [00:28:09.672] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 {"gasLimit":56804,"maxFeePerGas":"13.193025819","maxPriorityFeePerGas":"12.839184643"} 00:28:09 [00:28:09.673] VERBOSE: e2e:e2e_keys Added staking asset handler 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 as minter on staking asset in 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 00:28:09 [00:28:09.673] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 00:28:09 [00:28:09.673] INFO: e2e:e2e_keys Waiting for 9 transactions to be mined... 00:28:09 [00:28:09.703] INFO: e2e:e2e_keys All transactions mined successfully 00:28:09 [00:28:09.779] VERBOSE: e2e:e2e_keys Deployed shared contracts 00:28:09 [00:28:09.819] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 {"gasLimit":84837,"maxFeePerGas":"15.719309155","maxPriorityFeePerGas":"15.407021571"} 00:28:09 [00:28:09.820] VERBOSE: e2e:e2e_keys Funded reward distributor with 10000000000000000000000000 fee asset in 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 00:28:09 [00:28:09.826] VERBOSE: e2e:e2e_keys Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 00:28:09 [00:28:09.849] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x4b00bf010016af1a686fa82a49ffcd696fe6567f2fafe88a5077a3196ed3622b {"gasLimit":86397,"maxFeePerGas":"18.761800709","maxPriorityFeePerGas":"18.488425885"} 00:28:09 [00:28:09.921] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x5ab770e71a294d59a299893ceef963b3326d216a75236785d0f3ad5811b5880e {"gasLimit":6285180,"maxFeePerGas":"22.425475094","maxPriorityFeePerGas":"22.186111062"} 00:28:09 [00:28:09.931] VERBOSE: e2e:e2e_keys Awaiting for linked libraries to be deployed 00:28:10 [00:28:10.006] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xd0725689e259697044abb17ed2022f745b480a4cf5e1d374109e8159d943060d {"gasLimit":10716795,"maxFeePerGas":"26.832920416","maxPriorityFeePerGas":"26.623333274"} 00:28:10 [00:28:10.017] VERBOSE: e2e:e2e_keys Deployed Rollup at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 00:28:10 [00:28:10.018] INFO: e2e:e2e_keys Waiting for 10 transactions to be mined... 00:28:10 [00:28:10.055] INFO: e2e:e2e_keys All transactions mined successfully 00:28:10 [00:28:10.056] VERBOSE: e2e:e2e_keys All core contracts have been deployed 00:28:10 [00:28:10.075] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a {"gasLimit":64317,"maxFeePerGas":"32.140536546","maxPriorityFeePerGas":"31.947999928"} 00:28:10 [00:28:10.076] VERBOSE: e2e:e2e_keys Funding fee juice portal with 10000000000000000000000 fee juice in 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a (accelerated test deployments) 00:28:10 [00:28:10.103] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xd413488be28de232ee67a932daca30e5e7bc4e1ad751dedc3910bbbe185aafe4 {"gasLimit":1040116,"maxFeePerGas":"38.520398451","maxPriorityFeePerGas":"38.337599913"} 00:28:10 [00:28:10.114] VERBOSE: e2e:e2e_keys Deployed SlashFactory at 0x3aa5ebb10dc797cac828524e59a333d0a371443c 00:28:10 [00:28:10.170] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x9cf2e8cc19e5a14c92d6ffc7b824d5af7abcd5e99d83ce8f9d8f18174a0f48ec {"gasLimit":116846,"maxFeePerGas":"46.165150264","maxPriorityFeePerGas":"46.005119895"} 00:28:10 [00:28:10.171] VERBOSE: e2e:e2e_keys Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to registry 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 in tx 0x9cf2e8cc19e5a14c92d6ffc7b824d5af7abcd5e99d83ce8f9d8f18174a0f48ec 00:28:10 [00:28:10.195] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x2a5b9e16c5abd0a75879e886c16c1174a5bec7f4574004840187d6fdb1602285 {"gasLimit":109478,"maxFeePerGas":"55.34732635","maxPriorityFeePerGas":"55.206143874"} 00:28:10 [00:28:10.195] VERBOSE: e2e:e2e_keys Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to GSE 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x2a5b9e16c5abd0a75879e886c16c1174a5bec7f4574004840187d6fdb1602285 00:28:10 [00:28:10.195] INFO: e2e:e2e_keys Waiting for 11 transactions to be mined... 00:28:10 [00:28:10.235] INFO: e2e:e2e_keys All transactions mined successfully 00:28:10 [00:28:10.249] VERBOSE: e2e:e2e_keys Rollup deployed 00:28:10 [00:28:10.249] VERBOSE: e2e:e2e_keys Waiting for rollup and slash factory to be deployed 00:28:10 [00:28:10.250] INFO: e2e:e2e_keys Waiting for 11 transactions to be mined... 00:28:10 [00:28:10.283] INFO: e2e:e2e_keys All transactions mined successfully 00:28:10 [00:28:10.284] VERBOSE: e2e:e2e_keys All transactions for L1 deployment have been mined 00:28:10 [00:28:10.311] INFO: e2e:e2e_keys Aztec L1 contracts initialized {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","inboxAddress":"0xd2e813c297f3cf9985baa5331cec80778755bf2a","outboxAddress":"0x9cba1bd8bfe8f5376d45f0ba25287c16fdbe0268","feeJuicePortalAddress":"0x6034162d05782a3e836948e76bb048e3bbda726c","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 00:28:10 [00:28:10.312] INFO: e2e:e2e_keys Handing over to governance 00:28:10 [00:28:10.319] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 00:28:10 [00:28:10.321] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:10 [00:28:10.323] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x9A9f2CCfdE556A7E9Ff0848998Aa4a0CFD8863AE 00:28:10 [00:28:10.324] INFO: telemetry:client Using NoopTelemetryClient 00:28:10 [00:28:10.332] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 00:28:10 [00:28:10.332] WARN: foundation:test-date-provider Time set to 2025-06-04T00:32:32.000Z {"offset":261668,"timeMs":1748997152000} 00:28:10 [00:28:10.333] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/5b1241c00128031d/blob-sink with map size 134217728 KB (LMDB v2) 00:28:10 [00:28:10.355] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 00:28:10 [00:28:10.363] INFO: blob-sink:server Server is running on http://localhost:40233 00:28:10 [00:28:10.364] VERBOSE: e2e:e2e_keys Creating and synching an aztec node... 00:28:10 [00:28:10.372] VERBOSE: e2e:e2e_keys Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/381e6eee/acvm 00:28:10 [00:28:10.375] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:40233"} 00:28:10 [00:28:10.424] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 00:28:10 [00:28:10.426] INFO: archiver:lmdb Creating archiver data store at directory /tmp/5b1241c00128031d/archiver with map size 134217728 KB (LMDB v2) 00:28:10 [00:28:10.430] INFO: archiver:lmdb Starting data store with maxReaders 16 00:28:10 [00:28:10.715] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:10 [00:28:10.932] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:11 [00:28:11.097] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:40233"} 00:28:11 [00:28:11.133] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:40233"} 00:28:11 [00:28:11.134] WARN: node:blob-sink:client No L1 consensus host urls configured 00:28:11 [00:28:11.134] WARN: node:blob-sink:client No archive client configured 00:28:11 [00:28:11.154] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:11 [00:28:11.163] INFO: archiver Starting archiver sync to rollup contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae from L1 block 18 to current L1 block 22 with hash 0xc40aa1d596805fa9e2e7bd8ebfaf285d611ec47d4cd4e5cec984076961e48b1d {"blocksSynchedTo":18,"messagesSynchedTo":{"l1BlockHash":"0x15c453eda12b061ed66365fdeef3e43a18c265c7a69d8bc96e71b117b26d2a27","l1BlockNumber":18}} 00:28:11 [00:28:11.225] INFO: archiver Initial archiver sync to L1 block 22 complete. {"l1BlockNumber":22,"syncPoint":{"blocksSynchedTo":22,"messagesSynchedTo":{"l1BlockHash":"0xc40aa1d596805fa9e2e7bd8ebfaf285d611ec47d4cd4e5cec984076961e48b1d","l1BlockNumber":22}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 00:28:11 [00:28:11.243] INFO: world-state:database Creating world state data store at directory /tmp/5b1241c00128031d/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 00:28:11 [00:28:11.275] INFO: world_state Created world state synchroniser with block history of 64 00:28:11 [00:28:11.275] WARN: node Aztec node is accepting fake proofs 00:28:11 [00:28:11.290] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/5b1241c00128031d/p2p with map size 134217728 KB (LMDB v2) 00:28:11 [00:28:11.300] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 00:28:11 [00:28:11.304] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/5b1241c00128031d/p2p-archive with map size 134217728 KB (LMDB v2) 00:28:11 [00:28:11.307] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 00:28:11 [00:28:11.309] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/5b1241c00128031d/p2p-peers with map size 134217728 KB (LMDB v2) 00:28:11 [00:28:11.311] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 00:28:11 [00:28:11.313] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 00:28:11 [00:28:11.314] VERBOSE: p2p P2P is disabled. Using dummy P2P service 00:28:11 [00:28:11.317] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 00:28:11 [00:28:11.319] INFO: world_state Started world state synchronizer from block 1 00:28:11 [00:28:11.322] VERBOSE: world-state:block_stream Starting sync from block number 0 00:28:11 [00:28:11.325] INFO: p2p Starting p2p client from block 0 with empty mempools 00:28:11 [00:28:11.325] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 00:28:11 [00:28:11.326] INFO: epoch-prune-watcher EpochPruneWatcher initialized 00:28:11 [00:28:11.336] INFO: slasher Starting Slasher client... 00:28:11 [00:28:11.403] VERBOSE: validator Initialized validator with addresses: 0x2b9b0ca1058743b69df5296b61331bb6a0f8f4c5 00:28:11 [00:28:11.404] VERBOSE: node All Aztec Node subsystems synced 00:28:11 [00:28:11.423] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:11 [00:28:11.457] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 00:28:11 [00:28:11.504] INFO: sequencer-client Deploying forwarder contract 00:28:11 [00:28:11.665] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:11 [00:28:11.703] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 00:28:11 [00:28:11.708] WARN: foundation:test-date-provider Time set to 2025-06-04T00:32:44.000Z {"offset":272292,"timeMs":1748997164000} 00:28:11 [00:28:11.710] VERBOSE: sequencer-client Sent L1 transaction 0x602f411c9ae84deb9da90dad04b3e6b54d91158c82d01934d2b760f3de926311 {"gasLimit":323704,"maxFeePerGas":"66.371021876","maxPriorityFeePerGas":"66.247372648"} 00:28:11 [00:28:11.711] VERBOSE: sequencer-client Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000042 to address 0x83ac846879C81f414bDf47961839F4E842ead017 in tx 0x602f411c9ae84deb9da90dad04b3e6b54d91158c82d01934d2b760f3de926311. 00:28:11 [00:28:11.741] INFO: sequencer-client Forwarder contract deployed at 0x83ac846879c81f414bdf47961839f4e842ead017 00:28:11 [00:28:11.768] INFO: validator Started validator with addresses: 00:28:11 0x2b9b0ca1058743b69df5296b61331bb6a0f8f4c5 00:28:11 [00:28:11.769] VERBOSE: validator Validators 0x2b9b0ca1058743b69df5296b61331bb6a0f8f4c5 are not on the validator committee for epoch 0 00:28:11 [00:28:11.770] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/381e6eee/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 00:28:12 [00:28:12.129] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 00:28:12 [00:28:12.132] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 00:28:12 [00:28:12.134] INFO: node Aztec Node version: 0.87.6 00:28:12 [00:28:12.134] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","inboxAddress":"0xd2e813c297f3cf9985baa5331cec80778755bf2a","outboxAddress":"0x9cba1bd8bfe8f5376d45f0ba25287c16fdbe0268","feeJuicePortalAddress":"0x6034162d05782a3e836948e76bb048e3bbda726c","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x3aa5ebb10dc797cac828524e59a333d0a371443c","feeAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788","stakingAssetHandlerAddress":"0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0"} 00:28:12 [00:28:12.136] VERBOSE: e2e:e2e_keys Creating a pxe... 00:28:12 [00:28:12.143] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:12 [00:28:12.197] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/54416b2b3bd70560/pxe_data with map size 134217728 KB (LMDB v2) 00:28:12 [00:28:12.240] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x265f5ae5eaabc7b9c8342215ba0e536fb1da85442c7c0c17f19ad9f7ed96384a","blockNumber":1,"slot":2} 00:28:12 [00:28:12.249] INFO: pxe:data:lmdb Starting data store with maxReaders 16 00:28:12 [00:28:12.396] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:12 [00:28:12.616] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:12 [00:28:12.669] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 00:28:12 [00:28:12.671] INFO: pxe:service Started PXE connected to chain 31337 version 2214272623 00:28:12 [00:28:12.823] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x265f5ae5eaabc7b9c8342215ba0e536fb1da85442c7c0c17f19ad9f7ed96384a","blockNumber":1,"slot":2} 00:28:12 [00:28:12.835] INFO: pxe:service Registered account 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b 00:28:12 [00:28:12.937] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:12 [00:28:12.941] WARN: pxe:service No artifact found for contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a when looking for its metadata 00:28:12 [00:28:12.944] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a as part of deployment for 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b 00:28:13 [00:28:13.172] INFO: pxe:service Added contract SchnorrAccount at 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b with class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 00:28:13 [00:28:13.174] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:13 [00:28:13.232] VERBOSE: pxe:block_stream Starting sync from block number 0 00:28:13 [00:28:13.301] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 00:28:13 [00:28:13.384] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:13 [00:28:13.402] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x265f5ae5eaabc7b9c8342215ba0e536fb1da85442c7c0c17f19ad9f7ed96384a","blockNumber":1,"slot":2} 00:28:13 [00:28:13.474] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 00:28:14 [00:28:14.364] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a,0x2f6652d5f6110255f6132bc8be0dd74c664523f8808ebbc3eb467252a0b5e74a,0x1d9edc9e571a9e7ab4d2aa454dd024ee819579e3e22ae0fadf28089b29f3f0bc,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 00:28:14 [00:28:14.555] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 00:28:14 [00:28:14.684] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:14 [00:28:14.724] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x265f5ae5eaabc7b9c8342215ba0e536fb1da85442c7c0c17f19ad9f7ed96384a","blockNumber":1,"slot":2} 00:28:14 [00:28:14.887] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:14 [00:28:14.925] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:14 [00:28:14.936] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:14 [00:28:14.938] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:15 [00:28:15.014] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:15 [00:28:15.019] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:15 [00:28:15.140] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:15 [00:28:15.278] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:15 [00:28:15.341] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:15 [00:28:15.343] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:15 [00:28:15.362] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:15 [00:28:15.366] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x265f5ae5eaabc7b9c8342215ba0e536fb1da85442c7c0c17f19ad9f7ed96384a","blockNumber":1,"slot":2} 00:28:15 [00:28:15.433] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:15 [00:28:15.437] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:15 [00:28:15.459] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b as fee payer 00:28:15 [00:28:15.668] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:15 [00:28:15.977] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:16 [00:28:16.131] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x265f5ae5eaabc7b9c8342215ba0e536fb1da85442c7c0c17f19ad9f7ed96384a","blockNumber":1,"slot":2} 00:28:16 [00:28:16.263] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:16 [00:28:16.975] INFO: pxe:service Proving completed in 3756.3724810000003ms {"timings":{"total":3756.3724810000003,"sync":13.895211000000927,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":236.74632899999597,"oracles":{"callPrivateFunction":{"times":[1285.9235229999995,499.45606900000166,322.8870590000006]}}},{"functionName":"private_kernel_init","time":104.11373399999866},{"functionName":"ContractClassRegisterer:register","time":1254.5152320000016,"oracles":{"loadCapsule":{"times":[17.60145300000113]},"notifyCreatedNullifier":{"times":[25.44420700000046]},"debugLog":{"times":[1.3751979999997275]},"notifyCreatedContractClassLog":{"times":[16.155783000000156]}}},{"functionName":"private_kernel_inner","time":187.52916000000005},{"functionName":"SchnorrAccount:constructor","time":461.396216000001,"oracles":{"getContractInstance":{"times":[6.03720500000054]},"debugLog":{"times":[0.7899710000001505,0.5877260000015667]},"fetchTaggedLogs":{"times":[80.21945899999992]},"loadCapsule":{"times":[0.5546549999999115,0.3318790000012086,0.30813800000032643]},"validateEnqueuedNotes":{"times":[1.7453580000001239]},"notifyCreatedNullifier":{"times":[0.30989799999952083,0.36334999999962747]},"notifyCreatedNote":{"times":[0.6269370000009076]},"getRandomField":{"times":[0.4289219999991474,0.2677770000009332,0.131493999999293,0.08099199999924167,0.07403200000044308,0.07660199999918405,0.10174299999926006,0.05116199999974924,0.06605099999978847,0.12002299999949173,0.09829199999876437]},"getIndexedTaggingSecretAsSender":{"times":[46.55103599999893]},"incrementAppTaggingSecretIndexAsSender":{"times":[51.81214]}}},{"functionName":"private_kernel_inner","time":120.27534600000035},{"functionName":"SchnorrAccount:entrypoint","time":285.06227299999955,"oracles":{"debugLog":{"times":[1.739317999999912,0.521443999999974,0.972157000000152]},"fetchTaggedLogs":{"times":[92.74871200000052]},"loadCapsule":{"times":[0.36124000000017986,0.2583680000007007,0.2554869999985385]},"validateEnqueuedNotes":{"times":[1.0550289999991946]},"getNotes":{"times":[3.2167480000007345]},"getAuthWitness":{"times":[0.49210300000049756]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.4607900000009977]}}},{"functionName":"private_kernel_inner","time":285.07081300000027},{"functionName":"private_kernel_reset","time":233.1866310000005},{"functionName":"private_kernel_tail","time":92.38144199999988}],"unaccounted":482.2000940000016}} 00:28:17 [00:28:16.997] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:17 [00:28:17.110] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x265f5ae5eaabc7b9c8342215ba0e536fb1da85442c7c0c17f19ad9f7ed96384a","blockNumber":1,"slot":2} 00:28:17 [00:28:17.121] VERBOSE: p2p:tx_pool Adding tx 0x12d5a9c9e81502a7847ee19f9963f5b894d71a47664e4148d9bb7937da337084 to pool {"eventName":"tx-added-to-pool","txHash":"0x12d5a9c9e81502a7847ee19f9963f5b894d71a47664e4148d9bb7937da337084","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 00:28:17 [00:28:17.143] INFO: node Received tx 0x12d5a9c9e81502a7847ee19f9963f5b894d71a47664e4148d9bb7937da337084 {"txHash":"0x12d5a9c9e81502a7847ee19f9963f5b894d71a47664e4148d9bb7937da337084"} 00:28:17 [00:28:17.144] INFO: pxe:service Sent transaction 0x12d5a9c9e81502a7847ee19f9963f5b894d71a47664e4148d9bb7937da337084 00:28:17 [00:28:17.211] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:17 [00:28:17.416] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:17 [00:28:17.639] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:17 [00:28:17.697] VERBOSE: sequencer:block-builder Building block 1 for slot 2 {"slot":2,"slotStart":"2025-06-04T00:32:56.000Z","now":"2025-06-04T00:32:49.989Z","blockNumber":1,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x1e3aef33989764dcfb6810f6472a042297977115273a0fdb35027f7ea637b996"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 00:28:17 [00:28:17.794] VERBOSE: simulator:public-processor Processed tx 0x12d5a9c9e81502a7847ee19f9963f5b894d71a47664e4148d9bb7937da337084 with no public calls in 40.551290999999765ms {"txHash":"0x12d5a9c9e81502a7847ee19f9963f5b894d71a47664e4148d9bb7937da337084","txFee":2101212240,"revertCode":0,"gasUsed":{"totalGas":{"daGas":16896,"l2Gas":6324},"billedGas":{"daGas":16896,"l2Gas":6324},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":3,"noteHashCount":1,"contractClassLogCount":1,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":40.551290999999765} 00:28:17 [00:28:17.796] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.08451555700000245s {"duration":0.08451555700000245,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 00:28:17 [00:28:17.905] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:17 [00:28:17.921] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1f7c554123bc02fa0f6016c98a2f02080105d95f35641a6b2d6d19bba53088a3","globalVariables":{"chainId":31337,"version":2214272623,"blockNumber":1,"slotNumber":2,"timestamp":1748997176,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x12d5a9c9e81502a7847ee19f9963f5b894d71a47664e4148d9bb7937da337084"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":239.89493399999992,"publicProcessDuration":86.05211899999995,"rollupCircuitsDuration":224.67899800000305,"txCount":1,"blockNumber":1,"blockTimestamp":1748997176,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 00:28:17 [00:28:17.924] VERBOSE: sequencer Attesting committee is empty 00:28:18 [00:28:18.112] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:18 [00:28:18.366] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:18 [00:28:18.409] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x10b9a70e21c028535ca373488452c89a364b7e2e97ad0eba02a0d76073b6cdfb 00:28:18 [00:28:18.410] VERBOSE: node Sent L1 transaction 0x10b9a70e21c028535ca373488452c89a364b7e2e97ad0eba02a0d76073b6cdfb {"gasLimit":555073,"maxFeePerGas":"79.605134257","maxPriorityFeePerGas":"79.496847177","maxFeePerBlobGas":"0.000000001"} 00:28:18 [00:28:18.437] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 00:28:18 [00:28:18.444] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1748997212] on http://127.0.0.1:8545 00:28:18 [00:28:18.446] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 00:28:18 [00:28:18.456] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 00:28:18 [00:28:18.464] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x10b9a70e21c028535ca373488452c89a364b7e2e97ad0eba02a0d76073b6cdfb","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":1,"blockTimestamp":1748997176,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 00:28:18 [00:28:18.465] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 00:28:18 [00:28:18.470] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 00:28:18 [00:28:18.472] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 00:28:18 [00:28:18.474] WARN: ethereum:cheat_codes Warped L1 timestamp to 1748997212 00:28:18 [00:28:18.474] WARN: foundation:test-date-provider Time set to 2025-06-04T00:33:32.000Z {"offset":313526,"timeMs":1748997212000} 00:28:18 [00:28:18.480] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 00:28:18 [00:28:18.547] INFO: blob-sink:server Received blob sidecar for block 0x7f5b6fe93b70fe533067f94e781cf387916c544bf7520c7240bf05b1e2da5c75 00:28:18 [00:28:18.574] INFO: blob-sink:server Blob sidecar stored successfully for block 0x7f5b6fe93b70fe533067f94e781cf387916c544bf7520c7240bf05b1e2da5c75 00:28:18 [00:28:18.594] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:18 [00:28:18.839] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:18 [00:28:18.872] VERBOSE: archiver:block-helper Store contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 00:28:18 [00:28:18.899] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x1f7c554123bc02fa0f6016c98a2f02080105d95f35641a6b2d6d19bba53088a3","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":2214272623,"blockNumber":1,"slotNumber":2,"timestamp":1748997176,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","archiveNextLeafIndex":2} 00:28:18 [00:28:18.906] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 00:28:19 [00:28:19.411] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:19 [00:28:19.415] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":452.0884210000004,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1748997176,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 00:28:19 [00:28:19.417] WARN: pxe:service No artifact found for contract class 0x062b4261da61b756bcd196c172c777b3f2bcffc41cd5109b19e5b422753e5a4a when looking for its metadata 00:28:19 [00:28:19.422] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x062b4261da61b756bcd196c172c777b3f2bcffc41cd5109b19e5b422753e5a4a as part of deployment for 0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b 00:28:19 [00:28:19.708] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:19 [00:28:19.917] VERBOSE: p2p Synched to latest block 1 00:28:19 [00:28:19.961] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:20 [00:28:20.389] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:20 [00:28:20.414] INFO: pxe:service Added contract Test at 0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b with class 0x062b4261da61b756bcd196c172c777b3f2bcffc41cd5109b19e5b422753e5a4a 00:28:20 [00:28:20.432] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","blockNumber":2,"slot":3} 00:28:20 [00:28:20.464] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","header":{"contentCommitment":{"blobsHash":"0x006106f85eeb7e9e14983b76d1511bda65278071d4d9e0e9fc1d8b219a2728c5","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1748997176,"version":2214272623},"lastArchive":"0x265f5ae5eaabc7b9c8342215ba0e536fb1da85442c7c0c17f19ad9f7ed96384a","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x1dad6a67e7cd2745fa055ff97b3ee3564bbd9bccc8b6cbdf0176cd90fbe72bd8","nullifierTree":"0x1dfb7d41fee076cc5ae7e8e5126c1baa5693b1a7429a97d27ff5a5b1823daa05","publicDataTree":"0x2898a83823096d508f1193b38b726ba13071e60ee70f4c4407d703c480de8b9a"},"totalFees":2101212240,"totalManaUsed":6324}} 00:28:20 [00:28:20.634] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:20 [00:28:20.723] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:20 [00:28:20.724] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:20 [00:28:20.732] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:20 [00:28:20.833] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:20 [00:28:20.845] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x08bceeb2b2986852b2181064fd6abdbd39feed600f4db5ea8b7f96e33512504d 00:28:20 [00:28:20.865] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 00:28:20 [00:28:20.866] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 00:28:20 [00:28:20.876] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 00:28:20 [00:28:20.877] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 00:28:20 [00:28:20.883] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:20 [00:28:20.897] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1925c8cda2f8e79b6ad835313af2e00a0ebcf33d75b1ad42d03960b035f85943","nullifier":"0x2fc48506de58d1daabc66909d52e7406d17798cbee0351fdb5e918cbce6ad0e2"} 00:28:20 [00:28:20.918] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b as fee payer 00:28:20 [00:28:20.958] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:20 [00:28:20.975] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 00:28:21 [00:28:21.565] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x062b4261da61b756bcd196c172c777b3f2bcffc41cd5109b19e5b422753e5a4a,0x0fc37f07afee358f5bc0ced5768f1539f249742c094e9ca294ba80935f607832,0x2e5781bd7148d1ff1ed0540f5779b5c104613b3d4cdf032e4674ab68843fb124,0x20667cb04b0e683bcbd51294b34aad45f80c87f231c67434b5e853f817a3a838 00:28:21 [00:28:21.715] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 00:28:21 [00:28:21.862] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","blockNumber":2,"slot":3} 00:28:21 [00:28:21.946] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 00:28:22 [00:28:22.024] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 00:28:22 [00:28:22.040] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b,0x0000000000000000000000000000000000000000000000000000000000000001,0x2623287167abdcc71f87bdc78c04761ff57ded2a396c72a2c21baf3ef0a68d2c,0x062b4261da61b756bcd196c172c777b3f2bcffc41cd5109b19e5b422753e5a4a,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b 00:28:22 [00:28:22.046] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:22 [00:28:22.263] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:22 [00:28:22.475] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:22 [00:28:22.912] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:22 [00:28:22.947] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:22 [00:28:22.947] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:22 [00:28:22.976] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","blockNumber":2,"slot":3} 00:28:23 [00:28:23.150] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:23 [00:28:23.194] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:23 [00:28:23.197] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:23 [00:28:23.395] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:23 [00:28:23.524] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","blockNumber":2,"slot":3} 00:28:23 [00:28:23.772] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:24 [00:28:24.143] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:24 [00:28:24.282] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","blockNumber":2,"slot":3} 00:28:24 [00:28:24.475] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:24 [00:28:24.954] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:25 [00:28:25.293] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","blockNumber":2,"slot":3} 00:28:25 [00:28:25.687] INFO: pxe:service Proving completed in 5235.921490000001ms {"timings":{"total":5235.921490000001,"sync":15.55993700000181,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":939.1920549999995,"oracles":{"debugLog":{"times":[0.6596480000007432,0.6775190000007569,0.6178059999983816,0.6761390000028769,0.5637949999982084,0.5149439999986498,0.6533380000000761,0.7261299999991024]},"fetchTaggedLogs":{"times":[117.68095099999846]},"loadCapsule":{"times":[0.3832609999990382,0.9109950000020035,1.0212879999999132,0.6433780000006664,0.3081480000000738,0.36986000000251806,0.3476890000019921]},"getSharedSecret":{"times":[8.41382099999828]},"aes128Decrypt":{"times":[1.617633999998361,1.2569650000004913]},"getPublicKeysAndPartialAddress":{"times":[2.3418040000033216]},"getKeyValidationRequest":{"times":[2.7816359999997076]},"storeCapsule":{"times":[1.1154109999988577,2.173419999999169,0.5390850000003411]},"deleteCapsule":{"times":[0.5091240000001562]},"validateEnqueuedNotes":{"times":[15.010831999999937]},"getNotes":{"times":[3.2161989999985963]},"getAuthWitness":{"times":[0.2709469999972498]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.08252200000060839]},"callPrivateFunction":{"times":[883.2033870000014,239.15744600000107,1157.896166999999]}}},{"functionName":"private_kernel_init","time":81.25544000000082},{"functionName":"ContractClassRegisterer:register","time":856.0970619999971,"oracles":{"loadCapsule":{"times":[5.726796999999351]},"notifyCreatedNullifier":{"times":[0.5454349999999977]},"debugLog":{"times":[0.7511599999997998]},"notifyCreatedContractClassLog":{"times":[12.016940000001341]}}},{"functionName":"private_kernel_inner","time":238.64887100000124},{"functionName":"ContractInstanceDeployer:deploy","time":157.78966100000252,"oracles":{"storeInExecutionCache":{"times":[0.7171699999998964]},"callPrivateFunction":{"times":[78.31576000000132]},"notifyCreatedNullifier":{"times":[0.38660000000163564]},"debugLog":{"times":[0.7373000000006869]}}},{"functionName":"private_kernel_inner","time":169.79008199999953},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":52.595453999998426,"oracles":{}},{"functionName":"private_kernel_inner","time":184.14867500000037},{"functionName":"Test:initialize","time":703.2634760000001,"oracles":{"getContractInstance":{"times":[11.823165000001609]},"debugLog":{"times":[0.6788589999996475,0.391340999998647]},"fetchTaggedLogs":{"times":[248.81606100000135]},"loadCapsule":{"times":[0.23208700000031968,0.261687999998685,0.19720499999675667]},"validateEnqueuedNotes":{"times":[0.915965000000142]},"notifyCreatedNullifier":{"times":[0.16105400000014924]}}},{"functionName":"private_kernel_inner","time":118.291118000001},{"functionName":"private_kernel_reset","time":188.03416199999992},{"functionName":"private_kernel_tail","time":77.51991900000212}],"unaccounted":1453.7355779999962}} 00:28:25 [00:28:25.705] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:25 [00:28:25.832] VERBOSE: p2p:tx_pool Adding tx 0x29e63c6e359940883b1bb1d71790513cea1d95af03bd8d756c10adf770d8213f to pool {"eventName":"tx-added-to-pool","txHash":"0x29e63c6e359940883b1bb1d71790513cea1d95af03bd8d756c10adf770d8213f","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":566,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 00:28:25 [00:28:25.857] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","blockNumber":2,"slot":3} 00:28:25 [00:28:25.858] INFO: node Received tx 0x29e63c6e359940883b1bb1d71790513cea1d95af03bd8d756c10adf770d8213f {"txHash":"0x29e63c6e359940883b1bb1d71790513cea1d95af03bd8d756c10adf770d8213f"} 00:28:25 [00:28:25.858] INFO: pxe:service Sent transaction 0x29e63c6e359940883b1bb1d71790513cea1d95af03bd8d756c10adf770d8213f 00:28:25 [00:28:25.912] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:26 [00:28:26.116] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:26 [00:28:26.319] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:26 [00:28:26.385] VERBOSE: sequencer:block-builder Building block 2 for slot 3 {"slot":3,"slotStart":"2025-06-04T00:33:32.000Z","now":"2025-06-04T00:33:39.911Z","blockNumber":2,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x1e3aef33989764dcfb6810f6472a042297977115273a0fdb35027f7ea637b996"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 00:28:26 [00:28:26.502] VERBOSE: simulator:public-processor Processed tx 0x29e63c6e359940883b1bb1d71790513cea1d95af03bd8d756c10adf770d8213f with no public calls in 61.61553200000344ms {"txHash":"0x29e63c6e359940883b1bb1d71790513cea1d95af03bd8d756c10adf770d8213f","txFee":2176967520,"revertCode":0,"gasUsed":{"totalGas":{"daGas":301568,"l2Gas":6552},"billedGas":{"daGas":301568,"l2Gas":6552},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":4,"noteHashCount":0,"contractClassLogCount":1,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":61.61553200000344} 00:28:26 [00:28:26.504] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.11478692099999899s {"duration":0.11478692099999899,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":301568,"l2Gas":6552},"totalSizeInBytes":19104} 00:28:26 [00:28:26.608] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:26 [00:28:26.620] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x18b5e649d3f257ae7b370868198a1ce04f76f8fae431c1cffc239c04a6d6c23d","globalVariables":{"chainId":31337,"version":2214272623,"blockNumber":2,"slotNumber":3,"timestamp":1748997212,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x29e63c6e359940883b1bb1d71790513cea1d95af03bd8d756c10adf770d8213f"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":242.60311999999976,"publicProcessDuration":115.25726399999985,"rollupCircuitsDuration":235.50542499999938,"txCount":1,"blockNumber":2,"blockTimestamp":1748997212,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":566} 00:28:26 [00:28:26.623] VERBOSE: sequencer Attesting committee is empty 00:28:26 [00:28:26.814] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:27 [00:28:27.024] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x28bd051adba9c8e434f0e471fc1890121ab499485abbb4df2744cbf35ecaaf0b 00:28:27 [00:28:27.024] VERBOSE: node Sent L1 transaction 0x28bd051adba9c8e434f0e471fc1890121ab499485abbb4df2744cbf35ecaaf0b {"gasLimit":475198,"maxFeePerGas":"1.283442096","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 00:28:27 [00:28:27.028] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:27 [00:28:27.040] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 00:28:27 [00:28:27.040] WARN: foundation:test-date-provider Time set to 2025-06-04T00:33:44.000Z {"offset":316960,"timeMs":1748997224000} 00:28:27 [00:28:27.042] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 00:28:27 [00:28:27.046] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 00:28:27 [00:28:27.049] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1748997248] on http://127.0.0.1:8545 00:28:27 [00:28:27.051] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 00:28:27 [00:28:27.052] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 00:28:27 [00:28:27.056] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 00:28:27 [00:28:27.057] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x28bd051adba9c8e434f0e471fc1890121ab499485abbb4df2744cbf35ecaaf0b","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":2,"blockTimestamp":1748997212,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":566} 00:28:27 [00:28:27.057] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 00:28:27 [00:28:27.061] WARN: ethereum:cheat_codes Warped L1 timestamp to 1748997248 00:28:27 [00:28:27.062] WARN: foundation:test-date-provider Time set to 2025-06-04T00:34:08.000Z {"offset":340938,"timeMs":1748997248000} 00:28:27 [00:28:27.062] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 00:28:27 [00:28:27.070] INFO: blob-sink:server Received blob sidecar for block 0xa7deab04f2c0a61a0294762dca9086a205411feac80b1c84a1fee04e118701f4 00:28:27 [00:28:27.078] INFO: blob-sink:server Blob sidecar stored successfully for block 0xa7deab04f2c0a61a0294762dca9086a205411feac80b1c84a1fee04e118701f4 00:28:27 [00:28:27.252] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:27 [00:28:27.272] VERBOSE: archiver:block-helper Store contract instance at 0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b 00:28:27 [00:28:27.302] VERBOSE: archiver:block-helper Store contract class 0x062b4261da61b756bcd196c172c777b3f2bcffc41cd5109b19e5b422753e5a4a 00:28:27 [00:28:27.348] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x18b5e649d3f257ae7b370868198a1ce04f76f8fae431c1cffc239c04a6d6c23d","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":2214272623,"blockNumber":2,"slotNumber":3,"timestamp":1748997212,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x174a475c866edd2b05ae51462c1d1034185c3514a0bcd233d14fa95ce1175bb1","archiveNextLeafIndex":3} 00:28:27 [00:28:27.368] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 00:28:27 [00:28:27.388] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":10.16526900000099,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1748997212,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":566} 00:28:27 [00:28:27.479] VERBOSE: p2p Synched to latest block 2 00:28:27 [00:28:27.538] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:27 [00:28:27.593] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x174a475c866edd2b05ae51462c1d1034185c3514a0bcd233d14fa95ce1175bb1","blockNumber":3,"slot":4} 00:28:27 [00:28:27.741] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:27 [00:28:27.944] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:28 [00:28:28.283] INFO: aztecjs:deploy_sent_tx Contract 0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b successfully deployed. 00:28:28 [00:28:28.287] INFO: e2e:e2e_keys Running test: Keys using nsk_app to detect nullification nsk_app and contract address are enough to detect note nullification 00:28:28 [00:28:28.297] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:28 [00:28:28.327] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x174a475c866edd2b05ae51462c1d1034185c3514a0bcd233d14fa95ce1175bb1","blockNumber":3,"slot":4} 00:28:28 [00:28:28.351] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x174a475c866edd2b05ae51462c1d1034185c3514a0bcd233d14fa95ce1175bb1","header":{"contentCommitment":{"blobsHash":"0x00aaaae5c2452b3d2cc3033b4c8260ea3a55c4af38383a7312393d0a512ca172","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1748997212,"version":2214272623},"lastArchive":"0x1a32ba16b142f14bf7a362e8128bfc82fdede05327882af1365bbd14c9fe3696","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x1dad6a67e7cd2745fa055ff97b3ee3564bbd9bccc8b6cbdf0176cd90fbe72bd8","nullifierTree":"0x1946846306a7425f6705125a5427cf4d530884e71ab5a153662f11287fd126d1","publicDataTree":"0x02b35395d655c13ad4acbb313a2dc5df0add200d60026cd8f5f641681542631b"},"totalFees":2176967520,"totalManaUsed":6552}} 00:28:28 [00:28:28.434] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:28 [00:28:28.471] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:28 [00:28:28.472] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:28 [00:28:28.507] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:28 [00:28:28.553] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:28 [00:28:28.560] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x08bceeb2b2986852b2181064fd6abdbd39feed600f4db5ea8b7f96e33512504d 00:28:28 [00:28:28.570] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 00:28:28 [00:28:28.570] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 00:28:28 [00:28:28.580] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 00:28:28 [00:28:28.581] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 00:28:28 [00:28:28.585] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:28 [00:28:28.591] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1925c8cda2f8e79b6ad835313af2e00a0ebcf33d75b1ad42d03960b035f85943","nullifier":"0x2fc48506de58d1daabc66909d52e7406d17798cbee0351fdb5e918cbce6ad0e2"} 00:28:28 [00:28:28.605] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b as fee payer 00:28:28 [00:28:28.782] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:29 [00:28:29.443] VERBOSE: simulator:private_execution Executing private function Test:call_create_note {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:29 [00:28:29.488] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:29 [00:28:29.489] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:29 [00:28:29.504] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:29 [00:28:29.527] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x174a475c866edd2b05ae51462c1d1034185c3514a0bcd233d14fa95ce1175bb1","blockNumber":3,"slot":4} 00:28:29 [00:28:29.713] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:29 [00:28:29.715] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:29 [00:28:29.724] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:30 [00:28:30.059] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:30 [00:28:30.221] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x174a475c866edd2b05ae51462c1d1034185c3514a0bcd233d14fa95ce1175bb1","blockNumber":3,"slot":4} 00:28:30 [00:28:30.320] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:30 [00:28:30.844] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:30 [00:28:30.880] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x174a475c866edd2b05ae51462c1d1034185c3514a0bcd233d14fa95ce1175bb1","blockNumber":3,"slot":4} 00:28:31 [00:28:31.300] INFO: pxe:service Proving completed in 2966.2366709999988ms {"timings":{"total":2966.2366709999988,"sync":19.0031020000024,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":665.2117329999965,"oracles":{"debugLog":{"times":[0.7084689999974216,0.5607449999988603,0.4032709999992221,0.46317199999975855,0.3953510000028473,0.40941100000054576,0.6972489999971003,0.5180540000001201]},"fetchTaggedLogs":{"times":[85.90504800000053]},"loadCapsule":{"times":[0.3072280000014871,0.23101600000154576,0.48005299999931594,0.27015700000265497,0.5339749999984633,0.41285100000095554,0.29999899999893387]},"getSharedSecret":{"times":[3.2120079999986046]},"aes128Decrypt":{"times":[0.23299699999915902,0.654329000000871]},"getPublicKeysAndPartialAddress":{"times":[5.416688000001159]},"getKeyValidationRequest":{"times":[1.618424000000232]},"storeCapsule":{"times":[0.4111320000010892,0.32059900000240305,0.4704830000009679]},"deleteCapsule":{"times":[0.5290639999984705]},"validateEnqueuedNotes":{"times":[5.590133999998216]},"getNotes":{"times":[2.125317999998515]},"getAuthWitness":{"times":[0.1764550000007148]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05707099999926868]},"callPrivateFunction":{"times":[1475.6189900000027]}}},{"functionName":"private_kernel_init","time":80.0700480000014},{"functionName":"Test:call_create_note","time":1051.964178000002,"oracles":{"debugLog":{"times":[0.7781509999986156,0.4073710000011488]},"fetchTaggedLogs":{"times":[232.8605720000014]},"loadCapsule":{"times":[0.2966779999987921,1.0959100000000035,0.291958000001614]},"validateEnqueuedNotes":{"times":[3.203347999999096]},"getRandomField":{"times":[0.13010399999984656,0.14328399999794783,0.14128300000083982,0.11479299999700743,0.0744620000004943,0.05350199999884353,0.07976200000121025,0.07027200000084122,0.06627200000002631,0.05207199999858858,0.04233100000055856,0.06028200000218931]},"notifyCreatedNote":{"times":[0.21976599999834434]},"getIndexedTaggingSecretAsSender":{"times":[145.9841180000003]},"incrementAppTaggingSecretIndexAsSender":{"times":[173.36540900000182]}}},{"functionName":"private_kernel_inner","time":131.71533599999748},{"functionName":"private_kernel_reset","time":211.960278999999},{"functionName":"private_kernel_tail","time":70.61029899999994}],"unaccounted":735.7016960000001}} 00:28:31 [00:28:31.313] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:31 [00:28:31.369] VERBOSE: p2p:tx_pool Adding tx 0x1e70bc6a84410785d201ab9122ba773bdf9ed35614629d3c6949caa53a3c0ca5 to pool {"eventName":"tx-added-to-pool","txHash":"0x1e70bc6a84410785d201ab9122ba773bdf9ed35614629d3c6949caa53a3c0ca5","noteHashCount":1,"nullifierCount":1,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 00:28:31 [00:28:31.377] INFO: node Received tx 0x1e70bc6a84410785d201ab9122ba773bdf9ed35614629d3c6949caa53a3c0ca5 {"txHash":"0x1e70bc6a84410785d201ab9122ba773bdf9ed35614629d3c6949caa53a3c0ca5"} 00:28:31 [00:28:31.378] INFO: pxe:service Sent transaction 0x1e70bc6a84410785d201ab9122ba773bdf9ed35614629d3c6949caa53a3c0ca5 00:28:31 [00:28:31.403] VERBOSE: sequencer:block-builder Building block 3 for slot 4 {"slot":4,"slotStart":"2025-06-04T00:34:08.000Z","now":"2025-06-04T00:34:12.341Z","blockNumber":3,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x1e3aef33989764dcfb6810f6472a042297977115273a0fdb35027f7ea637b996"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 00:28:31 [00:28:31.428] VERBOSE: simulator:public-processor Processed tx 0x1e70bc6a84410785d201ab9122ba773bdf9ed35614629d3c6949caa53a3c0ca5 with no public calls in 16.534113999998226ms {"txHash":"0x1e70bc6a84410785d201ab9122ba773bdf9ed35614629d3c6949caa53a3c0ca5","txFee":1097787040,"revertCode":0,"gasUsed":{"totalGas":{"daGas":11264,"l2Gas":3304},"billedGas":{"daGas":11264,"l2Gas":3304},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":1,"noteHashCount":1,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":16.534113999998226} 00:28:31 [00:28:31.429] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.02415432299999884s {"duration":0.02415432299999884,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11264,"l2Gas":3304},"totalSizeInBytes":960} 00:28:31 [00:28:31.534] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:31 [00:28:31.546] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2bc027dc92f7632ab99f6466aac3df597ffac40721951c1cab1f3d4e8e2e141b","globalVariables":{"chainId":31337,"version":2214272623,"blockNumber":3,"slotNumber":4,"timestamp":1748997248,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1e70bc6a84410785d201ab9122ba773bdf9ed35614629d3c6949caa53a3c0ca5"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":148.5052770000002,"publicProcessDuration":24.641626000000542,"rollupCircuitsDuration":143.39698700000008,"txCount":1,"blockNumber":3,"blockTimestamp":1748997248,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 00:28:31 [00:28:31.548] VERBOSE: sequencer Attesting committee is empty 00:28:31 [00:28:31.823] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:31 [00:28:31.833] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x7900b6fa25bf14bd3de486358a004fc12d41128f919050d1fbf78f7b0f3cb514 00:28:31 [00:28:31.834] VERBOSE: node Sent L1 transaction 0x7900b6fa25bf14bd3de486358a004fc12d41128f919050d1fbf78f7b0f3cb514 {"gasLimit":475230,"maxFeePerGas":"1.264094069","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 00:28:31 [00:28:31.845] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 00:28:31 [00:28:31.845] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x7900b6fa25bf14bd3de486358a004fc12d41128f919050d1fbf78f7b0f3cb514","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":3,"blockTimestamp":1748997248,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 00:28:31 [00:28:31.846] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 00:28:31 [00:28:31.852] INFO: blob-sink:server Received blob sidecar for block 0x0676489926b68c58ee513abb3fa4e82e9b389475860988055d47c0c5190ab8a6 00:28:31 [00:28:31.859] INFO: blob-sink:server Blob sidecar stored successfully for block 0x0676489926b68c58ee513abb3fa4e82e9b389475860988055d47c0c5190ab8a6 00:28:31 [00:28:31.862] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1748997284] on http://127.0.0.1:8545 00:28:31 [00:28:31.863] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 00:28:31 [00:28:31.865] WARN: ethereum:cheat_codes Warped L1 timestamp to 1748997284 00:28:31 [00:28:31.865] WARN: foundation:test-date-provider Time set to 2025-06-04T00:34:44.000Z {"offset":372135,"timeMs":1748997284000} 00:28:31 [00:28:31.865] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 00:28:32 [00:28:32.012] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x2bc027dc92f7632ab99f6466aac3df597ffac40721951c1cab1f3d4e8e2e141b","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":2214272623,"blockNumber":3,"slotNumber":4,"timestamp":1748997248,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x286d50bc6926e12a6dc22e111b592519338dd2ab0da35e08144ebd44f154f02f","archiveNextLeafIndex":4} 00:28:32 [00:28:32.027] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:32 [00:28:32.035] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 00:28:32 [00:28:32.037] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 00:28:32 [00:28:32.039] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 00:28:32 [00:28:32.123] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":7.804274999998597,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1748997248,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 00:28:32 [00:28:32.129] VERBOSE: p2p Synched to latest block 3 00:28:32 [00:28:32.234] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:32 [00:28:32.361] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x286d50bc6926e12a6dc22e111b592519338dd2ab0da35e08144ebd44f154f02f","blockNumber":4,"slot":5} 00:28:32 [00:28:32.417] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x286d50bc6926e12a6dc22e111b592519338dd2ab0da35e08144ebd44f154f02f","header":{"contentCommitment":{"blobsHash":"0x009cebe6f24e10c839373a28c497af17a7a7b804d6b0788009b070f5983b82ef","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1748997248,"version":2214272623},"lastArchive":"0x174a475c866edd2b05ae51462c1d1034185c3514a0bcd233d14fa95ce1175bb1","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x26c1c303ce751dd81b7e68ef714962c0f31b3c52f10fa4eb09767e80128b34fc","nullifierTree":"0x006214b5c7c4823ddfe80b16be3f8529dc382aa413bb673cf5e515b3047dce94","publicDataTree":"0x0a1d6979210fb3aa53d11a70354486ecfa532c088a4d998dab4c4cdc3384ea84"},"totalFees":1097787040,"totalManaUsed":3304}} 00:28:32 [00:28:32.455] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:32 [00:28:32.512] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:32 [00:28:32.558] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:32 [00:28:32.559] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:32 [00:28:32.596] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 00:28:32 [00:28:32.624] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:32 [00:28:32.630] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x08bceeb2b2986852b2181064fd6abdbd39feed600f4db5ea8b7f96e33512504d 00:28:32 [00:28:32.644] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 00:28:32 [00:28:32.645] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 00:28:32 [00:28:32.652] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 00:28:32 [00:28:32.653] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 00:28:32 [00:28:32.656] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:32 [00:28:32.660] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1925c8cda2f8e79b6ad835313af2e00a0ebcf33d75b1ad42d03960b035f85943","nullifier":"0x2fc48506de58d1daabc66909d52e7406d17798cbee0351fdb5e918cbce6ad0e2"} 00:28:32 [00:28:32.672] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b as fee payer 00:28:33 [00:28:33.062] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:33 [00:28:33.406] VERBOSE: simulator:private_execution Executing private function Test:call_destroy_note {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:33 [00:28:33.441] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:33 [00:28:33.442] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:33 [00:28:33.444] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:33 [00:28:33.458] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x286d50bc6926e12a6dc22e111b592519338dd2ab0da35e08144ebd44f154f02f","blockNumber":4,"slot":5} 00:28:33 [00:28:33.657] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:33 [00:28:33.713] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:33 [00:28:33.720] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x156e78fb7217d8c62d4aad465d12aa1aaa66ade5efd38e0c4f40219067268c78 00:28:33 [00:28:33.735] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 00:28:33 [00:28:33.736] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 00:28:33 [00:28:33.748] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 00:28:33 [00:28:33.749] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 00:28:33 [00:28:33.756] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:33 [00:28:33.763] VERBOSE: pxe:service Added note {"index":128,"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x1595d7a343db48b8ab58239bde1c3f42433bbcc4f3587d50b77ff56cff82d478","nullifier":"0x09cb289039e9cc25d90e1197a3faf93d86896df8b73a6797556671dd6b83270d"} 00:28:33 [00:28:33.905] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:34 [00:28:34.187] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:34 [00:28:34.597] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:34 [00:28:34.615] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x286d50bc6926e12a6dc22e111b592519338dd2ab0da35e08144ebd44f154f02f","blockNumber":4,"slot":5} 00:28:35 [00:28:35.126] INFO: pxe:service Proving completed in 2716.6107919999995ms {"timings":{"total":2716.6107919999995,"sync":9.141009999999369,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":428.8959240000004,"oracles":{"debugLog":{"times":[0.9478059999964898,0.4563620000008086,0.5757249999987835,0.6139770000008866,0.47768400000131805,0.42037100000015926,0.42852100000163773,0.35622000000148546]},"fetchTaggedLogs":{"times":[68.80912899999748]},"loadCapsule":{"times":[0.238456999999471,0.1738649999970221,0.46315300000060233,0.38445100000171806,0.209525999998732,0.2293360000003304,0.21333600000070874]},"getSharedSecret":{"times":[4.108413000001747]},"aes128Decrypt":{"times":[0.3552589999999327,1.1028800000021874]},"getPublicKeysAndPartialAddress":{"times":[1.76115800000116]},"getKeyValidationRequest":{"times":[1.8001590000021679]},"storeCapsule":{"times":[0.42645099999936065,0.35122899999987567,0.5128640000002633]},"deleteCapsule":{"times":[0.3094490000003134]},"validateEnqueuedNotes":{"times":[4.325589000000036]},"getNotes":{"times":[1.143330999999307]},"getAuthWitness":{"times":[0.18712500000037835]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.03714100000070175]},"callPrivateFunction":{"times":[1133.617280999999]}}},{"functionName":"private_kernel_init","time":75.20336500000121},{"functionName":"Test:call_destroy_note","time":946.6510480000034,"oracles":{"debugLog":{"times":[0.7065389999988838,0.7206090000036056,0.5777260000031674,0.6668690000005881,0.987396999997145,0.610396000003675,0.7130600000018603]},"fetchTaggedLogs":{"times":[274.90853799999604]},"loadCapsule":{"times":[0.5063740000041435,0.35443900000245776,0.5436750000008033,0.9076650000060908,0.3515190000034636,0.30112799999915296,0.2514870000013616]},"getSharedSecret":{"times":[5.428429000006872]},"aes128Decrypt":{"times":[0.3548000000009779,1.299525000002177]},"getPublicKeysAndPartialAddress":{"times":[3.210268000002543,2.951771000000008]},"getKeyValidationRequest":{"times":[3.6981809999997495,4.12400300000445]},"storeCapsule":{"times":[1.0103669999953127,2.4656380000014906,0.3898509999999078]},"deleteCapsule":{"times":[0.42098100000293925]},"validateEnqueuedNotes":{"times":[6.429367000004277]},"getNotes":{"times":[2.8795590000008815]},"notifyNullifiedNote":{"times":[0.7191289999973378]}}},{"functionName":"private_kernel_inner","time":150.44934899999498},{"functionName":"private_kernel_reset","time":248.49746700000105},{"functionName":"private_kernel_tail","time":94.10124800000631}],"unaccounted":763.6713809999928}} 00:28:35 [00:28:35.141] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:35 [00:28:35.177] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x286d50bc6926e12a6dc22e111b592519338dd2ab0da35e08144ebd44f154f02f","blockNumber":4,"slot":5} 00:28:35 [00:28:35.178] VERBOSE: p2p:tx_pool Adding tx 0x1a6b5d05bc38cd4fb7b0812ef968bfb3baf87bce7aa94f58fbda0c60b3bd6703 to pool {"eventName":"tx-added-to-pool","txHash":"0x1a6b5d05bc38cd4fb7b0812ef968bfb3baf87bce7aa94f58fbda0c60b3bd6703","noteHashCount":0,"nullifierCount":2,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 00:28:35 [00:28:35.182] INFO: node Received tx 0x1a6b5d05bc38cd4fb7b0812ef968bfb3baf87bce7aa94f58fbda0c60b3bd6703 {"txHash":"0x1a6b5d05bc38cd4fb7b0812ef968bfb3baf87bce7aa94f58fbda0c60b3bd6703"} 00:28:35 [00:28:35.182] INFO: pxe:service Sent transaction 0x1a6b5d05bc38cd4fb7b0812ef968bfb3baf87bce7aa94f58fbda0c60b3bd6703 00:28:35 [00:28:35.351] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:35 [00:28:35.556] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:35 [00:28:35.701] VERBOSE: sequencer:block-builder Building block 4 for slot 5 {"slot":5,"slotStart":"2025-06-04T00:34:44.000Z","now":"2025-06-04T00:34:47.836Z","blockNumber":4,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x1e3aef33989764dcfb6810f6472a042297977115273a0fdb35027f7ea637b996"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 00:28:35 [00:28:35.725] VERBOSE: simulator:public-processor Processed tx 0x1a6b5d05bc38cd4fb7b0812ef968bfb3baf87bce7aa94f58fbda0c60b3bd6703 with no public calls in 13.612613999997848ms {"txHash":"0x1a6b5d05bc38cd4fb7b0812ef968bfb3baf87bce7aa94f58fbda0c60b3bd6703","txFee":144176240,"revertCode":0,"gasUsed":{"totalGas":{"daGas":1536,"l2Gas":3532},"billedGas":{"daGas":1536,"l2Gas":3532},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":2,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":13.612613999997848} 00:28:35 [00:28:35.727] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.023241047000003165s {"duration":0.023241047000003165,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1536,"l2Gas":3532},"totalSizeInBytes":288} 00:28:35 [00:28:35.814] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:35 [00:28:35.835] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x0786093aab0db4a431c345b95e0d895466e0245932fddc80317f582002b71797","globalVariables":{"chainId":31337,"version":2214272623,"blockNumber":4,"slotNumber":5,"timestamp":1748997284,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"txHashes":["0x1a6b5d05bc38cd4fb7b0812ef968bfb3baf87bce7aa94f58fbda0c60b3bd6703"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":139.52926099999604,"publicProcessDuration":23.71444000000338,"rollupCircuitsDuration":134.54581000000326,"txCount":1,"blockNumber":4,"blockTimestamp":1748997284,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 00:28:35 [00:28:35.840] VERBOSE: sequencer Attesting committee is empty 00:28:36 [00:28:36.103] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:36 [00:28:36.111] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x3bbd1cdaef201afc11fd1639a6a855a50da30123de19c8de32dcde77a1634d94 00:28:36 [00:28:36.111] VERBOSE: node Sent L1 transaction 0x3bbd1cdaef201afc11fd1639a6a855a50da30123de19c8de32dcde77a1634d94 {"gasLimit":475242,"maxFeePerGas":"1.249232347","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 00:28:36 [00:28:36.135] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1748997320] on http://127.0.0.1:8545 00:28:36 [00:28:36.138] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 00:28:36 [00:28:36.139] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x3bbd1cdaef201afc11fd1639a6a855a50da30123de19c8de32dcde77a1634d94","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":4,"blockTimestamp":1748997284,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 00:28:36 [00:28:36.139] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 00:28:36 [00:28:36.143] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 00:28:36 [00:28:36.147] WARN: ethereum:cheat_codes Warped L1 timestamp to 1748997320 00:28:36 [00:28:36.147] WARN: foundation:test-date-provider Time set to 2025-06-04T00:35:20.000Z {"offset":403853,"timeMs":1748997320000} 00:28:36 [00:28:36.147] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 00:28:36 [00:28:36.150] INFO: blob-sink:server Received blob sidecar for block 0x0eb0576b183dfaa8d5c345ce44a9884158f675ffe97676c0b63c1a7b7acc33f9 00:28:36 [00:28:36.155] INFO: blob-sink:server Blob sidecar stored successfully for block 0x0eb0576b183dfaa8d5c345ce44a9884158f675ffe97676c0b63c1a7b7acc33f9 00:28:36 [00:28:36.310] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:36 [00:28:36.319] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 00:28:36 [00:28:36.321] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 00:28:36 [00:28:36.323] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 00:28:36 [00:28:36.621] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:36 [00:28:36.644] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x0786093aab0db4a431c345b95e0d895466e0245932fddc80317f582002b71797","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":2214272623,"blockNumber":4,"slotNumber":5,"timestamp":1748997284,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"archiveRoot":"0x290fa3bdfe1d8f31cd31dc67faf93c9368039c4f7e25e20d63d55f13ba03fcb2","archiveNextLeafIndex":5} 00:28:36 [00:28:36.658] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 00:28:36 [00:28:36.753] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":10.105824999998731,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1748997284,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 00:28:36 [00:28:36.774] VERBOSE: p2p Synched to latest block 4 00:28:36 [00:28:36.833] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:37 [00:28:37.035] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:37 [00:28:37.179] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x290fa3bdfe1d8f31cd31dc67faf93c9368039c4f7e25e20d63d55f13ba03fcb2","blockNumber":5,"slot":6} 00:28:37 [00:28:37.206] INFO: e2e:e2e_keys Running test: Keys ovsk_app gets ovsk_app 00:28:37 [00:28:37.240] INFO: pxe:service Simulating transaction execution request to 0x27e740b2 at 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b {"origin":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x0000000000000000000000000000000000000000000000000000000083fb1e6f","authWitnesses":["0x282942e0aa74def29658362f8164751f5743cbfd27bed6b33f5f8d3bfcab576c"]} 00:28:37 [00:28:37.241] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:37 [00:28:37.253] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x290fa3bdfe1d8f31cd31dc67faf93c9368039c4f7e25e20d63d55f13ba03fcb2","header":{"contentCommitment":{"blobsHash":"0x002bf5ed47b6e3651128f2d9d0cb776675e68bc780d7bb03db7e2fa8a3f2a241","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1748997284,"version":2214272623},"lastArchive":"0x286d50bc6926e12a6dc22e111b592519338dd2ab0da35e08144ebd44f154f02f","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x26c1c303ce751dd81b7e68ef714962c0f31b3c52f10fa4eb09767e80128b34fc","nullifierTree":"0x08ab51cb631e485a8a3bfd8c7ed30b421507fdb2443157e9ff4f6966275272a9","publicDataTree":"0x2a59c18697680eacd2221d9d9d3b3315fab41558654bf2d9988b495fbafb9687"},"totalFees":144176240,"totalManaUsed":3532}} 00:28:37 [00:28:37.379] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:37 [00:28:37.413] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:37 [00:28:37.414] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:37 [00:28:37.449] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:37 [00:28:37.508] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b"} 00:28:37 [00:28:37.522] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x08bceeb2b2986852b2181064fd6abdbd39feed600f4db5ea8b7f96e33512504d 00:28:37 [00:28:37.544] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 00:28:37 [00:28:37.545] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 00:28:37 [00:28:37.561] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 00:28:37 [00:28:37.561] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 00:28:37 [00:28:37.566] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:37 [00:28:37.572] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1925c8cda2f8e79b6ad835313af2e00a0ebcf33d75b1ad42d03960b035f85943","nullifier":"0x2fc48506de58d1daabc66909d52e7406d17798cbee0351fdb5e918cbce6ad0e2"} 00:28:37 [00:28:37.590] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b as fee payer 00:28:37 [00:28:37.771] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:38 [00:28:38.366] VERBOSE: simulator:private_execution Executing private function Test:get_ovsk_app {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:38 [00:28:38.385] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 00:28:38 [00:28:38.386] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:38 [00:28:38.394] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:38 [00:28:38.419] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x290fa3bdfe1d8f31cd31dc67faf93c9368039c4f7e25e20d63d55f13ba03fcb2","blockNumber":5,"slot":6} 00:28:38 [00:28:38.603] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:38 [00:28:38.614] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b"} 00:28:38 [00:28:38.622] VERBOSE: pxe:service Removed note for contract 0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b at slot 0x000000000000000000000000000000000000000000000000000000000000000c {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","nullifier":"0x09cb289039e9cc25d90e1197a3faf93d86896df8b73a6797556671dd6b83270d"} 00:28:38 [00:28:38.624] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x156e78fb7217d8c62d4aad465d12aa1aaa66ade5efd38e0c4f40219067268c78 00:28:38 [00:28:38.635] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 00:28:38 [00:28:38.636] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 00:28:38 [00:28:38.644] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 00:28:38 [00:28:38.645] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 00:28:38 [00:28:38.651] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 00:28:38 [00:28:38.658] VERBOSE: pxe:service Added note {"index":128,"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x1595d7a343db48b8ab58239bde1c3f42433bbcc4f3587d50b77ff56cff82d478","nullifier":"0x09cb289039e9cc25d90e1197a3faf93d86896df8b73a6797556671dd6b83270d"} 00:28:38 [00:28:38.661] VERBOSE: pxe:service Removed just-added note {"contract":"0x1a60127dc2efa7f8cecffbcb8cc0ee2b9eef4c423dd5fd1c0264745f18ce0a4b","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x1595d7a343db48b8ab58239bde1c3f42433bbcc4f3587d50b77ff56cff82d478","nullifier":"0x09cb289039e9cc25d90e1197a3faf93d86896df8b73a6797556671dd6b83270d"} 00:28:38 [00:28:38.811] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:39 [00:28:39.457] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:39 [00:28:39.475] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x290fa3bdfe1d8f31cd31dc67faf93c9368039c4f7e25e20d63d55f13ba03fcb2","blockNumber":5,"slot":6} 00:28:39 [00:28:39.979] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 00:28:40 [00:28:40.023] INFO: pxe:service Simulation completed for 0x00a48201282fd00f836307840009d79cac64e0202603b99bc85516ddc718b0c4 in 2782.3667879999994ms {"txHash":"0x00a48201282fd00f836307840009d79cac64e0202603b99bc85516ddc718b0c4","origin":"0x140456c10cb469b29165af2437cfd853d22d4ae7eb63cf50d9b1465f7977e67b","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x0000000000000000000000000000000000000000000000000000000083fb1e6f","authWitnesses":["0x282942e0aa74def29658362f8164751f5743cbfd27bed6b33f5f8d3bfcab576c"]} 00:28:40 [00:28:40.032] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x290fa3bdfe1d8f31cd31dc67faf93c9368039c4f7e25e20d63d55f13ba03fcb2","blockNumber":5,"slot":6} 00:28:40 [00:28:40.036] INFO: node Stopping 00:28:40 [00:28:40.043] INFO: slasher Slasher client stopped. 00:28:40 [00:28:40.043] INFO: sequencer Stopped sequencer 00:28:40 [00:28:40.045] INFO: p2p P2P client stopped. 00:28:40 [00:28:40.052] INFO: world_state Stopped world state synchronizer 00:28:40 [00:28:40.053] INFO: archiver Stopped. 00:28:40 [00:28:40.053] INFO: node Stopped 00:28:40 [00:28:40.053] VERBOSE: e2e:e2e_keys Cleaning up ACVM state 00:28:40 [00:28:40.053] INFO: e2e:e2e_keys Cleaning up ACVM temp directory /tmp/381e6eee 00:28:40 [00:28:40.054] VERBOSE: e2e:e2e_keys Cleaning up BB state 00:28:40 [00:28:40.068] INFO: blob-sink:server Stopping blob sink 00:28:40 [00:28:40.069] INFO: blob-sink:server Blob sink stopped 00:28:40 [00:28:40.069] VERBOSE: e2e:e2e_keys Cleaning up data directory at /tmp/5b1241c00128031d 00:28:40 FAIL src/e2e_keys.test.ts 00:28:40 00:28:40 00:28:40 ● Test suite failed to run 00:28:40 00:28:40 HttpRequestError: HTTP request failed. 00:28:40 00:28:40 URL: http://127.0.0.1:8545 00:28:40 Request body: {"method":"eth_uninstallFilter","params":["0x7154446d5374346f65446a54765a5045"]} 00:28:40 00:28:40 Details: fetch failed 00:28:40 Version: viem@2.23.7 00:28:40 00:28:40 at Object.request (../../node_modules/viem/utils/rpc/http.ts:161:15) 00:28:40 at fn (../../node_modules/viem/clients/transports/http.ts:145:19) 00:28:40 at request (../../node_modules/viem/clients/transports/http.ts:150:39) 00:28:40 at withRetry.delay.count.count (../../node_modules/viem/utils/buildRequest.ts:129:22) 00:28:40 at attemptRetry (../../node_modules/viem/utils/promise/withRetry.ts:44:22) 00:28:40 00:28:40 Test Suites: 1 failed, 1 total 00:28:40 Tests: 2 passed, 2 total 00:28:40 Snapshots: 0 total 00:28:40 Time: 38.95 s 00:28:40 Ran all test suites matching /src\/e2e_keys.test.ts/i. 00:28:40 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?