Parent Log: http://ci.aztec-labs.com/ad41d2c01b79593b Command: 21081b81c02e2d2a:ISOLATE=1:NAME=e2e_circuit_recorder yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_circuit_recorder.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/c06f30929f18b85bcea93c0926a8d640a3819f00 Env: REF_NAME=gh-readonly-queue/master/pr-15074-2707ec99de02004f3e30e7af0810e0d741ab9794 CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Mon Jun 16 16:19:11 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15074_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_45fa6151872caa8b_master 16:19:11 +++ id -u 16:19:11 +++ id -g 16:19:11 ++ docker run -d --name e2e_circuit_recorder --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_circuit_recorder.test.ts'\''' 16:19:12 + cid=bc1f9f05f726ffec4bdf213c640e8c5872f7612ce57fc59b052e5f047418fabd 16:19:12 + set +x 16:19:12 {"level":25,"time":1750090752809,"pid":11,"hostname":"bc1f9f05f726","module":"logger","msg":"Logger initialized with level verbose"} 16:19:19 [16:19:19.448] INFO: e2e:e2e_circuit_recorder Running test: Circuit Recorder records circuit execution 16:19:19 [16:19:19.677] INFO: e2e:Circuit Logging metrics to log/Circuit.jsonl 16:19:19 [16:19:19.943] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-FFItkk/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 16:19:19 [16:19:19.982] INFO: e2e:Circuit Calling anvil_setBlockTimestampInterval with params: [12] 16:19:20 [16:19:20.003] WARN: e2e:Circuit Set block interval to 12 16:19:20 [16:19:20.003] VERBOSE: e2e:Circuit Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 16:19:20 [16:19:20.267] VERBOSE: e2e:Circuit Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 16:19:20 [16:19:20.317] VERBOSE: e2e:Circuit Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 16:19:20 [16:19:20.347] VERBOSE: e2e:Circuit Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 16:19:20 [16:19:20.354] VERBOSE: e2e:Circuit Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 16:19:20 [16:19:20.375] VERBOSE: e2e:Circuit Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 16:19:20 [16:19:20.382] VERBOSE: e2e:Circuit Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 16:19:20 [16:19:20.398] VERBOSE: e2e:Circuit Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 16:19:20 [16:19:20.403] VERBOSE: e2e:Circuit Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 16:19:20 [16:19:20.443] VERBOSE: e2e:Circuit Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 16:19:20 [16:19:20.453] VERBOSE: e2e:Circuit Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 16:19:20 [16:19:20.467] VERBOSE: e2e:Circuit Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 16:19:20 [16:19:20.472] VERBOSE: e2e:Circuit Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 16:19:20 [16:19:20.472] VERBOSE: e2e:Circuit Waiting for deployments to complete 16:19:20 [16:19:20.473] INFO: e2e:Circuit Waiting for 6 transactions to be mined... 16:19:20 [16:19:20.493] INFO: e2e:Circuit All transactions mined successfully 16:19:20 [16:19:20.509] VERBOSE: e2e:Circuit Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 16:19:20 [16:19:20.510] VERBOSE: e2e:Circuit Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 16:19:20 [16:19:20.523] VERBOSE: e2e:Circuit Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 16:19:20 [16:19:20.539] VERBOSE: e2e:Circuit Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 16:19:20 [16:19:20.547] VERBOSE: e2e:Circuit Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 16:19:20 [16:19:20.560] VERBOSE: e2e:Circuit Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 16:19:20 [16:19:20.560] VERBOSE: e2e:Circuit Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 16:19:20 [16:19:20.590] VERBOSE: e2e:Circuit Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 16:19:20 [16:19:20.596] VERBOSE: e2e:Circuit Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 16:19:20 [16:19:20.611] VERBOSE: e2e:Circuit Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 16:19:20 [16:19:20.612] VERBOSE: e2e:Circuit Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 16:19:20 [16:19:20.612] VERBOSE: e2e:Circuit Waiting for deployments to complete 16:19:20 [16:19:20.612] INFO: e2e:Circuit Waiting for 8 transactions to be mined... 16:19:20 [16:19:20.634] INFO: e2e:Circuit All transactions mined successfully 16:19:20 [16:19:20.647] VERBOSE: e2e:Circuit Deployed shared contracts 16:19:20 [16:19:20.674] VERBOSE: e2e:Circuit Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 16:19:20 [16:19:20.675] VERBOSE: e2e:Circuit Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 16:19:20 [16:19:20.681] VERBOSE: e2e:Circuit Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 16:19:20 [16:19:20.703] VERBOSE: e2e:Circuit Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 16:19:20 [16:19:20.737] VERBOSE: e2e:Circuit Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 16:19:20 [16:19:20.742] VERBOSE: e2e:Circuit Awaiting for linked libraries to be deployed 16:19:20 [16:19:20.784] VERBOSE: e2e:Circuit Sent L1 transaction 0x2d0fc0b5c566ba43b955a38fa75b93e1a65de033fa9392d225a037838057f69a {"gasLimit":10407394,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 16:19:20 [16:19:20.791] VERBOSE: e2e:Circuit Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016 {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 16:19:20 [16:19:20.792] INFO: e2e:Circuit Waiting for 9 transactions to be mined... 16:19:20 [16:19:20.809] INFO: e2e:Circuit All transactions mined successfully 16:19:20 [16:19:20.809] VERBOSE: e2e:Circuit All core contracts have been deployed 16:19:20 [16:19:20.823] VERBOSE: e2e:Circuit Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 16:19:20 [16:19:20.823] VERBOSE: e2e:Circuit Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 16:19:20 [16:19:20.845] VERBOSE: e2e:Circuit Sent L1 transaction 0x3076e8ed3c707fd6d82a919b677cf7a63a9c4828413a41044e3272f6f71e410a {"gasLimit":716194,"maxFeePerGas":"26.850966158","maxPriorityFeePerGas":"26.623333274"} 16:19:20 [16:19:20.850] VERBOSE: e2e:Circuit Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 16:19:20 [16:19:20.878] VERBOSE: e2e:Circuit Sent L1 transaction 0xcd2cca55b67399deb9b7db167c38dc1d22e6d4d796fedc3ba097570542992776 {"gasLimit":116953,"maxFeePerGas":"32.147280374","maxPriorityFeePerGas":"31.947999928"} 16:19:20 [16:19:20.879] VERBOSE: e2e:Circuit Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0xcd2cca55b67399deb9b7db167c38dc1d22e6d4d796fedc3ba097570542992776 16:19:20 [16:19:20.879] INFO: e2e:Circuit Waiting for 10 transactions to be mined... 16:19:20 [16:19:20.907] INFO: e2e:Circuit All transactions mined successfully 16:19:20 [16:19:20.916] VERBOSE: e2e:Circuit Rollup deployed 16:19:20 [16:19:20.916] VERBOSE: e2e:Circuit Waiting for rollup and slash factory to be deployed 16:19:20 [16:19:20.916] INFO: e2e:Circuit Waiting for 10 transactions to be mined... 16:19:20 [16:19:20.936] INFO: e2e:Circuit All transactions mined successfully 16:19:20 [16:19:20.936] VERBOSE: e2e:Circuit All transactions for L1 deployment have been mined 16:19:20 [16:19:20.970] INFO: e2e:Circuit 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"} 16:19:20 [16:19:20.980] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 16:19:20 [16:19:20.986] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:20 [16:19:20.987] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 16:19:20 [16:19:20.989] INFO: telemetry:client Using NoopTelemetryClient 16:19:20 [16:19:20.999] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 16:19:20 [16:19:21.000] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 16:19:21 [16:19:21.002] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/5ce1dbba301b99eb/blob-sink with map size 134217728 KB (LMDB v2) 16:19:21 [16:19:21.004] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:21 [16:19:21.007] INFO: aztecjs:utils:watcher New L1 time: 1750090999 16:19:21 [16:19:21.008] WARN: foundation:test-date-provider Time set to 2025-06-16T16:23:19.000Z {"offset":237992,"timeMs":1750090999000} 16:19:21 [16:19:21.009] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 16:19:21 [16:19:21.017] INFO: blob-sink:server Server is running on http://localhost:46785 16:19:21 [16:19:21.017] VERBOSE: e2e:Circuit Creating and synching an aztec node... 16:19:21 [16:19:21.018] VERBOSE: e2e:Circuit Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/21a1f3b1/acvm 16:19:21 [16:19:21.018] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:46785"} 16:19:21 [16:19:21.030] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 16:19:21 [16:19:21.031] INFO: archiver:lmdb Creating archiver data store at directory /tmp/5ce1dbba301b99eb/archiver with map size 134217728 KB (LMDB v2) 16:19:21 [16:19:21.032] INFO: archiver:lmdb Starting data store with maxReaders 16 16:19:21 [16:19:21.286] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:21 [16:19:21.509] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:21 [16:19:21.524] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:46785"} 16:19:21 [16:19:21.554] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:46785"} 16:19:21 [16:19:21.554] WARN: node:blob-sink:client No L1 consensus host urls configured 16:19:21 [16:19:21.554] WARN: node:blob-sink:client No archive client configured 16:19:21 [16:19:21.558] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x4ccb0aa6f62b6affea26a737ba0febc096f31420ebb72303e38a59d264eda63b {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0xd96d74a1118e00a202c8bfb8a339ccab87a2ecb24db5846f5aed0e943b84c289","l1BlockNumber":16}} 16:19:21 [16:19:21.584] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0x4ccb0aa6f62b6affea26a737ba0febc096f31420ebb72303e38a59d264eda63b","l1BlockNumber":20}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 16:19:21 [16:19:21.592] INFO: world-state:database Creating world state data store at directory /tmp/5ce1dbba301b99eb/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 16:19:21 [16:19:21.629] INFO: world_state Created world state synchroniser with block history of 64 16:19:21 [16:19:21.630] WARN: node Aztec node is accepting fake proofs 16:19:21 [16:19:21.651] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/5ce1dbba301b99eb/p2p with map size 134217728 KB (LMDB v2) 16:19:21 [16:19:21.654] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 16:19:21 [16:19:21.658] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/5ce1dbba301b99eb/p2p-archive with map size 134217728 KB (LMDB v2) 16:19:21 [16:19:21.660] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 16:19:21 [16:19:21.662] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/5ce1dbba301b99eb/p2p-peers with map size 134217728 KB (LMDB v2) 16:19:21 [16:19:21.663] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 16:19:21 [16:19:21.665] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 16:19:21 [16:19:21.666] VERBOSE: p2p P2P is disabled. Using dummy P2P service 16:19:21 [16:19:21.669] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 16:19:21 [16:19:21.670] INFO: world_state Started world state synchronizer from block 1 16:19:21 [16:19:21.672] VERBOSE: world-state:block_stream Starting sync from block number 0 16:19:21 [16:19:21.674] INFO: p2p Starting p2p client from block 0 with empty mempools 16:19:21 [16:19:21.674] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 16:19:21 [16:19:21.676] INFO: slasher Slasher client initialized 16:19:21 [16:19:21.676] INFO: slasher Starting Slasher client... 16:19:21 [16:19:21.720] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 16:19:21 [16:19:21.721] VERBOSE: node All Aztec Node subsystems synced 16:19:21 [16:19:21.729] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:21 [16:19:21.732] INFO: sequencer-client Deploying forwarder contract 16:19:21 [16:19:21.738] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 16:19:21 [16:19:21.752] VERBOSE: sequencer-client Sent L1 transaction 0xb1f3df2017e7ba07c13a74da934c53780ad6675aaaf877e947cca2001f9da968 {"gasLimit":436981,"maxFeePerGas":"1.353583762","maxPriorityFeePerGas":"1.2"} 16:19:21 [16:19:21.753] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xb1f3df2017e7ba07c13a74da934c53780ad6675aaaf877e947cca2001f9da968. 16:19:21 [16:19:21.758] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 16:19:21 [16:19:21.772] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 16:19:21 [16:19:21.773] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 16:19:21 [16:19:21.773] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/21a1f3b1/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 16:19:22 [16:19:22.054] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 16:19:22 [16:19:22.058] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 16:19:22 [16:19:22.061] INFO: node Aztec Node version: 0.87.8 16:19:22 [16:19:22.061] 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"} 16:19:22 [16:19:22.063] VERBOSE: e2e:Circuit Creating a pxe... 16:19:22 [16:19:22.075] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:22 [16:19:22.088] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/53fb57fcd4c6614f/pxe_data with map size 134217728 KB (LMDB v2) 16:19:22 [16:19:22.107] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x12a955ab7424fe8431911b23e9f97a1ad9fd65a2f35736c1ae00f41cbe90bae7","blockNumber":1,"slot":2} 16:19:22 [16:19:22.113] INFO: pxe:data:lmdb Starting data store with maxReaders 16 16:19:22 [16:19:22.319] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:22 [16:19:22.586] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:22 [16:19:22.645] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 16:19:22 [16:19:22.647] INFO: pxe:service Started PXE connected to chain 31337 version 594854967 16:19:22 [16:19:22.726] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x12a955ab7424fe8431911b23e9f97a1ad9fd65a2f35736c1ae00f41cbe90bae7","blockNumber":1,"slot":2} 16:19:22 [16:19:22.743] INFO: pxe:service Registered account 0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca 16:19:22 [16:19:22.901] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:22 [16:19:22.908] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 16:19:22 [16:19:22.917] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca 16:19:23 [16:19:23.095] INFO: pxe:service Added contract SchnorrAccount at 0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 16:19:23 [16:19:23.136] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:23 [16:19:23.149] VERBOSE: pxe:block_stream Starting sync from block number 0 16:19:23 [16:19:23.207] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 16:19:23 [16:19:23.268] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x12a955ab7424fe8431911b23e9f97a1ad9fd65a2f35736c1ae00f41cbe90bae7","blockNumber":1,"slot":2} 16:19:23 [16:19:23.313] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 16:19:23 [16:19:23.366] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:23 [16:19:23.582] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:24 [16:19:24.186] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:24 [16:19:24.203] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 16:19:24 [16:19:24.382] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 16:19:24 [16:19:24.497] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:24 [16:19:24.501] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x12a955ab7424fe8431911b23e9f97a1ad9fd65a2f35736c1ae00f41cbe90bae7","blockNumber":1,"slot":2} 16:19:24 [16:19:24.684] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca"} 16:19:24 [16:19:24.732] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:24 [16:19:24.747] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:19:24 [16:19:24.754] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca"} 16:19:24 [16:19:24.813] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca"} 16:19:24 [16:19:24.822] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:19:24 [16:19:24.959] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:25 [16:19:25.038] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x12a955ab7424fe8431911b23e9f97a1ad9fd65a2f35736c1ae00f41cbe90bae7","blockNumber":1,"slot":2} 16:19:25 [16:19:25.150] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca"} 16:19:25 [16:19:25.170] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:25 [16:19:25.216] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:19:25 [16:19:25.237] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca"} 16:19:25 [16:19:25.300] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca"} 16:19:25 [16:19:25.310] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:19:25 [16:19:25.338] VERBOSE: simulator:client_execution_context:debug_log Setting 0x2ac189f5c6ee7e786f995b0080245d9d727e80df9f94e21e419f4ea1c683b5ca as fee payer 16:19:25 [16:19:25.386] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:25 [16:19:25.601] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:25 [16:19:25.987] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x12a955ab7424fe8431911b23e9f97a1ad9fd65a2f35736c1ae00f41cbe90bae7","blockNumber":1,"slot":2} 16:19:25 [16:19:25.992] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:26 [16:19:26.307] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:26 [16:19:26.561] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:26 [16:19:26.632] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x12a955ab7424fe8431911b23e9f97a1ad9fd65a2f35736c1ae00f41cbe90bae7","blockNumber":1,"slot":2} 16:19:26 [16:19:26.830] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:27 [16:19:27.065] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:27 [16:19:27.443] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:27 [16:19:27.446] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x12a955ab7424fe8431911b23e9f97a1ad9fd65a2f35736c1ae00f41cbe90bae7","blockNumber":1,"slot":2} 16:19:27 [16:19:27.510] INFO: pxe:service Proving completed in 4376.547418ms {"timings":{"total":4376.547418,"sync":16.16858499999944,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":237.2464519999976,"oracles":{"callPrivateFunction":{"times":[1258.8720840000005,472.16983699999946,363.1528980000003]}}},{"functionName":"private_kernel_init","time":262.7341469999992},{"functionName":"ContractClassRegisterer:register","time":1235.4779219999982,"oracles":{"loadCapsule":{"times":[27.08972299999914]},"notifyCreatedNullifier":{"times":[19.312529000000723]},"debugLog":{"times":[1.4566540000014356]},"notifyCreatedContractClassLog":{"times":[18.654849999998987]}}},{"functionName":"private_kernel_inner","time":395.18573800000013},{"functionName":"SchnorrAccount:constructor","time":427.11591600000247,"oracles":{"getContractInstance":{"times":[5.358700999999201]},"debugLog":{"times":[0.7943340000001626,0.6802410000000236]},"fetchTaggedLogs":{"times":[64.40938099999948]},"loadCapsule":{"times":[0.476265000001149,0.3569599999991624,0.28386800000043877]},"notifyCreatedNullifier":{"times":[0.33461999999963155,0.3768420000014885]},"notifyCreatedNote":{"times":[0.6007680000002438]},"getRandomField":{"times":[0.4509039999993547,0.3175790000004781,0.17550499999924796,0.09012199999961013,0.08138300000064191,0.0680819999997766,0.0669819999984611,0.11265300000013667,0.07361200000013923,0.0969729999997071,0.05238199999985227]},"getIndexedTaggingSecretAsSender":{"times":[40.83961499999896]},"incrementAppTaggingSecretIndexAsSender":{"times":[69.52021400000012]}}},{"functionName":"private_kernel_inner","time":157.57599400000072},{"functionName":"SchnorrAccount:entrypoint","time":315.68314499999906,"oracles":{"debugLog":{"times":[0.9004779999995662,0.6652500000000146,2.286568999999872]},"fetchTaggedLogs":{"times":[66.65178799999921]},"loadCapsule":{"times":[0.3206499999996595,2.5995180000008986,0.3064190000004601]},"getNotes":{"times":[2.8487850000001345]},"getAuthWitness":{"times":[0.7062809999988531]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.4611340000010387]}}},{"functionName":"private_kernel_inner","time":197.11463000000003},{"functionName":"private_kernel_reset","time":270.6836060000005},{"functionName":"private_kernel_tail","time":106.0256389999995}],"unaccounted":755.5356440000032}} 16:19:27 [16:19:27.679] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:27 [16:19:27.730] VERBOSE: p2p:tx_pool Adding tx 0x1e8bf134b8f29abac5068a9d36ce842fa7555a35e267f8030da5caa15abd1fc6 to pool {"eventName":"tx-added-to-pool","txHash":"0x1e8bf134b8f29abac5068a9d36ce842fa7555a35e267f8030da5caa15abd1fc6","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 16:19:27 [16:19:27.756] INFO: node Received tx 0x1e8bf134b8f29abac5068a9d36ce842fa7555a35e267f8030da5caa15abd1fc6 {"txHash":"0x1e8bf134b8f29abac5068a9d36ce842fa7555a35e267f8030da5caa15abd1fc6"} 16:19:27 [16:19:27.756] INFO: pxe:service Sent transaction 0x1e8bf134b8f29abac5068a9d36ce842fa7555a35e267f8030da5caa15abd1fc6 16:19:27 [16:19:27.887] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:27 [16:19:27.970] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 16:19:27 [16:19:27.983] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-16T16:23:43.000Z","now":"2025-06-16T16:23:25.975Z"} 16:19:28 [16:19:28.168] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:28 [16:19:28.235] VERBOSE: simulator:public-processor Processed tx 0x1e8bf134b8f29abac5068a9d36ce842fa7555a35e267f8030da5caa15abd1fc6 with no public calls in 177.1987309999986ms {"txHash":"0x1e8bf134b8f29abac5068a9d36ce842fa7555a35e267f8030da5caa15abd1fc6","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":177.1987309999986} 16:19:28 [16:19:28.238] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.25392279100000087s {"duration":0.25392279100000087,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 16:19:28 [16:19:28.384] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:28 [16:19:28.398] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x218ec2bfdbd82bdba168172d3e3c24d22e8acb94c5f412a4b32423bdc9117201","globalVariables":{"chainId":31337,"version":594854967,"blockNumber":1,"slotNumber":2,"timestamp":1750091023,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1e8bf134b8f29abac5068a9d36ce842fa7555a35e267f8030da5caa15abd1fc6"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":428.8688970000003,"publicProcessDuration":255.0177050000002,"rollupCircuitsDuration":416.62638100000004,"txCount":1,"blockNumber":1,"blockTimestamp":1750091023,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 16:19:28 [16:19:28.401] VERBOSE: sequencer Attesting committee is empty 16:19:28 [16:19:28.591] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:28 [16:19:28.763] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x1ca4a02a8b16484daa6234d884e98998e294e6a932076fdb9a7552f1829a5c8f 16:19:28 [16:19:28.763] VERBOSE: sequencer-client Sent L1 transaction 0x1ca4a02a8b16484daa6234d884e98998e294e6a932076fdb9a7552f1829a5c8f {"gasLimit":514585,"maxFeePerGas":"1.574385791","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 16:19:28 [16:19:28.779] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 16:19:28 [16:19:28.780] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x1ca4a02a8b16484daa6234d884e98998e294e6a932076fdb9a7552f1829a5c8f","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":1,"blockTimestamp":1750091023,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 16:19:28 [16:19:28.781] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 16:19:28 [16:19:28.805] INFO: blob-sink:server Received blob sidecar for block 0x1bf7cde91daf47734a2d678e8fab557a1439b85b583854990b5d5f45f8c7f3b7 16:19:28 [16:19:28.808] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:28 [16:19:28.821] INFO: blob-sink:server Blob sidecar stored successfully for block 0x1bf7cde91daf47734a2d678e8fab557a1439b85b583854990b5d5f45f8c7f3b7 16:19:28 [16:19:28.828] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 16:19:28 [16:19:28.831] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 16:19:28 [16:19:28.833] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 16:19:28 [16:19:28.963] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 16:19:28 [16:19:28.969] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x218ec2bfdbd82bdba168172d3e3c24d22e8acb94c5f412a4b32423bdc9117201","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":594854967,"blockNumber":1,"slotNumber":2,"timestamp":1750091023,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x195f902a399d2ec6f4ec13f2a4d1844aa19ee4b81d5c4d1b9c3b25b183fc598e","archiveNextLeafIndex":2} 16:19:28 [16:19:28.974] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 16:19:28 [16:19:28.984] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750091059] on http://127.0.0.1:8545 16:19:28 [16:19:28.985] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 16:19:28 [16:19:28.987] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750091059 16:19:28 [16:19:28.987] WARN: foundation:test-date-provider Time set to 2025-06-16T16:24:19.000Z {"offset":290013,"timeMs":1750091059000} 16:19:28 [16:19:28.987] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 16:19:29 [16:19:29.015] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:19:29 [16:19:29.074] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":16.18072200000097,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750091023,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 16:19:29 [16:19:29.106] VERBOSE: p2p Synched to latest block 1 16:19:29 [16:19:29.118] INFO: node Stopping 16:19:29 [16:19:29.120] INFO: slasher Slasher client stopped. 16:19:29 [16:19:29.120] INFO: sequencer Stopped sequencer 16:19:29 [16:19:29.121] INFO: p2p P2P client stopped. 16:19:29 [16:19:29.124] INFO: world_state Stopped world state synchronizer 16:19:29 [16:19:29.125] INFO: archiver Stopped. 16:19:29 [16:19:29.125] INFO: node Stopped 16:19:29 [16:19:29.125] VERBOSE: e2e:Circuit Cleaning up ACVM state 16:19:29 [16:19:29.125] INFO: e2e:Circuit Cleaning up ACVM temp directory /tmp/21a1f3b1 16:19:29 [16:19:29.126] VERBOSE: e2e:Circuit Cleaning up BB state 16:19:29 [16:19:29.136] INFO: blob-sink:server Stopping blob sink 16:19:29 [16:19:29.137] INFO: blob-sink:server Blob sink stopped 16:19:29 [16:19:29.137] VERBOSE: e2e:Circuit Cleaning up data directory at /tmp/5ce1dbba301b99eb 16:19:29 PASS src/e2e_circuit_recorder.test.ts 16:19:29 Circuit Recorder 16:19:29 records circuit execution (9731 ms) 16:19:29 16:19:29 Test Suites: 1 passed, 1 total 16:19:29 Tests: 1 passed, 1 total 16:19:29 Snapshots: 0 total 16:19:29 Time: 16.508 s 16:19:29 Ran all test suites matching /src\/e2e_circuit_recorder.test.ts/i. 16:19:29 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?