Parent Log: http://ci.aztec-labs.com/a6379728c23043a9 Command: d2a1f586812a792c:ISOLATE=1:NAME=e2e_max_block_number yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_max_block_number.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/464e1a9d9b40b5188586643b44bc52bffccbc3e6 Env: REF_NAME=gh-readonly-queue/master/pr-15117-c06f30929f18b85bcea93c0926a8d640a3819f00 CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Tue Jun 17 20:22:45 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15117_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_d488fdfe97c9ae48_master 20:22:45 +++ id -u 20:22:45 +++ id -g 20:22:45 ++ docker run -d --name e2e_max_block_number --net=none --cpuset-cpus=0-127 --cpus=2 --memory=8g --user 1000:1000 -v/home/aztec-dev:/home/aztec-dev --mount type=tmpfs,target=/tmp,tmpfs-size=1g --workdir /home/aztec-dev/aztec-packages -e HOME -e VERBOSE -e GIT_CONFIG_GLOBAL=/home/aztec-dev/aztec-packages/build-images/src/home/.gitconfig -e FORCE_COLOR=true -e CPUS -e MEM aztecprotocol/build:3.0 /bin/bash -c 'timeout -v 600s bash -c '\''yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_max_block_number.test.ts'\''' 20:22:45 + cid=7cf498f7cd33ff05c6b1703cf759c7d13aca8b30aa766b465cabba32893bf078 20:22:45 + set +x 20:22:46 {"level":25,"time":1750191766571,"pid":12,"hostname":"7cf498f7cd33","module":"logger","msg":"Logger initialized with level verbose"} 20:22:54 [20:22:54.002] INFO: e2e:e2e_max_block_number Logging metrics to log/unknown.jsonl 20:22:54 [20:22:54.370] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-yRLkON/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 20:22:54 [20:22:54.425] INFO: e2e:e2e_max_block_number Calling anvil_setBlockTimestampInterval with params: [12] 20:22:54 [20:22:54.461] WARN: e2e:e2e_max_block_number Set block interval to 12 20:22:54 [20:22:54.462] VERBOSE: e2e:e2e_max_block_number Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 20:22:54 [20:22:54.520] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 20:22:54 [20:22:54.537] VERBOSE: e2e:e2e_max_block_number Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 20:22:54 [20:22:54.564] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 20:22:54 [20:22:54.573] VERBOSE: e2e:e2e_max_block_number Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 20:22:54 [20:22:54.603] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 20:22:54 [20:22:54.609] VERBOSE: e2e:e2e_max_block_number Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 20:22:54 [20:22:54.624] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 20:22:54 [20:22:54.628] VERBOSE: e2e:e2e_max_block_number Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 20:22:54 [20:22:54.658] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 20:22:54 [20:22:54.663] VERBOSE: e2e:e2e_max_block_number Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 20:22:54 [20:22:54.690] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 20:22:54 [20:22:54.695] VERBOSE: e2e:e2e_max_block_number Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 20:22:54 [20:22:54.696] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 20:22:54 [20:22:54.696] INFO: e2e:e2e_max_block_number Waiting for 6 transactions to be mined... 20:22:54 [20:22:54.716] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:54 [20:22:54.731] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 20:22:54 [20:22:54.732] VERBOSE: e2e:e2e_max_block_number Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 20:22:54 [20:22:54.748] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 20:22:54 [20:22:54.767] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 20:22:54 [20:22:54.773] VERBOSE: e2e:e2e_max_block_number Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 20:22:54 [20:22:54.787] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 20:22:54 [20:22:54.788] VERBOSE: e2e:e2e_max_block_number Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 20:22:54 [20:22:54.824] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 20:22:54 [20:22:54.829] VERBOSE: e2e:e2e_max_block_number Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 20:22:54 [20:22:54.845] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 20:22:54 [20:22:54.846] VERBOSE: e2e:e2e_max_block_number Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 20:22:54 [20:22:54.846] VERBOSE: e2e:e2e_max_block_number Waiting for deployments to complete 20:22:54 [20:22:54.846] INFO: e2e:e2e_max_block_number Waiting for 8 transactions to be mined... 20:22:54 [20:22:54.868] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:54 [20:22:54.877] VERBOSE: e2e:e2e_max_block_number Deployed shared contracts 20:22:54 [20:22:54.895] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 20:22:54 [20:22:54.896] VERBOSE: e2e:e2e_max_block_number Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 20:22:54 [20:22:54.899] VERBOSE: e2e:e2e_max_block_number Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 20:22:54 [20:22:54.912] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 20:22:54 [20:22:54.977] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 20:22:54 [20:22:54.987] VERBOSE: e2e:e2e_max_block_number Awaiting for linked libraries to be deployed 20:22:55 [20:22:55.036] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xce832c9329a5aa8bcac7d734184635f2c9b6c40c8e239c2db1572a8dc611698a {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 20:22:55 [20:22:55.046] VERBOSE: e2e:e2e_max_block_number Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016 {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 20:22:55 [20:22:55.047] INFO: e2e:e2e_max_block_number Waiting for 9 transactions to be mined... 20:22:55 [20:22:55.073] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:55 [20:22:55.074] VERBOSE: e2e:e2e_max_block_number All core contracts have been deployed 20:22:55 [20:22:55.097] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 20:22:55 [20:22:55.098] VERBOSE: e2e:e2e_max_block_number Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 20:22:55 [20:22:55.111] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 20:22:55 [20:22:55.115] VERBOSE: e2e:e2e_max_block_number Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 20:22:55 [20:22:55.141] VERBOSE: e2e:e2e_max_block_number Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 20:22:55 [20:22:55.142] VERBOSE: e2e:e2e_max_block_number Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 20:22:55 [20:22:55.142] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 20:22:55 [20:22:55.191] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:55 [20:22:55.197] VERBOSE: e2e:e2e_max_block_number Rollup deployed 20:22:55 [20:22:55.197] VERBOSE: e2e:e2e_max_block_number Waiting for rollup and slash factory to be deployed 20:22:55 [20:22:55.197] INFO: e2e:e2e_max_block_number Waiting for 10 transactions to be mined... 20:22:55 [20:22:55.218] INFO: e2e:e2e_max_block_number All transactions mined successfully 20:22:55 [20:22:55.218] VERBOSE: e2e:e2e_max_block_number All transactions for L1 deployment have been mined 20:22:55 [20:22:55.247] INFO: e2e:e2e_max_block_number Aztec L1 contracts initialized {"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 20:22:55 [20:22:55.249] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 20:22:55 [20:22:55.251] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:55 [20:22:55.252] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 20:22:55 [20:22:55.253] INFO: telemetry:client Using NoopTelemetryClient 20:22:55 [20:22:55.258] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 20:22:55 [20:22:55.258] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 20:22:55 [20:22:55.259] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/6ef802eecd98bd37/blob-sink with map size 134217728 KB (LMDB v2) 20:22:55 [20:22:55.261] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:55 [20:22:55.263] INFO: aztecjs:utils:watcher New L1 time: 1750192013 20:22:55 [20:22:55.264] WARN: foundation:test-date-provider Time set to 2025-06-17T20:26:53.000Z {"offset":237737,"timeMs":1750192013000} 20:22:55 [20:22:55.266] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 20:22:55 [20:22:55.274] INFO: blob-sink:server Server is running on http://localhost:34771 20:22:55 [20:22:55.275] VERBOSE: e2e:e2e_max_block_number Creating and synching an aztec node... 20:22:55 [20:22:55.276] VERBOSE: e2e:e2e_max_block_number Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/ea13c32d/acvm 20:22:55 [20:22:55.277] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:34771"} 20:22:55 [20:22:55.296] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 20:22:55 [20:22:55.297] INFO: archiver:lmdb Creating archiver data store at directory /tmp/6ef802eecd98bd37/archiver with map size 134217728 KB (LMDB v2) 20:22:55 [20:22:55.299] INFO: archiver:lmdb Starting data store with maxReaders 16 20:22:55 [20:22:55.564] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:55 [20:22:55.689] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:34771"} 20:22:55 [20:22:55.714] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:34771"} 20:22:55 [20:22:55.715] WARN: node:blob-sink:client No L1 consensus host urls configured 20:22:55 [20:22:55.715] WARN: node:blob-sink:client No archive client configured 20:22:55 [20:22:55.720] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0xc5c927f0c0b60ab13f68c26a965ff00b2871633b47bf43fbd6757843abefc288 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x8d8144cd9f516faa8d7cf9fc96d2726a002dcab658a678d192ab129b94f487da","l1BlockNumber":16}} 20:22:55 [20:22:55.753] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 20:22:55 [20:22:55.764] INFO: world-state:database Creating world state data store at directory /tmp/6ef802eecd98bd37/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 20:22:55 [20:22:55.794] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:55 [20:22:55.816] INFO: world_state Created world state synchroniser with block history of 64 20:22:55 [20:22:55.817] WARN: node Aztec node is accepting fake proofs 20:22:55 [20:22:55.845] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/6ef802eecd98bd37/p2p with map size 134217728 KB (LMDB v2) 20:22:55 [20:22:55.848] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 20:22:55 [20:22:55.849] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/6ef802eecd98bd37/p2p-archive with map size 134217728 KB (LMDB v2) 20:22:55 [20:22:55.851] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 20:22:55 [20:22:55.853] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/6ef802eecd98bd37/p2p-peers with map size 134217728 KB (LMDB v2) 20:22:55 [20:22:55.855] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 20:22:55 [20:22:55.856] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 20:22:55 [20:22:55.857] VERBOSE: p2p P2P is disabled. Using dummy P2P service 20:22:55 [20:22:55.861] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 20:22:55 [20:22:55.862] INFO: world_state Started world state synchronizer from block 1 20:22:55 [20:22:55.866] VERBOSE: world-state:block_stream Starting sync from block number 0 20:22:55 [20:22:55.879] INFO: p2p Starting p2p client from block 0 with empty mempools 20:22:55 [20:22:55.879] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 20:22:55 [20:22:55.882] INFO: slasher Slasher client initialized 20:22:55 [20:22:55.882] INFO: slasher Starting Slasher client... 20:22:55 [20:22:55.931] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 20:22:55 [20:22:55.931] VERBOSE: node All Aztec Node subsystems synced 20:22:55 [20:22:55.951] INFO: sequencer-client Deploying forwarder contract 20:22:55 [20:22:55.961] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 20:22:55 [20:22:55.980] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 20:22:55 [20:22:55.980] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 20:22:55 [20:22:55.988] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 20:22:56 [20:22:56.007] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:56 [20:22:56.014] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 20:22:56 [20:22:56.015] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 20:22:56 [20:22:56.015] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/ea13c32d/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 20:22:56 [20:22:56.362] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 20:22:56 [20:22:56.365] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 20:22:56 [20:22:56.367] INFO: node Aztec Node version: 0.87.8 20:22:56 [20:22:56.368] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","feeAssetHandlerAddress":"0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6","stakingAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788"} 20:22:56 [20:22:56.370] VERBOSE: e2e:e2e_max_block_number Creating a pxe... 20:22:56 [20:22:56.379] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:56 [20:22:56.401] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/7be753b0bca0c64b/pxe_data with map size 134217728 KB (LMDB v2) 20:22:56 [20:22:56.423] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x272dac774621fa020fb18f0178cf120f372febacf57353444717981eb4457b34","blockNumber":1,"slot":2} 20:22:56 [20:22:56.425] INFO: pxe:data:lmdb Starting data store with maxReaders 16 20:22:56 [20:22:56.634] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:56 [20:22:56.759] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 20:22:56 [20:22:56.762] INFO: pxe:service Started PXE connected to chain 31337 version 747159440 20:22:56 [20:22:56.826] INFO: pxe:service Registered account 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 20:22:56 [20:22:56.906] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:56 [20:22:56.908] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 20:22:56 [20:22:56.910] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 20:22:57 [20:22:57.051] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x272dac774621fa020fb18f0178cf120f372febacf57353444717981eb4457b34","blockNumber":1,"slot":2} 20:22:57 [20:22:57.056] INFO: pxe:service Added contract SchnorrAccount at 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 20:22:57 [20:22:57.092] VERBOSE: pxe:block_stream Starting sync from block number 0 20:22:57 [20:22:57.111] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:57 [20:22:57.116] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 20:22:57 [20:22:57.218] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 20:22:57 [20:22:57.934] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 20:22:58 [20:22:58.055] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 20:22:58 [20:22:58.143] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:58 [20:22:58.211] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x272dac774621fa020fb18f0178cf120f372febacf57353444717981eb4457b34","blockNumber":1,"slot":2} 20:22:58 [20:22:58.284] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:22:58 [20:22:58.334] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:22:58 [20:22:58.335] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:22:58 [20:22:58.360] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:58 [20:22:58.378] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:22:58 [20:22:58.386] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:22:58 [20:22:58.539] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:22:58 [20:22:58.577] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:22:58 [20:22:58.577] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:22:58 [20:22:58.582] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:58 [20:22:58.617] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:22:58 [20:22:58.620] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:22:58 [20:22:58.635] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:22:58 [20:22:58.801] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:59 [20:22:59.032] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:59 [20:22:59.049] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x272dac774621fa020fb18f0178cf120f372febacf57353444717981eb4457b34","blockNumber":1,"slot":2} 20:22:59 [20:22:59.259] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:59 [20:22:59.533] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:22:59 [20:22:59.620] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x272dac774621fa020fb18f0178cf120f372febacf57353444717981eb4457b34","blockNumber":1,"slot":2} 20:23:00 [20:23:00.030] INFO: pxe:service Proving completed in 2940.433042999999ms {"timings":{"total":2940.433042999999,"sync":3.6117019999983313,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":156.80117300000165,"oracles":{"callPrivateFunction":{"times":[964.0130800000006,334.0115540000006,179.85647400000016]}}},{"functionName":"private_kernel_init","time":98.888868},{"functionName":"ContractClassRegisterer:register","time":944.4589980000001,"oracles":{"loadCapsule":{"times":[68.76894999999968]},"notifyCreatedNullifier":{"times":[0.7818619999998191]},"debugLog":{"times":[0.8465529999994033]},"notifyCreatedContractClassLog":{"times":[12.41787000000113]}}},{"functionName":"private_kernel_inner","time":177.97490900000048},{"functionName":"SchnorrAccount:constructor","time":299.6920369999989,"oracles":{"getContractInstance":{"times":[4.442795000000842]},"debugLog":{"times":[0.6128570000000764,0.3361899999999878]},"fetchTaggedLogs":{"times":[49.862457000001086]},"loadCapsule":{"times":[0.368811000000278,0.5378249999994296,0.18022500000006403]},"notifyCreatedNullifier":{"times":[0.16603499999837368,0.2666480000007141]},"notifyCreatedNote":{"times":[0.3218589999996766]},"getRandomField":{"times":[0.24821700000029523,0.2545780000000377,0.09161200000016834,0.07876299999952607,0.06978200000048673,0.053271000000677304,0.04613199999948847,0.045750999999654596,0.05357199999889417,0.08020199999918987,0.04123100000106206]},"getIndexedTaggingSecretAsSender":{"times":[32.62814099999923]},"incrementAppTaggingSecretIndexAsSender":{"times":[23.197324000000663]}}},{"functionName":"private_kernel_inner","time":152.52538900000036},{"functionName":"SchnorrAccount:entrypoint","time":154.30048399999941,"oracles":{"debugLog":{"times":[0.5755260000005364,0.4214620000002469,0.7632219999995868]},"fetchTaggedLogs":{"times":[41.59977400000025]},"loadCapsule":{"times":[0.25084699999933946,0.18959600000016508,0.20790499999930034]},"getNotes":{"times":[1.6381660000006377]},"getAuthWitness":{"times":[0.33063899999979185]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.9548670000003767]}}},{"functionName":"private_kernel_inner","time":127.55667200000062},{"functionName":"private_kernel_reset","time":189.5787709999986},{"functionName":"private_kernel_tail","time":85.89062899999954}],"unaccounted":549.1534110000011}} 20:23:00 [20:23:00.045] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:00 [20:23:00.178] VERBOSE: p2p:tx_pool Adding tx 0x046d4506a5ee1d3e44dfb181c2eb49ff205914a19a3d7d6ee149ace2fb3c2a15 to pool {"eventName":"tx-added-to-pool","txHash":"0x046d4506a5ee1d3e44dfb181c2eb49ff205914a19a3d7d6ee149ace2fb3c2a15","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 20:23:00 [20:23:00.202] INFO: node Received tx 0x046d4506a5ee1d3e44dfb181c2eb49ff205914a19a3d7d6ee149ace2fb3c2a15 {"txHash":"0x046d4506a5ee1d3e44dfb181c2eb49ff205914a19a3d7d6ee149ace2fb3c2a15"} 20:23:00 [20:23:00.202] INFO: pxe:service Sent transaction 0x046d4506a5ee1d3e44dfb181c2eb49ff205914a19a3d7d6ee149ace2fb3c2a15 20:23:00 [20:23:00.211] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 20:23:00 [20:23:00.224] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-17T20:27:17.000Z","now":"2025-06-17T20:26:57.961Z"} 20:23:00 [20:23:00.287] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:00 [20:23:00.496] VERBOSE: simulator:public-processor Processed tx 0x046d4506a5ee1d3e44dfb181c2eb49ff205914a19a3d7d6ee149ace2fb3c2a15 with no public calls in 194.7532570000003ms {"txHash":"0x046d4506a5ee1d3e44dfb181c2eb49ff205914a19a3d7d6ee149ace2fb3c2a15","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":194.7532570000003} 20:23:00 [20:23:00.499] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:00 [20:23:00.503] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.27738216699999974s {"duration":0.27738216699999974,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 20:23:00 [20:23:00.638] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x15316f274210329a5ed94f5627371749819760e5733972b13e35b4f8a1378789","globalVariables":{"chainId":31337,"version":747159440,"blockNumber":1,"slotNumber":2,"timestamp":1750192037,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x046d4506a5ee1d3e44dfb181c2eb49ff205914a19a3d7d6ee149ace2fb3c2a15"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":427.2279209999997,"publicProcessDuration":278.26422900000034,"rollupCircuitsDuration":415.6589300000014,"txCount":1,"blockNumber":1,"blockTimestamp":1750192037,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:23:00 [20:23:00.642] VERBOSE: sequencer Attesting committee is empty 20:23:00 [20:23:00.731] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.023] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.031] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x3444e77eebe1cb5fa64d343b97dc96b857944bbabb5be63a4b6512e292df44dd 20:23:01 [20:23:01.031] VERBOSE: sequencer-client Sent L1 transaction 0x3444e77eebe1cb5fa64d343b97dc96b857944bbabb5be63a4b6512e292df44dd {"gasLimit":514599,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 20:23:01 [20:23:01.056] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:23:01 [20:23:01.058] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x3444e77eebe1cb5fa64d343b97dc96b857944bbabb5be63a4b6512e292df44dd","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":1,"blockTimestamp":1750192037,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:23:01 [20:23:01.059] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:23:01 [20:23:01.085] INFO: blob-sink:server Received blob sidecar for block 0x16490adf56863dc7fdc6b72d2368857c2edd77cc6b95902850ddbf473a6a4661 20:23:01 [20:23:01.093] INFO: blob-sink:server Blob sidecar stored successfully for block 0x16490adf56863dc7fdc6b72d2368857c2edd77cc6b95902850ddbf473a6a4661 20:23:01 [20:23:01.234] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 20:23:01 [20:23:01.237] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.238] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 20:23:01 [20:23:01.243] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 20:23:01 [20:23:01.278] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750192073] on http://127.0.0.1:8545 20:23:01 [20:23:01.282] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:23:01 [20:23:01.287] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750192073 20:23:01 [20:23:01.287] WARN: foundation:test-date-provider Time set to 2025-06-17T20:27:53.000Z {"offset":291713,"timeMs":1750192073000} 20:23:01 [20:23:01.288] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 20:23:01 [20:23:01.413] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 20:23:01 [20:23:01.421] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x15316f274210329a5ed94f5627371749819760e5733972b13e35b4f8a1378789","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":747159440,"blockNumber":1,"slotNumber":2,"timestamp":1750192037,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x1f67851faa2f670ec438d753a9edaefe1a0c9ce2a2b8d25865669d58969bbf87","archiveNextLeafIndex":2} 20:23:01 [20:23:01.434] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 20:23:01 [20:23:01.445] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.949] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:01 [20:23:01.953] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":442.6522559999994,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750192037,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:23:01 [20:23:01.955] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 20:23:01 [20:23:01.962] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45 20:23:02 [20:23:02.166] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:02 [20:23:02.267] VERBOSE: p2p Synched to latest block 1 20:23:02 [20:23:02.584] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:02 [20:23:02.594] INFO: pxe:service Added contract Test at 0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45 with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 20:23:02 [20:23:02.622] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x1f67851faa2f670ec438d753a9edaefe1a0c9ce2a2b8d25865669d58969bbf87","header":{"contentCommitment":{"blobsHash":"0x00b323a2cd12ac2e97533778a1533e1509f0f5da07a927a518805e90283cc3db","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750192037,"version":747159440},"lastArchive":"0x272dac774621fa020fb18f0178cf120f372febacf57353444717981eb4457b34","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2859c796c1e7f34f88db43869c744aca3e622f7f916bfdedfb7fc0db22485532","nullifierTree":"0x2388e9601eb5b7f1f2d118dcb689f09f8acac868413785582903d05d2ac92b87","publicDataTree":"0x19b76e88e82d58f755fd10e1148c4d0f03ab72e304238049e553a85d9cbb553e"},"totalFees":6794052480,"totalManaUsed":20448}} 20:23:02 [20:23:02.751] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:02 [20:23:02.809] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:02 [20:23:02.810] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:02 [20:23:02.813] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:02 [20:23:02.872] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1f67851faa2f670ec438d753a9edaefe1a0c9ce2a2b8d25865669d58969bbf87","blockNumber":2,"slot":3} 20:23:02 [20:23:02.902] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:02 [20:23:02.907] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x28fc7eba2a306064596bf4d26250bda9004a0a49e88d5d2d18dfa0845f01f658 20:23:02 [20:23:02.920] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:02 [20:23:02.922] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:02 [20:23:02.933] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:02 [20:23:02.934] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:02 [20:23:02.940] VERBOSE: pxe:service Added note {"index":0,"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x023722cb43ad237d8fff152c2f041875ea8ec84d03d5bf0be5632edef6525a0e","nullifier":"0x1af88ebe16d5ff1eae0c0ddb9f735eb86dcab64a3ffb359230b4e03954cfeef6"} 20:23:02 [20:23:02.942] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:02 [20:23:02.956] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:23:02 [20:23:02.991] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 20:23:03 [20:23:03.435] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 20:23:03 [20:23:03.569] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 20:23:03 [20:23:03.649] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:03 [20:23:03.726] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 20:23:03 [20:23:03.742] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1f67851faa2f670ec438d753a9edaefe1a0c9ce2a2b8d25865669d58969bbf87","blockNumber":2,"slot":3} 20:23:03 [20:23:03.766] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 20:23:03 [20:23:03.776] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45,0x0000000000000000000000000000000000000000000000000000000000000001,0x09f12ca127bc8215b2b7489d79cedb21145562631c20e48eb4c54ea00de1d4cc,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 20:23:03 [20:23:03.914] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:04 [20:23:04.216] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:04 [20:23:04.296] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:04 [20:23:04.304] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:04 [20:23:04.305] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:04 [20:23:04.612] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:04 [20:23:04.630] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1f67851faa2f670ec438d753a9edaefe1a0c9ce2a2b8d25865669d58969bbf87","blockNumber":2,"slot":3} 20:23:04 [20:23:04.661] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:04 [20:23:04.666] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:04 [20:23:04.909] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:05 [20:23:05.177] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:05 [20:23:05.272] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1f67851faa2f670ec438d753a9edaefe1a0c9ce2a2b8d25865669d58969bbf87","blockNumber":2,"slot":3} 20:23:05 [20:23:05.451] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:05 [20:23:05.729] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:06 [20:23:06.172] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:06 [20:23:06.611] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1f67851faa2f670ec438d753a9edaefe1a0c9ce2a2b8d25865669d58969bbf87","blockNumber":2,"slot":3} 20:23:06 [20:23:06.812] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:07 [20:23:07.167] INFO: pxe:service Proving completed in 4557.615944000001ms {"timings":{"total":4557.615944000001,"sync":16.440318999997544,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":501.05049999999756,"oracles":{"debugLog":{"times":[0.6914160000014817,0.4471000000012282,0.7517180000031658,0.7754580000000715,0.39228000000002794,0.3651080000017828,0.3559380000006058,0.5899640000025101]},"fetchTaggedLogs":{"times":[94.3095229999999]},"loadCapsule":{"times":[0.22347499999887077,0.39579999999841675,0.5480439999992086,0.25898700000107056,0.3068170000005921,0.2350059999989753]},"getSharedSecret":{"times":[4.509637999999541]},"aes128Decrypt":{"times":[1.47271499999988,1.0537350000013248]},"getPublicKeysAndPartialAddress":{"times":[6.423393999997643]},"getKeyValidationRequest":{"times":[2.3038460000025225]},"deliverNote":{"times":[6.045214999998279]},"deleteCapsule":{"times":[0.5464339999998629]},"storeCapsule":{"times":[0.44264100000145845]},"getNotes":{"times":[1.811044000001857]},"getAuthWitness":{"times":[0.16961400000218418]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05751099999906728]},"callPrivateFunction":{"times":[685.5784810000005,137.58509200000117,891.7854579999985]}}},{"functionName":"private_kernel_init","time":96.08700600000157},{"functionName":"ContractClassRegisterer:register","time":674.4777640000029,"oracles":{"loadCapsule":{"times":[3.092553999998927]},"notifyCreatedNullifier":{"times":[0.9746029999987513]},"debugLog":{"times":[1.038605000001553]},"notifyCreatedContractClassLog":{"times":[11.240459000000556]}}},{"functionName":"private_kernel_inner","time":172.64117199999964},{"functionName":"ContractInstanceDeployer:deploy","time":89.74696299999778,"oracles":{"storeInExecutionCache":{"times":[0.3863390000005893]},"callPrivateFunction":{"times":[39.54055900000094]},"notifyCreatedNullifier":{"times":[0.19083500000124332]},"debugLog":{"times":[0.39608900000166614]}}},{"functionName":"private_kernel_inner","time":169.5336279999974},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":31.226739999998244,"oracles":{}},{"functionName":"private_kernel_inner","time":174.68538099999932},{"functionName":"Test:initialize","time":720.5034699999997,"oracles":{"getContractInstance":{"times":[9.23138100000142]},"debugLog":{"times":[0.6563860000023851,0.5536529999990307]},"fetchTaggedLogs":{"times":[359.98354800000016]},"loadCapsule":{"times":[0.22256500000003143,0.29270700000051875,0.2570869999981369]},"notifyCreatedNullifier":{"times":[0.29019700000208104]}}},{"functionName":"private_kernel_inner","time":191.02081399999952},{"functionName":"private_kernel_reset","time":269.626989999997},{"functionName":"private_kernel_tail","time":88.50034400000004}],"unaccounted":1362.0748530000128}} 20:23:07 [20:23:07.192] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:07 [20:23:07.374] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1f67851faa2f670ec438d753a9edaefe1a0c9ce2a2b8d25865669d58969bbf87","blockNumber":2,"slot":3} 20:23:07 [20:23:07.383] VERBOSE: p2p:tx_pool Adding tx 0x112d8a9c250163fb1721571f6827847e19a6a60cda15556f6e43608ecad14c2b to pool {"eventName":"tx-added-to-pool","txHash":"0x112d8a9c250163fb1721571f6827847e19a6a60cda15556f6e43608ecad14c2b","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 20:23:07 [20:23:07.407] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:07 [20:23:07.411] INFO: node Received tx 0x112d8a9c250163fb1721571f6827847e19a6a60cda15556f6e43608ecad14c2b {"txHash":"0x112d8a9c250163fb1721571f6827847e19a6a60cda15556f6e43608ecad14c2b"} 20:23:07 [20:23:07.412] INFO: pxe:service Sent transaction 0x112d8a9c250163fb1721571f6827847e19a6a60cda15556f6e43608ecad14c2b 20:23:07 [20:23:07.613] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:07 [20:23:07.819] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:07 [20:23:07.912] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 20:23:07 [20:23:07.944] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-17T20:27:53.000Z","now":"2025-06-17T20:27:59.657Z"} 20:23:08 [20:23:08.042] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:08 [20:23:08.286] VERBOSE: simulator:public-processor Processed tx 0x112d8a9c250163fb1721571f6827847e19a6a60cda15556f6e43608ecad14c2b with no public calls in 232.13712999999916ms {"txHash":"0x112d8a9c250163fb1721571f6827847e19a6a60cda15556f6e43608ecad14c2b","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":232.13712999999916} 20:23:08 [20:23:08.288] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:08 [20:23:08.294] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.34851328300000023s {"duration":0.34851328300000023,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 20:23:08 [20:23:08.503] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:08 [20:23:08.517] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x073edea6d8cf1c7c25a608d964bfb92de45b19c650640d8d9504090c9c8c4f94","globalVariables":{"chainId":31337,"version":747159440,"blockNumber":2,"slotNumber":3,"timestamp":1750192073,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x112d8a9c250163fb1721571f6827847e19a6a60cda15556f6e43608ecad14c2b"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":604.9510259999988,"publicProcessDuration":349.09330599999885,"rollupCircuitsDuration":580.7952670000013,"txCount":1,"blockNumber":2,"blockTimestamp":1750192073,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 20:23:08 [20:23:08.521] VERBOSE: sequencer Attesting committee is empty 20:23:08 [20:23:08.708] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:08 [20:23:08.896] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xdcaeb51508b4adbc879f8910e58e241284997aa6c979e8eb81a042c8de1618e8 20:23:08 [20:23:08.897] VERBOSE: sequencer-client Sent L1 transaction 0xdcaeb51508b4adbc879f8910e58e241284997aa6c979e8eb81a042c8de1618e8 {"gasLimit":470236,"maxFeePerGas":"1.303611222","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 20:23:08 [20:23:08.922] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:08 [20:23:08.962] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750192109] on http://127.0.0.1:8545 20:23:08 [20:23:08.965] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 20:23:08 [20:23:08.969] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:23:08 [20:23:08.973] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 20:23:08 [20:23:08.979] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:23:08 [20:23:08.980] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xdcaeb51508b4adbc879f8910e58e241284997aa6c979e8eb81a042c8de1618e8","calldataSize":1444,"calldataGas":12268,"txCount":1,"blockNumber":2,"blockTimestamp":1750192073,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 20:23:08 [20:23:08.981] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:23:08 [20:23:08.989] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 20:23:08 [20:23:08.993] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750192109 20:23:08 [20:23:08.993] WARN: foundation:test-date-provider Time set to 2025-06-17T20:28:29.000Z {"offset":320007,"timeMs":1750192109000} 20:23:08 [20:23:08.993] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 20:23:09 [20:23:09.001] INFO: blob-sink:server Received blob sidecar for block 0x2bc4f8af95697fd54f4a3de1cd184521a605c2e8584e39f44faaea3a917cc2a6 20:23:09 [20:23:09.009] INFO: blob-sink:server Blob sidecar stored successfully for block 0x2bc4f8af95697fd54f4a3de1cd184521a605c2e8584e39f44faaea3a917cc2a6 20:23:09 [20:23:09.134] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:09 [20:23:09.337] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:09 [20:23:09.542] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:09 [20:23:09.704] VERBOSE: archiver:block-helper Store contract instance at 0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45 20:23:09 [20:23:09.738] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 20:23:09 [20:23:09.780] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:09 [20:23:09.800] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x073edea6d8cf1c7c25a608d964bfb92de45b19c650640d8d9504090c9c8c4f94","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":747159440,"blockNumber":2,"slotNumber":3,"timestamp":1750192073,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","archiveNextLeafIndex":3} 20:23:09 [20:23:09.808] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 20:23:09 [20:23:09.927] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":12.533811000001151,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750192073,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 20:23:09 [20:23:09.932] VERBOSE: p2p Synched to latest block 2 20:23:09 [20:23:09.998] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:10 [20:23:10.053] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","blockNumber":3,"slot":4} 20:23:10 [20:23:10.203] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:10 [20:23:10.409] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:10 [20:23:10.731] INFO: aztecjs:deploy_sent_tx Contract 0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45 successfully deployed. 20:23:10 [20:23:10.735] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with no enqueued public calls sets the max block number 20:23:10 [20:23:10.741] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:10 [20:23:10.794] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","blockNumber":3,"slot":4} 20:23:10 [20:23:10.803] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","header":{"contentCommitment":{"blobsHash":"0x008b7c77df106f9e2dc7606d2e44ac3f49fdf55bb533f5bfb1ed00e376431b26","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750192073,"version":747159440},"lastArchive":"0x1f67851faa2f670ec438d753a9edaefe1a0c9ce2a2b8d25865669d58969bbf87","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2859c796c1e7f34f88db43869c744aca3e622f7f916bfdedfb7fc0db22485532","nullifierTree":"0x111c9e397c214f6a1eb10619df62507e02d2c2ee6a1fc8a7476687fe58f877df","publicDataTree":"0x19b27c60b9d1bb360ebadb2c00c929d58be68b84d1d57d5b4f5e16272dde0d5c"},"totalFees":7272506880,"totalManaUsed":21888}} 20:23:10 [20:23:10.914] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:10 [20:23:10.968] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:10 [20:23:10.969] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:10 [20:23:10.974] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:11 [20:23:11.051] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:11 [20:23:11.063] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x28fc7eba2a306064596bf4d26250bda9004a0a49e88d5d2d18dfa0845f01f658 20:23:11 [20:23:11.077] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:11 [20:23:11.079] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:11 [20:23:11.105] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:11 [20:23:11.105] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:11 [20:23:11.110] VERBOSE: pxe:service Added note {"index":0,"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x023722cb43ad237d8fff152c2f041875ea8ec84d03d5bf0be5632edef6525a0e","nullifier":"0x1af88ebe16d5ff1eae0c0ddb9f735eb86dcab64a3ffb359230b4e03954cfeef6"} 20:23:11 [20:23:11.112] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:11 [20:23:11.132] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:23:11 [20:23:11.320] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:11 [20:23:11.995] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:12 [20:23:12.022] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:12 [20:23:12.022] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:12 [20:23:12.029] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:12 [20:23:12.258] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:12 [20:23:12.276] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","blockNumber":3,"slot":4} 20:23:12 [20:23:12.312] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:12 [20:23:12.317] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:12 [20:23:12.544] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:13 [20:23:13.069] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:13 [20:23:13.236] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","blockNumber":3,"slot":4} 20:23:13 [20:23:13.487] INFO: pxe:service Proving completed in 2697.480306999998ms {"timings":{"total":2697.480306999998,"sync":16.2946809999994,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":822.7647729999953,"oracles":{"debugLog":{"times":[0.7814690000013798,0.6078740000011749,0.5796039999986533,0.8400499999988824,0.5320829999982379,0.3002779999987979,0.3357079999987036,0.6987569999982952]},"fetchTaggedLogs":{"times":[91.60914799999955]},"loadCapsule":{"times":[0.2912870000000112,0.2910760000013397,0.6189150000027439,0.2655460000023595,0.229165999997349,0.18882400000074995]},"getSharedSecret":{"times":[4.77406400000109]},"aes128Decrypt":{"times":[0.3519479999995383,1.330522000000201]},"getPublicKeysAndPartialAddress":{"times":[8.409662000001845]},"getKeyValidationRequest":{"times":[4.535948999997345]},"deliverNote":{"times":[5.220574999999371]},"deleteCapsule":{"times":[0.2948969999997644]},"storeCapsule":{"times":[0.3181769999973767]},"getNotes":{"times":[6.737091999999393]},"getAuthWitness":{"times":[0.2443459999994957]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.09675199999765027]},"callPrivateFunction":{"times":[1191.2682050000003]}}},{"functionName":"private_kernel_init","time":90.45686099999875},{"functionName":"Test:set_tx_max_block_number","time":674.4619239999993,"oracles":{"debugLog":{"times":[0.706597000000329,0.4533100000007835]},"fetchTaggedLogs":{"times":[293.68539699999747]},"loadCapsule":{"times":[0.2704860000012559,0.18645499999911408,0.31203699999969103]}}},{"functionName":"private_kernel_inner","time":130.11594200000036},{"functionName":"private_kernel_reset","time":188.90588299999945},{"functionName":"private_kernel_tail","time":65.94554300000163}],"unaccounted":708.5347000000038}} 20:23:13 [20:23:13.494] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with no enqueued public calls does not invalidate the transaction 20:23:13 [20:23:13.502] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:13 [20:23:13.620] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:13 [20:23:13.669] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:13 [20:23:13.669] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:13 [20:23:13.712] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:13 [20:23:13.761] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:13 [20:23:13.764] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","blockNumber":3,"slot":4} 20:23:13 [20:23:13.769] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x28fc7eba2a306064596bf4d26250bda9004a0a49e88d5d2d18dfa0845f01f658 20:23:13 [20:23:13.786] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:13 [20:23:13.787] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:13 [20:23:13.795] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:13 [20:23:13.796] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:13 [20:23:13.803] VERBOSE: pxe:service Added note {"index":0,"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x023722cb43ad237d8fff152c2f041875ea8ec84d03d5bf0be5632edef6525a0e","nullifier":"0x1af88ebe16d5ff1eae0c0ddb9f735eb86dcab64a3ffb359230b4e03954cfeef6"} 20:23:13 [20:23:13.806] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:13 [20:23:13.825] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:23:14 [20:23:14.015] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:14 [20:23:14.370] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:14 [20:23:14.383] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:14 [20:23:14.384] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:14 [20:23:14.388] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:14 [20:23:14.603] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:14 [20:23:14.729] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","blockNumber":3,"slot":4} 20:23:14 [20:23:14.748] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:14 [20:23:14.752] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:14 [20:23:14.861] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:15 [20:23:15.104] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:15 [20:23:15.498] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:15 [20:23:15.704] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","blockNumber":3,"slot":4} 20:23:15 [20:23:15.929] INFO: pxe:service Proving completed in 2403.0572920000013ms {"timings":{"total":2403.0572920000013,"sync":3.3768510000008973,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":477.0373560000007,"oracles":{"debugLog":{"times":[0.6077449999975215,0.4580709999972896,0.6795160000001488,0.7654990000010002,0.606233999998949,0.4981020000013814,0.6009950000006938,0.6907160000009753]},"fetchTaggedLogs":{"times":[97.76519600000029]},"loadCapsule":{"times":[0.26611599999887403,0.2715669999997772,0.46746100000018487,0.355068000000756,0.28034699999989243,0.3436089999995602]},"getSharedSecret":{"times":[7.749565999998595]},"aes128Decrypt":{"times":[0.34192799999800627,1.3951739999974961]},"getPublicKeysAndPartialAddress":{"times":[2.0595390000016778]},"getKeyValidationRequest":{"times":[2.171672000000399]},"deliverNote":{"times":[7.679314000000886]},"deleteCapsule":{"times":[0.3580480000018724]},"storeCapsule":{"times":[0.36524800000188407]},"getNotes":{"times":[2.539510999999038]},"getAuthWitness":{"times":[0.32279799999741954]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.08513200000015786]},"callPrivateFunction":{"times":[932.9945269999989]}}},{"functionName":"private_kernel_init","time":70.16176300000006},{"functionName":"Test:set_tx_max_block_number","time":736.337399,"oracles":{"debugLog":{"times":[0.623555000001943,0.45036999999865657]},"fetchTaggedLogs":{"times":[367.3248739999981]},"loadCapsule":{"times":[0.26870599999892875,0.17517399999996996,0.22030499999891617]}}},{"functionName":"private_kernel_inner","time":162.11970999999903},{"functionName":"private_kernel_reset","time":167.40412600000127},{"functionName":"private_kernel_tail","time":68.8324219999995}],"unaccounted":717.7876649999998}} 20:23:15 [20:23:15.943] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:15 [20:23:15.968] VERBOSE: p2p:tx_pool Adding tx 0x27613f3a326b67e8a26e37fdff5b0d8abee798cde4aa1acbb0a977d71a82c735 to pool {"eventName":"tx-added-to-pool","txHash":"0x27613f3a326b67e8a26e37fdff5b0d8abee798cde4aa1acbb0a977d71a82c735","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 20:23:15 [20:23:15.973] INFO: node Received tx 0x27613f3a326b67e8a26e37fdff5b0d8abee798cde4aa1acbb0a977d71a82c735 {"txHash":"0x27613f3a326b67e8a26e37fdff5b0d8abee798cde4aa1acbb0a977d71a82c735"} 20:23:15 [20:23:15.974] INFO: pxe:service Sent transaction 0x27613f3a326b67e8a26e37fdff5b0d8abee798cde4aa1acbb0a977d71a82c735 20:23:16 [20:23:16.149] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:16 [20:23:16.221] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 20:23:16 [20:23:16.235] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-17T20:28:29.000Z","now":"2025-06-17T20:28:36.242Z"} 20:23:16 [20:23:16.253] VERBOSE: simulator:public-processor Processed tx 0x27613f3a326b67e8a26e37fdff5b0d8abee798cde4aa1acbb0a977d71a82c735 with no public calls in 11.904316000000108ms {"txHash":"0x27613f3a326b67e8a26e37fdff5b0d8abee798cde4aa1acbb0a977d71a82c735","txFee":1945714560,"revertCode":0,"gasUsed":{"totalGas":{"daGas":1024,"l2Gas":5856},"billedGas":{"daGas":1024,"l2Gas":5856},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":1,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":11.904316000000108} 20:23:16 [20:23:16.254] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.018858392000001913s {"duration":0.018858392000001913,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1024,"l2Gas":5856},"totalSizeInBytes":256} 20:23:16 [20:23:16.351] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:16 [20:23:16.356] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2425705678195c75f7e9874691f2f201866fc06cd1ddf5929f5d66cf8f816144","globalVariables":{"chainId":31337,"version":747159440,"blockNumber":3,"slotNumber":4,"timestamp":1750192109,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x27613f3a326b67e8a26e37fdff5b0d8abee798cde4aa1acbb0a977d71a82c735"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":135.03255100000024,"publicProcessDuration":19.210691000000224,"rollupCircuitsDuration":122.2811040000015,"txCount":1,"blockNumber":3,"blockTimestamp":1750192109,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:16 [20:23:16.358] VERBOSE: sequencer Attesting committee is empty 20:23:16 [20:23:16.594] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:16 [20:23:16.599] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x436996d968b7b142f146d61b741a2ecf718b6a98519c93d93ceaff8019bc3555 20:23:16 [20:23:16.600] VERBOSE: sequencer-client Sent L1 transaction 0x436996d968b7b142f146d61b741a2ecf718b6a98519c93d93ceaff8019bc3555 {"gasLimit":470248,"maxFeePerGas":"1.279583398","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 20:23:16 [20:23:16.610] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:23:16 [20:23:16.611] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x436996d968b7b142f146d61b741a2ecf718b6a98519c93d93ceaff8019bc3555","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":3,"blockTimestamp":1750192109,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:16 [20:23:16.612] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:23:16 [20:23:16.617] INFO: blob-sink:server Received blob sidecar for block 0xb5f8d372771e7b8cb62e90c63d5c4c511dd1e5a2a787f3f9575b730bb70056df 20:23:16 [20:23:16.620] INFO: blob-sink:server Blob sidecar stored successfully for block 0xb5f8d372771e7b8cb62e90c63d5c4c511dd1e5a2a787f3f9575b730bb70056df 20:23:16 [20:23:16.645] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750192145] on http://127.0.0.1:8545 20:23:16 [20:23:16.646] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:23:16 [20:23:16.648] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750192145 20:23:16 [20:23:16.648] WARN: foundation:test-date-provider Time set to 2025-06-17T20:29:05.000Z {"offset":348352,"timeMs":1750192145000} 20:23:16 [20:23:16.648] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 20:23:16 [20:23:16.799] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:16 [20:23:16.800] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 20:23:16 [20:23:16.802] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 20:23:16 [20:23:16.806] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 20:23:17 [20:23:17.009] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:17 [20:23:17.149] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x2425705678195c75f7e9874691f2f201866fc06cd1ddf5929f5d66cf8f816144","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":747159440,"blockNumber":3,"slotNumber":4,"timestamp":1750192109,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x17bb4c1823b6e081503e973dfcb3ad56fc8a7f2868faa446d6e8cfb1c7d46f0e","archiveNextLeafIndex":4} 20:23:17 [20:23:17.160] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 20:23:17 [20:23:17.213] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:17 [20:23:17.245] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":5.9440829999985,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750192109,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:17 [20:23:17.263] VERBOSE: p2p Synched to latest block 3 20:23:17 [20:23:17.417] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:17 [20:23:17.623] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:17 [20:23:17.677] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x17bb4c1823b6e081503e973dfcb3ad56fc8a7f2868faa446d6e8cfb1c7d46f0e","blockNumber":4,"slot":5} 20:23:17 [20:23:17.827] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:17 [20:23:17.979] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with an enqueued public call sets the max block number 20:23:18 [20:23:18.000] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x17bb4c1823b6e081503e973dfcb3ad56fc8a7f2868faa446d6e8cfb1c7d46f0e","header":{"contentCommitment":{"blobsHash":"0x00e7a149d868e355803a92a177a7af0ac0318c1212f40e17aa9b92aa5055cef9","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750192109,"version":747159440},"lastArchive":"0x2e341ceb7b429e557dae2cda05bdc64366fda874d714a6b46407e43980906a19","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2859c796c1e7f34f88db43869c744aca3e622f7f916bfdedfb7fc0db22485532","nullifierTree":"0x2a9a22e18d6bd014aef4be3a373ac317d2b14c361b278596fd94e6b6e202d98e","publicDataTree":"0x122949aa3b9fe8bb368d117ead8a857215f97834d08c45a67757225ce6c821b3"},"totalFees":1945714560,"totalManaUsed":5856}} 20:23:18 [20:23:18.042] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:18 [20:23:18.060] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:18 [20:23:18.094] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:18 [20:23:18.095] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:18 [20:23:18.151] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:18 [20:23:18.156] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x28fc7eba2a306064596bf4d26250bda9004a0a49e88d5d2d18dfa0845f01f658 20:23:18 [20:23:18.169] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:18 [20:23:18.170] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:18 [20:23:18.177] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:18 [20:23:18.177] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:18 [20:23:18.209] VERBOSE: pxe:service Added note {"index":0,"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x023722cb43ad237d8fff152c2f041875ea8ec84d03d5bf0be5632edef6525a0e","nullifier":"0x1af88ebe16d5ff1eae0c0ddb9f735eb86dcab64a3ffb359230b4e03954cfeef6"} 20:23:18 [20:23:18.229] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:18 [20:23:18.233] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x17bb4c1823b6e081503e973dfcb3ad56fc8a7f2868faa446d6e8cfb1c7d46f0e","blockNumber":4,"slot":5} 20:23:18 [20:23:18.248] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:23:18 [20:23:18.376] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:18 [20:23:18.640] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:18 [20:23:18.655] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:18 [20:23:18.656] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:18 [20:23:18.799] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:18 [20:23:18.843] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:18 [20:23:18.843] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x17bb4c1823b6e081503e973dfcb3ad56fc8a7f2868faa446d6e8cfb1c7d46f0e","blockNumber":4,"slot":5} 20:23:18 [20:23:18.850] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:19 [20:23:19.026] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:19 [20:23:19.348] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:19 [20:23:19.669] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:19 [20:23:19.800] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x17bb4c1823b6e081503e973dfcb3ad56fc8a7f2868faa446d6e8cfb1c7d46f0e","blockNumber":4,"slot":5} 20:23:20 [20:23:20.036] INFO: pxe:service Proving completed in 2042.9295639999982ms {"timings":{"total":2042.9295639999982,"sync":8.653008000001137,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":404.7431820000056,"oracles":{"debugLog":{"times":[0.7252079999998386,0.4812820000006468,0.5404529999977967,0.6595849999976053,0.43351999999867985,0.40883000000030734,0.4799220000022615,0.6331449999997858]},"fetchTaggedLogs":{"times":[59.627550000001065]},"loadCapsule":{"times":[0.16415300000153366,0.20909499999834225,0.5139819999967585,9.884336999999505,1.1691570000002685,0.3109970000004978]},"getSharedSecret":{"times":[4.105518000000302]},"aes128Decrypt":{"times":[0.3857499999976426,1.208798999999999]},"getPublicKeysAndPartialAddress":{"times":[1.6610099999998056]},"getKeyValidationRequest":{"times":[1.53629699999874]},"deliverNote":{"times":[32.754626000001736]},"deleteCapsule":{"times":[4.123258999999962]},"storeCapsule":{"times":[2.9456710000013118]},"getNotes":{"times":[4.004456000002392]},"getAuthWitness":{"times":[0.19007500000225264]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.058000999997602776]},"callPrivateFunction":{"times":[635.4468769999985]}}},{"functionName":"private_kernel_init","time":86.80506899999455},{"functionName":"Test:set_tx_max_block_number","time":468.95053199999893,"oracles":{"debugLog":{"times":[0.44557999999960884,0.3905099999974482]},"fetchTaggedLogs":{"times":[192.94051000000036]},"loadCapsule":{"times":[0.29278700000577373,0.2066449999983888,0.21113499999773921]},"storeInExecutionCache":{"times":[0.04955199999676552]},"notifyEnqueuedPublicFunctionCall":{"times":[0.355067999997118]}}},{"functionName":"private_kernel_inner","time":107.21689199999673},{"functionName":"private_kernel_reset","time":158.87853399999585},{"functionName":"private_kernel_tail","time":78.93121500000416}],"unaccounted":728.7511320000012}} 20:23:20 [20:23:20.040] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with an enqueued public call does not invalidate the transaction 20:23:20 [20:23:20.043] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:20 [20:23:20.118] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:20 [20:23:20.153] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:20 [20:23:20.153] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:20 [20:23:20.192] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:20 [20:23:20.196] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x28fc7eba2a306064596bf4d26250bda9004a0a49e88d5d2d18dfa0845f01f658 20:23:20 [20:23:20.205] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:20 [20:23:20.206] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:20 [20:23:20.213] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:20 [20:23:20.213] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:20 [20:23:20.216] VERBOSE: pxe:service Added note {"index":0,"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x023722cb43ad237d8fff152c2f041875ea8ec84d03d5bf0be5632edef6525a0e","nullifier":"0x1af88ebe16d5ff1eae0c0ddb9f735eb86dcab64a3ffb359230b4e03954cfeef6"} 20:23:20 [20:23:20.218] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:20 [20:23:20.230] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:23:20 [20:23:20.355] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:20 [20:23:20.586] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:20 [20:23:20.600] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:20 [20:23:20.601] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:20 [20:23:20.723] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:20 [20:23:20.733] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x17bb4c1823b6e081503e973dfcb3ad56fc8a7f2868faa446d6e8cfb1c7d46f0e","blockNumber":4,"slot":5} 20:23:20 [20:23:20.748] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:20 [20:23:20.750] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:20 [20:23:20.928] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:21 [20:23:21.218] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:21 [20:23:21.613] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:21 [20:23:21.755] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x17bb4c1823b6e081503e973dfcb3ad56fc8a7f2868faa446d6e8cfb1c7d46f0e","blockNumber":4,"slot":5} 20:23:22 [20:23:22.065] INFO: pxe:service Proving completed in 2004.0915810000006ms {"timings":{"total":2004.0915810000006,"sync":2.590274000001955,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":277.4793259999933,"oracles":{"debugLog":{"times":[0.5012129999959143,0.4162099999957718,0.3792689999972936,0.5609039999981178,0.5117619999946328,0.3983399999997346,0.28011700000206474,0.5342929999969783]},"fetchTaggedLogs":{"times":[41.12097299999732]},"loadCapsule":{"times":[0.166614000001573,0.18804500000260305,0.3223980000038864,0.22585600000456907,0.16751400000066496,0.16556400000263238]},"getSharedSecret":{"times":[3.1961090000040713]},"aes128Decrypt":{"times":[0.39030900000216207,0.809000000001106]},"getPublicKeysAndPartialAddress":{"times":[1.1911890000046697]},"getKeyValidationRequest":{"times":[1.6629910000046948]},"deliverNote":{"times":[3.071654999999737]},"deleteCapsule":{"times":[0.2580759999982547]},"storeCapsule":{"times":[0.27412700000422774]},"getNotes":{"times":[1.074916999998095]},"getAuthWitness":{"times":[0.23694599999726051]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06091200000082608]},"callPrivateFunction":{"times":[523.5344400000031]}}},{"functionName":"private_kernel_init","time":85.17297900000267},{"functionName":"Test:set_tx_max_block_number","time":402.5288780000046,"oracles":{"debugLog":{"times":[0.6350660000025528,0.334127999994962]},"fetchTaggedLogs":{"times":[148.55275099999562]},"loadCapsule":{"times":[0.14379400000325404,0.1440939999956754,0.13748299999861047]},"storeInExecutionCache":{"times":[0.03353100000094855]},"notifyEnqueuedPublicFunctionCall":{"times":[0.07643100000132108]}}},{"functionName":"private_kernel_inner","time":110.32284800000343},{"functionName":"private_kernel_reset","time":196.5565029999998},{"functionName":"private_kernel_tail","time":118.51787999999942}],"unaccounted":810.9228929999954}} 20:23:22 [20:23:22.099] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:22 [20:23:22.126] VERBOSE: p2p:tx_pool Adding tx 0x0b67e29278ed2f8cacf499a1c9323bf4e721f702d38ef1c33bcf9051566fdce7 to pool {"eventName":"tx-added-to-pool","txHash":"0x0b67e29278ed2f8cacf499a1c9323bf4e721f702d38ef1c33bcf9051566fdce7","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226281,"feePaymentMethod":"fee_juice"} 20:23:22 [20:23:22.135] INFO: node Received tx 0x0b67e29278ed2f8cacf499a1c9323bf4e721f702d38ef1c33bcf9051566fdce7 {"txHash":"0x0b67e29278ed2f8cacf499a1c9323bf4e721f702d38ef1c33bcf9051566fdce7"} 20:23:22 [20:23:22.135] INFO: pxe:service Sent transaction 0x0b67e29278ed2f8cacf499a1c9323bf4e721f702d38ef1c33bcf9051566fdce7 20:23:22 [20:23:22.270] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 20:23:22 [20:23:22.282] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-17T20:29:05.000Z","now":"2025-06-17T20:29:10.634Z"} 20:23:22 [20:23:22.304] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:22 [20:23:22.341] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x5189a417 completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x5189a417","duration":34.732726000002} 20:23:22 [20:23:22.368] VERBOSE: simulator:public-processor Processed tx 0x0b67e29278ed2f8cacf499a1c9323bf4e721f702d38ef1c33bcf9051566fdce7 with 1 public calls in 73.53521199999523ms {"txHash":"0x0b67e29278ed2f8cacf499a1c9323bf4e721f702d38ef1c33bcf9051566fdce7","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":73.53521199999523} 20:23:22 [20:23:22.369] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.08676268800000253s {"duration":0.08676268800000253,"rate":40143.98447406215,"totalPublicGas":{"daGas":0,"l2Gas":3483},"totalBlockGas":{"daGas":1024,"l2Gas":29339},"totalSizeInBytes":256} 20:23:22 [20:23:22.478] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 16751.067744704054 mana/s {"blockHash":"0x24107777a605b0519d77c178463c72d264f81888abff72508c4faf0cc241233e","globalVariables":{"chainId":31337,"version":747159440,"blockNumber":4,"slotNumber":5,"timestamp":1750192145,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x0b67e29278ed2f8cacf499a1c9323bf4e721f702d38ef1c33bcf9051566fdce7"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":207.25332699999854,"publicProcessDuration":87.2270290000015,"rollupCircuitsDuration":196.62055500000133,"txCount":1,"blockNumber":4,"blockTimestamp":1750192145,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:22 [20:23:22.480] VERBOSE: sequencer Attesting committee is empty 20:23:22 [20:23:22.554] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:22 [20:23:22.719] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xf2304347428d7520fdd6c2d1c9785c85a8877cf505b406da09bce807e4dbd3b5 20:23:22 [20:23:22.720] VERBOSE: sequencer-client Sent L1 transaction 0xf2304347428d7520fdd6c2d1c9785c85a8877cf505b406da09bce807e4dbd3b5 {"gasLimit":470263,"maxFeePerGas":"1.261127735","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 20:23:22 [20:23:22.730] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:23:22 [20:23:22.731] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xf2304347428d7520fdd6c2d1c9785c85a8877cf505b406da09bce807e4dbd3b5","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":4,"blockTimestamp":1750192145,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:22 [20:23:22.731] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:23:22 [20:23:22.736] INFO: blob-sink:server Received blob sidecar for block 0x248a3e3da9b5f63b43ec793980ada43c512be1b316313537b536b1ed90af0706 20:23:22 [20:23:22.740] INFO: blob-sink:server Blob sidecar stored successfully for block 0x248a3e3da9b5f63b43ec793980ada43c512be1b316313537b536b1ed90af0706 20:23:22 [20:23:22.757] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:22 [20:23:22.764] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 20:23:22 [20:23:22.767] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 20:23:22 [20:23:22.770] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 20:23:22 [20:23:22.773] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750192181] on http://127.0.0.1:8545 20:23:22 [20:23:22.775] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:23:22 [20:23:22.776] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750192181 20:23:22 [20:23:22.777] WARN: foundation:test-date-provider Time set to 2025-06-17T20:29:41.000Z {"offset":378223,"timeMs":1750192181000} 20:23:22 [20:23:22.777] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 20:23:22 [20:23:22.962] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:23 [20:23:23.249] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:23 [20:23:23.271] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x24107777a605b0519d77c178463c72d264f81888abff72508c4faf0cc241233e","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":747159440,"blockNumber":4,"slotNumber":5,"timestamp":1750192145,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","archiveNextLeafIndex":5} 20:23:23 [20:23:23.278] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 20:23:23 [20:23:23.370] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":6.040788999998767,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1750192145,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:23:23 [20:23:23.389] VERBOSE: p2p Synched to latest block 4 20:23:23 [20:23:23.455] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:23 [20:23:23.658] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:23 [20:23:23.792] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:23 [20:23:23.860] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:24 [20:23:24.063] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:24 [20:23:24.143] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with no enqueued public calls sets the max block number 20:23:24 [20:23:24.166] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","header":{"contentCommitment":{"blobsHash":"0x00c5a53743911e8982a2491e650b4e5c12bd9e3c6864e76a2b846bdbd6771fd2","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750192145,"version":747159440},"lastArchive":"0x17bb4c1823b6e081503e973dfcb3ad56fc8a7f2868faa446d6e8cfb1c7d46f0e","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2859c796c1e7f34f88db43869c744aca3e622f7f916bfdedfb7fc0db22485532","nullifierTree":"0x16f934e63e8500d8d26d5c0e05e7c82dbc4a0d9069a4851be487891e78584bcd","publicDataTree":"0x1230ef5ef8ec92fc142526342231af8e67240aa1c97a04708204aebcaeefe355"},"totalFees":1487487300,"totalManaUsed":29339}} 20:23:24 [20:23:24.259] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:24 [20:23:24.312] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:24 [20:23:24.312] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:24 [20:23:24.316] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:24 [20:23:24.380] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:24 [20:23:24.400] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:24 [20:23:24.406] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x28fc7eba2a306064596bf4d26250bda9004a0a49e88d5d2d18dfa0845f01f658 20:23:24 [20:23:24.420] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:24 [20:23:24.421] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:24 [20:23:24.430] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:24 [20:23:24.431] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:24 [20:23:24.435] VERBOSE: pxe:service Added note {"index":0,"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x023722cb43ad237d8fff152c2f041875ea8ec84d03d5bf0be5632edef6525a0e","nullifier":"0x1af88ebe16d5ff1eae0c0ddb9f735eb86dcab64a3ffb359230b4e03954cfeef6"} 20:23:24 [20:23:24.439] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:24 [20:23:24.456] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:23:24 [20:23:24.623] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:24 [20:23:24.893] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:24 [20:23:24.910] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:24 [20:23:24.910] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:25 [20:23:25.057] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:25 [20:23:25.066] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:25 [20:23:25.084] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:25 [20:23:25.087] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:25 [20:23:25.408] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:25 [20:23:25.818] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:25 [20:23:25.963] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:26 [20:23:26.186] INFO: pxe:service Proving completed in 2026.3113490000032ms {"timings":{"total":2026.3113490000032,"sync":8.647601999997278,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":403.2301249999946,"oracles":{"debugLog":{"times":[0.6952969999983907,0.5832650000011199,0.5652330000011716,0.8042400000049383,0.610225000003993,0.5249929999990854,0.44119099999807077,0.5803839999935008]},"fetchTaggedLogs":{"times":[91.58336699999927]},"loadCapsule":{"times":[0.23965499999758322,0.24879599999985658,0.5975150000012945,0.5517440000039642,0.22034500000154367,0.3102279999948223]},"getSharedSecret":{"times":[4.800998999999138]},"aes128Decrypt":{"times":[0.4235899999985122,1.0294560000038473]},"getPublicKeysAndPartialAddress":{"times":[2.3445879999999306]},"getKeyValidationRequest":{"times":[2.2743160000027274]},"deliverNote":{"times":[3.670429999998305]},"deleteCapsule":{"times":[1.7347030000019004]},"storeCapsule":{"times":[0.2970770000029006]},"getNotes":{"times":[1.4914369999969495]},"getAuthWitness":{"times":[0.2712470000042231]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.08272199999919394]},"callPrivateFunction":{"times":[636.3321690000012]}}},{"functionName":"private_kernel_init","time":59.209908999997424},{"functionName":"Test:set_tx_max_block_number","time":501.3671630000026,"oracles":{"debugLog":{"times":[0.6326460000054794,0.3988600000011502]},"fetchTaggedLogs":{"times":[175.6989889999968]},"loadCapsule":{"times":[0.17995499999960884,0.22490599999582628,0.16515400000207592]}}},{"functionName":"private_kernel_inner","time":116.36401699999988},{"functionName":"private_kernel_reset","time":160.37044099999912},{"functionName":"private_kernel_tail","time":67.97315499999968}],"unaccounted":709.1489370000127}} 20:23:26 [20:23:26.188] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with no enqueued public calls invalidates the transaction 20:23:26 [20:23:26.189] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:26 [20:23:26.289] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:26 [20:23:26.342] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:26 [20:23:26.342] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:26 [20:23:26.395] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:26 [20:23:26.405] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:26 [20:23:26.413] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x28fc7eba2a306064596bf4d26250bda9004a0a49e88d5d2d18dfa0845f01f658 20:23:26 [20:23:26.425] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:26 [20:23:26.426] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:26 [20:23:26.433] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:26 [20:23:26.433] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:26 [20:23:26.436] VERBOSE: pxe:service Added note {"index":0,"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x023722cb43ad237d8fff152c2f041875ea8ec84d03d5bf0be5632edef6525a0e","nullifier":"0x1af88ebe16d5ff1eae0c0ddb9f735eb86dcab64a3ffb359230b4e03954cfeef6"} 20:23:26 [20:23:26.438] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:26 [20:23:26.455] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:23:26 [20:23:26.696] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:26 [20:23:26.816] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:26 [20:23:26.828] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:26 [20:23:26.828] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:26 [20:23:26.839] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:26 [20:23:26.978] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:26 [20:23:26.982] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:27 [20:23:27.033] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:27 [20:23:27.315] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:27 [20:23:27.581] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:27 [20:23:27.729] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:27 [20:23:27.946] INFO: pxe:service Proving completed in 1738.068975999995ms {"timings":{"total":1738.068975999995,"sync":2.383678999998665,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":351.35326700000587,"oracles":{"debugLog":{"times":[0.6609460000036051,0.5631040000007488,0.45303099999728147,0.6142749999999069,0.453070999996271,0.38506000000052154,0.37636899999779416,0.5145019999981741]},"fetchTaggedLogs":{"times":[68.650701999999]},"loadCapsule":{"times":[0.23296599999594036,0.17469400000118185,0.45114099999773316,0.1866440000012517,0.20459499999560649,0.18595400000049267]},"getSharedSecret":{"times":[3.5513670000000275]},"aes128Decrypt":{"times":[0.26088699999672826,1.1418279999998049]},"getPublicKeysAndPartialAddress":{"times":[1.5167379999984405]},"getKeyValidationRequest":{"times":[1.5736389999947278]},"deliverNote":{"times":[2.9585230000011506]},"deleteCapsule":{"times":[0.2939669999977923]},"storeCapsule":{"times":[0.21527600000263192]},"getNotes":{"times":[1.2864919999992708]},"getAuthWitness":{"times":[0.28240699999878416]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05939199999556877]},"callPrivateFunction":{"times":[530.5434819999937]}}},{"functionName":"private_kernel_init","time":95.29082799999742},{"functionName":"Test:set_tx_max_block_number","time":404.74445299999934,"oracles":{"debugLog":{"times":[0.40918000000237953,0.5231720000010682]},"fetchTaggedLogs":{"times":[152.70338300000003]},"loadCapsule":{"times":[0.24221599999873433,0.24735599999985425,0.20731499999965308]}}},{"functionName":"private_kernel_inner","time":117.67061999999714},{"functionName":"private_kernel_reset","time":163.19495099999767},{"functionName":"private_kernel_tail","time":62.93240099999821}],"unaccounted":540.4987770000007}} 20:23:27 [20:23:27.957] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:27 [20:23:27.973] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x2056d4a7ea073d35c595e8e93c5e683b1aa092279cb74c87105fa19031979acc for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 20:23:27 [20:23:27.976] WARN: node Received invalid tx 0x2056d4a7ea073d35c595e8e93c5e683b1aa092279cb74c87105fa19031979acc: Invalid max block number {"txHash":"0x2056d4a7ea073d35c595e8e93c5e683b1aa092279cb74c87105fa19031979acc"} 20:23:27 [20:23:27.980] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 20:23:27 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 20:23:27 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 20:23:27 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 20:23:27 [20:23:27.981] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with an enqueued public call sets the max block number 20:23:28 [20:23:28.086] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:28 [20:23:28.122] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:28 [20:23:28.123] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:28 [20:23:28.166] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:28 [20:23:28.188] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:28 [20:23:28.193] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x28fc7eba2a306064596bf4d26250bda9004a0a49e88d5d2d18dfa0845f01f658 20:23:28 [20:23:28.203] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:28 [20:23:28.204] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:28 [20:23:28.211] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:28 [20:23:28.212] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:28 [20:23:28.231] VERBOSE: pxe:service Added note {"index":0,"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x023722cb43ad237d8fff152c2f041875ea8ec84d03d5bf0be5632edef6525a0e","nullifier":"0x1af88ebe16d5ff1eae0c0ddb9f735eb86dcab64a3ffb359230b4e03954cfeef6"} 20:23:28 [20:23:28.289] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:28 [20:23:28.303] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:23:28 [20:23:28.306] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:28 [20:23:28.470] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:28 [20:23:28.792] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:28 [20:23:28.807] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:28 [20:23:28.807] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:28 [20:23:28.821] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:28 [20:23:28.944] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:28 [20:23:28.951] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:28 [20:23:28.954] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:29 [20:23:29.039] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:29 [20:23:29.507] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:29 [20:23:29.665] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:29 [20:23:29.990] INFO: pxe:service Proving completed in 1998.610735999995ms {"timings":{"total":1998.610735999995,"sync":2.714077000004181,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":470.9124440000014,"oracles":{"debugLog":{"times":[0.5461839999989024,0.3187480000051437,0.38636000000406057,0.4933919999966747,0.42194000000017695,0.3325979999935953,0.3840199999976903,0.37703899999905843]},"fetchTaggedLogs":{"times":[68.84916599999997]},"loadCapsule":{"times":[0.1942849999977625,0.12428299999737646,0.3132069999992382,2.706146999997145,2.3146769999948447,0.34705899999971734]},"getSharedSecret":{"times":[4.057679999998072]},"aes128Decrypt":{"times":[0.23572599999897648,0.767548000003444]},"getPublicKeysAndPartialAddress":{"times":[1.9401169999982812]},"getKeyValidationRequest":{"times":[2.1306020000047283]},"deliverNote":{"times":[19.515651000001526]},"deleteCapsule":{"times":[0.7937489999967511]},"storeCapsule":{"times":[50.72033999999985]},"getNotes":{"times":[3.875136000002385]},"getAuthWitness":{"times":[0.18581499999709195]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.17574400000012247]},"callPrivateFunction":{"times":[656.3254420000012]}}},{"functionName":"private_kernel_init","time":55.31797300000471},{"functionName":"Test:set_tx_max_block_number","time":475.8015230000019,"oracles":{"debugLog":{"times":[0.5278630000029807,0.4175099999993108]},"fetchTaggedLogs":{"times":[145.10605500000383]},"loadCapsule":{"times":[0.15967400000226917,0.1515739999958896,0.3219180000014603]},"storeInExecutionCache":{"times":[0.07906199999706587]},"notifyEnqueuedPublicFunctionCall":{"times":[0.056331999992835335]}}},{"functionName":"private_kernel_inner","time":127.28532699999778},{"functionName":"private_kernel_reset","time":251.45153500000015},{"functionName":"private_kernel_tail","time":80.53772400000162}],"unaccounted":534.5901329999833}} 20:23:29 [20:23:29.995] INFO: e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with an enqueued public call invalidates the transaction 20:23:29 [20:23:29.997] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:30 [20:23:30.110] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:30 [20:23:30.163] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:30 [20:23:30.163] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:30 [20:23:30.207] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:30 [20:23:30.222] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:30 [20:23:30.262] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5"} 20:23:30 [20:23:30.270] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x28fc7eba2a306064596bf4d26250bda9004a0a49e88d5d2d18dfa0845f01f658 20:23:30 [20:23:30.298] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:23:30 [20:23:30.299] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:23:30 [20:23:30.309] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:23:30 [20:23:30.310] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:23:30 [20:23:30.318] VERBOSE: pxe:service Added note {"index":0,"contract":"0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x023722cb43ad237d8fff152c2f041875ea8ec84d03d5bf0be5632edef6525a0e","nullifier":"0x1af88ebe16d5ff1eae0c0ddb9f735eb86dcab64a3ffb359230b4e03954cfeef6"} 20:23:30 [20:23:30.322] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:30 [20:23:30.414] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1ac39daf991b9fe5a2e9a758e8983ec98f9c42eface99eda35103acb3302ddc5 as fee payer 20:23:30 [20:23:30.441] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:30 [20:23:30.652] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:30 [20:23:30.997] VERBOSE: simulator:private_execution Executing private function Test:set_tx_max_block_number {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:31 [20:23:31.014] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:23:31 [20:23:31.015] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:31 [20:23:31.035] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:31 [20:23:31.236] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:31 [20:23:31.253] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x132a9d6e2f2bcb90280b7de9a96d900c91bd14c5b1b6b3b262420b4f62237e45"} 20:23:31 [20:23:31.257] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:23:31 [20:23:31.476] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:32 [20:23:32.022] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:32 [20:23:32.146] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x044dbaf161c6ebd07bce487eec307a63adebee8ed8ec0165eb75630381f1e2a2","blockNumber":5,"slot":6} 20:23:32 [20:23:32.445] INFO: pxe:service Proving completed in 2425.562815999998ms {"timings":{"total":2425.562815999998,"sync":3.2368099999948754,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":551.2013030000016,"oracles":{"debugLog":{"times":[0.6556060000002617,0.6670259999955306,0.7167780000017956,0.8511910000015632,0.5587539999978617,0.5087829999974929,63.7074999999968,0.8241699999998673]},"fetchTaggedLogs":{"times":[103.1764020000046]},"loadCapsule":{"times":[0.22684500000468688,0.5820739999981015,1.61387900000409,0.3050280000024941,0.3883700000005774,0.7886099999959697]},"getSharedSecret":{"times":[16.290172000000894]},"aes128Decrypt":{"times":[1.0875359999990906,2.2550459999984014]},"getPublicKeysAndPartialAddress":{"times":[2.7663979999997537]},"getKeyValidationRequest":{"times":[3.0073039999988396]},"deliverNote":{"times":[8.701435000002675]},"deleteCapsule":{"times":[0.6761670000050799]},"storeCapsule":{"times":[0.7426790000026813]},"getNotes":{"times":[7.613886999999522]},"getAuthWitness":{"times":[0.8410509999957867]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.13717300000280375]},"callPrivateFunction":{"times":[848.3384030000016]}}},{"functionName":"private_kernel_init","time":91.86642299999949},{"functionName":"Test:set_tx_max_block_number","time":676.7074439999997,"oracles":{"debugLog":{"times":[0.6561460000011721,0.3909490000005462]},"fetchTaggedLogs":{"times":[240.84107400000357]},"loadCapsule":{"times":[0.2588460000042687,0.21266499999910593,0.2645169999959762]},"storeInExecutionCache":{"times":[0.08642300000065006]},"notifyEnqueuedPublicFunctionCall":{"times":[0.0609420000037062]}}},{"functionName":"private_kernel_inner","time":100.78192399999534},{"functionName":"private_kernel_reset","time":203.51615499999753},{"functionName":"private_kernel_tail","time":98.64004099999875}],"unaccounted":699.6127160000106}} 20:23:32 [20:23:32.483] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:23:32 [20:23:32.501] VERBOSE: p2p:tx_validator:tx_metadata Rejecting tx 0x017e6b6bed629bf90a82a1367ec7ce71ef3e6c9164eaf6c796b77030b8926213 for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005. 20:23:32 [20:23:32.505] WARN: node Received invalid tx 0x017e6b6bed629bf90a82a1367ec7ce71ef3e6c9164eaf6c796b77030b8926213: Invalid max block number {"txHash":"0x017e6b6bed629bf90a82a1367ec7ce71ef3e6c9164eaf6c796b77030b8926213"} 20:23:32 [20:23:32.508] ERROR: pxe:service Error: Error: Invalid tx: Invalid max block number 20:23:32 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 20:23:32 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 20:23:32 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 20:23:32 [20:23:32.511] INFO: node Stopping 20:23:32 [20:23:32.512] INFO: slasher Slasher client stopped. 20:23:32 [20:23:32.512] INFO: sequencer Stopped sequencer 20:23:32 [20:23:32.512] INFO: p2p P2P client stopped. 20:23:32 [20:23:32.514] INFO: world_state Stopped world state synchronizer 20:23:32 [20:23:32.514] INFO: archiver Stopped. 20:23:32 [20:23:32.514] INFO: node Stopped 20:23:32 [20:23:32.514] VERBOSE: e2e:e2e_max_block_number Cleaning up ACVM state 20:23:32 [20:23:32.515] INFO: e2e:e2e_max_block_number Cleaning up ACVM temp directory /tmp/ea13c32d 20:23:32 [20:23:32.515] VERBOSE: e2e:e2e_max_block_number Cleaning up BB state 20:23:32 [20:23:32.524] INFO: blob-sink:server Stopping blob sink 20:23:32 [20:23:32.525] INFO: blob-sink:server Blob sink stopped 20:23:32 [20:23:32.525] VERBOSE: e2e:e2e_max_block_number Cleaning up data directory at /tmp/6ef802eecd98bd37 20:23:32 PASS src/e2e_max_block_number.test.ts 20:23:32 e2e_max_block_number 20:23:32 when requesting max block numbers higher than the mined one 20:23:32 with no enqueued public calls 20:23:32 ✓ sets the max block number (2760 ms) 20:23:32 ✓ does not invalidate the transaction (4485 ms) 20:23:32 with an enqueued public call 20:23:32 ✓ sets the max block number (2061 ms) 20:23:32 ✓ does not invalidate the transaction (4102 ms) 20:23:32 when requesting max block numbers lower than the mined one 20:23:32 with no enqueued public calls 20:23:32 ✓ sets the max block number (2045 ms) 20:23:32 ✓ invalidates the transaction (1792 ms) 20:23:32 with an enqueued public call 20:23:32 ✓ sets the max block number (2013 ms) 20:23:32 ✓ invalidates the transaction (2515 ms) 20:23:32 20:23:32 Test Suites: 1 passed, 1 total 20:23:32 Tests: 8 passed, 8 total 20:23:32 Snapshots: 0 total 20:23:32 Time: 46.16 s 20:23:32 Ran all test suites matching /src\/e2e_max_block_number.test.ts/i. 20:23:32 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?