Parent Log: http://ci.aztec-labs.com/a0947d4e5c3fb9d9 Command: 3cad4e1fd0756a5c:ISOLATE=1:NAME=e2e_max_block_number yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_max_block_number.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/e9a2301883801edbe36ea8bba05dcca7e714f30a Env: REF_NAME=gh-readonly-queue/master/pr-15131-464e1a9d9b40b5188586643b44bc52bffccbc3e6 CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Wed Jun 18 12:49:48 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15131_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_d488fdfe97c9ae48_master 12:49:48 +++ id -u 12:49:48 +++ id -g 12:49:48 ++ 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'\''' 12:49:49 + cid=cb1f3edef7c7a27c025c5924015ceca0ca7bf5b8d19dc480a10c8c333724e0bf 12:49:49 + set +x 12:49:49 {"level":25,"time":1750250989797,"pid":12,"hostname":"cb1f3edef7c7","module":"logger","msg":"Logger initialized with level verbose"} 12:49:57 [12:49:57.098] INFO: e2e:e2e_max_block_number Logging metrics to log/unknown.jsonl 12:49:57 [12:49:57.409] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-LQiNld/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 12:49:57 [12:49:57.449] INFO: e2e:e2e_max_block_number Calling anvil_setBlockTimestampInterval with params: [12] 12:49:57 [12:49:57.472] WARN: e2e:e2e_max_block_number Set block interval to 12 12:49:57 [12:49:57.472] VERBOSE: e2e:e2e_max_block_number Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 12:49:57 [12:49:57.549] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 12:49:57 [12:49:57.559] VERBOSE: e2e:e2e_max_block_number Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 12:49:57 [12:49:57.582] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 12:49:57 [12:49:57.589] VERBOSE: e2e:e2e_max_block_number Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 12:49:57 [12:49:57.609] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 12:49:57 [12:49:57.617] VERBOSE: e2e:e2e_max_block_number Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 12:49:57 [12:49:57.636] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 12:49:57 [12:49:57.666] VERBOSE: e2e:e2e_max_block_number Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 12:49:57 [12:49:57.702] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 12:49:57 [12:49:57.709] VERBOSE: e2e:e2e_max_block_number Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 12:49:57 [12:49:57.732] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 12:49:57 [12:49:57.738] VERBOSE: e2e:e2e_max_block_number Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 12:49:57 [12:49:57.739] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 12:49:57 [12:49:57.739] INFO: e2e:e2e_max_block_number Waiting for 6 transactions to be mined... 12:49:57 [12:49:57.763] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:49:57 [12:49:57.785] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 12:49:57 [12:49:57.785] VERBOSE: e2e:e2e_max_block_number Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 12:49:57 [12:49:57.802] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 12:49:57 [12:49:57.824] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 12:49:57 [12:49:57.848] VERBOSE: e2e:e2e_max_block_number Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 12:49:57 [12:49:57.882] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 12:49:57 [12:49:57.883] VERBOSE: e2e:e2e_max_block_number Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 12:49:57 [12:49:57.910] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 12:49:57 [12:49:57.920] VERBOSE: e2e:e2e_max_block_number Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 12:49:57 [12:49:57.933] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 12:49:57 [12:49:57.934] VERBOSE: e2e:e2e_max_block_number Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 12:49:57 [12:49:57.934] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 12:49:57 [12:49:57.934] INFO: e2e:e2e_max_block_number Waiting for 8 transactions to be mined... 12:49:57 [12:49:57.954] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:49:57 [12:49:57.970] VERBOSE: e2e:e2e_max_block_number Deployed shared contracts 12:49:57 [12:49:57.993] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 12:49:57 [12:49:57.993] VERBOSE: e2e:e2e_max_block_number Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 12:49:57 [12:49:57.997] VERBOSE: e2e:e2e_max_block_number Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 12:49:58 [12:49:58.009] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 12:49:58 [12:49:58.076] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 12:49:58 [12:49:58.088] VERBOSE: e2e:e2e_max_block_number Awaiting for linked libraries to be deployed 12:49:58 [12:49:58.151] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x558572bd4fdb3dac3606992dee5f915095dc0605e4a7eef67472e411833c5617 {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 12:49:58 [12:49:58.159] VERBOSE: e2e:e2e_max_block_number Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016 {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 12:49:58 [12:49:58.159] INFO: e2e:e2e_max_block_number Waiting for 9 transactions to be mined... 12:49:58 [12:49:58.182] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:49:58 [12:49:58.182] VERBOSE: e2e:e2e_max_block_number All core contracts have been deployed 12:49:58 [12:49:58.212] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 12:49:58 [12:49:58.212] VERBOSE: e2e:e2e_max_block_number Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 12:49:58 [12:49:58.228] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 12:49:58 [12:49:58.234] VERBOSE: e2e:e2e_max_block_number Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 12:49:58 [12:49:58.264] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 12:49:58 [12:49:58.265] VERBOSE: e2e:e2e_max_block_number Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 12:49:58 [12:49:58.265] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 12:49:58 [12:49:58.293] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:49:58 [12:49:58.303] VERBOSE: e2e:e2e_max_block_number Rollup deployed 12:49:58 [12:49:58.304] VERBOSE: e2e:e2e_max_block_number Waiting for rollup and slash factory to be deployed 12:49:58 [12:49:58.304] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 12:49:58 [12:49:58.321] INFO: e2e:e2e_max_block_number All transactions mined successfully 12:49:58 [12:49:58.321] VERBOSE: e2e:e2e_max_block_number All transactions for L1 deployment have been mined 12:49:58 [12:49:58.346] INFO: e2e:e2e_max_block_number Aztec L1 contracts initialized {"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 12:49:58 [12:49:58.348] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 12:49:58 [12:49:58.351] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:49:58 [12:49:58.351] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 12:49:58 [12:49:58.352] INFO: telemetry:client Using NoopTelemetryClient 12:49:58 [12:49:58.357] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 12:49:58 [12:49:58.357] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 12:49:58 [12:49:58.358] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/a47420aa8ed7ece3/blob-sink with map size 134217728 KB (LMDB v2) 12:49:58 [12:49:58.360] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:49:58 [12:49:58.362] INFO: aztecjs:utils:watcher New L1 time: 1750251237 12:49:58 [12:49:58.362] WARN: foundation:test-date-provider Time set to 2025-06-18T12:53:57.000Z {"offset":238638,"timeMs":1750251237000} 12:49:58 [12:49:58.364] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 12:49:58 [12:49:58.370] INFO: blob-sink:server Server is running on http://localhost:46469 12:49:58 [12:49:58.371] VERBOSE: e2e:e2e_max_block_number Creating and synching an aztec node... 12:49:58 [12:49:58.378] VERBOSE: e2e:e2e_max_block_number Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/fc43aa35/acvm 12:49:58 [12:49:58.379] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:46469"} 12:49:58 [12:49:58.405] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 12:49:58 [12:49:58.406] INFO: archiver:lmdb Creating archiver data store at directory /tmp/a47420aa8ed7ece3/archiver with map size 134217728 KB (LMDB v2) 12:49:58 [12:49:58.408] INFO: archiver:lmdb Starting data store with maxReaders 16 12:49:58 [12:49:58.622] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:49:58 [12:49:58.710] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:46469"} 12:49:58 [12:49:58.725] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:46469"} 12:49:58 [12:49:58.726] WARN: node:blob-sink:client No L1 consensus host urls configured 12:49:58 [12:49:58.726] WARN: node:blob-sink:client No archive client configured 12:49:58 [12:49:58.728] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x10ff2ac07c4c18cf3b5684f561f7638cc72e1624d150fababdd97712803bf1c9 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0xe3ac9c5ee8d69f0b5ba21d675f52117841be264d8a8b1b24b3ab2d0c5832f7e5","l1BlockNumber":16}} 12:49:58 [12:49:58.740] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 12:49:58 [12:49:58.748] INFO: world-state:database Creating world state data store at directory /tmp/a47420aa8ed7ece3/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 12:49:58 [12:49:58.772] INFO: world_state Created world state synchroniser with block history of 64 12:49:58 [12:49:58.772] WARN: node Aztec node is accepting fake proofs 12:49:58 [12:49:58.780] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/a47420aa8ed7ece3/p2p with map size 134217728 KB (LMDB v2) 12:49:58 [12:49:58.782] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 12:49:58 [12:49:58.783] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/a47420aa8ed7ece3/p2p-archive with map size 134217728 KB (LMDB v2) 12:49:58 [12:49:58.784] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 12:49:58 [12:49:58.785] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/a47420aa8ed7ece3/p2p-peers with map size 134217728 KB (LMDB v2) 12:49:58 [12:49:58.786] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 12:49:58 [12:49:58.787] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 12:49:58 [12:49:58.788] VERBOSE: p2p P2P is disabled. Using dummy P2P service 12:49:58 [12:49:58.790] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 12:49:58 [12:49:58.791] INFO: world_state Started world state synchronizer from block 1 12:49:58 [12:49:58.793] VERBOSE: world-state:block_stream Starting sync from block number 0 12:49:58 [12:49:58.794] INFO: p2p Starting p2p client from block 0 with empty mempools 12:49:58 [12:49:58.795] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 12:49:58 [12:49:58.796] INFO: slasher Slasher client initialized 12:49:58 [12:49:58.796] INFO: slasher Starting Slasher client... 12:49:58 [12:49:58.824] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 12:49:58 [12:49:58.825] VERBOSE: node All Aztec Node subsystems synced 12:49:58 [12:49:58.832] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:49:58 [12:49:58.834] INFO: sequencer-client Deploying forwarder contract 12:49:58 [12:49:58.839] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 12:49:58 [12:49:58.854] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 12:49:58 [12:49:58.854] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 12:49:58 [12:49:58.860] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 12:49:58 [12:49:58.879] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 12:49:58 [12:49:58.879] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 12:49:58 [12:49:58.880] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/fc43aa35/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 12:49:59 [12:49:59.140] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 12:49:59 [12:49:59.147] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 12:49:59 [12:49:59.150] INFO: node Aztec Node version: 0.87.8 12:49:59 [12:49:59.150] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","feeAssetHandlerAddress":"0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6","stakingAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788"} 12:49:59 [12:49:59.153] VERBOSE: e2e:e2e_max_block_number Creating a pxe... 12:49:59 [12:49:59.165] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:49:59 [12:49:59.178] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/af9f9099073d1bd1/pxe_data with map size 134217728 KB (LMDB v2) 12:49:59 [12:49:59.197] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x03d4e43c9273c6164e55bf1dc3400c0d7d5c881a64d9eee04959da4027baf88b","blockNumber":1,"slot":2} 12:49:59 [12:49:59.199] INFO: pxe:data:lmdb Starting data store with maxReaders 16 12:49:59 [12:49:59.400] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:49:59 [12:49:59.488] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 12:49:59 [12:49:59.489] INFO: pxe:service Started PXE connected to chain 31337 version 1007235379 12:49:59 [12:49:59.554] INFO: pxe:service Registered account 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 12:49:59 [12:49:59.630] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:49:59 [12:49:59.632] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 12:49:59 [12:49:59.633] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 12:49:59 [12:49:59.771] INFO: pxe:service Added contract SchnorrAccount at 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 12:49:59 [12:49:59.778] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x03d4e43c9273c6164e55bf1dc3400c0d7d5c881a64d9eee04959da4027baf88b","blockNumber":1,"slot":2} 12:49:59 [12:49:59.819] VERBOSE: pxe:block_stream Starting sync from block number 0 12:49:59 [12:49:59.861] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 12:49:59 [12:49:59.909] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:49:59 [12:49:59.960] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 12:50:00 [12:50:00.651] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 12:50:00 [12:50:00.806] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 12:50:00 [12:50:00.882] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:00 [12:50:00.914] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x03d4e43c9273c6164e55bf1dc3400c0d7d5c881a64d9eee04959da4027baf88b","blockNumber":1,"slot":2} 12:50:00 [12:50:00.998] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:01 [12:50:01.052] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:01 [12:50:01.054] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:01 [12:50:01.096] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:01 [12:50:01.137] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:01 [12:50:01.149] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:01 [12:50:01.321] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:01 [12:50:01.346] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:01 [12:50:01.392] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:01 [12:50:01.393] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:01 [12:50:01.441] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x03d4e43c9273c6164e55bf1dc3400c0d7d5c881a64d9eee04959da4027baf88b","blockNumber":1,"slot":2} 12:50:01 [12:50:01.446] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:01 [12:50:01.451] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:01 [12:50:01.467] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:01 [12:50:01.668] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:02 [12:50:02.088] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:02 [12:50:02.219] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x03d4e43c9273c6164e55bf1dc3400c0d7d5c881a64d9eee04959da4027baf88b","blockNumber":1,"slot":2} 12:50:02 [12:50:02.379] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:02 [12:50:02.861] INFO: pxe:service Proving completed in 3044.8784749999995ms {"timings":{"total":3044.8784749999995,"sync":3.3184439999986353,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":165.0119440000035,"oracles":{"callPrivateFunction":{"times":[957.1132290000005,395.0590169999996,223.72856599999977]}}},{"functionName":"private_kernel_init","time":117.6743690000003},{"functionName":"ContractClassRegisterer:register","time":938.2499920000009,"oracles":{"loadCapsule":{"times":[11.705168000000413]},"notifyCreatedNullifier":{"times":[13.748663000000306]},"debugLog":{"times":[1.0728859999999258]},"notifyCreatedContractClassLog":{"times":[10.910420000000158]}}},{"functionName":"private_kernel_inner","time":226.62573300000076},{"functionName":"SchnorrAccount:constructor","time":373.61312199999884,"oracles":{"getContractInstance":{"times":[2.3947170000010374]},"debugLog":{"times":[0.6802619999998569,0.665750999998636]},"fetchTaggedLogs":{"times":[92.4181410000001]},"loadCapsule":{"times":[0.7466459999996005,0.41099600000052305,0.32980099999986123]},"notifyCreatedNullifier":{"times":[0.41540600000007544,0.31819999999970605]},"notifyCreatedNote":{"times":[0.8860039999999572]},"getRandomField":{"times":[0.5934170000000449,0.22630299999946146,0.06538399999953981,0.07251399999950081,0.046983000000182074,0.04744300000129442,0.07122499999968568,0.05754400000114401,0.08511499999985972,0.09321499999896332,0.06804399999964517]},"getIndexedTaggingSecretAsSender":{"times":[35.29210500000045]},"incrementAppTaggingSecretIndexAsSender":{"times":[34.23007099999995]}}},{"functionName":"private_kernel_inner","time":150.98090299999967},{"functionName":"SchnorrAccount:entrypoint","time":188.0512269999981,"oracles":{"debugLog":{"times":[0.8481420000007347,0.7622169999995094,0.881413999999495]},"fetchTaggedLogs":{"times":[55.189446000000316]},"loadCapsule":{"times":[0.30933899999945424,0.5872259999996459,0.35953199999858043]},"getNotes":{"times":[2.107598999999027]},"getAuthWitness":{"times":[0.3122690000000148]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.2392959999997402]}}},{"functionName":"private_kernel_inner","time":138.50852799999848},{"functionName":"private_kernel_reset","time":161.19614},{"functionName":"private_kernel_tail","time":77.74967999999899}],"unaccounted":503.8983930000013}} 12:50:02 [12:50:02.876] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:02 [12:50:02.987] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x03d4e43c9273c6164e55bf1dc3400c0d7d5c881a64d9eee04959da4027baf88b","blockNumber":1,"slot":2} 12:50:02 [12:50:02.995] VERBOSE: p2p:tx_pool Adding tx 0x164ecd73f6c7ca717625ffdf01b7d9c65f1e2748cd040b9cfdf20c96713c9bba to pool {"eventName":"tx-added-to-pool","txHash":"0x164ecd73f6c7ca717625ffdf01b7d9c65f1e2748cd040b9cfdf20c96713c9bba","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 12:50:03 [12:50:03.011] INFO: node Received tx 0x164ecd73f6c7ca717625ffdf01b7d9c65f1e2748cd040b9cfdf20c96713c9bba {"txHash":"0x164ecd73f6c7ca717625ffdf01b7d9c65f1e2748cd040b9cfdf20c96713c9bba"} 12:50:03 [12:50:03.012] INFO: pxe:service Sent transaction 0x164ecd73f6c7ca717625ffdf01b7d9c65f1e2748cd040b9cfdf20c96713c9bba 12:50:03 [12:50:03.083] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:03 [12:50:03.288] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:03 [12:50:03.492] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:03 [12:50:03.507] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 12:50:03 [12:50:03.523] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-18T12:54:21.000Z","now":"2025-06-18T12:54:02.161Z"} 12:50:03 [12:50:03.715] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:03 [12:50:03.774] VERBOSE: simulator:public-processor Processed tx 0x164ecd73f6c7ca717625ffdf01b7d9c65f1e2748cd040b9cfdf20c96713c9bba with no public calls in 182.04334899999958ms {"txHash":"0x164ecd73f6c7ca717625ffdf01b7d9c65f1e2748cd040b9cfdf20c96713c9bba","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":182.04334899999958} 12:50:03 [12:50:03.776] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.25172425300000034s {"duration":0.25172425300000034,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 12:50:03 [12:50:03.922] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x02e3625fdf72191e15c75d197c88e5d91e60d6cc36a9c724b68e76d23ca8c591","globalVariables":{"chainId":31337,"version":1007235379,"blockNumber":1,"slotNumber":2,"timestamp":1750251261,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x164ecd73f6c7ca717625ffdf01b7d9c65f1e2748cd040b9cfdf20c96713c9bba"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":414.50828999999976,"publicProcessDuration":252.81535000000076,"rollupCircuitsDuration":400.82316100000025,"txCount":1,"blockNumber":1,"blockTimestamp":1750251261,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 12:50:03 [12:50:03.924] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:03 [12:50:03.927] VERBOSE: sequencer Attesting committee is empty 12:50:04 [12:50:04.127] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:04 [12:50:04.350] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:04 [12:50:04.357] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xc7fb46142a462813d0fda20697ce941ffd71f7bb30aa376f1cbc2e3b544e2990 12:50:04 [12:50:04.357] VERBOSE: sequencer-client Sent L1 transaction 0xc7fb46142a462813d0fda20697ce941ffd71f7bb30aa376f1cbc2e3b544e2990 {"gasLimit":514585,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 12:50:04 [12:50:04.382] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 12:50:04 [12:50:04.383] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xc7fb46142a462813d0fda20697ce941ffd71f7bb30aa376f1cbc2e3b544e2990","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":1,"blockTimestamp":1750251261,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 12:50:04 [12:50:04.384] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 12:50:04 [12:50:04.407] INFO: blob-sink:server Received blob sidecar for block 0x465a2bda1d2e6c8ddc2e90c848bd4518fa55f484d16198b80abca3cd02cd580a 12:50:04 [12:50:04.419] INFO: blob-sink:server Blob sidecar stored successfully for block 0x465a2bda1d2e6c8ddc2e90c848bd4518fa55f484d16198b80abca3cd02cd580a 12:50:04 [12:50:04.574] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 12:50:04 [12:50:04.576] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:04 [12:50:04.586] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 12:50:04 [12:50:04.594] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x02e3625fdf72191e15c75d197c88e5d91e60d6cc36a9c724b68e76d23ca8c591","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":1007235379,"blockNumber":1,"slotNumber":2,"timestamp":1750251261,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2d891e38b6b338539d82215c158a2f557b6351890ac3a752227bc2eba3f8dc86","archiveNextLeafIndex":2} 12:50:04 [12:50:04.598] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 12:50:04 [12:50:04.606] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 12:50:04 [12:50:04.609] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750251297] on http://127.0.0.1:8545 12:50:04 [12:50:04.612] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 12:50:04 [12:50:04.614] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750251297 12:50:04 [12:50:04.615] WARN: foundation:test-date-provider Time set to 2025-06-18T12:54:57.000Z {"offset":292385,"timeMs":1750251297000} 12:50:04 [12:50:04.615] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 12:50:05 [12:50:05.036] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:05 [12:50:05.044] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":365.74185899999975,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750251261,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 12:50:05 [12:50:05.046] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 12:50:05 [12:50:05.052] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b 12:50:05 [12:50:05.249] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:05 [12:50:05.349] VERBOSE: p2p Synched to latest block 1 12:50:05 [12:50:05.350] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 12:50:05 [12:50:05.614] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:05 [12:50:05.626] INFO: pxe:service Added contract Test at 0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 12:50:05 [12:50:05.667] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x2d891e38b6b338539d82215c158a2f557b6351890ac3a752227bc2eba3f8dc86","header":{"contentCommitment":{"blobsHash":"0x00ea66067c2c631fa46f6e81496c8c05a651318ecbe86d074d7b1c0564f667b8","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750251261,"version":1007235379},"lastArchive":"0x03d4e43c9273c6164e55bf1dc3400c0d7d5c881a64d9eee04959da4027baf88b","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x05bb3bdad53a4a939aeb2bd1c13041e1969083d47c898cc2a87e3abc635dc7c8","nullifierTree":"0x01c6dc449bb40de971e7168b32864d02369f3778e6d7e9496af9edaa372713d0","publicDataTree":"0x00b9641d961ea30397b51f215f236ae763cb93556c0dc7d80108637c21d59063"},"totalFees":6794052480,"totalManaUsed":20448}} 12:50:05 [12:50:05.786] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:05 [12:50:05.832] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:05 [12:50:05.833] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:05 [12:50:05.834] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:05 [12:50:05.884] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2d891e38b6b338539d82215c158a2f557b6351890ac3a752227bc2eba3f8dc86","blockNumber":2,"slot":3} 12:50:05 [12:50:05.913] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:05 [12:50:05.917] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x037b6efe9d7fa63214444b7c867d4e08a4d1ddf373a0625ff318ff571cace3df 12:50:05 [12:50:05.934] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:50:05 [12:50:05.935] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:50:05 [12:50:05.951] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:50:05 [12:50:05.952] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:50:05 [12:50:05.961] VERBOSE: pxe:service Added note {"index":0,"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x03c27c43b7ec6b889a991ed897ba314556b2f63bdac64f585d2863c773f6361e","nullifier":"0x15fb928d8492bf8a997e2615a3ffc4ba82de5697e43dade4a33dd7f4e17c9a67"} 12:50:05 [12:50:05.963] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:05 [12:50:05.982] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:06 [12:50:06.015] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 12:50:06 [12:50:06.432] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 12:50:06 [12:50:06.533] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 12:50:06 [12:50:06.590] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:06 [12:50:06.668] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 12:50:06 [12:50:06.680] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2d891e38b6b338539d82215c158a2f557b6351890ac3a752227bc2eba3f8dc86","blockNumber":2,"slot":3} 12:50:06 [12:50:06.727] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 12:50:06 [12:50:06.743] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b,0x0000000000000000000000000000000000000000000000000000000000000001,0x256d7645f33ec65647125bf915076164c75cb30f93773ccc6e1f08c0e626370c,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 12:50:07 [12:50:07.069] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:07 [12:50:07.458] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:07 [12:50:07.483] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:07 [12:50:07.483] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:07 [12:50:07.509] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:07 [12:50:07.733] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:07 [12:50:07.735] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2d891e38b6b338539d82215c158a2f557b6351890ac3a752227bc2eba3f8dc86","blockNumber":2,"slot":3} 12:50:07 [12:50:07.780] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:07 [12:50:07.787] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:08 [12:50:08.028] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:08 [12:50:08.287] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:08 [12:50:08.634] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:08 [12:50:08.636] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2d891e38b6b338539d82215c158a2f557b6351890ac3a752227bc2eba3f8dc86","blockNumber":2,"slot":3} 12:50:08 [12:50:08.884] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:09 [12:50:09.286] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:09 [12:50:09.689] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2d891e38b6b338539d82215c158a2f557b6351890ac3a752227bc2eba3f8dc86","blockNumber":2,"slot":3} 12:50:10 [12:50:10.202] INFO: pxe:service Proving completed in 4556.2099259999995ms {"timings":{"total":4556.2099259999995,"sync":27.983156999998755,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":488.16661100000056,"oracles":{"debugLog":{"times":[0.7078440000004775,0.35427100000015344,0.7937679999995453,0.6312790000010864,0.7529859999995097,0.3850739999979851,0.4397370000006049,0.9556179999999586]},"fetchTaggedLogs":{"times":[82.66306199999963]},"loadCapsule":{"times":[0.3424209999975574,0.30893899999864516,0.40174499999920954,0.31003899999996065,0.2946979999978794,0.20116200000120443]},"getSharedSecret":{"times":[4.727579999998852]},"aes128Decrypt":{"times":[2.6640430000006745,1.6092879999996512]},"getPublicKeysAndPartialAddress":{"times":[7.631139000000985]},"getKeyValidationRequest":{"times":[4.521077000001242]},"deliverNote":{"times":[9.222866000000067]},"deleteCapsule":{"times":[0.538023000000976]},"storeCapsule":{"times":[0.3618219999989378]},"getNotes":{"times":[2.5267449999992095]},"getAuthWitness":{"times":[0.3555220000016561]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.10882599999968079]},"callPrivateFunction":{"times":[603.8003140000001,163.79174899999998,1041.1172329999972]}}},{"functionName":"private_kernel_init","time":91.40161700000317},{"functionName":"ContractClassRegisterer:register","time":592.7792969999973,"oracles":{"loadCapsule":{"times":[7.908186000000569]},"notifyCreatedNullifier":{"times":[0.45897799999875133]},"debugLog":{"times":[0.5837760000031267]},"notifyCreatedContractClassLog":{"times":[10.087468000001536]}}},{"functionName":"private_kernel_inner","time":148.3002090000009},{"functionName":"ContractInstanceDeployer:deploy","time":103.18217899999945,"oracles":{"storeInExecutionCache":{"times":[0.24086499999975786]},"callPrivateFunction":{"times":[65.5325010000015]},"notifyCreatedNullifier":{"times":[0.412425999998959]},"debugLog":{"times":[0.6701610000018263]}}},{"functionName":"private_kernel_inner","time":206.50711899999806},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":37.602907000000414,"oracles":{}},{"functionName":"private_kernel_inner","time":165.24018799999976},{"functionName":"Test:initialize","time":883.8282130000007,"oracles":{"getContractInstance":{"times":[5.854189000001497]},"debugLog":{"times":[0.6765720000003057,0.618107999998756]},"fetchTaggedLogs":{"times":[301.4139009999999]},"loadCapsule":{"times":[0.5075710000019171,0.3054389999997511,0.3487009999989823]},"notifyCreatedNullifier":{"times":[0.40118400000210386]}}},{"functionName":"private_kernel_inner","time":164.045603999999},{"functionName":"private_kernel_reset","time":202.91290899999876},{"functionName":"private_kernel_tail","time":139.6613580000012}],"unaccounted":1304.5985580000015}} 12:50:10 [12:50:10.225] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:10 [12:50:10.433] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2d891e38b6b338539d82215c158a2f557b6351890ac3a752227bc2eba3f8dc86","blockNumber":2,"slot":3} 12:50:10 [12:50:10.446] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:10 [12:50:10.449] VERBOSE: p2p:tx_pool Adding tx 0x1ebba2d657a41f38c4cf7b82fde9e6aea994263c343326dd906a4b74cbe276ea to pool {"eventName":"tx-added-to-pool","txHash":"0x1ebba2d657a41f38c4cf7b82fde9e6aea994263c343326dd906a4b74cbe276ea","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 12:50:10 [12:50:10.474] INFO: node Received tx 0x1ebba2d657a41f38c4cf7b82fde9e6aea994263c343326dd906a4b74cbe276ea {"txHash":"0x1ebba2d657a41f38c4cf7b82fde9e6aea994263c343326dd906a4b74cbe276ea"} 12:50:10 [12:50:10.475] INFO: pxe:service Sent transaction 0x1ebba2d657a41f38c4cf7b82fde9e6aea994263c343326dd906a4b74cbe276ea 12:50:10 [12:50:10.649] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:10 [12:50:10.851] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:10 [12:50:10.962] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 12:50:10 [12:50:10.981] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-18T12:54:57.000Z","now":"2025-06-18T12:55:03.366Z"} 12:50:11 [12:50:11.094] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:11 [12:50:11.346] VERBOSE: simulator:public-processor Processed tx 0x1ebba2d657a41f38c4cf7b82fde9e6aea994263c343326dd906a4b74cbe276ea with no public calls in 234.94922399999996ms {"txHash":"0x1ebba2d657a41f38c4cf7b82fde9e6aea994263c343326dd906a4b74cbe276ea","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":234.94922399999996} 12:50:11 [12:50:11.362] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:11 [12:50:11.370] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.3881029610000005s {"duration":0.3881029610000005,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 12:50:11 [12:50:11.566] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x07d73edb446db9e176b78028624c8ad68f0f5f5a5e9db7de1931619ef0faf755","globalVariables":{"chainId":31337,"version":1007235379,"blockNumber":2,"slotNumber":3,"timestamp":1750251297,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1ebba2d657a41f38c4cf7b82fde9e6aea994263c343326dd906a4b74cbe276ea"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":604.0688480000026,"publicProcessDuration":389.28403700000126,"rollupCircuitsDuration":586.8237009999975,"txCount":1,"blockNumber":2,"blockTimestamp":1750251297,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 12:50:11 [12:50:11.572] VERBOSE: sequencer Attesting committee is empty 12:50:11 [12:50:11.669] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:11 [12:50:11.896] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:11 [12:50:11.905] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x44e8fc6c1aa2fd52199ae03118a7cc10b27c12db95bc22b76f1b79760e577d61 12:50:11 [12:50:11.905] VERBOSE: sequencer-client Sent L1 transaction 0x44e8fc6c1aa2fd52199ae03118a7cc10b27c12db95bc22b76f1b79760e577d61 {"gasLimit":470265,"maxFeePerGas":"1.303611208","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 12:50:11 [12:50:11.920] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 12:50:11 [12:50:11.923] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 12:50:11 [12:50:11.926] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750251333] on http://127.0.0.1:8545 12:50:11 [12:50:11.928] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 12:50:11 [12:50:11.930] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 12:50:11 [12:50:11.934] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 12:50:11 [12:50:11.934] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x44e8fc6c1aa2fd52199ae03118a7cc10b27c12db95bc22b76f1b79760e577d61","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":2,"blockTimestamp":1750251297,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 12:50:11 [12:50:11.935] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 12:50:11 [12:50:11.938] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750251333 12:50:11 [12:50:11.939] WARN: foundation:test-date-provider Time set to 2025-06-18T12:55:33.000Z {"offset":321061,"timeMs":1750251333000} 12:50:11 [12:50:11.939] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 12:50:11 [12:50:11.949] INFO: blob-sink:server Received blob sidecar for block 0x6885d13c3cec49e5761d0102835b128029f10d07119a52f15fb4bedbb25637ab 12:50:11 [12:50:11.970] INFO: blob-sink:server Blob sidecar stored successfully for block 0x6885d13c3cec49e5761d0102835b128029f10d07119a52f15fb4bedbb25637ab 12:50:12 [12:50:12.264] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:12 [12:50:12.281] VERBOSE: archiver:block-helper Store contract instance at 0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b 12:50:12 [12:50:12.313] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 12:50:12 [12:50:12.344] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x07d73edb446db9e176b78028624c8ad68f0f5f5a5e9db7de1931619ef0faf755","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":1007235379,"blockNumber":2,"slotNumber":3,"timestamp":1750251297,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x094151a514a84510ec5579e196597f4e1d4f6b2dc857e9c13e7e7a4f692d8f1c","archiveNextLeafIndex":3} 12:50:12 [12:50:12.362] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 12:50:12 [12:50:12.387] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":7.867162999998982,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750251297,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 12:50:12 [12:50:12.472] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:12 [12:50:12.480] VERBOSE: p2p Synched to latest block 2 12:50:12 [12:50:12.771] INFO: aztecjs:deploy_sent_tx Contract 0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b successfully deployed. 12:50:12 [12:50:12.774] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with no enqueued public calls sets the max block number 12:50:12 [12:50:12.779] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:12 [12:50:12.818] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x094151a514a84510ec5579e196597f4e1d4f6b2dc857e9c13e7e7a4f692d8f1c","header":{"contentCommitment":{"blobsHash":"0x0095dc282bf1061c301d742b9b562068016a2d904c3b338b99e6cb1a46b6aabd","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750251297,"version":1007235379},"lastArchive":"0x2d891e38b6b338539d82215c158a2f557b6351890ac3a752227bc2eba3f8dc86","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x05bb3bdad53a4a939aeb2bd1c13041e1969083d47c898cc2a87e3abc635dc7c8","nullifierTree":"0x2586e28d652a4e8aeced1a2f23558d11bdd8614befdb5e3ee3972e313443f6e8","publicDataTree":"0x127d216730be2b4e2d4ff5b65e2898ce9530a49b4beb941dcfa432ee34e767fc"},"totalFees":7272506880,"totalManaUsed":21888}} 12:50:12 [12:50:12.919] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:12 [12:50:12.971] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:12 [12:50:12.972] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:12 [12:50:12.992] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:13 [12:50:13.064] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x094151a514a84510ec5579e196597f4e1d4f6b2dc857e9c13e7e7a4f692d8f1c","blockNumber":3,"slot":4} 12:50:13 [12:50:13.108] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:13 [12:50:13.116] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x037b6efe9d7fa63214444b7c867d4e08a4d1ddf373a0625ff318ff571cace3df 12:50:13 [12:50:13.135] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:50:13 [12:50:13.136] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:50:13 [12:50:13.142] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:50:13 [12:50:13.142] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:50:13 [12:50:13.146] VERBOSE: pxe:service Added note {"index":0,"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x03c27c43b7ec6b889a991ed897ba314556b2f63bdac64f585d2863c773f6361e","nullifier":"0x15fb928d8492bf8a997e2615a3ffc4ba82de5697e43dade4a33dd7f4e17c9a67"} 12:50:13 [12:50:13.151] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:13 [12:50:13.169] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:13 [12:50:13.324] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:13 [12:50:13.873] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:13 [12:50:13.895] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:13 [12:50:13.896] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:13 [12:50:13.901] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:13 [12:50:13.932] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x094151a514a84510ec5579e196597f4e1d4f6b2dc857e9c13e7e7a4f692d8f1c","blockNumber":3,"slot":4} 12:50:14 [12:50:14.112] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:14 [12:50:14.144] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:14 [12:50:14.150] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:14 [12:50:14.382] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:14 [12:50:14.917] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:15 [12:50:15.068] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x094151a514a84510ec5579e196597f4e1d4f6b2dc857e9c13e7e7a4f692d8f1c","blockNumber":3,"slot":4} 12:50:15 [12:50:15.371] INFO: pxe:service Proving completed in 2571.345709999998ms {"timings":{"total":2571.345709999998,"sync":21.006773000000976,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":556.7306310000022,"oracles":{"debugLog":{"times":[1.3945690000000468,0.49528099999952246,0.46116999999867403,0.42056699999739067,0.4108670000023267,0.29623800000263145,0.5656369999996969,0.8236829999987094]},"fetchTaggedLogs":{"times":[140.85831699999835]},"loadCapsule":{"times":[0.3295810000017809,0.24232599999959348,0.5349640000022191,0.39522499999657157,2.239493000000948,0.38761499999964144]},"getSharedSecret":{"times":[9.917884000002232]},"aes128Decrypt":{"times":[0.31897100000060163,1.0687679999973625]},"getPublicKeysAndPartialAddress":{"times":[1.6701070000017353]},"getKeyValidationRequest":{"times":[1.618943000001309]},"deliverNote":{"times":[3.8338949999997567]},"deleteCapsule":{"times":[0.5787769999988086]},"storeCapsule":{"times":[0.7014749999980268]},"getNotes":{"times":[1.777194000002055]},"getAuthWitness":{"times":[0.2834480000019539]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.09540600000036648]},"callPrivateFunction":{"times":[987.8904629999997]}}},{"functionName":"private_kernel_init","time":108.16506699999809},{"functionName":"Test:set_tx_max_block_number","time":764.8966719999953,"oracles":{"debugLog":{"times":[0.8515540000007604,0.7523980000005395]},"fetchTaggedLogs":{"times":[251.91859000000113]},"loadCapsule":{"times":[0.3441019999991113,0.31260899999688263,0.2773680000027525]}}},{"functionName":"private_kernel_inner","time":120.83236699999907},{"functionName":"private_kernel_reset","time":217.64586899999995},{"functionName":"private_kernel_tail","time":85.81833800000095}],"unaccounted":696.2499930000013}} 12:50:15 [12:50:15.378] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with no enqueued public calls does not invalidate the transaction 12:50:15 [12:50:15.383] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:15 [12:50:15.487] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:15 [12:50:15.531] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:15 [12:50:15.532] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:15 [12:50:15.590] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:15 [12:50:15.593] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x094151a514a84510ec5579e196597f4e1d4f6b2dc857e9c13e7e7a4f692d8f1c","blockNumber":3,"slot":4} 12:50:15 [12:50:15.596] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:15 [12:50:15.607] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x037b6efe9d7fa63214444b7c867d4e08a4d1ddf373a0625ff318ff571cace3df 12:50:15 [12:50:15.619] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:50:15 [12:50:15.620] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:50:15 [12:50:15.625] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:50:15 [12:50:15.626] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:50:15 [12:50:15.629] VERBOSE: pxe:service Added note {"index":0,"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x03c27c43b7ec6b889a991ed897ba314556b2f63bdac64f585d2863c773f6361e","nullifier":"0x15fb928d8492bf8a997e2615a3ffc4ba82de5697e43dade4a33dd7f4e17c9a67"} 12:50:15 [12:50:15.631] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:15 [12:50:15.644] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:15 [12:50:15.818] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:16 [12:50:16.097] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:16 [12:50:16.114] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:16 [12:50:16.114] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:16 [12:50:16.413] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:16 [12:50:16.439] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x094151a514a84510ec5579e196597f4e1d4f6b2dc857e9c13e7e7a4f692d8f1c","blockNumber":3,"slot":4} 12:50:16 [12:50:16.450] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:16 [12:50:16.453] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:16 [12:50:16.681] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:17 [12:50:17.173] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:17 [12:50:17.389] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:17 [12:50:17.414] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x094151a514a84510ec5579e196597f4e1d4f6b2dc857e9c13e7e7a4f692d8f1c","blockNumber":3,"slot":4} 12:50:17 [12:50:17.767] INFO: pxe:service Proving completed in 2363.9564979999996ms {"timings":{"total":2363.9564979999996,"sync":2.859932999999728,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":361.2827540000071,"oracles":{"debugLog":{"times":[0.5036919999984093,0.4675210000023071,0.5249729999995907,0.5384750000011991,0.4419180000004417,0.2957989999995334,0.34052100000189967,0.7664789999980712]},"fetchTaggedLogs":{"times":[73.38090299999749]},"loadCapsule":{"times":[0.3110390000001644,0.25109599999996135,0.5208329999986745,0.2080439999990631,0.2119539999985136,0.14815900000030524]},"getSharedSecret":{"times":[3.2056249999986903]},"aes128Decrypt":{"times":[0.20556299999952898,1.1724839999988035]},"getPublicKeysAndPartialAddress":{"times":[1.619724000000133]},"getKeyValidationRequest":{"times":[1.459714000000531]},"deliverNote":{"times":[3.3679860000011104]},"deleteCapsule":{"times":[0.3426419999996142]},"storeCapsule":{"times":[0.24962600000071689]},"getNotes":{"times":[1.6477259999992384]},"getAuthWitness":{"times":[0.17161100000157603]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07392499999696156]},"callPrivateFunction":{"times":[812.5162280000004]}}},{"functionName":"private_kernel_init","time":79.87681800000064},{"functionName":"Test:set_tx_max_block_number","time":669.5875179999966,"oracles":{"debugLog":{"times":[0.8457440000020142,0.3508330000004207]},"fetchTaggedLogs":{"times":[337.7174960000011]},"loadCapsule":{"times":[0.22428500000023632,0.1576599999971222,0.15930999999909545]}}},{"functionName":"private_kernel_inner","time":177.82993100000022},{"functionName":"private_kernel_reset","time":270.641767000001},{"functionName":"private_kernel_tail","time":109.8197820000023}],"unaccounted":692.0579949999919}} 12:50:17 [12:50:17.797] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:17 [12:50:17.842] VERBOSE: p2p:tx_pool Adding tx 0x0519b2409bf789804b02455e81273c53e5d48e4ed136cd2ccb87d3bac912c5c5 to pool {"eventName":"tx-added-to-pool","txHash":"0x0519b2409bf789804b02455e81273c53e5d48e4ed136cd2ccb87d3bac912c5c5","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 12:50:17 [12:50:17.847] INFO: node Received tx 0x0519b2409bf789804b02455e81273c53e5d48e4ed136cd2ccb87d3bac912c5c5 {"txHash":"0x0519b2409bf789804b02455e81273c53e5d48e4ed136cd2ccb87d3bac912c5c5"} 12:50:17 [12:50:17.847] INFO: pxe:service Sent transaction 0x0519b2409bf789804b02455e81273c53e5d48e4ed136cd2ccb87d3bac912c5c5 12:50:17 [12:50:17.948] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 12:50:17 [12:50:17.966] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-18T12:55:33.000Z","now":"2025-06-18T12:55:39.027Z"} 12:50:17 [12:50:17.994] VERBOSE: simulator:public-processor Processed tx 0x0519b2409bf789804b02455e81273c53e5d48e4ed136cd2ccb87d3bac912c5c5 with no public calls in 17.628286999999546ms {"txHash":"0x0519b2409bf789804b02455e81273c53e5d48e4ed136cd2ccb87d3bac912c5c5","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":17.628286999999546} 12:50:17 [12:50:17.996] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.028568607000001064s {"duration":0.028568607000001064,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1024,"l2Gas":5856},"totalSizeInBytes":256} 12:50:18 [12:50:18.009] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:18 [12:50:18.159] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x262f75d975bd32c53f362735925fb3eb597ee93db4ee0185038f4433bac51efc","globalVariables":{"chainId":31337,"version":1007235379,"blockNumber":3,"slotNumber":4,"timestamp":1750251333,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x0519b2409bf789804b02455e81273c53e5d48e4ed136cd2ccb87d3bac912c5c5"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":211.08329800000138,"publicProcessDuration":28.994923000002018,"rollupCircuitsDuration":195.54455400000006,"txCount":1,"blockNumber":3,"blockTimestamp":1750251333,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:50:18 [12:50:18.162] VERBOSE: sequencer Attesting committee is empty 12:50:18 [12:50:18.263] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:18 [12:50:18.453] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x029b11e62c2b857d5a85ab60f92b10e4c75fe7730967659aa93f89cdb9e77075 12:50:18 [12:50:18.453] VERBOSE: sequencer-client Sent L1 transaction 0x029b11e62c2b857d5a85ab60f92b10e4c75fe7730967659aa93f89cdb9e77075 {"gasLimit":470312,"maxFeePerGas":"1.279583409","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 12:50:18 [12:50:18.462] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 12:50:18 [12:50:18.463] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x029b11e62c2b857d5a85ab60f92b10e4c75fe7730967659aa93f89cdb9e77075","calldataSize":1444,"calldataGas":12316,"txCount":1,"blockNumber":3,"blockTimestamp":1750251333,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:50:18 [12:50:18.463] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 12:50:18 [12:50:18.467] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:18 [12:50:18.470] INFO: blob-sink:server Received blob sidecar for block 0x15dbcd176d8e555703b9ef18cce93b9a3cb7840614bf662939002fa8e56af741 12:50:18 [12:50:18.476] INFO: blob-sink:server Blob sidecar stored successfully for block 0x15dbcd176d8e555703b9ef18cce93b9a3cb7840614bf662939002fa8e56af741 12:50:18 [12:50:18.478] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 12:50:18 [12:50:18.479] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 12:50:18 [12:50:18.481] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 12:50:18 [12:50:18.487] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750251369] on http://127.0.0.1:8545 12:50:18 [12:50:18.489] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 12:50:18 [12:50:18.490] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750251369 12:50:18 [12:50:18.491] WARN: foundation:test-date-provider Time set to 2025-06-18T12:56:09.000Z {"offset":350510,"timeMs":1750251369000} 12:50:18 [12:50:18.491] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 12:50:18 [12:50:18.674] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:18 [12:50:18.877] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:19 [12:50:19.004] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x262f75d975bd32c53f362735925fb3eb597ee93db4ee0185038f4433bac51efc","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":1007235379,"blockNumber":3,"slotNumber":4,"timestamp":1750251333,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2f8057292ce474a1490a48a02385ab122c586625c33f740253a518b08d5a9261","archiveNextLeafIndex":4} 12:50:19 [12:50:19.018] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 12:50:19 [12:50:19.081] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:19 [12:50:19.101] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":6.01860399999714,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750251333,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:50:19 [12:50:19.120] VERBOSE: p2p Synched to latest block 3 12:50:19 [12:50:19.284] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:19 [12:50:19.486] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:19 [12:50:19.533] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2f8057292ce474a1490a48a02385ab122c586625c33f740253a518b08d5a9261","blockNumber":4,"slot":5} 12:50:19 [12:50:19.689] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:19 [12:50:19.855] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with an enqueued public call sets the max block number 12:50:19 [12:50:19.878] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x2f8057292ce474a1490a48a02385ab122c586625c33f740253a518b08d5a9261","header":{"contentCommitment":{"blobsHash":"0x009108ec2d35ad66aff7c2e75fd6b4a69ace14c61e308b5c66b7cb4216991e02","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750251333,"version":1007235379},"lastArchive":"0x094151a514a84510ec5579e196597f4e1d4f6b2dc857e9c13e7e7a4f692d8f1c","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x05bb3bdad53a4a939aeb2bd1c13041e1969083d47c898cc2a87e3abc635dc7c8","nullifierTree":"0x0416b667b1b139f64865ff24c58a670f1f01cd70719815767f5c9842f18adfaf","publicDataTree":"0x2d87081913e013fb6ff7ff72b0c02b79fbd921e21cb832247b793071902ee11f"},"totalFees":1945714560,"totalManaUsed":5856}} 12:50:19 [12:50:19.904] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:19 [12:50:19.945] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:19 [12:50:19.982] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:19 [12:50:19.983] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:20 [12:50:20.070] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:20 [12:50:20.073] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2f8057292ce474a1490a48a02385ab122c586625c33f740253a518b08d5a9261","blockNumber":4,"slot":5} 12:50:20 [12:50:20.079] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x037b6efe9d7fa63214444b7c867d4e08a4d1ddf373a0625ff318ff571cace3df 12:50:20 [12:50:20.093] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:50:20 [12:50:20.095] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:50:20 [12:50:20.104] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:50:20 [12:50:20.105] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:50:20 [12:50:20.108] VERBOSE: pxe:service Added note {"index":0,"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x03c27c43b7ec6b889a991ed897ba314556b2f63bdac64f585d2863c773f6361e","nullifier":"0x15fb928d8492bf8a997e2615a3ffc4ba82de5697e43dade4a33dd7f4e17c9a67"} 12:50:20 [12:50:20.110] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:20 [12:50:20.129] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:20 [12:50:20.131] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:20 [12:50:20.467] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:20 [12:50:20.602] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:20 [12:50:20.620] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:20 [12:50:20.621] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:20 [12:50:20.804] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2f8057292ce474a1490a48a02385ab122c586625c33f740253a518b08d5a9261","blockNumber":4,"slot":5} 12:50:20 [12:50:20.825] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:20 [12:50:20.847] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:20 [12:50:20.857] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:21 [12:50:21.105] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:21 [12:50:21.551] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:21 [12:50:21.706] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2f8057292ce474a1490a48a02385ab122c586625c33f740253a518b08d5a9261","blockNumber":4,"slot":5} 12:50:21 [12:50:21.969] INFO: pxe:service Proving completed in 2099.7663350000003ms {"timings":{"total":2099.7663350000003,"sync":10.370097999999416,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":377.3775489999971,"oracles":{"debugLog":{"times":[0.6650599999993574,0.3697219999994559,0.6335780000008526,0.6548999999977241,0.520541999998386,0.3450909999992291,0.3073789999980363,0.7269540000015695]},"fetchTaggedLogs":{"times":[94.52833300000202]},"loadCapsule":{"times":[0.22486399999979767,0.1888009999966016,0.32524000000194064,0.1899520000006305,0.16535899999871617,0.17253100000016275]},"getSharedSecret":{"times":[6.780550999999832]},"aes128Decrypt":{"times":[0.3333299999976589,1.1032570000024862]},"getPublicKeysAndPartialAddress":{"times":[3.769217999997636]},"getKeyValidationRequest":{"times":[1.679281999997329]},"deliverNote":{"times":[3.2525969999987865]},"deleteCapsule":{"times":[0.2710670000014943]},"storeCapsule":{"times":[0.24721499999941443]},"getNotes":{"times":[1.8695829999996931]},"getAuthWitness":{"times":[0.293177999999898]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07241400000202702]},"callPrivateFunction":{"times":[734.0350509999989]}}},{"functionName":"private_kernel_init","time":78.10360800000126},{"functionName":"Test:set_tx_max_block_number","time":593.5615960000032,"oracles":{"debugLog":{"times":[0.7047629999979108,0.5119809999996505]},"fetchTaggedLogs":{"times":[234.39518099999987]},"loadCapsule":{"times":[0.478728999998566,0.37370199999713805,0.38216300000203773]},"storeInExecutionCache":{"times":[0.05436399999962305]},"notifyEnqueuedPublicFunctionCall":{"times":[0.4249560000025667]}}},{"functionName":"private_kernel_inner","time":118.61647100000118},{"functionName":"private_kernel_reset","time":180.8351380000022},{"functionName":"private_kernel_tail","time":90.80335800000466}],"unaccounted":650.0985169999913}} 12:50:21 [12:50:21.972] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with an enqueued public call does not invalidate the transaction 12:50:21 [12:50:21.974] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:22 [12:50:22.061] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:22 [12:50:22.099] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:22 [12:50:22.099] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:22 [12:50:22.153] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:22 [12:50:22.157] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x037b6efe9d7fa63214444b7c867d4e08a4d1ddf373a0625ff318ff571cace3df 12:50:22 [12:50:22.165] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:50:22 [12:50:22.166] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:50:22 [12:50:22.171] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:50:22 [12:50:22.172] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:50:22 [12:50:22.174] VERBOSE: pxe:service Added note {"index":0,"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x03c27c43b7ec6b889a991ed897ba314556b2f63bdac64f585d2863c773f6361e","nullifier":"0x15fb928d8492bf8a997e2615a3ffc4ba82de5697e43dade4a33dd7f4e17c9a67"} 12:50:22 [12:50:22.176] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:22 [12:50:22.178] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:22 [12:50:22.189] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:22 [12:50:22.445] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:22 [12:50:22.550] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:22 [12:50:22.564] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:22 [12:50:22.565] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:22 [12:50:22.579] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2f8057292ce474a1490a48a02385ab122c586625c33f740253a518b08d5a9261","blockNumber":4,"slot":5} 12:50:22 [12:50:22.681] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:22 [12:50:22.715] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:22 [12:50:22.719] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:22 [12:50:22.899] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:23 [12:50:23.108] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:23 [12:50:23.411] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:23 [12:50:23.556] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2f8057292ce474a1490a48a02385ab122c586625c33f740253a518b08d5a9261","blockNumber":4,"slot":5} 12:50:23 [12:50:23.858] INFO: pxe:service Proving completed in 1863.9493980000043ms {"timings":{"total":1863.9493980000043,"sync":2.747386999995797,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":287.25,"oracles":{"debugLog":{"times":[0.532601999999315,0.39595399999961955,0.36799299999984214,0.4018140000043786,0.36241200000222307,0.3221400000038557,0.2656859999988228,0.40855500000179745]},"fetchTaggedLogs":{"times":[55.98195900000428]},"loadCapsule":{"times":[0.19058099999529077,0.1997830000036629,0.3837530000018887,0.18461100000422448,0.20033200000034412,0.1996720000024652]},"getSharedSecret":{"times":[2.5776259999984177]},"aes128Decrypt":{"times":[0.31801899999845773,0.920455999999831]},"getPublicKeysAndPartialAddress":{"times":[1.381213000000571]},"getKeyValidationRequest":{"times":[1.337241000001086]},"deliverNote":{"times":[2.4849909999975353]},"deleteCapsule":{"times":[0.2382339999967371]},"storeCapsule":{"times":[0.22816400000010617]},"getNotes":{"times":[3.0848469999982626]},"getAuthWitness":{"times":[0.15842899999552174]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05324300000211224]},"callPrivateFunction":{"times":[535.2933879999982]}}},{"functionName":"private_kernel_init","time":67.37127899999905},{"functionName":"Test:set_tx_max_block_number","time":426.421577000001,"oracles":{"debugLog":{"times":[0.694841999997152,0.5853560000032303]},"fetchTaggedLogs":{"times":[152.4686010000005]},"loadCapsule":{"times":[0.2531449999951292,0.2258330000040587,0.22216299999854527]},"storeInExecutionCache":{"times":[0.05407299999933457]},"notifyEnqueuedPublicFunctionCall":{"times":[0.06449399999837624]}}},{"functionName":"private_kernel_inner","time":125.18032800000219},{"functionName":"private_kernel_reset","time":208.3597940000036},{"functionName":"private_kernel_tail","time":92.95907799999986}],"unaccounted":653.6599550000028}} 12:50:23 [12:50:23.901] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:23 [12:50:23.928] VERBOSE: p2p:tx_pool Adding tx 0x0fc8e738f097dc7624ae1052918c1e343c9ae986e863e69642c99e10fe1b0c28 to pool {"eventName":"tx-added-to-pool","txHash":"0x0fc8e738f097dc7624ae1052918c1e343c9ae986e863e69642c99e10fe1b0c28","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226281,"feePaymentMethod":"fee_juice"} 12:50:23 [12:50:23.938] INFO: node Received tx 0x0fc8e738f097dc7624ae1052918c1e343c9ae986e863e69642c99e10fe1b0c28 {"txHash":"0x0fc8e738f097dc7624ae1052918c1e343c9ae986e863e69642c99e10fe1b0c28"} 12:50:23 [12:50:23.938] INFO: pxe:service Sent transaction 0x0fc8e738f097dc7624ae1052918c1e343c9ae986e863e69642c99e10fe1b0c28 12:50:24 [12:50:24.076] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 12:50:24 [12:50:24.089] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-18T12:56:09.000Z","now":"2025-06-18T12:56:14.599Z"} 12:50:24 [12:50:24.106] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:24 [12:50:24.147] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x5189a417 completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x5189a417","duration":32.149076000001514} 12:50:24 [12:50:24.167] VERBOSE: simulator:public-processor Processed tx 0x0fc8e738f097dc7624ae1052918c1e343c9ae986e863e69642c99e10fe1b0c28 with 1 public calls in 67.22196000000258ms {"txHash":"0x0fc8e738f097dc7624ae1052918c1e343c9ae986e863e69642c99e10fe1b0c28","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":67.22196000000258} 12:50:24 [12:50:24.170] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.08020697599999403s {"duration":0.08020697599999403,"rate":43425.15045075704,"totalPublicGas":{"daGas":0,"l2Gas":3483},"totalBlockGas":{"daGas":1024,"l2Gas":29339},"totalSizeInBytes":256} 12:50:24 [12:50:24.276] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 17376.84435957635 mana/s {"blockHash":"0x2845b7a4a99169a662d57b51a2afe15b1051a57add69510dea21f2cf364f55a3","globalVariables":{"chainId":31337,"version":1007235379,"blockNumber":4,"slotNumber":5,"timestamp":1750251369,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x0fc8e738f097dc7624ae1052918c1e343c9ae986e863e69642c99e10fe1b0c28"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":199.92653399999836,"publicProcessDuration":80.60119000000122,"rollupCircuitsDuration":187.2100649999993,"txCount":1,"blockNumber":4,"blockTimestamp":1750251369,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:50:24 [12:50:24.277] VERBOSE: sequencer Attesting committee is empty 12:50:24 [12:50:24.351] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:24 [12:50:24.541] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xe4c485dfc54ebb9e9744417f8238bf6c334ac93e85ae54f3874e7c48d5494f1b 12:50:24 [12:50:24.542] VERBOSE: sequencer-client Sent L1 transaction 0xe4c485dfc54ebb9e9744417f8238bf6c334ac93e85ae54f3874e7c48d5494f1b {"gasLimit":470310,"maxFeePerGas":"1.261127755","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 12:50:24 [12:50:24.553] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:24 [12:50:24.563] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 12:50:24 [12:50:24.564] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xe4c485dfc54ebb9e9744417f8238bf6c334ac93e85ae54f3874e7c48d5494f1b","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":4,"blockTimestamp":1750251369,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:50:24 [12:50:24.564] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 12:50:24 [12:50:24.568] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 12:50:24 [12:50:24.571] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 12:50:24 [12:50:24.573] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 12:50:24 [12:50:24.575] INFO: blob-sink:server Received blob sidecar for block 0x717a9a5bc03af898d4322dcbb3219544077645a8246fda02de8670c713dcee1c 12:50:24 [12:50:24.579] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750251405] on http://127.0.0.1:8545 12:50:24 [12:50:24.580] INFO: blob-sink:server Blob sidecar stored successfully for block 0x717a9a5bc03af898d4322dcbb3219544077645a8246fda02de8670c713dcee1c 12:50:24 [12:50:24.581] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 12:50:24 [12:50:24.582] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750251405 12:50:24 [12:50:24.582] WARN: foundation:test-date-provider Time set to 2025-06-18T12:56:45.000Z {"offset":380418,"timeMs":1750251405000} 12:50:24 [12:50:24.583] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 12:50:24 [12:50:24.760] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:25 [12:50:25.048] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:25 [12:50:25.060] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x2845b7a4a99169a662d57b51a2afe15b1051a57add69510dea21f2cf364f55a3","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":1007235379,"blockNumber":4,"slotNumber":5,"timestamp":1750251369,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","archiveNextLeafIndex":5} 12:50:25 [12:50:25.065] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 12:50:25 [12:50:25.074] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":7.375946000000113,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1750251369,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 12:50:25 [12:50:25.175] VERBOSE: p2p Synched to latest block 4 12:50:25 [12:50:25.252] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:25 [12:50:25.456] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:25 [12:50:25.595] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:25 [12:50:25.659] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:25 [12:50:25.861] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:25 [12:50:25.947] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with no enqueued public calls sets the max block number 12:50:25 [12:50:25.964] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","header":{"contentCommitment":{"blobsHash":"0x00daeb90ef51c419721d2d085cdb65025b5f16bc45d3a252e1f3dc45e4803943","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750251369,"version":1007235379},"lastArchive":"0x2f8057292ce474a1490a48a02385ab122c586625c33f740253a518b08d5a9261","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x05bb3bdad53a4a939aeb2bd1c13041e1969083d47c898cc2a87e3abc635dc7c8","nullifierTree":"0x2fc56b64b5253e0a3ed6250cec77ef2c40dd3d3ae7148c0d95d0b87d1bd8fd51","publicDataTree":"0x13fbba3352adfc9bb50c58507e04d0fe6281cb6dd89d63c4256857d92ee65313"},"totalFees":1487487300,"totalManaUsed":29339}} 12:50:26 [12:50:26.051] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:26 [12:50:26.093] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:26 [12:50:26.093] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:26 [12:50:26.097] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:26 [12:50:26.144] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:26 [12:50:26.157] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:26 [12:50:26.163] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x037b6efe9d7fa63214444b7c867d4e08a4d1ddf373a0625ff318ff571cace3df 12:50:26 [12:50:26.180] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:50:26 [12:50:26.181] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:50:26 [12:50:26.188] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:50:26 [12:50:26.188] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:50:26 [12:50:26.191] VERBOSE: pxe:service Added note {"index":0,"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x03c27c43b7ec6b889a991ed897ba314556b2f63bdac64f585d2863c773f6361e","nullifier":"0x15fb928d8492bf8a997e2615a3ffc4ba82de5697e43dade4a33dd7f4e17c9a67"} 12:50:26 [12:50:26.192] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:26 [12:50:26.203] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:26 [12:50:26.327] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:26 [12:50:26.549] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:26 [12:50:26.565] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:26 [12:50:26.565] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:26 [12:50:26.663] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:26 [12:50:26.690] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:26 [12:50:26.691] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:26 [12:50:26.696] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:26 [12:50:26.970] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:27 [12:50:27.452] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:27 [12:50:27.610] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:27 [12:50:27.831] INFO: pxe:service Proving completed in 1871.5855329999977ms {"timings":{"total":1871.5855329999977,"sync":6.343673999996099,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":333.3496210000085,"oracles":{"debugLog":{"times":[0.6579700000002049,0.5758249999998952,0.48509899999771733,0.6139370000018971,0.4152150000008987,0.23461500000121305,0.24962499999674037,0.4951209999999264]},"fetchTaggedLogs":{"times":[68.00524700000096]},"loadCapsule":{"times":[0.24122499999793945,0.20497299999988172,0.42636599999968894,0.17412999999942258,0.1337569999959669,0.13894900000013877]},"getSharedSecret":{"times":[8.190746000000217]},"aes128Decrypt":{"times":[0.3496309999973164,1.1373489999969024]},"getPublicKeysAndPartialAddress":{"times":[1.560604999998759]},"getKeyValidationRequest":{"times":[1.5127920000013546]},"deliverNote":{"times":[2.514392000004591]},"deleteCapsule":{"times":[0.18788099999801489]},"storeCapsule":{"times":[0.20457199999509612]},"getNotes":{"times":[1.2732969999997295]},"getAuthWitness":{"times":[0.1441290000002482]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.04374200000165729]},"callPrivateFunction":{"times":[496.0800339999987]}}},{"functionName":"private_kernel_init","time":61.56231700000353},{"functionName":"Test:set_tx_max_block_number","time":379.9124909999955,"oracles":{"debugLog":{"times":[0.6401480000058655,0.25656500000332016]},"fetchTaggedLogs":{"times":[129.67764099999476]},"loadCapsule":{"times":[0.2064819999941392,0.16664000000309898,0.24204499999905238]}}},{"functionName":"private_kernel_inner","time":118.25691299999744},{"functionName":"private_kernel_reset","time":162.36635899999965},{"functionName":"private_kernel_tail","time":69.51934600000095}],"unaccounted":740.274811999996}} 12:50:27 [12:50:27.833] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with no enqueued public calls invalidates the transaction 12:50:27 [12:50:27.837] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:27 [12:50:27.938] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:27 [12:50:27.976] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:27 [12:50:27.977] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:28 [12:50:28.043] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:28 [12:50:28.050] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:28 [12:50:28.060] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x037b6efe9d7fa63214444b7c867d4e08a4d1ddf373a0625ff318ff571cace3df 12:50:28 [12:50:28.071] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:50:28 [12:50:28.072] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:50:28 [12:50:28.079] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:50:28 [12:50:28.080] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:50:28 [12:50:28.086] VERBOSE: pxe:service Added note {"index":0,"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x03c27c43b7ec6b889a991ed897ba314556b2f63bdac64f585d2863c773f6361e","nullifier":"0x15fb928d8492bf8a997e2615a3ffc4ba82de5697e43dade4a33dd7f4e17c9a67"} 12:50:28 [12:50:28.088] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:28 [12:50:28.102] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:28 [12:50:28.375] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:28 [12:50:28.486] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:28 [12:50:28.501] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:28 [12:50:28.502] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:28 [12:50:28.642] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:28 [12:50:28.670] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:28 [12:50:28.674] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:28 [12:50:28.716] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:28 [12:50:28.949] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:29 [12:50:29.235] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:29 [12:50:29.372] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:29 [12:50:29.593] INFO: pxe:service Proving completed in 1740.1436759999997ms {"timings":{"total":1740.1436759999997,"sync":2.5359039999966626,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":344.94552300000214,"oracles":{"debugLog":{"times":[0.7786370000030729,0.4025039999978617,0.4781689999945229,0.5369720000016969,0.39809499999682885,0.3288600000014412,0.35198099999979604,0.7773769999985234]},"fetchTaggedLogs":{"times":[80.46768099999463]},"loadCapsule":{"times":[0.19367200000124285,0.16061900000204332,0.42884599999524653,0.24156399999628775,0.17932099999597995,0.19254199999704724]},"getSharedSecret":{"times":[3.5361439999978757]},"aes128Decrypt":{"times":[0.2899269999979879,1.0959769999972195]},"getPublicKeysAndPartialAddress":{"times":[1.5667749999993248]},"getKeyValidationRequest":{"times":[1.704293000002508]},"deliverNote":{"times":[6.135842999996385]},"deleteCapsule":{"times":[0.2516150000010384]},"storeCapsule":{"times":[0.24611500000173692]},"getNotes":{"times":[1.4307969999936176]},"getAuthWitness":{"times":[0.29800799999793526]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07185399999434594]},"callPrivateFunction":{"times":[577.5377700000026]}}},{"functionName":"private_kernel_init","time":63.23809500000061},{"functionName":"Test:set_tx_max_block_number","time":460.3226299999951,"oracles":{"debugLog":{"times":[0.5029500000018743,0.6885719999991124]},"fetchTaggedLogs":{"times":[170.90533600000344]},"loadCapsule":{"times":[0.25659599999926286,0.28261700000439305,0.24026500000036322]}}},{"functionName":"private_kernel_inner","time":110.2301260000022},{"functionName":"private_kernel_reset","time":164.7750440000018},{"functionName":"private_kernel_tail","time":62.86087299999781}],"unaccounted":531.2354810000033}} 12:50:29 [12:50:29.608] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:29 [12:50:29.620] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x24334099b22e547e429ca7404075c7c2b4542ad8171d8bf1522e97227d2d566d for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 12:50:29 [12:50:29.623] WARN: node Received invalid tx 0x24334099b22e547e429ca7404075c7c2b4542ad8171d8bf1522e97227d2d566d: Invalid max block number {"txHash":"0x24334099b22e547e429ca7404075c7c2b4542ad8171d8bf1522e97227d2d566d"} 12:50:29 [12:50:29.627] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 12:50:29 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 12:50:29 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 12:50:29 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 12:50:29 [12:50:29.628] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with an enqueued public call sets the max block number 12:50:29 [12:50:29.704] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:29 [12:50:29.742] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:29 [12:50:29.742] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:29 [12:50:29.815] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:29 [12:50:29.852] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:29 [12:50:29.868] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x037b6efe9d7fa63214444b7c867d4e08a4d1ddf373a0625ff318ff571cace3df 12:50:29 [12:50:29.958] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:50:29 [12:50:29.959] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:50:30 [12:50:30.002] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:50:30 [12:50:30.003] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:50:30 [12:50:30.006] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:30 [12:50:30.010] VERBOSE: pxe:service Added note {"index":0,"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x03c27c43b7ec6b889a991ed897ba314556b2f63bdac64f585d2863c773f6361e","nullifier":"0x15fb928d8492bf8a997e2615a3ffc4ba82de5697e43dade4a33dd7f4e17c9a67"} 12:50:30 [12:50:30.013] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:30 [12:50:30.051] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:30 [12:50:30.054] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:30 [12:50:30.355] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:30 [12:50:30.478] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:30 [12:50:30.490] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:30 [12:50:30.491] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:30 [12:50:30.673] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:30 [12:50:30.680] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:30 [12:50:30.684] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:30 [12:50:30.702] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:30 [12:50:30.946] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:31 [12:50:31.232] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:31 [12:50:31.425] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:31 [12:50:31.753] INFO: pxe:service Proving completed in 2115.136099999996ms {"timings":{"total":2115.136099999996,"sync":2.640129999999772,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":524.8394320000007,"oracles":{"debugLog":{"times":[0.6177069999976084,0.724263999996765,0.6294879999986733,0.6473600000026636,0.56341500000417,0.3263299999962328,0.3998340000034659,0.6374490000016522]},"fetchTaggedLogs":{"times":[122.3682420000041]},"loadCapsule":{"times":[0.372892999999749,0.28958699999930104,0.8762530000021798,0.5548229999985779,0.2237840000016149,0.18850099999690428]},"getSharedSecret":{"times":[79.41872700000386]},"aes128Decrypt":{"times":[0.7886080000025686,1.4830600000059349]},"getPublicKeysAndPartialAddress":{"times":[30.99149000000034]},"getKeyValidationRequest":{"times":[7.574809000005189]},"deliverNote":{"times":[7.982524999999441]},"deleteCapsule":{"times":[0.3152489999993122]},"storeCapsule":{"times":[0.2737970000016503]},"getNotes":{"times":[1.920497000006435]},"getAuthWitness":{"times":[0.2536760000002687]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.21381299999484327]},"callPrivateFunction":{"times":[637.1811680000028]}}},{"functionName":"private_kernel_init","time":63.70151299999998},{"functionName":"Test:set_tx_max_block_number","time":507.10609799999656,"oracles":{"debugLog":{"times":[0.40282500000466825,0.38552299999719253]},"fetchTaggedLogs":{"times":[192.43064199999935]},"loadCapsule":{"times":[0.2063819999966654,0.1836509999993723,0.21436200000607641]},"storeInExecutionCache":{"times":[0.04521200000453973]},"notifyEnqueuedPublicFunctionCall":{"times":[0.05022299999836832]}}},{"functionName":"private_kernel_inner","time":156.24494699999923},{"functionName":"private_kernel_reset","time":251.97404400000232},{"functionName":"private_kernel_tail","time":85.46985400000267}],"unaccounted":523.1600819999949}} 12:50:31 [12:50:31.755] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with an enqueued public call invalidates the transaction 12:50:31 [12:50:31.758] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:31 [12:50:31.834] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:31 [12:50:31.870] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:31 [12:50:31.870] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:31 [12:50:31.911] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32"} 12:50:31 [12:50:31.917] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x037b6efe9d7fa63214444b7c867d4e08a4d1ddf373a0625ff318ff571cace3df 12:50:31 [12:50:31.931] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 12:50:31 [12:50:31.932] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 12:50:31 [12:50:31.959] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 12:50:31 [12:50:31.960] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 12:50:31 [12:50:31.964] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:32 [12:50:32.042] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:32 [12:50:32.043] VERBOSE: pxe:service Added note {"index":0,"contract":"0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x03c27c43b7ec6b889a991ed897ba314556b2f63bdac64f585d2863c773f6361e","nullifier":"0x15fb928d8492bf8a997e2615a3ffc4ba82de5697e43dade4a33dd7f4e17c9a67"} 12:50:32 [12:50:32.047] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:32 [12:50:32.059] VERBOSE: simulator:client_execution_context:debug_log Setting 0x02536edb6937b2b467d18e109fd7cb4c5ac6d31ad8c02ceb3b20b5116219ec32 as fee payer 12:50:32 [12:50:32.186] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:32 [12:50:32.444] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:32 [12:50:32.456] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 12:50:32 [12:50:32.456] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:32 [12:50:32.568] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:32 [12:50:32.585] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:32 [12:50:32.592] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x09ec1c19d8e8b123ebb2ab2692cea576bdc630775c29e80c14d66144b18e851b"} 12:50:32 [12:50:32.595] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 12:50:32 [12:50:32.806] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:33 [12:50:33.264] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:33 [12:50:33.399] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x0f13ea35fffbb70ba2dcc6ca114dc0e851bd829d47bff14293614e3830c87b5a","blockNumber":5,"slot":6} 12:50:33 [12:50:33.638] INFO: pxe:service Proving completed in 1862.3415969999987ms {"timings":{"total":1862.3415969999987,"sync":2.3717429999960586,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":411.840999,"oracles":{"debugLog":{"times":[0.4971499999955995,0.4189349999942351,0.5246810000026016,0.5354729999962728,0.5027000000045518,0.370612999999139,0.279117000005499,0.5101100000028964]},"fetchTaggedLogs":{"times":[44.507540000005974]},"loadCapsule":{"times":[0.2879179999945336,0.27215699999942444,0.5845550000012736,2.087166999997862,0.19828200000483776,0.1540690000038012]},"getSharedSecret":{"times":[5.070658000004187]},"aes128Decrypt":{"times":[0.5727939999997034,1.440506999999343]},"getPublicKeysAndPartialAddress":{"times":[15.594845999999961]},"getKeyValidationRequest":{"times":[8.030247000002419]},"deliverNote":{"times":[83.47166299999662]},"deleteCapsule":{"times":[0.7647959999958402]},"storeCapsule":{"times":[0.33060000000114087]},"getNotes":{"times":[1.3052699999971082]},"getAuthWitness":{"times":[0.19982200000231387]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.1057959999961895]},"callPrivateFunction":{"times":[542.0467769999959]}}},{"functionName":"private_kernel_init","time":91.38941300000442},{"functionName":"Test:set_tx_max_block_number","time":404.57414900000003,"oracles":{"debugLog":{"times":[0.45781800000258954,0.31999000000359956]},"fetchTaggedLogs":{"times":[137.9985799999995]},"loadCapsule":{"times":[0.2015929999979562,0.16982999999891035,0.22607399999833433]},"storeInExecutionCache":{"times":[0.041722999994817656]},"notifyEnqueuedPublicFunctionCall":{"times":[0.051322999999683816]}}},{"functionName":"private_kernel_inner","time":109.84654199999932},{"functionName":"private_kernel_reset","time":166.42772400000104},{"functionName":"private_kernel_tail","time":78.92761700000119}],"unaccounted":596.9634099999967}} 12:50:33 [12:50:33.666] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 12:50:33 [12:50:33.677] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x0302b41e027545edd66464b1470227ea4afa2c8ee6e481d27efc720428d56eac for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 12:50:33 [12:50:33.680] WARN: node Received invalid tx 0x0302b41e027545edd66464b1470227ea4afa2c8ee6e481d27efc720428d56eac: Invalid max block number {"txHash":"0x0302b41e027545edd66464b1470227ea4afa2c8ee6e481d27efc720428d56eac"} 12:50:33 [12:50:33.682] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 12:50:33 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 12:50:33 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 12:50:33 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 12:50:33 [12:50:33.684] INFO: node Stopping 12:50:33 [12:50:33.685] INFO: slasher Slasher client stopped. 12:50:33 [12:50:33.685] INFO: sequencer Stopped sequencer 12:50:33 [12:50:33.685] INFO: p2p P2P client stopped. 12:50:33 [12:50:33.687] INFO: world_state Stopped world state synchronizer 12:50:33 [12:50:33.687] INFO: archiver Stopped. 12:50:33 [12:50:33.687] INFO: node Stopped 12:50:33 [12:50:33.687] VERBOSE: e2e:e2e_max_block_number Cleaning up ACVM state 12:50:33 [12:50:33.687] INFO: e2e:e2e_max_block_number Cleaning up ACVM temp directory /tmp/fc43aa35 12:50:33 [12:50:33.688] VERBOSE: e2e:e2e_max_block_number Cleaning up BB state 12:50:33 [12:50:33.698] INFO: blob-sink:server Stopping blob sink 12:50:33 [12:50:33.699] INFO: blob-sink:server Blob sink stopped 12:50:33 [12:50:33.699] VERBOSE: e2e:e2e_max_block_number Cleaning up data directory at /tmp/a47420aa8ed7ece3 12:50:33 PASS src/e2e_max_block_number.test.ts 12:50:33 e2e_max_block_number 12:50:33 when requesting max block numbers higher than the mined one 12:50:33 with no enqueued public calls 12:50:33 ✓ sets the max block number (2603 ms) 12:50:33 ✓ does not invalidate the transaction (4476 ms) 12:50:33 with an enqueued public call 12:50:33 ✓ sets the max block number (2116 ms) 12:50:33 ✓ does not invalidate the transaction (3975 ms) 12:50:33 when requesting max block numbers lower than the mined one 12:50:33 with no enqueued public calls 12:50:33 ✓ sets the max block number (1887 ms) 12:50:33 ✓ invalidates the transaction (1795 ms) 12:50:33 with an enqueued public call 12:50:33 ✓ sets the max block number (2126 ms) 12:50:33 ✓ invalidates the transaction (1927 ms) 12:50:33 12:50:33 Test Suites: 1 passed, 1 total 12:50:33 Tests: 8 passed, 8 total 12:50:33 Snapshots: 0 total 12:50:33 Time: 44.117 s 12:50:33 Ran all test suites matching /src\/e2e_max_block_number.test.ts/i. 12:50:33 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?