Parent Log: http://ci.aztec-labs.com/7ea8a95c2d722890 Command: e49a94ea893520f8: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/e9a2301883801edbe36ea8bba05dcca7e714f30a Env: REF_NAME=gh-readonly-queue/master/pr-15131-464e1a9d9b40b5188586643b44bc52bffccbc3e6 CURRENT_VERSION=0.87.8 CI_FULL=0 Date: Wed Jun 18 12:54:47 UTC 2025 System: ARCH=arm64 CPUS=64 MEM=247Gi HOSTNAME=pr-15131_arm64_a1-fast Resources: CPU_LIST=0-63 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_d488fdfe97c9ae48_master 12:54:47 +++ id -u 12:54:47 +++ id -g 12:54:47 ++ docker run -d --name e2e_max_block_number --net=none --cpuset-cpus=0-63 --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'\''' 12:54:47 + cid=6b93d2ede3faf4f820b679be1832ec3a6b3f1d3a187fd822a65c485a5b789f09 12:54:47 + set +x 12:54:48 {"level":25,"time":1750251288458,"pid":12,"hostname":"6b93d2ede3fa","module":"logger","msg":"Logger initialized with level verbose"} 12:54:52 [12:54:52.517] INFO: e2e:e2e_max_block_number Logging metrics to log/unknown.jsonl 12:54:52 [12:54:52.708] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-YdaWsl/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 12:54:52 [12:54:52.743] INFO: e2e:e2e_max_block_number Calling anvil_setBlockTimestampInterval with params: [12] 12:54:52 [12:54:52.765] WARN: e2e:e2e_max_block_number Set block interval to 12 12:54:52 [12:54:52.765] VERBOSE: e2e:e2e_max_block_number Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 12:54:52 [12:54:52.811] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 12:54:52 [12:54:52.818] VERBOSE: e2e:e2e_max_block_number Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 12:54:52 [12:54:52.834] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 12:54:52 [12:54:52.839] VERBOSE: e2e:e2e_max_block_number Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 12:54:52 [12:54:52.854] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 12:54:52 [12:54:52.858] VERBOSE: e2e:e2e_max_block_number Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 12:54:52 [12:54:52.872] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 12:54:52 [12:54:52.876] VERBOSE: e2e:e2e_max_block_number Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 12:54:52 [12:54:52.893] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 12:54:52 [12:54:52.898] VERBOSE: e2e:e2e_max_block_number Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 12:54:52 [12:54:52.910] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 12:54:52 [12:54:52.915] VERBOSE: e2e:e2e_max_block_number Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 12:54:52 [12:54:52.916] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 12:54:52 [12:54:52.916] INFO: e2e:e2e_max_block_number Waiting for 6 transactions to be mined... 12:54:52 [12:54:52.935] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:54:52 [12:54:52.948] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 12:54:52 [12:54:52.949] VERBOSE: e2e:e2e_max_block_number Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 12:54:52 [12:54:52.959] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 12:54:52 [12:54:52.971] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 12:54:52 [12:54:52.975] VERBOSE: e2e:e2e_max_block_number Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 12:54:52 [12:54:52.986] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 12:54:52 [12:54:52.986] VERBOSE: e2e:e2e_max_block_number Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 12:54:53 [12:54:53.004] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 12:54:53 [12:54:53.008] VERBOSE: e2e:e2e_max_block_number Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 12:54:53 [12:54:53.020] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 12:54:53 [12:54:53.020] VERBOSE: e2e:e2e_max_block_number Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 12:54:53 [12:54:53.020] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 12:54:53 [12:54:53.020] INFO: e2e:e2e_max_block_number Waiting for 8 transactions to be mined... 12:54:53 [12:54:53.037] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:54:53 [12:54:53.046] VERBOSE: e2e:e2e_max_block_number Deployed shared contracts 12:54:53 [12:54:53.061] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 12:54:53 [12:54:53.061] VERBOSE: e2e:e2e_max_block_number Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 12:54:53 [12:54:53.064] 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} 12:54:53 [12:54:53.074] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 12:54:53 [12:54:53.098] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 12:54:53 [12:54:53.103] VERBOSE: e2e:e2e_max_block_number Awaiting for linked libraries to be deployed 12:54:53 [12:54:53.136] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xc27d3ecb0b75a01ab186e956c3f450084303be3e886e5c81aa0d9c4334ed7f56 {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 12:54:53 [12:54:53.141] 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} 12:54:53 [12:54:53.142] INFO: e2e:e2e_max_block_number Waiting for 9 transactions to be mined... 12:54:53 [12:54:53.159] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:54:53 [12:54:53.159] VERBOSE: e2e:e2e_max_block_number All core contracts have been deployed 12:54:53 [12:54:53.171] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 12:54:53 [12:54:53.172] VERBOSE: e2e:e2e_max_block_number Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 12:54:53 [12:54:53.185] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 12:54:53 [12:54:53.189] VERBOSE: e2e:e2e_max_block_number Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 12:54:53 [12:54:53.207] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 12:54:53 [12:54:53.208] VERBOSE: e2e:e2e_max_block_number Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 12:54:53 [12:54:53.208] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 12:54:53 [12:54:53.226] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:54:53 [12:54:53.232] VERBOSE: e2e:e2e_max_block_number Rollup deployed 12:54:53 [12:54:53.232] VERBOSE: e2e:e2e_max_block_number Waiting for rollup and slash factory to be deployed 12:54:53 [12:54:53.232] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 12:54:53 [12:54:53.249] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:54:53 [12:54:53.249] VERBOSE: e2e:e2e_max_block_number All transactions for L1 deployment have been mined 12:54:53 [12:54:53.267] 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"} 12:54:53 [12:54:53.269] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 12:54:53 [12:54:53.271] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:53 [12:54:53.272] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 12:54:53 [12:54:53.273] INFO: telemetry:client Using NoopTelemetryClient 12:54:53 [12:54:53.278] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 12:54:53 [12:54:53.278] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 12:54:53 [12:54:53.279] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/65ed0a767c40e665/blob-sink with map size 134217728 KB (LMDB v2) 12:54:53 [12:54:53.281] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:53 [12:54:53.284] INFO: aztecjs:utils:watcher New L1 time: 1750251532 12:54:53 [12:54:53.284] WARN: foundation:test-date-provider Time set to 2025-06-18T12:58:52.000Z {"offset":238716,"timeMs":1750251532000} 12:54:53 [12:54:53.286] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 12:54:53 [12:54:53.291] INFO: blob-sink:server Server is running on http://localhost:38421 12:54:53 [12:54:53.291] VERBOSE: e2e:e2e_max_block_number Creating and synching an aztec node... 12:54:53 [12:54:53.292] VERBOSE: e2e:e2e_max_block_number Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/2d90b13d/acvm 12:54:53 [12:54:53.293] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:38421"} 12:54:53 [12:54:53.306] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 12:54:53 [12:54:53.307] INFO: archiver:lmdb Creating archiver data store at directory /tmp/65ed0a767c40e665/archiver with map size 134217728 KB (LMDB v2) 12:54:53 [12:54:53.307] INFO: archiver:lmdb Starting data store with maxReaders 16 12:54:53 [12:54:53.491] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:53 [12:54:53.565] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:38421"} 12:54:53 [12:54:53.580] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:38421"} 12:54:53 [12:54:53.580] WARN: node:blob-sink:client No L1 consensus host urls configured 12:54:53 [12:54:53.580] WARN: node:blob-sink:client No archive client configured 12:54:53 [12:54:53.583] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x1960ce79e2fb0f2667523ebc06fd9ff9900c6f33e0c0b63d13c4f6d2dca93eb3 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x633a35fd2aa27ba9e6391a3d184cecc803d31c1963fe7cb1c44c6255b9f0c2f9","l1BlockNumber":16}} 12:54:53 [12:54:53.595] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 12:54:53 [12:54:53.601] INFO: world-state:database Creating world state data store at directory /tmp/65ed0a767c40e665/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 12:54:53 [12:54:53.624] INFO: world_state Created world state synchroniser with block history of 64 12:54:53 [12:54:53.624] WARN: node Aztec node is accepting fake proofs 12:54:53 [12:54:53.634] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/65ed0a767c40e665/p2p with map size 134217728 KB (LMDB v2) 12:54:53 [12:54:53.635] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 12:54:53 [12:54:53.636] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/65ed0a767c40e665/p2p-archive with map size 134217728 KB (LMDB v2) 12:54:53 [12:54:53.637] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 12:54:53 [12:54:53.639] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/65ed0a767c40e665/p2p-peers with map size 134217728 KB (LMDB v2) 12:54:53 [12:54:53.640] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 12:54:53 [12:54:53.641] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 12:54:53 [12:54:53.641] VERBOSE: p2p P2P is disabled. Using dummy P2P service 12:54:53 [12:54:53.643] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 12:54:53 [12:54:53.644] INFO: world_state Started world state synchronizer from block 1 12:54:53 [12:54:53.646] VERBOSE: world-state:block_stream Starting sync from block number 0 12:54:53 [12:54:53.648] INFO: p2p Starting p2p client from block 0 with empty mempools 12:54:53 [12:54:53.648] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 12:54:53 [12:54:53.649] INFO: slasher Slasher client initialized 12:54:53 [12:54:53.650] INFO: slasher Starting Slasher client... 12:54:53 [12:54:53.678] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 12:54:53 [12:54:53.679] VERBOSE: node All Aztec Node subsystems synced 12:54:53 [12:54:53.685] INFO: sequencer-client Deploying forwarder contract 12:54:53 [12:54:53.690] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 12:54:53 [12:54:53.695] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:53 [12:54:53.704] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 12:54:53 [12:54:53.704] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 12:54:53 [12:54:53.709] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 12:54:53 [12:54:53.722] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 12:54:53 [12:54:53.722] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 12:54:53 [12:54:53.722] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/2d90b13d/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 12:54:53 [12:54:53.905] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 12:54:53 [12:54:53.906] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 12:54:53 [12:54:53.907] INFO: node Aztec Node version: 0.87.8 12:54:53 [12:54:53.908] 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"} 12:54:53 [12:54:53.909] VERBOSE: e2e:e2e_max_block_number Creating a pxe... 12:54:53 [12:54:53.912] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:53 [12:54:53.920] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/b01707e8e49596aa/pxe_data with map size 134217728 KB (LMDB v2) 12:54:53 [12:54:53.929] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2cc5ec716cdf6cba3ccefb45ff15e9d27337f9fd386082c2787a1f895fabdc3b","blockNumber":1,"slot":2} 12:54:53 [12:54:53.930] INFO: pxe:data:lmdb Starting data store with maxReaders 16 12:54:54 [12:54:54.123] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:54 [12:54:54.151] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 12:54:54 [12:54:54.152] INFO: pxe:service Started PXE connected to chain 31337 version 3080034620 12:54:54 [12:54:54.200] INFO: pxe:service Registered account 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 12:54:54 [12:54:54.254] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 12:54:54 [12:54:54.254] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 12:54:54 [12:54:54.365] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:54 [12:54:54.368] INFO: pxe:service Added contract SchnorrAccount at 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 12:54:54 [12:54:54.406] VERBOSE: pxe:block_stream Starting sync from block number 0 12:54:54 [12:54:54.423] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 12:54:54 [12:54:54.507] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 12:54:54 [12:54:54.913] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 12:54:54 [12:54:54.987] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 12:54:55 [12:54:55.042] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2cc5ec716cdf6cba3ccefb45ff15e9d27337f9fd386082c2787a1f895fabdc3b","blockNumber":1,"slot":2} 12:54:55 [12:54:55.047] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:55 [12:54:55.131] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:54:55 [12:54:55.169] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:54:55 [12:54:55.170] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:54:55 [12:54:55.204] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:54:55 [12:54:55.208] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:54:55 [12:54:55.254] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:55 [12:54:55.359] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:54:55 [12:54:55.393] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:54:55 [12:54:55.393] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:54:55 [12:54:55.420] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:54:55 [12:54:55.423] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:54:55 [12:54:55.436] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:54:55 [12:54:55.459] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:55 [12:54:55.757] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:55 [12:54:55.767] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2cc5ec716cdf6cba3ccefb45ff15e9d27337f9fd386082c2787a1f895fabdc3b","blockNumber":1,"slot":2} 12:54:55 [12:54:55.983] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:56 [12:54:56.521] INFO: pxe:service Proving completed in 2123.010652ms {"timings":{"total":2123.010652,"sync":7.883041000000048,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":115.90817000000061,"oracles":{"callPrivateFunction":{"times":[577.3492189999997,252.43303900000046,152.92036699999971]}}},{"functionName":"private_kernel_init","time":92.34467500000028},{"functionName":"ContractClassRegisterer:register","time":563.6878919999999,"oracles":{"loadCapsule":{"times":[16.569762000000082]},"notifyCreatedNullifier":{"times":[0.5785260000002381]},"debugLog":{"times":[0.7426189999996495]},"notifyCreatedContractClassLog":{"times":[8.073263000000225]}}},{"functionName":"private_kernel_inner","time":118.1984910000001},{"functionName":"SchnorrAccount:constructor","time":230.61940199999935,"oracles":{"getContractInstance":{"times":[1.1758159999999407]},"debugLog":{"times":[0.47995799999989686,0.4164329999994152]},"fetchTaggedLogs":{"times":[37.04243300000053]},"loadCapsule":{"times":[0.3497860000006767,0.23874700000033044,0.23846000000048662]},"notifyCreatedNullifier":{"times":[0.2048320000003514,0.21948200000042561]},"notifyCreatedNote":{"times":[0.3950219999996989]},"getRandomField":{"times":[0.23900400000002264,0.17828900000040449,0.24551700000029086,0.10129400000005262,0.07921200000055251,0.08347199999934674,0.07279900000048656,0.08535799999935989,0.08085600000049453,0.0974740000001475,0.06117499999982101]},"getIndexedTaggingSecretAsSender":{"times":[28.889781999999286]},"incrementAppTaggingSecretIndexAsSender":{"times":[24.91770699999961]}}},{"functionName":"private_kernel_inner","time":113.26011600000038},{"functionName":"SchnorrAccount:entrypoint","time":131.9854009999999,"oracles":{"debugLog":{"times":[0.4564119999995455,0.3843280000000959,0.5686649999997826]},"fetchTaggedLogs":{"times":[28.52456499999971]},"loadCapsule":{"times":[0.1981839999998556,0.20208399999955873,0.23760800000036397]},"getNotes":{"times":[1.426143000000593]},"getAuthWitness":{"times":[0.33957700000064506]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.7573269999993499]}}},{"functionName":"private_kernel_inner","time":106.94472400000086},{"functionName":"private_kernel_reset","time":163.1348620000008},{"functionName":"private_kernel_tail","time":71.15089399999852}],"unaccounted":407.89298399999916}} 12:54:56 [12:54:56.536] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:56 [12:54:56.604] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2cc5ec716cdf6cba3ccefb45ff15e9d27337f9fd386082c2787a1f895fabdc3b","blockNumber":1,"slot":2} 12:54:56 [12:54:56.666] VERBOSE: p2p:tx_pool Adding tx 0x06ff808f91f69eac5053e313952ae70c71d6be99b683d0355756ce2e5fb0c2c2 to pool {"eventName":"tx-added-to-pool","txHash":"0x06ff808f91f69eac5053e313952ae70c71d6be99b683d0355756ce2e5fb0c2c2","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 12:54:56 [12:54:56.683] INFO: node Received tx 0x06ff808f91f69eac5053e313952ae70c71d6be99b683d0355756ce2e5fb0c2c2 {"txHash":"0x06ff808f91f69eac5053e313952ae70c71d6be99b683d0355756ce2e5fb0c2c2"} 12:54:56 [12:54:56.683] INFO: pxe:service Sent transaction 0x06ff808f91f69eac5053e313952ae70c71d6be99b683d0355756ce2e5fb0c2c2 12:54:56 [12:54:56.743] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:56 [12:54:56.947] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:57 [12:54:57.117] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 12:54:57 [12:54:57.129] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-18T12:59:16.000Z","now":"2025-06-18T12:58:55.845Z"} 12:54:57 [12:54:57.192] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:57 [12:54:57.358] VERBOSE: simulator:public-processor Processed tx 0x06ff808f91f69eac5053e313952ae70c71d6be99b683d0355756ce2e5fb0c2c2 with no public calls in 156.77673099999993ms {"txHash":"0x06ff808f91f69eac5053e313952ae70c71d6be99b683d0355756ce2e5fb0c2c2","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":156.77673099999993} 12:54:57 [12:54:57.361] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.23109570200000054s {"duration":0.23109570200000054,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 12:54:57 [12:54:57.473] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:57 [12:54:57.487] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x0db30783de18b5855a4cb0773717e4cb5760127c9284030a4e29ad3a63c0ea23","globalVariables":{"chainId":31337,"version":3080034620,"blockNumber":1,"slotNumber":2,"timestamp":1750251556,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x06ff808f91f69eac5053e313952ae70c71d6be99b683d0355756ce2e5fb0c2c2"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":370.22856499999943,"publicProcessDuration":231.8708549999992,"rollupCircuitsDuration":358.943679,"txCount":1,"blockNumber":1,"blockTimestamp":1750251556,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 12:54:57 [12:54:57.490] VERBOSE: sequencer Attesting committee is empty 12:54:57 [12:54:57.678] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:57 [12:54:57.846] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x7c3731e824368b5729b550a0542ee36b29fff0ff233974f46ab2eb79665ce101 12:54:57 [12:54:57.846] VERBOSE: sequencer-client Sent L1 transaction 0x7c3731e824368b5729b550a0542ee36b29fff0ff233974f46ab2eb79665ce101 {"gasLimit":514552,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 12:54:57 [12:54:57.856] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 12:54:57 [12:54:57.857] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x7c3731e824368b5729b550a0542ee36b29fff0ff233974f46ab2eb79665ce101","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":1,"blockTimestamp":1750251556,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 12:54:57 [12:54:57.857] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 12:54:57 [12:54:57.871] INFO: blob-sink:server Received blob sidecar for block 0xbbcc65eb8089eaf1636245d903268e13a4f0910f4ce5e198f7952ab24bfbd3c6 12:54:57 [12:54:57.876] INFO: blob-sink:server Blob sidecar stored successfully for block 0xbbcc65eb8089eaf1636245d903268e13a4f0910f4ce5e198f7952ab24bfbd3c6 12:54:57 [12:54:57.882] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:57 [12:54:57.886] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 12:54:57 [12:54:57.888] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 12:54:57 [12:54:57.889] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 12:54:57 [12:54:57.902] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750251592] on http://127.0.0.1:8545 12:54:57 [12:54:57.904] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 12:54:57 [12:54:57.905] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750251592 12:54:57 [12:54:57.906] WARN: foundation:test-date-provider Time set to 2025-06-18T12:59:52.000Z {"offset":294094,"timeMs":1750251592000} 12:54:57 [12:54:57.906] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 12:54:58 [12:54:58.085] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:58 [12:54:58.240] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 12:54:58 [12:54:58.245] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x0db30783de18b5855a4cb0773717e4cb5760127c9284030a4e29ad3a63c0ea23","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":3080034620,"blockNumber":1,"slotNumber":2,"timestamp":1750251556,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x018f02f7ecbdbd532d0d8ddc2ed253556b9e928f207b577ed7dfd7359fdba2fa","archiveNextLeafIndex":2} 12:54:58 [12:54:58.250] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 12:54:58 [12:54:58.286] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:58 [12:54:58.653] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:58 [12:54:58.656] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 12:54:58 [12:54:58.660] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":10.695665999999619,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750251556,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 12:54:58 [12:54:58.661] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323 12:54:58 [12:54:58.878] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:58 [12:54:58.886] VERBOSE: p2p Synched to latest block 1 12:54:59 [12:54:59.117] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:59 [12:54:59.129] INFO: pxe:service Added contract Test at 0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323 with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 12:54:59 [12:54:59.148] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x018f02f7ecbdbd532d0d8ddc2ed253556b9e928f207b577ed7dfd7359fdba2fa","header":{"contentCommitment":{"blobsHash":"0x009500119b78beafa597f0b1e6d32a6bce5b161f48136a97cf6460500681d2b6","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750251556,"version":3080034620},"lastArchive":"0x2cc5ec716cdf6cba3ccefb45ff15e9d27337f9fd386082c2787a1f895fabdc3b","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2d527b915f58e66f068b64f6db62c4fdee56b374bb43ec45a7d90499395581d5","nullifierTree":"0x2a9256a8bd94779ea498e5dcdbdb49a1b6a49547b638b43d5c236ca450d8c170","publicDataTree":"0x20c6c5b703b0a0caec64735a588601d9d0eff11f034add0d9f588ea770e76033"},"totalFees":6794052480,"totalManaUsed":20448}} 12:54:59 [12:54:59.217] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:54:59 [12:54:59.251] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:54:59 [12:54:59.251] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:54:59 [12:54:59.297] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:54:59 [12:54:59.301] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0c5ad1431c828a4d83565f5180252cb0c210ea5b49dd5121cbd965376549e6b7 12:54:59 [12:54:59.311] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:54:59 [12:54:59.312] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:54:59 [12:54:59.324] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:59 [12:54:59.331] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:54:59 [12:54:59.332] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:54:59 [12:54:59.344] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x018f02f7ecbdbd532d0d8ddc2ed253556b9e928f207b577ed7dfd7359fdba2fa","blockNumber":2,"slot":3} 12:54:59 [12:54:59.344] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x08c5558485317ed51c37fc6a4f5738484514d1b61d4a58d0daf50c29e2d9c0b3","nullifier":"0x07be5b5baf6e31b838dbe5d800db9e8300e23da17504756fb324d7e05fb8cfac"} 12:54:59 [12:54:59.347] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:54:59 [12:54:59.358] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:54:59 [12:54:59.387] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 12:54:59 [12:54:59.702] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 12:54:59 [12:54:59.772] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 12:54:59 [12:54:59.826] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:54:59 [12:54:59.876] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 12:54:59 [12:54:59.907] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x018f02f7ecbdbd532d0d8ddc2ed253556b9e928f207b577ed7dfd7359fdba2fa","blockNumber":2,"slot":3} 12:54:59 [12:54:59.921] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 12:54:59 [12:54:59.932] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323,0x0000000000000000000000000000000000000000000000000000000000000001,0x2f8071f220ebf97fa590d394eeb618170185dd19b3064d71eecccd5e3d8ba719,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 12:55:00 [12:55:00.047] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:00 [12:55:00.341] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:00 [12:55:00.358] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:00 [12:55:00.359] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:00 [12:55:00.463] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:00 [12:55:00.500] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:00 [12:55:00.506] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x018f02f7ecbdbd532d0d8ddc2ed253556b9e928f207b577ed7dfd7359fdba2fa","blockNumber":2,"slot":3} 12:55:00 [12:55:00.510] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:00 [12:55:00.673] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:00 [12:55:00.891] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:01 [12:55:01.195] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:01 [12:55:01.197] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x018f02f7ecbdbd532d0d8ddc2ed253556b9e928f207b577ed7dfd7359fdba2fa","blockNumber":2,"slot":3} 12:55:01 [12:55:01.468] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:02 [12:55:02.105] INFO: pxe:service Proving completed in 2963.6756509999996ms {"timings":{"total":2963.6756509999996,"sync":9.088565000000017,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":338.7736590000022,"oracles":{"debugLog":{"times":[0.5021210000013525,0.3664989999997488,0.42550500000106695,0.5293259999998554,0.3968960000001971,0.37346900000011374,0.35001699999884295,0.4554900000002817]},"fetchTaggedLogs":{"times":[47.671094000001176]},"loadCapsule":{"times":[0.2186600000004546,0.3510129999995115,0.4047049999990122,0.2389390000007552,0.20409600000130013,0.2023879999997007]},"getSharedSecret":{"times":[3.06388700000025]},"aes128Decrypt":{"times":[0.9237479999992502,0.9136650000000373]},"getPublicKeysAndPartialAddress":{"times":[5.302042000001165]},"getKeyValidationRequest":{"times":[11.43269599999985]},"deliverNote":{"times":[12.687846000000718]},"deleteCapsule":{"times":[0.40503700000044773]},"storeCapsule":{"times":[0.3406250000007276]},"getNotes":{"times":[1.323197000001528]},"getAuthWitness":{"times":[0.20309900000029302]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07132699999965553]},"callPrivateFunction":{"times":[464.98441900000034,111.05031400000007,577.9581030000008]}}},{"functionName":"private_kernel_init","time":59.44610599999942},{"functionName":"ContractClassRegisterer:register","time":455.1084779999983,"oracles":{"loadCapsule":{"times":[3.5696849999985716]},"notifyCreatedNullifier":{"times":[0.29969700000037847]},"debugLog":{"times":[0.4463500000001659]},"notifyCreatedContractClassLog":{"times":[6.898699999999735]}}},{"functionName":"private_kernel_inner","time":105.8620350000001},{"functionName":"ContractInstanceDeployer:deploy","time":57.61773699999867,"oracles":{"storeInExecutionCache":{"times":[0.1843879999996716]},"callPrivateFunction":{"times":[46.916761999998926]},"notifyCreatedNullifier":{"times":[0.2569260000000213]},"debugLog":{"times":[0.43619500000022526]}}},{"functionName":"private_kernel_inner","time":105.86961399999927},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":36.332102000000305,"oracles":{}},{"functionName":"private_kernel_inner","time":110.98416499999985},{"functionName":"Test:initialize","time":462.87420399999974,"oracles":{"getContractInstance":{"times":[3.8994780000011815]},"debugLog":{"times":[0.4307250000001659,0.33967200000006414]},"fetchTaggedLogs":{"times":[150.44129799999973]},"loadCapsule":{"times":[0.17878200000086508,0.16308400000161782,0.2210770000001503]},"notifyCreatedNullifier":{"times":[0.21204200000101991]}}},{"functionName":"private_kernel_inner","time":105.61465099999987},{"functionName":"private_kernel_reset","time":161.6057209999999},{"functionName":"private_kernel_tail","time":69.10512000000017}],"unaccounted":885.3934940000017}} 12:55:02 [12:55:02.118] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:02 [12:55:02.123] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x018f02f7ecbdbd532d0d8ddc2ed253556b9e928f207b577ed7dfd7359fdba2fa","blockNumber":2,"slot":3} 12:55:02 [12:55:02.262] VERBOSE: p2p:tx_pool Adding tx 0x17a882dc9b39957595de1118719066f703e6c15d1ddb21facbe3a947d5c7362d to pool {"eventName":"tx-added-to-pool","txHash":"0x17a882dc9b39957595de1118719066f703e6c15d1ddb21facbe3a947d5c7362d","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 12:55:02 [12:55:02.281] INFO: node Received tx 0x17a882dc9b39957595de1118719066f703e6c15d1ddb21facbe3a947d5c7362d {"txHash":"0x17a882dc9b39957595de1118719066f703e6c15d1ddb21facbe3a947d5c7362d"} 12:55:02 [12:55:02.281] INFO: pxe:service Sent transaction 0x17a882dc9b39957595de1118719066f703e6c15d1ddb21facbe3a947d5c7362d 12:55:02 [12:55:02.324] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:02 [12:55:02.525] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:02 [12:55:02.641] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 12:55:02 [12:55:02.655] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-18T12:59:52.000Z","now":"2025-06-18T12:59:56.749Z"} 12:55:02 [12:55:02.736] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:02 [12:55:02.925] VERBOSE: simulator:public-processor Processed tx 0x17a882dc9b39957595de1118719066f703e6c15d1ddb21facbe3a947d5c7362d with no public calls in 180.8169139999991ms {"txHash":"0x17a882dc9b39957595de1118719066f703e6c15d1ddb21facbe3a947d5c7362d","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":180.8169139999991} 12:55:02 [12:55:02.930] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.2743435040000004s {"duration":0.2743435040000004,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 12:55:02 [12:55:02.942] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:03 [12:55:03.095] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2d1ac130cf0808bff2b1efd7def61abb9f99bc05fefd144ea919b749f595daee","globalVariables":{"chainId":31337,"version":3080034620,"blockNumber":2,"slotNumber":3,"timestamp":1750251592,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x17a882dc9b39957595de1118719066f703e6c15d1ddb21facbe3a947d5c7362d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":453.46908799999983,"publicProcessDuration":274.7752509999991,"rollupCircuitsDuration":440.55590400000074,"txCount":1,"blockNumber":2,"blockTimestamp":1750251592,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 12:55:03 [12:55:03.099] VERBOSE: sequencer Attesting committee is empty 12:55:03 [12:55:03.206] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:03 [12:55:03.402] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x12a06a95146774e51ad3412fd13814aa23aac457b2ff00d613244905fa02a94d 12:55:03 [12:55:03.402] VERBOSE: sequencer-client Sent L1 transaction 0x12a06a95146774e51ad3412fd13814aa23aac457b2ff00d613244905fa02a94d {"gasLimit":470298,"maxFeePerGas":"1.303611197","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 12:55:03 [12:55:03.409] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:03 [12:55:03.415] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 12:55:03 [12:55:03.415] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x12a06a95146774e51ad3412fd13814aa23aac457b2ff00d613244905fa02a94d","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":2,"blockTimestamp":1750251592,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 12:55:03 [12:55:03.416] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 12:55:03 [12:55:03.418] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 12:55:03 [12:55:03.420] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 12:55:03 [12:55:03.423] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 12:55:03 [12:55:03.424] INFO: blob-sink:server Received blob sidecar for block 0xd6fea3fa07323cb68678643efb97aaf97bfb023b051b93355bcfb4cbe184ecca 12:55:03 [12:55:03.428] INFO: blob-sink:server Blob sidecar stored successfully for block 0xd6fea3fa07323cb68678643efb97aaf97bfb023b051b93355bcfb4cbe184ecca 12:55:03 [12:55:03.592] VERBOSE: archiver:block-helper Store contract instance at 0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323 12:55:03 [12:55:03.613] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 12:55:03 [12:55:03.641] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:03 [12:55:03.654] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x2d1ac130cf0808bff2b1efd7def61abb9f99bc05fefd144ea919b749f595daee","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":3080034620,"blockNumber":2,"slotNumber":3,"timestamp":1750251592,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x08cc3ca2c89e223040e78aa2f275ad063cddf494c628041cffdbde1f87f99a80","archiveNextLeafIndex":3} 12:55:03 [12:55:03.660] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750251628] on http://127.0.0.1:8545 12:55:03 [12:55:03.662] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 12:55:03 [12:55:03.663] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 12:55:03 [12:55:03.664] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750251628 12:55:03 [12:55:03.664] WARN: foundation:test-date-provider Time set to 2025-06-18T13:00:28.000Z {"offset":324336,"timeMs":1750251628000} 12:55:03 [12:55:03.664] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 12:55:03 [12:55:03.757] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":6.413875999998709,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750251592,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 12:55:03 [12:55:03.776] VERBOSE: p2p Synched to latest block 2 12:55:03 [12:55:03.851] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:03 [12:55:03.933] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x08cc3ca2c89e223040e78aa2f275ad063cddf494c628041cffdbde1f87f99a80","blockNumber":3,"slot":4} 12:55:04 [12:55:04.053] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:04 [12:55:04.256] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:04 [12:55:04.435] INFO: aztecjs:deploy_sent_tx Contract 0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323 successfully deployed. 12:55:04 [12:55:04.437] 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 12:55:04 [12:55:04.459] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:04 [12:55:04.474] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x08cc3ca2c89e223040e78aa2f275ad063cddf494c628041cffdbde1f87f99a80","blockNumber":3,"slot":4} 12:55:04 [12:55:04.480] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x08cc3ca2c89e223040e78aa2f275ad063cddf494c628041cffdbde1f87f99a80","header":{"contentCommitment":{"blobsHash":"0x00c2fd5c40062046759b0225fa992c6529681010acbce68371fb25ed68f653a4","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750251592,"version":3080034620},"lastArchive":"0x018f02f7ecbdbd532d0d8ddc2ed253556b9e928f207b577ed7dfd7359fdba2fa","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2d527b915f58e66f068b64f6db62c4fdee56b374bb43ec45a7d90499395581d5","nullifierTree":"0x2a11e1eaa62391b6f2a9ce267d192664cb9b358306d1b488469f60921e6ac272","publicDataTree":"0x140149bfdb995b10676fc37398d786d29b8ced3933ee1913f3c66f4bcdc6401c"},"totalFees":7272506880,"totalManaUsed":21888}} 12:55:04 [12:55:04.548] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:04 [12:55:04.581] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:04 [12:55:04.582] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:04 [12:55:04.620] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:04 [12:55:04.625] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0c5ad1431c828a4d83565f5180252cb0c210ea5b49dd5121cbd965376549e6b7 12:55:04 [12:55:04.634] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:55:04 [12:55:04.635] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:55:04 [12:55:04.640] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:55:04 [12:55:04.640] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:55:04 [12:55:04.643] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x08c5558485317ed51c37fc6a4f5738484514d1b61d4a58d0daf50c29e2d9c0b3","nullifier":"0x07be5b5baf6e31b838dbe5d800db9e8300e23da17504756fb324d7e05fb8cfac"} 12:55:04 [12:55:04.645] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:04 [12:55:04.656] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:55:04 [12:55:04.847] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:05 [12:55:05.057] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:05 [12:55:05.068] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:05 [12:55:05.069] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:05 [12:55:05.186] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:05 [12:55:05.202] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x08cc3ca2c89e223040e78aa2f275ad063cddf494c628041cffdbde1f87f99a80","blockNumber":3,"slot":4} 12:55:05 [12:55:05.210] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:05 [12:55:05.213] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:05 [12:55:05.465] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:05 [12:55:05.744] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:05 [12:55:05.888] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x08cc3ca2c89e223040e78aa2f275ad063cddf494c628041cffdbde1f87f99a80","blockNumber":3,"slot":4} 12:55:06 [12:55:06.125] INFO: pxe:service Proving completed in 1654.083917ms {"timings":{"total":1654.083917,"sync":14.37604999999894,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":266.08502199999566,"oracles":{"debugLog":{"times":[0.44593300000269664,0.39699599999948987,0.40937599999961094,0.511410999999498,0.38830199999938486,0.3274459999993269,0.3579650000028778,0.45495100000334787]},"fetchTaggedLogs":{"times":[41.70701700000063]},"loadCapsule":{"times":[0.21450199999890174,0.18544399999882444,0.32422500000029686,0.22143100000175764,0.1625370000001567,0.19987099999707425]},"getSharedSecret":{"times":[2.8545869999979914]},"aes128Decrypt":{"times":[0.2318329999980051,0.838633999999729]},"getPublicKeysAndPartialAddress":{"times":[1.2232650000005378]},"getKeyValidationRequest":{"times":[1.4103150000009919]},"deliverNote":{"times":[2.5874409999996715]},"deleteCapsule":{"times":[0.2616410000009637]},"storeCapsule":{"times":[0.2282859999977518]},"getNotes":{"times":[1.3634490000004007]},"getAuthWitness":{"times":[0.19918800000232295]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.0666769999988901]},"callPrivateFunction":{"times":[560.7768270000015]}}},{"functionName":"private_kernel_init","time":55.35708099999829},{"functionName":"Test:set_tx_max_block_number","time":454.2834320000038,"oracles":{"debugLog":{"times":[0.42702099999951315,0.3830049999996845]},"fetchTaggedLogs":{"times":[143.72304900000017]},"loadCapsule":{"times":[0.2112520000009681,0.16858599999977741,0.19620600000052946]}}},{"functionName":"private_kernel_inner","time":108.3130740000015},{"functionName":"private_kernel_reset","time":171.67810499999905},{"functionName":"private_kernel_tail","time":66.86278800000218}],"unaccounted":517.1283650000005}} 12:55:06 [12:55:06.129] 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 12:55:06 [12:55:06.131] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:06 [12:55:06.211] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:06 [12:55:06.246] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:06 [12:55:06.246] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:06 [12:55:06.285] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:06 [12:55:06.289] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0c5ad1431c828a4d83565f5180252cb0c210ea5b49dd5121cbd965376549e6b7 12:55:06 [12:55:06.299] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:55:06 [12:55:06.300] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:55:06 [12:55:06.343] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:06 [12:55:06.347] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:55:06 [12:55:06.348] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:55:06 [12:55:06.372] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x08c5558485317ed51c37fc6a4f5738484514d1b61d4a58d0daf50c29e2d9c0b3","nullifier":"0x07be5b5baf6e31b838dbe5d800db9e8300e23da17504756fb324d7e05fb8cfac"} 12:55:06 [12:55:06.374] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:06 [12:55:06.385] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:55:06 [12:55:06.607] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:06 [12:55:06.708] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:06 [12:55:06.719] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:06 [12:55:06.719] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:06 [12:55:06.833] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x08cc3ca2c89e223040e78aa2f275ad063cddf494c628041cffdbde1f87f99a80","blockNumber":3,"slot":4} 12:55:06 [12:55:06.855] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:06 [12:55:06.858] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:06 [12:55:06.938] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:07 [12:55:07.381] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:07 [12:55:07.530] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x08cc3ca2c89e223040e78aa2f275ad063cddf494c628041cffdbde1f87f99a80","blockNumber":3,"slot":4} 12:55:07 [12:55:07.761] INFO: pxe:service Proving completed in 1609.168494999998ms {"timings":{"total":1609.168494999998,"sync":2.641318999998475,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":330.6176639999976,"oracles":{"debugLog":{"times":[0.45085800000015297,0.4350769999982731,0.427042999999685,0.522571000001335,0.39864400000078604,0.42865099999835365,0.36335999999937485,0.4573420000015176]},"fetchTaggedLogs":{"times":[41.49309499999799]},"loadCapsule":{"times":[0.2042699999983597,0.19424199999775738,0.3629259999979695,0.2783259999996517,0.27106500000081724,0.2572249999975611]},"getSharedSecret":{"times":[3.023606999999174]},"aes128Decrypt":{"times":[0.28055099999983213,0.9160460000020976]},"getPublicKeysAndPartialAddress":{"times":[1.3345620000000054]},"getKeyValidationRequest":{"times":[43.473971999999776]},"deliverNote":{"times":[24.214837000003172]},"deleteCapsule":{"times":[0.2408499999983178]},"storeCapsule":{"times":[0.22284700000091107]},"getNotes":{"times":[1.150885000002745]},"getAuthWitness":{"times":[0.18588900000031572]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06244499999957043]},"callPrivateFunction":{"times":[476.29612700000143]}}},{"functionName":"private_kernel_init","time":54.98667199999909},{"functionName":"Test:set_tx_max_block_number","time":368.4895460000007,"oracles":{"debugLog":{"times":[0.44602300000042305,0.35579800000050454]},"fetchTaggedLogs":{"times":[137.65986800000246]},"loadCapsule":{"times":[0.1793870000001334,0.16116600000168546,0.1996770000005199]}}},{"functionName":"private_kernel_inner","time":107.70718200000192},{"functionName":"private_kernel_reset","time":172.54426100000273},{"functionName":"private_kernel_tail","time":67.12556799999948}],"unaccounted":505.0562829999981}} 12:55:07 [12:55:07.775] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:07 [12:55:07.795] VERBOSE: p2p:tx_pool Adding tx 0x29e096406ea9c3e9d5c60c07e4eeb155b843472a0982128e27c9fb9148f7f222 to pool {"eventName":"tx-added-to-pool","txHash":"0x29e096406ea9c3e9d5c60c07e4eeb155b843472a0982128e27c9fb9148f7f222","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 12:55:07 [12:55:07.799] INFO: node Received tx 0x29e096406ea9c3e9d5c60c07e4eeb155b843472a0982128e27c9fb9148f7f222 {"txHash":"0x29e096406ea9c3e9d5c60c07e4eeb155b843472a0982128e27c9fb9148f7f222"} 12:55:07 [12:55:07.799] INFO: pxe:service Sent transaction 0x29e096406ea9c3e9d5c60c07e4eeb155b843472a0982128e27c9fb9148f7f222 12:55:07 [12:55:07.979] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:08 [12:55:08.048] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 12:55:08 [12:55:08.062] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-18T13:00:28.000Z","now":"2025-06-18T13:00:32.398Z"} 12:55:08 [12:55:08.081] VERBOSE: simulator:public-processor Processed tx 0x29e096406ea9c3e9d5c60c07e4eeb155b843472a0982128e27c9fb9148f7f222 with no public calls in 12.308875999999145ms {"txHash":"0x29e096406ea9c3e9d5c60c07e4eeb155b843472a0982128e27c9fb9148f7f222","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":12.308875999999145} 12:55:08 [12:55:08.081] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.018620818999999757s {"duration":0.018620818999999757,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1024,"l2Gas":5856},"totalSizeInBytes":256} 12:55:08 [12:55:08.188] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:08 [12:55:08.196] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x13054e6e1a77295462eaf0736a5e925f7d68113a8351657ce1e4314d64af84f5","globalVariables":{"chainId":31337,"version":3080034620,"blockNumber":3,"slotNumber":4,"timestamp":1750251628,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x29e096406ea9c3e9d5c60c07e4eeb155b843472a0982128e27c9fb9148f7f222"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":147.54515100000208,"publicProcessDuration":18.97543299999961,"rollupCircuitsDuration":134.72056899999734,"txCount":1,"blockNumber":3,"blockTimestamp":1750251628,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:55:08 [12:55:08.199] VERBOSE: sequencer Attesting committee is empty 12:55:08 [12:55:08.457] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:08 [12:55:08.465] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x8c67a9885be7f51b324d6048504b979093d1d1fc42155c8232d989170eee0350 12:55:08 [12:55:08.466] VERBOSE: sequencer-client Sent L1 transaction 0x8c67a9885be7f51b324d6048504b979093d1d1fc42155c8232d989170eee0350 {"gasLimit":470248,"maxFeePerGas":"1.279583409","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 12:55:08 [12:55:08.475] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 12:55:08 [12:55:08.476] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x8c67a9885be7f51b324d6048504b979093d1d1fc42155c8232d989170eee0350","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":3,"blockTimestamp":1750251628,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:55:08 [12:55:08.476] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 12:55:08 [12:55:08.480] INFO: blob-sink:server Received blob sidecar for block 0xec6fb492b9d6573364a5575e66c7e0adf4ccf4a82b67c9ad99005092fb97bd5a 12:55:08 [12:55:08.484] INFO: blob-sink:server Blob sidecar stored successfully for block 0xec6fb492b9d6573364a5575e66c7e0adf4ccf4a82b67c9ad99005092fb97bd5a 12:55:08 [12:55:08.501] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750251664] on http://127.0.0.1:8545 12:55:08 [12:55:08.502] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 12:55:08 [12:55:08.503] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750251664 12:55:08 [12:55:08.503] WARN: foundation:test-date-provider Time set to 2025-06-18T13:01:04.000Z {"offset":355497,"timeMs":1750251664000} 12:55:08 [12:55:08.503] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 12:55:08 [12:55:08.662] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:08 [12:55:08.665] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 12:55:08 [12:55:08.666] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 12:55:08 [12:55:08.667] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 12:55:08 [12:55:08.948] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:08 [12:55:08.962] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x13054e6e1a77295462eaf0736a5e925f7d68113a8351657ce1e4314d64af84f5","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":3080034620,"blockNumber":3,"slotNumber":4,"timestamp":1750251628,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x26ad1665be31cd190a0d0c85b288cd563aaa2fb352d4ff7e1c844dc51f8b721b","archiveNextLeafIndex":4} 12:55:08 [12:55:08.968] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 12:55:09 [12:55:09.065] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":6.1438600000001315,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750251628,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:55:09 [12:55:09.075] VERBOSE: p2p Synched to latest block 3 12:55:09 [12:55:09.152] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:09 [12:55:09.354] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:09 [12:55:09.494] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x26ad1665be31cd190a0d0c85b288cd563aaa2fb352d4ff7e1c844dc51f8b721b","blockNumber":4,"slot":5} 12:55:09 [12:55:09.556] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:09 [12:55:09.758] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:09 [12:55:09.804] 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 12:55:09 [12:55:09.821] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x26ad1665be31cd190a0d0c85b288cd563aaa2fb352d4ff7e1c844dc51f8b721b","header":{"contentCommitment":{"blobsHash":"0x00df56bf437c38e8fc260f940c48b02c90a1e2f70fb1284ca31733507c8f65a6","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750251628,"version":3080034620},"lastArchive":"0x08cc3ca2c89e223040e78aa2f275ad063cddf494c628041cffdbde1f87f99a80","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2d527b915f58e66f068b64f6db62c4fdee56b374bb43ec45a7d90499395581d5","nullifierTree":"0x29e24ac3af7479a840cb2ab8c52890843ab829a042c99ff0d1512ccb65f99b5e","publicDataTree":"0x292d5035af021d11063add39cb50d0c277f78e1912988b7357b28298faef2308"},"totalFees":1945714560,"totalManaUsed":5856}} 12:55:09 [12:55:09.880] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:09 [12:55:09.915] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:09 [12:55:09.915] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:09 [12:55:09.951] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:09 [12:55:09.955] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0c5ad1431c828a4d83565f5180252cb0c210ea5b49dd5121cbd965376549e6b7 12:55:09 [12:55:09.964] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:55:09 [12:55:09.965] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:55:09 [12:55:09.966] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:09 [12:55:09.972] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:55:09 [12:55:09.973] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:55:09 [12:55:09.975] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x08c5558485317ed51c37fc6a4f5738484514d1b61d4a58d0daf50c29e2d9c0b3","nullifier":"0x07be5b5baf6e31b838dbe5d800db9e8300e23da17504756fb324d7e05fb8cfac"} 12:55:09 [12:55:09.976] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:09 [12:55:09.987] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:55:10 [12:55:10.180] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:10 [12:55:10.387] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:10 [12:55:10.398] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:10 [12:55:10.398] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:10 [12:55:10.507] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:10 [12:55:10.512] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x26ad1665be31cd190a0d0c85b288cd563aaa2fb352d4ff7e1c844dc51f8b721b","blockNumber":4,"slot":5} 12:55:10 [12:55:10.518] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:10 [12:55:10.521] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:10 [12:55:10.762] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:11 [12:55:11.173] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x26ad1665be31cd190a0d0c85b288cd563aaa2fb352d4ff7e1c844dc51f8b721b","blockNumber":4,"slot":5} 12:55:11 [12:55:11.422] INFO: pxe:service Proving completed in 1607.815975999998ms {"timings":{"total":1607.815975999998,"sync":8.647096000000602,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":262.61695699999837,"oracles":{"debugLog":{"times":[0.44263299999875017,0.38023900000189315,0.3712019999984477,0.48249199999918346,0.37532000000283006,0.34062400000038906,0.3268819999975676,0.4648190000007162]},"fetchTaggedLogs":{"times":[38.64017799999783]},"loadCapsule":{"times":[0.17609299999821815,0.15053199999965727,0.3297859999984212,0.2052220000005036,0.17124200000034762,0.17111600000134786]},"getSharedSecret":{"times":[2.513645999999426]},"aes128Decrypt":{"times":[0.23632999999972526,0.8420120000009774]},"getPublicKeysAndPartialAddress":{"times":[3.801939000000857]},"getKeyValidationRequest":{"times":[1.246156000001065]},"deliverNote":{"times":[2.443995999998151]},"deleteCapsule":{"times":[0.2040080000006128]},"storeCapsule":{"times":[0.19861799999853247]},"getNotes":{"times":[0.9963880000032077]},"getAuthWitness":{"times":[0.17964199999914854]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.061935000001540175]},"callPrivateFunction":{"times":[537.702652]}}},{"functionName":"private_kernel_init","time":56.348079000003054},{"functionName":"Test:set_tx_max_block_number","time":432.1686750000008,"oracles":{"debugLog":{"times":[0.4151579999997921,0.3711539999967499]},"fetchTaggedLogs":{"times":[121.38463899999988]},"loadCapsule":{"times":[0.1633640000000014,0.14563799999814364,0.17665699999997742]},"storeInExecutionCache":{"times":[0.04772400000001653]},"notifyEnqueuedPublicFunctionCall":{"times":[0.2076070000002801]}}},{"functionName":"private_kernel_inner","time":104.77757299999939},{"functionName":"private_kernel_reset","time":166.20437200000015},{"functionName":"private_kernel_tail","time":83.46972499999902}],"unaccounted":493.58349899999666}} 12:55:11 [12:55:11.423] 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 12:55:11 [12:55:11.424] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:11 [12:55:11.504] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:11 [12:55:11.537] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:11 [12:55:11.538] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:11 [12:55:11.572] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:11 [12:55:11.576] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0c5ad1431c828a4d83565f5180252cb0c210ea5b49dd5121cbd965376549e6b7 12:55:11 [12:55:11.585] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:55:11 [12:55:11.586] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:55:11 [12:55:11.591] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:55:11 [12:55:11.591] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:55:11 [12:55:11.595] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x08c5558485317ed51c37fc6a4f5738484514d1b61d4a58d0daf50c29e2d9c0b3","nullifier":"0x07be5b5baf6e31b838dbe5d800db9e8300e23da17504756fb324d7e05fb8cfac"} 12:55:11 [12:55:11.597] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:11 [12:55:11.608] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:55:11 [12:55:11.707] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:11 [12:55:12.001] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:12 [12:55:12.011] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:12 [12:55:12.012] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:12 [12:55:12.126] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:12 [12:55:12.138] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x26ad1665be31cd190a0d0c85b288cd563aaa2fb352d4ff7e1c844dc51f8b721b","blockNumber":4,"slot":5} 12:55:12 [12:55:12.145] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:12 [12:55:12.148] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:12 [12:55:12.394] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:12 [12:55:12.673] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:12 [12:55:12.815] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x26ad1665be31cd190a0d0c85b288cd563aaa2fb352d4ff7e1c844dc51f8b721b","blockNumber":4,"slot":5} 12:55:13 [12:55:13.060] INFO: pxe:service Proving completed in 1616.3820860000014ms {"timings":{"total":1616.3820860000014,"sync":2.435879999997269,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":306.05081600000267,"oracles":{"debugLog":{"times":[0.4117789999982051,0.374496000000363,0.37424900000041816,0.4635039999993751,0.4046969999981229,0.3136580000027607,0.35442800000237185,0.4144769999984419]},"fetchTaggedLogs":{"times":[36.985507000001235]},"loadCapsule":{"times":[0.1561890000011772,0.13614400000005844,0.3532500000001164,0.21087700000134646,0.14461399999709101,0.16921499999807565]},"getSharedSecret":{"times":[2.4900009999983013]},"aes128Decrypt":{"times":[0.39762899999914225,0.9966830000012123]},"getPublicKeysAndPartialAddress":{"times":[1.149810000002617]},"getKeyValidationRequest":{"times":[1.2210459999987506]},"deliverNote":{"times":[4.013967999999295]},"deleteCapsule":{"times":[0.2025370000010298]},"storeCapsule":{"times":[0.28449299999920186]},"getNotes":{"times":[1.0374359999987064]},"getAuthWitness":{"times":[0.18293400000038673]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05998199999885401]},"callPrivateFunction":{"times":[544.3940120000007]}}},{"functionName":"private_kernel_init","time":54.77521500000148},{"functionName":"Test:set_tx_max_block_number","time":390.84835100000055,"oracles":{"debugLog":{"times":[0.45121300000027986,0.3310719999972207]},"fetchTaggedLogs":{"times":[135.19916999999987]},"loadCapsule":{"times":[0.15954000000056112,0.1438479999997071,0.17495599999892875]},"storeInExecutionCache":{"times":[0.04378800000267802]},"notifyEnqueuedPublicFunctionCall":{"times":[0.047445000000152504]}}},{"functionName":"private_kernel_inner","time":104.57139100000131},{"functionName":"private_kernel_reset","time":171.1823979999972},{"functionName":"private_kernel_tail","time":82.98508700000093}],"unaccounted":503.53294800000003}} 12:55:13 [12:55:13.087] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:13 [12:55:13.110] VERBOSE: p2p:tx_pool Adding tx 0x13d246282b2e255cebc276a02b5adbb6daa0725e8fcf1ec368cc2b6c8cac236e to pool {"eventName":"tx-added-to-pool","txHash":"0x13d246282b2e255cebc276a02b5adbb6daa0725e8fcf1ec368cc2b6c8cac236e","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226281,"feePaymentMethod":"fee_juice"} 12:55:13 [12:55:13.117] INFO: node Received tx 0x13d246282b2e255cebc276a02b5adbb6daa0725e8fcf1ec368cc2b6c8cac236e {"txHash":"0x13d246282b2e255cebc276a02b5adbb6daa0725e8fcf1ec368cc2b6c8cac236e"} 12:55:13 [12:55:13.117] INFO: pxe:service Sent transaction 0x13d246282b2e255cebc276a02b5adbb6daa0725e8fcf1ec368cc2b6c8cac236e 12:55:13 [12:55:13.291] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:13 [12:55:13.329] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 12:55:13 [12:55:13.340] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-18T13:01:04.000Z","now":"2025-06-18T13:01:08.837Z"} 12:55:13 [12:55:13.376] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x5189a417 completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x5189a417","duration":17.00206700000126} 12:55:13 [12:55:13.395] VERBOSE: simulator:public-processor Processed tx 0x13d246282b2e255cebc276a02b5adbb6daa0725e8fcf1ec368cc2b6c8cac236e with 1 public calls in 45.08278599999903ms {"txHash":"0x13d246282b2e255cebc276a02b5adbb6daa0725e8fcf1ec368cc2b6c8cac236e","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":45.08278599999903} 12:55:13 [12:55:13.396] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.055483164000001126s {"duration":0.055483164000001126,"rate":62775.79988048139,"totalPublicGas":{"daGas":0,"l2Gas":3483},"totalBlockGas":{"daGas":1024,"l2Gas":29339},"totalSizeInBytes":256} 12:55:13 [12:55:13.501] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:13 [12:55:13.514] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 18800.576343732137 mana/s {"blockHash":"0x1bf730ac7363887c7e55346542f372fd0be349e7875e0f4fd0f08235314ee3b9","globalVariables":{"chainId":31337,"version":3080034620,"blockNumber":4,"slotNumber":5,"timestamp":1750251664,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x13d246282b2e255cebc276a02b5adbb6daa0725e8fcf1ec368cc2b6c8cac236e"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":184.66872199999852,"publicProcessDuration":55.89085300000079,"rollupCircuitsDuration":174.01175700000022,"txCount":1,"blockNumber":4,"blockTimestamp":1750251664,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:55:13 [12:55:13.517] VERBOSE: sequencer Attesting committee is empty 12:55:13 [12:55:13.770] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:13 [12:55:13.778] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xe4374deab8999b49602c7da50f526f6f6bf794552674c2931f830725b54ab009 12:55:13 [12:55:13.779] VERBOSE: sequencer-client Sent L1 transaction 0xe4374deab8999b49602c7da50f526f6f6bf794552674c2931f830725b54ab009 {"gasLimit":470263,"maxFeePerGas":"1.26112774","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 12:55:13 [12:55:13.787] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 12:55:13 [12:55:13.787] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xe4374deab8999b49602c7da50f526f6f6bf794552674c2931f830725b54ab009","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":4,"blockTimestamp":1750251664,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:55:13 [12:55:13.787] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 12:55:13 [12:55:13.791] INFO: blob-sink:server Received blob sidecar for block 0x5d3e449abba001e8ec2d564fef95a10e9f43e805d0bb03df1f09261d2a49853f 12:55:13 [12:55:13.794] INFO: blob-sink:server Blob sidecar stored successfully for block 0x5d3e449abba001e8ec2d564fef95a10e9f43e805d0bb03df1f09261d2a49853f 12:55:13 [12:55:13.812] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750251700] on http://127.0.0.1:8545 12:55:13 [12:55:13.813] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 12:55:13 [12:55:13.814] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750251700 12:55:13 [12:55:13.814] WARN: foundation:test-date-provider Time set to 2025-06-18T13:01:40.000Z {"offset":386186,"timeMs":1750251700000} 12:55:13 [12:55:13.815] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 12:55:13 [12:55:13.973] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:13 [12:55:13.977] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 12:55:13 [12:55:13.978] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 12:55:13 [12:55:13.980] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 12:55:14 [12:55:14.256] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:14 [12:55:14.270] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x1bf730ac7363887c7e55346542f372fd0be349e7875e0f4fd0f08235314ee3b9","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":3080034620,"blockNumber":4,"slotNumber":5,"timestamp":1750251664,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","archiveNextLeafIndex":5} 12:55:14 [12:55:14.273] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 12:55:14 [12:55:14.374] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":6.366008000000875,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1750251664,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:55:14 [12:55:14.381] VERBOSE: p2p Synched to latest block 4 12:55:14 [12:55:14.460] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:14 [12:55:14.662] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:14 [12:55:14.804] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:14 [12:55:14.864] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:15 [12:55:15.067] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:15 [12:55:15.122] 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 12:55:15 [12:55:15.137] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","header":{"contentCommitment":{"blobsHash":"0x00eebb007069991cbe1c2fb75e1a9ff6fca41624f8233668112f605e990964bb","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750251664,"version":3080034620},"lastArchive":"0x26ad1665be31cd190a0d0c85b288cd563aaa2fb352d4ff7e1c844dc51f8b721b","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2d527b915f58e66f068b64f6db62c4fdee56b374bb43ec45a7d90499395581d5","nullifierTree":"0x1ec4354d4f78a349e4892606fac7b606b4f24c2af2c843c754b51cb648f4cca5","publicDataTree":"0x2392455a39083bba8dca30fd681f2433292523443053191654efe2a2b17599bb"},"totalFees":1487487300,"totalManaUsed":29339}} 12:55:15 [12:55:15.198] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:15 [12:55:15.231] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:15 [12:55:15.232] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:15 [12:55:15.266] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:15 [12:55:15.269] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:15 [12:55:15.273] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0c5ad1431c828a4d83565f5180252cb0c210ea5b49dd5121cbd965376549e6b7 12:55:15 [12:55:15.282] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:55:15 [12:55:15.283] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:55:15 [12:55:15.293] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:55:15 [12:55:15.293] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:55:15 [12:55:15.300] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x08c5558485317ed51c37fc6a4f5738484514d1b61d4a58d0daf50c29e2d9c0b3","nullifier":"0x07be5b5baf6e31b838dbe5d800db9e8300e23da17504756fb324d7e05fb8cfac"} 12:55:15 [12:55:15.311] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:15 [12:55:15.327] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:55:15 [12:55:15.332] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:15 [12:55:15.528] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:15 [12:55:15.658] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:15 [12:55:15.669] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:15 [12:55:15.670] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:15 [12:55:15.870] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:15 [12:55:15.873] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:15 [12:55:15.878] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:15 [12:55:15.893] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:16 [12:55:16.121] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:16 [12:55:16.400] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:16 [12:55:16.536] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:16 [12:55:16.766] INFO: pxe:service Proving completed in 1633.6161030000003ms {"timings":{"total":1633.6161030000003,"sync":6.713471999999456,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":313.29757999999856,"oracles":{"debugLog":{"times":[0.41498499999943306,0.35260099999868544,0.40300500000012107,0.46956499999942025,0.39676699999836273,0.3344519999991462,0.3576180000018212,0.42261900000085006]},"fetchTaggedLogs":{"times":[36.902977000001556]},"loadCapsule":{"times":[1.9654369999989285,0.846891000001051,0.9350679999988643,0.889763999999559,3.7821879999974044,1.567017000001215]},"getSharedSecret":{"times":[2.584736000000703]},"aes128Decrypt":{"times":[0.23670299999866984,0.8453110000009474]},"getPublicKeysAndPartialAddress":{"times":[4.36290100000042]},"getKeyValidationRequest":{"times":[2.9942100000007486]},"deliverNote":{"times":[7.213949000000866]},"deleteCapsule":{"times":[2.7674709999992047]},"storeCapsule":{"times":[2.0815609999990556]},"getNotes":{"times":[4.74185899999793]},"getAuthWitness":{"times":[0.19913900000028661]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05851500000062515]},"callPrivateFunction":{"times":[554.3096030000015]}}},{"functionName":"private_kernel_init","time":54.46778799999811},{"functionName":"Test:set_tx_max_block_number","time":420.6315089999989,"oracles":{"debugLog":{"times":[0.47666300000128103,0.342490999999427]},"fetchTaggedLogs":{"times":[206.3520329999992]},"loadCapsule":{"times":[0.824823999999353,0.32351899999775924,0.2782289999995555]}}},{"functionName":"private_kernel_inner","time":104.4037759999992},{"functionName":"private_kernel_reset","time":168.87665800000104},{"functionName":"private_kernel_tail","time":68.76915400000144}],"unaccounted":496.4561660000036}} 12:55:16 [12:55:16.768] 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 12:55:16 [12:55:16.768] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:16 [12:55:16.846] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:16 [12:55:16.880] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:16 [12:55:16.880] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:16 [12:55:16.913] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:16 [12:55:16.917] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0c5ad1431c828a4d83565f5180252cb0c210ea5b49dd5121cbd965376549e6b7 12:55:16 [12:55:16.925] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:55:16 [12:55:16.926] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:55:16 [12:55:16.930] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:55:16 [12:55:16.931] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:55:16 [12:55:16.934] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x08c5558485317ed51c37fc6a4f5738484514d1b61d4a58d0daf50c29e2d9c0b3","nullifier":"0x07be5b5baf6e31b838dbe5d800db9e8300e23da17504756fb324d7e05fb8cfac"} 12:55:16 [12:55:16.936] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:16 [12:55:16.946] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:55:17 [12:55:17.046] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:17 [12:55:17.247] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:17 [12:55:17.258] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:17 [12:55:17.258] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:17 [12:55:17.364] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:17 [12:55:17.375] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:17 [12:55:17.389] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:17 [12:55:17.396] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:17 [12:55:17.597] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:17 [12:55:17.979] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:18 [12:55:18.119] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:18 [12:55:18.348] INFO: pxe:service Proving completed in 1561.5273610000004ms {"timings":{"total":1561.5273610000004,"sync":2.4236869999986084,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":284.2888869999988,"oracles":{"debugLog":{"times":[0.4112710000008519,0.36177200000020093,0.34315699999933713,0.44472399999722256,0.3475719999987632,0.3004209999999148,0.2867389999992156,0.3922679999996035]},"fetchTaggedLogs":{"times":[35.13807699999961]},"loadCapsule":{"times":[0.15222500000163564,0.14087500000096043,0.28906500000084634,0.16330699999889475,0.12863900000229478,0.14677500000107102]},"getSharedSecret":{"times":[2.3467689999997674]},"aes128Decrypt":{"times":[0.17722999999750755,0.786234000002878]},"getPublicKeysAndPartialAddress":{"times":[1.0767919999998412]},"getKeyValidationRequest":{"times":[1.0786999999982072]},"deliverNote":{"times":[3.609754000000976]},"deleteCapsule":{"times":[0.19218000000182656]},"storeCapsule":{"times":[0.20215599999937695]},"getNotes":{"times":[0.9490760000007867]},"getAuthWitness":{"times":[0.18185299999822746]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.059135999999853084]},"callPrivateFunction":{"times":[454.4252740000011]}}},{"functionName":"private_kernel_init","time":54.99326700000165},{"functionName":"Test:set_tx_max_block_number","time":348.0124990000004,"oracles":{"debugLog":{"times":[0.40649600000324426,0.3654209999986051]},"fetchTaggedLogs":{"times":[136.36460100000113]},"loadCapsule":{"times":[0.29729999999835854,0.3603610000027402,0.22303800000008778]}}},{"functionName":"private_kernel_inner","time":104.15527199999997},{"functionName":"private_kernel_reset","time":168.36905899999692},{"functionName":"private_kernel_tail","time":68.8772710000012}],"unaccounted":530.4074190000028}} 12:55:18 [12:55:18.360] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:18 [12:55:18.376] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x1c5fd2e195a9dba00050dea9822fa9a60f80b0fcbcc8e601f969b9af5fb9fddd for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 12:55:18 [12:55:18.379] WARN: node Received invalid tx 0x1c5fd2e195a9dba00050dea9822fa9a60f80b0fcbcc8e601f969b9af5fb9fddd: Invalid max block number {"txHash":"0x1c5fd2e195a9dba00050dea9822fa9a60f80b0fcbcc8e601f969b9af5fb9fddd"} 12:55:18 [12:55:18.384] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 12:55:18 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 12:55:18 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 12:55:18 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 12:55:18 [12:55:18.385] 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 12:55:18 [12:55:18.457] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:18 [12:55:18.491] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:18 [12:55:18.491] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:18 [12:55:18.525] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:18 [12:55:18.528] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0c5ad1431c828a4d83565f5180252cb0c210ea5b49dd5121cbd965376549e6b7 12:55:18 [12:55:18.537] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:55:18 [12:55:18.538] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:55:18 [12:55:18.542] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:55:18 [12:55:18.543] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:55:18 [12:55:18.547] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x08c5558485317ed51c37fc6a4f5738484514d1b61d4a58d0daf50c29e2d9c0b3","nullifier":"0x07be5b5baf6e31b838dbe5d800db9e8300e23da17504756fb324d7e05fb8cfac"} 12:55:18 [12:55:18.548] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:18 [12:55:18.560] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:55:18 [12:55:18.661] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:18 [12:55:18.866] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:18 [12:55:18.877] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:18 [12:55:18.877] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:18 [12:55:18.986] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:18 [12:55:18.997] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:19 [12:55:19.004] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:19 [12:55:19.007] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:19 [12:55:19.250] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:19 [12:55:19.626] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:19 [12:55:19.761] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:20 [12:55:20.004] INFO: pxe:service Proving completed in 1608.4327559999983ms {"timings":{"total":1608.4327559999983,"sync":2.5686189999978524,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":259.43649099999675,"oracles":{"debugLog":{"times":[0.40575600000011036,0.36904300000242074,0.3619350000008126,0.4670899999982794,0.40507500000239816,0.3025359999992361,0.34543699999994715,0.4205120000005991]},"fetchTaggedLogs":{"times":[35.830385000001115]},"loadCapsule":{"times":[0.15701400000034482,0.14123400000244146,0.3164440000000468,0.19481299999824842,0.15930200000002515,0.17393000000083703]},"getSharedSecret":{"times":[2.4388159999980417]},"aes128Decrypt":{"times":[0.18571199999860255,0.7873789999975997]},"getPublicKeysAndPartialAddress":{"times":[1.1114059999999881]},"getKeyValidationRequest":{"times":[1.1688719999983732]},"deliverNote":{"times":[4.110586999999214]},"deleteCapsule":{"times":[0.19625900000028196]},"storeCapsule":{"times":[0.2043939999966824]},"getNotes":{"times":[1.4762960000007297]},"getAuthWitness":{"times":[0.18511000000216882]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.22485199999937322]},"callPrivateFunction":{"times":[450.734547]}}},{"functionName":"private_kernel_init","time":59.61263500000132},{"functionName":"Test:set_tx_max_block_number","time":344.5102820000029,"oracles":{"debugLog":{"times":[0.40499000000272645,0.32977800000298885]},"fetchTaggedLogs":{"times":[128.8135059999986]},"loadCapsule":{"times":[0.1553459999995539,0.15389499999946565,0.17234299999836367]},"storeInExecutionCache":{"times":[0.0434739999982412]},"notifyEnqueuedPublicFunctionCall":{"times":[0.04325500000049942]}}},{"functionName":"private_kernel_inner","time":104.7191419999981},{"functionName":"private_kernel_reset","time":169.59393400000044},{"functionName":"private_kernel_tail","time":82.69475199999943}],"unaccounted":585.2969010000015}} 12:55:20 [12:55:20.006] 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 12:55:20 [12:55:20.008] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:20 [12:55:20.088] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:20 [12:55:20.121] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:20 [12:55:20.122] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:20 [12:55:20.155] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618"} 12:55:20 [12:55:20.159] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0c5ad1431c828a4d83565f5180252cb0c210ea5b49dd5121cbd965376549e6b7 12:55:20 [12:55:20.168] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:55:20 [12:55:20.168] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:55:20 [12:55:20.175] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:55:20 [12:55:20.176] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:55:20 [12:55:20.178] VERBOSE: pxe:service Added note {"index":0,"contract":"0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x08c5558485317ed51c37fc6a4f5738484514d1b61d4a58d0daf50c29e2d9c0b3","nullifier":"0x07be5b5baf6e31b838dbe5d800db9e8300e23da17504756fb324d7e05fb8cfac"} 12:55:20 [12:55:20.180] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:20 [12:55:20.191] VERBOSE: simulator:client_execution_context:debug_log Setting 0x140c894a71b3635442b18a73e26de859eb59c4373bfdf28d145e3a395cd09618 as fee payer 12:55:20 [12:55:20.294] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:20 [12:55:20.495] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:20 [12:55:20.505] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:55:20 [12:55:20.506] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:20 [12:55:20.613] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:20 [12:55:20.621] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:20 [12:55:20.630] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x04047f6839b6e9dd146a717fbad87418a6e1f0bbf270812ef0284dae8f645323"} 12:55:20 [12:55:20.635] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:55:20 [12:55:20.877] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:21 [12:55:21.289] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x11a06b0b7501c9e9a6ad0f32723ba95538ac32e091e54a8a3e9b0b89c6c13b8b","blockNumber":5,"slot":6} 12:55:21 [12:55:21.533] INFO: pxe:service Proving completed in 1506.3845130000009ms {"timings":{"total":1506.3845130000009,"sync":2.514718999998877,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":259.5355520000012,"oracles":{"debugLog":{"times":[0.43183700000008685,0.3572890000032203,0.4254030000010971,0.47798300000067684,0.37710900000092806,0.30974000000060187,0.28286800000205403,0.4122529999986]},"fetchTaggedLogs":{"times":[35.60661700000128]},"loadCapsule":{"times":[0.16290200000003097,0.13806999999724212,0.31698600000163424,0.17391199999838136,0.16749499999787076,0.1641300000010233]},"getSharedSecret":{"times":[2.6951399999998102]},"aes128Decrypt":{"times":[0.19044099999882746,0.8047569999980624]},"getPublicKeysAndPartialAddress":{"times":[1.0965389999983017]},"getKeyValidationRequest":{"times":[3.1461829999971087]},"deliverNote":{"times":[2.628334000000905]},"deleteCapsule":{"times":[0.20657199999914155]},"storeCapsule":{"times":[0.29948099999819533]},"getNotes":{"times":[1.2661200000002282]},"getAuthWitness":{"times":[0.23726999999780674]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07558799999969779]},"callPrivateFunction":{"times":[448.01984500000253]}}},{"functionName":"private_kernel_init","time":57.72048199999699},{"functionName":"Test:set_tx_max_block_number","time":342.3629929999988,"oracles":{"debugLog":{"times":[0.4052129999981844,0.32583100000192644]},"fetchTaggedLogs":{"times":[128.2237550000027]},"loadCapsule":{"times":[0.15267400000084308,0.15450199999759207,0.15412300000025425]},"storeInExecutionCache":{"times":[0.04515399999945657]},"notifyEnqueuedPublicFunctionCall":{"times":[0.04308099999980186]}}},{"functionName":"private_kernel_inner","time":104.55058000000281},{"functionName":"private_kernel_reset","time":168.7151640000011},{"functionName":"private_kernel_tail","time":82.85172199999943}],"unaccounted":488.13330100000167}} 12:55:21 [12:55:21.559] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:55:21 [12:55:21.574] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x09e50044ba7d53383b3db3fe723cdfbba3e7d2c78fbf39243b5ed9b11466096b for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 12:55:21 [12:55:21.577] WARN: node Received invalid tx 0x09e50044ba7d53383b3db3fe723cdfbba3e7d2c78fbf39243b5ed9b11466096b: Invalid max block number {"txHash":"0x09e50044ba7d53383b3db3fe723cdfbba3e7d2c78fbf39243b5ed9b11466096b"} 12:55:21 [12:55:21.579] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 12:55:21 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 12:55:21 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 12:55:21 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 12:55:21 [12:55:21.581] INFO: node Stopping 12:55:21 [12:55:21.582] INFO: slasher Slasher client stopped. 12:55:21 [12:55:21.582] INFO: sequencer Stopped sequencer 12:55:21 [12:55:21.582] INFO: p2p P2P client stopped. 12:55:21 [12:55:21.585] INFO: world_state Stopped world state synchronizer 12:55:21 [12:55:21.585] INFO: archiver Stopped. 12:55:21 [12:55:21.585] INFO: node Stopped 12:55:21 [12:55:21.585] VERBOSE: e2e:e2e_max_block_number Cleaning up ACVM state 12:55:21 [12:55:21.586] INFO: e2e:e2e_max_block_number Cleaning up ACVM temp directory /tmp/2d90b13d 12:55:21 [12:55:21.586] VERBOSE: e2e:e2e_max_block_number Cleaning up BB state 12:55:21 [12:55:21.594] INFO: blob-sink:server Stopping blob sink 12:55:21 [12:55:21.595] INFO: blob-sink:server Blob sink stopped 12:55:21 [12:55:21.595] VERBOSE: e2e:e2e_max_block_number Cleaning up data directory at /tmp/65ed0a767c40e665 12:55:21 PASS src/e2e_max_block_number.test.ts 12:55:21 e2e_max_block_number 12:55:21 when requesting max block numbers higher than the mined one 12:55:21 with no enqueued public calls 12:55:21 ✓ sets the max block number (1692 ms) 12:55:21 ✓ does not invalidate the transaction (3675 ms) 12:55:21 with an enqueued public call 12:55:21 ✓ sets the max block number (1619 ms) 12:55:21 ✓ does not invalidate the transaction (3698 ms) 12:55:21 when requesting max block numbers lower than the mined one 12:55:21 with no enqueued public calls 12:55:21 ✓ sets the max block number (1645 ms) 12:55:21 ✓ invalidates the transaction (1617 ms) 12:55:21 with an enqueued public call 12:55:21 ✓ sets the max block number (1621 ms) 12:55:21 ✓ invalidates the transaction (1574 ms) 12:55:21 12:55:21 Test Suites: 1 passed, 1 total 12:55:21 Tests: 8 passed, 8 total 12:55:21 Snapshots: 0 total 12:55:21 Time: 33.286 s 12:55:21 Ran all test suites matching /src\/e2e_max_block_number.test.ts/i. 12:55:21 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?