Parent Log: http://ci.aztec-labs.com/0037d898807e86c0 Command: 4bfd2d3585b9db66: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/267ac35e8ccb487bdc79bbe12d428f4c8b4d4d9f Env: REF_NAME=gh-readonly-queue/master/pr-15002-1338cef09c870378254c56748f972ae827c4d482 CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Fri Jun 13 14:53:51 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15002_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_d488fdfe97c9ae48_master 14:53:51 +++ id -u 14:53:51 +++ id -g 14:53:51 ++ docker run -d --name e2e_max_block_number --net=none --cpuset-cpus=0-127 --cpus=2 --memory=8g --user 1000:1000 -v/home/aztec-dev:/home/aztec-dev --mount type=tmpfs,target=/tmp,tmpfs-size=1g --workdir /home/aztec-dev/aztec-packages -e HOME -e VERBOSE -e GIT_CONFIG_GLOBAL=/home/aztec-dev/aztec-packages/build-images/src/home/.gitconfig -e FORCE_COLOR=true -e CPUS -e MEM aztecprotocol/build:3.0 /bin/bash -c 'timeout -v 600s bash -c '\''yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_max_block_number.test.ts'\''' 14:53:52 + cid=ba0d0abedab2b3359304175d0f2380bcf85a9d73f80db5c6b6e0e8d04ff6593e 14:53:52 + set +x 14:53:52 {"level":25,"time":1749826432916,"pid":12,"hostname":"ba0d0abedab2","module":"logger","msg":"Logger initialized with level verbose"} 14:53:59 [14:53:59.361] INFO: e2e:e2e_max_block_number Logging metrics to log/unknown.jsonl 14:53:59 [14:53:59.642] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-2QZmoN/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 14:53:59 [14:53:59.681] INFO: e2e:e2e_max_block_number Calling anvil_setBlockTimestampInterval with params: [12] 14:53:59 [14:53:59.708] WARN: e2e:e2e_max_block_number Set block interval to 12 14:53:59 [14:53:59.709] VERBOSE: e2e:e2e_max_block_number Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 14:53:59 [14:53:59.759] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 14:53:59 [14:53:59.767] VERBOSE: e2e:e2e_max_block_number Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 14:53:59 [14:53:59.782] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 14:53:59 [14:53:59.788] VERBOSE: e2e:e2e_max_block_number Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 14:53:59 [14:53:59.808] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 14:53:59 [14:53:59.814] VERBOSE: e2e:e2e_max_block_number Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 14:53:59 [14:53:59.833] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 14:53:59 [14:53:59.838] VERBOSE: e2e:e2e_max_block_number Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 14:53:59 [14:53:59.868] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 14:53:59 [14:53:59.886] VERBOSE: e2e:e2e_max_block_number Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 14:53:59 [14:53:59.905] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 14:53:59 [14:53:59.911] VERBOSE: e2e:e2e_max_block_number Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 14:53:59 [14:53:59.912] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 14:53:59 [14:53:59.912] INFO: e2e:e2e_max_block_number Waiting for 6 transactions to be mined... 14:53:59 [14:53:59.939] INFO: e2e:e2e_max_block_number All transactions mined successfully 14:53:59 [14:53:59.962] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 14:53:59 [14:53:59.962] VERBOSE: e2e:e2e_max_block_number Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 14:53:59 [14:53:59.988] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 14:54:00 [14:54:00.010] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 14:54:00 [14:54:00.016] VERBOSE: e2e:e2e_max_block_number Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 14:54:00 [14:54:00.033] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 14:54:00 [14:54:00.034] VERBOSE: e2e:e2e_max_block_number Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 14:54:00 [14:54:00.059] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 14:54:00 [14:54:00.069] VERBOSE: e2e:e2e_max_block_number Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 14:54:00 [14:54:00.086] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 14:54:00 [14:54:00.087] VERBOSE: e2e:e2e_max_block_number Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 14:54:00 [14:54:00.087] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 14:54:00 [14:54:00.088] INFO: e2e:e2e_max_block_number Waiting for 8 transactions to be mined... 14:54:00 [14:54:00.112] INFO: e2e:e2e_max_block_number All transactions mined successfully 14:54:00 [14:54:00.125] VERBOSE: e2e:e2e_max_block_number Deployed shared contracts 14:54:00 [14:54:00.149] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 14:54:00 [14:54:00.149] VERBOSE: e2e:e2e_max_block_number Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 14:54:00 [14:54:00.154] 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} 14:54:00 [14:54:00.179] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 14:54:00 [14:54:00.212] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 14:54:00 [14:54:00.219] VERBOSE: e2e:e2e_max_block_number Awaiting for linked libraries to be deployed 14:54:00 [14:54:00.265] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xdb49291e64a6cc607cdd72c4c0a6da2398a7896a53bf58816d2283027e382ac8 {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 14:54:00 [14:54:00.271] 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} 14:54:00 [14:54:00.271] INFO: e2e:e2e_max_block_number Waiting for 9 transactions to be mined... 14:54:00 [14:54:00.289] INFO: e2e:e2e_max_block_number All transactions mined successfully 14:54:00 [14:54:00.290] VERBOSE: e2e:e2e_max_block_number All core contracts have been deployed 14:54:00 [14:54:00.310] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 14:54:00 [14:54:00.311] VERBOSE: e2e:e2e_max_block_number Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 14:54:00 [14:54:00.328] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 14:54:00 [14:54:00.334] VERBOSE: e2e:e2e_max_block_number Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 14:54:00 [14:54:00.360] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 14:54:00 [14:54:00.360] VERBOSE: e2e:e2e_max_block_number Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 14:54:00 [14:54:00.361] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 14:54:00 [14:54:00.386] INFO: e2e:e2e_max_block_number All transactions mined successfully 14:54:00 [14:54:00.400] VERBOSE: e2e:e2e_max_block_number Rollup deployed 14:54:00 [14:54:00.401] VERBOSE: e2e:e2e_max_block_number Waiting for rollup and slash factory to be deployed 14:54:00 [14:54:00.401] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 14:54:00 [14:54:00.427] INFO: e2e:e2e_max_block_number All transactions mined successfully 14:54:00 [14:54:00.427] VERBOSE: e2e:e2e_max_block_number All transactions for L1 deployment have been mined 14:54:00 [14:54:00.497] 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"} 14:54:00 [14:54:00.511] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 14:54:00 [14:54:00.515] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:00 [14:54:00.516] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 14:54:00 [14:54:00.518] INFO: telemetry:client Using NoopTelemetryClient 14:54:00 [14:54:00.527] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 14:54:00 [14:54:00.527] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 14:54:00 [14:54:00.528] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/2884ef46cff5f2bd/blob-sink with map size 134217728 KB (LMDB v2) 14:54:00 [14:54:00.531] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:00 [14:54:00.534] INFO: aztecjs:utils:watcher New L1 time: 1749826679 14:54:00 [14:54:00.535] WARN: foundation:test-date-provider Time set to 2025-06-13T14:57:59.000Z {"offset":238465,"timeMs":1749826679000} 14:54:00 [14:54:00.538] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 14:54:00 [14:54:00.547] INFO: blob-sink:server Server is running on http://localhost:44505 14:54:00 [14:54:00.548] VERBOSE: e2e:e2e_max_block_number Creating and synching an aztec node... 14:54:00 [14:54:00.549] VERBOSE: e2e:e2e_max_block_number Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/dbea358f/acvm 14:54:00 [14:54:00.550] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:44505"} 14:54:00 [14:54:00.567] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 14:54:00 [14:54:00.568] INFO: archiver:lmdb Creating archiver data store at directory /tmp/2884ef46cff5f2bd/archiver with map size 134217728 KB (LMDB v2) 14:54:00 [14:54:00.569] INFO: archiver:lmdb Starting data store with maxReaders 16 14:54:00 [14:54:00.805] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:00 [14:54:00.926] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:44505"} 14:54:00 [14:54:00.947] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:44505"} 14:54:00 [14:54:00.948] WARN: node:blob-sink:client No L1 consensus host urls configured 14:54:00 [14:54:00.948] WARN: node:blob-sink:client No archive client configured 14:54:00 [14:54:00.952] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x09baaa2c8c59f57b02b5189cca1a5b6b927559d4675fb2abdeed9ef5512f4976 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x8289e987606ce76ef5ac83039e4fb635058ca8b248172820f620f83d010dc5c9","l1BlockNumber":16}} 14:54:00 [14:54:00.980] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0x09baaa2c8c59f57b02b5189cca1a5b6b927559d4675fb2abdeed9ef5512f4976","l1BlockNumber":20}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 14:54:01 [14:54:01.001] INFO: world-state:database Creating world state data store at directory /tmp/2884ef46cff5f2bd/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 14:54:01 [14:54:01.027] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:01 [14:54:01.035] INFO: world_state Created world state synchroniser with block history of 64 14:54:01 [14:54:01.035] WARN: node Aztec node is accepting fake proofs 14:54:01 [14:54:01.053] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/2884ef46cff5f2bd/p2p with map size 134217728 KB (LMDB v2) 14:54:01 [14:54:01.063] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 14:54:01 [14:54:01.065] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/2884ef46cff5f2bd/p2p-archive with map size 134217728 KB (LMDB v2) 14:54:01 [14:54:01.067] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 14:54:01 [14:54:01.069] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/2884ef46cff5f2bd/p2p-peers with map size 134217728 KB (LMDB v2) 14:54:01 [14:54:01.071] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 14:54:01 [14:54:01.072] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 14:54:01 [14:54:01.073] VERBOSE: p2p P2P is disabled. Using dummy P2P service 14:54:01 [14:54:01.077] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 14:54:01 [14:54:01.078] INFO: world_state Started world state synchronizer from block 1 14:54:01 [14:54:01.081] VERBOSE: world-state:block_stream Starting sync from block number 0 14:54:01 [14:54:01.083] INFO: p2p Starting p2p client from block 0 with empty mempools 14:54:01 [14:54:01.083] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 14:54:01 [14:54:01.085] INFO: slasher Slasher client initialized 14:54:01 [14:54:01.085] INFO: slasher Starting Slasher client... 14:54:01 [14:54:01.138] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 14:54:01 [14:54:01.139] VERBOSE: node All Aztec Node subsystems synced 14:54:01 [14:54:01.150] INFO: sequencer-client Deploying forwarder contract 14:54:01 [14:54:01.158] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 14:54:01 [14:54:01.174] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 14:54:01 [14:54:01.174] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 14:54:01 [14:54:01.180] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 14:54:01 [14:54:01.198] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 14:54:01 [14:54:01.198] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 14:54:01 [14:54:01.199] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/dbea358f/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 14:54:01 [14:54:01.435] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 14:54:01 [14:54:01.436] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 14:54:01 [14:54:01.437] INFO: node Aztec Node version: 0.87.8 14:54:01 [14:54:01.437] 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"} 14:54:01 [14:54:01.438] VERBOSE: e2e:e2e_max_block_number Creating a pxe... 14:54:01 [14:54:01.440] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:01 [14:54:01.450] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/96d74eacbf817ad2/pxe_data with map size 134217728 KB (LMDB v2) 14:54:01 [14:54:01.463] INFO: pxe:data:lmdb Starting data store with maxReaders 16 14:54:01 [14:54:01.468] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1f6f354ca1f23386fd9792cd2d842fe12fa234e29e9addcfe8027bacdbde60ac","blockNumber":1,"slot":2} 14:54:01 [14:54:01.660] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:01 [14:54:01.749] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 14:54:01 [14:54:01.750] INFO: pxe:service Started PXE connected to chain 31337 version 4058855014 14:54:01 [14:54:01.812] INFO: pxe:service Registered account 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 14:54:01 [14:54:01.883] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:01 [14:54:01.885] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 14:54:01 [14:54:01.887] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 14:54:02 [14:54:02.018] INFO: pxe:service Added contract SchnorrAccount at 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 14:54:02 [14:54:02.028] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1f6f354ca1f23386fd9792cd2d842fe12fa234e29e9addcfe8027bacdbde60ac","blockNumber":1,"slot":2} 14:54:02 [14:54:02.072] VERBOSE: pxe:block_stream Starting sync from block number 0 14:54:02 [14:54:02.093] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:02 [14:54:02.106] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 14:54:02 [14:54:02.215] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 14:54:02 [14:54:02.891] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 14:54:02 [14:54:02.993] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 14:54:03 [14:54:03.094] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:03 [14:54:03.211] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1f6f354ca1f23386fd9792cd2d842fe12fa234e29e9addcfe8027bacdbde60ac","blockNumber":1,"slot":2} 14:54:03 [14:54:03.239] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:03 [14:54:03.280] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:03 [14:54:03.281] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:03 [14:54:03.319] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:03 [14:54:03.349] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:03 [14:54:03.353] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:03 [14:54:03.540] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:03 [14:54:03.592] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:03 [14:54:03.592] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:03 [14:54:03.593] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:03 [14:54:03.643] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:03 [14:54:03.647] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:03 [14:54:03.670] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:03 [14:54:03.746] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1f6f354ca1f23386fd9792cd2d842fe12fa234e29e9addcfe8027bacdbde60ac","blockNumber":1,"slot":2} 14:54:03 [14:54:03.884] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:04 [14:54:04.129] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:04 [14:54:04.359] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:04 [14:54:04.446] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1f6f354ca1f23386fd9792cd2d842fe12fa234e29e9addcfe8027bacdbde60ac","blockNumber":1,"slot":2} 14:54:04 [14:54:04.635] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:05 [14:54:05.158] INFO: pxe:service Proving completed in 3089.466483ms {"timings":{"total":3089.466483,"sync":3.746200999999928,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":172.98932500000046,"oracles":{"callPrivateFunction":{"times":[934.7927780000009,362.71587399999953,241.97632599999997]}}},{"functionName":"private_kernel_init","time":126.94937899999968},{"functionName":"ContractClassRegisterer:register","time":911.0639780000001,"oracles":{"loadCapsule":{"times":[21.10962499999914]},"notifyCreatedNullifier":{"times":[14.743089999999938]},"debugLog":{"times":[1.1302579999992304]},"notifyCreatedContractClassLog":{"times":[10.96995699999934]}}},{"functionName":"private_kernel_inner","time":153.58278900000005},{"functionName":"SchnorrAccount:constructor","time":326.3565060000001,"oracles":{"getContractInstance":{"times":[1.2281200000015815]},"debugLog":{"times":[0.9482729999999719,0.5295619999997143]},"fetchTaggedLogs":{"times":[70.94598199999928]},"loadCapsule":{"times":[0.4395309999999881,0.2710559999995894,0.3028470000008383]},"notifyCreatedNullifier":{"times":[0.3199979999990319,0.23133599999891885]},"notifyCreatedNote":{"times":[0.6266250000007858]},"getRandomField":{"times":[0.5108920000002399,0.239716000000044,0.14027299999906973,0.0724819999995816,0.08046199999989767,0.05885200000011537,0.04893099999935657,0.05135100000006787,0.08055200000126206,0.15849399999933667,0.12308300000040617]},"getIndexedTaggingSecretAsSender":{"times":[32.347279999999955]},"incrementAppTaggingSecretIndexAsSender":{"times":[22.626302999999098]}}},{"functionName":"private_kernel_inner","time":160.02270599999974},{"functionName":"SchnorrAccount:entrypoint","time":207.78199099999983,"oracles":{"debugLog":{"times":[0.7530679999999847,0.6798859999998967,0.9068509999997332]},"fetchTaggedLogs":{"times":[52.72003699999914]},"loadCapsule":{"times":[0.2597459999997227,0.24273599999833095,0.3860899999999674]},"getNotes":{"times":[2.3961180000005697]},"getAuthWitness":{"times":[0.47285200000078476]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.361522999999579]}}},{"functionName":"private_kernel_inner","time":160.55273900000066},{"functionName":"private_kernel_reset","time":208.72302499999932},{"functionName":"private_kernel_tail","time":69.60651899999903}],"unaccounted":588.0913250000012}} 14:54:05 [14:54:05.176] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:05 [14:54:05.246] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1f6f354ca1f23386fd9792cd2d842fe12fa234e29e9addcfe8027bacdbde60ac","blockNumber":1,"slot":2} 14:54:05 [14:54:05.327] VERBOSE: p2p:tx_pool Adding tx 0x204c5986e4443f333ecfd43ebd501c23a5c980fd1445ac6a0a9d03f7a53fe740 to pool {"eventName":"tx-added-to-pool","txHash":"0x204c5986e4443f333ecfd43ebd501c23a5c980fd1445ac6a0a9d03f7a53fe740","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 14:54:05 [14:54:05.344] INFO: node Received tx 0x204c5986e4443f333ecfd43ebd501c23a5c980fd1445ac6a0a9d03f7a53fe740 {"txHash":"0x204c5986e4443f333ecfd43ebd501c23a5c980fd1445ac6a0a9d03f7a53fe740"} 14:54:05 [14:54:05.344] INFO: pxe:service Sent transaction 0x204c5986e4443f333ecfd43ebd501c23a5c980fd1445ac6a0a9d03f7a53fe740 14:54:05 [14:54:05.384] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:05 [14:54:05.592] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:05 [14:54:05.765] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 14:54:05 [14:54:05.780] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-13T14:58:23.000Z","now":"2025-06-13T14:58:04.245Z"} 14:54:05 [14:54:05.800] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:06 [14:54:06.031] VERBOSE: simulator:public-processor Processed tx 0x204c5986e4443f333ecfd43ebd501c23a5c980fd1445ac6a0a9d03f7a53fe740 with no public calls in 156.02434899999935ms {"txHash":"0x204c5986e4443f333ecfd43ebd501c23a5c980fd1445ac6a0a9d03f7a53fe740","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.02434899999935} 14:54:06 [14:54:06.033] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.25161974200000076s {"duration":0.25161974200000076,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 14:54:06 [14:54:06.137] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:06 [14:54:06.152] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1c003986f19579ec9b91b38c781b41366ed84317f8f924a9f9fd2fc7254bce27","globalVariables":{"chainId":31337,"version":4058855014,"blockNumber":1,"slotNumber":2,"timestamp":1749826703,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x204c5986e4443f333ecfd43ebd501c23a5c980fd1445ac6a0a9d03f7a53fe740"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":387.6582729999991,"publicProcessDuration":252.58461499999976,"rollupCircuitsDuration":373.97346800000014,"txCount":1,"blockNumber":1,"blockTimestamp":1749826703,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 14:54:06 [14:54:06.155] VERBOSE: sequencer Attesting committee is empty 14:54:06 [14:54:06.344] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:06 [14:54:06.479] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x4d7fcf7f84a451cd620d9f01b7a695fce9e9377b420c7830ed7ee198ab879627 14:54:06 [14:54:06.480] VERBOSE: sequencer-client Sent L1 transaction 0x4d7fcf7f84a451cd620d9f01b7a695fce9e9377b420c7830ed7ee198ab879627 {"gasLimit":514599,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 14:54:06 [14:54:06.493] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 14:54:06 [14:54:06.494] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x4d7fcf7f84a451cd620d9f01b7a695fce9e9377b420c7830ed7ee198ab879627","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":1,"blockTimestamp":1749826703,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 14:54:06 [14:54:06.495] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 14:54:06 [14:54:06.511] INFO: blob-sink:server Received blob sidecar for block 0x555133357c935a17d58332d7a5ea9402a80f37bc4bacd7af502df1ff8591aca1 14:54:06 [14:54:06.517] INFO: blob-sink:server Blob sidecar stored successfully for block 0x555133357c935a17d58332d7a5ea9402a80f37bc4bacd7af502df1ff8591aca1 14:54:06 [14:54:06.548] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:06 [14:54:06.555] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 14:54:06 [14:54:06.556] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 14:54:06 [14:54:06.557] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 14:54:06 [14:54:06.566] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749826739] on http://127.0.0.1:8545 14:54:06 [14:54:06.567] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 14:54:06 [14:54:06.568] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749826739 14:54:06 [14:54:06.569] WARN: foundation:test-date-provider Time set to 2025-06-13T14:58:59.000Z {"offset":292431,"timeMs":1749826739000} 14:54:06 [14:54:06.569] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 14:54:06 [14:54:06.754] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:06 [14:54:06.888] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 14:54:06 [14:54:06.893] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x1c003986f19579ec9b91b38c781b41366ed84317f8f924a9f9fd2fc7254bce27","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":4058855014,"blockNumber":1,"slotNumber":2,"timestamp":1749826703,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x0c3a0830c804cea476ac887c70eecc65c692f6fb71dd18ab63c75601036e1eb2","archiveNextLeafIndex":2} 14:54:06 [14:54:06.898] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 14:54:06 [14:54:06.957] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:07 [14:54:07.395] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:07 [14:54:07.398] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":410.61513299999933,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749826703,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 14:54:07 [14:54:07.399] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 14:54:07 [14:54:07.402] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306 14:54:07 [14:54:07.604] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:07 [14:54:07.678] VERBOSE: p2p Synched to latest block 1 14:54:08 [14:54:08.002] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:08 [14:54:08.016] INFO: pxe:service Added contract Test at 0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306 with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 14:54:08 [14:54:08.042] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x0c3a0830c804cea476ac887c70eecc65c692f6fb71dd18ab63c75601036e1eb2","header":{"contentCommitment":{"blobsHash":"0x00d101809776b6b4233a68e3abfb9efbdd0101dd24149f0f1bf6e23d5b075176","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749826703,"version":4058855014},"lastArchive":"0x1f6f354ca1f23386fd9792cd2d842fe12fa234e29e9addcfe8027bacdbde60ac","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x28f37c56bbf1e997d48ac8adbb9b14192eeeec17300b518e66a47ee72196d9cc","nullifierTree":"0x2b78631b1d9740c44fa579d63c7bf90ff6c5cd631fe7a3d750e98ab5517614ab","publicDataTree":"0x19cf0eaf8d08f98b9fe6e0260dd7f6e251d12dcfab789a5442689f1a7736a6ca"},"totalFees":6794052480,"totalManaUsed":20448}} 14:54:08 [14:54:08.131] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:08 [14:54:08.184] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:08 [14:54:08.185] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:08 [14:54:08.212] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:08 [14:54:08.245] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0c3a0830c804cea476ac887c70eecc65c692f6fb71dd18ab63c75601036e1eb2","blockNumber":2,"slot":3} 14:54:08 [14:54:08.278] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:08 [14:54:08.283] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0547051037ab51889410cc5e141a989e506be446ed2759fe1b0e081ffdfef394 14:54:08 [14:54:08.294] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:54:08 [14:54:08.295] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:54:08 [14:54:08.301] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:54:08 [14:54:08.302] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:54:08 [14:54:08.315] VERBOSE: pxe:service Added note {"index":0,"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x23d2403b49259694b6bf1d29b8c08e3219bf1f543649f2599aa2620fbe5f1c7b","nullifier":"0x1bc9f35d64f5b6cca6445c1a642cbd9699369727d1da9d50c654d8f0a6d08c88"} 14:54:08 [14:54:08.318] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:08 [14:54:08.338] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:08 [14:54:08.378] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 14:54:08 [14:54:08.848] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 14:54:08 [14:54:08.959] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 14:54:09 [14:54:09.028] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:09 [14:54:09.113] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 14:54:09 [14:54:09.131] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0c3a0830c804cea476ac887c70eecc65c692f6fb71dd18ab63c75601036e1eb2","blockNumber":2,"slot":3} 14:54:09 [14:54:09.161] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 14:54:09 [14:54:09.171] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306,0x0000000000000000000000000000000000000000000000000000000000000001,0x2a91cc8f9a4123bcf34a64497b838408e1b1ef326667ef82bc611ae04c80f61e,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 14:54:09 [14:54:09.418] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:09 [14:54:09.844] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:09 [14:54:09.868] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:09 [14:54:09.869] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:09 [14:54:09.897] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:10 [14:54:10.041] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0c3a0830c804cea476ac887c70eecc65c692f6fb71dd18ab63c75601036e1eb2","blockNumber":2,"slot":3} 14:54:10 [14:54:10.060] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:10 [14:54:10.064] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:10 [14:54:10.243] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:10 [14:54:10.462] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:10 [14:54:10.706] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:10 [14:54:10.738] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0c3a0830c804cea476ac887c70eecc65c692f6fb71dd18ab63c75601036e1eb2","blockNumber":2,"slot":3} 14:54:11 [14:54:11.102] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:11 [14:54:11.641] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:12 [14:54:12.363] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0c3a0830c804cea476ac887c70eecc65c692f6fb71dd18ab63c75601036e1eb2","blockNumber":2,"slot":3} 14:54:12 [14:54:12.366] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:12 [14:54:12.624] INFO: pxe:service Proving completed in 4592.271215000001ms {"timings":{"total":4592.271215000001,"sync":12.780542000000423,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":484.6304199999977,"oracles":{"debugLog":{"times":[1.1270779999995284,0.4469910000007076,0.5687239999988378,0.5061330000007729,0.4653010000001814,0.380009999998947,0.6851170000009006,0.7301180000013119]},"fetchTaggedLogs":{"times":[95.99369300000035]},"loadCapsule":{"times":[0.24129600000014761,0.3241379999999481,0.45219100000031176,0.3039870000011433,0.29350700000031793,0.4500109999989945]},"getSharedSecret":{"times":[3.850934000000052]},"aes128Decrypt":{"times":[1.0677969999997003,0.8325299999996787]},"getPublicKeysAndPartialAddress":{"times":[1.5604690000000119]},"getKeyValidationRequest":{"times":[2.0526300000001356]},"deliverNote":{"times":[13.28148500000134]},"deleteCapsule":{"times":[0.5207430000009481]},"storeCapsule":{"times":[0.4908720000003086]},"getNotes":{"times":[1.9870289999998931]},"getAuthWitness":{"times":[0.24989599999935308]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07724199999938719]},"callPrivateFunction":{"times":[683.1905559999996,150.8315309999998,893.1953319999993]}}},{"functionName":"private_kernel_init","time":63.680554999999},{"functionName":"ContractClassRegisterer:register","time":669.7845290000005,"oracles":{"loadCapsule":{"times":[6.475048000000243]},"notifyCreatedNullifier":{"times":[0.6689969999970344]},"debugLog":{"times":[0.9993540000032226]},"notifyCreatedContractClassLog":{"times":[7.225745999996434]}}},{"functionName":"private_kernel_inner","time":107.8376419999986},{"functionName":"ContractInstanceDeployer:deploy","time":83.16479000000254,"oracles":{"storeInExecutionCache":{"times":[0.2936169999993581]},"callPrivateFunction":{"times":[48.467123000002175]},"notifyCreatedNullifier":{"times":[0.25030700000206707]},"debugLog":{"times":[0.5121519999993325]}}},{"functionName":"private_kernel_inner","time":112.4204150000005},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":38.44053899999926,"oracles":{}},{"functionName":"private_kernel_inner","time":156.89046900000176},{"functionName":"Test:initialize","time":731.2239379999992,"oracles":{"getContractInstance":{"times":[8.100117999998474]},"debugLog":{"times":[0.6423649999996996,0.5365029999993567]},"fetchTaggedLogs":{"times":[194.15496899999926]},"loadCapsule":{"times":[0.26901599999837345,0.22537499999816646,0.22576600000320468]},"notifyCreatedNullifier":{"times":[0.2992969999977504]}}},{"functionName":"private_kernel_inner","time":171.7780329999987},{"functionName":"private_kernel_reset","time":168.52053299999898},{"functionName":"private_kernel_tail","time":92.05109699999957}],"unaccounted":1699.067713000004}} 14:54:12 [14:54:12.642] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:12 [14:54:12.802] VERBOSE: p2p:tx_pool Adding tx 0x11658e19c1afba258bd40f4a95181bc2f2ff522687d4127ed1b03680fc04aa47 to pool {"eventName":"tx-added-to-pool","txHash":"0x11658e19c1afba258bd40f4a95181bc2f2ff522687d4127ed1b03680fc04aa47","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 14:54:12 [14:54:12.828] INFO: node Received tx 0x11658e19c1afba258bd40f4a95181bc2f2ff522687d4127ed1b03680fc04aa47 {"txHash":"0x11658e19c1afba258bd40f4a95181bc2f2ff522687d4127ed1b03680fc04aa47"} 14:54:12 [14:54:12.828] INFO: pxe:service Sent transaction 0x11658e19c1afba258bd40f4a95181bc2f2ff522687d4127ed1b03680fc04aa47 14:54:12 [14:54:12.850] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:12 [14:54:12.916] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 14:54:12 [14:54:12.934] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-13T14:58:59.000Z","now":"2025-06-13T14:59:05.365Z"} 14:54:13 [14:54:13.059] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:13 [14:54:13.293] VERBOSE: simulator:public-processor Processed tx 0x11658e19c1afba258bd40f4a95181bc2f2ff522687d4127ed1b03680fc04aa47 with no public calls in 224.9139099999993ms {"txHash":"0x11658e19c1afba258bd40f4a95181bc2f2ff522687d4127ed1b03680fc04aa47","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":224.9139099999993} 14:54:13 [14:54:13.297] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:13 [14:54:13.299] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.35428675899999873s {"duration":0.35428675899999873,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 14:54:13 [14:54:13.480] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x012f2782614f9b0c4b958c74b7fafbbe307b681d4501688a91c175571a91dee9","globalVariables":{"chainId":31337,"version":4058855014,"blockNumber":2,"slotNumber":3,"timestamp":1749826739,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x11658e19c1afba258bd40f4a95181bc2f2ff522687d4127ed1b03680fc04aa47"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":564.2003210000003,"publicProcessDuration":354.88357299999916,"rollupCircuitsDuration":548.557721000001,"txCount":1,"blockNumber":2,"blockTimestamp":1749826739,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 14:54:13 [14:54:13.483] VERBOSE: sequencer Attesting committee is empty 14:54:13 [14:54:13.580] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:13 [14:54:13.790] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:13 [14:54:13.796] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x65ac8cdfe4575b629bd00351a8c430245d9f99b3b32e1fa2ab5e13e6e2e65ed2 14:54:13 [14:54:13.796] VERBOSE: sequencer-client Sent L1 transaction 0x65ac8cdfe4575b629bd00351a8c430245d9f99b3b32e1fa2ab5e13e6e2e65ed2 {"gasLimit":470265,"maxFeePerGas":"1.303611222","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 14:54:13 [14:54:13.812] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 14:54:13 [14:54:13.812] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x65ac8cdfe4575b629bd00351a8c430245d9f99b3b32e1fa2ab5e13e6e2e65ed2","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":2,"blockTimestamp":1749826739,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 14:54:13 [14:54:13.813] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 14:54:13 [14:54:13.825] INFO: blob-sink:server Received blob sidecar for block 0xb16d39f555f7768474874b16fb2bc1b320d0149f22c782c7b92d2025b451da92 14:54:13 [14:54:13.831] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749826775] on http://127.0.0.1:8545 14:54:13 [14:54:13.836] INFO: blob-sink:server Blob sidecar stored successfully for block 0xb16d39f555f7768474874b16fb2bc1b320d0149f22c782c7b92d2025b451da92 14:54:13 [14:54:13.837] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 14:54:13 [14:54:13.840] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749826775 14:54:13 [14:54:13.841] WARN: foundation:test-date-provider Time set to 2025-06-13T14:59:35.000Z {"offset":321159,"timeMs":1749826775000} 14:54:13 [14:54:13.841] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 14:54:13 [14:54:13.998] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:14 [14:54:14.001] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 14:54:14 [14:54:14.006] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 14:54:14 [14:54:14.010] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 14:54:14 [14:54:14.294] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:14 [14:54:14.319] VERBOSE: archiver:block-helper Store contract instance at 0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306 14:54:14 [14:54:14.354] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 14:54:14 [14:54:14.413] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x012f2782614f9b0c4b958c74b7fafbbe307b681d4501688a91c175571a91dee9","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":4058855014,"blockNumber":2,"slotNumber":3,"timestamp":1749826739,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x16080ead09a7926abd24d0209f863d8bf776b6791a706d0c0298a4de8ca56a0c","archiveNextLeafIndex":3} 14:54:14 [14:54:14.447] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 14:54:14 [14:54:14.456] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":6.963180999999167,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749826739,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 14:54:14 [14:54:14.500] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:14 [14:54:14.541] VERBOSE: p2p Synched to latest block 2 14:54:14 [14:54:14.703] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:15 [14:54:15.083] INFO: aztecjs:deploy_sent_tx Contract 0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306 successfully deployed. 14:54:15 [14:54:15.086] 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 14:54:15 [14:54:15.086] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:15 [14:54:15.135] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x16080ead09a7926abd24d0209f863d8bf776b6791a706d0c0298a4de8ca56a0c","blockNumber":3,"slot":4} 14:54:15 [14:54:15.141] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x16080ead09a7926abd24d0209f863d8bf776b6791a706d0c0298a4de8ca56a0c","header":{"contentCommitment":{"blobsHash":"0x0020d7d8a4e30ae504a89be5d6acaa73605b3fecb11e616d5dc8461567492fc5","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749826739,"version":4058855014},"lastArchive":"0x0c3a0830c804cea476ac887c70eecc65c692f6fb71dd18ab63c75601036e1eb2","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x28f37c56bbf1e997d48ac8adbb9b14192eeeec17300b518e66a47ee72196d9cc","nullifierTree":"0x19ffbc863f36ebc3b79787b16a486f9d80cd03ab371fbbae669d0851242439d4","publicDataTree":"0x0d24f745df73c5d44ac3d45c166a010a08542e71a3c424278659117b923a6f26"},"totalFees":7272506880,"totalManaUsed":21888}} 14:54:15 [14:54:15.235] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:15 [14:54:15.282] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:15 [14:54:15.283] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:15 [14:54:15.320] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:15 [14:54:15.367] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:15 [14:54:15.372] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0547051037ab51889410cc5e141a989e506be446ed2759fe1b0e081ffdfef394 14:54:15 [14:54:15.382] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:54:15 [14:54:15.383] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:54:15 [14:54:15.392] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:54:15 [14:54:15.393] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:54:15 [14:54:15.396] VERBOSE: pxe:service Added note {"index":0,"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x23d2403b49259694b6bf1d29b8c08e3219bf1f543649f2599aa2620fbe5f1c7b","nullifier":"0x1bc9f35d64f5b6cca6445c1a642cbd9699369727d1da9d50c654d8f0a6d08c88"} 14:54:15 [14:54:15.398] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:15 [14:54:15.414] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:15 [14:54:15.621] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:15 [14:54:15.917] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:15 [14:54:15.933] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:15 [14:54:15.934] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:16 [14:54:16.182] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:16 [14:54:16.202] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x16080ead09a7926abd24d0209f863d8bf776b6791a706d0c0298a4de8ca56a0c","blockNumber":3,"slot":4} 14:54:16 [14:54:16.235] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:16 [14:54:16.238] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:16 [14:54:16.477] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:17 [14:54:17.308] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:17 [14:54:17.518] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:17 [14:54:17.533] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x16080ead09a7926abd24d0209f863d8bf776b6791a706d0c0298a4de8ca56a0c","blockNumber":3,"slot":4} 14:54:17 [14:54:17.829] INFO: pxe:service Proving completed in 2704.867611999998ms {"timings":{"total":2704.867611999998,"sync":17.927726999998413,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":394.6652410000024,"oracles":{"debugLog":{"times":[0.7331110000013723,0.5009050000007846,0.6010769999993499,0.5650459999997111,0.5933570000015607,0.4599839999973483,0.32224000000132946,4.463109000000259]},"fetchTaggedLogs":{"times":[87.91866400000072]},"loadCapsule":{"times":[0.2180660000012722,0.1703849999976228,0.309358999998949,0.23745699999926728,0.2118560000017169,0.21615699999892968]},"getSharedSecret":{"times":[3.3291659999995318]},"aes128Decrypt":{"times":[0.2980189999980212,0.7641709999988961]},"getPublicKeysAndPartialAddress":{"times":[1.4589620000006107]},"getKeyValidationRequest":{"times":[3.6659450000006473]},"deliverNote":{"times":[3.370986999998422]},"deleteCapsule":{"times":[0.2903989999977057]},"storeCapsule":{"times":[0.4752230000012787]},"getNotes":{"times":[1.5005330000021786]},"getAuthWitness":{"times":[0.24137699999846518]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.11876399999891873]},"callPrivateFunction":{"times":[824.3982800000013]}}},{"functionName":"private_kernel_init","time":90.0204739999972},{"functionName":"Test:set_tx_max_block_number","time":686.0235429999993,"oracles":{"debugLog":{"times":[0.9099769999993441,0.2847379999984696]},"fetchTaggedLogs":{"times":[302.7407940000012]},"loadCapsule":{"times":[0.14749400000073365,0.1503450000018347,0.14635400000042864]}}},{"functionName":"private_kernel_inner","time":146.94442499999786},{"functionName":"private_kernel_reset","time":216.97420299999794},{"functionName":"private_kernel_tail","time":102.97924799999964}],"unaccounted":1049.3327510000054}} 14:54:17 [14:54:17.836] 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 14:54:17 [14:54:17.837] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:17 [14:54:17.937] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:17 [14:54:17.981] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:17 [14:54:17.982] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:18 [14:54:18.031] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:18 [14:54:18.044] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0547051037ab51889410cc5e141a989e506be446ed2759fe1b0e081ffdfef394 14:54:18 [14:54:18.046] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:18 [14:54:18.066] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:54:18 [14:54:18.067] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:54:18 [14:54:18.080] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x16080ead09a7926abd24d0209f863d8bf776b6791a706d0c0298a4de8ca56a0c","blockNumber":3,"slot":4} 14:54:18 [14:54:18.093] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:54:18 [14:54:18.095] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:54:18 [14:54:18.100] VERBOSE: pxe:service Added note {"index":0,"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x23d2403b49259694b6bf1d29b8c08e3219bf1f543649f2599aa2620fbe5f1c7b","nullifier":"0x1bc9f35d64f5b6cca6445c1a642cbd9699369727d1da9d50c654d8f0a6d08c88"} 14:54:18 [14:54:18.103] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:18 [14:54:18.123] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:18 [14:54:18.311] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:18 [14:54:18.618] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:18 [14:54:18.633] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:18 [14:54:18.634] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:18 [14:54:18.662] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:18 [14:54:18.804] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x16080ead09a7926abd24d0209f863d8bf776b6791a706d0c0298a4de8ca56a0c","blockNumber":3,"slot":4} 14:54:18 [14:54:18.809] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:18 [14:54:18.812] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:19 [14:54:19.012] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:19 [14:54:19.483] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:19 [14:54:19.639] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x16080ead09a7926abd24d0209f863d8bf776b6791a706d0c0298a4de8ca56a0c","blockNumber":3,"slot":4} 14:54:19 [14:54:19.857] INFO: pxe:service Proving completed in 1996.869358ms {"timings":{"total":1996.869358,"sync":4.165400000001682,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":414.95603899999696,"oracles":{"debugLog":{"times":[0.73562099999981,0.3957510000000184,0.5205849999983911,0.6609590000007302,0.8802050000012969,0.6931499999991502,0.6117769999982556,0.8147639999988314]},"fetchTaggedLogs":{"times":[53.04602899999736]},"loadCapsule":{"times":[2.935113999999885,0.8033140000006824,4.275553999999829,0.29316800000015064,0.26383699999860255,0.555646999997407]},"getSharedSecret":{"times":[13.895391000001837]},"aes128Decrypt":{"times":[0.2823279999975057,1.1603829999985464]},"getPublicKeysAndPartialAddress":{"times":[13.311313000001974]},"getKeyValidationRequest":{"times":[8.771993000002112]},"deliverNote":{"times":[5.334224000001996]},"deleteCapsule":{"times":[0.40138200000001234]},"storeCapsule":{"times":[0.3935510000010254]},"getNotes":{"times":[2.175971999997273]},"getAuthWitness":{"times":[0.29936799999995856]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.13836400000218418]},"callPrivateFunction":{"times":[691.4115259999999]}}},{"functionName":"private_kernel_init","time":89.38291599999866},{"functionName":"Test:set_tx_max_block_number","time":525.2053860000015,"oracles":{"debugLog":{"times":[0.7905019999998331,0.38152099999933853]},"fetchTaggedLogs":{"times":[177.02505199999723]},"loadCapsule":{"times":[0.1830260000024282,0.1758559999980207,0.17598500000167405]}}},{"functionName":"private_kernel_inner","time":110.11181299999953},{"functionName":"private_kernel_reset","time":168.13575599999967},{"functionName":"private_kernel_tail","time":62.15368099999978}],"unaccounted":622.7583670000022}} 14:54:19 [14:54:19.870] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:19 [14:54:19.890] VERBOSE: p2p:tx_pool Adding tx 0x304e8c716c6c673f2b073e5a7978949b32f8237e71153b32b5a9ca3f02a41ee0 to pool {"eventName":"tx-added-to-pool","txHash":"0x304e8c716c6c673f2b073e5a7978949b32f8237e71153b32b5a9ca3f02a41ee0","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 14:54:19 [14:54:19.896] INFO: node Received tx 0x304e8c716c6c673f2b073e5a7978949b32f8237e71153b32b5a9ca3f02a41ee0 {"txHash":"0x304e8c716c6c673f2b073e5a7978949b32f8237e71153b32b5a9ca3f02a41ee0"} 14:54:19 [14:54:19.896] INFO: pxe:service Sent transaction 0x304e8c716c6c673f2b073e5a7978949b32f8237e71153b32b5a9ca3f02a41ee0 14:54:20 [14:54:20.076] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:20 [14:54:20.176] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 14:54:20 [14:54:20.192] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-13T14:59:35.000Z","now":"2025-06-13T14:59:41.351Z"} 14:54:20 [14:54:20.212] VERBOSE: simulator:public-processor Processed tx 0x304e8c716c6c673f2b073e5a7978949b32f8237e71153b32b5a9ca3f02a41ee0 with no public calls in 11.334826999998768ms {"txHash":"0x304e8c716c6c673f2b073e5a7978949b32f8237e71153b32b5a9ca3f02a41ee0","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":11.334826999998768} 14:54:20 [14:54:20.213] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.020274044000001594s {"duration":0.020274044000001594,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1024,"l2Gas":5856},"totalSizeInBytes":256} 14:54:20 [14:54:20.303] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:20 [14:54:20.320] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x05ae646dc0744e8dcb6c6b27d48594502cb3a90147016e1d0241c7c27be22970","globalVariables":{"chainId":31337,"version":4058855014,"blockNumber":3,"slotNumber":4,"timestamp":1749826775,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x304e8c716c6c673f2b073e5a7978949b32f8237e71153b32b5a9ca3f02a41ee0"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":143.9176069999994,"publicProcessDuration":20.759268999998312,"rollupCircuitsDuration":129.16278299999976,"txCount":1,"blockNumber":3,"blockTimestamp":1749826775,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:54:20 [14:54:20.323] VERBOSE: sequencer Attesting committee is empty 14:54:20 [14:54:20.544] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:20 [14:54:20.553] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x08588b7cc9f96979a54b5aad8512275a3fc898ae2a70cf1f7aad66c54b24d557 14:54:20 [14:54:20.553] VERBOSE: sequencer-client Sent L1 transaction 0x08588b7cc9f96979a54b5aad8512275a3fc898ae2a70cf1f7aad66c54b24d557 {"gasLimit":470312,"maxFeePerGas":"1.279583419","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 14:54:20 [14:54:20.562] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749826811] on http://127.0.0.1:8545 14:54:20 [14:54:20.564] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 14:54:20 [14:54:20.567] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 14:54:20 [14:54:20.567] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x08588b7cc9f96979a54b5aad8512275a3fc898ae2a70cf1f7aad66c54b24d557","calldataSize":1444,"calldataGas":12316,"txCount":1,"blockNumber":3,"blockTimestamp":1749826775,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:54:20 [14:54:20.568] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 14:54:20 [14:54:20.573] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749826811 14:54:20 [14:54:20.573] WARN: foundation:test-date-provider Time set to 2025-06-13T15:00:11.000Z {"offset":350427,"timeMs":1749826811000} 14:54:20 [14:54:20.574] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 14:54:20 [14:54:20.578] INFO: blob-sink:server Received blob sidecar for block 0x3284478fb263d5f76e6c11db593474ad6e64a8fff5cfeaa129f51e55c382ab35 14:54:20 [14:54:20.582] INFO: blob-sink:server Blob sidecar stored successfully for block 0x3284478fb263d5f76e6c11db593474ad6e64a8fff5cfeaa129f51e55c382ab35 14:54:20 [14:54:20.827] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:20 [14:54:20.843] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x05ae646dc0744e8dcb6c6b27d48594502cb3a90147016e1d0241c7c27be22970","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":4058855014,"blockNumber":3,"slotNumber":4,"timestamp":1749826775,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x21676ae4bddaa7e18113a1a1b778be69d49a2f7bba2149bf0bba3586b1f3b0b0","archiveNextLeafIndex":4} 14:54:20 [14:54:20.844] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 14:54:20 [14:54:20.848] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 14:54:20 [14:54:20.854] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 14:54:20 [14:54:20.901] 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 14:54:20 [14:54:20.922] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x21676ae4bddaa7e18113a1a1b778be69d49a2f7bba2149bf0bba3586b1f3b0b0","header":{"contentCommitment":{"blobsHash":"0x0072175f3bb8d50cb1fa5414b7768c8e707b1fb575f4c1768668edde425353b1","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749826775,"version":4058855014},"lastArchive":"0x16080ead09a7926abd24d0209f863d8bf776b6791a706d0c0298a4de8ca56a0c","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x28f37c56bbf1e997d48ac8adbb9b14192eeeec17300b518e66a47ee72196d9cc","nullifierTree":"0x1e24378e65f24010c0a1625ea6540a0602e43f4a5181895783af058c8c4d3e2c","publicDataTree":"0x280a26704daa2a945e3ece3ec402de07cb9c6338484d833f58c60de4b0949a62"},"totalFees":1945714560,"totalManaUsed":5856}} 14:54:20 [14:54:20.936] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":6.829413999999815,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749826775,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:54:21 [14:54:21.026] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:21 [14:54:21.078] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:21 [14:54:21.079] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:21 [14:54:21.079] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:21 [14:54:21.133] VERBOSE: p2p Synched to latest block 3 14:54:21 [14:54:21.191] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:21 [14:54:21.200] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0547051037ab51889410cc5e141a989e506be446ed2759fe1b0e081ffdfef394 14:54:21 [14:54:21.215] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:54:21 [14:54:21.216] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:54:21 [14:54:21.289] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:54:21 [14:54:21.289] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:54:21 [14:54:21.290] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:21 [14:54:21.300] VERBOSE: pxe:service Added note {"index":0,"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x23d2403b49259694b6bf1d29b8c08e3219bf1f543649f2599aa2620fbe5f1c7b","nullifier":"0x1bc9f35d64f5b6cca6445c1a642cbd9699369727d1da9d50c654d8f0a6d08c88"} 14:54:21 [14:54:21.305] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:21 [14:54:21.335] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:21 [14:54:21.532] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:21 [14:54:21.927] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:21 [14:54:21.939] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:21 [14:54:21.939] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:21 [14:54:21.946] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:21 [14:54:21.960] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 14:54:22 [14:54:22.105] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x21676ae4bddaa7e18113a1a1b778be69d49a2f7bba2149bf0bba3586b1f3b0b0","blockNumber":4,"slot":5} 14:54:22 [14:54:22.124] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:22 [14:54:22.130] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:22 [14:54:22.161] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:22 [14:54:22.542] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:22 [14:54:22.847] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:22 [14:54:22.995] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x21676ae4bddaa7e18113a1a1b778be69d49a2f7bba2149bf0bba3586b1f3b0b0","blockNumber":4,"slot":5} 14:54:23 [14:54:23.270] INFO: pxe:service Proving completed in 2354.211965999999ms {"timings":{"total":2354.211965999999,"sync":8.108314999997674,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":665.5294590000049,"oracles":{"debugLog":{"times":[0.6813959999999497,0.48119100000258186,0.525193000001309,0.6494259999999485,0.8386009999994712,0.34038800000053016,0.41225000000122236,0.5996640000012121]},"fetchTaggedLogs":{"times":[119.21010799999931]},"loadCapsule":{"times":[0.4395509999994829,0.3090169999995851,0.4678410000014992,1.7580519999974058,0.2887170000030892,1.4189039999982924]},"getSharedSecret":{"times":[5.08530299999984]},"aes128Decrypt":{"times":[0.4840409999997064,1.7972129999980098]},"getPublicKeysAndPartialAddress":{"times":[7.863188999999693]},"getKeyValidationRequest":{"times":[2.522601000000577]},"deliverNote":{"times":[11.129198000002361]},"deleteCapsule":{"times":[0.3524680000009539]},"storeCapsule":{"times":[1.4818860000013956]},"getNotes":{"times":[17.221773999997822]},"getAuthWitness":{"times":[0.17420400000264635]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.0485210000006191]},"callPrivateFunction":{"times":[803.7431419999994]}}},{"functionName":"private_kernel_init","time":89.35865900000135},{"functionName":"Test:set_tx_max_block_number","time":534.626648999998,"oracles":{"debugLog":{"times":[0.4901319999989937,0.7586489999994228]},"fetchTaggedLogs":{"times":[188.44435299999896]},"loadCapsule":{"times":[0.4394110000030196,0.3173069999975269,0.3017370000015944]},"storeInExecutionCache":{"times":[0.08407200000146986]},"notifyEnqueuedPublicFunctionCall":{"times":[0.5200130000011995]}}},{"functionName":"private_kernel_inner","time":105.20562000000064},{"functionName":"private_kernel_reset","time":188.32185000000027},{"functionName":"private_kernel_tail","time":101.98886199999833}],"unaccounted":661.0725519999978}} 14:54:23 [14:54:23.272] 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 14:54:23 [14:54:23.274] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:23 [14:54:23.370] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:23 [14:54:23.402] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:23 [14:54:23.402] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:23 [14:54:23.442] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:23 [14:54:23.446] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0547051037ab51889410cc5e141a989e506be446ed2759fe1b0e081ffdfef394 14:54:23 [14:54:23.454] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:54:23 [14:54:23.455] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:54:23 [14:54:23.461] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:54:23 [14:54:23.462] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:54:23 [14:54:23.465] VERBOSE: pxe:service Added note {"index":0,"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x23d2403b49259694b6bf1d29b8c08e3219bf1f543649f2599aa2620fbe5f1c7b","nullifier":"0x1bc9f35d64f5b6cca6445c1a642cbd9699369727d1da9d50c654d8f0a6d08c88"} 14:54:23 [14:54:23.466] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:23 [14:54:23.477] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:23 [14:54:23.479] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:23 [14:54:23.719] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:23 [14:54:23.891] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:23 [14:54:23.908] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:23 [14:54:23.909] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:24 [14:54:24.105] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x21676ae4bddaa7e18113a1a1b778be69d49a2f7bba2149bf0bba3586b1f3b0b0","blockNumber":4,"slot":5} 14:54:24 [14:54:24.128] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:24 [14:54:24.141] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:24 [14:54:24.154] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:24 [14:54:24.364] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:24 [14:54:24.626] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:24 [14:54:24.909] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:25 [14:54:25.054] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x21676ae4bddaa7e18113a1a1b778be69d49a2f7bba2149bf0bba3586b1f3b0b0","blockNumber":4,"slot":5} 14:54:25 [14:54:25.309] INFO: pxe:service Proving completed in 2013.5515719999967ms {"timings":{"total":2013.5515719999967,"sync":3.5564759999979287,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":374.711841999997,"oracles":{"debugLog":{"times":[0.6280750000005355,0.3286980000011681,0.3297080000011192,0.395329999999376,0.5424730000013369,0.41287000000011176,0.30921699999817065,0.4853819999989355]},"fetchTaggedLogs":{"times":[42.25859700000001]},"loadCapsule":{"times":[0.14860400000179652,0.14704300000084913,0.29287699999986216,0.26556600000185426,0.15084399999977904,0.2310059999981604]},"getSharedSecret":{"times":[2.461128999999346]},"aes128Decrypt":{"times":[0.29051699999763514,0.7720379999991565]},"getPublicKeysAndPartialAddress":{"times":[1.3663029999988794]},"getKeyValidationRequest":{"times":[1.6507000000019616]},"deliverNote":{"times":[3.0591029999995953]},"deleteCapsule":{"times":[0.26876699999775155]},"storeCapsule":{"times":[0.208585000000312]},"getNotes":{"times":[1.0764449999987846]},"getAuthWitness":{"times":[0.14138399999865214]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.04480100000000675]},"callPrivateFunction":{"times":[712.8049350000001]}}},{"functionName":"private_kernel_init","time":81.24902400000065},{"functionName":"Test:set_tx_max_block_number","time":501.5073919999995,"oracles":{"debugLog":{"times":[0.79562900000019,0.4544610000011744]},"fetchTaggedLogs":{"times":[243.8986870000008]},"loadCapsule":{"times":[0.38902999999845633,0.39662999999927706,0.39438999999765656]},"storeInExecutionCache":{"times":[0.05591099999946891]},"notifyEnqueuedPublicFunctionCall":{"times":[0.06527199999982258]}}},{"functionName":"private_kernel_inner","time":119.32372000000032},{"functionName":"private_kernel_reset","time":161.15062600000238},{"functionName":"private_kernel_tail","time":95.35571300000447}],"unaccounted":676.6967789999944}} 14:54:25 [14:54:25.337] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:25 [14:54:25.362] VERBOSE: p2p:tx_pool Adding tx 0x18f760c5f1125f9d743a159967b4caea559494463acd624640e1fd88a19ab183 to pool {"eventName":"tx-added-to-pool","txHash":"0x18f760c5f1125f9d743a159967b4caea559494463acd624640e1fd88a19ab183","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226281,"feePaymentMethod":"fee_juice"} 14:54:25 [14:54:25.369] INFO: node Received tx 0x18f760c5f1125f9d743a159967b4caea559494463acd624640e1fd88a19ab183 {"txHash":"0x18f760c5f1125f9d743a159967b4caea559494463acd624640e1fd88a19ab183"} 14:54:25 [14:54:25.369] INFO: pxe:service Sent transaction 0x18f760c5f1125f9d743a159967b4caea559494463acd624640e1fd88a19ab183 14:54:25 [14:54:25.542] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:25 [14:54:25.576] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 14:54:25 [14:54:25.588] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-13T15:00:11.000Z","now":"2025-06-13T15:00:16.015Z"} 14:54:25 [14:54:25.628] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x5189a417 completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x5189a417","duration":16.655370999993465} 14:54:25 [14:54:25.646] VERBOSE: simulator:public-processor Processed tx 0x18f760c5f1125f9d743a159967b4caea559494463acd624640e1fd88a19ab183 with 1 public calls in 44.112331000003906ms {"txHash":"0x18f760c5f1125f9d743a159967b4caea559494463acd624640e1fd88a19ab183","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":44.112331000003906} 14:54:25 [14:54:25.647] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.05786850200000481s {"duration":0.05786850200000481,"rate":60188.183201972475,"totalPublicGas":{"daGas":0,"l2Gas":3483},"totalBlockGas":{"daGas":1024,"l2Gas":29339},"totalSizeInBytes":256} 14:54:25 [14:54:25.741] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 21011.755923605924 mana/s {"blockHash":"0x1bb1802fe657609fd07208d265cae7db2ef0c86898253d0c96513fc94009f424","globalVariables":{"chainId":31337,"version":4058855014,"blockNumber":4,"slotNumber":5,"timestamp":1749826811,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x18f760c5f1125f9d743a159967b4caea559494463acd624640e1fd88a19ab183"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":165.13771200000338,"publicProcessDuration":58.186948999995366,"rollupCircuitsDuration":152.98274999999558,"txCount":1,"blockNumber":4,"blockTimestamp":1749826811,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:54:25 [14:54:25.743] VERBOSE: sequencer Attesting committee is empty 14:54:25 [14:54:25.824] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:26 [14:54:26.021] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xb4def59619f010105e2516f4ab70a6e77302f6d3ec803e94f9b2fcc3d4b976dd 14:54:26 [14:54:26.022] VERBOSE: sequencer-client Sent L1 transaction 0xb4def59619f010105e2516f4ab70a6e77302f6d3ec803e94f9b2fcc3d4b976dd {"gasLimit":470295,"maxFeePerGas":"1.261127761","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 14:54:26 [14:54:26.029] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:26 [14:54:26.036] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 14:54:26 [14:54:26.037] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xb4def59619f010105e2516f4ab70a6e77302f6d3ec803e94f9b2fcc3d4b976dd","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":4,"blockTimestamp":1749826811,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:54:26 [14:54:26.038] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 14:54:26 [14:54:26.048] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 14:54:26 [14:54:26.052] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 14:54:26 [14:54:26.057] INFO: blob-sink:server Received blob sidecar for block 0xe8192972bf965b3436bcfc72e5beaf172d4c6c5ded6b99cdd75f707574cec486 14:54:26 [14:54:26.060] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 14:54:26 [14:54:26.064] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749826847] on http://127.0.0.1:8545 14:54:26 [14:54:26.066] INFO: blob-sink:server Blob sidecar stored successfully for block 0xe8192972bf965b3436bcfc72e5beaf172d4c6c5ded6b99cdd75f707574cec486 14:54:26 [14:54:26.067] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 14:54:26 [14:54:26.069] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749826847 14:54:26 [14:54:26.070] WARN: foundation:test-date-provider Time set to 2025-06-13T15:00:47.000Z {"offset":380930,"timeMs":1749826847000} 14:54:26 [14:54:26.070] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 14:54:26 [14:54:26.230] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x1bb1802fe657609fd07208d265cae7db2ef0c86898253d0c96513fc94009f424","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":4058855014,"blockNumber":4,"slotNumber":5,"timestamp":1749826811,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","archiveNextLeafIndex":5} 14:54:26 [14:54:26.233] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 14:54:26 [14:54:26.234] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:26 [14:54:26.340] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":7.703144999999495,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749826811,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:54:26 [14:54:26.350] VERBOSE: p2p Synched to latest block 4 14:54:26 [14:54:26.374] 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 14:54:26 [14:54:26.393] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","header":{"contentCommitment":{"blobsHash":"0x00d6b9366850ebe7454e23a592b30e3f7dffc5716af02d748aadb38bffee2679","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749826811,"version":4058855014},"lastArchive":"0x21676ae4bddaa7e18113a1a1b778be69d49a2f7bba2149bf0bba3586b1f3b0b0","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x28f37c56bbf1e997d48ac8adbb9b14192eeeec17300b518e66a47ee72196d9cc","nullifierTree":"0x1fda3bb2f0706a60b00ebe89e68fcaa36881325fb99642f969e4dc6990bb664c","publicDataTree":"0x21222841d15bbb9c3874c57fa4d0bc804d8865e824daabd9c007b219e38f6293"},"totalFees":1487487300,"totalManaUsed":29339}} 14:54:26 [14:54:26.443] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:26 [14:54:26.463] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:26 [14:54:26.499] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:26 [14:54:26.499] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:26 [14:54:26.566] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:26 [14:54:26.577] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:26 [14:54:26.582] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0547051037ab51889410cc5e141a989e506be446ed2759fe1b0e081ffdfef394 14:54:26 [14:54:26.594] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:54:26 [14:54:26.595] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:54:26 [14:54:26.601] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:54:26 [14:54:26.601] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:54:26 [14:54:26.607] VERBOSE: pxe:service Added note {"index":0,"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x23d2403b49259694b6bf1d29b8c08e3219bf1f543649f2599aa2620fbe5f1c7b","nullifier":"0x1bc9f35d64f5b6cca6445c1a642cbd9699369727d1da9d50c654d8f0a6d08c88"} 14:54:26 [14:54:26.609] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:26 [14:54:26.623] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:26 [14:54:26.782] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:27 [14:54:27.095] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:27 [14:54:27.107] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:27 [14:54:27.108] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:27 [14:54:27.259] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:27 [14:54:27.294] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:27 [14:54:27.310] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:27 [14:54:27.314] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:27 [14:54:27.552] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:27 [14:54:27.789] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:28 [14:54:28.236] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:28 [14:54:28.463] INFO: pxe:service Proving completed in 2075.296107000002ms {"timings":{"total":2075.296107000002,"sync":7.979251999997359,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":344.9486370000013,"oracles":{"debugLog":{"times":[0.4986829999979818,0.43663999999989755,0.5693339999997988,0.4892420000032871,0.5230129999981727,0.3980890000020736,0.4136500000022352,0.5906740000064019]},"fetchTaggedLogs":{"times":[80.82765400000062]},"loadCapsule":{"times":[0.1640040000056615,0.13426299999991897,0.3758689999958733,0.20759499999985565,0.27069699999992736,0.20843500000046333]},"getSharedSecret":{"times":[3.596846000000369]},"aes128Decrypt":{"times":[0.29943700000148965,1.2556400000030408]},"getPublicKeysAndPartialAddress":{"times":[1.7115310000008321]},"getKeyValidationRequest":{"times":[1.420084000004863]},"deliverNote":{"times":[5.964612999996461]},"deleteCapsule":{"times":[0.2887560000017402]},"storeCapsule":{"times":[0.2756860000008601]},"getNotes":{"times":[1.3769830000019283]},"getAuthWitness":{"times":[0.1763040000005276]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07355200000165496]},"callPrivateFunction":{"times":[697.1432180000047]}}},{"functionName":"private_kernel_init","time":57.18958600000042},{"functionName":"Test:set_tx_max_block_number","time":576.6073199999955,"oracles":{"debugLog":{"times":[0.5199529999954393,0.536921999999322]},"fetchTaggedLogs":{"times":[205.02344200000516]},"loadCapsule":{"times":[0.2693559999970603,0.22391500000230735,0.22472500000003492]}}},{"functionName":"private_kernel_inner","time":103.70410399999673},{"functionName":"private_kernel_reset","time":172.38072600000305},{"functionName":"private_kernel_tail","time":63.65220099999715}],"unaccounted":748.8342810000104}} 14:54:28 [14:54:28.465] 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 14:54:28 [14:54:28.466] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:28 [14:54:28.546] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:28 [14:54:28.578] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:28 [14:54:28.578] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:28 [14:54:28.624] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:28 [14:54:28.628] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0547051037ab51889410cc5e141a989e506be446ed2759fe1b0e081ffdfef394 14:54:28 [14:54:28.636] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:54:28 [14:54:28.637] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:54:28 [14:54:28.642] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:54:28 [14:54:28.642] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:54:28 [14:54:28.644] VERBOSE: pxe:service Added note {"index":0,"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x23d2403b49259694b6bf1d29b8c08e3219bf1f543649f2599aa2620fbe5f1c7b","nullifier":"0x1bc9f35d64f5b6cca6445c1a642cbd9699369727d1da9d50c654d8f0a6d08c88"} 14:54:28 [14:54:28.646] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:28 [14:54:28.658] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:28 [14:54:28.771] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:28 [14:54:28.981] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:28 [14:54:28.991] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:28 [14:54:28.991] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:29 [14:54:29.101] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:29 [14:54:29.114] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:29 [14:54:29.127] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:29 [14:54:29.129] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:29 [14:54:29.425] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:29 [14:54:29.827] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:29 [14:54:29.969] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:30 [14:54:30.188] INFO: pxe:service Proving completed in 1703.7941620000056ms {"timings":{"total":1703.7941620000056,"sync":2.5632210000039777,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":269.6453249999977,"oracles":{"debugLog":{"times":[0.4916319999974803,0.4336509999993723,0.3417780000017956,0.3688990000009653,0.3102579999977024,0.28668699999980163,0.37417899999854853,0.5381829999969341]},"fetchTaggedLogs":{"times":[48.78220400000282]},"loadCapsule":{"times":[0.16714399999909801,0.1430529999997816,0.3099870000005467,0.2056239999947138,0.1930050000009942,0.16598399999929825]},"getSharedSecret":{"times":[2.6089129999963916]},"aes128Decrypt":{"times":[0.1789040000003297,0.6885269999984303]},"getPublicKeysAndPartialAddress":{"times":[1.0086149999988265]},"getKeyValidationRequest":{"times":[1.0880159999942407]},"deliverNote":{"times":[2.5524720000030356]},"deleteCapsule":{"times":[0.23691499999404186]},"storeCapsule":{"times":[0.20793499999854248]},"getNotes":{"times":[1.2411400000055437]},"getAuthWitness":{"times":[0.24619600000005448]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06496100000367733]},"callPrivateFunction":{"times":[474.42207100000087]}}},{"functionName":"private_kernel_init","time":76.95154000000184},{"functionName":"Test:set_tx_max_block_number","time":365.7387979999985,"oracles":{"debugLog":{"times":[0.4419209999978193,0.34845900000073016]},"fetchTaggedLogs":{"times":[137.13537899999938]},"loadCapsule":{"times":[0.1677140000028885,0.13502300000254763,0.17399399999703746]}}},{"functionName":"private_kernel_inner","time":109.99332499999582},{"functionName":"private_kernel_reset","time":162.04743799999414},{"functionName":"private_kernel_tail","time":63.85488600000099}],"unaccounted":652.9996290000126}} 14:54:30 [14:54:30.200] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:30 [14:54:30.214] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x106c6d8cd3cb0c85b3438717dc9146d96f982a804fcf9e0332d944b6772155fd for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 14:54:30 [14:54:30.218] WARN: node Received invalid tx 0x106c6d8cd3cb0c85b3438717dc9146d96f982a804fcf9e0332d944b6772155fd: Invalid max block number {"txHash":"0x106c6d8cd3cb0c85b3438717dc9146d96f982a804fcf9e0332d944b6772155fd"} 14:54:30 [14:54:30.222] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 14:54:30 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 14:54:30 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 14:54:30 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 14:54:30 [14:54:30.223] 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 14:54:30 [14:54:30.315] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:30 [14:54:30.354] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:30 [14:54:30.354] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:30 [14:54:30.397] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:30 [14:54:30.403] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0547051037ab51889410cc5e141a989e506be446ed2759fe1b0e081ffdfef394 14:54:30 [14:54:30.405] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:30 [14:54:30.415] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:54:30 [14:54:30.416] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:54:30 [14:54:30.424] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:54:30 [14:54:30.425] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:54:30 [14:54:30.427] VERBOSE: pxe:service Added note {"index":0,"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x23d2403b49259694b6bf1d29b8c08e3219bf1f543649f2599aa2620fbe5f1c7b","nullifier":"0x1bc9f35d64f5b6cca6445c1a642cbd9699369727d1da9d50c654d8f0a6d08c88"} 14:54:30 [14:54:30.428] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:30 [14:54:30.439] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:30 [14:54:30.720] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:30 [14:54:30.861] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:30 [14:54:30.872] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:30 [14:54:30.872] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:31 [14:54:31.017] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:31 [14:54:31.026] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:31 [14:54:31.028] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:31 [14:54:31.073] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:31 [14:54:31.608] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:31 [14:54:31.761] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:31 [14:54:31.977] INFO: pxe:service Proving completed in 1735.1209890000027ms {"timings":{"total":1735.1209890000027,"sync":8.169066999995266,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":326.0131160000019,"oracles":{"debugLog":{"times":[0.6333660000018426,0.31612800000584684,0.32641899999725865,0.31520799999998417,0.2888470000034431,0.24875699999392964,0.24000599999999395,0.5257429999983287]},"fetchTaggedLogs":{"times":[47.14134699999704]},"loadCapsule":{"times":[0.17601500000455417,0.1837939999968512,0.27836699999897974,0.20131500000570668,0.13779300000169314,0.16452399999980116]},"getSharedSecret":{"times":[6.73729099999764]},"aes128Decrypt":{"times":[0.17255399999703513,0.5744239999985439]},"getPublicKeysAndPartialAddress":{"times":[2.532333999995899]},"getKeyValidationRequest":{"times":[3.1088789999994333]},"deliverNote":{"times":[2.310937999995076]},"deleteCapsule":{"times":[0.19289500000013504]},"storeCapsule":{"times":[0.1813140000012936]},"getNotes":{"times":[1.26933299999655]},"getAuthWitness":{"times":[0.1580439999961527]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.17404399999941234]},"callPrivateFunction":{"times":[595.5826399999933]}}},{"functionName":"private_kernel_init","time":60.838495000003604},{"functionName":"Test:set_tx_max_block_number","time":448.6072690000001,"oracles":{"debugLog":{"times":[0.40503000000171596,0.2919469999978901]},"fetchTaggedLogs":{"times":[155.32823400000052]},"loadCapsule":{"times":[0.16096399999514688,0.14224400000239257,0.16369399999530287]},"storeInExecutionCache":{"times":[0.05029099999956088]},"notifyEnqueuedPublicFunctionCall":{"times":[0.07180199999856995]}}},{"functionName":"private_kernel_inner","time":115.8667709999936},{"functionName":"private_kernel_reset","time":145.53031400000327},{"functionName":"private_kernel_tail","time":76.48138199999812}],"unaccounted":553.6145750000069}} 14:54:31 [14:54:31.980] 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 14:54:31 [14:54:31.981] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:32 [14:54:32.062] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:32 [14:54:32.098] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:32 [14:54:32.099] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:32 [14:54:32.172] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721"} 14:54:32 [14:54:32.188] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:32 [14:54:32.192] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0547051037ab51889410cc5e141a989e506be446ed2759fe1b0e081ffdfef394 14:54:32 [14:54:32.223] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:54:32 [14:54:32.224] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:54:32 [14:54:32.338] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:54:32 [14:54:32.339] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:54:32 [14:54:32.347] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:32 [14:54:32.348] VERBOSE: pxe:service Added note {"index":0,"contract":"0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x23d2403b49259694b6bf1d29b8c08e3219bf1f543649f2599aa2620fbe5f1c7b","nullifier":"0x1bc9f35d64f5b6cca6445c1a642cbd9699369727d1da9d50c654d8f0a6d08c88"} 14:54:32 [14:54:32.352] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:32 [14:54:32.391] VERBOSE: simulator:client_execution_context:debug_log Setting 0x09aa2f618b3e9770621d1ce416ad63b3d98f1159b916082c9a5de2cfc707f721 as fee payer 14:54:32 [14:54:32.394] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:32 [14:54:32.666] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:32 [14:54:32.797] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:32 [14:54:32.809] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:54:32 [14:54:32.810] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:32 [14:54:32.974] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x10ae29505aee02ebe7c35d0fd2677f49e3db594b9077d9788be27548a1fee306"} 14:54:32 [14:54:32.975] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:32 [14:54:32.977] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:54:33 [14:54:33.024] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:33 [14:54:33.328] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:33 [14:54:33.656] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:33 [14:54:33.803] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1b040f08b4f3be1d37fac3e8fe06f000153fa78340831d3e73229fa7654d474a","blockNumber":5,"slot":6} 14:54:34 [14:54:34.023] INFO: pxe:service Proving completed in 2026.1690979999985ms {"timings":{"total":2026.1690979999985,"sync":2.2881780000025174,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":513.964006000002,"oracles":{"debugLog":{"times":[0.6061159999953816,0.5107229999994161,0.6095159999968018,0.5343040000007022,0.514273000000685,0.3266989999974612,0.4365209999959916,0.7075180000028922]},"fetchTaggedLogs":{"times":[78.21663599999738]},"loadCapsule":{"times":[0.3279379999949015,0.34203799999522744,13.069252000001143,1.072027000001981,0.3607090000004973,0.26392699999996694]},"getSharedSecret":{"times":[21.003183000000718]},"aes128Decrypt":{"times":[0.7443190000049071,1.7290839999986929]},"getPublicKeysAndPartialAddress":{"times":[73.01569300000119]},"getKeyValidationRequest":{"times":[36.985218999994686]},"deliverNote":{"times":[9.67112499999348]},"deleteCapsule":{"times":[0.2378960000060033]},"storeCapsule":{"times":[0.8421110000053886]},"getNotes":{"times":[2.293837999997777]},"getAuthWitness":{"times":[0.2867869999972754]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.09077200000319863]},"callPrivateFunction":{"times":[590.0210490000027]}}},{"functionName":"private_kernel_init","time":57.1551399999953},{"functionName":"Test:set_tx_max_block_number","time":454.86342799999693,"oracles":{"debugLog":{"times":[0.6247460000013234,0.33387799999763956]},"fetchTaggedLogs":{"times":[166.83654500000557]},"loadCapsule":{"times":[0.15870399999403162,0.14537399999971967,0.14295400000264635]},"storeInExecutionCache":{"times":[0.037110999997821636]},"notifyEnqueuedPublicFunctionCall":{"times":[0.038550999997823965]}}},{"functionName":"private_kernel_inner","time":107.51878900000156},{"functionName":"private_kernel_reset","time":155.98870100000204},{"functionName":"private_kernel_tail","time":72.26723499999935}],"unaccounted":662.1236209999988}} 14:54:34 [14:54:34.047] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:54:34 [14:54:34.062] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x2bfcd202047ba67e3e993ea697ccafa0a3c3ad6ec2f93c4b30bc7ddd8f270ee0 for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 14:54:34 [14:54:34.066] WARN: node Received invalid tx 0x2bfcd202047ba67e3e993ea697ccafa0a3c3ad6ec2f93c4b30bc7ddd8f270ee0: Invalid max block number {"txHash":"0x2bfcd202047ba67e3e993ea697ccafa0a3c3ad6ec2f93c4b30bc7ddd8f270ee0"} 14:54:34 [14:54:34.070] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 14:54:34 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 14:54:34 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 14:54:34 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 14:54:34 [14:54:34.073] INFO: node Stopping 14:54:34 [14:54:34.074] INFO: slasher Slasher client stopped. 14:54:34 [14:54:34.075] INFO: sequencer Stopped sequencer 14:54:34 [14:54:34.075] INFO: p2p P2P client stopped. 14:54:34 [14:54:34.076] INFO: world_state Stopped world state synchronizer 14:54:34 [14:54:34.077] INFO: archiver Stopped. 14:54:34 [14:54:34.077] INFO: node Stopped 14:54:34 [14:54:34.077] VERBOSE: e2e:e2e_max_block_number Cleaning up ACVM state 14:54:34 [14:54:34.077] INFO: e2e:e2e_max_block_number Cleaning up ACVM temp directory /tmp/dbea358f 14:54:34 [14:54:34.078] VERBOSE: e2e:e2e_max_block_number Cleaning up BB state 14:54:34 [14:54:34.090] INFO: blob-sink:server Stopping blob sink 14:54:34 [14:54:34.090] INFO: blob-sink:server Blob sink stopped 14:54:34 [14:54:34.091] VERBOSE: e2e:e2e_max_block_number Cleaning up data directory at /tmp/2884ef46cff5f2bd 14:54:34 PASS src/e2e_max_block_number.test.ts 14:54:34 e2e_max_block_number 14:54:34 when requesting max block numbers higher than the mined one 14:54:34 with no enqueued public calls 14:54:34 ✓ sets the max block number (2750 ms) 14:54:34 ✓ does not invalidate the transaction (3065 ms) 14:54:34 with an enqueued public call 14:54:34 ✓ sets the max block number (2371 ms) 14:54:34 ✓ does not invalidate the transaction (3100 ms) 14:54:34 when requesting max block numbers lower than the mined one 14:54:34 with no enqueued public calls 14:54:34 ✓ sets the max block number (2091 ms) 14:54:34 ✓ invalidates the transaction (1757 ms) 14:54:34 with an enqueued public call 14:54:34 ✓ sets the max block number (1756 ms) 14:54:34 ✓ invalidates the transaction (2090 ms) 14:54:34 14:54:34 Test Suites: 1 passed, 1 total 14:54:34 Tests: 8 passed, 8 total 14:54:34 Snapshots: 0 total 14:54:34 Time: 41.386 s 14:54:34 Ran all test suites matching /src\/e2e_max_block_number.test.ts/i. 14:54:34 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?