Parent Log: http://ci.aztec-labs.com/c642570c7f61346a Command: 16ba859834c0b700:ISOLATE=1:NAME=e2e_multi_validator_node yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_multi_validator_node.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/510b2bbfaf2f57a6a5b5c960ed10726a6d44c9d9 Env: REF_NAME=gh-readonly-queue/next/pr-14787-72adea8c86d5156a24785e2813df662bf9157942 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Wed Jun 4 20:36:01 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14787_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/0d13f39a62f4c76d 20:36:01 +++ id -u 20:36:01 +++ id -g 20:36:01 ++ docker run -d --name e2e_multi_validator_node --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_multi_validator_node.test.ts'\''' 20:36:01 + cid=472e7fabf775ade91955b69987bdf13ff110f5c7896f9d273f21a94a355e83af 20:36:01 + set +x 20:36:02 {"level":25,"time":1749069362733,"pid":12,"hostname":"472e7fabf775","module":"logger","msg":"Logger initialized with level verbose"} 20:36:09 [20:36:09.313] INFO: e2e:e2e_multi_validator_node Running test: e2e_multi_validator_node should build blocks & attest with multiple validator keys 20:36:09 [20:36:09.807] INFO: e2e:e2e_multi_validator_node Logging metrics to log/e2e_multi_validator_node.jsonl 20:36:10 [20:36:10.501] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-SarHN1/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 20:36:10 [20:36:10.547] INFO: e2e:e2e_multi_validator_node Calling anvil_setBlockTimestampInterval with params: [12] 20:36:10 [20:36:10.575] WARN: e2e:e2e_multi_validator_node Set block interval to 12 20:36:10 [20:36:10.576] VERBOSE: e2e:e2e_multi_validator_node Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 20:36:10 [20:36:10.624] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 20:36:10 [20:36:10.633] VERBOSE: e2e:e2e_multi_validator_node Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 20:36:10 [20:36:10.653] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 20:36:10 [20:36:10.659] VERBOSE: e2e:e2e_multi_validator_node Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 20:36:10 [20:36:10.685] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x15a910452a602ec46131d746d66411db81d7fc72b90362a00ee4d18cc6abe51b {"gasLimit":3064716,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 20:36:10 [20:36:10.698] VERBOSE: e2e:e2e_multi_validator_node Deployed GSE at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 20:36:10 [20:36:10.723] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x263f559e4ac0e4916749f1cfef6c86ab177d4ed50b4e9dd5b714b2907c3ea485 {"gasLimit":1014492,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 20:36:10 [20:36:10.730] VERBOSE: e2e:e2e_multi_validator_node Deployed Registry at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 20:36:10 [20:36:10.756] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x20a9b0b462f62bc3be8ca34c505c34cf2a4c1358e5584da2d561d72abc96aed1 {"gasLimit":1945066,"maxFeePerGas":"3.602461376","maxPriorityFeePerGas":"2.48832"} 20:36:10 [20:36:10.763] VERBOSE: e2e:e2e_multi_validator_node Deployed GovernanceProposer at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 20:36:10 [20:36:10.806] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x28a12a8c995db07c5f5cafec9b82f038e63dc5e97dcd98daa9cfc9f4a5ab8de7 {"gasLimit":3043143,"maxFeePerGas":"3.968706922","maxPriorityFeePerGas":"2.985984"} 20:36:10 [20:36:10.814] VERBOSE: e2e:e2e_multi_validator_node Deployed Governance at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 20:36:10 [20:36:10.838] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f {"gasLimit":55548,"maxFeePerGas":"4.456337397","maxPriorityFeePerGas":"3.5831808"} 20:36:10 [20:36:10.839] VERBOSE: e2e:e2e_multi_validator_node Set governance on GSE in 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f 20:36:10 [20:36:10.856] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x5d35a155eec0ed0c7deff752275abe3c6d5f448014ad234660feb820ef8620df {"gasLimit":391705,"maxFeePerGas":"5.082281352","maxPriorityFeePerGas":"4.29981696"} 20:36:10 [20:36:10.862] VERBOSE: e2e:e2e_multi_validator_node Deployed CoinIssuer at 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 20:36:10 [20:36:10.863] VERBOSE: e2e:e2e_multi_validator_node Waiting for deployments to complete 20:36:10 [20:36:10.864] INFO: e2e:e2e_multi_validator_node Waiting for 7 transactions to be mined... 20:36:10 [20:36:10.904] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:10 [20:36:10.922] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed {"gasLimit":56804,"maxFeePerGas":"5.84473853","maxPriorityFeePerGas":"5.159780352"} 20:36:10 [20:36:10.924] VERBOSE: e2e:e2e_multi_validator_node Added coin issuer 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 as minter on fee asset in 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed 20:36:10 [20:36:10.943] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x8e92b552cc623d62987e85e1df7c096f9eae4836710a4f62deed3668e8d02fa6 {"gasLimit":56661,"maxFeePerGas":"6.792938035","maxPriorityFeePerGas":"6.191736422"} 20:36:10 [20:36:10.962] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2c50b6224c5aaa3d953e1f1029dcd0ea319c195a3237972ff224e8c29a1434cd {"gasLimit":339691,"maxFeePerGas":"7.956372276","maxPriorityFeePerGas":"7.430083706"} 20:36:10 [20:36:10.970] VERBOSE: e2e:e2e_multi_validator_node Deployed FeeAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 20:36:10 [20:36:10.995] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c {"gasLimit":56804,"maxFeePerGas":"9.376810034","maxPriorityFeePerGas":"8.916100447"} 20:36:10 [20:36:10.995] VERBOSE: e2e:e2e_multi_validator_node Added fee asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on fee asset in 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c 20:36:11 [20:36:11.017] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x3bf548a99420047e1164bf2dfa64c5fc4ed32845f7019722b8db00da065120d2 {"gasLimit":1089256,"maxFeePerGas":"11.103528222","maxPriorityFeePerGas":"10.699320536"} 20:36:11 [20:36:11.023] VERBOSE: e2e:e2e_multi_validator_node Deployed StakingAssetHandler at 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 20:36:11 [20:36:11.048] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 {"gasLimit":56804,"maxFeePerGas":"13.193025819","maxPriorityFeePerGas":"12.839184643"} 20:36:11 [20:36:11.048] VERBOSE: e2e:e2e_multi_validator_node Added staking asset handler 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 as minter on staking asset in 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 20:36:11 [20:36:11.049] VERBOSE: e2e:e2e_multi_validator_node Waiting for deployments to complete 20:36:11 [20:36:11.049] INFO: e2e:e2e_multi_validator_node Waiting for 9 transactions to be mined... 20:36:11 [20:36:11.093] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:11 [20:36:11.114] VERBOSE: e2e:e2e_multi_validator_node Deployed shared contracts 20:36:11 [20:36:11.144] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 {"gasLimit":84837,"maxFeePerGas":"15.719309155","maxPriorityFeePerGas":"15.407021571"} 20:36:11 [20:36:11.144] VERBOSE: e2e:e2e_multi_validator_node Funded reward distributor with 10000000000000000000000000 fee asset in 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 20:36:11 [20:36:11.150] VERBOSE: e2e:e2e_multi_validator_node Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 20:36:11 [20:36:11.174] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x4b00bf010016af1a686fa82a49ffcd696fe6567f2fafe88a5077a3196ed3622b {"gasLimit":86397,"maxFeePerGas":"18.761800709","maxPriorityFeePerGas":"18.488425885"} 20:36:11 [20:36:11.207] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x5ab770e71a294d59a299893ceef963b3326d216a75236785d0f3ad5811b5880e {"gasLimit":6285180,"maxFeePerGas":"22.425475094","maxPriorityFeePerGas":"22.186111062"} 20:36:11 [20:36:11.217] VERBOSE: e2e:e2e_multi_validator_node Awaiting for linked libraries to be deployed 20:36:11 [20:36:11.274] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x034fc1169617d93998df7c6d2a8cfe2df8793a7cc6400c5ea9d5b61d2b2e2e9e {"gasLimit":10716781,"maxFeePerGas":"26.832920416","maxPriorityFeePerGas":"26.623333274"} 20:36:11 [20:36:11.284] VERBOSE: e2e:e2e_multi_validator_node Deployed Rollup at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 20:36:11 [20:36:11.285] INFO: e2e:e2e_multi_validator_node Waiting for 10 transactions to be mined... 20:36:11 [20:36:11.312] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:11 [20:36:11.312] VERBOSE: e2e:e2e_multi_validator_node All core contracts have been deployed 20:36:11 [20:36:11.328] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a {"gasLimit":64317,"maxFeePerGas":"32.140536546","maxPriorityFeePerGas":"31.947999928"} 20:36:11 [20:36:11.328] VERBOSE: e2e:e2e_multi_validator_node Funding fee juice portal with 10000000000000000000000 fee juice in 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a (accelerated test deployments) 20:36:11 [20:36:11.348] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xa8897c0adb59c20b46f23cd39fab84926417a78cc6c803792273c45350f8ad09 {"gasLimit":1040116,"maxFeePerGas":"38.520398432","maxPriorityFeePerGas":"38.337599913"} 20:36:11 [20:36:11.366] VERBOSE: e2e:e2e_multi_validator_node Deployed SlashFactory at 0x3aa5ebb10dc797cac828524e59a333d0a371443c 20:36:11 [20:36:11.406] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x7bc3134cb4f006cb0eba3309cde2b12b5fc725d64f94cb000683899c40dc2c49 {"gasLimit":116846,"maxFeePerGas":"46.165150248","maxPriorityFeePerGas":"46.005119895"} 20:36:11 [20:36:11.407] VERBOSE: e2e:e2e_multi_validator_node Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to registry 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 in tx 0x7bc3134cb4f006cb0eba3309cde2b12b5fc725d64f94cb000683899c40dc2c49 20:36:11 [20:36:11.422] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x388f02bfd5668c6cfde7e35cf05ecf7fe659d4f8c1be0f621681b4b668e000cf {"gasLimit":109478,"maxFeePerGas":"55.347326337","maxPriorityFeePerGas":"55.206143874"} 20:36:11 [20:36:11.422] VERBOSE: e2e:e2e_multi_validator_node Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to GSE 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x388f02bfd5668c6cfde7e35cf05ecf7fe659d4f8c1be0f621681b4b668e000cf 20:36:11 [20:36:11.432] INFO: e2e:e2e_multi_validator_node Waiting for 11 transactions to be mined... 20:36:11 [20:36:11.478] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:11 [20:36:11.494] VERBOSE: e2e:e2e_multi_validator_node Rollup deployed 20:36:11 [20:36:11.495] VERBOSE: e2e:e2e_multi_validator_node Waiting for rollup and slash factory to be deployed 20:36:11 [20:36:11.495] INFO: e2e:e2e_multi_validator_node Waiting for 11 transactions to be mined... 20:36:11 [20:36:11.549] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:11 [20:36:11.550] VERBOSE: e2e:e2e_multi_validator_node All transactions for L1 deployment have been mined 20:36:11 [20:36:11.587] INFO: e2e:e2e_multi_validator_node Aztec L1 contracts initialized {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","inboxAddress":"0xd2e813c297f3cf9985baa5331cec80778755bf2a","outboxAddress":"0x9cba1bd8bfe8f5376d45f0ba25287c16fdbe0268","feeJuicePortalAddress":"0x6034162d05782a3e836948e76bb048e3bbda726c","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 20:36:11 [20:36:11.589] INFO: e2e:e2e_multi_validator_node Handing over to governance 20:36:11 [20:36:11.601] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 20:36:11 [20:36:11.605] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:11 [20:36:11.607] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x9A9f2CCfdE556A7E9Ff0848998Aa4a0CFD8863AE 20:36:11 [20:36:11.609] INFO: telemetry:client Using NoopTelemetryClient 20:36:11 [20:36:11.621] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:11 [20:36:11.623] WARN: foundation:test-date-provider Time set to 2025-06-04T20:40:33.000Z {"offset":261378,"timeMs":1749069633000} 20:36:11 [20:36:11.624] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/cc803fb2b82dc2b6/blob-sink with map size 134217728 KB (LMDB v2) 20:36:11 [20:36:11.635] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 20:36:11 [20:36:11.655] INFO: blob-sink:server Server is running on http://localhost:33427 20:36:11 [20:36:11.656] VERBOSE: e2e:e2e_multi_validator_node Creating and synching an aztec node... 20:36:11 [20:36:11.658] VERBOSE: e2e:e2e_multi_validator_node Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/c8e322b8/acvm 20:36:11 [20:36:11.660] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:33427"} 20:36:11 [20:36:11.708] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 20:36:11 [20:36:11.708] INFO: archiver:lmdb Creating archiver data store at directory /tmp/cc803fb2b82dc2b6/archiver with map size 134217728 KB (LMDB v2) 20:36:11 [20:36:11.710] INFO: archiver:lmdb Starting data store with maxReaders 16 20:36:11 [20:36:11.850] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:12 [20:36:12.060] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:12 [20:36:12.125] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:33427"} 20:36:12 [20:36:12.154] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:33427"} 20:36:12 [20:36:12.154] WARN: node:blob-sink:client No L1 consensus host urls configured 20:36:12 [20:36:12.154] WARN: node:blob-sink:client No archive client configured 20:36:12 [20:36:12.158] INFO: archiver Starting archiver sync to rollup contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae from L1 block 18 to current L1 block 22 with hash 0x8ca1d330a617349a4c26406323371249146123877096b85c3a111095f7a94ee8 {"blocksSynchedTo":18,"messagesSynchedTo":{"l1BlockHash":"0x3d4c8c78913aa1b6931bc4af70601b8b950736bedc1de1eb57fa352a6d8b62f1","l1BlockNumber":18}} 20:36:12 [20:36:12.190] INFO: archiver Initial archiver sync to L1 block 22 complete. {"l1BlockNumber":22,"syncPoint":{"blocksSynchedTo":22,"messagesSynchedTo":{"l1BlockHash":"0x8ca1d330a617349a4c26406323371249146123877096b85c3a111095f7a94ee8","l1BlockNumber":22}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 20:36:12 [20:36:12.200] INFO: world-state:database Creating world state data store at directory /tmp/cc803fb2b82dc2b6/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 20:36:12 [20:36:12.242] INFO: world_state Created world state synchroniser with block history of 64 20:36:12 [20:36:12.243] WARN: node Aztec node is accepting fake proofs 20:36:12 [20:36:12.257] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/cc803fb2b82dc2b6/p2p with map size 134217728 KB (LMDB v2) 20:36:12 [20:36:12.264] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 20:36:12 [20:36:12.274] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/cc803fb2b82dc2b6/p2p-archive with map size 134217728 KB (LMDB v2) 20:36:12 [20:36:12.291] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 20:36:12 [20:36:12.293] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/cc803fb2b82dc2b6/p2p-peers with map size 134217728 KB (LMDB v2) 20:36:12 [20:36:12.300] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 20:36:12 [20:36:12.302] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 20:36:12 [20:36:12.303] VERBOSE: p2p P2P is disabled. Using dummy P2P service 20:36:12 [20:36:12.306] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":200} 20:36:12 [20:36:12.307] INFO: world_state Started world state synchronizer from block 1 20:36:12 [20:36:12.310] VERBOSE: world-state:block_stream Starting sync from block number 0 20:36:12 [20:36:12.314] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:12 [20:36:12.324] INFO: p2p Starting p2p client from block 0 with empty mempools 20:36:12 [20:36:12.324] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":200,"startingBlock":0} 20:36:12 [20:36:12.327] INFO: epoch-prune-watcher EpochPruneWatcher initialized 20:36:12 [20:36:12.333] INFO: slasher Starting Slasher client... 20:36:12 [20:36:12.431] VERBOSE: validator Initialized validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 20:36:12 [20:36:12.432] VERBOSE: node All Aztec Node subsystems synced 20:36:12 [20:36:12.443] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 20:36:12 [20:36:12.453] INFO: sequencer-client Deploying forwarder contract 20:36:12 [20:36:12.494] VERBOSE: sequencer-client Sent L1 transaction 0xd55cdb6ebb71ba0fcaadbf6ded6a5b8e0f60991502922716080b2393915bf73f {"gasLimit":323704,"maxFeePerGas":"66.371021863","maxPriorityFeePerGas":"66.247372648"} 20:36:12 [20:36:12.494] VERBOSE: sequencer-client Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000042 to address 0x83ac846879C81f414bDf47961839F4E842ead017 in tx 0xd55cdb6ebb71ba0fcaadbf6ded6a5b8e0f60991502922716080b2393915bf73f. 20:36:12 [20:36:12.509] INFO: sequencer-client Forwarder contract deployed at 0x83ac846879c81f414bdf47961839f4e842ead017 20:36:12 [20:36:12.520] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:12 [20:36:12.523] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:12 [20:36:12.524] WARN: foundation:test-date-provider Time set to 2025-06-04T20:40:45.000Z {"offset":272476,"timeMs":1749069645000} 20:36:12 [20:36:12.528] INFO: validator Started validator with addresses: 20:36:12 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 20:36:12 [20:36:12.529] VERBOSE: validator Validators 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 are not on the validator committee for epoch 0 20:36:12 [20:36:12.530] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":200,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/c8e322b8/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 20:36:12 [20:36:12.872] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 20:36:12 [20:36:12.875] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 20:36:12 [20:36:12.876] INFO: node Aztec Node version: 0.87.6 20:36:12 [20:36:12.876] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","inboxAddress":"0xd2e813c297f3cf9985baa5331cec80778755bf2a","outboxAddress":"0x9cba1bd8bfe8f5376d45f0ba25287c16fdbe0268","feeJuicePortalAddress":"0x6034162d05782a3e836948e76bb048e3bbda726c","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x3aa5ebb10dc797cac828524e59a333d0a371443c","feeAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788","stakingAssetHandlerAddress":"0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0"} 20:36:12 [20:36:12.878] VERBOSE: e2e:e2e_multi_validator_node Creating and syncing a simulated prover node... 20:36:12 [20:36:12.923] INFO: blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:33427"} 20:36:12 [20:36:12.927] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:12 [20:36:12.947] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/cc803fb2b82dc2b6/ca17514ff06a0d7c/archiver with map size 134217728 KB (LMDB v2) 20:36:12 [20:36:12.959] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:12 [20:36:12.969] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 20:36:13 [20:36:13.272] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:13 [20:36:13.320] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:13 [20:36:13.448] INFO: blob-sink:client:prover-node Testing configured blob sources {"blobSinkUrl":"http://localhost:33427"} 20:36:13 [20:36:13.452] INFO: blob-sink:client:prover-node Blob sink is reachable {"blobSinkUrl":"http://localhost:33427"} 20:36:13 [20:36:13.453] WARN: blob-sink:client:prover-node No L1 consensus host urls configured 20:36:13 [20:36:13.453] WARN: blob-sink:client:prover-node No archive client configured 20:36:13 [20:36:13.457] INFO: archiver:prover-node Starting archiver sync to rollup contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae from L1 block 18 to current L1 block 23 with hash 0x95678a5f92ec0bf6af411d6487d83df2823a7fbefd13c381fb6802e2d99bbb1e {"blocksSynchedTo":18,"messagesSynchedTo":{"l1BlockHash":"0x3d4c8c78913aa1b6931bc4af70601b8b950736bedc1de1eb57fa352a6d8b62f1","l1BlockNumber":18}} 20:36:13 [20:36:13.491] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:13 [20:36:13.504] INFO: archiver:prover-node Initial archiver sync to L1 block 23 complete. {"l1BlockNumber":23,"syncPoint":{"blocksSynchedTo":23,"messagesSynchedTo":{"l1BlockHash":"0x95678a5f92ec0bf6af411d6487d83df2823a7fbefd13c381fb6802e2d99bbb1e","l1BlockNumber":23}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 20:36:13 [20:36:13.508] INFO: prover-node:blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:33427"} 20:36:13 [20:36:13.509] VERBOSE: prover-node:prover-node Snapshot sync is disabled. No snapshots URL provided. 20:36:13 [20:36:13.511] VERBOSE: prover-node:prover-node Created archiver and synced to block 0 20:36:13 [20:36:13.520] INFO: world-state:database:prover-node Creating world state data store at directory /tmp/cc803fb2b82dc2b6/ca17514ff06a0d7c/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 20:36:13 [20:36:13.552] INFO: world_state:prover-node Created world state synchroniser with block history of 64 20:36:13 [20:36:13.556] VERBOSE: world-state:block_stream:prover-node Starting L2 block stream {"proven":false,"pollIntervalMS":200} 20:36:13 [20:36:13.556] INFO: world_state:prover-node Started world state synchronizer from block 1 20:36:13 [20:36:13.560] VERBOSE: world-state:block_stream:prover-node Starting sync from block number 0 20:36:13 [20:36:13.560] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/cc803fb2b82dc2b6/ca17514ff06a0d7c/archiver when loading epoch databases, ignoring 20:36:13 [20:36:13.560] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/cc803fb2b82dc2b6/ca17514ff06a0d7c/world_state when loading epoch databases, ignoring 20:36:13 [20:36:13.562] INFO: prover-client:proving-broker:prover-node Proving Broker started 20:36:13 [20:36:13.571] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:13 [20:36:13.577] INFO: prover-node:prover-coordination:prover-node Using prover coordination via aztec node 20:36:13 [20:36:13.579] INFO: e2e:e2e_multi_validator_node:prover-node Created and synced prover node {"publisherAddress":"0x3C44CdDdB6a900fa2b585dd299e03d12FA4293BC"} 20:36:13 [20:36:13.580] INFO: prover-node:epoch-monitor:prover-node Started EpochMonitor {"pollingIntervalMs":200} 20:36:13 [20:36:13.583] INFO: prover-node:prover-node Started Prover Node with prover id 0x0000000000000000000000003c44cdddb6a900fa2b585dd299e03d12fa4293bc {"proverNodePollingIntervalMs":200,"proverNodeMaxPendingJobs":10,"proverNodeMaxParallelBlocksPerEpoch":32,"txGatheringIntervalMs":1000,"txGatheringBatchSize":10,"txGatheringMaxParallelRequestsPerNode":100,"dataDirectory":"/tmp/cc803fb2b82dc2b6/ca17514ff06a0d7c","l1ChainId":31337,"rollupVersion":1730810666} 20:36:13 [20:36:13.584] VERBOSE: e2e:e2e_multi_validator_node Creating a pxe... 20:36:13 [20:36:13.588] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/f0e6096b719c6a47/pxe_data with map size 134217728 KB (LMDB v2) 20:36:13 [20:36:13.591] INFO: pxe:data:lmdb Starting data store with maxReaders 16 20:36:13 [20:36:13.704] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:13 [20:36:13.921] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:13 [20:36:13.964] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 20:36:13 [20:36:13.965] INFO: pxe:service Started PXE connected to chain 31337 version 1730810666 20:36:14 [20:36:14.025] INFO: pxe:service Registered account 0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a 20:36:14 [20:36:14.100] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:14 [20:36:14.107] WARN: pxe:service No artifact found for contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a when looking for its metadata 20:36:14 [20:36:14.113] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a as part of deployment for 0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a 20:36:14 [20:36:14.196] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:14 [20:36:14.325] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:14 [20:36:14.331] INFO: pxe:service Added contract SchnorrAccount at 0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a with class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 20:36:14 [20:36:14.395] VERBOSE: pxe:block_stream Starting sync from block number 0 20:36:14 [20:36:14.412] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:14 [20:36:14.465] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 20:36:14 [20:36:14.529] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:14 [20:36:14.599] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 20:36:15 [20:36:15.463] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a,0x2f6652d5f6110255f6132bc8be0dd74c664523f8808ebbc3eb467252a0b5e74a,0x1d9edc9e571a9e7ab4d2aa454dd024ee819579e3e22ae0fadf28089b29f3f0bc,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 20:36:15 [20:36:15.618] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 20:36:15 [20:36:15.708] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:15 [20:36:15.742] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:15 [20:36:15.869] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a"} 20:36:15 [20:36:15.927] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:15 [20:36:15.929] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a"} 20:36:15 [20:36:15.933] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:15 [20:36:15.991] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:16 [20:36:16.016] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a"} 20:36:16 [20:36:16.020] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:16 [20:36:16.177] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:16 [20:36:16.284] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a"} 20:36:16 [20:36:16.346] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:16 [20:36:16.346] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a"} 20:36:16 [20:36:16.356] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:16 [20:36:16.407] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a"} 20:36:16 [20:36:16.411] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:16 [20:36:16.431] VERBOSE: simulator:client_execution_context:debug_log Setting 0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a as fee payer 20:36:16 [20:36:16.651] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:16 [20:36:16.771] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:16 [20:36:16.922] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:17 [20:36:17.030] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:17 [20:36:17.190] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:17 [20:36:17.321] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:17 [20:36:17.822] INFO: pxe:service Proving completed in 3446.118973999999ms {"timings":{"total":3446.118973999999,"sync":19.934991000000082,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":249.45418299999983,"oracles":{"callPrivateFunction":{"times":[1172.843825,426.0320389999997,330.5605839999989]}}},{"functionName":"private_kernel_init","time":114.67821000000004},{"functionName":"ContractClassRegisterer:register","time":1143.5173759999998,"oracles":{"loadCapsule":{"times":[34.12853499999983]},"notifyCreatedNullifier":{"times":[18.412625000000844]},"debugLog":{"times":[1.1103870000006282]},"notifyCreatedContractClassLog":{"times":[12.710857000000033]}}},{"functionName":"private_kernel_inner","time":139.85271699999976},{"functionName":"SchnorrAccount:constructor","time":388.29676799999834,"oracles":{"getContractInstance":{"times":[1.7313819999999396]},"debugLog":{"times":[1.0098439999983384,0.4516619999994873]},"fetchTaggedLogs":{"times":[89.91370199999983]},"loadCapsule":{"times":[0.42948099999921396,0.23312599999917438,0.23232499999903666]},"validateEnqueuedNotes":{"times":[1.4962159999995492]},"notifyCreatedNullifier":{"times":[0.22911600000043109,0.29384700000082375]},"notifyCreatedNote":{"times":[0.39189900000019406]},"getRandomField":{"times":[0.3524280000001454,0.3447280000000319,0.13167299999986426,0.10657299999911629,0.06177100000058999,0.06519099999968603,0.08526200000051176,0.052131000000372296,0.08333200000015495,0.14082299999972747,0.05975199999920733]},"getIndexedTaggingSecretAsSender":{"times":[40.64146099999925]},"incrementAppTaggingSecretIndexAsSender":{"times":[18.38071399999899]}}},{"functionName":"private_kernel_inner","time":155.28728000000046},{"functionName":"SchnorrAccount:entrypoint","time":265.0698620000021,"oracles":{"debugLog":{"times":[0.6979670000000624,0.46783200000027136,0.8161600000003091]},"fetchTaggedLogs":{"times":[63.32101900000089]},"loadCapsule":{"times":[0.3110770000002958,0.25935600000048,0.20996500000001106]},"validateEnqueuedNotes":{"times":[0.8945110000004206]},"getNotes":{"times":[2.361977000000479]},"getAuthWitness":{"times":[0.49006200000076205]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.9396670000005543]}}},{"functionName":"private_kernel_inner","time":184.6804200000006},{"functionName":"private_kernel_reset","time":230.95445800000016},{"functionName":"private_kernel_tail","time":70.96644399999968}],"unaccounted":483.42626499999824}} 20:36:17 [20:36:17.845] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:17 [20:36:17.934] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","blockNumber":1,"slot":2} 20:36:17 [20:36:17.940] VERBOSE: p2p:tx_pool Adding tx 0x0e2bfdc3b6cfc967ee760b4554a6af8f444949059d1ceeb06803f94e6dba0343 to pool {"eventName":"tx-added-to-pool","txHash":"0x0e2bfdc3b6cfc967ee760b4554a6af8f444949059d1ceeb06803f94e6dba0343","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 20:36:17 [20:36:17.959] INFO: node Received tx 0x0e2bfdc3b6cfc967ee760b4554a6af8f444949059d1ceeb06803f94e6dba0343 {"txHash":"0x0e2bfdc3b6cfc967ee760b4554a6af8f444949059d1ceeb06803f94e6dba0343"} 20:36:17 [20:36:17.960] INFO: pxe:service Sent transaction 0x0e2bfdc3b6cfc967ee760b4554a6af8f444949059d1ceeb06803f94e6dba0343 20:36:18 [20:36:18.060] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:18 [20:36:18.163] VERBOSE: sequencer:block-builder Building block 1 for slot 2 {"slot":2,"slotStart":"2025-06-04T20:40:57.000Z","now":"2025-06-04T20:40:50.639Z","blockNumber":1,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x14ec80ae247d6466b8a7781a48ca0907b90f46c4992d9724347ae8346a2fda71"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 20:36:18 [20:36:18.233] VERBOSE: simulator:public-processor Processed tx 0x0e2bfdc3b6cfc967ee760b4554a6af8f444949059d1ceeb06803f94e6dba0343 with no public calls in 34.60322500000075ms {"txHash":"0x0e2bfdc3b6cfc967ee760b4554a6af8f444949059d1ceeb06803f94e6dba0343","txFee":2101212240,"revertCode":0,"gasUsed":{"totalGas":{"daGas":16896,"l2Gas":6324},"billedGas":{"daGas":16896,"l2Gas":6324},"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":34.60322500000075} 20:36:18 [20:36:18.237] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.06993743800000084s {"duration":0.06993743800000084,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 20:36:18 [20:36:18.345] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:18 [20:36:18.363] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2a971252926d77f12bdeeaf900d604caa708f3d1d27dad882e3e1f67a5cdc89e","globalVariables":{"chainId":31337,"version":1730810666,"blockNumber":1,"slotNumber":2,"timestamp":1749069657,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x0e2bfdc3b6cfc967ee760b4554a6af8f444949059d1ceeb06803f94e6dba0343"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":210.24846699999944,"publicProcessDuration":71.26753099999951,"rollupCircuitsDuration":200.18003399999907,"txCount":1,"blockNumber":1,"blockTimestamp":1749069657,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:36:18 [20:36:18.366] VERBOSE: sequencer Attesting committee is empty 20:36:18 [20:36:18.553] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:18 [20:36:18.790] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:18 [20:36:18.798] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x323961afe7a00695e8293ae37f1581f9ac3e3ae14d0a5c3b68756c2bb1072b9c 20:36:18 [20:36:18.798] VERBOSE: node Sent L1 transaction 0x323961afe7a00695e8293ae37f1581f9ac3e3ae14d0a5c3b68756c2bb1072b9c {"gasLimit":555044,"maxFeePerGas":"79.605134246","maxPriorityFeePerGas":"79.496847177","maxFeePerBlobGas":"0.000000001"} 20:36:18 [20:36:18.805] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 20:36:18 [20:36:18.810] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 20:36:18 [20:36:18.813] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 20:36:18 [20:36:18.819] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:36:18 [20:36:18.820] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x323961afe7a00695e8293ae37f1581f9ac3e3ae14d0a5c3b68756c2bb1072b9c","calldataSize":1444,"calldataGas":12268,"txCount":1,"blockNumber":1,"blockTimestamp":1749069657,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:36:18 [20:36:18.820] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:36:18 [20:36:18.859] INFO: blob-sink:server Received blob sidecar for block 0xe8f68228f12cf9d9bbedfc6216f8bc9e3826727f76bfef0589c1d23238a9e6b2 20:36:18 [20:36:18.878] INFO: blob-sink:server Blob sidecar stored successfully for block 0xe8f68228f12cf9d9bbedfc6216f8bc9e3826727f76bfef0589c1d23238a9e6b2 20:36:19 [20:36:19.012] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:19 [20:36:19.029] VERBOSE: archiver:block-helper Store contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 20:36:19 [20:36:19.032] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:19 [20:36:19.033] WARN: foundation:test-date-provider Time set to 2025-06-04T20:40:57.000Z {"offset":277967,"timeMs":1749069657000} 20:36:19 [20:36:19.143] VERBOSE: archiver:block-helper:prover-node Store contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 20:36:19 [20:36:19.151] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x2a971252926d77f12bdeeaf900d604caa708f3d1d27dad882e3e1f67a5cdc89e","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":1730810666,"blockNumber":1,"slotNumber":2,"timestamp":1749069657,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","archiveNextLeafIndex":2} 20:36:19 [20:36:19.160] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749069693] on http://127.0.0.1:8545 20:36:19 [20:36:19.165] INFO: archiver:prover-node Downloaded L2 block 1 {"blockHash":"0x2a971252926d77f12bdeeaf900d604caa708f3d1d27dad882e3e1f67a5cdc89e","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":1730810666,"blockNumber":1,"slotNumber":2,"timestamp":1749069657,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","archiveNextLeafIndex":2} 20:36:19 [20:36:19.167] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:36:19 [20:36:19.173] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749069693 20:36:19 [20:36:19.173] WARN: foundation:test-date-provider Time set to 2025-06-04T20:41:33.000Z {"offset":313827,"timeMs":1749069693000} 20:36:19 [20:36:19.174] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 20:36:19 [20:36:19.174] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 20:36:19 [20:36:19.181] INFO: archiver:prover-node Updated proven chain to block 1 {"provenBlockNumber":1} 20:36:19 [20:36:19.187] VERBOSE: sequencer Unable to build/enqueue block Rollup__InvalidArchive(0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27, 0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3) 20:36:19 [20:36:19.234] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:19 [20:36:19.238] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":10.511714000000211,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749069657,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:36:19 [20:36:19.249] INFO: world_state:prover-node World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":18.2918920000011,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749069657,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:36:19 [20:36:19.255] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749071997] on http://127.0.0.1:8545 20:36:19 [20:36:19.256] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:36:19 [20:36:19.257] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749071997 20:36:19 [20:36:19.258] INFO: e2e:e2e_multi_validator_node Deploying contract from 0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a 20:36:19 [20:36:19.673] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:19 [20:36:19.680] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:19 [20:36:19.680] WARN: foundation:test-date-provider Time set to 2025-06-04T21:19:57.000Z {"offset":2617320,"timeMs":1749071997000} 20:36:19 [20:36:19.695] INFO: pxe:service Added contract StatefulTest at 0x117f9d048a43177ecf490849d582e3248ce1659baa03a806831ced14ef62e6d7 with class 0x0e75e50f67127bddbbf0c6e812b093c4e20dc26681235d9c77c3d57f93ab52a0 20:36:19 [20:36:19.777] VERBOSE: p2p Synched to latest block 1 20:36:19 [20:36:19.806] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","header":{"contentCommitment":{"blobsHash":"0x0017b3ed85c325ba2f02f14cf9597cb92751172cef248531919b7fe721c244ad","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749069657,"version":1730810666},"lastArchive":"0x1bf768550386f13aba8da7aeddc2b439fd3d02561c9a8c9433005593335191b3","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2b4554024d9788b26144d8451a15598b01668fb4889ff97789163f16462bc754","nullifierTree":"0x0ef8ae98d306326eb28d97750fc1d300bdfb7a60c60e7579e12cb9ddd30902e7","publicDataTree":"0x0c93e95396d30f82726468160426f1efb91e6bc2bdaf9455cfb1ded539b90cc5"},"totalFees":2101212240,"totalManaUsed":6324}} 20:36:19 [20:36:19.895] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:19 [20:36:19.928] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a"} 20:36:19 [20:36:19.985] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:19 [20:36:19.986] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a"} 20:36:19 [20:36:19.989] VERBOSE: validator Validators 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 are not on the validator committee for epoch 2 20:36:20 [20:36:20.013] VERBOSE: prover-node:prover-node Fetching 1 tx hashes for block number 1 from coordination 20:36:20 [20:36:20.014] INFO: prover-node:combined-prover-coordination:prover-node Check for 1 txs found 1 missing. Will gather from nodes and p2p 20:36:20 [20:36:20.033] VERBOSE: prover-node:combined-prover-coordination:prover-node Gathered 1 of 1 txs from a node 20:36:20 [20:36:20.034] INFO: prover-node:combined-prover-coordination:prover-node Found all 1 txs directly from nodes 20:36:20 [20:36:20.137] VERBOSE: sequencer Not enough txs to build block 2 at slot 67 (got 0 txs, need 1) {"chainTipArchive":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","blockNumber":2,"slot":67} 20:36:20 [20:36:20.189] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a"} 20:36:20 [20:36:20.192] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:20 [20:36:20.202] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0a557d69a48ef15f85cc496087dcc68b9995c697f26db794b507a0fcf10cdabf 20:36:20 [20:36:20.217] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:36:20 [20:36:20.218] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:36:20 [20:36:20.234] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:36:20 [20:36:20.235] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:36:20 [20:36:20.239] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:20 [20:36:20.246] VERBOSE: pxe:service Added note {"index":0,"contract":"0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x0788372b2ec417bb18f448625f02c2ff200052a47364ff9705465c96c79860ec","nullifier":"0x1bf9af7f61f5c8be674dc6d72e830d5861269504c4b5370ccdeff63e2ad538c4"} 20:36:20 [20:36:20.266] VERBOSE: simulator:client_execution_context:debug_log Setting 0x090c422f75636e1352651b859adefbc960697458be5a5cb3f060ab67ffbfe11a as fee payer 20:36:20 [20:36:20.449] VERBOSE: simulator:private_execution Executing private function StatefulTest:constructor {"contract":"0x117f9d048a43177ecf490849d582e3248ce1659baa03a806831ced14ef62e6d7"} 20:36:20 [20:36:20.476] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:20 [20:36:20.483] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:20 [20:36:20.484] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x117f9d048a43177ecf490849d582e3248ce1659baa03a806831ced14ef62e6d7"} 20:36:20 [20:36:20.598] VERBOSE: sequencer Not enough txs to build block 2 at slot 67 (got 0 txs, need 1) {"chainTipArchive":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","blockNumber":2,"slot":67} 20:36:20 [20:36:20.632] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x117f9d048a43177ecf490849d582e3248ce1659baa03a806831ced14ef62e6d7"} 20:36:20 [20:36:20.637] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:20 [20:36:20.726] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:20 [20:36:20.878] VERBOSE: simulator:private_execution Executing private function StatefulTest:create_note_no_init_check {"contract":"0x117f9d048a43177ecf490849d582e3248ce1659baa03a806831ced14ef62e6d7"} 20:36:20 [20:36:20.917] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:20 [20:36:20.918] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x117f9d048a43177ecf490849d582e3248ce1659baa03a806831ced14ef62e6d7"} 20:36:21 [20:36:21.031] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:21 [20:36:21.053] VERBOSE: sequencer Not enough txs to build block 2 at slot 67 (got 0 txs, need 1) {"chainTipArchive":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","blockNumber":2,"slot":67} 20:36:21 [20:36:21.096] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x117f9d048a43177ecf490849d582e3248ce1659baa03a806831ced14ef62e6d7"} 20:36:21 [20:36:21.101] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:21 [20:36:21.258] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:21 [20:36:21.429] VERBOSE: sequencer Not enough txs to build block 2 at slot 67 (got 0 txs, need 1) {"chainTipArchive":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","blockNumber":2,"slot":67} 20:36:21 [20:36:21.538] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:21 [20:36:21.905] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:22 [20:36:22.116] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:22 [20:36:22.119] VERBOSE: sequencer Not enough txs to build block 2 at slot 67 (got 0 txs, need 1) {"chainTipArchive":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","blockNumber":2,"slot":67} 20:36:22 [20:36:22.363] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:22 [20:36:22.557] VERBOSE: sequencer Not enough txs to build block 2 at slot 67 (got 0 txs, need 1) {"chainTipArchive":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","blockNumber":2,"slot":67} 20:36:23 [20:36:23.018] INFO: pxe:service Proving completed in 3244.3524620000026ms {"timings":{"total":3244.3524620000026,"sync":34.02795200000037,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":519.3950130000012,"oracles":{"debugLog":{"times":[0.875780999998824,0.6443060000019614,0.6168649999999616,0.7163280000022496,0.6449460000003455,0.47849099999803,0.44469000000026426,0.9982549999986077]},"fetchTaggedLogs":{"times":[212.5651739999994]},"loadCapsule":{"times":[0.7758090000024822,0.5780339999982971,0.6803270000018529,0.2970969999987574,0.24125500000081956,0.3382279999968887,0.27663699999902747]},"getSharedSecret":{"times":[5.2516969999996945]},"aes128Decrypt":{"times":[1.300641000001633,1.2290499999980966]},"getPublicKeysAndPartialAddress":{"times":[7.6690659999985655]},"getKeyValidationRequest":{"times":[3.158306999997876]},"storeCapsule":{"times":[0.6510759999982838,0.4626910000006319,0.35202900000149384]},"deleteCapsule":{"times":[0.6828070000010484]},"validateEnqueuedNotes":{"times":[7.990292999998928]},"getNotes":{"times":[2.4823800000012852]},"getAuthWitness":{"times":[0.3035180000006221]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.16726399999970454]},"callPrivateFunction":{"times":[1021.9788509999998]}}},{"functionName":"private_kernel_init","time":84.91819100000066},{"functionName":"StatefulTest:constructor","time":369.3408999999956,"oracles":{"getContractInstance":{"times":[9.924129999999423]},"debugLog":{"times":[0.6397460000007413,0.514433000000281]},"fetchTaggedLogs":{"times":[151.76283500000136]},"loadCapsule":{"times":[0.33649900000091293,0.2062949999999546,0.3116679999984626]},"validateEnqueuedNotes":{"times":[1.0157340000005206]},"storeInExecutionCache":{"times":[0.3209979999992356]},"callPrivateFunction":{"times":[642.8136840000006]},"notifyCreatedNullifier":{"times":[0.3110579999993206]}}},{"functionName":"private_kernel_inner","time":168.96018000000186},{"functionName":"StatefulTest:create_note_no_init_check","time":568.9933710000041,"oracles":{"debugLog":{"times":[0.7369679999974323,0.5501030000013998]},"fetchTaggedLogs":{"times":[181.65853699999934]},"loadCapsule":{"times":[0.2911470000035479,0.2924170000005688,0.315747000000556]},"validateEnqueuedNotes":{"times":[1.5047359999989567]},"getRandomField":{"times":[0.1776739999986603,0.1296939999992901,0.16211400000247522,0.08009200000014971,0.05795099999886588,0.06825100000060047,0.052060999998502666,0.057920999999623746,0.04544200000236742,0.036230999998224434,0.06697100000019418,0.03640099999756785]},"notifyCreatedNote":{"times":[0.14333299999998417]},"getIndexedTaggingSecretAsSender":{"times":[79.3125059999984]},"incrementAppTaggingSecretIndexAsSender":{"times":[68.3749510000016]}}},{"functionName":"private_kernel_inner","time":155.3505210000003},{"functionName":"private_kernel_reset","time":213.57999800000107},{"functionName":"private_kernel_tail","time":85.34425099999862}],"unaccounted":1044.4420849999988}} 20:36:23 [20:36:23.040] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:23 [20:36:23.122] VERBOSE: p2p:tx_pool Adding tx 0x087513ef4dca48554db57eea993c02d9ee411d63aff845a524f5eb33bf6fec4d to pool {"eventName":"tx-added-to-pool","txHash":"0x087513ef4dca48554db57eea993c02d9ee411d63aff845a524f5eb33bf6fec4d","noteHashCount":1,"nullifierCount":2,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 20:36:23 [20:36:23.139] VERBOSE: sequencer Not enough txs to build block 2 at slot 67 (got 0 txs, need 1) {"chainTipArchive":"0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27","blockNumber":2,"slot":67} 20:36:23 [20:36:23.147] INFO: node Received tx 0x087513ef4dca48554db57eea993c02d9ee411d63aff845a524f5eb33bf6fec4d {"txHash":"0x087513ef4dca48554db57eea993c02d9ee411d63aff845a524f5eb33bf6fec4d"} 20:36:23 [20:36:23.147] INFO: pxe:service Sent transaction 0x087513ef4dca48554db57eea993c02d9ee411d63aff845a524f5eb33bf6fec4d 20:36:23 [20:36:23.270] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:23 [20:36:23.382] VERBOSE: sequencer:block-builder Building block 2 for slot 67 {"slot":67,"slotStart":"2025-06-04T21:19:57.000Z","now":"2025-06-04T21:20:00.702Z","blockNumber":2,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x14ec80ae247d6466b8a7781a48ca0907b90f46c4992d9724347ae8346a2fda71"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 20:36:23 [20:36:23.419] VERBOSE: simulator:public-processor Processed tx 0x087513ef4dca48554db57eea993c02d9ee411d63aff845a524f5eb33bf6fec4d with no public calls in 21.30882500000007ms {"txHash":"0x087513ef4dca48554db57eea993c02d9ee411d63aff845a524f5eb33bf6fec4d","txFee":196507480,"revertCode":0,"gasUsed":{"totalGas":{"daGas":11776,"l2Gas":4814},"billedGas":{"daGas":11776,"l2Gas":4814},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":2,"noteHashCount":1,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":21.30882500000007} 20:36:23 [20:36:23.421] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03618714399999953s {"duration":0.03618714399999953,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 20:36:23 [20:36:23.562] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:23 [20:36:23.605] INFO: sequencer Built block 2 for slot 67 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x018ad9a10dc5c00b0b49324f67207beb1c3e2bc090f46fe2f8137995c8752789","globalVariables":{"chainId":31337,"version":1730810666,"blockNumber":2,"slotNumber":67,"timestamp":1749071997,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"txHashes":["0x087513ef4dca48554db57eea993c02d9ee411d63aff845a524f5eb33bf6fec4d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":230.61429999999746,"publicProcessDuration":36.73146700000143,"rollupCircuitsDuration":223.98266899999726,"txCount":1,"blockNumber":2,"blockTimestamp":1749071997,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:36:23 [20:36:23.613] VERBOSE: sequencer Attesting committee is empty 20:36:23 [20:36:23.778] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:23 [20:36:23.936] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xf0dd24d5c66fec83ef96d499c0905159748f34b36509bf8ed475437f0d42a068 20:36:23 [20:36:23.937] VERBOSE: node Sent L1 transaction 0xf0dd24d5c66fec83ef96d499c0905159748f34b36509bf8ed475437f0d42a068 {"gasLimit":565458,"maxFeePerGas":"1.273011811","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 20:36:23 [20:36:23.953] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:36:23 [20:36:23.953] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xf0dd24d5c66fec83ef96d499c0905159748f34b36509bf8ed475437f0d42a068","calldataSize":1444,"calldataGas":12268,"txCount":1,"blockNumber":2,"blockTimestamp":1749071997,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:36:23 [20:36:23.954] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:36:23 [20:36:23.962] INFO: blob-sink:server Received blob sidecar for block 0xf8530d86f4b9aad4d7b366c58577e7b20c668026fb1a9d726804012044c29307 20:36:23 [20:36:23.976] INFO: blob-sink:server Blob sidecar stored successfully for block 0xf8530d86f4b9aad4d7b366c58577e7b20c668026fb1a9d726804012044c29307 20:36:23 [20:36:23.982] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:23 [20:36:23.992] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:23 [20:36:23.993] WARN: foundation:test-date-provider Time set to 2025-06-04T21:20:09.000Z {"offset":2625008,"timeMs":1749072009000} 20:36:24 [20:36:24.005] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 20:36:24 [20:36:24.013] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 20:36:24 [20:36:24.022] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 20:36:24 [20:36:24.050] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749072033] on http://127.0.0.1:8545 20:36:24 [20:36:24.055] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:36:24 [20:36:24.058] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749072033 20:36:24 [20:36:24.059] WARN: foundation:test-date-provider Time set to 2025-06-04T21:20:33.000Z {"offset":2648941,"timeMs":1749072033000} 20:36:24 [20:36:24.059] INFO: aztecjs:utils:watcher Slot 67 was filled, jumped to next slot 20:36:24 [20:36:24.209] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:24 [20:36:24.420] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:24 [20:36:24.436] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x018ad9a10dc5c00b0b49324f67207beb1c3e2bc090f46fe2f8137995c8752789","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":1730810666,"blockNumber":2,"slotNumber":67,"timestamp":1749071997,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"archiveRoot":"0x206d096d6f3accbb99bbfbffb1824a6dfbad65e7c017edc7dd6845111badc7c4","archiveNextLeafIndex":3} 20:36:24 [20:36:24.452] INFO: archiver:prover-node Downloaded L2 block 2 {"blockHash":"0x018ad9a10dc5c00b0b49324f67207beb1c3e2bc090f46fe2f8137995c8752789","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":1730810666,"blockNumber":2,"slotNumber":67,"timestamp":1749071997,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"archiveRoot":"0x206d096d6f3accbb99bbfbffb1824a6dfbad65e7c017edc7dd6845111badc7c4","archiveNextLeafIndex":3} 20:36:24 [20:36:24.496] INFO: archiver:prover-node Updated proven chain to block 2 {"provenBlockNumber":2} 20:36:24 [20:36:24.518] VERBOSE: sequencer Unable to build/enqueue block Rollup__InvalidArchive(0x206d096d6f3accbb99bbfbffb1824a6dfbad65e7c017edc7dd6845111badc7c4, 0x29432f4511b822164bfabab9732982a49b04b01002743dde22e0775d03edde27) 20:36:24 [20:36:24.523] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":22.475242000000435,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749071997,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:36:24 [20:36:24.608] INFO: world_state:prover-node World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":10.631466000002547,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749071997,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:36:24 [20:36:24.647] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:24 [20:36:24.698] VERBOSE: p2p Synched to latest block 2 20:36:24 [20:36:24.713] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 20:36:24 [20:36:24.774] VERBOSE: sequencer Not enough txs to build block 3 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x206d096d6f3accbb99bbfbffb1824a6dfbad65e7c017edc7dd6845111badc7c4","blockNumber":3,"slot":68} 20:36:24 [20:36:24.850] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:25 [20:36:25.000] VERBOSE: sequencer Not enough txs to build block 3 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x206d096d6f3accbb99bbfbffb1824a6dfbad65e7c017edc7dd6845111badc7c4","blockNumber":3,"slot":68} 20:36:25 [20:36:25.056] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:25 [20:36:25.240] VERBOSE: prover-node:prover-node Fetching 1 tx hashes for block number 2 from coordination 20:36:25 [20:36:25.240] INFO: prover-node:combined-prover-coordination:prover-node Check for 1 txs found 1 missing. Will gather from nodes and p2p 20:36:25 [20:36:25.247] VERBOSE: prover-node:combined-prover-coordination:prover-node Gathered 1 of 1 txs from a node 20:36:25 [20:36:25.247] INFO: prover-node:combined-prover-coordination:prover-node Found all 1 txs directly from nodes 20:36:25 [20:36:25.257] VERBOSE: sequencer Not enough txs to build block 3 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x206d096d6f3accbb99bbfbffb1824a6dfbad65e7c017edc7dd6845111badc7c4","blockNumber":3,"slot":68} 20:36:25 [20:36:25.258] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:25 [20:36:25.470] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:25 [20:36:25.503] INFO: node Stopping 20:36:25 [20:36:25.506] VERBOSE: sequencer Not enough txs to build block 3 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x206d096d6f3accbb99bbfbffb1824a6dfbad65e7c017edc7dd6845111badc7c4","blockNumber":3,"slot":68} 20:36:25 [20:36:25.508] INFO: slasher Slasher client stopped. 20:36:25 [20:36:25.509] INFO: sequencer Stopped sequencer 20:36:25 [20:36:25.510] INFO: p2p P2P client stopped. 20:36:25 [20:36:25.513] INFO: world_state Stopped world state synchronizer 20:36:25 [20:36:25.514] INFO: archiver Stopped. 20:36:25 [20:36:25.514] INFO: node Stopped 20:36:25 [20:36:25.515] INFO: prover-node:prover-node Stopping ProverNode 20:36:25 [20:36:25.515] INFO: prover-node:epoch-monitor:prover-node Stopped EpochMonitor 20:36:25 [20:36:25.515] INFO: archiver:prover-node Stopped. 20:36:25 [20:36:25.517] INFO: world_state:prover-node Stopped world state synchronizer 20:36:25 [20:36:25.517] INFO: prover-node:prover-node Stopped ProverNode 20:36:25 [20:36:25.517] VERBOSE: e2e:e2e_multi_validator_node Cleaning up ACVM state 20:36:25 [20:36:25.517] INFO: e2e:e2e_multi_validator_node Cleaning up ACVM temp directory /tmp/c8e322b8 20:36:25 [20:36:25.519] VERBOSE: e2e:e2e_multi_validator_node Cleaning up BB state 20:36:25 [20:36:25.528] INFO: blob-sink:server Stopping blob sink 20:36:25 [20:36:25.528] INFO: blob-sink:server Blob sink stopped 20:36:25 [20:36:25.529] VERBOSE: e2e:e2e_multi_validator_node Cleaning up data directory at /tmp/cc803fb2b82dc2b6 20:36:25 [20:36:25.560] INFO: e2e:e2e_multi_validator_node Running test: e2e_multi_validator_node should attest ONLY with the correct validator keys 20:36:25 [20:36:25.870] INFO: e2e:e2e_multi_validator_node Logging metrics to log/e2e_multi_validator_node.jsonl 20:36:25 [20:36:25.928] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-ZILsIU/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 20:36:25 [20:36:25.998] INFO: e2e:e2e_multi_validator_node Calling anvil_setBlockTimestampInterval with params: [12] 20:36:26 [20:36:26.009] WARN: e2e:e2e_multi_validator_node Set block interval to 12 20:36:26 [20:36:26.010] VERBOSE: e2e:e2e_multi_validator_node Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 20:36:26 [20:36:26.054] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 20:36:26 [20:36:26.072] VERBOSE: e2e:e2e_multi_validator_node Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 20:36:26 [20:36:26.108] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 20:36:26 [20:36:26.117] VERBOSE: e2e:e2e_multi_validator_node Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 20:36:26 [20:36:26.148] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x15a910452a602ec46131d746d66411db81d7fc72b90362a00ee4d18cc6abe51b {"gasLimit":3064716,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 20:36:26 [20:36:26.158] VERBOSE: e2e:e2e_multi_validator_node Deployed GSE at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 20:36:26 [20:36:26.183] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x263f559e4ac0e4916749f1cfef6c86ab177d4ed50b4e9dd5b714b2907c3ea485 {"gasLimit":1014492,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 20:36:26 [20:36:26.189] VERBOSE: e2e:e2e_multi_validator_node Deployed Registry at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 20:36:26 [20:36:26.211] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x20a9b0b462f62bc3be8ca34c505c34cf2a4c1358e5584da2d561d72abc96aed1 {"gasLimit":1945066,"maxFeePerGas":"3.602461376","maxPriorityFeePerGas":"2.48832"} 20:36:26 [20:36:26.223] VERBOSE: e2e:e2e_multi_validator_node Deployed GovernanceProposer at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 20:36:26 [20:36:26.293] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x28a12a8c995db07c5f5cafec9b82f038e63dc5e97dcd98daa9cfc9f4a5ab8de7 {"gasLimit":3043143,"maxFeePerGas":"3.968706922","maxPriorityFeePerGas":"2.985984"} 20:36:26 [20:36:26.303] VERBOSE: e2e:e2e_multi_validator_node Deployed Governance at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 20:36:26 [20:36:26.324] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f {"gasLimit":55548,"maxFeePerGas":"4.456337397","maxPriorityFeePerGas":"3.5831808"} 20:36:26 [20:36:26.325] VERBOSE: e2e:e2e_multi_validator_node Set governance on GSE in 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f 20:36:26 [20:36:26.359] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x5d35a155eec0ed0c7deff752275abe3c6d5f448014ad234660feb820ef8620df {"gasLimit":391705,"maxFeePerGas":"5.082281352","maxPriorityFeePerGas":"4.29981696"} 20:36:26 [20:36:26.365] VERBOSE: e2e:e2e_multi_validator_node Deployed CoinIssuer at 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 20:36:26 [20:36:26.365] VERBOSE: e2e:e2e_multi_validator_node Waiting for deployments to complete 20:36:26 [20:36:26.366] INFO: e2e:e2e_multi_validator_node Waiting for 7 transactions to be mined... 20:36:26 [20:36:26.388] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:26 [20:36:26.417] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed {"gasLimit":56804,"maxFeePerGas":"5.84473853","maxPriorityFeePerGas":"5.159780352"} 20:36:26 [20:36:26.418] VERBOSE: e2e:e2e_multi_validator_node Added coin issuer 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 as minter on fee asset in 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed 20:36:26 [20:36:26.458] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x8e92b552cc623d62987e85e1df7c096f9eae4836710a4f62deed3668e8d02fa6 {"gasLimit":56661,"maxFeePerGas":"6.792938035","maxPriorityFeePerGas":"6.191736422"} 20:36:26 [20:36:26.486] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2c50b6224c5aaa3d953e1f1029dcd0ea319c195a3237972ff224e8c29a1434cd {"gasLimit":339691,"maxFeePerGas":"7.956372276","maxPriorityFeePerGas":"7.430083706"} 20:36:26 [20:36:26.495] VERBOSE: e2e:e2e_multi_validator_node Deployed FeeAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 20:36:26 [20:36:26.507] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c {"gasLimit":56804,"maxFeePerGas":"9.376810034","maxPriorityFeePerGas":"8.916100447"} 20:36:26 [20:36:26.508] VERBOSE: e2e:e2e_multi_validator_node Added fee asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on fee asset in 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c 20:36:26 [20:36:26.524] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x3bf548a99420047e1164bf2dfa64c5fc4ed32845f7019722b8db00da065120d2 {"gasLimit":1089256,"maxFeePerGas":"11.103528222","maxPriorityFeePerGas":"10.699320536"} 20:36:26 [20:36:26.531] VERBOSE: e2e:e2e_multi_validator_node Deployed StakingAssetHandler at 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 20:36:26 [20:36:26.546] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 {"gasLimit":56804,"maxFeePerGas":"13.193025819","maxPriorityFeePerGas":"12.839184643"} 20:36:26 [20:36:26.547] VERBOSE: e2e:e2e_multi_validator_node Added staking asset handler 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 as minter on staking asset in 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 20:36:26 [20:36:26.547] VERBOSE: e2e:e2e_multi_validator_node Waiting for deployments to complete 20:36:26 [20:36:26.547] INFO: e2e:e2e_multi_validator_node Waiting for 9 transactions to be mined... 20:36:26 [20:36:26.576] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:26 [20:36:26.602] VERBOSE: e2e:e2e_multi_validator_node Deployed shared contracts 20:36:26 [20:36:26.633] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 {"gasLimit":84837,"maxFeePerGas":"15.719309155","maxPriorityFeePerGas":"15.407021571"} 20:36:26 [20:36:26.633] VERBOSE: e2e:e2e_multi_validator_node Funded reward distributor with 10000000000000000000000000 fee asset in 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 20:36:26 [20:36:26.635] VERBOSE: e2e:e2e_multi_validator_node Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 20:36:26 [20:36:26.646] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x4b00bf010016af1a686fa82a49ffcd696fe6567f2fafe88a5077a3196ed3622b {"gasLimit":86397,"maxFeePerGas":"18.761800709","maxPriorityFeePerGas":"18.488425885"} 20:36:26 [20:36:26.672] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x5ab770e71a294d59a299893ceef963b3326d216a75236785d0f3ad5811b5880e {"gasLimit":6285180,"maxFeePerGas":"22.425475094","maxPriorityFeePerGas":"22.186111062"} 20:36:26 [20:36:26.678] VERBOSE: e2e:e2e_multi_validator_node Awaiting for linked libraries to be deployed 20:36:26 [20:36:26.735] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x63478852bddb049eb7fb196c255e84794126a3d7bb9a92e71c15eb08915e7206 {"gasLimit":10716795,"maxFeePerGas":"26.832920416","maxPriorityFeePerGas":"26.623333274"} 20:36:26 [20:36:26.744] VERBOSE: e2e:e2e_multi_validator_node Deployed Rollup at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 20:36:26 [20:36:26.745] INFO: e2e:e2e_multi_validator_node Waiting for 10 transactions to be mined... 20:36:26 [20:36:26.782] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:26 [20:36:26.783] VERBOSE: e2e:e2e_multi_validator_node All core contracts have been deployed 20:36:26 [20:36:26.808] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a {"gasLimit":64317,"maxFeePerGas":"32.140536546","maxPriorityFeePerGas":"31.947999928"} 20:36:26 [20:36:26.808] VERBOSE: e2e:e2e_multi_validator_node Funding fee juice portal with 10000000000000000000000 fee juice in 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a (accelerated test deployments) 20:36:26 [20:36:26.854] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xd413488be28de232ee67a932daca30e5e7bc4e1ad751dedc3910bbbe185aafe4 {"gasLimit":1040116,"maxFeePerGas":"38.520398451","maxPriorityFeePerGas":"38.337599913"} 20:36:26 [20:36:26.867] VERBOSE: e2e:e2e_multi_validator_node Deployed SlashFactory at 0x3aa5ebb10dc797cac828524e59a333d0a371443c 20:36:26 [20:36:26.917] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x9cf2e8cc19e5a14c92d6ffc7b824d5af7abcd5e99d83ce8f9d8f18174a0f48ec {"gasLimit":116846,"maxFeePerGas":"46.165150264","maxPriorityFeePerGas":"46.005119895"} 20:36:26 [20:36:26.917] VERBOSE: e2e:e2e_multi_validator_node Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to registry 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 in tx 0x9cf2e8cc19e5a14c92d6ffc7b824d5af7abcd5e99d83ce8f9d8f18174a0f48ec 20:36:26 [20:36:26.945] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2a5b9e16c5abd0a75879e886c16c1174a5bec7f4574004840187d6fdb1602285 {"gasLimit":109478,"maxFeePerGas":"55.34732635","maxPriorityFeePerGas":"55.206143874"} 20:36:26 [20:36:26.946] VERBOSE: e2e:e2e_multi_validator_node Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to GSE 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x2a5b9e16c5abd0a75879e886c16c1174a5bec7f4574004840187d6fdb1602285 20:36:26 [20:36:26.980] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2baf4763aa17682af85263faf72b939a3b0de80fabfa567cc2614de37cb716e2 {"gasLimit":362859,"maxFeePerGas":"66.371021876","maxPriorityFeePerGas":"66.247372648"} 20:36:26 [20:36:26.999] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xa623e13db3bc03c6828b186ab37039f59d8527e19526127bc6b78030c6e5fea9 {"gasLimit":84823,"maxFeePerGas":"79.605134257","maxPriorityFeePerGas":"79.496847177"} 20:36:27 [20:36:27.042] INFO: e2e:e2e_multi_validator_node Initialized validator set {"validators":[{"attester":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","withdrawer":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","privateKey":"0xac0974bec39a17e36ba4a6b4d238ff944bacb478cbed5efcae784d7bf4f2ff80"},{"attester":"0x70997970c51812dc3a010c7d01b50e0d17dc79c8","withdrawer":"0x70997970c51812dc3a010c7d01b50e0d17dc79c8","privateKey":"0x59c6995e998f97a5a0044966f0945389dc9e86dae88c7a8412f4603b6b78690d"},{"attester":"0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc","withdrawer":"0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc","privateKey":"0x5de4111afa1a4b94908f83103eb1f1706367c2e68ca870fc3fb9a804cdab365a"},{"attester":"0x90f79bf6eb2c4f870365e785982e1f101e93b906","withdrawer":"0x90f79bf6eb2c4f870365e785982e1f101e93b906","privateKey":"0x7c852118294e51e653712a81e05800f419141751be58f605c371e15141b007a6"},{"attester":"0x15d34aaf54267db7d7c367839aaf71a00a2c6a65","withdrawer":"0x15d34aaf54267db7d7c367839aaf71a00a2c6a65","privateKey":"0x47e179ec197488593b187f80a00eb0da91f1b9d0b13f8733639f19c30a34926a"}],"txHash":"0xc005a872652e5180c06511d7b2c65be32bd9db61f5c8a251e60d728456eb4cb3"} 20:36:27 [20:36:27.043] INFO: e2e:e2e_multi_validator_node Waiting for 12 transactions to be mined... 20:36:27 [20:36:27.080] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:27 [20:36:27.099] VERBOSE: e2e:e2e_multi_validator_node Rollup deployed 20:36:27 [20:36:27.099] VERBOSE: e2e:e2e_multi_validator_node Waiting for rollup and slash factory to be deployed 20:36:27 [20:36:27.099] INFO: e2e:e2e_multi_validator_node Waiting for 12 transactions to be mined... 20:36:27 [20:36:27.128] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 20:36:27 [20:36:27.128] VERBOSE: e2e:e2e_multi_validator_node All transactions for L1 deployment have been mined 20:36:27 [20:36:27.155] INFO: e2e:e2e_multi_validator_node Aztec L1 contracts initialized {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","inboxAddress":"0xd2e813c297f3cf9985baa5331cec80778755bf2a","outboxAddress":"0x9cba1bd8bfe8f5376d45f0ba25287c16fdbe0268","feeJuicePortalAddress":"0x6034162d05782a3e836948e76bb048e3bbda726c","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 20:36:27 [20:36:27.156] INFO: e2e:e2e_multi_validator_node Handing over to governance 20:36:27 [20:36:27.177] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 20:36:27 [20:36:27.180] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:27 [20:36:27.181] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x9A9f2CCfdE556A7E9Ff0848998Aa4a0CFD8863AE 20:36:27 [20:36:27.186] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:27 [20:36:27.186] WARN: foundation:test-date-provider Time set to 2025-06-04T20:41:25.000Z {"offset":297814,"timeMs":1749069685000} 20:36:27 [20:36:27.188] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/393607f63351eaa2/blob-sink with map size 134217728 KB (LMDB v2) 20:36:27 [20:36:27.200] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 20:36:27 [20:36:27.205] INFO: blob-sink:server Server is running on http://localhost:41365 20:36:27 [20:36:27.205] VERBOSE: e2e:e2e_multi_validator_node Creating and synching an aztec node... 20:36:27 [20:36:27.207] VERBOSE: e2e:e2e_multi_validator_node Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/e932d60d/acvm 20:36:27 [20:36:27.211] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:41365"} 20:36:27 [20:36:27.259] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 20:36:27 [20:36:27.261] INFO: archiver:lmdb Creating archiver data store at directory /tmp/393607f63351eaa2/archiver with map size 134217728 KB (LMDB v2) 20:36:27 [20:36:27.263] INFO: archiver:lmdb Starting data store with maxReaders 16 20:36:27 [20:36:27.388] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:27 [20:36:27.594] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:27 [20:36:27.597] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:41365"} 20:36:27 [20:36:27.602] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:41365"} 20:36:27 [20:36:27.602] WARN: node:blob-sink:client No L1 consensus host urls configured 20:36:27 [20:36:27.602] WARN: node:blob-sink:client No archive client configured 20:36:27 [20:36:27.605] INFO: archiver Starting archiver sync to rollup contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae from L1 block 18 to current L1 block 25 with hash 0x4ae71657663afb4c6420bd5ff972de427e46d138c32f118bef40c8dfdef29758 {"blocksSynchedTo":18,"messagesSynchedTo":{"l1BlockHash":"0x35a45a28e71efabfabe68bc65f262bef75fd8ef9a75332c707a57b16f23f97bb","l1BlockNumber":18}} 20:36:27 [20:36:27.622] INFO: archiver Initial archiver sync to L1 block 25 complete. {"l1BlockNumber":25,"syncPoint":{"blocksSynchedTo":25,"messagesSynchedTo":{"l1BlockHash":"0x4ae71657663afb4c6420bd5ff972de427e46d138c32f118bef40c8dfdef29758","l1BlockNumber":25}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 20:36:27 [20:36:27.638] INFO: world-state:database Creating world state data store at directory /tmp/393607f63351eaa2/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 20:36:27 [20:36:27.676] INFO: world_state Created world state synchroniser with block history of 64 20:36:27 [20:36:27.676] WARN: node Aztec node is accepting fake proofs 20:36:27 [20:36:27.688] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/393607f63351eaa2/p2p with map size 134217728 KB (LMDB v2) 20:36:27 [20:36:27.696] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 20:36:27 [20:36:27.705] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/393607f63351eaa2/p2p-archive with map size 134217728 KB (LMDB v2) 20:36:27 [20:36:27.710] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 20:36:27 [20:36:27.715] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/393607f63351eaa2/p2p-peers with map size 134217728 KB (LMDB v2) 20:36:27 [20:36:27.718] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 20:36:27 [20:36:27.722] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 20:36:27 [20:36:27.723] VERBOSE: p2p P2P is disabled. Using dummy P2P service 20:36:27 [20:36:27.729] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":200} 20:36:27 [20:36:27.732] INFO: world_state Started world state synchronizer from block 1 20:36:27 [20:36:27.741] VERBOSE: world-state:block_stream Starting sync from block number 0 20:36:27 [20:36:27.749] INFO: p2p Starting p2p client from block 0 with empty mempools 20:36:27 [20:36:27.749] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":200,"startingBlock":0} 20:36:27 [20:36:27.751] INFO: epoch-prune-watcher EpochPruneWatcher initialized 20:36:27 [20:36:27.756] INFO: slasher Starting Slasher client... 20:36:27 [20:36:27.762] VERBOSE: validator Initialized validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 20:36:27 [20:36:27.763] VERBOSE: node All Aztec Node subsystems synced 20:36:27 [20:36:27.768] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 20:36:27 [20:36:27.770] INFO: sequencer-client Deploying forwarder contract 20:36:27 [20:36:27.788] VERBOSE: sequencer-client Sent L1 transaction 0x5f35c2d0e4f65c467262e74571eec910db06feaae6991ac544d9a02dbb6f34dd {"gasLimit":323704,"maxFeePerGas":"95.479418643","maxPriorityFeePerGas":"95.396216612"} 20:36:27 [20:36:27.789] VERBOSE: sequencer-client Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000042 to address 0x83ac846879C81f414bDf47961839F4E842ead017 in tx 0x5f35c2d0e4f65c467262e74571eec910db06feaae6991ac544d9a02dbb6f34dd. 20:36:27 [20:36:27.795] INFO: sequencer-client Forwarder contract deployed at 0x83ac846879c81f414bdf47961839f4e842ead017 20:36:27 [20:36:27.799] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:27 [20:36:27.804] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:27 [20:36:27.805] WARN: foundation:test-date-provider Time set to 2025-06-04T20:41:37.000Z {"offset":309195,"timeMs":1749069697000} 20:36:27 [20:36:27.818] INFO: validator Started validator with addresses: 20:36:27 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 20:36:27 [20:36:27.819] VERBOSE: validator Validators 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 are not on the validator committee for epoch 0 20:36:27 [20:36:27.819] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":200,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/e932d60d/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 20:36:27 [20:36:27.820] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 20:36:27 [20:36:27.821] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 20:36:27 [20:36:27.822] INFO: node Aztec Node version: 0.87.6 20:36:27 [20:36:27.822] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","inboxAddress":"0xd2e813c297f3cf9985baa5331cec80778755bf2a","outboxAddress":"0x9cba1bd8bfe8f5376d45f0ba25287c16fdbe0268","feeJuicePortalAddress":"0x6034162d05782a3e836948e76bb048e3bbda726c","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x3aa5ebb10dc797cac828524e59a333d0a371443c","feeAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788","stakingAssetHandlerAddress":"0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0"} 20:36:27 [20:36:27.823] VERBOSE: e2e:e2e_multi_validator_node Creating and syncing a simulated prover node... 20:36:27 [20:36:27.851] INFO: blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:41365"} 20:36:27 [20:36:27.855] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/393607f63351eaa2/e251fedbf329cf35/archiver with map size 134217728 KB (LMDB v2) 20:36:27 [20:36:27.861] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:27 [20:36:27.863] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 20:36:28 [20:36:28.037] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:28 [20:36:28.147] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:28 [20:36:28.152] INFO: blob-sink:client:prover-node Testing configured blob sources {"blobSinkUrl":"http://localhost:41365"} 20:36:28 [20:36:28.156] INFO: blob-sink:client:prover-node Blob sink is reachable {"blobSinkUrl":"http://localhost:41365"} 20:36:28 [20:36:28.156] WARN: blob-sink:client:prover-node No L1 consensus host urls configured 20:36:28 [20:36:28.157] WARN: blob-sink:client:prover-node No archive client configured 20:36:28 [20:36:28.159] INFO: archiver:prover-node Starting archiver sync to rollup contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae from L1 block 18 to current L1 block 26 with hash 0x7f3154a102ec611e6d0df7e230f7f73b35ac6ccd45ed34b6828a525f0a7152bf {"blocksSynchedTo":18,"messagesSynchedTo":{"l1BlockHash":"0x35a45a28e71efabfabe68bc65f262bef75fd8ef9a75332c707a57b16f23f97bb","l1BlockNumber":18}} 20:36:28 [20:36:28.182] INFO: archiver:prover-node Initial archiver sync to L1 block 26 complete. {"l1BlockNumber":26,"syncPoint":{"blocksSynchedTo":26,"messagesSynchedTo":{"l1BlockHash":"0x7f3154a102ec611e6d0df7e230f7f73b35ac6ccd45ed34b6828a525f0a7152bf","l1BlockNumber":26}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 20:36:28 [20:36:28.185] INFO: prover-node:blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:41365"} 20:36:28 [20:36:28.185] VERBOSE: prover-node:prover-node Snapshot sync is disabled. No snapshots URL provided. 20:36:28 [20:36:28.187] VERBOSE: prover-node:prover-node Created archiver and synced to block 0 20:36:28 [20:36:28.193] INFO: world-state:database:prover-node Creating world state data store at directory /tmp/393607f63351eaa2/e251fedbf329cf35/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 20:36:28 [20:36:28.230] INFO: world_state:prover-node Created world state synchroniser with block history of 64 20:36:28 [20:36:28.234] VERBOSE: world-state:block_stream:prover-node Starting L2 block stream {"proven":false,"pollIntervalMS":200} 20:36:28 [20:36:28.236] INFO: world_state:prover-node Started world state synchronizer from block 1 20:36:28 [20:36:28.237] VERBOSE: world-state:block_stream:prover-node Starting sync from block number 0 20:36:28 [20:36:28.238] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/393607f63351eaa2/e251fedbf329cf35/archiver when loading epoch databases, ignoring 20:36:28 [20:36:28.238] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/393607f63351eaa2/e251fedbf329cf35/world_state when loading epoch databases, ignoring 20:36:28 [20:36:28.238] INFO: prover-client:proving-broker:prover-node Proving Broker started 20:36:28 [20:36:28.247] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:28 [20:36:28.254] INFO: prover-node:prover-coordination:prover-node Using prover coordination via aztec node 20:36:28 [20:36:28.255] INFO: e2e:e2e_multi_validator_node:prover-node Created and synced prover node {"publisherAddress":"0x3C44CdDdB6a900fa2b585dd299e03d12FA4293BC"} 20:36:28 [20:36:28.255] INFO: prover-node:epoch-monitor:prover-node Started EpochMonitor {"pollingIntervalMs":200} 20:36:28 [20:36:28.258] INFO: prover-node:prover-node Started Prover Node with prover id 0x0000000000000000000000003c44cdddb6a900fa2b585dd299e03d12fa4293bc {"proverNodePollingIntervalMs":200,"proverNodeMaxPendingJobs":10,"proverNodeMaxParallelBlocksPerEpoch":32,"txGatheringIntervalMs":1000,"txGatheringBatchSize":10,"txGatheringMaxParallelRequestsPerNode":100,"dataDirectory":"/tmp/393607f63351eaa2/e251fedbf329cf35","l1ChainId":31337,"rollupVersion":2629635894} 20:36:28 [20:36:28.258] VERBOSE: e2e:e2e_multi_validator_node Creating a pxe... 20:36:28 [20:36:28.259] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/bab546582cbae44b/pxe_data with map size 134217728 KB (LMDB v2) 20:36:28 [20:36:28.261] INFO: pxe:data:lmdb Starting data store with maxReaders 16 20:36:28 [20:36:28.543] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:28 [20:36:28.609] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:28 [20:36:28.617] WARN: sequencer:block-builder Error closing forks for block processing {"generatedMessage":false,"code":"ERR_ASSERTION","actual":false,"expected":true,"operator":"=="} 20:36:28 [20:36:28.724] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 20:36:28 [20:36:28.725] INFO: pxe:service Started PXE connected to chain 31337 version 2629635894 20:36:28 [20:36:28.773] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:28 [20:36:28.779] INFO: pxe:service Registered account 0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2 20:36:28 [20:36:28.836] WARN: pxe:service No artifact found for contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a when looking for its metadata 20:36:28 [20:36:28.839] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a as part of deployment for 0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2 20:36:28 [20:36:28.883] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:29 [20:36:29.017] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:29 [20:36:29.022] INFO: pxe:service Added contract SchnorrAccount at 0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2 with class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 20:36:29 [20:36:29.046] VERBOSE: pxe:block_stream Starting sync from block number 0 20:36:29 [20:36:29.088] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 20:36:29 [20:36:29.132] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:29 [20:36:29.200] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 20:36:29 [20:36:29.752] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a,0x2f6652d5f6110255f6132bc8be0dd74c664523f8808ebbc3eb467252a0b5e74a,0x1d9edc9e571a9e7ab4d2aa454dd024ee819579e3e22ae0fadf28089b29f3f0bc,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 20:36:29 [20:36:29.878] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 20:36:29 [20:36:29.948] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:29 [20:36:29.973] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:30 [20:36:30.067] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2"} 20:36:30 [20:36:30.107] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:30 [20:36:30.107] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2"} 20:36:30 [20:36:30.163] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:30 [20:36:30.165] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2"} 20:36:30 [20:36:30.183] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:30 [20:36:30.185] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:30 [20:36:30.393] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2"} 20:36:30 [20:36:30.439] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:30 [20:36:30.439] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2"} 20:36:30 [20:36:30.447] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:30 [20:36:30.487] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:30 [20:36:30.503] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2"} 20:36:30 [20:36:30.507] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:30 [20:36:30.524] VERBOSE: simulator:client_execution_context:debug_log Setting 0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2 as fee payer 20:36:30 [20:36:30.660] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:30 [20:36:30.774] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:30 [20:36:30.951] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:31 [20:36:31.133] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:31 [20:36:31.301] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:31 [20:36:31.453] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:31 [20:36:31.927] INFO: pxe:service Proving completed in 2883.6668910000008ms {"timings":{"total":2883.6668910000008,"sync":3.541594999998779,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":141.84920399999828,"oracles":{"callPrivateFunction":{"times":[834.2147769999974,351.32687499999884,238.68612399999984]}}},{"functionName":"private_kernel_init","time":63.62686699999904},{"functionName":"ContractClassRegisterer:register","time":816.9496590000017,"oracles":{"loadCapsule":{"times":[7.912501000002521]},"notifyCreatedNullifier":{"times":[0.5244229999989329]},"debugLog":{"times":[0.6165550000005169]},"notifyCreatedContractClassLog":{"times":[9.977300999998988]}}},{"functionName":"private_kernel_inner","time":168.13982000000033},{"functionName":"SchnorrAccount:constructor","time":315.5394919999999,"oracles":{"getContractInstance":{"times":[1.316262000000279]},"debugLog":{"times":[0.5208830000010494,0.4745120000006864]},"fetchTaggedLogs":{"times":[70.92906299999959]},"loadCapsule":{"times":[1.7439229999981762,1.7684230000013486,0.9823340000002645]},"validateEnqueuedNotes":{"times":[2.7560059999996156]},"notifyCreatedNullifier":{"times":[0.27514700000028824,0.3277479999997013]},"notifyCreatedNote":{"times":[0.13307300000087707]},"getRandomField":{"times":[0.13005400000110967,0.18562399999791523,0.08080199999676552,0.05905199999688193,0.08581200000116951,0.05325099999754457,0.0457910000004631,0.05412200000137091,0.06611100000009174,0.06020100000023376,0.043041000000812346]},"getIndexedTaggingSecretAsSender":{"times":[37.83809300000212]},"incrementAppTaggingSecretIndexAsSender":{"times":[42.41315399999803]}}},{"functionName":"private_kernel_inner","time":149.20530299999882},{"functionName":"SchnorrAccount:entrypoint","time":204.4327880000019,"oracles":{"debugLog":{"times":[0.6522760000007111,0.4217399999979534,0.5918740000015532]},"fetchTaggedLogs":{"times":[65.70714700000099]},"loadCapsule":{"times":[0.6338950000026671,0.2535359999965294,0.19306499999947846]},"validateEnqueuedNotes":{"times":[3.9311649999981455]},"getNotes":{"times":[0.9232630000005884]},"getAuthWitness":{"times":[0.20953500000177883]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.6049540000021807]}}},{"functionName":"private_kernel_inner","time":138.34000100000048},{"functionName":"private_kernel_reset","time":249.38778199999797},{"functionName":"private_kernel_tail","time":69.42579599999954}],"unaccounted":563.228584000004}} 20:36:31 [20:36:31.939] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:32 [20:36:32.007] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","blockNumber":1,"slot":3} 20:36:32 [20:36:32.014] VERBOSE: p2p:tx_pool Adding tx 0x264f29c912c6b586ec58c55a66a702ad4368e2b42cf9e666a8ca1256fc61ad94 to pool {"eventName":"tx-added-to-pool","txHash":"0x264f29c912c6b586ec58c55a66a702ad4368e2b42cf9e666a8ca1256fc61ad94","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 20:36:32 [20:36:32.039] INFO: node Received tx 0x264f29c912c6b586ec58c55a66a702ad4368e2b42cf9e666a8ca1256fc61ad94 {"txHash":"0x264f29c912c6b586ec58c55a66a702ad4368e2b42cf9e666a8ca1256fc61ad94"} 20:36:32 [20:36:32.040] INFO: pxe:service Sent transaction 0x264f29c912c6b586ec58c55a66a702ad4368e2b42cf9e666a8ca1256fc61ad94 20:36:32 [20:36:32.150] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:32 [20:36:32.239] VERBOSE: sequencer:block-builder Building block 1 for slot 3 {"slot":3,"slotStart":"2025-06-04T20:41:49.000Z","now":"2025-06-04T20:41:41.434Z","blockNumber":1,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x14ec80ae247d6466b8a7781a48ca0907b90f46c4992d9724347ae8346a2fda71"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 20:36:32 [20:36:32.299] VERBOSE: simulator:public-processor Processed tx 0x264f29c912c6b586ec58c55a66a702ad4368e2b42cf9e666a8ca1256fc61ad94 with no public calls in 31.2799649999979ms {"txHash":"0x264f29c912c6b586ec58c55a66a702ad4368e2b42cf9e666a8ca1256fc61ad94","txFee":2101212240,"revertCode":0,"gasUsed":{"totalGas":{"daGas":16896,"l2Gas":6324},"billedGas":{"daGas":16896,"l2Gas":6324},"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":31.2799649999979} 20:36:32 [20:36:32.301] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.05904434599999877s {"duration":0.05904434599999877,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 20:36:32 [20:36:32.393] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:32 [20:36:32.409] INFO: sequencer Built block 1 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x194870ec6480b8e5583c5622774dcb1f53f1eb9164ade3e5579bb2e1f7e14614","globalVariables":{"chainId":31337,"version":2629635894,"blockNumber":1,"slotNumber":3,"timestamp":1749069709,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x264f29c912c6b586ec58c55a66a702ad4368e2b42cf9e666a8ca1256fc61ad94"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":176.81052999999883,"publicProcessDuration":59.51874699999826,"rollupCircuitsDuration":170.44677600000068,"txCount":1,"blockNumber":1,"blockTimestamp":1749069709,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:36:32 [20:36:32.414] VERBOSE: sequencer Attesting committee is empty 20:36:32 [20:36:32.599] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:32 [20:36:32.799] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x0ce99d50287fa121b392cef29f1428a94c50f6e68d1ce4cd823fa12cedc17675 20:36:32 [20:36:32.799] VERBOSE: node Sent L1 transaction 0x0ce99d50287fa121b392cef29f1428a94c50f6e68d1ce4cd823fa12cedc17675 {"gasLimit":555044,"maxFeePerGas":"114.549196547","maxPriorityFeePerGas":"114.475459934","maxFeePerBlobGas":"0.000000001"} 20:36:32 [20:36:32.804] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:32 [20:36:32.819] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 20:36:32 [20:36:32.823] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:36:32 [20:36:32.823] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x0ce99d50287fa121b392cef29f1428a94c50f6e68d1ce4cd823fa12cedc17675","calldataSize":1444,"calldataGas":12268,"txCount":1,"blockNumber":1,"blockTimestamp":1749069709,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:36:32 [20:36:32.823] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:36:32 [20:36:32.826] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 20:36:32 [20:36:32.830] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 20:36:32 [20:36:32.834] INFO: blob-sink:server Received blob sidecar for block 0x636f79db3c3e4e7d869063321871e62df5df2cb46cd5bd5d8d2963bfce622670 20:36:32 [20:36:32.847] INFO: blob-sink:server Blob sidecar stored successfully for block 0x636f79db3c3e4e7d869063321871e62df5df2cb46cd5bd5d8d2963bfce622670 20:36:33 [20:36:33.005] VERBOSE: archiver:block-helper Store contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 20:36:33 [20:36:33.108] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:33 [20:36:33.121] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:33 [20:36:33.121] WARN: foundation:test-date-provider Time set to 2025-06-04T20:41:49.000Z {"offset":315879,"timeMs":1749069709000} 20:36:33 [20:36:33.130] VERBOSE: archiver:block-helper:prover-node Store contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 20:36:33 [20:36:33.137] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x194870ec6480b8e5583c5622774dcb1f53f1eb9164ade3e5579bb2e1f7e14614","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":2629635894,"blockNumber":1,"slotNumber":3,"timestamp":1749069709,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","archiveNextLeafIndex":2} 20:36:33 [20:36:33.148] INFO: archiver:prover-node Downloaded L2 block 1 {"blockHash":"0x194870ec6480b8e5583c5622774dcb1f53f1eb9164ade3e5579bb2e1f7e14614","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":2629635894,"blockNumber":1,"slotNumber":3,"timestamp":1749069709,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","archiveNextLeafIndex":2} 20:36:33 [20:36:33.150] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749069745] on http://127.0.0.1:8545 20:36:33 [20:36:33.155] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 20:36:33 [20:36:33.157] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:36:33 [20:36:33.162] VERBOSE: sequencer Unable to build/enqueue block Rollup__InvalidArchive(0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9, 0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8) 20:36:33 [20:36:33.163] INFO: archiver:prover-node Updated proven chain to block 1 {"provenBlockNumber":1} 20:36:33 [20:36:33.165] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749069745 20:36:33 [20:36:33.165] WARN: foundation:test-date-provider Time set to 2025-06-04T20:42:25.000Z {"offset":351835,"timeMs":1749069745000} 20:36:33 [20:36:33.165] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 20:36:33 [20:36:33.225] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749072049] on http://127.0.0.1:8545 20:36:33 [20:36:33.226] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:36:33 [20:36:33.228] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749072049 20:36:33 [20:36:33.261] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749074353] on http://127.0.0.1:8545 20:36:33 [20:36:33.262] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:36:33 [20:36:33.264] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749074353 20:36:33 [20:36:33.267] INFO: e2e:e2e_multi_validator_node Deploying contract from 0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2 20:36:33 [20:36:33.613] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:33 [20:36:33.621] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:33 [20:36:33.621] WARN: foundation:test-date-provider Time set to 2025-06-04T21:59:13.000Z {"offset":4959379,"timeMs":1749074353000} 20:36:33 [20:36:33.636] INFO: pxe:service Added contract StatefulTest at 0x01c2a8350bf01bf4c70f6cd7dc40db3be6d14dcef11da94d8773d7800c686c9d with class 0x0e75e50f67127bddbbf0c6e812b093c4e20dc26681235d9c77c3d57f93ab52a0 20:36:33 [20:36:33.663] INFO: world_state:prover-node World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":10.34158000000025,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749069709,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:36:33 [20:36:33.665] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":10.841852000001381,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749069709,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 20:36:33 [20:36:33.688] VERBOSE: p2p Synched to latest block 1 20:36:33 [20:36:33.702] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","header":{"contentCommitment":{"blobsHash":"0x0039ef447476e7bd8d24b1f6fe0b589b69ee6bee294a71b15600f520f90fc5fc","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749069709,"version":2629635894},"lastArchive":"0x17a244dc5bf3063071422794cab8d3590702912d18f0b774e58bb219e2154fb8","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x0d4d61680ff7eb507a67d70ed512baa4df2c46cc02c4671581b666685c80da06","nullifierTree":"0x0a3ad2d3fb2e53e87b0580919ac97b1944fb2564ba1afdedf97faadf9c157da9","publicDataTree":"0x01f142aa9e86bdf9420b274c2a27565f09b18fd27c0b4bcebda84d863b08213e"},"totalFees":2101212240,"totalManaUsed":6324}} 20:36:33 [20:36:33.802] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2"} 20:36:33 [20:36:33.861] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:33 [20:36:33.862] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2"} 20:36:33 [20:36:33.862] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:33 [20:36:33.925] VERBOSE: sequencer Not enough txs to build block 2 at slot 132 (got 0 txs, need 1) {"chainTipArchive":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","blockNumber":2,"slot":132} 20:36:33 [20:36:33.969] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2"} 20:36:33 [20:36:33.973] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0ec8ba64606b1cfb60a1ecaaf9d05415693bdd43c9c037d121239f055377bcd8 20:36:33 [20:36:33.986] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 20:36:33 [20:36:33.987] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 20:36:33 [20:36:33.995] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 20:36:33 [20:36:33.996] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 20:36:33 [20:36:33.999] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:34 [20:36:34.003] VERBOSE: pxe:service Added note {"index":0,"contract":"0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1c2f38814cfcdea8f93dea68c813c7a7c666ffcbb7d4746b067b89de4c5c932e","nullifier":"0x0714c919746e825a393fa3591ddbb33e424dc672394708da6ee5d0c55581c8d9"} 20:36:34 [20:36:34.022] VERBOSE: simulator:client_execution_context:debug_log Setting 0x08d9b0c04f74b06d244458f676eff6030483b2d6fbecb4bdc554af206d62dca2 as fee payer 20:36:34 [20:36:34.102] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:34 [20:36:34.171] INFO: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 is on the validator committee for epoch 4 20:36:34 [20:36:34.171] INFO: validator Validator 0x70997970c51812dc3a010c7d01b50e0d17dc79c8 is on the validator committee for epoch 4 20:36:34 [20:36:34.171] INFO: validator Validator 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc is on the validator committee for epoch 4 20:36:34 [20:36:34.229] VERBOSE: simulator:private_execution Executing private function StatefulTest:constructor {"contract":"0x01c2a8350bf01bf4c70f6cd7dc40db3be6d14dcef11da94d8773d7800c686c9d"} 20:36:34 [20:36:34.260] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:34 [20:36:34.261] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x01c2a8350bf01bf4c70f6cd7dc40db3be6d14dcef11da94d8773d7800c686c9d"} 20:36:34 [20:36:34.272] VERBOSE: prover-node:prover-node Fetching 1 tx hashes for block number 1 from coordination 20:36:34 [20:36:34.272] INFO: prover-node:combined-prover-coordination:prover-node Check for 1 txs found 1 missing. Will gather from nodes and p2p 20:36:34 [20:36:34.284] VERBOSE: prover-node:combined-prover-coordination:prover-node Gathered 1 of 1 txs from a node 20:36:34 [20:36:34.285] INFO: prover-node:combined-prover-coordination:prover-node Found all 1 txs directly from nodes 20:36:34 [20:36:34.298] VERBOSE: sequencer Not enough txs to build block 2 at slot 132 (got 0 txs, need 1) {"chainTipArchive":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","blockNumber":2,"slot":132} 20:36:34 [20:36:34.387] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:34 [20:36:34.412] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x01c2a8350bf01bf4c70f6cd7dc40db3be6d14dcef11da94d8773d7800c686c9d"} 20:36:34 [20:36:34.418] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:34 [20:36:34.637] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:34 [20:36:34.764] VERBOSE: simulator:private_execution Executing private function StatefulTest:create_note_no_init_check {"contract":"0x01c2a8350bf01bf4c70f6cd7dc40db3be6d14dcef11da94d8773d7800c686c9d"} 20:36:34 [20:36:34.816] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 20:36:34 [20:36:34.816] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x01c2a8350bf01bf4c70f6cd7dc40db3be6d14dcef11da94d8773d7800c686c9d"} 20:36:34 [20:36:34.907] VERBOSE: sequencer Not enough txs to build block 2 at slot 132 (got 0 txs, need 1) {"chainTipArchive":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","blockNumber":2,"slot":132} 20:36:34 [20:36:34.916] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:34 [20:36:34.954] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x01c2a8350bf01bf4c70f6cd7dc40db3be6d14dcef11da94d8773d7800c686c9d"} 20:36:34 [20:36:34.957] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 20:36:35 [20:36:35.147] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:35 [20:36:35.275] VERBOSE: sequencer Not enough txs to build block 2 at slot 132 (got 0 txs, need 1) {"chainTipArchive":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","blockNumber":2,"slot":132} 20:36:35 [20:36:35.432] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:35 [20:36:35.698] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:35 [20:36:35.736] VERBOSE: sequencer Not enough txs to build block 2 at slot 132 (got 0 txs, need 1) {"chainTipArchive":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","blockNumber":2,"slot":132} 20:36:35 [20:36:35.937] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:36 [20:36:36.152] VERBOSE: sequencer Not enough txs to build block 2 at slot 132 (got 0 txs, need 1) {"chainTipArchive":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","blockNumber":2,"slot":132} 20:36:36 [20:36:36.637] INFO: pxe:service Proving completed in 2956.5826209999977ms {"timings":{"total":2956.5826209999977,"sync":23.33971400000155,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":363.8934859999972,"oracles":{"debugLog":{"times":[0.6687459999993735,0.4110900000014226,0.5260329999982787,0.5980239999989863,0.4312599999975646,0.38752999999996973,0.33268799999859766,0.6948660000016389]},"fetchTaggedLogs":{"times":[109.09039499999926]},"loadCapsule":{"times":[0.2551769999990938,0.1776739999986603,0.4910719999970752,0.22919499999989057,0.20077500000115833,0.22878599999967264,0.22101499999916996]},"getSharedSecret":{"times":[3.9260450000001583]},"aes128Decrypt":{"times":[0.28178700000353274,1.0252939999991213]},"getPublicKeysAndPartialAddress":{"times":[1.8207839999995485]},"getKeyValidationRequest":{"times":[2.512730999998894]},"storeCapsule":{"times":[0.6088940000008733,0.28923699999722885,0.2919870000005176]},"deleteCapsule":{"times":[0.3449090000030992]},"validateEnqueuedNotes":{"times":[5.108313000000635]},"getNotes":{"times":[1.4840859999967506]},"getAuthWitness":{"times":[0.2818170000027749]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.11257300000215764]},"callPrivateFunction":{"times":[1102.0763860000006]}}},{"functionName":"private_kernel_init","time":70.04082100000232},{"functionName":"StatefulTest:constructor","time":406.55745000000024,"oracles":{"getContractInstance":{"times":[8.070934999999736]},"debugLog":{"times":[0.5022019999996701,0.4494509999967704]},"fetchTaggedLogs":{"times":[155.05224499999895]},"loadCapsule":{"times":[0.6851369999967574,0.35971799999970244,0.24867599999925005]},"validateEnqueuedNotes":{"times":[1.6565100000007078]},"storeInExecutionCache":{"times":[0.052971000000979984]},"callPrivateFunction":{"times":[698.6198910000021]},"notifyCreatedNullifier":{"times":[0.27541699999710545]}}},{"functionName":"private_kernel_inner","time":127.77883600000496},{"functionName":"StatefulTest:create_note_no_init_check","time":630.3255820000013,"oracles":{"debugLog":{"times":[0.6092939999944065,0.28773699999874225]},"fetchTaggedLogs":{"times":[139.8854680000004]},"loadCapsule":{"times":[0.19072500000038417,0.17414399999688612,0.642095999995945]},"validateEnqueuedNotes":{"times":[2.5178109999978915]},"getRandomField":{"times":[0.14862400000129128,0.11349300000438234,0.18544399999518646,0.05352100000163773,0.08256199999595992,0.04619100000127219,0.04457099999854108,0.05228099999658298,0.035240999997768085,0.03508099999453407,0.07403200000408106,0.05473199999687495]},"notifyCreatedNote":{"times":[0.1754739999960293]},"getIndexedTaggingSecretAsSender":{"times":[59.205198999996355]},"incrementAppTaggingSecretIndexAsSender":{"times":[63.572504999996454]}}},{"functionName":"private_kernel_inner","time":151.92209799999546},{"functionName":"private_kernel_reset","time":236.5968040000007},{"functionName":"private_kernel_tail","time":90.17190799999662}],"unaccounted":855.9559219999974}} 20:36:36 [20:36:36.659] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:36 [20:36:36.720] VERBOSE: sequencer Not enough txs to build block 2 at slot 132 (got 0 txs, need 1) {"chainTipArchive":"0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9","blockNumber":2,"slot":132} 20:36:36 [20:36:36.736] VERBOSE: p2p:tx_pool Adding tx 0x1a1d83fa060a7e811c6b11b6687bb97f8d3d39d0eb86396615054ea1ffd88c6b to pool {"eventName":"tx-added-to-pool","txHash":"0x1a1d83fa060a7e811c6b11b6687bb97f8d3d39d0eb86396615054ea1ffd88c6b","noteHashCount":1,"nullifierCount":2,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 20:36:36 [20:36:36.753] INFO: node Received tx 0x1a1d83fa060a7e811c6b11b6687bb97f8d3d39d0eb86396615054ea1ffd88c6b {"txHash":"0x1a1d83fa060a7e811c6b11b6687bb97f8d3d39d0eb86396615054ea1ffd88c6b"} 20:36:36 [20:36:36.753] INFO: pxe:service Sent transaction 0x1a1d83fa060a7e811c6b11b6687bb97f8d3d39d0eb86396615054ea1ffd88c6b 20:36:36 [20:36:36.878] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:36 [20:36:36.970] VERBOSE: sequencer:block-builder Building block 2 for slot 132 {"slot":132,"slotStart":"2025-06-04T21:59:13.000Z","now":"2025-06-04T21:59:16.349Z","blockNumber":2,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x14ec80ae247d6466b8a7781a48ca0907b90f46c4992d9724347ae8346a2fda71"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 20:36:37 [20:36:37.003] VERBOSE: simulator:public-processor Processed tx 0x1a1d83fa060a7e811c6b11b6687bb97f8d3d39d0eb86396615054ea1ffd88c6b with no public calls in 15.34603000000061ms {"txHash":"0x1a1d83fa060a7e811c6b11b6687bb97f8d3d39d0eb86396615054ea1ffd88c6b","txFee":196507480,"revertCode":0,"gasUsed":{"totalGas":{"daGas":11776,"l2Gas":4814},"billedGas":{"daGas":11776,"l2Gas":4814},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":2,"noteHashCount":1,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":15.34603000000061} 20:36:37 [20:36:37.014] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.04188322199999675s {"duration":0.04188322199999675,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 20:36:37 [20:36:37.130] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:37 [20:36:37.146] INFO: sequencer Built block 2 for slot 132 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x26229f4848be6579f58aac81a3cccff3a6048dacb6947b29c821c7c56fdcc209","globalVariables":{"chainId":31337,"version":2629635894,"blockNumber":2,"slotNumber":132,"timestamp":1749074353,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"txHashes":["0x1a1d83fa060a7e811c6b11b6687bb97f8d3d39d0eb86396615054ea1ffd88c6b"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":184.6287380000067,"publicProcessDuration":42.5077569999994,"rollupCircuitsDuration":176.9320130000051,"txCount":1,"blockNumber":2,"blockTimestamp":1749074353,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:36:37 [20:36:37.153] VERBOSE: p2p Broadcasting proposal for slot 132 to peers 20:36:37 [20:36:37.163] VERBOSE: p2p:attestation_pool Added attestation for slot 132 from 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 {"signature":"0xad67307eb60933423d87544a7e7185eff29e323a09ff57019e42050c58a59bcc26755b11141455d4daa63f12464d10917bc97c04fefcabde0c456dc23f5b94771b","slotNumber":"0x0000000000000000000000000000000000000000000000000000000000000084","address":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","proposalId":"0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097"} 20:36:37 [20:36:37.167] VERBOSE: p2p:attestation_pool Added attestation for slot 132 from 0x70997970c51812dc3a010c7d01b50e0d17dc79c8 {"signature":"0x29a37c791ea64137a355bd90520a2d6843441a17aafcb144521a016aa48238ea7018dfce9ee888255d16b931b7bc9aa0c745973597cc6dea94e53351a0283e991c","slotNumber":"0x0000000000000000000000000000000000000000000000000000000000000084","address":"0x70997970c51812dc3a010c7d01b50e0d17dc79c8","proposalId":"0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097"} 20:36:37 [20:36:37.170] VERBOSE: p2p:attestation_pool Added attestation for slot 132 from 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc {"signature":"0x036b618b66f3ed4b6ed7c4ec8bda2b53bad643a8ee4a4d358db6348403120c6610dce8b710efd16864476fa90a78c43b006973da790d4d5be83e75239d6705711c","slotNumber":"0x0000000000000000000000000000000000000000000000000000000000000084","address":"0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc","proposalId":"0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097"} 20:36:37 [20:36:37.171] VERBOSE: validator Collected all 3 attestations for slot 132 20:36:37 [20:36:37.171] VERBOSE: sequencer Collected 3 attestations {"blockHash":"0x26229f4848be6579f58aac81a3cccff3a6048dacb6947b29c821c7c56fdcc209","blockNumber":2} 20:36:37 [20:36:37.342] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:37 [20:36:37.552] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:37 [20:36:37.556] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x6b9d00230df67acae33f6a196e3db6583b5349987bf97186e9bb05e088a794ef 20:36:37 [20:36:37.557] VERBOSE: node Sent L1 transaction 0x6b9d00230df67acae33f6a196e3db6583b5349987bf97186e9bb05e088a794ef {"gasLimit":633246,"maxFeePerGas":"1.233593069","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 20:36:37 [20:36:37.565] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 20:36:37 [20:36:37.571] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 20:36:37 [20:36:37.576] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 20:36:37 [20:36:37.585] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 20:36:37 [20:36:37.586] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x6b9d00230df67acae33f6a196e3db6583b5349987bf97186e9bb05e088a794ef","calldataSize":1828,"calldataGas":16864,"txCount":1,"blockNumber":2,"blockTimestamp":1749074353,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:36:37 [20:36:37.586] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 20:36:37 [20:36:37.644] INFO: blob-sink:server Received blob sidecar for block 0xf83ad93c20f6169ab71a5080d74c9080bab6918ef17536fc96e36652a0b8bfde 20:36:37 [20:36:37.651] INFO: blob-sink:server Blob sidecar stored successfully for block 0xf83ad93c20f6169ab71a5080d74c9080bab6918ef17536fc96e36652a0b8bfde 20:36:37 [20:36:37.652] WARN: node:blob-sink:client Unable to get blob sidecar for 0xf83ad93c20f6169ab71a5080d74c9080bab6918ef17536fc96e36652a0b8bfde: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 20:36:37 [20:36:37.652] WARN: node:blob-sink:client Failed to fetch blobs for 0xf83ad93c20f6169ab71a5080d74c9080bab6918ef17536fc96e36652a0b8bfde from all blob sources {"blobSinkUrl":"http://localhost:41365"} 20:36:37 [20:36:37.778] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:37 [20:36:37.787] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 20:36:37 [20:36:37.787] WARN: foundation:test-date-provider Time set to 2025-06-04T21:59:25.000Z {"offset":4967213,"timeMs":1749074365000} 20:36:37 [20:36:37.802] INFO: archiver:prover-node Downloaded L2 block 2 {"blockHash":"0x26229f4848be6579f58aac81a3cccff3a6048dacb6947b29c821c7c56fdcc209","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":2629635894,"blockNumber":2,"slotNumber":132,"timestamp":1749074353,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"archiveRoot":"0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097","archiveNextLeafIndex":3} 20:36:37 [20:36:37.807] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749074389] on http://127.0.0.1:8545 20:36:37 [20:36:37.814] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 20:36:37 [20:36:37.817] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749074389 20:36:37 [20:36:37.817] WARN: foundation:test-date-provider Time set to 2025-06-04T21:59:49.000Z {"offset":4991183,"timeMs":1749074389000} 20:36:37 [20:36:37.818] INFO: aztecjs:utils:watcher Slot 132 was filled, jumped to next slot 20:36:37 [20:36:37.818] INFO: archiver:prover-node Updated proven chain to block 2 {"provenBlockNumber":2} 20:36:37 [20:36:37.826] VERBOSE: sequencer Unable to build/enqueue block Rollup__InvalidArchive(0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097, 0x0033d1be14c8c9f056f1099eb697c6c8176bca1d670ec948356c825f056723f9) 20:36:38 [20:36:38.001] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:38 [20:36:38.014] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x26229f4848be6579f58aac81a3cccff3a6048dacb6947b29c821c7c56fdcc209","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":2629635894,"blockNumber":2,"slotNumber":132,"timestamp":1749074353,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"archiveRoot":"0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097","archiveNextLeafIndex":3} 20:36:38 [20:36:38.017] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 20:36:38 [20:36:38.022] INFO: world_state:prover-node World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":7.706552000003285,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749074353,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:36:38 [20:36:38.050] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":6.450237999997626,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749074353,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 20:36:38 [20:36:38.053] VERBOSE: p2p Synched to latest block 2 20:36:38 [20:36:38.205] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:38 [20:36:38.244] VERBOSE: sequencer Not enough txs to build block 3 at slot 133 (got 0 txs, need 1) {"chainTipArchive":"0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097","blockNumber":3,"slot":133} 20:36:38 [20:36:38.324] VERBOSE: prover-node:prover-node Fetching 1 tx hashes for block number 2 from coordination 20:36:38 [20:36:38.325] INFO: prover-node:combined-prover-coordination:prover-node Check for 1 txs found 1 missing. Will gather from nodes and p2p 20:36:38 [20:36:38.327] VERBOSE: prover-node:combined-prover-coordination:prover-node Gathered 1 of 1 txs from a node 20:36:38 [20:36:38.327] INFO: prover-node:combined-prover-coordination:prover-node Found all 1 txs directly from nodes 20:36:38 [20:36:38.408] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:38 [20:36:38.460] VERBOSE: sequencer Not enough txs to build block 3 at slot 133 (got 0 txs, need 1) {"chainTipArchive":"0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097","blockNumber":3,"slot":133} 20:36:38 [20:36:38.610] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:38 [20:36:38.672] VERBOSE: sequencer Not enough txs to build block 3 at slot 133 (got 0 txs, need 1) {"chainTipArchive":"0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097","blockNumber":3,"slot":133} 20:36:38 [20:36:38.911] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 20:36:38 [20:36:38.964] VERBOSE: sequencer Not enough txs to build block 3 at slot 133 (got 0 txs, need 1) {"chainTipArchive":"0x110b07367199e46566fc1f7e495714358a183ea68880e750f238f9937c212097","blockNumber":3,"slot":133} 20:36:38 [20:36:38.967] INFO: node Stopping 20:36:38 [20:36:38.968] INFO: slasher Slasher client stopped. 20:36:38 [20:36:38.969] INFO: sequencer Stopped sequencer 20:36:38 [20:36:38.969] INFO: p2p P2P client stopped. 20:36:38 [20:36:38.972] INFO: world_state Stopped world state synchronizer 20:36:38 [20:36:38.972] INFO: archiver Stopped. 20:36:38 [20:36:38.972] INFO: node Stopped 20:36:38 [20:36:38.973] INFO: prover-node:prover-node Stopping ProverNode 20:36:38 [20:36:38.973] INFO: prover-node:epoch-monitor:prover-node Stopped EpochMonitor 20:36:38 [20:36:38.973] INFO: archiver:prover-node Stopped. 20:36:38 [20:36:38.975] INFO: world_state:prover-node Stopped world state synchronizer 20:36:38 [20:36:38.976] INFO: prover-node:prover-node Stopped ProverNode 20:36:38 [20:36:38.976] VERBOSE: e2e:e2e_multi_validator_node Cleaning up ACVM state 20:36:38 [20:36:38.976] INFO: e2e:e2e_multi_validator_node Cleaning up ACVM temp directory /tmp/e932d60d 20:36:38 [20:36:38.977] VERBOSE: e2e:e2e_multi_validator_node Cleaning up BB state 20:36:38 [20:36:38.980] INFO: blob-sink:server Stopping blob sink 20:36:38 [20:36:38.980] INFO: blob-sink:server Blob sink stopped 20:36:38 [20:36:38.980] VERBOSE: e2e:e2e_multi_validator_node Cleaning up data directory at /tmp/393607f63351eaa2 20:36:39 FAIL src/e2e_multi_validator_node.test.ts 20:36:39 e2e_multi_validator_node 20:36:39 ✕ should build blocks & attest with multiple validator keys (16234 ms) 20:36:39 ✓ should attest ONLY with the correct validator keys (13428 ms) 20:36:39 20:36:39 ● e2e_multi_validator_node › should build blocks & attest with multiple validator keys 20:36:39 20:36:39 expect(received).toBeGreaterThanOrEqual(expected) 20:36:39 20:36:39 Expected: >= 4 20:36:39 Received: 0 20:36:39 20:36:39 130 | .map(a => new BlockAttestation(new Fr(block.block.number), payload, a.signature)); 20:36:39 131 | 20:36:39 > 132 | expect(attestations.length).toBeGreaterThanOrEqual(4); // Math.floor((5 * 2) / 3) + 1 20:36:39 | ^ 20:36:39 133 | 20:36:39 134 | const signers = attestations.map(att => att.getSender().toString()); 20:36:39 135 | 20:36:39 20:36:39 at Object.toBeGreaterThanOrEqual (e2e_multi_validator_node.test.ts:132:33) 20:36:39 20:36:39 Test Suites: 1 failed, 1 total 20:36:39 Tests: 1 failed, 1 passed, 2 total 20:36:39 Snapshots: 0 total 20:36:39 Time: 36.479 s 20:36:39 Ran all test suites matching /src\/e2e_multi_validator_node.test.ts/i. 20:36:39 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?