ACI ·

Parent Log: http://ci.aztec-labs.com/d447f9a13417abbf Command: d2a1f586812a792c:ISOLATE=1:NAME=e2e_mempool_limit yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_mempool_limit.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/71fe6b444df8fafa5531226cf458dbd22d2f3ab7 Env: REF_NAME=gh-readonly-queue/master/pr-15117-c06f30929f18b85bcea93c0926a8d640a3819f00 CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Tue Jun 17 19:26:33 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_3177e4e547f012b7_master 19:26:33 +++ id -u 19:26:33 +++ id -g 19:26:33 ++ docker run -d --name e2e_mempool_limit --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_mempool_limit.test.ts'\''' 19:26:34 + cid=b14a57a5e88a14884c4072b8cfd24ef23f91ff91f3b52c9251d426fa46ecd32b 19:26:34 + set +x 19:26:34 {"level":25,"time":1750188394853,"pid":12,"hostname":"b14a57a5e88a","module":"logger","msg":"Logger initialized with level verbose"} 19:26:40 [19:26:40.865] INFO: e2e:e2e_mempool_limit Logging metrics to log/unknown.jsonl 19:26:41 [19:26:41.103] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-O3eREF/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 19:26:41 [19:26:41.144] INFO: e2e:e2e_mempool_limit Calling anvil_setBlockTimestampInterval with params: [12] 19:26:41 [19:26:41.170] WARN: e2e:e2e_mempool_limit Set block interval to 12 19:26:41 [19:26:41.170] VERBOSE: e2e:e2e_mempool_limit Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 19:26:41 [19:26:41.219] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 19:26:41 [19:26:41.228] VERBOSE: e2e:e2e_mempool_limit Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 19:26:41 [19:26:41.244] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 19:26:41 [19:26:41.250] VERBOSE: e2e:e2e_mempool_limit Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 19:26:41 [19:26:41.270] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 19:26:41 [19:26:41.276] VERBOSE: e2e:e2e_mempool_limit Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 19:26:41 [19:26:41.293] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 19:26:41 [19:26:41.299] VERBOSE: e2e:e2e_mempool_limit Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 19:26:41 [19:26:41.321] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 19:26:41 [19:26:41.327] VERBOSE: e2e:e2e_mempool_limit Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 19:26:41 [19:26:41.340] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 19:26:41 [19:26:41.345] VERBOSE: e2e:e2e_mempool_limit Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 19:26:41 [19:26:41.346] VERBOSE: e2e:e2e_mempool_limit Waiting for deployments to complete 19:26:41 [19:26:41.346] INFO: e2e:e2e_mempool_limit Waiting for 6 transactions to be mined... 19:26:41 [19:26:41.367] INFO: e2e:e2e_mempool_limit All transactions mined successfully 19:26:41 [19:26:41.382] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 19:26:41 [19:26:41.382] VERBOSE: e2e:e2e_mempool_limit Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 19:26:41 [19:26:41.392] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 19:26:41 [19:26:41.404] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 19:26:41 [19:26:41.409] VERBOSE: e2e:e2e_mempool_limit Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 19:26:41 [19:26:41.418] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 19:26:41 [19:26:41.419] VERBOSE: e2e:e2e_mempool_limit Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 19:26:41 [19:26:41.438] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 19:26:41 [19:26:41.443] VERBOSE: e2e:e2e_mempool_limit Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 19:26:41 [19:26:41.454] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 19:26:41 [19:26:41.454] VERBOSE: e2e:e2e_mempool_limit Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 19:26:41 [19:26:41.454] VERBOSE: e2e:e2e_mempool_limit Waiting for deployments to complete 19:26:41 [19:26:41.454] INFO: e2e:e2e_mempool_limit Waiting for 8 transactions to be mined... 19:26:41 [19:26:41.470] INFO: e2e:e2e_mempool_limit All transactions mined successfully 19:26:41 [19:26:41.479] VERBOSE: e2e:e2e_mempool_limit Deployed shared contracts 19:26:41 [19:26:41.503] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 19:26:41 [19:26:41.503] VERBOSE: e2e:e2e_mempool_limit Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 19:26:41 [19:26:41.507] VERBOSE: e2e:e2e_mempool_limit Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 19:26:41 [19:26:41.518] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 19:26:41 [19:26:41.541] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 19:26:41 [19:26:41.546] VERBOSE: e2e:e2e_mempool_limit Awaiting for linked libraries to be deployed 19:26:41 [19:26:41.593] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x2c8e1ca441df6a6fb40b2dfef775bc15631cf018e464841160c62f95df0369b5 {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 19:26:41 [19:26:41.602] VERBOSE: e2e:e2e_mempool_limit Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016 {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 19:26:41 [19:26:41.602] INFO: e2e:e2e_mempool_limit Waiting for 9 transactions to be mined... 19:26:41 [19:26:41.624] INFO: e2e:e2e_mempool_limit All transactions mined successfully 19:26:41 [19:26:41.625] VERBOSE: e2e:e2e_mempool_limit All core contracts have been deployed 19:26:41 [19:26:41.645] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 19:26:41 [19:26:41.646] VERBOSE: e2e:e2e_mempool_limit Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 19:26:41 [19:26:41.665] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 19:26:41 [19:26:41.671] VERBOSE: e2e:e2e_mempool_limit Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 19:26:41 [19:26:41.689] VERBOSE: e2e:e2e_mempool_limit Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 19:26:41 [19:26:41.689] VERBOSE: e2e:e2e_mempool_limit Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 19:26:41 [19:26:41.689] INFO: e2e:e2e_mempool_limit Waiting for 10 transactions to be mined... 19:26:41 [19:26:41.704] INFO: e2e:e2e_mempool_limit All transactions mined successfully 19:26:41 [19:26:41.709] VERBOSE: e2e:e2e_mempool_limit Rollup deployed 19:26:41 [19:26:41.709] VERBOSE: e2e:e2e_mempool_limit Waiting for rollup and slash factory to be deployed 19:26:41 [19:26:41.709] INFO: e2e:e2e_mempool_limit Waiting for 10 transactions to be mined... 19:26:41 [19:26:41.726] INFO: e2e:e2e_mempool_limit All transactions mined successfully 19:26:41 [19:26:41.726] VERBOSE: e2e:e2e_mempool_limit All transactions for L1 deployment have been mined 19:26:41 [19:26:41.751] INFO: e2e:e2e_mempool_limit 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"} 19:26:41 [19:26:41.754] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 19:26:41 [19:26:41.757] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:41 [19:26:41.758] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 19:26:41 [19:26:41.758] INFO: telemetry:client Using NoopTelemetryClient 19:26:41 [19:26:41.764] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/5c0e17d8bf33d0c4/blob-sink with map size 134217728 KB (LMDB v2) 19:26:41 [19:26:41.765] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 19:26:41 [19:26:41.766] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 19:26:41 [19:26:41.769] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:41 [19:26:41.773] INFO: aztecjs:utils:watcher New L1 time: 1750188640 19:26:41 [19:26:41.774] WARN: foundation:test-date-provider Time set to 2025-06-17T19:30:40.000Z {"offset":238226,"timeMs":1750188640000} 19:26:41 [19:26:41.776] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 19:26:41 [19:26:41.784] INFO: blob-sink:server Server is running on http://localhost:44985 19:26:41 [19:26:41.784] VERBOSE: e2e:e2e_mempool_limit Creating and synching an aztec node... 19:26:41 [19:26:41.785] VERBOSE: e2e:e2e_mempool_limit Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/7d7ff2c8/acvm 19:26:41 [19:26:41.786] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:44985"} 19:26:41 [19:26:41.803] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 19:26:41 [19:26:41.804] INFO: archiver:lmdb Creating archiver data store at directory /tmp/5c0e17d8bf33d0c4/archiver with map size 134217728 KB (LMDB v2) 19:26:41 [19:26:41.805] INFO: archiver:lmdb Starting data store with maxReaders 16 19:26:42 [19:26:42.000] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:42 [19:26:42.107] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:44985"} 19:26:42 [19:26:42.122] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:44985"} 19:26:42 [19:26:42.123] WARN: node:blob-sink:client No L1 consensus host urls configured 19:26:42 [19:26:42.123] WARN: node:blob-sink:client No archive client configured 19:26:42 [19:26:42.127] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x5740d5befe2064428c48aadaa056b3a7769e7492d6a71fa69ccb9d0ba11271d8 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0xfc02f9f3990b943cf4287f932d4fb8c269a926902e3472c1daa9fe9fd729f679","l1BlockNumber":16}} 19:26:42 [19:26:42.149] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 19:26:42 [19:26:42.161] INFO: world-state:database Creating world state data store at directory /tmp/5c0e17d8bf33d0c4/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 19:26:42 [19:26:42.187] INFO: world_state Created world state synchroniser with block history of 64 19:26:42 [19:26:42.187] WARN: node Aztec node is accepting fake proofs 19:26:42 [19:26:42.198] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/5c0e17d8bf33d0c4/p2p with map size 134217728 KB (LMDB v2) 19:26:42 [19:26:42.200] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 19:26:42 [19:26:42.202] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/5c0e17d8bf33d0c4/p2p-archive with map size 134217728 KB (LMDB v2) 19:26:42 [19:26:42.204] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 19:26:42 [19:26:42.207] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:42 [19:26:42.209] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/5c0e17d8bf33d0c4/p2p-peers with map size 134217728 KB (LMDB v2) 19:26:42 [19:26:42.212] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 19:26:42 [19:26:42.213] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 19:26:42 [19:26:42.214] VERBOSE: p2p P2P is disabled. Using dummy P2P service 19:26:42 [19:26:42.217] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 19:26:42 [19:26:42.218] INFO: world_state Started world state synchronizer from block 1 19:26:42 [19:26:42.221] VERBOSE: world-state:block_stream Starting sync from block number 0 19:26:42 [19:26:42.224] INFO: p2p Starting p2p client from block 0 with empty mempools 19:26:42 [19:26:42.224] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 19:26:42 [19:26:42.226] INFO: slasher Slasher client initialized 19:26:42 [19:26:42.226] INFO: slasher Starting Slasher client... 19:26:42 [19:26:42.259] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 19:26:42 [19:26:42.260] VERBOSE: node All Aztec Node subsystems synced 19:26:42 [19:26:42.273] INFO: sequencer-client Deploying forwarder contract 19:26:42 [19:26:42.285] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 19:26:42 [19:26:42.304] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 19:26:42 [19:26:42.305] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 19:26:42 [19:26:42.313] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 19:26:42 [19:26:42.334] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 19:26:42 [19:26:42.335] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 19:26:42 [19:26:42.336] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/7d7ff2c8/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 19:26:42 [19:26:42.566] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 19:26:42 [19:26:42.568] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 19:26:42 [19:26:42.569] INFO: node Aztec Node version: 0.87.8 19:26:42 [19:26:42.570] 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"} 19:26:42 [19:26:42.571] VERBOSE: e2e:e2e_mempool_limit Creating a pxe... 19:26:42 [19:26:42.574] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:42 [19:26:42.582] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/50c5cd68a27d308a/pxe_data with map size 134217728 KB (LMDB v2) 19:26:42 [19:26:42.593] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x16c6d97fa1e3b8663bf1153077f6702e41786d43ebb0fca2f6e26fdebb899cc2","blockNumber":1,"slot":2} 19:26:42 [19:26:42.594] INFO: pxe:data:lmdb Starting data store with maxReaders 16 19:26:42 [19:26:42.799] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:42 [19:26:42.905] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 19:26:42 [19:26:42.906] INFO: pxe:service Started PXE connected to chain 31337 version 3213983016 19:26:42 [19:26:42.970] INFO: pxe:service Registered account 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a 19:26:43 [19:26:43.046] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:43 [19:26:43.048] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 19:26:43 [19:26:43.050] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a 19:26:43 [19:26:43.210] INFO: pxe:service Added contract SchnorrAccount at 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 19:26:43 [19:26:43.219] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x16c6d97fa1e3b8663bf1153077f6702e41786d43ebb0fca2f6e26fdebb899cc2","blockNumber":1,"slot":2} 19:26:43 [19:26:43.257] VERBOSE: pxe:block_stream Starting sync from block number 0 19:26:43 [19:26:43.282] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:43 [19:26:43.286] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 19:26:43 [19:26:43.376] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 19:26:44 [19:26:44.062] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 19:26:44 [19:26:44.223] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 19:26:44 [19:26:44.313] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:44 [19:26:44.373] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x16c6d97fa1e3b8663bf1153077f6702e41786d43ebb0fca2f6e26fdebb899cc2","blockNumber":1,"slot":2} 19:26:44 [19:26:44.454] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:44 [19:26:44.502] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:26:44 [19:26:44.503] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:44 [19:26:44.538] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:44 [19:26:44.570] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:44 [19:26:44.576] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:26:44 [19:26:44.782] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:44 [19:26:44.801] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:44 [19:26:44.853] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:26:44 [19:26:44.854] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:44 [19:26:44.910] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x16c6d97fa1e3b8663bf1153077f6702e41786d43ebb0fca2f6e26fdebb899cc2","blockNumber":1,"slot":2} 19:26:44 [19:26:44.923] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:44 [19:26:44.926] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:26:44 [19:26:44.944] VERBOSE: simulator:client_execution_context:debug_log Setting 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a as fee payer 19:26:45 [19:26:45.142] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:45 [19:26:45.444] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:45 [19:26:45.635] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x16c6d97fa1e3b8663bf1153077f6702e41786d43ebb0fca2f6e26fdebb899cc2","blockNumber":1,"slot":2} 19:26:45 [19:26:45.788] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:46 [19:26:46.397] INFO: pxe:service Proving completed in 3146.626363000001ms {"timings":{"total":3146.626363000001,"sync":6.577234999998836,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":155.94520199999897,"oracles":{"callPrivateFunction":{"times":[974.0105459999995,400.1538150000015,251.62035600000127]}}},{"functionName":"private_kernel_init","time":127.55186800000047},{"functionName":"ContractClassRegisterer:register","time":954.7675799999997,"oracles":{"loadCapsule":{"times":[68.35279900000023]},"notifyCreatedNullifier":{"times":[15.864219999999477]},"debugLog":{"times":[0.9801110000007611]},"notifyCreatedContractClassLog":{"times":[13.558878999998342]}}},{"functionName":"private_kernel_inner","time":163.8579179999997},{"functionName":"SchnorrAccount:constructor","time":361.65842500000144,"oracles":{"getContractInstance":{"times":[1.4170220000014524]},"debugLog":{"times":[0.7941379999992932,0.6698639999995066]},"fetchTaggedLogs":{"times":[71.62575199999992]},"loadCapsule":{"times":[0.5210010000009788,0.3960189999997965,0.3519879999985278]},"notifyCreatedNullifier":{"times":[0.3324670000001788,0.3889790000011999]},"notifyCreatedNote":{"times":[0.6330539999999019]},"getRandomField":{"times":[0.5297319999990577,0.3005859999993845,0.17662399999971967,0.13560300000062853,0.15754299999935029,0.1378229999991163,0.11160200000085752,0.0996130000003177,0.10345199999937904,0.11634200000116834,0.07462200000009034]},"getIndexedTaggingSecretAsSender":{"times":[61.02190700000028]},"incrementAppTaggingSecretIndexAsSender":{"times":[30.225986999999805]}}},{"functionName":"private_kernel_inner","time":137.1924740000013},{"functionName":"SchnorrAccount:entrypoint","time":226.14670399999886,"oracles":{"debugLog":{"times":[0.7939480000004551,0.49656099999992875,0.9364509999995789]},"fetchTaggedLogs":{"times":[70.94245600000067]},"loadCapsule":{"times":[0.23975499999869498,0.4105390000004263,0.2761960000007093]},"getNotes":{"times":[2.1601670000000013]},"getAuthWitness":{"times":[0.33349700000144367]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.0253130000000965]}}},{"functionName":"private_kernel_inner","time":201.43047299999853},{"functionName":"private_kernel_reset","time":211.19437000000107},{"functionName":"private_kernel_tail","time":78.49253100000169}],"unaccounted":521.8115830000006}} 19:26:46 [19:26:46.413] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:46 [19:26:46.582] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x16c6d97fa1e3b8663bf1153077f6702e41786d43ebb0fca2f6e26fdebb899cc2","blockNumber":1,"slot":2} 19:26:46 [19:26:46.594] VERBOSE: p2p:tx_pool Adding tx 0x18918df33171cc852f72ff52f34097cc510c37937fe78b2b30e9cf155a58a426 to pool {"eventName":"tx-added-to-pool","txHash":"0x18918df33171cc852f72ff52f34097cc510c37937fe78b2b30e9cf155a58a426","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 19:26:46 [19:26:46.621] INFO: node Received tx 0x18918df33171cc852f72ff52f34097cc510c37937fe78b2b30e9cf155a58a426 {"txHash":"0x18918df33171cc852f72ff52f34097cc510c37937fe78b2b30e9cf155a58a426"} 19:26:46 [19:26:46.622] INFO: pxe:service Sent transaction 0x18918df33171cc852f72ff52f34097cc510c37937fe78b2b30e9cf155a58a426 19:26:46 [19:26:46.625] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:46 [19:26:46.830] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:47 [19:26:47.046] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:47 [19:26:47.101] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 19:26:47 [19:26:47.114] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-17T19:31:04.000Z","now":"2025-06-17T19:30:45.340Z"} 19:26:47 [19:26:47.321] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:47 [19:26:47.388] VERBOSE: simulator:public-processor Processed tx 0x18918df33171cc852f72ff52f34097cc510c37937fe78b2b30e9cf155a58a426 with no public calls in 192.25465900000017ms {"txHash":"0x18918df33171cc852f72ff52f34097cc510c37937fe78b2b30e9cf155a58a426","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":192.25465900000017} 19:26:47 [19:26:47.399] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.2832006540000002s {"duration":0.2832006540000002,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 19:26:47 [19:26:47.576] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:47 [19:26:47.588] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1cf78e1023b29032d206be2fa097f9df690fa956c97eaa2c88dde1a408279897","globalVariables":{"chainId":31337,"version":3213983016,"blockNumber":1,"slotNumber":2,"timestamp":1750188664,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x18918df33171cc852f72ff52f34097cc510c37937fe78b2b30e9cf155a58a426"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":487.40895699999965,"publicProcessDuration":284.9646309999989,"rollupCircuitsDuration":474.7987089999988,"txCount":1,"blockNumber":1,"blockTimestamp":1750188664,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 19:26:47 [19:26:47.592] VERBOSE: sequencer Attesting committee is empty 19:26:47 [19:26:47.784] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:48 [19:26:48.025] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:48 [19:26:48.032] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xe2a48979e07ec158322639fa96efe651c2c5060181fe688a61fef3567b90242d 19:26:48 [19:26:48.032] VERBOSE: sequencer-client Sent L1 transaction 0xe2a48979e07ec158322639fa96efe651c2c5060181fe688a61fef3567b90242d {"gasLimit":514570,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 19:26:48 [19:26:48.055] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 19:26:48 [19:26:48.057] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xe2a48979e07ec158322639fa96efe651c2c5060181fe688a61fef3567b90242d","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":1,"blockTimestamp":1750188664,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 19:26:48 [19:26:48.058] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 19:26:48 [19:26:48.089] INFO: blob-sink:server Received blob sidecar for block 0x381b613439fd7eff70cca5337924109078ec6304369f31f7c29636c24e5863fb 19:26:48 [19:26:48.098] INFO: blob-sink:server Blob sidecar stored successfully for block 0x381b613439fd7eff70cca5337924109078ec6304369f31f7c29636c24e5863fb 19:26:48 [19:26:48.233] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:48 [19:26:48.249] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 19:26:48 [19:26:48.255] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 19:26:48 [19:26:48.264] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 19:26:48 [19:26:48.275] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750188700] on http://127.0.0.1:8545 19:26:48 [19:26:48.278] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 19:26:48 [19:26:48.280] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750188700 19:26:48 [19:26:48.281] WARN: foundation:test-date-provider Time set to 2025-06-17T19:31:40.000Z {"offset":291719,"timeMs":1750188700000} 19:26:48 [19:26:48.281] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 19:26:48 [19:26:48.449] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:48 [19:26:48.740] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:48 [19:26:48.762] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 19:26:48 [19:26:48.773] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x1cf78e1023b29032d206be2fa097f9df690fa956c97eaa2c88dde1a408279897","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":3213983016,"blockNumber":1,"slotNumber":2,"timestamp":1750188664,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x25bc4eb2dade978b73ec08a6d6b41c35b90d0a5d1690c720763a014e9c862be5","archiveNextLeafIndex":2} 19:26:48 [19:26:48.780] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 19:26:49 [19:26:49.326] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:49 [19:26:49.330] WARN: pxe:service No artifact found for contract class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb when looking for its metadata 19:26:49 [19:26:49.336] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb as part of deployment for 0x20fc05b71e82aa9787703eada18b2ad97e09fbb7c9feecdb0a0126331e517ea3 19:26:49 [19:26:49.586] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":262.4090240000005,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750188664,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 19:26:49 [19:26:49.588] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:49 [19:26:49.717] VERBOSE: p2p Synched to latest block 1 19:26:50 [19:26:50.098] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:50 [19:26:50.128] INFO: pxe:service Added contract Token at 0x20fc05b71e82aa9787703eada18b2ad97e09fbb7c9feecdb0a0126331e517ea3 with class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb 19:26:50 [19:26:50.149] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x25bc4eb2dade978b73ec08a6d6b41c35b90d0a5d1690c720763a014e9c862be5","blockNumber":2,"slot":3} 19:26:50 [19:26:50.159] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x25bc4eb2dade978b73ec08a6d6b41c35b90d0a5d1690c720763a014e9c862be5","header":{"contentCommitment":{"blobsHash":"0x007b2e07990323c5fec0696b1650476fa413b386840f2ef72cb6c405fe46ac1d","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750188664,"version":3213983016},"lastArchive":"0x16c6d97fa1e3b8663bf1153077f6702e41786d43ebb0fca2f6e26fdebb899cc2","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x01f3924540932d3440666a765da3b63be06b148e26c077cdf52545d5b7fd8eac","nullifierTree":"0x0509bab1a81baa27ec6bb5005d0e2ee63f090583c56d974ec013f9c9a6fb881c","publicDataTree":"0x0022a9f7d96e2534a3690c3ac5ac54f4fb5c2905f467d079f89d751f2b4d57c8"},"totalFees":6794052480,"totalManaUsed":20448}} 19:26:50 [19:26:50.292] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:50 [19:26:50.329] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:26:50 [19:26:50.330] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:50 [19:26:50.336] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:50 [19:26:50.429] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:50 [19:26:50.436] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x29dcf61b9784386c24698190e94c2c1cb0a0e2540d241d5416214dbb9a6fa408 19:26:50 [19:26:50.455] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:26:50 [19:26:50.457] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 19:26:50 [19:26:50.472] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:26:50 [19:26:50.472] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:26:50 [19:26:50.494] VERBOSE: pxe:service Added note {"index":0,"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2cc9faaa4349caf43f5d1e67e2d4b8a6d8ba6dbf4ce22f20e04365d71ea52391","nullifier":"0x11fb83616a016eb531ccbb1223b3334891ad913726b3fa72929ff780ea0bed89"} 19:26:50 [19:26:50.505] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:26:50 [19:26:50.524] VERBOSE: simulator:client_execution_context:debug_log Setting 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a as fee payer 19:26:50 [19:26:50.541] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:50 [19:26:50.572] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 19:26:51 [19:26:51.137] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb,0x15aae2e0e2a7081ca06113c682f5373e67df2c3553eda599696574961c502fb4,0x0d6c93288fa1ed94413e630fd9c2f5ded8cd010325d6df84f5b9c8ac12bcac0c,0x146e9c4b5411f0c1e61cf995f05f86929ea24d4cd1b7e2f76bf817490af90f2f 19:26:51 [19:26:51.257] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 19:26:51 [19:26:51.311] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:51 [19:26:51.385] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 19:26:51 [19:26:51.400] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x25bc4eb2dade978b73ec08a6d6b41c35b90d0a5d1690c720763a014e9c862be5","blockNumber":2,"slot":3} 19:26:51 [19:26:51.430] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 19:26:51 [19:26:51.440] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x20fc05b71e82aa9787703eada18b2ad97e09fbb7c9feecdb0a0126331e517ea3,0x0000000000000000000000000000000000000000000000000000000000000001,0x076a8e444fc8a202026eb846c9c0c38b17d5fde2f91c7d059362746b6f270d33,0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb,0x037a000b29a7c64007bd8f7e46d1eb4ab31511ea43e9f71753d25ffb677c216e,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a 19:26:51 [19:26:51.549] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:51 [19:26:51.913] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:52 [19:26:52.094] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x25bc4eb2dade978b73ec08a6d6b41c35b90d0a5d1690c720763a014e9c862be5","blockNumber":2,"slot":3} 19:26:52 [19:26:52.262] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:52 [19:26:52.861] INFO: pxe:service Proving completed in 2712.0343299999986ms {"timings":{"total":2712.0343299999986,"sync":12.606236999999965,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":373.839570999995,"oracles":{"debugLog":{"times":[0.5948019999996177,0.6595830000005662,0.7277450000001409,2.0721840000005614,0.6879549999994197,0.4409689999993134,0.9285600000002887,1.2449569999989762]},"fetchTaggedLogs":{"times":[101.40840599999865]},"loadCapsule":{"times":[0.6713140000010753,2.3109889999996085,0.723095999999714,2.4679530000030354,0.8271769999992102,0.4669899999971676]},"getSharedSecret":{"times":[6.354074999999284]},"aes128Decrypt":{"times":[1.8627699999997276,1.140953999998601]},"getPublicKeysAndPartialAddress":{"times":[6.990898000000016]},"getKeyValidationRequest":{"times":[3.0462050000005547]},"deliverNote":{"times":[21.406543000000966]},"deleteCapsule":{"times":[2.4606720000010682]},"storeCapsule":{"times":[4.008714999999938]},"getNotes":{"times":[2.2314970000006724]},"getAuthWitness":{"times":[0.3258270000005723]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.0899520000020857]},"callPrivateFunction":{"times":[779.8587660000012,139.92556100000002]},"notifyEnqueuedPublicFunctionCall":{"times":[0.27926600000137114]}}},{"functionName":"private_kernel_init","time":90.61447799999951},{"functionName":"ContractClassRegisterer:register","time":764.9972420000049,"oracles":{"loadCapsule":{"times":[3.7041690000005474]},"notifyCreatedNullifier":{"times":[0.5901519999970333]},"debugLog":{"times":[0.6817550000014307]},"notifyCreatedContractClassLog":{"times":[6.924756000000343]}}},{"functionName":"private_kernel_inner","time":182.78206900000077},{"functionName":"ContractInstanceDeployer:deploy","time":88.41021199999523,"oracles":{"storeInExecutionCache":{"times":[0.3036359999969136]},"callPrivateFunction":{"times":[44.31562800000029]},"notifyCreatedNullifier":{"times":[0.26781599999958416]},"debugLog":{"times":[0.500700999997207]}}},{"functionName":"private_kernel_inner","time":164.91591999999946},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":33.6706030000023,"oracles":{}},{"functionName":"private_kernel_inner","time":123.6520070000006},{"functionName":"private_kernel_reset","time":264.781164},{"functionName":"private_kernel_tail","time":128.06997099999717}],"unaccounted":483.6948560000037}} 19:26:52 [19:26:52.897] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:53 [19:26:53.182] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:53 [19:26:53.191] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x25bc4eb2dade978b73ec08a6d6b41c35b90d0a5d1690c720763a014e9c862be5","blockNumber":2,"slot":3} 19:26:53 [19:26:53.218] VERBOSE: p2p:tx_pool Adding tx 0x26048e843fa9d026f9128c135cc8d9500005de8f654f325d25b74408ce7cd9a9 to pool {"eventName":"tx-added-to-pool","txHash":"0x26048e843fa9d026f9128c135cc8d9500005de8f654f325d25b74408ce7cd9a9","noteHashCount":0,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":709,"proofSize":172052,"size":324937,"feePaymentMethod":"fee_juice"} 19:26:53 [19:26:53.250] INFO: node Received tx 0x26048e843fa9d026f9128c135cc8d9500005de8f654f325d25b74408ce7cd9a9 {"txHash":"0x26048e843fa9d026f9128c135cc8d9500005de8f654f325d25b74408ce7cd9a9"} 19:26:53 [19:26:53.250] INFO: pxe:service Sent transaction 0x26048e843fa9d026f9128c135cc8d9500005de8f654f325d25b74408ce7cd9a9 19:26:53 [19:26:53.391] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:53 [19:26:53.599] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:53 [19:26:53.712] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 19:26:53 [19:26:53.733] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-17T19:31:40.000Z","now":"2025-06-17T19:31:45.452Z"} 19:26:53 [19:26:53.904] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:54 [19:26:54.114] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:54 [19:26:54.322] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:54 [19:26:54.421] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x5e134abf completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x5e134abf","duration":308.624452} 19:26:54 [19:26:54.643] VERBOSE: simulator:public-processor Processed tx 0x26048e843fa9d026f9128c135cc8d9500005de8f654f325d25b74408ce7cd9a9 with 1 public calls in 716.2755399999987ms {"txHash":"0x26048e843fa9d026f9128c135cc8d9500005de8f654f325d25b74408ce7cd9a9","txFee":139009277500,"revertCode":0,"gasUsed":{"totalGas":{"daGas":380416,"l2Gas":418375},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":6144,"l2Gas":381831},"billedGas":{"daGas":380416,"l2Gas":418375}},"publicDataWriteCount":12,"nullifierCount":4,"noteHashCount":0,"contractClassLogCount":1,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":716.2755399999987} 19:26:54 [19:26:54.645] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:54 [19:26:54.650] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.9164120660000008s {"duration":0.9164120660000008,"rate":416658.63443574484,"totalPublicGas":{"daGas":6144,"l2Gas":381831},"totalBlockGas":{"daGas":380416,"l2Gas":418375},"totalSizeInBytes":24384} 19:26:54 [19:26:54.891] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:54 [19:26:54.929] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 313596.44616483065 mana/s {"blockHash":"0x19f10b77d4a2ac222ba589a2945e0eaf7f72cbbe3fa7a44004b216c6829d9564","globalVariables":{"chainId":31337,"version":3213983016,"blockNumber":2,"slotNumber":3,"timestamp":1750188700,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x26048e843fa9d026f9128c135cc8d9500005de8f654f325d25b74408ce7cd9a9"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":1216.4938269999984,"publicProcessDuration":917.0731000000014,"rollupCircuitsDuration":1197.0417749999979,"txCount":1,"blockNumber":2,"blockTimestamp":1750188700,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":709} 19:26:54 [19:26:54.934] VERBOSE: sequencer Attesting committee is empty 19:26:55 [19:26:55.110] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:55 [19:26:55.292] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x6a06673c8119b38ff63bc47f9e6a8c4234553c6014f488013d960148d2f6a6f0 19:26:55 [19:26:55.292] VERBOSE: sequencer-client Sent L1 transaction 0x6a06673c8119b38ff63bc47f9e6a8c4234553c6014f488013d960148d2f6a6f0 {"gasLimit":470283,"maxFeePerGas":"1.303611197","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 19:26:55 [19:26:55.306] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 19:26:55 [19:26:55.307] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x6a06673c8119b38ff63bc47f9e6a8c4234553c6014f488013d960148d2f6a6f0","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":2,"blockTimestamp":1750188700,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":709} 19:26:55 [19:26:55.308] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 19:26:55 [19:26:55.312] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:55 [19:26:55.339] INFO: blob-sink:server Received blob sidecar for block 0xdf0bc569d8e405cd312d10382ee911492ed36c79fb2b47f7364585ad5baeaf5c 19:26:55 [19:26:55.345] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 19:26:55 [19:26:55.353] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 19:26:55 [19:26:55.357] INFO: blob-sink:server Blob sidecar stored successfully for block 0xdf0bc569d8e405cd312d10382ee911492ed36c79fb2b47f7364585ad5baeaf5c 19:26:55 [19:26:55.378] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 19:26:55 [19:26:55.384] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750188736] on http://127.0.0.1:8545 19:26:55 [19:26:55.387] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 19:26:55 [19:26:55.389] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750188736 19:26:55 [19:26:55.390] WARN: foundation:test-date-provider Time set to 2025-06-17T19:32:16.000Z {"offset":320610,"timeMs":1750188736000} 19:26:55 [19:26:55.390] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 19:26:55 [19:26:55.532] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:55 [19:26:55.734] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:56 [19:26:56.043] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:56 [19:26:56.074] VERBOSE: archiver:block-helper Store contract instance at 0x20fc05b71e82aa9787703eada18b2ad97e09fbb7c9feecdb0a0126331e517ea3 19:26:56 [19:26:56.104] VERBOSE: archiver:block-helper Store contract class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb 19:26:56 [19:26:56.151] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x19f10b77d4a2ac222ba589a2945e0eaf7f72cbbe3fa7a44004b216c6829d9564","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":3213983016,"blockNumber":2,"slotNumber":3,"timestamp":1750188700,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2de7cc7e5c24824f9c809b4136fdc13f4f876b311d9ceb3260202d29975bb6e5","archiveNextLeafIndex":3} 19:26:56 [19:26:56.158] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 19:26:56 [19:26:56.176] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":7.534439999999449,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750188700,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":709} 19:26:56 [19:26:56.255] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:56 [19:26:56.277] VERBOSE: p2p Synched to latest block 2 19:26:56 [19:26:56.569] INFO: aztecjs:deploy_sent_tx Contract 0x20fc05b71e82aa9787703eada18b2ad97e09fbb7c9feecdb0a0126331e517ea3 successfully deployed. 19:26:56 [19:26:56.572] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:56 [19:26:56.587] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2de7cc7e5c24824f9c809b4136fdc13f4f876b311d9ceb3260202d29975bb6e5","blockNumber":3,"slot":4} 19:26:56 [19:26:56.605] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x2de7cc7e5c24824f9c809b4136fdc13f4f876b311d9ceb3260202d29975bb6e5","header":{"contentCommitment":{"blobsHash":"0x00e6a01751ea1e86939e3363b7dae39051d70ea09ac249db93329a5420699d1f","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750188700,"version":3213983016},"lastArchive":"0x25bc4eb2dade978b73ec08a6d6b41c35b90d0a5d1690c720763a014e9c862be5","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x01f3924540932d3440666a765da3b63be06b148e26c077cdf52545d5b7fd8eac","nullifierTree":"0x18f1634d1cd26a5d9b3ab463daf470df9385a82c4d2a27770a7fb5134bf1ac38","publicDataTree":"0x1dcef52d41ac105099a700ee456dae6fe04aee2fd995fa3a488416bb13b00791"},"totalFees":139009277500,"totalManaUsed":418375}} 19:26:56 [19:26:56.712] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:56 [19:26:56.765] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:26:56 [19:26:56.765] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:56 [19:26:56.797] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:56 [19:26:56.843] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:56 [19:26:56.848] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x29dcf61b9784386c24698190e94c2c1cb0a0e2540d241d5416214dbb9a6fa408 19:26:56 [19:26:56.858] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:26:56 [19:26:56.858] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 19:26:56 [19:26:56.866] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:26:56 [19:26:56.867] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:26:56 [19:26:56.873] VERBOSE: pxe:service Added note {"index":0,"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2cc9faaa4349caf43f5d1e67e2d4b8a6d8ba6dbf4ce22f20e04365d71ea52391","nullifier":"0x11fb83616a016eb531ccbb1223b3334891ad913726b3fa72929ff780ea0bed89"} 19:26:56 [19:26:56.875] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:26:56 [19:26:56.890] VERBOSE: simulator:client_execution_context:debug_log Setting 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a as fee payer 19:26:57 [19:26:57.060] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:57 [19:26:57.310] INFO: pxe:service Proving completed in 714.0388829999974ms {"timings":{"total":714.0388829999974,"sync":10.559614000001602,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":265.05481900000086,"oracles":{"debugLog":{"times":[0.6002619999999297,0.4038780000009865,0.41311900000073365,0.40471899999829475,0.4222489999992831,0.27636599999823375,0.3284270000003744,0.6572630000009667]},"fetchTaggedLogs":{"times":[81.05636500000037]},"loadCapsule":{"times":[0.2074639999991632,0.17800400000123773,0.3286770000013348,0.23206500000014785,0.26863500000035856,0.19481399999858695]},"getSharedSecret":{"times":[3.7938800000010815]},"aes128Decrypt":{"times":[0.22840500000165775,0.6483740000003309]},"getPublicKeysAndPartialAddress":{"times":[1.907961000000796]},"getKeyValidationRequest":{"times":[1.8045679999995627]},"deliverNote":{"times":[5.966026000001875]},"deleteCapsule":{"times":[0.5764920000001439]},"storeCapsule":{"times":[0.3876380000001518]},"getNotes":{"times":[2.004531999999017]},"getAuthWitness":{"times":[0.22335499999826425]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.08896199999799137]},"notifyEnqueuedPublicFunctionCall":{"times":[0.12398300000131712]}}},{"functionName":"private_kernel_init","time":67.36796600000162},{"functionName":"private_kernel_reset","time":166.96202400000038},{"functionName":"private_kernel_tail","time":85.33429599999727}],"unaccounted":118.76016399999571}} 19:26:57 [19:26:57.342] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:57 [19:26:57.384] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2de7cc7e5c24824f9c809b4136fdc13f4f876b311d9ceb3260202d29975bb6e5","blockNumber":3,"slot":4} 19:26:57 [19:26:57.389] VERBOSE: p2p:tx_pool Adding tx 0x1a76bb1bd6d5b49954f6ceea91413bb846bbae059feaf64e2c76fe1b0d0f13c1 to pool {"eventName":"tx-added-to-pool","txHash":"0x1a76bb1bd6d5b49954f6ceea91413bb846bbae059feaf64e2c76fe1b0d0f13c1","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226345,"feePaymentMethod":"fee_juice"} 19:26:57 [19:26:57.397] INFO: node Received tx 0x1a76bb1bd6d5b49954f6ceea91413bb846bbae059feaf64e2c76fe1b0d0f13c1 {"txHash":"0x1a76bb1bd6d5b49954f6ceea91413bb846bbae059feaf64e2c76fe1b0d0f13c1"} 19:26:57 [19:26:57.398] INFO: pxe:service Sent transaction 0x1a76bb1bd6d5b49954f6ceea91413bb846bbae059feaf64e2c76fe1b0d0f13c1 19:26:57 [19:26:57.547] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:57 [19:26:57.751] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:57 [19:26:57.915] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 19:26:57 [19:26:57.931] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-17T19:32:16.000Z","now":"2025-06-17T19:32:18.541Z"} 19:26:57 [19:26:57.954] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:58 [19:26:58.011] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x451b5fae completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x451b5fae","duration":54.21018699999695} 19:26:58 [19:26:58.035] VERBOSE: simulator:public-processor Processed tx 0x1a76bb1bd6d5b49954f6ceea91413bb846bbae059feaf64e2c76fe1b0d0f13c1 with 1 public calls in 87.92197099999976ms {"txHash":"0x1a76bb1bd6d5b49954f6ceea91413bb846bbae059feaf64e2c76fe1b0d0f13c1","txFee":22774761700,"revertCode":0,"gasUsed":{"totalGas":{"daGas":2048,"l2Gas":68545},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":1024,"l2Gas":42689},"billedGas":{"daGas":2048,"l2Gas":68545}},"publicDataWriteCount":3,"nullifierCount":1,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":87.92197099999976} 19:26:58 [19:26:58.036] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.10400868099999934s {"duration":0.10400868099999934,"rate":410436.89420501614,"totalPublicGas":{"daGas":1024,"l2Gas":42689},"totalBlockGas":{"daGas":2048,"l2Gas":68545},"totalSizeInBytes":384} 19:26:58 [19:26:58.143] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 185225.23364981264 mana/s {"blockHash":"0x00a893fc9c90aa07ef8eba6468974815bc1c78ce3d011f60aab4ebd69ec1d86b","globalVariables":{"chainId":31337,"version":3213983016,"blockNumber":3,"slotNumber":4,"timestamp":1750188736,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1a76bb1bd6d5b49954f6ceea91413bb846bbae059feaf64e2c76fe1b0d0f13c1"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":229.94818700000178,"publicProcessDuration":104.37802899999951,"rollupCircuitsDuration":212.9940379999971,"txCount":1,"blockNumber":3,"blockTimestamp":1750188736,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 19:26:58 [19:26:58.145] VERBOSE: sequencer Attesting committee is empty 19:26:58 [19:26:58.236] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:58 [19:26:58.417] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x42a5140c52bf4e2212b7c7eac00a8fb8f1e485bc3e529b6701213a7a15a387ce 19:26:58 [19:26:58.417] VERBOSE: sequencer-client Sent L1 transaction 0x42a5140c52bf4e2212b7c7eac00a8fb8f1e485bc3e529b6701213a7a15a387ce {"gasLimit":470326,"maxFeePerGas":"1.279583398","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 19:26:58 [19:26:58.430] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 19:26:58 [19:26:58.431] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x42a5140c52bf4e2212b7c7eac00a8fb8f1e485bc3e529b6701213a7a15a387ce","calldataSize":1444,"calldataGas":12328,"txCount":1,"blockNumber":3,"blockTimestamp":1750188736,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 19:26:58 [19:26:58.431] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 19:26:58 [19:26:58.437] INFO: blob-sink:server Received blob sidecar for block 0xadfcef73fced075d65cb0dea32fcdf39bb68a52845bab9c873e8b32f50d3de78 19:26:58 [19:26:58.441] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:58 [19:26:58.446] INFO: blob-sink:server Blob sidecar stored successfully for block 0xadfcef73fced075d65cb0dea32fcdf39bb68a52845bab9c873e8b32f50d3de78 19:26:58 [19:26:58.457] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 19:26:58 [19:26:58.460] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 19:26:58 [19:26:58.465] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 19:26:58 [19:26:58.475] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750188772] on http://127.0.0.1:8545 19:26:58 [19:26:58.477] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 19:26:58 [19:26:58.484] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750188772 19:26:58 [19:26:58.485] WARN: foundation:test-date-provider Time set to 2025-06-17T19:32:52.000Z {"offset":353515,"timeMs":1750188772000} 19:26:58 [19:26:58.485] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 19:26:58 [19:26:58.581] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x00a893fc9c90aa07ef8eba6468974815bc1c78ce3d011f60aab4ebd69ec1d86b","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":3213983016,"blockNumber":3,"slotNumber":4,"timestamp":1750188736,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2a7e5c673252adff55ec384fe9263ec742adc158594c38e31d0a982f1e55f18b","archiveNextLeafIndex":4} 19:26:58 [19:26:58.649] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:58 [19:26:58.692] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":7.975808999999572,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750188736,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 19:26:58 [19:26:58.703] VERBOSE: p2p Synched to latest block 3 19:26:58 [19:26:58.851] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:58 [19:26:58.954] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2a7e5c673252adff55ec384fe9263ec742adc158594c38e31d0a982f1e55f18b","blockNumber":4,"slot":5} 19:26:59 [19:26:59.054] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:59 [19:26:59.103] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 19:26:59 [19:26:59.256] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:59 [19:26:59.424] INFO: e2e:e2e_mempool_limit Running test: e2e_mempool_limit should evict txs if there are too many 19:26:59 [19:26:59.444] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x2a7e5c673252adff55ec384fe9263ec742adc158594c38e31d0a982f1e55f18b","header":{"contentCommitment":{"blobsHash":"0x00b58f521aff5fa3b2faf9c28f7e230dd7f560d4691507d2c7db3d1399cdbaf4","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750188736,"version":3213983016},"lastArchive":"0x2de7cc7e5c24824f9c809b4136fdc13f4f876b311d9ceb3260202d29975bb6e5","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x01f3924540932d3440666a765da3b63be06b148e26c077cdf52545d5b7fd8eac","nullifierTree":"0x2407eecdde8e52d2866d8f86563bd684d9e8ddba3ec4efb4e6b68c62dabed186","publicDataTree":"0x044a588dd8ef46caf26e04dae2966127e96b91a53ef783033545550e8262843a"},"totalFees":22774761700,"totalManaUsed":68545}} 19:26:59 [19:26:59.468] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:59 [19:26:59.551] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:59 [19:26:59.611] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:26:59 [19:26:59.612] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:59 [19:26:59.668] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2a7e5c673252adff55ec384fe9263ec742adc158594c38e31d0a982f1e55f18b","blockNumber":4,"slot":5} 19:26:59 [19:26:59.703] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:26:59 [19:26:59.707] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:26:59 [19:26:59.711] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x29dcf61b9784386c24698190e94c2c1cb0a0e2540d241d5416214dbb9a6fa408 19:26:59 [19:26:59.719] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:26:59 [19:26:59.720] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 19:26:59 [19:26:59.727] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:26:59 [19:26:59.728] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:26:59 [19:26:59.734] VERBOSE: pxe:service Added note {"index":0,"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2cc9faaa4349caf43f5d1e67e2d4b8a6d8ba6dbf4ce22f20e04365d71ea52391","nullifier":"0x11fb83616a016eb531ccbb1223b3334891ad913726b3fa72929ff780ea0bed89"} 19:26:59 [19:26:59.736] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:26:59 [19:26:59.747] VERBOSE: simulator:client_execution_context:debug_log Setting 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a as fee payer 19:26:59 [19:26:59.911] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:27:00 [19:27:00.159] INFO: pxe:service Proving completed in 720.6940340000001ms {"timings":{"total":720.6940340000001,"sync":7.254972999999154,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":261.4185019999968,"oracles":{"debugLog":{"times":[1.0738019999989774,0.3950189999995928,0.39015800000197487,0.40923800000018673,0.3687279999976454,0.3015159999995376,0.26265600000260747,0.37960799999927985]},"fetchTaggedLogs":{"times":[95.08186199999909]},"loadCapsule":{"times":[1.7300559999966936,0.8365379999995639,0.34108699999706005,0.19807399999990594,0.31392699999923934,0.14789299999756622]},"getSharedSecret":{"times":[2.6981869999981427]},"aes128Decrypt":{"times":[0.21793500000057975,0.7145850000015344]},"getPublicKeysAndPartialAddress":{"times":[1.9597719999983383]},"getKeyValidationRequest":{"times":[2.701927000001888]},"deliverNote":{"times":[6.414905000001454]},"deleteCapsule":{"times":[0.21546399999715504]},"storeCapsule":{"times":[0.23381499999959487]},"getNotes":{"times":[1.1206230000025243]},"getAuthWitness":{"times":[0.15887300000031246]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.04303099999742699]},"notifyEnqueuedPublicFunctionCall":{"times":[0.05009100000097533]}}},{"functionName":"private_kernel_init","time":68.42891800000143},{"functionName":"private_kernel_reset","time":166.06143499999962},{"functionName":"private_kernel_tail","time":83.38819499999954}],"unaccounted":134.14201100000355}} 19:27:00 [19:27:00.162] INFO: sequencer Sequencer config set {"minTxsPerBlock":4} 19:27:00 [19:27:00.162] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 19:27:00 [19:27:00.162] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":432930} 19:27:00 [19:27:00.196] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 4) {"chainTipArchive":"0x2a7e5c673252adff55ec384fe9263ec742adc158594c38e31d0a982f1e55f18b","blockNumber":4,"slot":5} 19:27:00 [19:27:00.266] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:27:00 [19:27:00.304] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:27:00 [19:27:00.304] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:27:00 [19:27:00.382] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:27:00 [19:27:00.456] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:27:00 [19:27:00.473] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x29dcf61b9784386c24698190e94c2c1cb0a0e2540d241d5416214dbb9a6fa408 19:27:00 [19:27:00.549] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:27:00 [19:27:00.551] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 19:27:00 [19:27:00.592] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:27:00 [19:27:00.608] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:27:00 [19:27:00.609] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:27:00 [19:27:00.621] VERBOSE: pxe:service Added note {"index":0,"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2cc9faaa4349caf43f5d1e67e2d4b8a6d8ba6dbf4ce22f20e04365d71ea52391","nullifier":"0x11fb83616a016eb531ccbb1223b3334891ad913726b3fa72929ff780ea0bed89"} 19:27:00 [19:27:00.650] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:27:00 [19:27:00.672] VERBOSE: simulator:client_execution_context:debug_log Setting 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a as fee payer 19:27:00 [19:27:00.803] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:27:00 [19:27:00.826] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 4) {"chainTipArchive":"0x2a7e5c673252adff55ec384fe9263ec742adc158594c38e31d0a982f1e55f18b","blockNumber":4,"slot":5} 19:27:01 [19:27:01.206] INFO: pxe:service Proving completed in 1018.227691ms {"timings":{"total":1018.227691,"sync":9.714476000001014,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":467.83040200000323,"oracles":{"debugLog":{"times":[0.5382410000020172,0.6115129999998317,0.7677070000027015,1.180194999997184,0.6129629999995814,0.4215780000013183,0.7078550000005635,0.7674860000006447]},"fetchTaggedLogs":{"times":[163.03837099999873]},"loadCapsule":{"times":[0.6964049999987765,0.29571600000053877,2.4088510000001406,26.52335200000016,0.40575900000112597,0.8229769999998098]},"getSharedSecret":{"times":[15.486327999999048]},"aes128Decrypt":{"times":[0.40245900000081747,1.4100990000006277]},"getPublicKeysAndPartialAddress":{"times":[6.450377000001026]},"getKeyValidationRequest":{"times":[46.48288400000092]},"deliverNote":{"times":[11.706478000000061]},"deleteCapsule":{"times":[0.5586509999993723]},"storeCapsule":{"times":[0.4456090000021504]},"getNotes":{"times":[2.9672429999991436]},"getAuthWitness":{"times":[0.45218000000022585]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.08510199999727774]},"notifyEnqueuedPublicFunctionCall":{"times":[0.11758299999928568]}}},{"functionName":"private_kernel_init","time":62.935881999997946},{"functionName":"private_kernel_reset","time":212.60799000000043},{"functionName":"private_kernel_tail","time":97.68336699999782}],"unaccounted":167.45557399999961}} 19:27:01 [19:27:01.210] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:27:01 [19:27:01.297] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:27:01 [19:27:01.337] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:27:01 [19:27:01.337] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:27:01 [19:27:01.377] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 4) {"chainTipArchive":"0x2a7e5c673252adff55ec384fe9263ec742adc158594c38e31d0a982f1e55f18b","blockNumber":4,"slot":5} 19:27:01 [19:27:01.393] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a"} 19:27:01 [19:27:01.398] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x29dcf61b9784386c24698190e94c2c1cb0a0e2540d241d5416214dbb9a6fa408 19:27:01 [19:27:01.413] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:27:01 [19:27:01.414] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 19:27:01 [19:27:01.417] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:27:01 [19:27:01.427] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:27:01 [19:27:01.428] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:27:01 [19:27:01.431] VERBOSE: pxe:service Added note {"index":0,"contract":"0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2cc9faaa4349caf43f5d1e67e2d4b8a6d8ba6dbf4ce22f20e04365d71ea52391","nullifier":"0x11fb83616a016eb531ccbb1223b3334891ad913726b3fa72929ff780ea0bed89"} 19:27:01 [19:27:01.432] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:27:01 [19:27:01.443] VERBOSE: simulator:client_execution_context:debug_log Setting 0x242a2c282ceb975e60647673264990d513957fb4cdfe323e29bce5685e76965a as fee payer 19:27:01 [19:27:01.637] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:27:01 [19:27:01.923] INFO: pxe:service Proving completed in 697.6032190000005ms {"timings":{"total":697.6032190000005,"sync":2.6258559999987483,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":200.3873040000035,"oracles":{"debugLog":{"times":[0.6649140000008629,0.4069439999984752,0.598679999999149,0.45092400000066846,0.40622300000177347,0.26298899999892456,0.2248379999982717,0.49834700000064913]},"fetchTaggedLogs":{"times":[58.87689600000158]},"loadCapsule":{"times":[0.20000599999912083,0.15451500000199303,0.29635899999993853,0.21497699999963515,0.15488500000355998,0.16050600000016857]},"getSharedSecret":{"times":[7.431108999997377]},"aes128Decrypt":{"times":[0.6102700000010373,1.353043000002799]},"getPublicKeysAndPartialAddress":{"times":[6.884420999998838]},"getKeyValidationRequest":{"times":[3.562275000000227]},"deliverNote":{"times":[2.82452099999864]},"deleteCapsule":{"times":[0.26721900000120513]},"storeCapsule":{"times":[0.2554980000022624]},"getNotes":{"times":[1.074414999999135]},"getAuthWitness":{"times":[0.1528550000002724]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.04904200000237324]},"notifyEnqueuedPublicFunctionCall":{"times":[0.05026200000065728]}}},{"functionName":"private_kernel_init","time":86.38002899999992},{"functionName":"private_kernel_reset","time":203.57485099999758},{"functionName":"private_kernel_tail","time":82.06983099999707}],"unaccounted":122.56534800000372}} 19:27:01 [19:27:01.953] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:27:01 [19:27:01.986] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 4) {"chainTipArchive":"0x2a7e5c673252adff55ec384fe9263ec742adc158594c38e31d0a982f1e55f18b","blockNumber":4,"slot":5} 19:27:01 [19:27:01.992] VERBOSE: p2p:tx_pool Adding tx 0x1f23fbed9e97e6f798b1cc7623d96491250b1ec3e6dcfbba375dba6dedd68f78 to pool {"eventName":"tx-added-to-pool","txHash":"0x1f23fbed9e97e6f798b1cc7623d96491250b1ec3e6dcfbba375dba6dedd68f78","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226409,"feePaymentMethod":"fee_juice"} 19:27:02 [19:27:02.000] INFO: node Received tx 0x1f23fbed9e97e6f798b1cc7623d96491250b1ec3e6dcfbba375dba6dedd68f78 {"txHash":"0x1f23fbed9e97e6f798b1cc7623d96491250b1ec3e6dcfbba375dba6dedd68f78"} 19:27:02 [19:27:02.000] INFO: pxe:service Sent transaction 0x1f23fbed9e97e6f798b1cc7623d96491250b1ec3e6dcfbba375dba6dedd68f78 19:27:02 [19:27:02.044] VERBOSE: p2p:tx_pool Adding tx 0x0a03b2469ebab1960a82e4eec1609472042a53faf7d34ed155930bad585165dd to pool {"eventName":"tx-added-to-pool","txHash":"0x0a03b2469ebab1960a82e4eec1609472042a53faf7d34ed155930bad585165dd","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226409,"feePaymentMethod":"fee_juice"} 19:27:02 [19:27:02.054] INFO: node Received tx 0x0a03b2469ebab1960a82e4eec1609472042a53faf7d34ed155930bad585165dd {"txHash":"0x0a03b2469ebab1960a82e4eec1609472042a53faf7d34ed155930bad585165dd"} 19:27:02 [19:27:02.054] INFO: pxe:service Sent transaction 0x0a03b2469ebab1960a82e4eec1609472042a53faf7d34ed155930bad585165dd 19:27:02 [19:27:02.113] VERBOSE: p2p:tx_pool Adding tx 0x05b603969e45147dbe107ffa984076b9823aaacdc77610e6202478d9987fc10c to pool {"eventName":"tx-added-to-pool","txHash":"0x05b603969e45147dbe107ffa984076b9823aaacdc77610e6202478d9987fc10c","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226409,"feePaymentMethod":"fee_juice"} 19:27:02 [19:27:02.122] VERBOSE: p2p:tx_pool Evicting tx 0x05b603969e45147dbe107ffa984076b9823aaacdc77610e6202478d9987fc10c from pool due to low priority to satisfy max tx size limit {"txHash":"0x05b603969e45147dbe107ffa984076b9823aaacdc77610e6202478d9987fc10c","txSize":173172} 19:27:02 [19:27:02.128] INFO: node Received tx 0x05b603969e45147dbe107ffa984076b9823aaacdc77610e6202478d9987fc10c {"txHash":"0x05b603969e45147dbe107ffa984076b9823aaacdc77610e6202478d9987fc10c"} 19:27:02 [19:27:02.128] INFO: pxe:service Sent transaction 0x05b603969e45147dbe107ffa984076b9823aaacdc77610e6202478d9987fc10c 19:27:02 PASS src/e2e_mempool_limit.test.ts 19:27:02 e2e_mempool_limit 19:27:02 ✓ should evict txs if there are too many (2709 ms) 19:27:02 19:27:02 Test Suites: 1 passed, 1 total 19:27:02 Tests: 1 passed, 1 total 19:27:02 Snapshots: 0 total 19:27:02 Time: 27.557 s 19:27:02 Ran all test suites matching /src\/e2e_mempool_limit.test.ts/i. 19:27:02 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?