Parent Log: http://ci.aztec-labs.com/814f01bc2b04ad56 Command: d2a1f586812a792c:ISOLATE=1:NAME=e2e_max_block_number yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_max_block_number.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/464e1a9d9b40b5188586643b44bc52bffccbc3e6 Env: REF_NAME=gh-readonly-queue/master/pr-15117-c06f30929f18b85bcea93c0926a8d640a3819f00 CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Tue Jun 17 20:22:36 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15117_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_d488fdfe97c9ae48_master 20:22:36 +++ id -u 20:22:36 +++ id -g 20:22:36 ++ docker run -d --name e2e_max_block_number --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_max_block_number.test.ts'\''' 20:22:36 + cid=1b5a49f9a7979074ec3f2ed7c9e6d834eaa7a9c8875060f3cb0959989601402c 20:22:36 + set +x 20:22:37 {"level":25,"time":1750191757520,"pid":11,"hostname":"1b5a49f9a797","module":"logger","msg":"Logger initialized with level verbose"} 20:22:44 [20:22:44.882] INFO: e2e:e2e_max_block_number Logging metrics to log/unknown.jsonl 20:22:45 [20:22:45.177] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-yb9CKp/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 20:22:45 [20:22:45.237] INFO: e2e:e2e_max_block_number Calling anvil_setBlockTimestampInterval with params: [12] 20:22:45 [20:22:45.273] WARN: e2e:e2e_max_block_number Set block interval to 12 20:22:45 [20:22:45.273] VERBOSE: e2e:e2e_max_block_number Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 20:22:45 [20:22:45.338] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 20:22:45 [20:22:45.347] VERBOSE: e2e:e2e_max_block_number Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 20:22:45 [20:22:45.365] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 20:22:45 [20:22:45.370] VERBOSE: e2e:e2e_max_block_number Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 20:22:45 [20:22:45.417] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 20:22:45 [20:22:45.432] VERBOSE: e2e:e2e_max_block_number Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 20:22:45 [20:22:45.460] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 20:22:45 [20:22:45.468] VERBOSE: e2e:e2e_max_block_number Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 20:22:45 [20:22:45.491] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 20:22:45 [20:22:45.496] VERBOSE: e2e:e2e_max_block_number Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 20:22:45 [20:22:45.508] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 20:22:45 [20:22:45.513] VERBOSE: e2e:e2e_max_block_number Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 20:22:45 [20:22:45.514] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 20:22:45 [20:22:45.515] INFO: e2e:e2e_max_block_number Waiting for 6 transactions to be mined... 20:22:45 [20:22:45.539] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:45 [20:22:45.556] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 20:22:45 [20:22:45.556] VERBOSE: e2e:e2e_max_block_number Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 20:22:45 [20:22:45.568] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 20:22:45 [20:22:45.605] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 20:22:45 [20:22:45.616] VERBOSE: e2e:e2e_max_block_number Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 20:22:45 [20:22:45.638] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 20:22:45 [20:22:45.639] VERBOSE: e2e:e2e_max_block_number Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 20:22:45 [20:22:45.665] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 20:22:45 [20:22:45.676] VERBOSE: e2e:e2e_max_block_number Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 20:22:45 [20:22:45.690] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 20:22:45 [20:22:45.691] VERBOSE: e2e:e2e_max_block_number Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 20:22:45 [20:22:45.691] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 20:22:45 [20:22:45.691] INFO: e2e:e2e_max_block_number Waiting for 8 transactions to be mined... 20:22:45 [20:22:45.718] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:45 [20:22:45.729] VERBOSE: e2e:e2e_max_block_number Deployed shared contracts 20:22:45 [20:22:45.747] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 20:22:45 [20:22:45.748] VERBOSE: e2e:e2e_max_block_number Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 20:22:45 [20:22:45.752] VERBOSE: e2e:e2e_max_block_number Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 20:22:45 [20:22:45.772] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 20:22:45 [20:22:45.812] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 20:22:45 [20:22:45.830] VERBOSE: e2e:e2e_max_block_number Awaiting for linked libraries to be deployed 20:22:45 [20:22:45.879] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xa9ffc6b76c9aeb55e9a31a71c18cc6f3e0de760e0f5299cde1d5daeae7cb8586 {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 20:22:45 [20:22:45.889] VERBOSE: e2e:e2e_max_block_number Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016 {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 20:22:45 [20:22:45.890] INFO: e2e:e2e_max_block_number Waiting for 9 transactions to be mined... 20:22:45 [20:22:45.921] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:45 [20:22:45.921] VERBOSE: e2e:e2e_max_block_number All core contracts have been deployed 20:22:45 [20:22:45.935] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 20:22:45 [20:22:45.936] VERBOSE: e2e:e2e_max_block_number Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 20:22:45 [20:22:45.950] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 20:22:45 [20:22:45.954] VERBOSE: e2e:e2e_max_block_number Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 20:22:45 [20:22:45.984] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 20:22:45 [20:22:45.985] VERBOSE: e2e:e2e_max_block_number Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 20:22:45 [20:22:45.985] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 20:22:46 [20:22:46.013] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:46 [20:22:46.020] VERBOSE: e2e:e2e_max_block_number Rollup deployed 20:22:46 [20:22:46.021] VERBOSE: e2e:e2e_max_block_number Waiting for rollup and slash factory to be deployed 20:22:46 [20:22:46.021] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 20:22:46 [20:22:46.049] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:46 [20:22:46.049] VERBOSE: e2e:e2e_max_block_number All transactions for L1 deployment have been mined 20:22:46 [20:22:46.069] INFO: e2e:e2e_max_block_number Aztec L1 contracts initialized {"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 20:22:46 [20:22:46.074] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 20:22:46 [20:22:46.079] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:46 [20:22:46.081] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 20:22:46 [20:22:46.083] INFO: telemetry:client Using NoopTelemetryClient 20:22:46 [20:22:46.090] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 20:22:46 [20:22:46.091] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 20:22:46 [20:22:46.093] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/4a1ff08944b0f424/blob-sink with map size 134217728 KB (LMDB v2) 20:22:46 [20:22:46.097] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:46 [20:22:46.101] INFO: aztecjs:utils:watcher New L1 time: 1750192004 20:22:46 [20:22:46.102] WARN: foundation:test-date-provider Time set to 2025-06-17T20:26:44.000Z {"offset":237899,"timeMs":1750192004000} 20:22:46 [20:22:46.105] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 20:22:46 [20:22:46.112] INFO: blob-sink:server Server is running on http://localhost:45931 20:22:46 [20:22:46.112] VERBOSE: e2e:e2e_max_block_number Creating and synching an aztec node... 20:22:46 [20:22:46.113] VERBOSE: e2e:e2e_max_block_number Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/f61174a5/acvm 20:22:46 [20:22:46.114] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:45931"} 20:22:46 [20:22:46.132] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 20:22:46 [20:22:46.132] INFO: archiver:lmdb Creating archiver data store at directory /tmp/4a1ff08944b0f424/archiver with map size 134217728 KB (LMDB v2) 20:22:46 [20:22:46.133] INFO: archiver:lmdb Starting data store with maxReaders 16 20:22:46 [20:22:46.404] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:46 [20:22:46.523] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:45931"} 20:22:46 [20:22:46.540] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:45931"} 20:22:46 [20:22:46.540] WARN: node:blob-sink:client No L1 consensus host urls configured 20:22:46 [20:22:46.541] WARN: node:blob-sink:client No archive client configured 20:22:46 [20:22:46.545] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x4e73b567e5db873d08140fd62b9892a26199ca5fc1e36006eb15967cf1034de1 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0xcc4a5eb3b855493372a211e9ed086c000a3fc1b98ecb167e3f8131d675c34fa3","l1BlockNumber":16}} 20:22:46 [20:22:46.572] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 20:22:46 [20:22:46.596] INFO: world-state:database Creating world state data store at directory /tmp/4a1ff08944b0f424/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 20:22:46 [20:22:46.632] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:46 [20:22:46.638] INFO: world_state Created world state synchroniser with block history of 64 20:22:46 [20:22:46.639] WARN: node Aztec node is accepting fake proofs 20:22:46 [20:22:46.655] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/4a1ff08944b0f424/p2p with map size 134217728 KB (LMDB v2) 20:22:46 [20:22:46.667] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 20:22:46 [20:22:46.671] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/4a1ff08944b0f424/p2p-archive with map size 134217728 KB (LMDB v2) 20:22:46 [20:22:46.674] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 20:22:46 [20:22:46.677] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/4a1ff08944b0f424/p2p-peers with map size 134217728 KB (LMDB v2) 20:22:46 [20:22:46.680] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 20:22:46 [20:22:46.684] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 20:22:46 [20:22:46.685] VERBOSE: p2p P2P is disabled. Using dummy P2P service 20:22:46 [20:22:46.689] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 20:22:46 [20:22:46.691] INFO: world_state Started world state synchronizer from block 1 20:22:46 [20:22:46.694] VERBOSE: world-state:block_stream Starting sync from block number 0 20:22:46 [20:22:46.697] INFO: p2p Starting p2p client from block 0 with empty mempools 20:22:46 [20:22:46.697] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 20:22:46 [20:22:46.699] INFO: slasher Slasher client initialized 20:22:46 [20:22:46.699] INFO: slasher Starting Slasher client... 20:22:46 [20:22:46.748] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 20:22:46 [20:22:46.748] VERBOSE: node All Aztec Node subsystems synced 20:22:46 [20:22:46.759] INFO: sequencer-client Deploying forwarder contract 20:22:46 [20:22:46.766] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 20:22:46 [20:22:46.782] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 20:22:46 [20:22:46.782] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 20:22:46 [20:22:46.788] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 20:22:46 [20:22:46.806] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 20:22:46 [20:22:46.806] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 20:22:46 [20:22:46.807] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/f61174a5/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 20:22:47 [20:22:47.078] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 20:22:47 [20:22:47.081] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 20:22:47 [20:22:47.083] INFO: node Aztec Node version: 0.87.8 20:22:47 [20:22:47.083] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","feeAssetHandlerAddress":"0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6","stakingAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788"} 20:22:47 [20:22:47.085] VERBOSE: e2e:e2e_max_block_number Creating a pxe... 20:22:47 [20:22:47.090] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:47 [20:22:47.124] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/5c70d1453ff86016/pxe_data with map size 134217728 KB (LMDB v2) 20:22:47 [20:22:47.149] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x04debdceefa1a9c93aac83174676cdf975ea6a4dac8102e41b5c3a5fe53a3301","blockNumber":1,"slot":2} 20:22:47 [20:22:47.151] INFO: pxe:data:lmdb Starting data store with maxReaders 16 20:22:47 [20:22:47.365] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:47 [20:22:47.466] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 20:22:47 [20:22:47.468] INFO: pxe:service Started PXE connected to chain 31337 version 3123621913 20:22:47 [20:22:47.521] INFO: pxe:service Registered account 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 20:22:47 [20:22:47.590] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:47 [20:22:47.591] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 20:22:47 [20:22:47.593] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 20:22:47 [20:22:47.736] INFO: pxe:service Added contract SchnorrAccount at 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 20:22:47 [20:22:47.753] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x04debdceefa1a9c93aac83174676cdf975ea6a4dac8102e41b5c3a5fe53a3301","blockNumber":1,"slot":2} 20:22:47 [20:22:47.790] VERBOSE: pxe:block_stream Starting sync from block number 0 20:22:47 [20:22:47.822] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 20:22:47 [20:22:47.872] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:47 [20:22:47.921] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 20:22:48 [20:22:48.765] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 20:22:48 [20:22:48.884] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 20:22:48 [20:22:48.975] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:49 [20:22:49.012] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x04debdceefa1a9c93aac83174676cdf975ea6a4dac8102e41b5c3a5fe53a3301","blockNumber":1,"slot":2} 20:22:49 [20:22:49.134] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:22:49 [20:22:49.172] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:22:49 [20:22:49.173] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:22:49 [20:22:49.199] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:49 [20:22:49.230] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:22:49 [20:22:49.234] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:22:49 [20:22:49.413] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:22:49 [20:22:49.462] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:22:49 [20:22:49.463] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:22:49 [20:22:49.465] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:49 [20:22:49.514] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:22:49 [20:22:49.527] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:22:49 [20:22:49.527] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x04debdceefa1a9c93aac83174676cdf975ea6a4dac8102e41b5c3a5fe53a3301","blockNumber":1,"slot":2} 20:22:49 [20:22:49.546] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:22:49 [20:22:49.788] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:50 [20:22:50.011] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:50 [20:22:50.344] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:50 [20:22:50.484] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x04debdceefa1a9c93aac83174676cdf975ea6a4dac8102e41b5c3a5fe53a3301","blockNumber":1,"slot":2} 20:22:50 [20:22:50.675] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:51 [20:22:51.218] INFO: pxe:service Proving completed in 3431.290153ms {"timings":{"total":3431.290153,"sync":4.349310999999943,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":157.1662269999997,"oracles":{"callPrivateFunction":{"times":[1087.5027609999997,359.9001279999993,243.9872180000002]}}},{"functionName":"private_kernel_init","time":147.6698520000009},{"functionName":"ContractClassRegisterer:register","time":1071.7069629999987,"oracles":{"loadCapsule":{"times":[73.48400500000025]},"notifyCreatedNullifier":{"times":[1.4181229999994684]},"debugLog":{"times":[1.584657999999763]},"notifyCreatedContractClassLog":{"times":[12.996794000000591]}}},{"functionName":"private_kernel_inner","time":310.63978599999973},{"functionName":"SchnorrAccount:constructor","time":325.87597900000037,"oracles":{"getContractInstance":{"times":[0.9620489999997517]},"debugLog":{"times":[0.596268000001146,0.4169929999989108]},"fetchTaggedLogs":{"times":[59.85934199999974]},"loadCapsule":{"times":[0.3562110000002576,0.25566799999978684,0.20211700000072597]},"notifyCreatedNullifier":{"times":[0.18624500000078115,0.4070429999992484]},"notifyCreatedNote":{"times":[0.34187000000019907]},"getRandomField":{"times":[0.3138890000009269,0.21947700000055193,0.10755299999982526,0.08736200000021199,0.06553200000053039,0.07847200000105659,0.055561999999554246,0.05468199999995704,0.0584019999987504,0.08302199999889126,0.05000099999961094]},"getIndexedTaggingSecretAsSender":{"times":[25.17044200000055]},"incrementAppTaggingSecretIndexAsSender":{"times":[26.67453800000112]}}},{"functionName":"private_kernel_inner","time":181.74606400000084},{"functionName":"SchnorrAccount:entrypoint","time":213.7458319999987,"oracles":{"debugLog":{"times":[0.7048219999996945,0.4371629999986908,0.788724000000002]},"fetchTaggedLogs":{"times":[62.213394000000335]},"loadCapsule":{"times":[0.2653280000013183,0.8034939999997732,0.560737000001609]},"getNotes":{"times":[1.8755870000004506]},"getAuthWitness":{"times":[0.44772399999965273]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.0556410000008327]}}},{"functionName":"private_kernel_inner","time":134.02037799999925},{"functionName":"private_kernel_reset","time":195.05305600000065},{"functionName":"private_kernel_tail","time":105.081322}],"unaccounted":584.2353830000011}} 20:22:51 [20:22:51.240] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:51 [20:22:51.327] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x04debdceefa1a9c93aac83174676cdf975ea6a4dac8102e41b5c3a5fe53a3301","blockNumber":1,"slot":2} 20:22:51 [20:22:51.414] VERBOSE: p2p:tx_pool Adding tx 0x260ec9fa92904cc8b4ab41fc4c1b49fe22f530f68542e3b3339f8117e1239fb5 to pool {"eventName":"tx-added-to-pool","txHash":"0x260ec9fa92904cc8b4ab41fc4c1b49fe22f530f68542e3b3339f8117e1239fb5","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 20:22:51 [20:22:51.439] INFO: node Received tx 0x260ec9fa92904cc8b4ab41fc4c1b49fe22f530f68542e3b3339f8117e1239fb5 {"txHash":"0x260ec9fa92904cc8b4ab41fc4c1b49fe22f530f68542e3b3339f8117e1239fb5"} 20:22:51 [20:22:51.439] INFO: pxe:service Sent transaction 0x260ec9fa92904cc8b4ab41fc4c1b49fe22f530f68542e3b3339f8117e1239fb5 20:22:51 [20:22:51.450] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:51 [20:22:51.653] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:51 [20:22:51.843] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 20:22:51 [20:22:51.858] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:51 [20:22:51.859] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-17T20:27:08.000Z","now":"2025-06-17T20:26:49.758Z"} 20:22:52 [20:22:52.084] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:52 [20:22:52.144] VERBOSE: simulator:public-processor Processed tx 0x260ec9fa92904cc8b4ab41fc4c1b49fe22f530f68542e3b3339f8117e1239fb5 with no public calls in 175.40649200000007ms {"txHash":"0x260ec9fa92904cc8b4ab41fc4c1b49fe22f530f68542e3b3339f8117e1239fb5","txFee":6794052480,"revertCode":0,"gasUsed":{"totalGas":{"daGas":16896,"l2Gas":20448},"billedGas":{"daGas":16896,"l2Gas":20448},"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":175.40649200000007} 20:22:52 [20:22:52.150] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.28852603700000146s {"duration":0.28852603700000146,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 20:22:52 [20:22:52.265] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x12c77967347bc95fceb9bfa763bdfaa92f84d7267d6619ea12d2dc9d58c16a91","globalVariables":{"chainId":31337,"version":3123621913,"blockNumber":1,"slotNumber":2,"timestamp":1750192028,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x260ec9fa92904cc8b4ab41fc4c1b49fe22f530f68542e3b3339f8117e1239fb5"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":422.2574569999997,"publicProcessDuration":289.65006099999846,"rollupCircuitsDuration":408.6018530000001,"txCount":1,"blockNumber":1,"blockTimestamp":1750192028,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:22:52 [20:22:52.267] VERBOSE: sequencer Attesting committee is empty 20:22:52 [20:22:52.367] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:52 [20:22:52.672] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:52 [20:22:52.678] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x2badd62ee93d2c67e0fb710ac88802354119dbb7a698e3000477d4714f02bd2c 20:22:52 [20:22:52.679] VERBOSE: sequencer-client Sent L1 transaction 0x2badd62ee93d2c67e0fb710ac88802354119dbb7a698e3000477d4714f02bd2c {"gasLimit":514585,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 20:22:52 [20:22:52.696] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:22:52 [20:22:52.698] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x2badd62ee93d2c67e0fb710ac88802354119dbb7a698e3000477d4714f02bd2c","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":1,"blockTimestamp":1750192028,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:22:52 [20:22:52.698] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:22:52 [20:22:52.722] INFO: blob-sink:server Received blob sidecar for block 0x3d9bd6f465463d1786fab486440327932ad7a0192fb5f2b068115d1e575b45e0 20:22:52 [20:22:52.734] INFO: blob-sink:server Blob sidecar stored successfully for block 0x3d9bd6f465463d1786fab486440327932ad7a0192fb5f2b068115d1e575b45e0 20:22:52 [20:22:52.877] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:52 [20:22:52.882] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 20:22:52 [20:22:52.891] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 20:22:52 [20:22:52.895] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 20:22:52 [20:22:52.905] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750192064] on http://127.0.0.1:8545 20:22:52 [20:22:52.908] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:22:52 [20:22:52.910] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750192064 20:22:52 [20:22:52.911] WARN: foundation:test-date-provider Time set to 2025-06-17T20:27:44.000Z {"offset":291090,"timeMs":1750192064000} 20:22:52 [20:22:52.911] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 20:22:53 [20:22:53.098] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:53 [20:22:53.122] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 20:22:53 [20:22:53.135] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x12c77967347bc95fceb9bfa763bdfaa92f84d7267d6619ea12d2dc9d58c16a91","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":3123621913,"blockNumber":1,"slotNumber":2,"timestamp":1750192028,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x1b108d76f1eac65c45daa8347b6f20578d72c5b2f49ea710372c37a3d3d69af3","archiveNextLeafIndex":2} 20:22:53 [20:22:53.149] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 20:22:53 [20:22:53.565] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:53 [20:22:53.568] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":350.0768739999985,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750192028,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:22:53 [20:22:53.569] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 20:22:53 [20:22:53.572] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b 20:22:53 [20:22:53.809] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:53 [20:22:53.824] VERBOSE: p2p Synched to latest block 1 20:22:54 [20:22:54.107] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:54 [20:22:54.126] INFO: pxe:service Added contract Test at 0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 20:22:54 [20:22:54.151] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x1b108d76f1eac65c45daa8347b6f20578d72c5b2f49ea710372c37a3d3d69af3","header":{"contentCommitment":{"blobsHash":"0x00756ed86cc8ae64ff70c2fc363943fc14bbfbadf1684032556a2c0734e0bd30","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750192028,"version":3123621913},"lastArchive":"0x04debdceefa1a9c93aac83174676cdf975ea6a4dac8102e41b5c3a5fe53a3301","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x1514a7d966aab352942d2c849ec3078571a0db7e3d1e80d0b055245a2e0fd91c","nullifierTree":"0x058a7a8a74b5a8435a91dbe7f2fa48c216647f1818aa048fe483d8c96b07f07f","publicDataTree":"0x2426bb0a6eef6446ff2952311af565017e32009e571d5e3f4ddbeac246dbc973"},"totalFees":6794052480,"totalManaUsed":20448}} 20:22:54 [20:22:54.265] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:22:54 [20:22:54.306] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:22:54 [20:22:54.307] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:22:54 [20:22:54.315] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:54 [20:22:54.384] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1b108d76f1eac65c45daa8347b6f20578d72c5b2f49ea710372c37a3d3d69af3","blockNumber":2,"slot":3} 20:22:54 [20:22:54.433] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:22:54 [20:22:54.448] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f558d0a00386847947da89b218ac2ca8306b7159128c05cce1fec41e19670e0 20:22:54 [20:22:54.470] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:22:54 [20:22:54.472] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:22:54 [20:22:54.506] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:22:54 [20:22:54.507] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:22:54 [20:22:54.514] VERBOSE: pxe:service Added note {"index":0,"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a1868b5f4ca4f5cd1ec898f36b0fe8a16e4fc3bf93b6996ed5191e16ffff395","nullifier":"0x074a5ddc240bd6f3b3617ef18aee1278262785d162395f5b589a145896de52b7"} 20:22:54 [20:22:54.518] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:22:54 [20:22:54.522] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:54 [20:22:54.602] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:22:54 [20:22:54.726] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:54 [20:22:54.750] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 20:22:55 [20:22:55.223] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 20:22:55 [20:22:55.359] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 20:22:55 [20:22:55.529] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 20:22:55 [20:22:55.540] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1b108d76f1eac65c45daa8347b6f20578d72c5b2f49ea710372c37a3d3d69af3","blockNumber":2,"slot":3} 20:22:55 [20:22:55.571] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 20:22:55 [20:22:55.582] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b,0x0000000000000000000000000000000000000000000000000000000000000001,0x1709554960a8c6f7f84c1c0adb25778399fd1a4a36274f5d9d66fcc6fa06e77c,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 20:22:55 [20:22:55.789] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:56 [20:22:56.371] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:22:56 [20:22:56.392] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:56 [20:22:56.399] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:22:56 [20:22:56.400] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:22:56 [20:22:56.671] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:56 [20:22:56.693] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1b108d76f1eac65c45daa8347b6f20578d72c5b2f49ea710372c37a3d3d69af3","blockNumber":2,"slot":3} 20:22:56 [20:22:56.728] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:22:56 [20:22:56.752] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:22:56 [20:22:56.903] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:57 [20:22:57.114] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:57 [20:22:57.349] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:57 [20:22:57.548] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1b108d76f1eac65c45daa8347b6f20578d72c5b2f49ea710372c37a3d3d69af3","blockNumber":2,"slot":3} 20:22:57 [20:22:57.691] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:57 [20:22:57.948] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:58 [20:22:58.154] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:58 [20:22:58.413] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:58 [20:22:58.925] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1b108d76f1eac65c45daa8347b6f20578d72c5b2f49ea710372c37a3d3d69af3","blockNumber":2,"slot":3} 20:22:59 [20:22:59.122] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:59 [20:22:59.475] INFO: pxe:service Proving completed in 5333.377248000001ms {"timings":{"total":5333.377248000001,"sync":10.630490999999893,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":912.0833819999934,"oracles":{"debugLog":{"times":[0.8067209999971965,0.8500630000016827,0.7861009999978705,1.2374930000005406,0.7201889999996638,0.5321640000001935,0.6886180000001332,0.873053000002983]},"fetchTaggedLogs":{"times":[128.32422199999928]},"loadCapsule":{"times":[0.22481600000173785,0.3439189999990049,10.753543999999238,0.34675900000002,0.5533240000004298,0.3563300000023446]},"getSharedSecret":{"times":[9.162582000000839]},"aes128Decrypt":{"times":[1.959430999999313,1.7730660000015632]},"getPublicKeysAndPartialAddress":{"times":[21.929520000001503]},"getKeyValidationRequest":{"times":[7.480318000001716]},"deliverNote":{"times":[7.147369000002072]},"deleteCapsule":{"times":[0.8892430000014429]},"storeCapsule":{"times":[0.7491399999998976]},"getNotes":{"times":[6.234775000000809]},"getAuthWitness":{"times":[0.3150279999972554]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.10805299999992712]},"callPrivateFunction":{"times":[825.1634250000025,157.40284099999917,1189.7655220000015]}}},{"functionName":"private_kernel_init","time":95.06652299999769},{"functionName":"ContractClassRegisterer:register","time":807.7526240000007,"oracles":{"loadCapsule":{"times":[10.401985000000423]},"notifyCreatedNullifier":{"times":[0.5301140000010491]},"debugLog":{"times":[0.6969390000012936]},"notifyCreatedContractClassLog":{"times":[12.564281999999366]}}},{"functionName":"private_kernel_inner","time":185.84846299999845},{"functionName":"ContractInstanceDeployer:deploy","time":104.3513389999971,"oracles":{"storeInExecutionCache":{"times":[0.29939699999886216]},"callPrivateFunction":{"times":[42.148473999997805]},"notifyCreatedNullifier":{"times":[0.29412800000136485]},"debugLog":{"times":[0.5768150000003516]}}},{"functionName":"private_kernel_inner","time":133.91253999999753},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":27.646921000003204,"oracles":{}},{"functionName":"private_kernel_inner","time":159.02999500000078},{"functionName":"Test:initialize","time":750.6992160000009,"oracles":{"getContractInstance":{"times":[7.364814000000479]},"debugLog":{"times":[0.6027859999994689,0.8352019999983895]},"fetchTaggedLogs":{"times":[331.34458900000027]},"loadCapsule":{"times":[15.010046999999759,4.70986499999708,0.8954740000008314]},"notifyCreatedNullifier":{"times":[0.440522000000783]}}},{"functionName":"private_kernel_inner","time":180.30249800000092},{"functionName":"private_kernel_reset","time":248.70211299999937},{"functionName":"private_kernel_tail","time":107.57995700000174}],"unaccounted":1609.771186000009}} 20:22:59 [20:22:59.501] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:59 [20:22:59.703] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1b108d76f1eac65c45daa8347b6f20578d72c5b2f49ea710372c37a3d3d69af3","blockNumber":2,"slot":3} 20:22:59 [20:22:59.709] VERBOSE: p2p:tx_pool Adding tx 0x0366f8199d22505e56c573cad56d46c50679212e3714c689e52cee20ae1afa17 to pool {"eventName":"tx-added-to-pool","txHash":"0x0366f8199d22505e56c573cad56d46c50679212e3714c689e52cee20ae1afa17","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 20:22:59 [20:22:59.726] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:59 [20:22:59.727] INFO: node Received tx 0x0366f8199d22505e56c573cad56d46c50679212e3714c689e52cee20ae1afa17 {"txHash":"0x0366f8199d22505e56c573cad56d46c50679212e3714c689e52cee20ae1afa17"} 20:22:59 [20:22:59.727] INFO: pxe:service Sent transaction 0x0366f8199d22505e56c573cad56d46c50679212e3714c689e52cee20ae1afa17 20:22:59 [20:22:59.932] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:00 [20:23:00.138] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:00 [20:23:00.226] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 20:23:00 [20:23:00.244] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-17T20:27:44.000Z","now":"2025-06-17T20:27:51.334Z"} 20:23:00 [20:23:00.357] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:00 [20:23:00.635] VERBOSE: simulator:public-processor Processed tx 0x0366f8199d22505e56c573cad56d46c50679212e3714c689e52cee20ae1afa17 with no public calls in 266.506942ms {"txHash":"0x0366f8199d22505e56c573cad56d46c50679212e3714c689e52cee20ae1afa17","txFee":7272506880,"revertCode":0,"gasUsed":{"totalGas":{"daGas":310272,"l2Gas":21888},"billedGas":{"daGas":310272,"l2Gas":21888},"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":266.506942} 20:23:00 [20:23:00.637] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:00 [20:23:00.640] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.39472691100000157s {"duration":0.39472691100000157,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 20:23:00 [20:23:00.849] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:00 [20:23:00.865] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x182d2e1bea1c538a6c317bcd364e0312c4607cd4f7db36052a8906debfddfd0f","globalVariables":{"chainId":31337,"version":3123621913,"blockNumber":2,"slotNumber":3,"timestamp":1750192064,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x0366f8199d22505e56c573cad56d46c50679212e3714c689e52cee20ae1afa17"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":637.6342750000003,"publicProcessDuration":395.2012250000007,"rollupCircuitsDuration":621.8703260000002,"txCount":1,"blockNumber":2,"blockTimestamp":1750192064,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 20:23:00 [20:23:00.869] VERBOSE: sequencer Attesting committee is empty 20:23:01 [20:23:01.060] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.219] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x37e280cba438d0bf3f860bee757cd627af19f1a7a7d78d171f74b3a5ded3e16e 20:23:01 [20:23:01.219] VERBOSE: sequencer-client Sent L1 transaction 0x37e280cba438d0bf3f860bee757cd627af19f1a7a7d78d171f74b3a5ded3e16e {"gasLimit":470220,"maxFeePerGas":"1.303611208","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 20:23:01 [20:23:01.232] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750192100] on http://127.0.0.1:8545 20:23:01 [20:23:01.235] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:23:01 [20:23:01.236] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x37e280cba438d0bf3f860bee757cd627af19f1a7a7d78d171f74b3a5ded3e16e","calldataSize":1444,"calldataGas":12268,"txCount":1,"blockNumber":2,"blockTimestamp":1750192064,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 20:23:01 [20:23:01.236] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:23:01 [20:23:01.239] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:23:01 [20:23:01.242] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750192100 20:23:01 [20:23:01.242] WARN: foundation:test-date-provider Time set to 2025-06-17T20:28:20.000Z {"offset":318758,"timeMs":1750192100000} 20:23:01 [20:23:01.242] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 20:23:01 [20:23:01.248] INFO: blob-sink:server Received blob sidecar for block 0x2df11b7d2d206d27ff42243dc8e1de30a1dd2fc21e11ed30067a3484fe06cd13 20:23:01 [20:23:01.253] INFO: blob-sink:server Blob sidecar stored successfully for block 0x2df11b7d2d206d27ff42243dc8e1de30a1dd2fc21e11ed30067a3484fe06cd13 20:23:01 [20:23:01.260] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 20:23:01 [20:23:01.261] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 20:23:01 [20:23:01.262] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.263] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 20:23:01 [20:23:01.464] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.667] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.928] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.947] VERBOSE: archiver:block-helper Store contract instance at 0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b 20:23:01 [20:23:01.967] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 20:23:02 [20:23:02.003] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x182d2e1bea1c538a6c317bcd364e0312c4607cd4f7db36052a8906debfddfd0f","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":3123621913,"blockNumber":2,"slotNumber":3,"timestamp":1750192064,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","archiveNextLeafIndex":3} 20:23:02 [20:23:02.009] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 20:23:02 [20:23:02.051] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":8.563020000001416,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750192064,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 20:23:02 [20:23:02.137] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:02 [20:23:02.142] VERBOSE: p2p Synched to latest block 2 20:23:02 [20:23:02.290] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","blockNumber":3,"slot":4} 20:23:02 [20:23:02.341] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:02 [20:23:02.546] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:02 [20:23:02.753] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:02 [20:23:02.809] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","blockNumber":3,"slot":4} 20:23:03 [20:23:03.161] INFO: aztecjs:deploy_sent_tx Contract 0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b successfully deployed. 20:23:03 [20:23:03.165] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with no enqueued public calls sets the max block number 20:23:03 [20:23:03.166] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:03 [20:23:03.221] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","header":{"contentCommitment":{"blobsHash":"0x001de4fb11eb6ae95d19fa35a0946084a4b13e0ec668e6650392ab57c92bb910","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750192064,"version":3123621913},"lastArchive":"0x1b108d76f1eac65c45daa8347b6f20578d72c5b2f49ea710372c37a3d3d69af3","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x1514a7d966aab352942d2c849ec3078571a0db7e3d1e80d0b055245a2e0fd91c","nullifierTree":"0x046225ec17c4001769031e4e0341ebd0a4db867fe7f82605ebc1aaa31fda54f9","publicDataTree":"0x29d474d1fee22c6259e54d9a54d32c8772bcb36f53354111d5d19c8205f814c5"},"totalFees":7272506880,"totalManaUsed":21888}} 20:23:03 [20:23:03.318] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:03 [20:23:03.369] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:03 [20:23:03.370] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:03 [20:23:03.380] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:03 [20:23:03.435] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","blockNumber":3,"slot":4} 20:23:03 [20:23:03.460] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:03 [20:23:03.466] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f558d0a00386847947da89b218ac2ca8306b7159128c05cce1fec41e19670e0 20:23:03 [20:23:03.474] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:03 [20:23:03.475] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:03 [20:23:03.484] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:03 [20:23:03.484] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:03 [20:23:03.487] VERBOSE: pxe:service Added note {"index":0,"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a1868b5f4ca4f5cd1ec898f36b0fe8a16e4fc3bf93b6996ed5191e16ffff395","nullifier":"0x074a5ddc240bd6f3b3617ef18aee1278262785d162395f5b589a145896de52b7"} 20:23:03 [20:23:03.488] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:03 [20:23:03.499] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:23:03 [20:23:03.627] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:04 [20:23:04.048] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:04 [20:23:04.060] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:04 [20:23:04.060] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:04 [20:23:04.063] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:04 [20:23:04.220] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","blockNumber":3,"slot":4} 20:23:04 [20:23:04.230] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:04 [20:23:04.233] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:04 [20:23:04.313] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:04 [20:23:04.838] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:05 [20:23:05.003] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","blockNumber":3,"slot":4} 20:23:05 [20:23:05.220] INFO: pxe:service Proving completed in 2018.8679059999995ms {"timings":{"total":2018.8679059999995,"sync":22.27676900000006,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":551.3503480000036,"oracles":{"debugLog":{"times":[0.6904699999977311,0.360209999998915,0.3807409999972151,0.3870309999983874,0.39214200000060373,0.311719000001176,0.30087900000216905,0.3968510000013339]},"fetchTaggedLogs":{"times":[94.21346699999776]},"loadCapsule":{"times":[0.21719600000142236,0.17799500000182888,0.40719199999875855,0.24013600000034785,0.1684049999967101,0.17127499999696738]},"getSharedSecret":{"times":[2.905764999999519]},"aes128Decrypt":{"times":[0.19154599999819766,0.6913300000014715]},"getPublicKeysAndPartialAddress":{"times":[3.182952999999543]},"getKeyValidationRequest":{"times":[2.8667629999981727]},"deliverNote":{"times":[2.7097690000009607]},"deleteCapsule":{"times":[0.2041159999971569]},"storeCapsule":{"times":[0.25670700000046054]},"getNotes":{"times":[1.2229960000004212]},"getAuthWitness":{"times":[0.15043499999956111]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.03783100000146078]},"callPrivateFunction":{"times":[737.7548160000006]}}},{"functionName":"private_kernel_init","time":53.828239999998914},{"functionName":"Test:set_tx_max_block_number","time":434.9293639999996,"oracles":{"debugLog":{"times":[0.4840540000004694,0.26999699999942095]},"fetchTaggedLogs":{"times":[171.8332709999995]},"loadCapsule":{"times":[0.2630779999999504,0.1789649999991525,0.18467500000042492]}}},{"functionName":"private_kernel_inner","time":109.56703500000003},{"functionName":"private_kernel_reset","time":167.22685699999784},{"functionName":"private_kernel_tail","time":66.0352460000031}],"unaccounted":613.6540469999964}} 20:23:05 [20:23:05.225] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with no enqueued public calls does not invalidate the transaction 20:23:05 [20:23:05.225] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:05 [20:23:05.316] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:05 [20:23:05.349] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:05 [20:23:05.349] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:05 [20:23:05.391] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:05 [20:23:05.395] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f558d0a00386847947da89b218ac2ca8306b7159128c05cce1fec41e19670e0 20:23:05 [20:23:05.404] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:05 [20:23:05.405] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:05 [20:23:05.419] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:05 [20:23:05.420] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:05 [20:23:05.424] VERBOSE: pxe:service Added note {"index":0,"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a1868b5f4ca4f5cd1ec898f36b0fe8a16e4fc3bf93b6996ed5191e16ffff395","nullifier":"0x074a5ddc240bd6f3b3617ef18aee1278262785d162395f5b589a145896de52b7"} 20:23:05 [20:23:05.427] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:05 [20:23:05.439] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:23:05 [20:23:05.444] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:05 [20:23:05.683] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:05 [20:23:05.803] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:05 [20:23:05.814] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:05 [20:23:05.814] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:05 [20:23:05.955] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","blockNumber":3,"slot":4} 20:23:06 [20:23:06.001] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:06 [20:23:06.006] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:06 [20:23:06.044] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:06 [20:23:06.456] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:06 [20:23:06.766] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:06 [20:23:06.917] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","blockNumber":3,"slot":4} 20:23:07 [20:23:07.150] INFO: pxe:service Proving completed in 1905.609192ms {"timings":{"total":1905.609192,"sync":2.9458359999989625,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":311.5187739999965,"oracles":{"debugLog":{"times":[0.500793999999587,0.34024999999746797,0.45699299999978393,0.4674329999979818,0.8309539999972912,0.35222000000067055,0.3868010000005597,0.46076299999913317]},"fetchTaggedLogs":{"times":[43.99067300000024]},"loadCapsule":{"times":[0.1719150000026275,0.17831500000102096,0.3385199999975157,0.4307520000002114,0.2502469999999448,0.15983499999856576]},"getSharedSecret":{"times":[2.854573000000528]},"aes128Decrypt":{"times":[0.18944499999997788,0.789162999997643]},"getPublicKeysAndPartialAddress":{"times":[1.4152409999987867]},"getKeyValidationRequest":{"times":[2.9198749999995925]},"deliverNote":{"times":[4.756459000000177]},"deleteCapsule":{"times":[0.40752100000099745]},"storeCapsule":{"times":[0.27585800000088057]},"getNotes":{"times":[1.475973000000522]},"getAuthWitness":{"times":[0.15625400000135414]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05258200000025681]},"callPrivateFunction":{"times":[573.6845700000013]}}},{"functionName":"private_kernel_init","time":60.097083000000566},{"functionName":"Test:set_tx_max_block_number","time":447.76404800000455,"oracles":{"debugLog":{"times":[0.48196399999869755,0.4753740000014659]},"fetchTaggedLogs":{"times":[190.05455200000142]},"loadCapsule":{"times":[0.3298399999985122,0.45878300000185845,0.4168420000023616]}}},{"functionName":"private_kernel_inner","time":118.9955000000009},{"functionName":"private_kernel_reset","time":168.0642110000008},{"functionName":"private_kernel_tail","time":66.0970269999998}],"unaccounted":730.1267129999978}} 20:23:07 [20:23:07.162] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:07 [20:23:07.188] VERBOSE: p2p:tx_pool Adding tx 0x2ebb2c8267be9d3aa850c624ffc71d9a01580549c762278f2040de4e0364e2dd to pool {"eventName":"tx-added-to-pool","txHash":"0x2ebb2c8267be9d3aa850c624ffc71d9a01580549c762278f2040de4e0364e2dd","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 20:23:07 [20:23:07.196] INFO: node Received tx 0x2ebb2c8267be9d3aa850c624ffc71d9a01580549c762278f2040de4e0364e2dd {"txHash":"0x2ebb2c8267be9d3aa850c624ffc71d9a01580549c762278f2040de4e0364e2dd"} 20:23:07 [20:23:07.197] INFO: pxe:service Sent transaction 0x2ebb2c8267be9d3aa850c624ffc71d9a01580549c762278f2040de4e0364e2dd 20:23:07 [20:23:07.367] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:07 [20:23:07.441] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 20:23:07 [20:23:07.456] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-17T20:28:20.000Z","now":"2025-06-17T20:28:26.214Z"} 20:23:07 [20:23:07.481] VERBOSE: simulator:public-processor Processed tx 0x2ebb2c8267be9d3aa850c624ffc71d9a01580549c762278f2040de4e0364e2dd with no public calls in 15.748309000002337ms {"txHash":"0x2ebb2c8267be9d3aa850c624ffc71d9a01580549c762278f2040de4e0364e2dd","txFee":1945714560,"revertCode":0,"gasUsed":{"totalGas":{"daGas":1024,"l2Gas":5856},"billedGas":{"daGas":1024,"l2Gas":5856},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":1,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":15.748309000002337} 20:23:07 [20:23:07.483] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.025965337999998155s {"duration":0.025965337999998155,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1024,"l2Gas":5856},"totalSizeInBytes":256} 20:23:07 [20:23:07.612] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:07 [20:23:07.628] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x04b31ea0194bbd20d1035fa022948426eb2be53dd8685fb51f115497ecac1641","globalVariables":{"chainId":31337,"version":3123621913,"blockNumber":3,"slotNumber":4,"timestamp":1750192100,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x2ebb2c8267be9d3aa850c624ffc71d9a01580549c762278f2040de4e0364e2dd"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":186.71439499999906,"publicProcessDuration":26.456672000000253,"rollupCircuitsDuration":172.34252600000036,"txCount":1,"blockNumber":3,"blockTimestamp":1750192100,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:07 [20:23:07.631] VERBOSE: sequencer Attesting committee is empty 20:23:07 [20:23:07.907] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:07 [20:23:07.917] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x51d7304d70292d3f5c6bf8afa7437a24f895242015855c9b0c9c8234e988045a 20:23:07 [20:23:07.917] VERBOSE: sequencer-client Sent L1 transaction 0x51d7304d70292d3f5c6bf8afa7437a24f895242015855c9b0c9c8234e988045a {"gasLimit":470280,"maxFeePerGas":"1.279583392","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 20:23:07 [20:23:07.929] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750192136] on http://127.0.0.1:8545 20:23:07 [20:23:07.932] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:23:07 [20:23:07.935] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:23:07 [20:23:07.935] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x51d7304d70292d3f5c6bf8afa7437a24f895242015855c9b0c9c8234e988045a","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":3,"blockTimestamp":1750192100,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:07 [20:23:07.936] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:23:07 [20:23:07.938] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750192136 20:23:07 [20:23:07.939] WARN: foundation:test-date-provider Time set to 2025-06-17T20:28:56.000Z {"offset":348061,"timeMs":1750192136000} 20:23:07 [20:23:07.939] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 20:23:07 [20:23:07.942] INFO: blob-sink:server Received blob sidecar for block 0xd5d657975859ac5a64bb31be34d754548764bdb882094e223c1b8ae54921fc0c 20:23:07 [20:23:07.947] INFO: blob-sink:server Blob sidecar stored successfully for block 0xd5d657975859ac5a64bb31be34d754548764bdb882094e223c1b8ae54921fc0c 20:23:08 [20:23:08.110] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:08 [20:23:08.116] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 20:23:08 [20:23:08.118] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 20:23:08 [20:23:08.120] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 20:23:08 [20:23:08.397] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:08 [20:23:08.410] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x04b31ea0194bbd20d1035fa022948426eb2be53dd8685fb51f115497ecac1641","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":3123621913,"blockNumber":3,"slotNumber":4,"timestamp":1750192100,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x06c91fd89c3290d9684b0c9ec4c9ebd617c51468df3032e0a1a6da041a5fde80","archiveNextLeafIndex":4} 20:23:08 [20:23:08.414] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 20:23:08 [20:23:08.521] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":7.637191999998322,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750192100,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:08 [20:23:08.526] VERBOSE: p2p Synched to latest block 3 20:23:08 [20:23:08.602] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:08 [20:23:08.805] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:08 [20:23:08.954] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x06c91fd89c3290d9684b0c9ec4c9ebd617c51468df3032e0a1a6da041a5fde80","blockNumber":4,"slot":5} 20:23:09 [20:23:09.009] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:09 [20:23:09.204] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with an enqueued public call sets the max block number 20:23:09 [20:23:09.213] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:09 [20:23:09.228] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x06c91fd89c3290d9684b0c9ec4c9ebd617c51468df3032e0a1a6da041a5fde80","header":{"contentCommitment":{"blobsHash":"0x00aac03b66e459f7e09485e7e083af8a7ca88a6ca8b6ee39753b3d79515698f1","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750192100,"version":3123621913},"lastArchive":"0x23289a8b4a6940d109330fa865879ea2623ddb64c0f389ecbe91e7be13ed1ff6","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x1514a7d966aab352942d2c849ec3078571a0db7e3d1e80d0b055245a2e0fd91c","nullifierTree":"0x22a3c317dd5c59d6b6cb9ffa48d0a5a7a8fdff4f97c9434da014c81d69f2f4b4","publicDataTree":"0x056bc3c33384acfa8be1b9116f4444713160086406a5e1769a781a7bf4cbf7e4"},"totalFees":1945714560,"totalManaUsed":5856}} 20:23:09 [20:23:09.292] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:09 [20:23:09.330] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:09 [20:23:09.330] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:09 [20:23:09.381] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:09 [20:23:09.387] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f558d0a00386847947da89b218ac2ca8306b7159128c05cce1fec41e19670e0 20:23:09 [20:23:09.398] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:09 [20:23:09.399] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:09 [20:23:09.406] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:09 [20:23:09.406] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:09 [20:23:09.412] VERBOSE: pxe:service Added note {"index":0,"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a1868b5f4ca4f5cd1ec898f36b0fe8a16e4fc3bf93b6996ed5191e16ffff395","nullifier":"0x074a5ddc240bd6f3b3617ef18aee1278262785d162395f5b589a145896de52b7"} 20:23:09 [20:23:09.414] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:09 [20:23:09.428] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:23:09 [20:23:09.430] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:09 [20:23:09.673] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:09 [20:23:09.811] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:09 [20:23:09.822] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:09 [20:23:09.823] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:09 [20:23:09.952] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:09 [20:23:09.965] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x06c91fd89c3290d9684b0c9ec4c9ebd617c51468df3032e0a1a6da041a5fde80","blockNumber":4,"slot":5} 20:23:09 [20:23:09.980] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:09 [20:23:09.984] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:10 [20:23:10.207] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:10 [20:23:10.464] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:10 [20:23:10.756] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:10 [20:23:10.895] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x06c91fd89c3290d9684b0c9ec4c9ebd617c51468df3032e0a1a6da041a5fde80","blockNumber":4,"slot":5} 20:23:11 [20:23:11.124] INFO: pxe:service Proving completed in 1904.886900999998ms {"timings":{"total":1904.886900999998,"sync":10.870597000000998,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":341.683906000002,"oracles":{"debugLog":{"times":[0.6483180000032007,0.4286920000013197,0.5210449999976845,0.5489259999994829,0.4574030000003404,0.4296429999994871,0.35425000000032014,0.4815340000022843]},"fetchTaggedLogs":{"times":[54.594632000000274]},"loadCapsule":{"times":[0.17810600000302657,0.2319869999992079,0.40811199999734527,0.27916800000093644,0.16956500000014785,0.24960699999792268]},"getSharedSecret":{"times":[3.3865889999979117]},"aes128Decrypt":{"times":[0.26820799999768496,1.0255599999982223]},"getPublicKeysAndPartialAddress":{"times":[1.7355810000008205]},"getKeyValidationRequest":{"times":[1.6493379999992612]},"deliverNote":{"times":[5.722216999998636]},"deleteCapsule":{"times":[0.3295490000018617]},"storeCapsule":{"times":[0.24271699999735574]},"getNotes":{"times":[1.3261989999991783]},"getAuthWitness":{"times":[0.22334699999919394]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.048502000001462875]},"callPrivateFunction":{"times":[561.7974939999986]}}},{"functionName":"private_kernel_init","time":90.08021700000245},{"functionName":"Test:set_tx_max_block_number","time":417.3654809999971,"oracles":{"debugLog":{"times":[0.5471160000015516,0.4332330000033835]},"fetchTaggedLogs":{"times":[159.69232699999702]},"loadCapsule":{"times":[0.2326969999994617,0.20098600000346778,0.2114270000020042]},"storeInExecutionCache":{"times":[0.050641000001633074]},"notifyEnqueuedPublicFunctionCall":{"times":[0.4192830000029062]}}},{"functionName":"private_kernel_inner","time":110.07856000000174},{"functionName":"private_kernel_reset","time":157.49618299999565},{"functionName":"private_kernel_tail","time":76.65098499999294}],"unaccounted":700.6609720000051}} 20:23:11 [20:23:11.126] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with an enqueued public call does not invalidate the transaction 20:23:11 [20:23:11.128] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:11 [20:23:11.209] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:11 [20:23:11.255] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:11 [20:23:11.255] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:11 [20:23:11.311] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:11 [20:23:11.317] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f558d0a00386847947da89b218ac2ca8306b7159128c05cce1fec41e19670e0 20:23:11 [20:23:11.330] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:11 [20:23:11.331] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:11 [20:23:11.335] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:11 [20:23:11.348] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:11 [20:23:11.349] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:11 [20:23:11.356] VERBOSE: pxe:service Added note {"index":0,"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a1868b5f4ca4f5cd1ec898f36b0fe8a16e4fc3bf93b6996ed5191e16ffff395","nullifier":"0x074a5ddc240bd6f3b3617ef18aee1278262785d162395f5b589a145896de52b7"} 20:23:11 [20:23:11.358] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:11 [20:23:11.378] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:23:11 [20:23:11.680] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:11 [20:23:11.799] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:11 [20:23:11.812] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:11 [20:23:11.812] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:11 [20:23:11.945] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x06c91fd89c3290d9684b0c9ec4c9ebd617c51468df3032e0a1a6da041a5fde80","blockNumber":4,"slot":5} 20:23:11 [20:23:11.955] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:11 [20:23:11.958] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:12 [20:23:12.046] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:12 [20:23:12.618] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:12 [20:23:12.792] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x06c91fd89c3290d9684b0c9ec4c9ebd617c51468df3032e0a1a6da041a5fde80","blockNumber":4,"slot":5} 20:23:13 [20:23:13.032] INFO: pxe:service Proving completed in 1886.8106169999955ms {"timings":{"total":1886.8106169999955,"sync":3.1460619999998016,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":344.0272160000095,"oracles":{"debugLog":{"times":[0.5998869999966701,0.4304120000015246,0.5324760000003153,0.7778120000002673,0.5506959999984247,0.43315199999778997,0.4429930000042077,0.5808170000018436]},"fetchTaggedLogs":{"times":[59.168956000001344]},"loadCapsule":{"times":[0.20814599999721395,0.19145600000047125,0.46861400000489084,0.2762380000058329,0.23364700000092853,0.24842699999862816]},"getSharedSecret":{"times":[4.011536999998498]},"aes128Decrypt":{"times":[0.4317330000048969,1.3184089999995194]},"getPublicKeysAndPartialAddress":{"times":[5.913831999998365]},"getKeyValidationRequest":{"times":[6.7868279999966035]},"deliverNote":{"times":[6.980733000003966]},"deleteCapsule":{"times":[0.3317490000044927]},"storeCapsule":{"times":[0.3386899999968591]},"getNotes":{"times":[3.5492540000050212]},"getAuthWitness":{"times":[0.2582870000042021]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.10587300000042887]},"callPrivateFunction":{"times":[583.2042099999962]}}},{"functionName":"private_kernel_init","time":56.186705000000075},{"functionName":"Test:set_tx_max_block_number","time":456.8031889999984,"oracles":{"debugLog":{"times":[0.7003800000020419,0.30883799999719486]},"fetchTaggedLogs":{"times":[144.62265399999887]},"loadCapsule":{"times":[0.15423500000179047,0.15005399999790825,0.20609600000170758]},"storeInExecutionCache":{"times":[0.04600100000243401]},"notifyEnqueuedPublicFunctionCall":{"times":[0.045352000001003034]}}},{"functionName":"private_kernel_inner","time":133.5942240000004},{"functionName":"private_kernel_reset","time":170.65237999999954},{"functionName":"private_kernel_tail","time":80.5607530000052}],"unaccounted":641.8400879999826}} 20:23:13 [20:23:13.060] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:13 [20:23:13.080] VERBOSE: p2p:tx_pool Adding tx 0x2c68ed17c1562d7513e56d73c6493a890be3eeb40e356347b84c234f7b8cff9d to pool {"eventName":"tx-added-to-pool","txHash":"0x2c68ed17c1562d7513e56d73c6493a890be3eeb40e356347b84c234f7b8cff9d","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226281,"feePaymentMethod":"fee_juice"} 20:23:13 [20:23:13.088] INFO: node Received tx 0x2c68ed17c1562d7513e56d73c6493a890be3eeb40e356347b84c234f7b8cff9d {"txHash":"0x2c68ed17c1562d7513e56d73c6493a890be3eeb40e356347b84c234f7b8cff9d"} 20:23:13 [20:23:13.089] INFO: pxe:service Sent transaction 0x2c68ed17c1562d7513e56d73c6493a890be3eeb40e356347b84c234f7b8cff9d 20:23:13 [20:23:13.264] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:13 [20:23:13.309] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 20:23:13 [20:23:13.321] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-17T20:28:56.000Z","now":"2025-06-17T20:29:01.382Z"} 20:23:13 [20:23:13.358] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x5189a417 completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x5189a417","duration":19.012204999999085} 20:23:13 [20:23:13.379] VERBOSE: simulator:public-processor Processed tx 0x2c68ed17c1562d7513e56d73c6493a890be3eeb40e356347b84c234f7b8cff9d with 1 public calls in 48.32592499999737ms {"txHash":"0x2c68ed17c1562d7513e56d73c6493a890be3eeb40e356347b84c234f7b8cff9d","txFee":1487487300,"revertCode":0,"gasUsed":{"totalGas":{"daGas":1024,"l2Gas":29339},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":3483},"billedGas":{"daGas":1024,"l2Gas":29339}},"publicDataWriteCount":1,"nullifierCount":1,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":48.32592499999737} 20:23:13 [20:23:13.380] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.05879119399999763s {"duration":0.05879119399999763,"rate":59243.56630688842,"totalPublicGas":{"daGas":0,"l2Gas":3483},"totalBlockGas":{"daGas":1024,"l2Gas":29339},"totalSizeInBytes":256} 20:23:13 [20:23:13.472] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:13 [20:23:13.480] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 20389.043198634252 mana/s {"blockHash":"0x127388eec6e5f301b1d5b4022722c33645f1f9f9e5c40ccd15a34f2e2a794b9c","globalVariables":{"chainId":31337,"version":3123621913,"blockNumber":4,"slotNumber":5,"timestamp":1750192136,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x2c68ed17c1562d7513e56d73c6493a890be3eeb40e356347b84c234f7b8cff9d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":170.14287599999807,"publicProcessDuration":59.171573999999964,"rollupCircuitsDuration":159.7000479999988,"txCount":1,"blockNumber":4,"blockTimestamp":1750192136,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:13 [20:23:13.482] VERBOSE: sequencer Attesting committee is empty 20:23:13 [20:23:13.722] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:13 [20:23:13.731] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x331380403e73a8db7bb336776a617e5b7dedb0b868e2f7bd744e60e549b0f37e 20:23:13 [20:23:13.731] VERBOSE: sequencer-client Sent L1 transaction 0x331380403e73a8db7bb336776a617e5b7dedb0b868e2f7bd744e60e549b0f37e {"gasLimit":470215,"maxFeePerGas":"1.261127734","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 20:23:13 [20:23:13.741] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:23:13 [20:23:13.742] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x331380403e73a8db7bb336776a617e5b7dedb0b868e2f7bd744e60e549b0f37e","calldataSize":1444,"calldataGas":12268,"txCount":1,"blockNumber":4,"blockTimestamp":1750192136,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:13 [20:23:13.742] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:23:13 [20:23:13.747] INFO: blob-sink:server Received blob sidecar for block 0x3e5c4a9a22a4efc7f984011285bb29d34b168b6ec53da485013513fe02e20ba2 20:23:13 [20:23:13.751] INFO: blob-sink:server Blob sidecar stored successfully for block 0x3e5c4a9a22a4efc7f984011285bb29d34b168b6ec53da485013513fe02e20ba2 20:23:13 [20:23:13.776] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750192172] on http://127.0.0.1:8545 20:23:13 [20:23:13.777] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:23:13 [20:23:13.778] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750192172 20:23:13 [20:23:13.778] WARN: foundation:test-date-provider Time set to 2025-06-17T20:29:32.000Z {"offset":378222,"timeMs":1750192172000} 20:23:13 [20:23:13.778] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 20:23:13 [20:23:13.928] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 20:23:13 [20:23:13.929] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 20:23:13 [20:23:13.931] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 20:23:13 [20:23:13.931] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:14 [20:23:14.215] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:14 [20:23:14.232] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x127388eec6e5f301b1d5b4022722c33645f1f9f9e5c40ccd15a34f2e2a794b9c","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":3123621913,"blockNumber":4,"slotNumber":5,"timestamp":1750192136,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","archiveNextLeafIndex":5} 20:23:14 [20:23:14.235] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 20:23:14 [20:23:14.340] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":7.518980000000738,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1750192136,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:14 [20:23:14.348] VERBOSE: p2p Synched to latest block 4 20:23:14 [20:23:14.422] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:14 [20:23:14.625] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:14 [20:23:14.771] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:14 [20:23:14.830] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:15 [20:23:15.034] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:15 [20:23:15.095] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with no enqueued public calls sets the max block number 20:23:15 [20:23:15.118] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","header":{"contentCommitment":{"blobsHash":"0x0092fc32244b21913259929799670639576c9ed20f93d5d9b8f0ae27a9e2f8ed","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750192136,"version":3123621913},"lastArchive":"0x06c91fd89c3290d9684b0c9ec4c9ebd617c51468df3032e0a1a6da041a5fde80","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x1514a7d966aab352942d2c849ec3078571a0db7e3d1e80d0b055245a2e0fd91c","nullifierTree":"0x18558f4a7adc0dfcd9d442394d39ba23f1a16c3da5513bbc701051a197d03d6a","publicDataTree":"0x1bf1644fd6237539eb80a1482a86c54d563f10d824fb169ba302c704bcd7cda7"},"totalFees":1487487300,"totalManaUsed":29339}} 20:23:15 [20:23:15.215] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:15 [20:23:15.264] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:15 [20:23:15.265] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:15 [20:23:15.271] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:15 [20:23:15.327] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:15 [20:23:15.342] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:15 [20:23:15.346] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f558d0a00386847947da89b218ac2ca8306b7159128c05cce1fec41e19670e0 20:23:15 [20:23:15.355] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:15 [20:23:15.356] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:15 [20:23:15.361] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:15 [20:23:15.362] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:15 [20:23:15.364] VERBOSE: pxe:service Added note {"index":0,"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a1868b5f4ca4f5cd1ec898f36b0fe8a16e4fc3bf93b6996ed5191e16ffff395","nullifier":"0x074a5ddc240bd6f3b3617ef18aee1278262785d162395f5b589a145896de52b7"} 20:23:15 [20:23:15.366] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:15 [20:23:15.377] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:23:15 [20:23:15.502] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:15 [20:23:15.731] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:15 [20:23:15.742] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:15 [20:23:15.742] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:15 [20:23:15.906] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:15 [20:23:15.941] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:15 [20:23:15.942] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:15 [20:23:15.948] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:16 [20:23:16.176] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:16 [20:23:16.736] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:16 [20:23:16.892] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:17 [20:23:17.178] INFO: pxe:service Proving completed in 2066.7351019999987ms {"timings":{"total":2066.7351019999987,"sync":9.705539000002318,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":364.9140239999979,"oracles":{"debugLog":{"times":[0.6374789999972563,0.4021310000025551,0.3711509999993723,0.3911020000014105,0.3685200000036275,0.2751369999969029,0.264536999995471,0.5782869999966351]},"fetchTaggedLogs":{"times":[80.10896199999843]},"loadCapsule":{"times":[0.13904399999591988,0.13889300000300864,0.3101989999995567,0.15461399999912828,0.24284700000134762,0.17280499999469612]},"getSharedSecret":{"times":[2.7454890000008163]},"aes128Decrypt":{"times":[0.2112859999979264,0.667449000000488]},"getPublicKeysAndPartialAddress":{"times":[1.510802999997395]},"getKeyValidationRequest":{"times":[1.284287000002223]},"deliverNote":{"times":[2.579544000000169]},"deleteCapsule":{"times":[0.1959359999964363]},"storeCapsule":{"times":[0.20944599999347702]},"getNotes":{"times":[1.086470999995072]},"getAuthWitness":{"times":[0.20404599999892525]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07823300000018207]},"callPrivateFunction":{"times":[575.9988389999999]}}},{"functionName":"private_kernel_init","time":90.54301100000157},{"functionName":"Test:set_tx_max_block_number","time":453.0634890000001,"oracles":{"debugLog":{"times":[0.41850200000044424,0.3866509999934351]},"fetchTaggedLogs":{"times":[204.5688680000021]},"loadCapsule":{"times":[0.18593500000133645,0.23227700000279583,0.253086999997322]}}},{"functionName":"private_kernel_inner","time":121.72422700000607},{"functionName":"private_kernel_reset","time":200.21981300000334},{"functionName":"private_kernel_tail","time":91.84926799999812}],"unaccounted":734.7157309999893}} 20:23:17 [20:23:17.180] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with no enqueued public calls invalidates the transaction 20:23:17 [20:23:17.182] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:17 [20:23:17.284] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:17 [20:23:17.315] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:17 [20:23:17.316] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:17 [20:23:17.369] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:17 [20:23:17.375] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f558d0a00386847947da89b218ac2ca8306b7159128c05cce1fec41e19670e0 20:23:17 [20:23:17.397] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:17 [20:23:17.411] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:17 [20:23:17.412] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:17 [20:23:17.520] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:17 [20:23:17.521] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:17 [20:23:17.531] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:17 [20:23:17.533] VERBOSE: pxe:service Added note {"index":0,"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a1868b5f4ca4f5cd1ec898f36b0fe8a16e4fc3bf93b6996ed5191e16ffff395","nullifier":"0x074a5ddc240bd6f3b3617ef18aee1278262785d162395f5b589a145896de52b7"} 20:23:17 [20:23:17.535] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:17 [20:23:17.554] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:23:17 [20:23:17.738] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:18 [20:23:18.016] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:18 [20:23:18.027] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:18 [20:23:18.028] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:18 [20:23:18.185] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:18 [20:23:18.198] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:18 [20:23:18.221] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:18 [20:23:18.225] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:18 [20:23:18.420] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:18 [20:23:18.884] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:19 [20:23:19.015] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:19 [20:23:19.285] INFO: pxe:service Proving completed in 2083.1581739999965ms {"timings":{"total":2083.1581739999965,"sync":3.69160600000032,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":469.1095500000083,"oracles":{"debugLog":{"times":[0.5121349999972153,0.6356789999990724,0.6317280000002938,0.7536620000028051,0.6918099999966216,0.5079249999980675,0.4422519999934593,0.6537890000035986]},"fetchTaggedLogs":{"times":[57.57976400000189]},"loadCapsule":{"times":[0.30440899999666726,0.2992389999999432,0.5795270000016899,0.20448600000236183,0.19993499999691267,0.20052600000053644]},"getSharedSecret":{"times":[25.42005000000063]},"aes128Decrypt":{"times":[0.6807989999942947,2.8008000000045286]},"getPublicKeysAndPartialAddress":{"times":[22.50050700000429]},"getKeyValidationRequest":{"times":[81.80085099999997]},"deliverNote":{"times":[11.871351000001596]},"deleteCapsule":{"times":[0.3449590000018361]},"storeCapsule":{"times":[0.31557900000188965]},"getNotes":{"times":[2.150891999997839]},"getAuthWitness":{"times":[0.27864799999952083]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.062280999998620246]},"callPrivateFunction":{"times":[676.3294129999995]}}},{"functionName":"private_kernel_init","time":59.267722999997204},{"functionName":"Test:set_tx_max_block_number","time":550.8606469999941,"oracles":{"debugLog":{"times":[0.5808260000048904,0.4119120000032126]},"fetchTaggedLogs":{"times":[195.9432500000039]},"loadCapsule":{"times":[0.20248600000195438,0.17850500000349712,0.16694400000415044]}}},{"functionName":"private_kernel_inner","time":99.2382609999986},{"functionName":"private_kernel_reset","time":206.14976300000126},{"functionName":"private_kernel_tail","time":73.70625799999834}],"unaccounted":621.1343659999984}} 20:23:19 [20:23:19.296] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:19 [20:23:19.311] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x0609f8a9f04a1b40c95e96d2760f05abcf4ea6ddeebe65a640136e8b6073a8ed for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 20:23:19 [20:23:19.314] WARN: node Received invalid tx 0x0609f8a9f04a1b40c95e96d2760f05abcf4ea6ddeebe65a640136e8b6073a8ed: Invalid max block number {"txHash":"0x0609f8a9f04a1b40c95e96d2760f05abcf4ea6ddeebe65a640136e8b6073a8ed"} 20:23:19 [20:23:19.318] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 20:23:19 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 20:23:19 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 20:23:19 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 20:23:19 [20:23:19.320] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with an enqueued public call sets the max block number 20:23:19 [20:23:19.413] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:19 [20:23:19.445] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:19 [20:23:19.446] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:19 [20:23:19.500] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:19 [20:23:19.504] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:19 [20:23:19.535] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f558d0a00386847947da89b218ac2ca8306b7159128c05cce1fec41e19670e0 20:23:19 [20:23:19.620] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:19 [20:23:19.621] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:19 [20:23:19.623] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:19 [20:23:19.640] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:19 [20:23:19.640] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:19 [20:23:19.643] VERBOSE: pxe:service Added note {"index":0,"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a1868b5f4ca4f5cd1ec898f36b0fe8a16e4fc3bf93b6996ed5191e16ffff395","nullifier":"0x074a5ddc240bd6f3b3617ef18aee1278262785d162395f5b589a145896de52b7"} 20:23:19 [20:23:19.644] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:19 [20:23:19.657] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:23:19 [20:23:19.824] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:20 [20:23:20.093] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:20 [20:23:20.107] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:20 [20:23:20.108] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:20 [20:23:20.111] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:20 [20:23:20.291] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:20 [20:23:20.299] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:20 [20:23:20.303] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:20 [20:23:20.322] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:20 [20:23:20.637] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:20 [20:23:20.994] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:21 [20:23:21.196] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:21 [20:23:21.499] INFO: pxe:service Proving completed in 2165.419636999999ms {"timings":{"total":2165.419636999999,"sync":3.7244960000025458,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":465.46300399999745,"oracles":{"debugLog":{"times":[0.49806399999943096,0.42996199999470264,0.5585360000040964,1.0132289999964996,0.3715310000043246,0.32006900000124006,0.26047799999651033,0.5379249999969034]},"fetchTaggedLogs":{"times":[82.30274500000087]},"loadCapsule":{"times":[1.8544030000048224,2.7669390000010026,0.9288870000018505,0.20536600000195904,0.14322399999946356,0.17816499999753432]},"getSharedSecret":{"times":[76.44576700000471]},"aes128Decrypt":{"times":[0.41980200000398327,1.305647000001045]},"getPublicKeysAndPartialAddress":{"times":[13.574499000002106]},"getKeyValidationRequest":{"times":[1.8717329999999492]},"deliverNote":{"times":[2.867052000001422]},"deleteCapsule":{"times":[0.2490170000019134]},"storeCapsule":{"times":[0.2504770000014105]},"getNotes":{"times":[1.4282709999970393]},"getAuthWitness":{"times":[0.26014800000120886]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.20718599999963772]},"callPrivateFunction":{"times":[652.7297249999974]}}},{"functionName":"private_kernel_init","time":83.88253000000259},{"functionName":"Test:set_tx_max_block_number","time":488.6887209999986,"oracles":{"debugLog":{"times":[0.5521860000008019,0.39128099999652477]},"fetchTaggedLogs":{"times":[193.69459600000118]},"loadCapsule":{"times":[0.21376600000075996,0.2985380000027362,0.28738800000428455]},"storeInExecutionCache":{"times":[0.053950999994412996]},"notifyEnqueuedPublicFunctionCall":{"times":[0.10653299999830779]}}},{"functionName":"private_kernel_inner","time":160.76976899999863},{"functionName":"private_kernel_reset","time":232.13545899999735},{"functionName":"private_kernel_tail","time":81.25363500000094}],"unaccounted":649.5020230000009}} 20:23:21 [20:23:21.500] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with an enqueued public call invalidates the transaction 20:23:21 [20:23:21.502] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:21 [20:23:21.596] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:21 [20:23:21.633] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:21 [20:23:21.634] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:21 [20:23:21.684] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75"} 20:23:21 [20:23:21.690] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f558d0a00386847947da89b218ac2ca8306b7159128c05cce1fec41e19670e0 20:23:21 [20:23:21.704] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:21 [20:23:21.705] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:21 [20:23:21.708] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:21 [20:23:21.731] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:21 [20:23:21.732] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:21 [20:23:21.812] VERBOSE: pxe:service Added note {"index":0,"contract":"0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a1868b5f4ca4f5cd1ec898f36b0fe8a16e4fc3bf93b6996ed5191e16ffff395","nullifier":"0x074a5ddc240bd6f3b3617ef18aee1278262785d162395f5b589a145896de52b7"} 20:23:21 [20:23:21.813] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:21 [20:23:21.815] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:21 [20:23:21.827] VERBOSE: simulator:client_execution_context:debug_log Setting 0x12cfd8232042cae4daa55e8e5393af4c67ac9d42d74d80f03e287d3a31edca75 as fee payer 20:23:21 [20:23:21.979] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:22 [20:23:22.214] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:22 [20:23:22.229] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:22 [20:23:22.230] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:22 [20:23:22.231] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:22 [20:23:22.398] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:22 [20:23:22.400] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0364d1bf94f07c86ce39907ad1ddb1886f751b36fa32bb5d1e598802e169082b"} 20:23:22 [20:23:22.403] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:22 [20:23:22.445] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:22 [20:23:22.689] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:23 [20:23:23.023] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:23 [20:23:23.219] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0fd277c4c31b1e672ab4813d45236841141ad5b0acc0da23160afd299a9f8805","blockNumber":5,"slot":6} 20:23:23 [20:23:23.497] INFO: pxe:service Proving completed in 1975.5717129999975ms {"timings":{"total":1975.5717129999975,"sync":2.9964660000041476,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":411.3815490000052,"oracles":{"debugLog":{"times":[0.6212479999958305,0.5152740000048652,0.5468949999994948,0.6137579999995069,0.4088320000009844,0.2971079999988433,0.28578900000138674,0.7011899999997695]},"fetchTaggedLogs":{"times":[53.80056499999773]},"loadCapsule":{"times":[0.40055199999915203,0.23047599999699742,0.5513559999963036,0.636398000002373,0.17068499999732012,0.2385969999959343]},"getSharedSecret":{"times":[4.791327999999339]},"aes128Decrypt":{"times":[0.3975720000016736,1.4668019999953685]},"getPublicKeysAndPartialAddress":{"times":[10.482061999995494]},"getKeyValidationRequest":{"times":[12.235622000000149]},"deliverNote":{"times":[80.91508500000054]},"deleteCapsule":{"times":[0.22555699999793433]},"storeCapsule":{"times":[0.2707580000060261]},"getNotes":{"times":[1.1825640000024578]},"getAuthWitness":{"times":[0.16333399999712128]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.11280300000362331]},"callPrivateFunction":{"times":[580.4597480000011]}}},{"functionName":"private_kernel_init","time":60.19348999999784},{"functionName":"Test:set_tx_max_block_number","time":459.230134999998,"oracles":{"debugLog":{"times":[0.596386999997776,0.38462100000469945]},"fetchTaggedLogs":{"times":[172.49126599999727]},"loadCapsule":{"times":[0.2074170000050799,0.21006599999964237,0.2082460000019637]},"storeInExecutionCache":{"times":[0.04150099999969825]},"notifyEnqueuedPublicFunctionCall":{"times":[0.0665420000004815]}}},{"functionName":"private_kernel_inner","time":154.56149200000073},{"functionName":"private_kernel_reset","time":186.01892499999667},{"functionName":"private_kernel_tail","time":101.2565390000018}],"unaccounted":599.9331169999932}} 20:23:23 [20:23:23.522] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:23 [20:23:23.541] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x108e2909148fc8b56e62169a2b92991422a034ce0354c6c0676be8c3900d492b for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 20:23:23 [20:23:23.546] WARN: node Received invalid tx 0x108e2909148fc8b56e62169a2b92991422a034ce0354c6c0676be8c3900d492b: Invalid max block number {"txHash":"0x108e2909148fc8b56e62169a2b92991422a034ce0354c6c0676be8c3900d492b"} 20:23:23 [20:23:23.549] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 20:23:23 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 20:23:23 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 20:23:23 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 20:23:23 [20:23:23.553] INFO: node Stopping 20:23:23 [20:23:23.554] INFO: slasher Slasher client stopped. 20:23:23 [20:23:23.555] INFO: sequencer Stopped sequencer 20:23:23 [20:23:23.555] INFO: p2p P2P client stopped. 20:23:23 [20:23:23.557] INFO: world_state Stopped world state synchronizer 20:23:23 [20:23:23.557] INFO: archiver Stopped. 20:23:23 [20:23:23.557] INFO: node Stopped 20:23:23 [20:23:23.557] VERBOSE: e2e:e2e_max_block_number Cleaning up ACVM state 20:23:23 [20:23:23.558] INFO: e2e:e2e_max_block_number Cleaning up ACVM temp directory /tmp/f61174a5 20:23:23 [20:23:23.558] VERBOSE: e2e:e2e_max_block_number Cleaning up BB state 20:23:23 [20:23:23.569] INFO: blob-sink:server Stopping blob sink 20:23:23 [20:23:23.569] INFO: blob-sink:server Blob sink stopped 20:23:23 [20:23:23.570] VERBOSE: e2e:e2e_max_block_number Cleaning up data directory at /tmp/4a1ff08944b0f424 20:23:23 PASS src/e2e_max_block_number.test.ts 20:23:23 e2e_max_block_number 20:23:23 when requesting max block numbers higher than the mined one 20:23:23 with no enqueued public calls 20:23:23 ✓ sets the max block number (2061 ms) 20:23:23 ✓ does not invalidate the transaction (3979 ms) 20:23:23 with an enqueued public call 20:23:23 ✓ sets the max block number (1922 ms) 20:23:23 ✓ does not invalidate the transaction (3968 ms) 20:23:23 when requesting max block numbers lower than the mined one 20:23:23 with no enqueued public calls 20:23:23 ✓ sets the max block number (2084 ms) 20:23:23 ✓ invalidates the transaction (2139 ms) 20:23:23 with an enqueued public call 20:23:23 ✓ sets the max block number (2181 ms) 20:23:23 ✓ invalidates the transaction (2050 ms) 20:23:23 20:23:23 Test Suites: 1 passed, 1 total 20:23:23 Tests: 8 passed, 8 total 20:23:23 Snapshots: 0 total 20:23:23 Time: 46.268 s 20:23:23 Ran all test suites matching /src\/e2e_max_block_number.test.ts/i. 20:23:23 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?