Parent Log: http://ci.aztec-labs.com/d6c554ed0d0fee9b Command: 53e9b8d3e8ba3411: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/2707ec99de02004f3e30e7af0810e0d741ab9794 Env: REF_NAME=gh-readonly-queue/master/pr-15049-f4ac84d7ac4041d505399edd8819840bb14d6c9d CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Sat Jun 14 10:47:12 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15049_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_d488fdfe97c9ae48_master 10:47:12 +++ id -u 10:47:12 +++ id -g 10:47:12 ++ 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'\''' 10:47:12 + cid=17b5169e321558e9908552a333a2d48c1111f9070545ce91bc99df6864069125 10:47:12 + set +x 10:47:13 {"level":25,"time":1749898033677,"pid":12,"hostname":"17b5169e3215","module":"logger","msg":"Logger initialized with level verbose"} 10:47:20 [10:47:20.982] INFO: e2e:e2e_max_block_number Logging metrics to log/unknown.jsonl 10:47:21 [10:47:21.249] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-QVCdSM/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 10:47:21 [10:47:21.291] INFO: e2e:e2e_max_block_number Calling anvil_setBlockTimestampInterval with params: [12] 10:47:21 [10:47:21.318] WARN: e2e:e2e_max_block_number Set block interval to 12 10:47:21 [10:47:21.319] VERBOSE: e2e:e2e_max_block_number Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 10:47:21 [10:47:21.369] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:47:21 [10:47:21.378] VERBOSE: e2e:e2e_max_block_number Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 10:47:21 [10:47:21.403] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:47:21 [10:47:21.411] VERBOSE: e2e:e2e_max_block_number Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 10:47:21 [10:47:21.433] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 10:47:21 [10:47:21.438] VERBOSE: e2e:e2e_max_block_number Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 10:47:21 [10:47:21.455] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 10:47:21 [10:47:21.461] VERBOSE: e2e:e2e_max_block_number Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 10:47:21 [10:47:21.493] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 10:47:21 [10:47:21.499] VERBOSE: e2e:e2e_max_block_number Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 10:47:21 [10:47:21.517] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 10:47:21 [10:47:21.524] VERBOSE: e2e:e2e_max_block_number Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 10:47:21 [10:47:21.524] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 10:47:21 [10:47:21.525] INFO: e2e:e2e_max_block_number Waiting for 6 transactions to be mined... 10:47:21 [10:47:21.544] INFO: e2e:e2e_max_block_number All transactions mined successfully 10:47:21 [10:47:21.559] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 10:47:21 [10:47:21.560] VERBOSE: e2e:e2e_max_block_number Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 10:47:21 [10:47:21.573] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 10:47:21 [10:47:21.584] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 10:47:21 [10:47:21.589] VERBOSE: e2e:e2e_max_block_number Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 10:47:21 [10:47:21.601] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 10:47:21 [10:47:21.601] VERBOSE: e2e:e2e_max_block_number Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 10:47:21 [10:47:21.628] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 10:47:21 [10:47:21.634] VERBOSE: e2e:e2e_max_block_number Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 10:47:21 [10:47:21.645] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 10:47:21 [10:47:21.646] VERBOSE: e2e:e2e_max_block_number Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 10:47:21 [10:47:21.646] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 10:47:21 [10:47:21.646] INFO: e2e:e2e_max_block_number Waiting for 8 transactions to be mined... 10:47:21 [10:47:21.664] INFO: e2e:e2e_max_block_number All transactions mined successfully 10:47:21 [10:47:21.679] VERBOSE: e2e:e2e_max_block_number Deployed shared contracts 10:47:21 [10:47:21.698] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 10:47:21 [10:47:21.699] VERBOSE: e2e:e2e_max_block_number Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 10:47:21 [10:47:21.701] 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} 10:47:21 [10:47:21.712] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 10:47:21 [10:47:21.758] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 10:47:21 [10:47:21.767] VERBOSE: e2e:e2e_max_block_number Awaiting for linked libraries to be deployed 10:47:21 [10:47:21.813] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x6fa1196c2ebeea963febbdf334522fd15a0b6c7a5fc6e3513e1fc9a25e24523d {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 10:47:21 [10:47:21.820] 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} 10:47:21 [10:47:21.821] INFO: e2e:e2e_max_block_number Waiting for 9 transactions to be mined... 10:47:21 [10:47:21.845] INFO: e2e:e2e_max_block_number All transactions mined successfully 10:47:21 [10:47:21.845] VERBOSE: e2e:e2e_max_block_number All core contracts have been deployed 10:47:21 [10:47:21.868] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 10:47:21 [10:47:21.868] VERBOSE: e2e:e2e_max_block_number Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 10:47:21 [10:47:21.887] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 10:47:21 [10:47:21.893] VERBOSE: e2e:e2e_max_block_number Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 10:47:21 [10:47:21.924] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 10:47:21 [10:47:21.925] VERBOSE: e2e:e2e_max_block_number Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 10:47:21 [10:47:21.925] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 10:47:21 [10:47:21.948] INFO: e2e:e2e_max_block_number All transactions mined successfully 10:47:21 [10:47:21.959] VERBOSE: e2e:e2e_max_block_number Rollup deployed 10:47:21 [10:47:21.959] VERBOSE: e2e:e2e_max_block_number Waiting for rollup and slash factory to be deployed 10:47:21 [10:47:21.959] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 10:47:21 [10:47:21.988] INFO: e2e:e2e_max_block_number All transactions mined successfully 10:47:21 [10:47:21.988] VERBOSE: e2e:e2e_max_block_number All transactions for L1 deployment have been mined 10:47:22 [10:47:22.022] 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"} 10:47:22 [10:47:22.026] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 10:47:22 [10:47:22.029] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:22 [10:47:22.030] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 10:47:22 [10:47:22.031] INFO: telemetry:client Using NoopTelemetryClient 10:47:22 [10:47:22.037] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/7d873113f3e41d82/blob-sink with map size 134217728 KB (LMDB v2) 10:47:22 [10:47:22.038] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 10:47:22 [10:47:22.038] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 10:47:22 [10:47:22.042] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:22 [10:47:22.045] INFO: aztecjs:utils:watcher New L1 time: 1749898280 10:47:22 [10:47:22.045] WARN: foundation:test-date-provider Time set to 2025-06-14T10:51:20.000Z {"offset":237955,"timeMs":1749898280000} 10:47:22 [10:47:22.048] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 10:47:22 [10:47:22.055] INFO: blob-sink:server Server is running on http://localhost:38601 10:47:22 [10:47:22.055] VERBOSE: e2e:e2e_max_block_number Creating and synching an aztec node... 10:47:22 [10:47:22.056] VERBOSE: e2e:e2e_max_block_number Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/a374ff5c/acvm 10:47:22 [10:47:22.057] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:38601"} 10:47:22 [10:47:22.082] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 10:47:22 [10:47:22.085] INFO: archiver:lmdb Creating archiver data store at directory /tmp/7d873113f3e41d82/archiver with map size 134217728 KB (LMDB v2) 10:47:22 [10:47:22.088] INFO: archiver:lmdb Starting data store with maxReaders 16 10:47:22 [10:47:22.303] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:22 [10:47:22.537] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:22 [10:47:22.646] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:38601"} 10:47:22 [10:47:22.677] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:38601"} 10:47:22 [10:47:22.678] WARN: node:blob-sink:client No L1 consensus host urls configured 10:47:22 [10:47:22.678] WARN: node:blob-sink:client No archive client configured 10:47:22 [10:47:22.682] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x60d44ad667082dca4e877b55eafef525c777ee0f3982fdc81a6e4981b1f7b170 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x2f370c17223ccf4336c37f9c37a7bcc318b9e8bcd7ba127d1efd89b2629802c3","l1BlockNumber":16}} 10:47:22 [10:47:22.702] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0x60d44ad667082dca4e877b55eafef525c777ee0f3982fdc81a6e4981b1f7b170","l1BlockNumber":20}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 10:47:22 [10:47:22.712] INFO: world-state:database Creating world state data store at directory /tmp/7d873113f3e41d82/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 10:47:22 [10:47:22.744] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:22 [10:47:22.756] INFO: world_state Created world state synchroniser with block history of 64 10:47:22 [10:47:22.757] WARN: node Aztec node is accepting fake proofs 10:47:22 [10:47:22.772] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/7d873113f3e41d82/p2p with map size 134217728 KB (LMDB v2) 10:47:22 [10:47:22.775] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 10:47:22 [10:47:22.777] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/7d873113f3e41d82/p2p-archive with map size 134217728 KB (LMDB v2) 10:47:22 [10:47:22.779] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 10:47:22 [10:47:22.780] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/7d873113f3e41d82/p2p-peers with map size 134217728 KB (LMDB v2) 10:47:22 [10:47:22.781] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 10:47:22 [10:47:22.783] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 10:47:22 [10:47:22.784] VERBOSE: p2p P2P is disabled. Using dummy P2P service 10:47:22 [10:47:22.787] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 10:47:22 [10:47:22.788] INFO: world_state Started world state synchronizer from block 1 10:47:22 [10:47:22.791] VERBOSE: world-state:block_stream Starting sync from block number 0 10:47:22 [10:47:22.793] INFO: p2p Starting p2p client from block 0 with empty mempools 10:47:22 [10:47:22.794] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 10:47:22 [10:47:22.795] INFO: slasher Slasher client initialized 10:47:22 [10:47:22.795] INFO: slasher Starting Slasher client... 10:47:22 [10:47:22.828] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:47:22 [10:47:22.829] VERBOSE: node All Aztec Node subsystems synced 10:47:22 [10:47:22.839] INFO: sequencer-client Deploying forwarder contract 10:47:22 [10:47:22.846] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 10:47:22 [10:47:22.860] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 10:47:22 [10:47:22.860] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 10:47:22 [10:47:22.867] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 10:47:22 [10:47:22.885] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:47:22 [10:47:22.886] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 10:47:22 [10:47:22.886] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/a374ff5c/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 10:47:23 [10:47:23.106] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 10:47:23 [10:47:23.108] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:47:23 [10:47:23.110] INFO: node Aztec Node version: 0.87.8 10:47:23 [10:47:23.110] 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"} 10:47:23 [10:47:23.112] VERBOSE: e2e:e2e_max_block_number Creating a pxe... 10:47:23 [10:47:23.119] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:23 [10:47:23.128] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/31b39ca79726be3d/pxe_data with map size 134217728 KB (LMDB v2) 10:47:23 [10:47:23.139] INFO: pxe:data:lmdb Starting data store with maxReaders 16 10:47:23 [10:47:23.142] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2a7e6a0b57046b64a34e92f3f93dca270d415796a05e7ce0e9036714065c70f4","blockNumber":1,"slot":2} 10:47:23 [10:47:23.352] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:23 [10:47:23.587] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:23 [10:47:23.594] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 10:47:23 [10:47:23.595] INFO: pxe:service Started PXE connected to chain 31337 version 4008247234 10:47:23 [10:47:23.674] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2a7e6a0b57046b64a34e92f3f93dca270d415796a05e7ce0e9036714065c70f4","blockNumber":1,"slot":2} 10:47:23 [10:47:23.678] INFO: pxe:service Registered account 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f 10:47:23 [10:47:23.770] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 10:47:23 [10:47:23.775] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f 10:47:23 [10:47:23.809] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:23 [10:47:23.961] INFO: pxe:service Added contract SchnorrAccount at 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 10:47:24 [10:47:24.008] VERBOSE: pxe:block_stream Starting sync from block number 0 10:47:24 [10:47:24.016] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:24 [10:47:24.033] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 10:47:24 [10:47:24.145] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:47:24 [10:47:24.865] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 10:47:24 [10:47:24.995] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 10:47:25 [10:47:25.067] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:25 [10:47:25.134] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2a7e6a0b57046b64a34e92f3f93dca270d415796a05e7ce0e9036714065c70f4","blockNumber":1,"slot":2} 10:47:25 [10:47:25.214] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:25 [10:47:25.266] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:25 [10:47:25.267] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:25 [10:47:25.300] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:25 [10:47:25.328] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:25 [10:47:25.361] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:25 [10:47:25.508] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:25 [10:47:25.620] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:25 [10:47:25.673] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:25 [10:47:25.674] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:25 [10:47:25.725] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:25 [10:47:25.735] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2a7e6a0b57046b64a34e92f3f93dca270d415796a05e7ce0e9036714065c70f4","blockNumber":1,"slot":2} 10:47:25 [10:47:25.748] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:25 [10:47:25.753] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:25 [10:47:25.773] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:26 [10:47:26.032] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:26 [10:47:26.355] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:26 [10:47:26.537] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2a7e6a0b57046b64a34e92f3f93dca270d415796a05e7ce0e9036714065c70f4","blockNumber":1,"slot":2} 10:47:26 [10:47:26.700] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:27 [10:47:27.318] INFO: pxe:service Proving completed in 3313.01246ms {"timings":{"total":3313.01246,"sync":3.752440000000206,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":167.34684800000105,"oracles":{"callPrivateFunction":{"times":[982.4943070000008,442.2117129999988,287.34992799999964]}}},{"functionName":"private_kernel_init","time":148.88852199999928},{"functionName":"ContractClassRegisterer:register","time":960.4057039999989,"oracles":{"loadCapsule":{"times":[51.22646600000007]},"notifyCreatedNullifier":{"times":[0.7412440000007336]},"debugLog":{"times":[1.2457030000005034]},"notifyCreatedContractClassLog":{"times":[13.719667000001209]}}},{"functionName":"private_kernel_inner","time":179.7463090000001},{"functionName":"SchnorrAccount:constructor","time":408.44090999999935,"oracles":{"getContractInstance":{"times":[6.868968999999197]},"debugLog":{"times":[0.7276940000010654,0.7664850000001024]},"fetchTaggedLogs":{"times":[83.09083199999986]},"loadCapsule":{"times":[4.71397799999977,4.262038999999277,4.42224300000089]},"notifyCreatedNullifier":{"times":[0.3688770000007935,0.47376900000017486]},"notifyCreatedNote":{"times":[0.7602239999996527]},"getRandomField":{"times":[0.8164949999991222,0.268434999999954,0.08895199999824399,0.06766099999913422,0.04407100000025821,0.0841920000002574,0.05984100000023318,0.06589100000019243,0.07041100000060396,0.1324029999996128,0.08920100000068487]},"getIndexedTaggingSecretAsSender":{"times":[46.064110999999684]},"incrementAppTaggingSecretIndexAsSender":{"times":[49.993514000001596]}}},{"functionName":"private_kernel_inner","time":155.03093700000136},{"functionName":"SchnorrAccount:entrypoint","time":247.54630500000167,"oracles":{"debugLog":{"times":[0.8278660000014497,0.5862409999990632,1.080550000000585]},"fetchTaggedLogs":{"times":[77.49778800000058]},"loadCapsule":{"times":[0.3080359999985376,0.3361960000002,0.2966359999991255]},"getNotes":{"times":[2.4753560000008292]},"getAuthWitness":{"times":[0.4703490000010788]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.3422449999998207]}}},{"functionName":"private_kernel_inner","time":137.0203500000007},{"functionName":"private_kernel_reset","time":236.71492299999954},{"functionName":"private_kernel_tail","time":110.9760640000004}],"unaccounted":557.1431479999974}} 10:47:27 [10:47:27.344] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:27 [10:47:27.432] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2a7e6a0b57046b64a34e92f3f93dca270d415796a05e7ce0e9036714065c70f4","blockNumber":1,"slot":2} 10:47:27 [10:47:27.500] VERBOSE: p2p:tx_pool Adding tx 0x0f4a1a56c528499a0e6eeba9a9e68433d592f7d0df9adc65e9af30f8496ecb23 to pool {"eventName":"tx-added-to-pool","txHash":"0x0f4a1a56c528499a0e6eeba9a9e68433d592f7d0df9adc65e9af30f8496ecb23","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 10:47:27 [10:47:27.519] INFO: node Received tx 0x0f4a1a56c528499a0e6eeba9a9e68433d592f7d0df9adc65e9af30f8496ecb23 {"txHash":"0x0f4a1a56c528499a0e6eeba9a9e68433d592f7d0df9adc65e9af30f8496ecb23"} 10:47:27 [10:47:27.520] INFO: pxe:service Sent transaction 0x0f4a1a56c528499a0e6eeba9a9e68433d592f7d0df9adc65e9af30f8496ecb23 10:47:27 [10:47:27.552] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:27 [10:47:27.756] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:27 [10:47:27.950] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 10:47:27 [10:47:27.960] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:27 [10:47:27.965] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-14T10:51:44.000Z","now":"2025-06-14T10:51:25.920Z"} 10:47:28 [10:47:28.178] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:28 [10:47:28.251] VERBOSE: simulator:public-processor Processed tx 0x0f4a1a56c528499a0e6eeba9a9e68433d592f7d0df9adc65e9af30f8496ecb23 with no public calls in 212.14741400000094ms {"txHash":"0x0f4a1a56c528499a0e6eeba9a9e68433d592f7d0df9adc65e9af30f8496ecb23","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":212.14741400000094} 10:47:28 [10:47:28.255] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.2884006790000003s {"duration":0.2884006790000003,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 10:47:28 [10:47:28.391] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:28 [10:47:28.405] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x0fdf125238d35ccf877fe08226e3c832d8c6aaa0478fdac1d108b2197bb158f4","globalVariables":{"chainId":31337,"version":4008247234,"blockNumber":1,"slotNumber":2,"timestamp":1749898304,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x0f4a1a56c528499a0e6eeba9a9e68433d592f7d0df9adc65e9af30f8496ecb23"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":455.0586820000008,"publicProcessDuration":289.2915059999996,"rollupCircuitsDuration":440.58153199999833,"txCount":1,"blockNumber":1,"blockTimestamp":1749898304,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:47:28 [10:47:28.408] VERBOSE: sequencer Attesting committee is empty 10:47:28 [10:47:28.598] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:28 [10:47:28.745] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x805826cc142b3d24c8ccf75e2785af663517eea380c7033ffa14bceb7215e9b6 10:47:28 [10:47:28.745] VERBOSE: sequencer-client Sent L1 transaction 0x805826cc142b3d24c8ccf75e2785af663517eea380c7033ffa14bceb7215e9b6 {"gasLimit":514599,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 10:47:28 [10:47:28.760] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:47:28 [10:47:28.761] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x805826cc142b3d24c8ccf75e2785af663517eea380c7033ffa14bceb7215e9b6","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":1,"blockTimestamp":1749898304,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:47:28 [10:47:28.761] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:47:28 [10:47:28.779] INFO: blob-sink:server Received blob sidecar for block 0xfa44d933132633fe58942839b46e4e225c45b303451095102951ed2bd5d7f1d4 10:47:28 [10:47:28.785] INFO: blob-sink:server Blob sidecar stored successfully for block 0xfa44d933132633fe58942839b46e4e225c45b303451095102951ed2bd5d7f1d4 10:47:28 [10:47:28.801] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:28 [10:47:28.806] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 10:47:28 [10:47:28.808] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 10:47:28 [10:47:28.811] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 10:47:29 [10:47:29.050] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:29 [10:47:29.069] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 10:47:29 [10:47:29.086] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x0fdf125238d35ccf877fe08226e3c832d8c6aaa0478fdac1d108b2197bb158f4","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":4008247234,"blockNumber":1,"slotNumber":2,"timestamp":1749898304,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x1003032bb4b19ee19a81e37026a39ac160b90dedd4d6d4c822530ee4b6640918","archiveNextLeafIndex":2} 10:47:29 [10:47:29.099] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749898340] on http://127.0.0.1:8545 10:47:29 [10:47:29.102] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:47:29 [10:47:29.103] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 10:47:29 [10:47:29.105] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749898340 10:47:29 [10:47:29.106] WARN: foundation:test-date-provider Time set to 2025-06-14T10:52:20.000Z {"offset":290895,"timeMs":1749898340000} 10:47:29 [10:47:29.106] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 10:47:29 [10:47:29.171] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.141384000000471,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749898304,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:47:29 [10:47:29.529] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:29 [10:47:29.531] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 10:47:29 [10:47:29.536] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f 10:47:29 [10:47:29.778] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:29 [10:47:29.791] VERBOSE: p2p Synched to latest block 1 10:47:30 [10:47:30.111] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:30 [10:47:30.127] INFO: pxe:service Added contract Test at 0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 10:47:30 [10:47:30.155] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x1003032bb4b19ee19a81e37026a39ac160b90dedd4d6d4c822530ee4b6640918","header":{"contentCommitment":{"blobsHash":"0x0016acbc9dcde32ef76ee58521836419ba81878894296be9688a623f8a88a039","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749898304,"version":4008247234},"lastArchive":"0x2a7e6a0b57046b64a34e92f3f93dca270d415796a05e7ce0e9036714065c70f4","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x046659c3d7c6ca69f4815d9f68ec10714d122dd622795b7ca8628cb4d1909934","nullifierTree":"0x0de942bbe52312666a7540be9f767e2a7e772d596606ad338ef80f79e68eec05","publicDataTree":"0x11e92b226ce0aed2999548bee59854b5367e69eaddc507572d7e225bbae0a8df"},"totalFees":6794052480,"totalManaUsed":20448}} 10:47:30 [10:47:30.262] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:30 [10:47:30.309] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:30 [10:47:30.309] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:30 [10:47:30.323] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:30 [10:47:30.374] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1003032bb4b19ee19a81e37026a39ac160b90dedd4d6d4c822530ee4b6640918","blockNumber":2,"slot":3} 10:47:30 [10:47:30.414] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:30 [10:47:30.420] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x09169cec0e1748b73a3a6604c825cb1e82c5675ae33bb774ba8b41e81aa24836 10:47:30 [10:47:30.435] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:30 [10:47:30.436] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:30 [10:47:30.451] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:30 [10:47:30.451] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:30 [10:47:30.458] VERBOSE: pxe:service Added note {"index":0,"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2c2c6e4bf1758e77517e67b0d9285b1c6d4f69e848b7e80bdd2892595886779e","nullifier":"0x001cf471b4284b3183d0561ef8190b09776fab6d8e1a2842f85d5fff293dc4ea"} 10:47:30 [10:47:30.462] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:30 [10:47:30.483] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:30 [10:47:30.523] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:47:31 [10:47:31.084] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 10:47:31 [10:47:31.186] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 10:47:31 [10:47:31.278] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:31 [10:47:31.389] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 10:47:31 [10:47:31.418] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1003032bb4b19ee19a81e37026a39ac160b90dedd4d6d4c822530ee4b6640918","blockNumber":2,"slot":3} 10:47:31 [10:47:31.458] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:47:31 [10:47:31.476] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f,0x0000000000000000000000000000000000000000000000000000000000000001,0x09cea8f59ef57228c752011af198ae3376a5822388ac70da76dae4d774e623f3,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f 10:47:31 [10:47:31.493] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:31 [10:47:31.703] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:32 [10:47:32.459] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:32 [10:47:32.477] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:32 [10:47:32.485] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:32 [10:47:32.486] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:32 [10:47:32.697] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:32 [10:47:32.702] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1003032bb4b19ee19a81e37026a39ac160b90dedd4d6d4c822530ee4b6640918","blockNumber":2,"slot":3} 10:47:32 [10:47:32.709] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:32 [10:47:32.713] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:32 [10:47:32.928] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:33 [10:47:33.187] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:33 [10:47:33.426] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:33 [10:47:33.427] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1003032bb4b19ee19a81e37026a39ac160b90dedd4d6d4c822530ee4b6640918","blockNumber":2,"slot":3} 10:47:33 [10:47:33.668] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:33 [10:47:33.886] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:34 [10:47:34.157] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:34 [10:47:34.793] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1003032bb4b19ee19a81e37026a39ac160b90dedd4d6d4c822530ee4b6640918","blockNumber":2,"slot":3} 10:47:35 [10:47:35.098] INFO: pxe:service Proving completed in 4951.214217000001ms {"timings":{"total":4951.214217000001,"sync":11.811901000000944,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":926.5651429999962,"oracles":{"debugLog":{"times":[0.7920850000009523,0.47253899999850546,0.5579209999996237,0.4417880000000878,0.5871010000009846,0.5156499999975495,0.5664310000029218,0.8204949999999371]},"fetchTaggedLogs":{"times":[107.9413569999997]},"loadCapsule":{"times":[0.36819699999978184,0.4357080000008864,0.4745890000012878,1.1096209999996063,0.3333259999999427,0.2347939999999653]},"getSharedSecret":{"times":[4.7665779999988445]},"aes128Decrypt":{"times":[1.9658170000002428,1.1547409999984666]},"getPublicKeysAndPartialAddress":{"times":[9.470516999997926]},"getKeyValidationRequest":{"times":[2.185761000000639]},"deliverNote":{"times":[6.79878699999972]},"deleteCapsule":{"times":[0.6779719999976805]},"storeCapsule":{"times":[0.44641799999953946]},"getNotes":{"times":[2.020637000001443]},"getAuthWitness":{"times":[0.25864500000170665]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07208099999843398]},"callPrivateFunction":{"times":[785.0062069999985,216.5768169999974,1230.5409910000017]}}},{"functionName":"private_kernel_init","time":66.66822499999762},{"functionName":"ContractClassRegisterer:register","time":768.3245349999997,"oracles":{"loadCapsule":{"times":[6.003182000000379]},"notifyCreatedNullifier":{"times":[0.9916489999995974]},"debugLog":{"times":[1.1043810000010126]},"notifyCreatedContractClassLog":{"times":[11.723778999999922]}}},{"functionName":"private_kernel_inner","time":179.72041799999715},{"functionName":"ContractInstanceDeployer:deploy","time":132.9754329999996,"oracles":{"storeInExecutionCache":{"times":[0.6155010000002221]},"callPrivateFunction":{"times":[71.54008600000088]},"notifyCreatedNullifier":{"times":[0.42177799999990384]},"debugLog":{"times":[0.7654039999979432]}}},{"functionName":"private_kernel_inner","time":126.02768500000093},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":53.838666000003286,"oracles":{}},{"functionName":"private_kernel_inner","time":151.93326800000068},{"functionName":"Test:initialize","time":658.8899410000013,"oracles":{"getContractInstance":{"times":[8.749614000000292]},"debugLog":{"times":[0.6470119999976305,0.4407280000013998]},"fetchTaggedLogs":{"times":[226.38284999999814]},"loadCapsule":{"times":[0.23390400000062073,0.2941049999972165,0.34911700000157]},"notifyCreatedNullifier":{"times":[0.2525950000017474]}}},{"functionName":"private_kernel_inner","time":146.08782000000065},{"functionName":"private_kernel_reset","time":207.90641400000095},{"functionName":"private_kernel_tail","time":104.4964620000028}],"unaccounted":1415.9683059999988}} 10:47:35 [10:47:35.125] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:35 [10:47:35.328] VERBOSE: p2p:tx_pool Adding tx 0x09fea39e23273dba214ebd38f6e9aed878adc12c9d15fb728cd24cd7a9ed0b59 to pool {"eventName":"tx-added-to-pool","txHash":"0x09fea39e23273dba214ebd38f6e9aed878adc12c9d15fb728cd24cd7a9ed0b59","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 10:47:35 [10:47:35.340] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:35 [10:47:35.376] INFO: node Received tx 0x09fea39e23273dba214ebd38f6e9aed878adc12c9d15fb728cd24cd7a9ed0b59 {"txHash":"0x09fea39e23273dba214ebd38f6e9aed878adc12c9d15fb728cd24cd7a9ed0b59"} 10:47:35 [10:47:35.378] INFO: pxe:service Sent transaction 0x09fea39e23273dba214ebd38f6e9aed878adc12c9d15fb728cd24cd7a9ed0b59 10:47:35 [10:47:35.407] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 10:47:35 [10:47:35.431] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-14T10:52:20.000Z","now":"2025-06-14T10:52:26.326Z"} 10:47:35 [10:47:35.654] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:35 [10:47:35.757] VERBOSE: simulator:public-processor Processed tx 0x09fea39e23273dba214ebd38f6e9aed878adc12c9d15fb728cd24cd7a9ed0b59 with no public calls in 215.78861200000028ms {"txHash":"0x09fea39e23273dba214ebd38f6e9aed878adc12c9d15fb728cd24cd7a9ed0b59","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":215.78861200000028} 10:47:35 [10:47:35.762] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.3296609190000017s {"duration":0.3296609190000017,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 10:47:35 [10:47:35.931] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:35 [10:47:35.946] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2c5f64af52c3f2a720f3c68d71dfe11f753daea4657c9c8373d4fdf9ab9b1382","globalVariables":{"chainId":31337,"version":4008247234,"blockNumber":2,"slotNumber":3,"timestamp":1749898340,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x09fea39e23273dba214ebd38f6e9aed878adc12c9d15fb728cd24cd7a9ed0b59"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":539.653123,"publicProcessDuration":330.3093719999997,"rollupCircuitsDuration":517.5545490000004,"txCount":1,"blockNumber":2,"blockTimestamp":1749898340,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 10:47:35 [10:47:35.949] VERBOSE: sequencer Attesting committee is empty 10:47:36 [10:47:36.136] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:36 [10:47:36.280] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x1805831110827f749989b22ff6cfda42852da72589fbed5f69612c5b9a04dcd7 10:47:36 [10:47:36.281] VERBOSE: sequencer-client Sent L1 transaction 0x1805831110827f749989b22ff6cfda42852da72589fbed5f69612c5b9a04dcd7 {"gasLimit":470265,"maxFeePerGas":"1.303611222","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:47:36 [10:47:36.307] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:47:36 [10:47:36.317] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x1805831110827f749989b22ff6cfda42852da72589fbed5f69612c5b9a04dcd7","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":2,"blockTimestamp":1749898340,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 10:47:36 [10:47:36.318] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:47:36 [10:47:36.323] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749898376] on http://127.0.0.1:8545 10:47:36 [10:47:36.329] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:47:36 [10:47:36.333] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749898376 10:47:36 [10:47:36.334] WARN: foundation:test-date-provider Time set to 2025-06-14T10:52:56.000Z {"offset":319666,"timeMs":1749898376000} 10:47:36 [10:47:36.334] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 10:47:36 [10:47:36.338] INFO: blob-sink:server Received blob sidecar for block 0xe7cbb139ecd636f0f085acf9a066689ab627445ac2765db025e475df86cd05a7 10:47:36 [10:47:36.342] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:36 [10:47:36.350] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 10:47:36 [10:47:36.353] INFO: blob-sink:server Blob sidecar stored successfully for block 0xe7cbb139ecd636f0f085acf9a066689ab627445ac2765db025e475df86cd05a7 10:47:36 [10:47:36.356] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 10:47:36 [10:47:36.359] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 10:47:36 [10:47:36.535] VERBOSE: archiver:block-helper Store contract instance at 0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f 10:47:36 [10:47:36.560] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 10:47:36 [10:47:36.590] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:36 [10:47:36.604] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x2c5f64af52c3f2a720f3c68d71dfe11f753daea4657c9c8373d4fdf9ab9b1382","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":4008247234,"blockNumber":2,"slotNumber":3,"timestamp":1749898340,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","archiveNextLeafIndex":3} 10:47:36 [10:47:36.641] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":8.441387999999279,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749898340,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 10:47:36 [10:47:36.733] VERBOSE: p2p Synched to latest block 2 10:47:36 [10:47:36.800] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:36 [10:47:36.859] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","blockNumber":3,"slot":4} 10:47:37 [10:47:37.006] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:37 [10:47:37.133] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 10:47:37 [10:47:37.209] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:37 [10:47:37.414] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:37 [10:47:37.423] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","blockNumber":3,"slot":4} 10:47:37 [10:47:37.631] INFO: aztecjs:deploy_sent_tx Contract 0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f successfully deployed. 10:47:37 [10:47:37.637] 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 10:47:37 [10:47:37.645] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:37 [10:47:37.720] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","header":{"contentCommitment":{"blobsHash":"0x00f951758a7b4136b2179a3eb57d3ba960f0f650ea02dc3c53b8dc0f9c80cb60","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749898340,"version":4008247234},"lastArchive":"0x1003032bb4b19ee19a81e37026a39ac160b90dedd4d6d4c822530ee4b6640918","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x046659c3d7c6ca69f4815d9f68ec10714d122dd622795b7ca8628cb4d1909934","nullifierTree":"0x175121a29043e5236aa4e369377f95457bcce4c904c4439fc98ee772e24d9bb3","publicDataTree":"0x0b4a37d6f210fa475712c704e88e7e16bf9941bd71a6c3457c194efa1c1666dd"},"totalFees":7272506880,"totalManaUsed":21888}} 10:47:37 [10:47:37.852] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:37 [10:47:37.891] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:37 [10:47:37.891] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:37 [10:47:37.897] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:38 [10:47:38.013] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","blockNumber":3,"slot":4} 10:47:38 [10:47:38.030] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:38 [10:47:38.040] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x09169cec0e1748b73a3a6604c825cb1e82c5675ae33bb774ba8b41e81aa24836 10:47:38 [10:47:38.061] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:38 [10:47:38.062] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:38 [10:47:38.071] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:38 [10:47:38.071] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:38 [10:47:38.076] VERBOSE: pxe:service Added note {"index":0,"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2c2c6e4bf1758e77517e67b0d9285b1c6d4f69e848b7e80bdd2892595886779e","nullifier":"0x001cf471b4284b3183d0561ef8190b09776fab6d8e1a2842f85d5fff293dc4ea"} 10:47:38 [10:47:38.079] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:38 [10:47:38.096] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:38 [10:47:38.106] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:38 [10:47:38.470] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:38 [10:47:38.900] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:38 [10:47:38.917] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:38 [10:47:38.918] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:39 [10:47:39.163] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:39 [10:47:39.198] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","blockNumber":3,"slot":4} 10:47:39 [10:47:39.219] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:39 [10:47:39.224] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:39 [10:47:39.435] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:39 [10:47:39.648] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:40 [10:47:40.360] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:40 [10:47:40.375] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","blockNumber":3,"slot":4} 10:47:40 [10:47:40.643] INFO: pxe:service Proving completed in 2952.064971ms {"timings":{"total":2952.064971,"sync":34.83117100000163,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":805.1246719999981,"oracles":{"debugLog":{"times":[0.9602180000001681,0.6212219999979425,0.6218320000007225,0.7325940000009723,0.41583800000080373,0.32791699999870616,0.4237680000005639,1.7880330000007234]},"fetchTaggedLogs":{"times":[145.20166299999983]},"loadCapsule":{"times":[0.40127799999754643,0.25565500000084285,0.40892800000074203,0.32816599999932805,0.26639499999873806,0.26406499999939115]},"getSharedSecret":{"times":[12.848920000000362]},"aes128Decrypt":{"times":[0.4330179999997199,0.8861670000005688]},"getPublicKeysAndPartialAddress":{"times":[2.0161279999992985]},"getKeyValidationRequest":{"times":[3.6551689999978407]},"deliverNote":{"times":[5.157165999997233]},"deleteCapsule":{"times":[0.3485770000006596]},"storeCapsule":{"times":[0.35889700000188896]},"getNotes":{"times":[1.8420640000003914]},"getAuthWitness":{"times":[0.24338400000124238]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.16228299999784213]},"callPrivateFunction":{"times":[1132.6391619999995]}}},{"functionName":"private_kernel_init","time":95.68971999999849},{"functionName":"Test:set_tx_max_block_number","time":687.4317240000019,"oracles":{"debugLog":{"times":[0.6325310000029276,0.6864829999976791]},"fetchTaggedLogs":{"times":[304.42346800000087]},"loadCapsule":{"times":[0.2709150000009686,0.3982770000002347,0.30848600000172155]}}},{"functionName":"private_kernel_inner","time":153.51796100000138},{"functionName":"private_kernel_reset","time":208.1301450000028},{"functionName":"private_kernel_tail","time":74.59764499999801}],"unaccounted":892.7419329999975}} 10:47:40 [10:47:40.651] 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 10:47:40 [10:47:40.659] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:40 [10:47:40.766] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:40 [10:47:40.813] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:40 [10:47:40.814] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:40 [10:47:40.869] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:40 [10:47:40.937] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:40 [10:47:40.948] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","blockNumber":3,"slot":4} 10:47:40 [10:47:40.956] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x09169cec0e1748b73a3a6604c825cb1e82c5675ae33bb774ba8b41e81aa24836 10:47:40 [10:47:40.965] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:40 [10:47:40.965] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:40 [10:47:40.973] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:40 [10:47:40.974] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:40 [10:47:40.977] VERBOSE: pxe:service Added note {"index":0,"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2c2c6e4bf1758e77517e67b0d9285b1c6d4f69e848b7e80bdd2892595886779e","nullifier":"0x001cf471b4284b3183d0561ef8190b09776fab6d8e1a2842f85d5fff293dc4ea"} 10:47:40 [10:47:40.978] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:40 [10:47:40.995] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:41 [10:47:41.204] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:41 [10:47:41.598] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:41 [10:47:41.614] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:41 [10:47:41.615] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:41 [10:47:41.618] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:42 [10:47:42.010] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:42 [10:47:42.149] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","blockNumber":3,"slot":4} 10:47:42 [10:47:42.158] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:42 [10:47:42.163] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:42 [10:47:42.383] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:42 [10:47:42.941] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:43 [10:47:43.162] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:43 [10:47:43.184] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","blockNumber":3,"slot":4} 10:47:43 [10:47:43.469] INFO: pxe:service Proving completed in 2779.955675000001ms {"timings":{"total":2779.955675000001,"sync":3.6253260000012233,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":516.7521200000047,"oracles":{"debugLog":{"times":[0.8648489999977755,0.5955119999998715,0.39543800000319607,0.37935799999831943,0.3594969999976456,0.2857960000001185,0.24846600000091712,0.8425980000029085]},"fetchTaggedLogs":{"times":[139.2521799999995]},"loadCapsule":{"times":[0.694694999998319,0.294555999997101,0.42024900000251364,0.36323799999809125,0.2026049999985844,0.22433499999897322]},"getSharedSecret":{"times":[3.088834999998653]},"aes128Decrypt":{"times":[0.28203600000051665,0.6818439999988186]},"getPublicKeysAndPartialAddress":{"times":[1.3096669999977166]},"getKeyValidationRequest":{"times":[3.6783980000000156]},"deliverNote":{"times":[3.170547000001534]},"deleteCapsule":{"times":[0.27467600000090897]},"storeCapsule":{"times":[0.23721500000101514]},"getNotes":{"times":[1.6320249999989755]},"getAuthWitness":{"times":[0.33125599999766564]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.10104199999841512]},"callPrivateFunction":{"times":[1173.8166740000015]}}},{"functionName":"private_kernel_init","time":74.45253199999934},{"functionName":"Test:set_tx_max_block_number","time":953.5760239999981,"oracles":{"debugLog":{"times":[0.6309330000003683,0.6982050000005984]},"fetchTaggedLogs":{"times":[546.4419269999999]},"loadCapsule":{"times":[0.3663479999995616,0.2945959999997285,0.31126599999697646]}}},{"functionName":"private_kernel_inner","time":169.86152700000093},{"functionName":"private_kernel_reset","time":228.6304369999998},{"functionName":"private_kernel_tail","time":82.56684300000052}],"unaccounted":750.4908659999965}} 10:47:43 [10:47:43.485] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:43 [10:47:43.509] VERBOSE: p2p:tx_pool Adding tx 0x1ca83fdb6cfbc0aa691b2e28ffc59f00c4630c5f8dd4a7b22fd19866b075972b to pool {"eventName":"tx-added-to-pool","txHash":"0x1ca83fdb6cfbc0aa691b2e28ffc59f00c4630c5f8dd4a7b22fd19866b075972b","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 10:47:43 [10:47:43.515] INFO: node Received tx 0x1ca83fdb6cfbc0aa691b2e28ffc59f00c4630c5f8dd4a7b22fd19866b075972b {"txHash":"0x1ca83fdb6cfbc0aa691b2e28ffc59f00c4630c5f8dd4a7b22fd19866b075972b"} 10:47:43 [10:47:43.516] INFO: pxe:service Sent transaction 0x1ca83fdb6cfbc0aa691b2e28ffc59f00c4630c5f8dd4a7b22fd19866b075972b 10:47:43 [10:47:43.697] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:43 [10:47:43.731] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 10:47:43 [10:47:43.749] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-14T10:52:56.000Z","now":"2025-06-14T10:53:03.415Z"} 10:47:43 [10:47:43.775] VERBOSE: simulator:public-processor Processed tx 0x1ca83fdb6cfbc0aa691b2e28ffc59f00c4630c5f8dd4a7b22fd19866b075972b with no public calls in 15.573758999998972ms {"txHash":"0x1ca83fdb6cfbc0aa691b2e28ffc59f00c4630c5f8dd4a7b22fd19866b075972b","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.573758999998972} 10:47:43 [10:47:43.777] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.026695752999999968s {"duration":0.026695752999999968,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1024,"l2Gas":5856},"totalSizeInBytes":256} 10:47:43 [10:47:43.946] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:43 [10:47:43.973] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1e6a7604df44f652cbba0d13d6fdeee19a3107560e98435a270fc715047b7f17","globalVariables":{"chainId":31337,"version":4008247234,"blockNumber":3,"slotNumber":4,"timestamp":1749898376,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1ca83fdb6cfbc0aa691b2e28ffc59f00c4630c5f8dd4a7b22fd19866b075972b"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":241.0211190000009,"publicProcessDuration":27.327257000000827,"rollupCircuitsDuration":224.4431489999988,"txCount":1,"blockNumber":3,"blockTimestamp":1749898376,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:43 [10:47:43.977] VERBOSE: sequencer Attesting committee is empty 10:47:44 [10:47:44.154] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:44 [10:47:44.298] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xe0b6276169660ad520353cf5edfe222cc7b90d610ca316462dc7e8017bee39ab 10:47:44 [10:47:44.299] VERBOSE: sequencer-client Sent L1 transaction 0xe0b6276169660ad520353cf5edfe222cc7b90d610ca316462dc7e8017bee39ab {"gasLimit":470265,"maxFeePerGas":"1.279583419","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:47:44 [10:47:44.312] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:47:44 [10:47:44.313] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xe0b6276169660ad520353cf5edfe222cc7b90d610ca316462dc7e8017bee39ab","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":3,"blockTimestamp":1749898376,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:44 [10:47:44.313] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:47:44 [10:47:44.323] INFO: blob-sink:server Received blob sidecar for block 0xe0aabd261760ce0abbcd66d33e3386fe829137b058d9aad8caf2baae00e0a917 10:47:44 [10:47:44.329] INFO: blob-sink:server Blob sidecar stored successfully for block 0xe0aabd261760ce0abbcd66d33e3386fe829137b058d9aad8caf2baae00e0a917 10:47:44 [10:47:44.357] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:44 [10:47:44.372] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 10:47:44 [10:47:44.374] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 10:47:44 [10:47:44.376] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 10:47:44 [10:47:44.506] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749898412] on http://127.0.0.1:8545 10:47:44 [10:47:44.508] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:47:44 [10:47:44.512] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749898412 10:47:44 [10:47:44.513] WARN: foundation:test-date-provider Time set to 2025-06-14T10:53:32.000Z {"offset":347488,"timeMs":1749898412000} 10:47:44 [10:47:44.513] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 10:47:44 [10:47:44.562] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:44 [10:47:44.755] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x1e6a7604df44f652cbba0d13d6fdeee19a3107560e98435a270fc715047b7f17","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":4008247234,"blockNumber":3,"slotNumber":4,"timestamp":1749898376,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","archiveNextLeafIndex":4} 10:47:44 [10:47:44.760] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 10:47:44 [10:47:44.765] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:44 [10:47:44.863] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":10.114943000000494,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749898376,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:44 [10:47:44.874] VERBOSE: p2p Synched to latest block 3 10:47:44 [10:47:44.968] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:45 [10:47:45.174] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:45 [10:47:45.340] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","blockNumber":4,"slot":5} 10:47:45 [10:47:45.376] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:45 [10:47:45.523] 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 10:47:45 [10:47:45.546] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","header":{"contentCommitment":{"blobsHash":"0x0065213ec0ac0ceab831dab8697ed460934019d444061ed228f781864baae14e","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749898376,"version":4008247234},"lastArchive":"0x2ca54f377df686abd4f73d0c475a6aad289d1142b16339492b3f3a4179edb7da","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x046659c3d7c6ca69f4815d9f68ec10714d122dd622795b7ca8628cb4d1909934","nullifierTree":"0x174bfb8bd2930fed2fa3d31d1d1deac3153bb3f735ebc456a59b871056f20b38","publicDataTree":"0x0d7394d2aa20596836691710bf04eafe382c248315fd9971dfb2a1265c279644"},"totalFees":1945714560,"totalManaUsed":5856}} 10:47:45 [10:47:45.613] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:45 [10:47:45.643] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:45 [10:47:45.699] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:45 [10:47:45.700] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:45 [10:47:45.811] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:45 [10:47:45.820] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x09169cec0e1748b73a3a6604c825cb1e82c5675ae33bb774ba8b41e81aa24836 10:47:45 [10:47:45.833] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:45 [10:47:45.834] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:45 [10:47:45.841] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:45 [10:47:45.842] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:45 [10:47:45.860] VERBOSE: pxe:service Added note {"index":0,"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2c2c6e4bf1758e77517e67b0d9285b1c6d4f69e848b7e80bdd2892595886779e","nullifier":"0x001cf471b4284b3183d0561ef8190b09776fab6d8e1a2842f85d5fff293dc4ea"} 10:47:45 [10:47:45.866] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:45 [10:47:45.873] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","blockNumber":4,"slot":5} 10:47:45 [10:47:45.891] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:46 [10:47:46.043] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:46 [10:47:46.293] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:46 [10:47:46.307] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:46 [10:47:46.308] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:46 [10:47:46.433] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:46 [10:47:46.466] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","blockNumber":4,"slot":5} 10:47:46 [10:47:46.470] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:46 [10:47:46.474] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:46 [10:47:46.732] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:47 [10:47:47.071] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:47 [10:47:47.393] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:47 [10:47:47.545] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","blockNumber":4,"slot":5} 10:47:47 [10:47:47.831] INFO: pxe:service Proving completed in 2291.990321999998ms {"timings":{"total":2291.990321999998,"sync":9.30013599999802,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":434.51311499999065,"oracles":{"debugLog":{"times":[0.871817999999621,0.625702999997884,0.6560339999996359,0.6873950000008335,0.6291940000010072,0.5149299999975483,0.39977899999939837,0.6537240000034217]},"fetchTaggedLogs":{"times":[117.31137700000181]},"loadCapsule":{"times":[0.2668760000015027,0.2395749999996042,0.6555840000000899,0.22039400000358,0.2576760000010836,1.1603439999962575]},"getSharedSecret":{"times":[4.058905999998387]},"aes128Decrypt":{"times":[0.47845000000233995,1.33441799999855]},"getPublicKeysAndPartialAddress":{"times":[1.816938999996637]},"getKeyValidationRequest":{"times":[1.8739189999978407]},"deliverNote":{"times":[17.784496000000217]},"deleteCapsule":{"times":[2.4132210000025225]},"storeCapsule":{"times":[1.660104999995383]},"getNotes":{"times":[5.197768999998516]},"getAuthWitness":{"times":[0.2572260000015376]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06024200000683777]},"callPrivateFunction":{"times":[586.7824099999998]}}},{"functionName":"private_kernel_init","time":89.57171100000414},{"functionName":"Test:set_tx_max_block_number","time":471.8695829999997,"oracles":{"debugLog":{"times":[0.5158010000013746,0.31359599999996135]},"fetchTaggedLogs":{"times":[165.05800499999896]},"loadCapsule":{"times":[0.2343440000040573,0.17196400000102585,0.18254399999568705]},"storeInExecutionCache":{"times":[0.05441200000495883]},"notifyEnqueuedPublicFunctionCall":{"times":[0.4425000000046566]}}},{"functionName":"private_kernel_inner","time":129.40015200000198},{"functionName":"private_kernel_reset","time":173.98674400000164},{"functionName":"private_kernel_tail","time":111.62610600000335}],"unaccounted":871.7227749999984}} 10:47:47 [10:47:47.834] 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 10:47:47 [10:47:47.838] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:47 [10:47:47.966] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:48 [10:47:48.016] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:48 [10:47:48.017] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:48 [10:47:48.055] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:48 [10:47:48.091] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","blockNumber":4,"slot":5} 10:47:48 [10:47:48.095] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:48 [10:47:48.100] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x09169cec0e1748b73a3a6604c825cb1e82c5675ae33bb774ba8b41e81aa24836 10:47:48 [10:47:48.113] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:48 [10:47:48.113] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:48 [10:47:48.120] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:48 [10:47:48.120] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:48 [10:47:48.125] VERBOSE: pxe:service Added note {"index":0,"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2c2c6e4bf1758e77517e67b0d9285b1c6d4f69e848b7e80bdd2892595886779e","nullifier":"0x001cf471b4284b3183d0561ef8190b09776fab6d8e1a2842f85d5fff293dc4ea"} 10:47:48 [10:47:48.128] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:48 [10:47:48.139] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:48 [10:47:48.277] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:48 [10:47:48.518] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:48 [10:47:48.536] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:48 [10:47:48.536] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:48 [10:47:48.699] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:48 [10:47:48.741] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","blockNumber":4,"slot":5} 10:47:48 [10:47:48.742] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:48 [10:47:48.747] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:48 [10:47:48.908] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:49 [10:47:49.513] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:49 [10:47:49.672] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","blockNumber":4,"slot":5} 10:47:49 [10:47:49.912] INFO: pxe:service Proving completed in 2048.0457000000024ms {"timings":{"total":2048.0457000000024,"sync":3.2829490000003716,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":359.3137579999966,"oracles":{"debugLog":{"times":[0.7522949999984121,0.7920670000021346,0.3783079999993788,0.46001000000251224,0.4753809999965597,0.565891999998712,0.29690600000321865,0.5010810000021593]},"fetchTaggedLogs":{"times":[80.89953799999785]},"loadCapsule":{"times":[0.23601500000222586,0.2484050000057323,0.5330510000057984,0.39473899999575224,0.15188399999897229,0.19980399999622023]},"getSharedSecret":{"times":[4.214057999997749]},"aes128Decrypt":{"times":[0.44861999999557156,0.9794899999978952]},"getPublicKeysAndPartialAddress":{"times":[1.706665999998222]},"getKeyValidationRequest":{"times":[1.3246779999972205]},"deliverNote":{"times":[4.6730680000036955]},"deleteCapsule":{"times":[1.1196639999980107]},"storeCapsule":{"times":[0.21479399999952875]},"getNotes":{"times":[1.010431000002427]},"getAuthWitness":{"times":[0.15654299999732757]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05302099999971688]},"callPrivateFunction":{"times":[615.4319140000007]}}},{"functionName":"private_kernel_init","time":63.26134599999932},{"functionName":"Test:set_tx_max_block_number","time":503.0760109999974,"oracles":{"debugLog":{"times":[0.6707640000022366,0.5525609999967855]},"fetchTaggedLogs":{"times":[208.13390400000208]},"loadCapsule":{"times":[0.19695299999875715,1.5857639999958337,2.1224849999998696]},"storeInExecutionCache":{"times":[0.06432099999801721]},"notifyEnqueuedPublicFunctionCall":{"times":[0.06467100000008941]}}},{"functionName":"private_kernel_inner","time":120.8608630000017},{"functionName":"private_kernel_reset","time":163.43485599999985},{"functionName":"private_kernel_tail","time":83.54018499999802}],"unaccounted":751.2757320000092}} 10:47:49 [10:47:49.938] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:49 [10:47:49.978] VERBOSE: p2p:tx_pool Adding tx 0x24fa5cd647526d1a9e7acd9e9e00d80526bcbeb31e6395cce6e379a447973fac to pool {"eventName":"tx-added-to-pool","txHash":"0x24fa5cd647526d1a9e7acd9e9e00d80526bcbeb31e6395cce6e379a447973fac","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226281,"feePaymentMethod":"fee_juice"} 10:47:49 [10:47:49.990] INFO: node Received tx 0x24fa5cd647526d1a9e7acd9e9e00d80526bcbeb31e6395cce6e379a447973fac {"txHash":"0x24fa5cd647526d1a9e7acd9e9e00d80526bcbeb31e6395cce6e379a447973fac"} 10:47:49 [10:47:49.990] INFO: pxe:service Sent transaction 0x24fa5cd647526d1a9e7acd9e9e00d80526bcbeb31e6395cce6e379a447973fac 10:47:50 [10:47:50.146] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:50 [10:47:50.188] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 10:47:50 [10:47:50.202] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-14T10:53:32.000Z","now":"2025-06-14T10:53:37.690Z"} 10:47:50 [10:47:50.245] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x5189a417 completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x5189a417","duration":22.910036000001128} 10:47:50 [10:47:50.265] VERBOSE: simulator:public-processor Processed tx 0x24fa5cd647526d1a9e7acd9e9e00d80526bcbeb31e6395cce6e379a447973fac with 1 public calls in 54.15240300000005ms {"txHash":"0x24fa5cd647526d1a9e7acd9e9e00d80526bcbeb31e6395cce6e379a447973fac","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":54.15240300000005} 10:47:50 [10:47:50.266] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.06378639700000349s {"duration":0.06378639700000349,"rate":54604.11880608038,"totalPublicGas":{"daGas":0,"l2Gas":3483},"totalBlockGas":{"daGas":1024,"l2Gas":29339},"totalSizeInBytes":256} 10:47:50 [10:47:50.386] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:50 [10:47:50.403] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 16198.487814656224 mana/s {"blockHash":"0x1b75529b608aea02babc23b43be5c05b42364f70ad89344ace7114454e4ee62e","globalVariables":{"chainId":31337,"version":4008247234,"blockNumber":4,"slotNumber":5,"timestamp":1749898412,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x24fa5cd647526d1a9e7acd9e9e00d80526bcbeb31e6395cce6e379a447973fac"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":214.3848999999973,"publicProcessDuration":64.13168299999961,"rollupCircuitsDuration":201.27926299999672,"txCount":1,"blockNumber":4,"blockTimestamp":1749898412,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:50 [10:47:50.405] VERBOSE: sequencer Attesting committee is empty 10:47:50 [10:47:50.591] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:50 [10:47:50.705] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xd13e124537bad9c87775ac4646a10714ce3f4a67a88772ac76994b83b9e439eb 10:47:50 [10:47:50.705] VERBOSE: sequencer-client Sent L1 transaction 0xd13e124537bad9c87775ac4646a10714ce3f4a67a88772ac76994b83b9e439eb {"gasLimit":470248,"maxFeePerGas":"1.261127749","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:47:50 [10:47:50.718] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749898448] on http://127.0.0.1:8545 10:47:50 [10:47:50.721] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:47:50 [10:47:50.722] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xd13e124537bad9c87775ac4646a10714ce3f4a67a88772ac76994b83b9e439eb","calldataSize":1444,"calldataGas":12268,"txCount":1,"blockNumber":4,"blockTimestamp":1749898412,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:50 [10:47:50.722] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:47:50 [10:47:50.725] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:47:50 [10:47:50.733] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749898448 10:47:50 [10:47:50.734] WARN: foundation:test-date-provider Time set to 2025-06-14T10:54:08.000Z {"offset":377267,"timeMs":1749898448000} 10:47:50 [10:47:50.734] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 10:47:50 [10:47:50.737] INFO: blob-sink:server Received blob sidecar for block 0x69bf2ac1e8ca6959c054695dd00e1e54d314988da1d15513125e96ef3ead994b 10:47:50 [10:47:50.741] INFO: blob-sink:server Blob sidecar stored successfully for block 0x69bf2ac1e8ca6959c054695dd00e1e54d314988da1d15513125e96ef3ead994b 10:47:50 [10:47:50.899] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:50 [10:47:50.902] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 10:47:50 [10:47:50.903] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 10:47:50 [10:47:50.905] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 10:47:51 [10:47:51.101] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:51 [10:47:51.362] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:51 [10:47:51.382] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x1b75529b608aea02babc23b43be5c05b42364f70ad89344ace7114454e4ee62e","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":4008247234,"blockNumber":4,"slotNumber":5,"timestamp":1749898412,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","archiveNextLeafIndex":5} 10:47:51 [10:47:51.387] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 10:47:51 [10:47:51.484] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":6.931495000004361,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749898412,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:51 [10:47:51.501] VERBOSE: p2p Synched to latest block 4 10:47:51 [10:47:51.568] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:51 [10:47:51.760] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:51 [10:47:51.771] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:51 [10:47:51.974] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:51 [10:47:51.996] 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 10:47:52 [10:47:52.023] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","header":{"contentCommitment":{"blobsHash":"0x00ab4c7071760806a9d1dce5f7be4020dbb80a374b332531281a5ebdeea71c59","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749898412,"version":4008247234},"lastArchive":"0x126e04d8e8aa7d75219d05870fa52a7580d4c7cc9354e5d978b7ab02bf189922","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x046659c3d7c6ca69f4815d9f68ec10714d122dd622795b7ca8628cb4d1909934","nullifierTree":"0x2a8261e6a07e3b4b6872c948a53aba56436fb2ad207d345c5cbe271e46c5defa","publicDataTree":"0x254c630d388c88fb57107cd57e2aba81aa856a0ad05b657216215ec20735984e"},"totalFees":1487487300,"totalManaUsed":29339}} 10:47:52 [10:47:52.104] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:52 [10:47:52.155] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:52 [10:47:52.155] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:52 [10:47:52.188] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:52 [10:47:52.212] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:52 [10:47:52.224] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x09169cec0e1748b73a3a6604c825cb1e82c5675ae33bb774ba8b41e81aa24836 10:47:52 [10:47:52.236] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:52 [10:47:52.238] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:52 [10:47:52.249] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:52 [10:47:52.250] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:52 [10:47:52.253] VERBOSE: pxe:service Added note {"index":0,"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2c2c6e4bf1758e77517e67b0d9285b1c6d4f69e848b7e80bdd2892595886779e","nullifier":"0x001cf471b4284b3183d0561ef8190b09776fab6d8e1a2842f85d5fff293dc4ea"} 10:47:52 [10:47:52.255] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:52 [10:47:52.267] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:52 [10:47:52.401] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:52 [10:47:52.737] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:52 [10:47:52.755] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:52 [10:47:52.755] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:52 [10:47:52.758] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:52 [10:47:52.763] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:52 [10:47:52.950] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:52 [10:47:52.953] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:52 [10:47:52.971] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:53 [10:47:53.310] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:53 [10:47:53.696] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:53 [10:47:53.865] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:54 [10:47:54.083] INFO: pxe:service Proving completed in 2066.664953000007ms {"timings":{"total":2066.664953000007,"sync":8.497283999997308,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":415.56278099999327,"oracles":{"debugLog":{"times":[0.7485530000049039,0.4085869999980787,0.6218609999996261,0.6379319999978179,0.5487900000007357,0.43240799999330193,0.40152699999453034,0.5992110000006505]},"fetchTaggedLogs":{"times":[67.44347299999936]},"loadCapsule":{"times":[0.23949400000128662,0.20838400000502588,0.34153599999990547,0.21466399999917485,0.2136640000026091,0.16263299999991432]},"getSharedSecret":{"times":[3.4883929999996326]},"aes128Decrypt":{"times":[0.2762349999975413,1.4416659999988042]},"getPublicKeysAndPartialAddress":{"times":[5.435107999997854]},"getKeyValidationRequest":{"times":[2.0453969999944093]},"deliverNote":{"times":[3.2792499999995925]},"deleteCapsule":{"times":[0.30906600000162143]},"storeCapsule":{"times":[0.2965750000003027]},"getNotes":{"times":[1.2316920000012033]},"getAuthWitness":{"times":[0.22553500000503846]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06714100000681356]},"callPrivateFunction":{"times":[689.9558899999975]}}},{"functionName":"private_kernel_init","time":87.7908729999981},{"functionName":"Test:set_tx_max_block_number","time":496.2361450000026,"oracles":{"debugLog":{"times":[0.5772310000029393,0.4412679999950342]},"fetchTaggedLogs":{"times":[196.89184300000488]},"loadCapsule":{"times":[0.3339360000027227,0.20508399999380345,0.20096299999568146]}}},{"functionName":"private_kernel_inner","time":133.67163599999913},{"functionName":"private_kernel_reset","time":159.1260570000013},{"functionName":"private_kernel_tail","time":66.14266099999804}],"unaccounted":699.6375160000171}} 10:47:54 [10:47:54.085] 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 10:47:54 [10:47:54.088] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:54 [10:47:54.173] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:54 [10:47:54.212] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:54 [10:47:54.213] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:54 [10:47:54.264] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:54 [10:47:54.271] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x09169cec0e1748b73a3a6604c825cb1e82c5675ae33bb774ba8b41e81aa24836 10:47:54 [10:47:54.289] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:54 [10:47:54.290] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:54 [10:47:54.297] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:54 [10:47:54.303] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:54 [10:47:54.304] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:54 [10:47:54.398] VERBOSE: pxe:service Added note {"index":0,"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2c2c6e4bf1758e77517e67b0d9285b1c6d4f69e848b7e80bdd2892595886779e","nullifier":"0x001cf471b4284b3183d0561ef8190b09776fab6d8e1a2842f85d5fff293dc4ea"} 10:47:54 [10:47:54.412] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:54 [10:47:54.439] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:54 [10:47:54.444] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:54 [10:47:54.605] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:54 [10:47:54.846] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:54 [10:47:54.858] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:54 [10:47:54.859] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:54 [10:47:54.979] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:55 [10:47:55.001] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:55 [10:47:55.001] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:55 [10:47:55.004] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:55 [10:47:55.282] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:55 [10:47:55.629] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:55 [10:47:55.797] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:56 [10:47:56.053] INFO: pxe:service Proving completed in 1942.678347999994ms {"timings":{"total":1942.678347999994,"sync":2.4727550000025076,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":441.76273700000456,"oracles":{"debugLog":{"times":[0.4419679999991786,0.4972490000000107,0.5476800000033109,0.662001999997301,0.45821799999976065,0.44215799999801675,0.6399020000026212,0.8321050000013201]},"fetchTaggedLogs":{"times":[55.69638099999429]},"loadCapsule":{"times":[0.3867469999968307,0.3991269999969518,0.7738339999996242,4.004442000004929,2.23076000000583,3.15387699999701]},"getSharedSecret":{"times":[8.902182000005268]},"aes128Decrypt":{"times":[0.3850970000057714,1.4802570000028936]},"getPublicKeysAndPartialAddress":{"times":[2.4323549999971874]},"getKeyValidationRequest":{"times":[7.7068900000012945]},"deliverNote":{"times":[94.29333100000076]},"deleteCapsule":{"times":[4.698145000002114]},"storeCapsule":{"times":[1.7236510000002454]},"getNotes":{"times":[6.166111999998975]},"getAuthWitness":{"times":[0.3396760000032373]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.08883100000093691]},"callPrivateFunction":{"times":[567.1461920000002]}}},{"functionName":"private_kernel_init","time":61.502816000000166},{"functionName":"Test:set_tx_max_block_number","time":442.73007399999915,"oracles":{"debugLog":{"times":[0.5081990000035148,0.26497500000550644]},"fetchTaggedLogs":{"times":[144.2764890000035]},"loadCapsule":{"times":[0.20989399999962188,0.1566129999991972,0.16250299999956042]}}},{"functionName":"private_kernel_inner","time":108.21690600000147},{"functionName":"private_kernel_reset","time":194.21643900000345},{"functionName":"private_kernel_tail","time":72.42533700000058}],"unaccounted":619.3512839999821}} 10:47:56 [10:47:56.067] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:56 [10:47:56.085] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x188c9d16820505646d5c7b716e16902b760c4d3902bf2221f80df40aa00d69f3 for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 10:47:56 [10:47:56.089] WARN: node Received invalid tx 0x188c9d16820505646d5c7b716e16902b760c4d3902bf2221f80df40aa00d69f3: Invalid max block number {"txHash":"0x188c9d16820505646d5c7b716e16902b760c4d3902bf2221f80df40aa00d69f3"} 10:47:56 [10:47:56.095] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 10:47:56 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 10:47:56 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 10:47:56 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 10:47:56 [10:47:56.097] 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 10:47:56 [10:47:56.209] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:56 [10:47:56.262] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:56 [10:47:56.263] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:56 [10:47:56.288] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:56 [10:47:56.328] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:56 [10:47:56.332] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:56 [10:47:56.340] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x09169cec0e1748b73a3a6604c825cb1e82c5675ae33bb774ba8b41e81aa24836 10:47:56 [10:47:56.384] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:56 [10:47:56.385] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:56 [10:47:56.396] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:56 [10:47:56.397] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:56 [10:47:56.400] VERBOSE: pxe:service Added note {"index":0,"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2c2c6e4bf1758e77517e67b0d9285b1c6d4f69e848b7e80bdd2892595886779e","nullifier":"0x001cf471b4284b3183d0561ef8190b09776fab6d8e1a2842f85d5fff293dc4ea"} 10:47:56 [10:47:56.402] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:56 [10:47:56.414] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:56 [10:47:56.492] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:56 [10:47:56.910] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:56 [10:47:56.924] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:56 [10:47:56.924] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:57 [10:47:57.050] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:57 [10:47:57.062] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:57 [10:47:57.073] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:57 [10:47:57.076] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:57 [10:47:57.273] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:57 [10:47:57.788] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:57 [10:47:57.993] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:58 [10:47:58.284] INFO: pxe:service Proving completed in 2171.98719ms {"timings":{"total":2171.98719,"sync":3.0097250000035274,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":411.77348399999755,"oracles":{"debugLog":{"times":[0.6919229999984964,0.546810000007099,0.4467489999951795,0.7176529999997001,0.4346480000021984,0.31121600000187755,0.34101599999848986,0.4719089999998687]},"fetchTaggedLogs":{"times":[74.81248999999661]},"loadCapsule":{"times":[0.40829699999449076,0.17680400000244845,0.6445319999984349,0.2760249999992084,0.2472940000006929,0.22988400000031106]},"getSharedSecret":{"times":[4.594964000003529]},"aes128Decrypt":{"times":[0.7036530000041239,1.6594899999981862]},"getPublicKeysAndPartialAddress":{"times":[6.3343259999965085]},"getKeyValidationRequest":{"times":[2.0723780000043917]},"deliverNote":{"times":[3.320339999998396]},"deleteCapsule":{"times":[0.3687270000009448]},"storeCapsule":{"times":[0.3475460000045132]},"getNotes":{"times":[2.101567999998224]},"getAuthWitness":{"times":[0.17980399999942165]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.15694300000177464]},"callPrivateFunction":{"times":[668.8998250000004]}}},{"functionName":"private_kernel_init","time":70.78934599999775},{"functionName":"Test:set_tx_max_block_number","time":534.1046159999969,"oracles":{"debugLog":{"times":[0.5567799999989802,0.38408699999854434]},"fetchTaggedLogs":{"times":[150.99982399999863]},"loadCapsule":{"times":[0.1797930000029737,0.1960830000025453,0.1715630000035162]},"storeInExecutionCache":{"times":[0.052550000000337604]},"notifyEnqueuedPublicFunctionCall":{"times":[0.05669099999795435]}}},{"functionName":"private_kernel_inner","time":164.67438199999742},{"functionName":"private_kernel_reset","time":218.6084320000009},{"functionName":"private_kernel_tail","time":80.05751500000042}],"unaccounted":688.9696900000054}} 10:47:58 [10:47:58.288] 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 10:47:58 [10:47:58.290] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:58 [10:47:58.371] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:58 [10:47:58.408] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:58 [10:47:58.409] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:58 [10:47:58.447] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f"} 10:47:58 [10:47:58.454] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x09169cec0e1748b73a3a6604c825cb1e82c5675ae33bb774ba8b41e81aa24836 10:47:58 [10:47:58.467] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:58 [10:47:58.468] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:58 [10:47:58.477] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:58 [10:47:58.477] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:58 [10:47:58.481] VERBOSE: pxe:service Added note {"index":0,"contract":"0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2c2c6e4bf1758e77517e67b0d9285b1c6d4f69e848b7e80bdd2892595886779e","nullifier":"0x001cf471b4284b3183d0561ef8190b09776fab6d8e1a2842f85d5fff293dc4ea"} 10:47:58 [10:47:58.484] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:58 [10:47:58.501] VERBOSE: simulator:client_execution_context:debug_log Setting 0x21579bd337201c6c2f4b1737164b6174e1adf3be4b3343401b1557cbcf980b7f as fee payer 10:47:58 [10:47:58.503] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:58 [10:47:58.772] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:59 [10:47:59.059] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:59 [10:47:59.072] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:59 [10:47:59.073] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:59 [10:47:59.080] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:47:59 [10:47:59.223] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:59 [10:47:59.248] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2cc0351ef926b6162b46a7962625f0f86131005d41ebb0e1d01371a3fd8e507f"} 10:47:59 [10:47:59.250] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:59 [10:47:59.474] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:59 [10:47:59.903] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:59 [10:47:59.924] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x27fed3f0e5b010a46d7b527632e4f3091f789fb6b7053dc6e22948886ade003c","blockNumber":5,"slot":6} 10:48:00 [10:48:00.281] INFO: pxe:service Proving completed in 1973.211037000001ms {"timings":{"total":1973.211037000001,"sync":2.6660679999986314,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":345.6921819999916,"oracles":{"debugLog":{"times":[0.4882789999974193,0.46379800000431715,0.523059999999532,0.6605219999983092,0.526030000000901,0.29986600000120234,0.38122699999803444,0.6319010000006529]},"fetchTaggedLogs":{"times":[41.51222499999858]},"loadCapsule":{"times":[0.17271299999993062,0.11055099999794038,3.1602469999997993,0.23403400000097463,0.4118780000062543,0.41175700000167126]},"getSharedSecret":{"times":[4.526511999996728]},"aes128Decrypt":{"times":[0.41309800000453833,1.2948539999997593]},"getPublicKeysAndPartialAddress":{"times":[2.1331089999948745]},"getKeyValidationRequest":{"times":[2.4328329999989364]},"deliverNote":{"times":[4.19562600000063]},"deleteCapsule":{"times":[0.30158499999379274]},"storeCapsule":{"times":[0.4068879999977071]},"getNotes":{"times":[2.214009999996051]},"getAuthWitness":{"times":[0.2975850000002538]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.12299199999688426]},"callPrivateFunction":{"times":[752.8717719999986]}}},{"functionName":"private_kernel_init","time":93.25363399999333},{"functionName":"Test:set_tx_max_block_number","time":585.1415940000006,"oracles":{"debugLog":{"times":[0.515889000002062,0.28368500000215136]},"fetchTaggedLogs":{"times":[176.53978899999493]},"loadCapsule":{"times":[0.1660630000042147,0.13372199999867007,0.17110300000058487]},"storeInExecutionCache":{"times":[0.08953099999780534]},"notifyEnqueuedPublicFunctionCall":{"times":[0.050500999997893814]}}},{"functionName":"private_kernel_inner","time":108.61422400000447},{"functionName":"private_kernel_reset","time":152.74066600000515},{"functionName":"private_kernel_tail","time":89.3974240000025}],"unaccounted":595.7052450000047}} 10:48:00 [10:48:00.314] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:00 [10:48:00.330] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x0192f138a3ab9b416b81be08e6e1b4ec3c119c1de557a8cb44663dcc64fc4a33 for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 10:48:00 [10:48:00.334] WARN: node Received invalid tx 0x0192f138a3ab9b416b81be08e6e1b4ec3c119c1de557a8cb44663dcc64fc4a33: Invalid max block number {"txHash":"0x0192f138a3ab9b416b81be08e6e1b4ec3c119c1de557a8cb44663dcc64fc4a33"} 10:48:00 [10:48:00.338] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 10:48:00 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 10:48:00 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 10:48:00 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 10:48:00 [10:48:00.342] INFO: node Stopping 10:48:00 [10:48:00.342] INFO: slasher Slasher client stopped. 10:48:00 [10:48:00.343] INFO: sequencer Stopped sequencer 10:48:00 [10:48:00.343] INFO: p2p P2P client stopped. 10:48:00 [10:48:00.344] INFO: world_state Stopped world state synchronizer 10:48:00 [10:48:00.345] INFO: archiver Stopped. 10:48:00 [10:48:00.345] INFO: node Stopped 10:48:00 [10:48:00.345] VERBOSE: e2e:e2e_max_block_number Cleaning up ACVM state 10:48:00 [10:48:00.345] INFO: e2e:e2e_max_block_number Cleaning up ACVM temp directory /tmp/a374ff5c 10:48:00 [10:48:00.346] VERBOSE: e2e:e2e_max_block_number Cleaning up BB state 10:48:00 [10:48:00.358] INFO: blob-sink:server Stopping blob sink 10:48:00 [10:48:00.359] INFO: blob-sink:server Blob sink stopped 10:48:00 [10:48:00.359] VERBOSE: e2e:e2e_max_block_number Cleaning up data directory at /tmp/7d873113f3e41d82 10:48:00 PASS src/e2e_max_block_number.test.ts 10:48:00 e2e_max_block_number 10:48:00 when requesting max block numbers higher than the mined one 10:48:00 with no enqueued public calls 10:48:00 ✓ sets the max block number (3016 ms) 10:48:00 ✓ does not invalidate the transaction (4871 ms) 10:48:00 with an enqueued public call 10:48:00 ✓ sets the max block number (2311 ms) 10:48:00 ✓ does not invalidate the transaction (4161 ms) 10:48:00 when requesting max block numbers lower than the mined one 10:48:00 with no enqueued public calls 10:48:00 ✓ sets the max block number (2088 ms) 10:48:00 ✓ invalidates the transaction (2012 ms) 10:48:00 with an enqueued public call 10:48:00 ✓ sets the max block number (2191 ms) 10:48:00 ✓ invalidates the transaction (2050 ms) 10:48:00 10:48:00 Test Suites: 1 passed, 1 total 10:48:00 Tests: 8 passed, 8 total 10:48:00 Snapshots: 0 total 10:48:00 Time: 46.855 s 10:48:00 Ran all test suites matching /src\/e2e_max_block_number.test.ts/i. 10:48:00 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?