Parent Log: http://ci.aztec-labs.com/0ea93f184b6022ad Command: 7db1570de137966f: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/a811d99754088e4a956a388689e3530049a3692f Env: REF_NAME=gh-readonly-queue/next/pr-14684-d04e170e799e9bb8e30688c5e848ab96a51ebb2b CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Tue Jun 3 21:28:49 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14684_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/0d13f39a62f4c76d 21:28:50 +++ id -u 21:28:50 +++ id -g 21:28:50 ++ 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'\''' 21:28:50 + cid=cd4bcaaa291914dac479d5f9d92133932bd03324af1c2401e3083d04d3659a3f 21:28:50 + set +x 21:28:51 {"level":25,"time":1748986131057,"pid":12,"hostname":"cd4bcaaa2919","module":"logger","msg":"Logger initialized with level verbose"} 21:28:58 [21:28:58.021] INFO: e2e:e2e_multi_validator_node Running test: e2e_multi_validator_node should build blocks & attest with multiple validator keys 21:28:58 [21:28:58.390] INFO: e2e:e2e_multi_validator_node Logging metrics to log/e2e_multi_validator_node.jsonl 21:28:58 [21:28:58.975] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-Z9WVMg/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 21:28:59 [21:28:59.085] INFO: e2e:e2e_multi_validator_node Calling anvil_setBlockTimestampInterval with params: [12] 21:28:59 [21:28:59.166] WARN: e2e:e2e_multi_validator_node Set block interval to 12 21:28:59 [21:28:59.166] VERBOSE: e2e:e2e_multi_validator_node Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 21:28:59 [21:28:59.257] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 21:28:59 [21:28:59.273] VERBOSE: e2e:e2e_multi_validator_node Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 21:28:59 [21:28:59.302] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 21:28:59 [21:28:59.310] VERBOSE: e2e:e2e_multi_validator_node Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 21:28:59 [21:28:59.339] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x15a910452a602ec46131d746d66411db81d7fc72b90362a00ee4d18cc6abe51b {"gasLimit":3064716,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 21:28:59 [21:28:59.347] VERBOSE: e2e:e2e_multi_validator_node Deployed GSE at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 21:28:59 [21:28:59.376] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x263f559e4ac0e4916749f1cfef6c86ab177d4ed50b4e9dd5b714b2907c3ea485 {"gasLimit":1014492,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 21:28:59 [21:28:59.385] VERBOSE: e2e:e2e_multi_validator_node Deployed Registry at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 21:28:59 [21:28:59.405] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x20a9b0b462f62bc3be8ca34c505c34cf2a4c1358e5584da2d561d72abc96aed1 {"gasLimit":1945066,"maxFeePerGas":"3.602461376","maxPriorityFeePerGas":"2.48832"} 21:28:59 [21:28:59.410] VERBOSE: e2e:e2e_multi_validator_node Deployed GovernanceProposer at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 21:28:59 [21:28:59.430] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x28a12a8c995db07c5f5cafec9b82f038e63dc5e97dcd98daa9cfc9f4a5ab8de7 {"gasLimit":3043143,"maxFeePerGas":"3.968706922","maxPriorityFeePerGas":"2.985984"} 21:28:59 [21:28:59.434] VERBOSE: e2e:e2e_multi_validator_node Deployed Governance at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 21:28:59 [21:28:59.460] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f {"gasLimit":55548,"maxFeePerGas":"4.456337397","maxPriorityFeePerGas":"3.5831808"} 21:28:59 [21:28:59.460] VERBOSE: e2e:e2e_multi_validator_node Set governance on GSE in 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f 21:28:59 [21:28:59.474] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x5d35a155eec0ed0c7deff752275abe3c6d5f448014ad234660feb820ef8620df {"gasLimit":391705,"maxFeePerGas":"5.082281352","maxPriorityFeePerGas":"4.29981696"} 21:28:59 [21:28:59.479] VERBOSE: e2e:e2e_multi_validator_node Deployed CoinIssuer at 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 21:28:59 [21:28:59.479] VERBOSE: e2e:e2e_multi_validator_node Waiting for deployments to complete 21:28:59 [21:28:59.480] INFO: e2e:e2e_multi_validator_node Waiting for 7 transactions to be mined... 21:28:59 [21:28:59.504] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:28:59 [21:28:59.520] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed {"gasLimit":56804,"maxFeePerGas":"5.84473853","maxPriorityFeePerGas":"5.159780352"} 21:28:59 [21:28:59.521] VERBOSE: e2e:e2e_multi_validator_node Added coin issuer 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 as minter on fee asset in 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed 21:28:59 [21:28:59.537] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x8e92b552cc623d62987e85e1df7c096f9eae4836710a4f62deed3668e8d02fa6 {"gasLimit":56661,"maxFeePerGas":"6.792938035","maxPriorityFeePerGas":"6.191736422"} 21:28:59 [21:28:59.581] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2c50b6224c5aaa3d953e1f1029dcd0ea319c195a3237972ff224e8c29a1434cd {"gasLimit":339691,"maxFeePerGas":"7.956372276","maxPriorityFeePerGas":"7.430083706"} 21:28:59 [21:28:59.589] VERBOSE: e2e:e2e_multi_validator_node Deployed FeeAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 21:28:59 [21:28:59.608] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c {"gasLimit":56804,"maxFeePerGas":"9.376810034","maxPriorityFeePerGas":"8.916100447"} 21:28:59 [21:28:59.609] VERBOSE: e2e:e2e_multi_validator_node Added fee asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on fee asset in 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c 21:28:59 [21:28:59.632] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x3bf548a99420047e1164bf2dfa64c5fc4ed32845f7019722b8db00da065120d2 {"gasLimit":1089256,"maxFeePerGas":"11.103528222","maxPriorityFeePerGas":"10.699320536"} 21:28:59 [21:28:59.639] VERBOSE: e2e:e2e_multi_validator_node Deployed StakingAssetHandler at 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 21:28:59 [21:28:59.670] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 {"gasLimit":56804,"maxFeePerGas":"13.193025819","maxPriorityFeePerGas":"12.839184643"} 21:28:59 [21:28:59.674] VERBOSE: e2e:e2e_multi_validator_node Added staking asset handler 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 as minter on staking asset in 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 21:28:59 [21:28:59.675] VERBOSE: e2e:e2e_multi_validator_node Waiting for deployments to complete 21:28:59 [21:28:59.675] INFO: e2e:e2e_multi_validator_node Waiting for 9 transactions to be mined... 21:28:59 [21:28:59.735] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:28:59 [21:28:59.750] VERBOSE: e2e:e2e_multi_validator_node Deployed shared contracts 21:28:59 [21:28:59.774] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 {"gasLimit":84837,"maxFeePerGas":"15.719309155","maxPriorityFeePerGas":"15.407021571"} 21:28:59 [21:28:59.774] VERBOSE: e2e:e2e_multi_validator_node Funded reward distributor with 10000000000000000000000000 fee asset in 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 21:28:59 [21:28:59.778] 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} 21:28:59 [21:28:59.809] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x4b00bf010016af1a686fa82a49ffcd696fe6567f2fafe88a5077a3196ed3622b {"gasLimit":86397,"maxFeePerGas":"18.761800709","maxPriorityFeePerGas":"18.488425885"} 21:28:59 [21:28:59.849] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x5ab770e71a294d59a299893ceef963b3326d216a75236785d0f3ad5811b5880e {"gasLimit":6285180,"maxFeePerGas":"22.425475094","maxPriorityFeePerGas":"22.186111062"} 21:28:59 [21:28:59.857] VERBOSE: e2e:e2e_multi_validator_node Awaiting for linked libraries to be deployed 21:28:59 [21:28:59.951] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x18dc639a11e51e609e87216fe25243d52d06763faeb52825350660e009348aae {"gasLimit":10716795,"maxFeePerGas":"26.832920416","maxPriorityFeePerGas":"26.623333274"} 21:28:59 [21:28:59.975] 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} 21:28:59 [21:28:59.977] INFO: e2e:e2e_multi_validator_node Waiting for 10 transactions to be mined... 21:29:00 [21:29:00.010] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:29:00 [21:29:00.010] VERBOSE: e2e:e2e_multi_validator_node All core contracts have been deployed 21:29:00 [21:29:00.030] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a {"gasLimit":64317,"maxFeePerGas":"32.140536546","maxPriorityFeePerGas":"31.947999928"} 21:29:00 [21:29:00.031] VERBOSE: e2e:e2e_multi_validator_node Funding fee juice portal with 10000000000000000000000 fee juice in 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a (accelerated test deployments) 21:29:00 [21:29:00.043] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xd413488be28de232ee67a932daca30e5e7bc4e1ad751dedc3910bbbe185aafe4 {"gasLimit":1040116,"maxFeePerGas":"38.520398451","maxPriorityFeePerGas":"38.337599913"} 21:29:00 [21:29:00.048] VERBOSE: e2e:e2e_multi_validator_node Deployed SlashFactory at 0x3aa5ebb10dc797cac828524e59a333d0a371443c 21:29:00 [21:29:00.080] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x9cf2e8cc19e5a14c92d6ffc7b824d5af7abcd5e99d83ce8f9d8f18174a0f48ec {"gasLimit":116846,"maxFeePerGas":"46.165150264","maxPriorityFeePerGas":"46.005119895"} 21:29:00 [21:29:00.081] VERBOSE: e2e:e2e_multi_validator_node Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to registry 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 in tx 0x9cf2e8cc19e5a14c92d6ffc7b824d5af7abcd5e99d83ce8f9d8f18174a0f48ec 21:29:00 [21:29:00.122] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2a5b9e16c5abd0a75879e886c16c1174a5bec7f4574004840187d6fdb1602285 {"gasLimit":109478,"maxFeePerGas":"55.34732635","maxPriorityFeePerGas":"55.206143874"} 21:29:00 [21:29:00.122] VERBOSE: e2e:e2e_multi_validator_node Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to GSE 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x2a5b9e16c5abd0a75879e886c16c1174a5bec7f4574004840187d6fdb1602285 21:29:00 [21:29:00.177] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2baf4763aa17682af85263faf72b939a3b0de80fabfa567cc2614de37cb716e2 {"gasLimit":362859,"maxFeePerGas":"66.371021876","maxPriorityFeePerGas":"66.247372648"} 21:29:00 [21:29:00.200] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xa623e13db3bc03c6828b186ab37039f59d8527e19526127bc6b78030c6e5fea9 {"gasLimit":84823,"maxFeePerGas":"79.605134257","maxPriorityFeePerGas":"79.496847177"} 21:29:00 [21:29:00.258] 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"} 21:29:00 [21:29:00.259] INFO: e2e:e2e_multi_validator_node Waiting for 12 transactions to be mined... 21:29:00 [21:29:00.289] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:29:00 [21:29:00.298] VERBOSE: e2e:e2e_multi_validator_node Rollup deployed 21:29:00 [21:29:00.299] VERBOSE: e2e:e2e_multi_validator_node Waiting for rollup and slash factory to be deployed 21:29:00 [21:29:00.299] INFO: e2e:e2e_multi_validator_node Waiting for 12 transactions to be mined... 21:29:00 [21:29:00.324] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:29:00 [21:29:00.324] VERBOSE: e2e:e2e_multi_validator_node All transactions for L1 deployment have been mined 21:29:00 [21:29:00.345] 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"} 21:29:00 [21:29:00.346] INFO: e2e:e2e_multi_validator_node Handing over to governance 21:29:00 [21:29:00.354] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 21:29:00 [21:29:00.358] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:00 [21:29:00.359] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x9A9f2CCfdE556A7E9Ff0848998Aa4a0CFD8863AE 21:29:00 [21:29:00.360] INFO: telemetry:client Using NoopTelemetryClient 21:29:00 [21:29:00.367] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/f3c035ca14017ae4/blob-sink with map size 134217728 KB (LMDB v2) 21:29:00 [21:29:00.368] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 21:29:00 [21:29:00.368] WARN: foundation:test-date-provider Time set to 2025-06-03T21:33:58.000Z {"offset":297632,"timeMs":1748986438000} 21:29:00 [21:29:00.375] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 21:29:00 [21:29:00.383] INFO: blob-sink:server Server is running on http://localhost:34091 21:29:00 [21:29:00.384] VERBOSE: e2e:e2e_multi_validator_node Creating and synching an aztec node... 21:29:00 [21:29:00.386] VERBOSE: e2e:e2e_multi_validator_node Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/c4cbc664/acvm 21:29:00 [21:29:00.388] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:34091"} 21:29:00 [21:29:00.416] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 21:29:00 [21:29:00.417] INFO: archiver:lmdb Creating archiver data store at directory /tmp/f3c035ca14017ae4/archiver with map size 134217728 KB (LMDB v2) 21:29:00 [21:29:00.419] INFO: archiver:lmdb Starting data store with maxReaders 16 21:29:00 [21:29:00.612] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:00 [21:29:00.751] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:34091"} 21:29:00 [21:29:00.769] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:34091"} 21:29:00 [21:29:00.769] WARN: node:blob-sink:client No L1 consensus host urls configured 21:29:00 [21:29:00.770] WARN: node:blob-sink:client No archive client configured 21:29:00 [21:29:00.772] INFO: archiver Starting archiver sync to rollup contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae from L1 block 18 to current L1 block 25 with hash 0x1fd0751a00b9b966b77105bf4409c747c54abcf70694e11b3c8b966cf0bbd3b6 {"blocksSynchedTo":18,"messagesSynchedTo":{"l1BlockHash":"0x090698e1ecac920842bbe7fd314065574c67b30f21adedaf77e0747ce8e49336","l1BlockNumber":18}} 21:29:00 [21:29:00.790] INFO: archiver Initial archiver sync to L1 block 25 complete. {"l1BlockNumber":25,"syncPoint":{"blocksSynchedTo":25,"messagesSynchedTo":{"l1BlockHash":"0x1fd0751a00b9b966b77105bf4409c747c54abcf70694e11b3c8b966cf0bbd3b6","l1BlockNumber":25}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 21:29:00 [21:29:00.799] INFO: world-state:database Creating world state data store at directory /tmp/f3c035ca14017ae4/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 21:29:00 [21:29:00.824] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:00 [21:29:00.831] INFO: world_state Created world state synchroniser with block history of 64 21:29:00 [21:29:00.831] WARN: node Aztec node is accepting fake proofs 21:29:00 [21:29:00.849] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/f3c035ca14017ae4/p2p with map size 134217728 KB (LMDB v2) 21:29:00 [21:29:00.854] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 21:29:00 [21:29:00.855] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/f3c035ca14017ae4/p2p-archive with map size 134217728 KB (LMDB v2) 21:29:00 [21:29:00.857] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 21:29:00 [21:29:00.858] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/f3c035ca14017ae4/p2p-peers with map size 134217728 KB (LMDB v2) 21:29:00 [21:29:00.859] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 21:29:00 [21:29:00.860] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 21:29:00 [21:29:00.860] VERBOSE: p2p P2P is disabled. Using dummy P2P service 21:29:00 [21:29:00.863] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":200} 21:29:00 [21:29:00.863] INFO: world_state Started world state synchronizer from block 1 21:29:00 [21:29:00.865] VERBOSE: world-state:block_stream Starting sync from block number 0 21:29:00 [21:29:00.867] INFO: p2p Starting p2p client from block 0 with empty mempools 21:29:00 [21:29:00.867] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":200,"startingBlock":0} 21:29:00 [21:29:00.868] INFO: epoch-prune-watcher EpochPruneWatcher initialized 21:29:00 [21:29:00.872] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 21:29:00 [21:29:00.874] INFO: slasher Starting Slasher client... 21:29:00 [21:29:00.927] VERBOSE: validator Initialized validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 21:29:00 [21:29:00.928] VERBOSE: node All Aztec Node subsystems synced 21:29:00 [21:29:00.941] INFO: sequencer-client Deploying forwarder contract 21:29:00 [21:29:00.958] VERBOSE: sequencer-client Sent L1 transaction 0x5f35c2d0e4f65c467262e74571eec910db06feaae6991ac544d9a02dbb6f34dd {"gasLimit":323704,"maxFeePerGas":"95.479418643","maxPriorityFeePerGas":"95.396216612"} 21:29:00 [21:29:00.959] VERBOSE: sequencer-client Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000042 to address 0x83ac846879C81f414bDf47961839F4E842ead017 in tx 0x5f35c2d0e4f65c467262e74571eec910db06feaae6991ac544d9a02dbb6f34dd. 21:29:00 [21:29:00.964] INFO: sequencer-client Forwarder contract deployed at 0x83ac846879c81f414bdf47961839f4e842ead017 21:29:00 [21:29:00.972] INFO: validator Started validator with addresses: 21:29:00 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 21:29:00 [21:29:00.973] VERBOSE: validator Validators 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 are not on the validator committee for epoch 0 21:29:00 [21:29:00.974] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":200,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/c4cbc664/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 21:29:01 [21:29:01.264] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 21:29:01 [21:29:01.267] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 21:29:01 [21:29:01.268] INFO: node Aztec Node version: 0.87.6 21:29:01 [21:29:01.268] 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"} 21:29:01 [21:29:01.269] VERBOSE: e2e:e2e_multi_validator_node Creating and syncing a simulated prover node... 21:29:01 [21:29:01.306] INFO: blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:34091"} 21:29:01 [21:29:01.308] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:01 [21:29:01.315] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 21:29:01 [21:29:01.315] WARN: foundation:test-date-provider Time set to 2025-06-03T21:34:10.000Z {"offset":308685,"timeMs":1748986450000} 21:29:01 [21:29:01.320] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/f3c035ca14017ae4/e0ea2917ed104194/archiver with map size 134217728 KB (LMDB v2) 21:29:01 [21:29:01.331] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:01 [21:29:01.336] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 21:29:01 [21:29:01.531] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:01 [21:29:01.569] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:01 [21:29:01.684] INFO: blob-sink:client:prover-node Testing configured blob sources {"blobSinkUrl":"http://localhost:34091"} 21:29:01 [21:29:01.689] INFO: blob-sink:client:prover-node Blob sink is reachable {"blobSinkUrl":"http://localhost:34091"} 21:29:01 [21:29:01.689] WARN: blob-sink:client:prover-node No L1 consensus host urls configured 21:29:01 [21:29:01.689] WARN: blob-sink:client:prover-node No archive client configured 21:29:01 [21:29:01.691] INFO: archiver:prover-node Starting archiver sync to rollup contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae from L1 block 18 to current L1 block 26 with hash 0x72486efbf7d8fa9d71371c197a9687bc1035d8004bd9f5f628791c46318a436a {"blocksSynchedTo":18,"messagesSynchedTo":{"l1BlockHash":"0x090698e1ecac920842bbe7fd314065574c67b30f21adedaf77e0747ce8e49336","l1BlockNumber":18}} 21:29:01 [21:29:01.704] INFO: archiver:prover-node Initial archiver sync to L1 block 26 complete. {"l1BlockNumber":26,"syncPoint":{"blocksSynchedTo":26,"messagesSynchedTo":{"l1BlockHash":"0x72486efbf7d8fa9d71371c197a9687bc1035d8004bd9f5f628791c46318a436a","l1BlockNumber":26}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 21:29:01 [21:29:01.709] INFO: prover-node:blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:34091"} 21:29:01 [21:29:01.709] VERBOSE: prover-node:prover-node Snapshot sync is disabled. No snapshots URL provided. 21:29:01 [21:29:01.711] VERBOSE: prover-node:prover-node Created archiver and synced to block 0 21:29:01 [21:29:01.719] INFO: world-state:database:prover-node Creating world state data store at directory /tmp/f3c035ca14017ae4/e0ea2917ed104194/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 21:29:01 [21:29:01.742] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:01 [21:29:01.747] INFO: world_state:prover-node Created world state synchroniser with block history of 64 21:29:01 [21:29:01.749] VERBOSE: world-state:block_stream:prover-node Starting L2 block stream {"proven":false,"pollIntervalMS":200} 21:29:01 [21:29:01.749] INFO: world_state:prover-node Started world state synchronizer from block 1 21:29:01 [21:29:01.751] VERBOSE: world-state:block_stream:prover-node Starting sync from block number 0 21:29:01 [21:29:01.751] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/f3c035ca14017ae4/e0ea2917ed104194/archiver when loading epoch databases, ignoring 21:29:01 [21:29:01.751] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/f3c035ca14017ae4/e0ea2917ed104194/world_state when loading epoch databases, ignoring 21:29:01 [21:29:01.753] INFO: prover-client:proving-broker:prover-node Proving Broker started 21:29:01 [21:29:01.765] INFO: prover-node:prover-coordination:prover-node Using prover coordination via aztec node 21:29:01 [21:29:01.767] INFO: e2e:e2e_multi_validator_node:prover-node Created and synced prover node {"publisherAddress":"0x3C44CdDdB6a900fa2b585dd299e03d12FA4293BC"} 21:29:01 [21:29:01.768] INFO: prover-node:epoch-monitor:prover-node Started EpochMonitor {"pollingIntervalMs":200} 21:29:01 [21:29:01.773] 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/f3c035ca14017ae4/e0ea2917ed104194","l1ChainId":31337,"rollupVersion":2574150199} 21:29:01 [21:29:01.774] VERBOSE: e2e:e2e_multi_validator_node Creating a pxe... 21:29:01 [21:29:01.784] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/c6d6447be770d2ef/pxe_data with map size 134217728 KB (LMDB v2) 21:29:01 [21:29:01.788] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:01 [21:29:01.792] INFO: pxe:data:lmdb Starting data store with maxReaders 16 21:29:01 [21:29:01.990] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:02 [21:29:02.101] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:02 [21:29:02.104] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 21:29:02 [21:29:02.106] INFO: pxe:service Started PXE connected to chain 31337 version 2574150199 21:29:02 [21:29:02.168] INFO: pxe:service Registered account 0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c 21:29:02 [21:29:02.248] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:02 [21:29:02.251] WARN: pxe:service No artifact found for contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a when looking for its metadata 21:29:02 [21:29:02.257] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a as part of deployment for 0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c 21:29:02 [21:29:02.414] INFO: pxe:service Added contract SchnorrAccount at 0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c with class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 21:29:02 [21:29:02.423] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:02 [21:29:02.469] VERBOSE: pxe:block_stream Starting sync from block number 0 21:29:02 [21:29:02.498] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:02 [21:29:02.510] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 21:29:02 [21:29:02.665] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 21:29:03 [21:29:03.365] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a,0x2f6652d5f6110255f6132bc8be0dd74c664523f8808ebbc3eb467252a0b5e74a,0x1d9edc9e571a9e7ab4d2aa454dd024ee819579e3e22ae0fadf28089b29f3f0bc,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 21:29:03 [21:29:03.524] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 21:29:03 [21:29:03.622] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:03 [21:29:03.646] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:03 [21:29:03.757] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c"} 21:29:03 [21:29:03.815] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 21:29:03 [21:29:03.817] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c"} 21:29:03 [21:29:03.855] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:03 [21:29:03.879] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:03 [21:29:03.905] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c"} 21:29:03 [21:29:03.910] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 21:29:04 [21:29:04.082] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:04 [21:29:04.126] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:04 [21:29:04.205] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c"} 21:29:04 [21:29:04.253] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 21:29:04 [21:29:04.253] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c"} 21:29:04 [21:29:04.296] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c"} 21:29:04 [21:29:04.301] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 21:29:04 [21:29:04.325] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c as fee payer 21:29:04 [21:29:04.355] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:04 [21:29:04.419] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:04 [21:29:04.614] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:04 [21:29:04.830] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:05 [21:29:05.010] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:05 [21:29:05.159] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:05 [21:29:05.323] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:05 [21:29:05.426] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:05 [21:29:05.902] INFO: pxe:service Proving completed in 3443.1409109999986ms {"timings":{"total":3443.1409109999986,"sync":10.599279000000024,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":179.795734000003,"oracles":{"callPrivateFunction":{"times":[1030.10966,497.710919000001,237.81816000000072]}}},{"functionName":"private_kernel_init","time":128.2352090000004},{"functionName":"ContractClassRegisterer:register","time":1000.2999479999999,"oracles":{"loadCapsule":{"times":[12.819306999999753]},"notifyCreatedNullifier":{"times":[15.34924299999875]},"debugLog":{"times":[1.125930000000153]},"notifyCreatedContractClassLog":{"times":[31.193590000000768]}}},{"functionName":"private_kernel_inner","time":163.7005019999997},{"functionName":"SchnorrAccount:constructor","time":473.0422210000015,"oracles":{"getContractInstance":{"times":[1.180041000001438]},"debugLog":{"times":[0.8200520000009419,0.5180830000008427]},"fetchTaggedLogs":{"times":[92.31137500000114]},"loadCapsule":{"times":[0.4526719999994384,0.3862599999993108,0.2103449999995064]},"validateEnqueuedNotes":{"times":[6.315975999999864]},"notifyCreatedNullifier":{"times":[0.2904580000013084,0.23520600000119884]},"notifyCreatedNote":{"times":[0.47512300000016694]},"getRandomField":{"times":[0.39298000000053435,0.3374890000013693,0.1514839999999822,0.06737199999952281,0.05486100000052829,0.07999199999903794,0.06204099999922619,0.06369099999938044,0.0589510000008886,0.07665200000155892,0.04213100000015402]},"getIndexedTaggingSecretAsSender":{"times":[47.27066199999899]},"incrementAppTaggingSecretIndexAsSender":{"times":[106.09452799999963]}}},{"functionName":"private_kernel_inner","time":155.8370159999995},{"functionName":"SchnorrAccount:entrypoint","time":211.54796799999895,"oracles":{"debugLog":{"times":[0.604326000000583,0.4881729999997333,0.8287619999991875]},"fetchTaggedLogs":{"times":[45.995678999999654]},"loadCapsule":{"times":[0.2867869999990944,0.3270090000005439,0.3062289999998029]},"validateEnqueuedNotes":{"times":[2.8433850000001257]},"getNotes":{"times":[2.415364000000409]},"getAuthWitness":{"times":[0.4526719999994384]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.086079000000609]}}},{"functionName":"private_kernel_inner","time":135.4751699999997},{"functionName":"private_kernel_reset","time":243.55187999999907},{"functionName":"private_kernel_tail","time":87.84418899999946}],"unaccounted":653.2117949999974}} 21:29:05 [21:29:05.921] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:06 [21:29:06.000] VERBOSE: sequencer Not enough txs to build block 1 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","blockNumber":1,"slot":3} 21:29:06 [21:29:06.011] VERBOSE: p2p:tx_pool Adding tx 0x0ecf3f6130ebe27d763c191dfd522de99f68cfc4c5e30d9ec280576448c20171 to pool {"eventName":"tx-added-to-pool","txHash":"0x0ecf3f6130ebe27d763c191dfd522de99f68cfc4c5e30d9ec280576448c20171","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 21:29:06 [21:29:06.031] INFO: node Received tx 0x0ecf3f6130ebe27d763c191dfd522de99f68cfc4c5e30d9ec280576448c20171 {"txHash":"0x0ecf3f6130ebe27d763c191dfd522de99f68cfc4c5e30d9ec280576448c20171"} 21:29:06 [21:29:06.031] INFO: pxe:service Sent transaction 0x0ecf3f6130ebe27d763c191dfd522de99f68cfc4c5e30d9ec280576448c20171 21:29:06 [21:29:06.131] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:06 [21:29:06.232] VERBOSE: sequencer:block-builder Building block 1 for slot 3 {"slot":3,"slotStart":"2025-06-03T21:34:22.000Z","now":"2025-06-03T21:34:14.917Z","blockNumber":1,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x1e3aef33989764dcfb6810f6472a042297977115273a0fdb35027f7ea637b996"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 21:29:06 [21:29:06.294] VERBOSE: simulator:public-processor Processed tx 0x0ecf3f6130ebe27d763c191dfd522de99f68cfc4c5e30d9ec280576448c20171 with no public calls in 31.833356999999523ms {"txHash":"0x0ecf3f6130ebe27d763c191dfd522de99f68cfc4c5e30d9ec280576448c20171","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.833356999999523} 21:29:06 [21:29:06.297] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.06089535099999921s {"duration":0.06089535099999921,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 21:29:06 [21:29:06.386] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:06 [21:29:06.404] INFO: sequencer Built block 1 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x10058401d6de2219591cfeced9c8b82a041508c0ce7cfbacf11c67a4eacc9a0d","globalVariables":{"chainId":31337,"version":2574150199,"blockNumber":1,"slotNumber":3,"timestamp":1748986462,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x0ecf3f6130ebe27d763c191dfd522de99f68cfc4c5e30d9ec280576448c20171"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":180.23858699999982,"publicProcessDuration":62.041300999999294,"rollupCircuitsDuration":172.32599800000025,"txCount":1,"blockNumber":1,"blockTimestamp":1748986462,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 21:29:06 [21:29:06.407] VERBOSE: sequencer Attesting committee is empty 21:29:06 [21:29:06.595] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:06 [21:29:06.770] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xcc6631c4d849ca5d89567e68b76350f2002283aa7f405f09e46168dffa08976f 21:29:06 [21:29:06.770] VERBOSE: node Sent L1 transaction 0xcc6631c4d849ca5d89567e68b76350f2002283aa7f405f09e46168dffa08976f {"gasLimit":555104,"maxFeePerGas":"114.549196547","maxPriorityFeePerGas":"114.475459934","maxFeePerBlobGas":"0.000000001"} 21:29:06 [21:29:06.784] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 21:29:06 [21:29:06.785] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xcc6631c4d849ca5d89567e68b76350f2002283aa7f405f09e46168dffa08976f","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":1,"blockTimestamp":1748986462,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 21:29:06 [21:29:06.786] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 21:29:06 [21:29:06.804] INFO: blob-sink:server Received blob sidecar for block 0x58a97d135342f420e4b6090644ed0546f9506add3a9fbc267646c528b2fb0e53 21:29:06 [21:29:06.809] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:06 [21:29:06.820] INFO: blob-sink:server Blob sidecar stored successfully for block 0x58a97d135342f420e4b6090644ed0546f9506add3a9fbc267646c528b2fb0e53 21:29:06 [21:29:06.824] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 21:29:06 [21:29:06.824] WARN: foundation:test-date-provider Time set to 2025-06-03T21:34:22.000Z {"offset":315176,"timeMs":1748986462000} 21:29:06 [21:29:06.825] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 21:29:06 [21:29:06.828] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 21:29:06 [21:29:06.831] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 21:29:06 [21:29:06.973] VERBOSE: archiver:block-helper:prover-node Store contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 21:29:06 [21:29:06.984] INFO: archiver:prover-node Downloaded L2 block 1 {"blockHash":"0x10058401d6de2219591cfeced9c8b82a041508c0ce7cfbacf11c67a4eacc9a0d","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":2574150199,"blockNumber":1,"slotNumber":3,"timestamp":1748986462,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","archiveNextLeafIndex":2} 21:29:07 [21:29:07.081] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:07 [21:29:07.108] VERBOSE: archiver:block-helper Store contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 21:29:07 [21:29:07.124] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1748986498] on http://127.0.0.1:8545 21:29:07 [21:29:07.126] INFO: archiver:prover-node Updated proven chain to block 1 {"provenBlockNumber":1} 21:29:07 [21:29:07.130] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x10058401d6de2219591cfeced9c8b82a041508c0ce7cfbacf11c67a4eacc9a0d","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":2574150199,"blockNumber":1,"slotNumber":3,"timestamp":1748986462,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","archiveNextLeafIndex":2} 21:29:07 [21:29:07.133] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 21:29:07 [21:29:07.140] WARN: ethereum:cheat_codes Warped L1 timestamp to 1748986498 21:29:07 [21:29:07.140] WARN: foundation:test-date-provider Time set to 2025-06-03T21:34:58.000Z {"offset":350860,"timeMs":1748986498000} 21:29:07 [21:29:07.140] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 21:29:07 [21:29:07.141] INFO: world_state:prover-node World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":13.866453999999067,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1748986462,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 21:29:07 [21:29:07.147] VERBOSE: sequencer Unable to build/enqueue block Rollup__InvalidArchive(0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03, 0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b) 21:29:07 [21:29:07.149] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 21:29:07 [21:29:07.219] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1748988802] on http://127.0.0.1:8545 21:29:07 [21:29:07.221] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 21:29:07 [21:29:07.223] WARN: ethereum:cheat_codes Warped L1 timestamp to 1748988802 21:29:07 [21:29:07.224] INFO: e2e:e2e_multi_validator_node Deploying contract from 0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c 21:29:07 [21:29:07.572] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:07 [21:29:07.589] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 21:29:07 [21:29:07.589] WARN: foundation:test-date-provider Time set to 2025-06-03T22:13:22.000Z {"offset":2654411,"timeMs":1748988802000} 21:29:07 [21:29:07.615] INFO: pxe:service Added contract StatefulTest at 0x123d5eb14c5de6766cb64391155bb2196844901c591c5eceb20ccdf05d55b64a with class 0x0e75e50f67127bddbbf0c6e812b093c4e20dc26681235d9c77c3d57f93ab52a0 21:29:07 [21:29:07.735] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":96.01072299999942,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1748986462,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 21:29:07 [21:29:07.827] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:07 [21:29:07.861] VERBOSE: p2p Synched to latest block 1 21:29:07 [21:29:07.889] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","header":{"contentCommitment":{"blobsHash":"0x00dca7c0ae79da56afa915b3c8a390c7a1614db9a8b60fe8dd38a27d8feb8149","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1748986462,"version":2574150199},"lastArchive":"0x242ccde7ef8aaa3d5d2cbd277268a569fd382c2f8c398d548b483f2645657f8b","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x02c05a6acda9e7502244d57be240885d925f90b264a875d0e7abeaca01f7a702","nullifierTree":"0x2d7196aafb70c81bc8f733b5f3cf036503cac1ff96c5100b48f222d622603f15","publicDataTree":"0x2126ce7fe62a30c7a7f45d581dbd9cc9b3b3058e5dda88b5aea6ee4a6bb44518"},"totalFees":2101212240,"totalManaUsed":6324}} 21:29:08 [21:29:08.040] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c"} 21:29:08 [21:29:08.100] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 21:29:08 [21:29:08.101] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c"} 21:29:08 [21:29:08.103] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:08 [21:29:08.116] INFO: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 is on the validator committee for epoch 2 21:29:08 [21:29:08.117] INFO: validator Validator 0x70997970c51812dc3a010c7d01b50e0d17dc79c8 is on the validator committee for epoch 2 21:29:08 [21:29:08.117] INFO: validator Validator 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc is on the validator committee for epoch 2 21:29:08 [21:29:08.117] INFO: validator Validator 0x90f79bf6eb2c4f870365e785982e1f101e93b906 is on the validator committee for epoch 2 21:29:08 [21:29:08.117] INFO: validator Validator 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 is on the validator committee for epoch 2 21:29:08 [21:29:08.134] VERBOSE: prover-node:prover-node Fetching 1 tx hashes for block number 1 from coordination 21:29:08 [21:29:08.135] INFO: prover-node:combined-prover-coordination:prover-node Check for 1 txs found 1 missing. Will gather from nodes and p2p 21:29:08 [21:29:08.155] VERBOSE: prover-node:combined-prover-coordination:prover-node Gathered 1 of 1 txs from a node 21:29:08 [21:29:08.156] INFO: prover-node:combined-prover-coordination:prover-node Found all 1 txs directly from nodes 21:29:08 [21:29:08.221] VERBOSE: sequencer Not enough txs to build block 2 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","blockNumber":2,"slot":68} 21:29:08 [21:29:08.269] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c"} 21:29:08 [21:29:08.274] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x27e02fbd9063a9bc1d74abe4971b8fe74124559117246f0ec3ff3eeb82fbf6ed 21:29:08 [21:29:08.291] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 21:29:08 [21:29:08.293] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 21:29:08 [21:29:08.307] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 21:29:08 [21:29:08.308] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 21:29:08 [21:29:08.318] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 21:29:08 [21:29:08.320] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:08 [21:29:08.332] VERBOSE: pxe:service Added note {"index":0,"contract":"0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x207e08ac4fec13c817939f9595ffb156b24b9e22756cc8e205a9bad79ce38d27","nullifier":"0x0ffd4a83a19e89878d8256bb0059136acc7a815e7938ea41bd0a4c2bc4f5da0b"} 21:29:08 [21:29:08.349] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0d6019c2f0663f2208d3269245220b8a9472bb72dcf6747cf2219e75409c570c as fee payer 21:29:08 [21:29:08.570] VERBOSE: simulator:private_execution Executing private function StatefulTest:constructor {"contract":"0x123d5eb14c5de6766cb64391155bb2196844901c591c5eceb20ccdf05d55b64a"} 21:29:08 [21:29:08.609] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:08 [21:29:08.628] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 21:29:08 [21:29:08.630] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x123d5eb14c5de6766cb64391155bb2196844901c591c5eceb20ccdf05d55b64a"} 21:29:08 [21:29:08.646] VERBOSE: sequencer Not enough txs to build block 2 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","blockNumber":2,"slot":68} 21:29:08 [21:29:08.759] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x123d5eb14c5de6766cb64391155bb2196844901c591c5eceb20ccdf05d55b64a"} 21:29:08 [21:29:08.764] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 21:29:08 [21:29:08.875] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:08 [21:29:08.968] VERBOSE: simulator:private_execution Executing private function StatefulTest:create_note_no_init_check {"contract":"0x123d5eb14c5de6766cb64391155bb2196844901c591c5eceb20ccdf05d55b64a"} 21:29:09 [21:29:09.029] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 21:29:09 [21:29:09.029] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x123d5eb14c5de6766cb64391155bb2196844901c591c5eceb20ccdf05d55b64a"} 21:29:09 [21:29:09.128] VERBOSE: sequencer Not enough txs to build block 2 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","blockNumber":2,"slot":68} 21:29:09 [21:29:09.184] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x123d5eb14c5de6766cb64391155bb2196844901c591c5eceb20ccdf05d55b64a"} 21:29:09 [21:29:09.192] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 21:29:09 [21:29:09.313] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:09 [21:29:09.431] VERBOSE: sequencer Not enough txs to build block 2 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","blockNumber":2,"slot":68} 21:29:09 [21:29:09.596] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:09 [21:29:09.789] VERBOSE: sequencer Not enough txs to build block 2 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","blockNumber":2,"slot":68} 21:29:09 [21:29:09.869] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:10 [21:29:10.181] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:10 [21:29:10.221] VERBOSE: sequencer Not enough txs to build block 2 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","blockNumber":2,"slot":68} 21:29:10 [21:29:10.431] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:10 [21:29:10.714] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:10 [21:29:10.743] VERBOSE: sequencer Not enough txs to build block 2 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","blockNumber":2,"slot":68} 21:29:11 [21:29:11.266] INFO: pxe:service Proving completed in 3519.3744069999993ms {"timings":{"total":3519.3744069999993,"sync":153.18524500000058,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":488.2408030000006,"oracles":{"debugLog":{"times":[0.9985359999991488,0.40740999999979977,0.9383649999981571,0.9518050000006042,1.0347769999971206,0.7426390000000538,0.6622579999966547,0.6892279999992752]},"fetchTaggedLogs":{"times":[170.16281099999833]},"loadCapsule":{"times":[0.608486000000994,0.37203000000226893,0.5049230000004172,1.3755459999993036,0.4369409999999334,0.7525090000017371,3.6591459999981453]},"getSharedSecret":{"times":[9.155300999998872]},"aes128Decrypt":{"times":[1.0154970000003232,1.1854510000011942]},"getPublicKeysAndPartialAddress":{"times":[3.495812000001024]},"getKeyValidationRequest":{"times":[5.274308000000019]},"storeCapsule":{"times":[1.2419730000001437,2.294689999998809,0.3932710000008228]},"deleteCapsule":{"times":[0.6948879999981727]},"validateEnqueuedNotes":{"times":[11.080461000001378]},"getNotes":{"times":[1.7707060000029742]},"getAuthWitness":{"times":[0.2696670000004815]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.13252400000055786]},"callPrivateFunction":{"times":[1099.2266560000025]}}},{"functionName":"private_kernel_init","time":116.45360200000141},{"functionName":"StatefulTest:constructor","time":447.2334130000054,"oracles":{"getContractInstance":{"times":[16.3719600000004]},"debugLog":{"times":[0.7383090000002994,0.4359020000010787]},"fetchTaggedLogs":{"times":[131.96036800000002]},"loadCapsule":{"times":[0.2451870000004419,0.27783800000179326,0.29024800000115647]},"validateEnqueuedNotes":{"times":[1.0062059999982012]},"storeInExecutionCache":{"times":[0.19919599999775528]},"callPrivateFunction":{"times":[679.8413049999981]},"notifyCreatedNullifier":{"times":[0.23164599999654456]}}},{"functionName":"private_kernel_inner","time":194.07399599999917},{"functionName":"StatefulTest:create_note_no_init_check","time":582.8557959999962,"oracles":{"debugLog":{"times":[0.7703500000025088,0.5309339999985241]},"fetchTaggedLogs":{"times":[159.0217479999992]},"loadCapsule":{"times":[0.8533820000011474,1.3617960000010498,0.35840900000039255]},"validateEnqueuedNotes":{"times":[1.684065000001283]},"getRandomField":{"times":[0.6187170000011974,0.6072059999969497,0.2910870000014256,0.0860130000000936,0.061370999999780906,0.08138200000030338,0.07332200000018929,0.07545199999731267,0.07592199999999139,0.07514200000150595,0.08430199999929755,0.11412299999938114]},"notifyCreatedNote":{"times":[0.603266000001895]},"getIndexedTaggingSecretAsSender":{"times":[126.1725379999989]},"incrementAppTaggingSecretIndexAsSender":{"times":[89.2964830000019]}}},{"functionName":"private_kernel_inner","time":126.33618299999944},{"functionName":"private_kernel_reset","time":255.7412570000015},{"functionName":"private_kernel_tail","time":115.55411800000002}],"unaccounted":1039.699993999995}} 21:29:11 [21:29:11.284] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:11 [21:29:11.347] VERBOSE: sequencer Not enough txs to build block 2 at slot 68 (got 0 txs, need 1) {"chainTipArchive":"0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03","blockNumber":2,"slot":68} 21:29:11 [21:29:11.348] VERBOSE: p2p:tx_pool Adding tx 0x2f20558905263b97b5430f644b70e86d3b0d11a611fd5591440b50b059cf076f to pool {"eventName":"tx-added-to-pool","txHash":"0x2f20558905263b97b5430f644b70e86d3b0d11a611fd5591440b50b059cf076f","noteHashCount":1,"nullifierCount":2,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 21:29:11 [21:29:11.357] INFO: node Received tx 0x2f20558905263b97b5430f644b70e86d3b0d11a611fd5591440b50b059cf076f {"txHash":"0x2f20558905263b97b5430f644b70e86d3b0d11a611fd5591440b50b059cf076f"} 21:29:11 [21:29:11.357] INFO: pxe:service Sent transaction 0x2f20558905263b97b5430f644b70e86d3b0d11a611fd5591440b50b059cf076f 21:29:11 [21:29:11.504] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:11 [21:29:11.652] VERBOSE: sequencer:block-builder Building block 2 for slot 68 {"slot":68,"slotStart":"2025-06-03T22:13:22.000Z","now":"2025-06-03T22:13:26.063Z","blockNumber":2,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x1e3aef33989764dcfb6810f6472a042297977115273a0fdb35027f7ea637b996"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 21:29:11 [21:29:11.700] VERBOSE: simulator:public-processor Processed tx 0x2f20558905263b97b5430f644b70e86d3b0d11a611fd5591440b50b059cf076f with no public calls in 27.072046999997838ms {"txHash":"0x2f20558905263b97b5430f644b70e86d3b0d11a611fd5591440b50b059cf076f","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":27.072046999997838} 21:29:11 [21:29:11.702] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.046744096999998874s {"duration":0.046744096999998874,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 21:29:11 [21:29:11.812] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:11 [21:29:11.833] INFO: sequencer Built block 2 for slot 68 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1ac5bd1bbd916357cd7a5a37654a93078fa6271b69ee7c28ef7886de14b51fba","globalVariables":{"chainId":31337,"version":2574150199,"blockNumber":2,"slotNumber":68,"timestamp":1748988802,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"txHashes":["0x2f20558905263b97b5430f644b70e86d3b0d11a611fd5591440b50b059cf076f"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":189.0423120000014,"publicProcessDuration":47.543728000000556,"rollupCircuitsDuration":180.94078799999988,"txCount":1,"blockNumber":2,"blockTimestamp":1748988802,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 21:29:11 [21:29:11.847] VERBOSE: p2p Broadcasting proposal for slot 68 to peers 21:29:11 [21:29:11.868] VERBOSE: p2p:attestation_pool Added attestation for slot 68 from 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 {"signature":"0x04055158ee46b6f7711230cc297466d916e578c9e4b479c9f7caae218f617ecc484ee2066c3abe2b84b7e6f9ca7dd274abdc7e9bda0d0b42ad98878ad67dc08a1b","slotNumber":"0x0000000000000000000000000000000000000000000000000000000000000044","address":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","proposalId":"0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be"} 21:29:11 [21:29:11.872] VERBOSE: p2p:attestation_pool Added attestation for slot 68 from 0x70997970c51812dc3a010c7d01b50e0d17dc79c8 {"signature":"0x6c7590d25d82674f38e737920108951c5363b1d1db7efbf1ad9c30b3c0d8f3ba401cf56252915daed50fff364697d179faa00232828cfa42ec494a4cbb18e68d1b","slotNumber":"0x0000000000000000000000000000000000000000000000000000000000000044","address":"0x70997970c51812dc3a010c7d01b50e0d17dc79c8","proposalId":"0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be"} 21:29:11 [21:29:11.878] VERBOSE: p2p:attestation_pool Added attestation for slot 68 from 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc {"signature":"0x7a3cfae8cf1ecf5d0c716d1ef353a8f36da259c872c9afaab46ef77952d8d7be44ec3a65d9c0837b05defd6512974eee9a4617fb071455d7d77532c05cfbfb841b","slotNumber":"0x0000000000000000000000000000000000000000000000000000000000000044","address":"0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc","proposalId":"0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be"} 21:29:11 [21:29:11.885] VERBOSE: p2p:attestation_pool Added attestation for slot 68 from 0x90f79bf6eb2c4f870365e785982e1f101e93b906 {"signature":"0x0b56c8613775b510074d9e32e90241e510c3d06f19f4d38996b00fcf468a44336b49f07ca8743bad017c36558c0786bfe603eebd6f97937dae350a6c6b9c46fb1b","slotNumber":"0x0000000000000000000000000000000000000000000000000000000000000044","address":"0x90f79bf6eb2c4f870365e785982e1f101e93b906","proposalId":"0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be"} 21:29:11 [21:29:11.892] VERBOSE: p2p:attestation_pool Added attestation for slot 68 from 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 {"signature":"0x6e7aee9d99feb1a6383f7f44186d5cffde80939f7c3b49fef270929b358e6ccd1b0d272104b64415967eb867f8585374ac076508883a84a3af6034fe61fcc6ae1c","slotNumber":"0x0000000000000000000000000000000000000000000000000000000000000044","address":"0x15d34aaf54267db7d7c367839aaf71a00a2c6a65","proposalId":"0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be"} 21:29:11 [21:29:11.893] VERBOSE: validator Collected all 4 attestations for slot 68 21:29:11 [21:29:11.894] VERBOSE: sequencer Collected 5 attestations {"blockHash":"0x1ac5bd1bbd916357cd7a5a37654a93078fa6271b69ee7c28ef7886de14b51fba","blockNumber":2} 21:29:12 [21:29:12.033] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:12 [21:29:12.324] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:12 [21:29:12.335] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x1d227b5dac55269bfce3b8f7e60ca1e6e523e3e2ee946f145f2c98f0d261a19a 21:29:12 [21:29:12.335] VERBOSE: node Sent L1 transaction 0x1d227b5dac55269bfce3b8f7e60ca1e6e523e3e2ee946f145f2c98f0d261a19a {"gasLimit":660445,"maxFeePerGas":"1.24971642","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 21:29:12 [21:29:12.366] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1748988838] on http://127.0.0.1:8545 21:29:12 [21:29:12.371] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 21:29:12 [21:29:12.379] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 21:29:12 [21:29:12.380] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x1d227b5dac55269bfce3b8f7e60ca1e6e523e3e2ee946f145f2c98f0d261a19a","calldataSize":2084,"calldataGas":19940,"txCount":1,"blockNumber":2,"blockTimestamp":1748988802,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 21:29:12 [21:29:12.380] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 21:29:12 [21:29:12.392] WARN: ethereum:cheat_codes Warped L1 timestamp to 1748988838 21:29:12 [21:29:12.393] WARN: foundation:test-date-provider Time set to 2025-06-03T22:13:58.000Z {"offset":2685607,"timeMs":1748988838000} 21:29:12 [21:29:12.393] INFO: aztecjs:utils:watcher Slot 68 was filled, jumped to next slot 21:29:12 [21:29:12.404] INFO: blob-sink:server Received blob sidecar for block 0xbeca87f119d76eaca6c03f3647dfd50cc2fd429f579025388782a68e1ff6b2f3 21:29:12 [21:29:12.424] INFO: blob-sink:server Blob sidecar stored successfully for block 0xbeca87f119d76eaca6c03f3647dfd50cc2fd429f579025388782a68e1ff6b2f3 21:29:12 [21:29:12.580] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:12 [21:29:12.600] INFO: archiver:prover-node Downloaded L2 block 2 {"blockHash":"0x1ac5bd1bbd916357cd7a5a37654a93078fa6271b69ee7c28ef7886de14b51fba","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":2574150199,"blockNumber":2,"slotNumber":68,"timestamp":1748988802,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"archiveRoot":"0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be","archiveNextLeafIndex":3} 21:29:12 [21:29:12.603] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 21:29:12 [21:29:12.611] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 21:29:12 [21:29:12.627] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 21:29:12 [21:29:12.678] VERBOSE: sequencer Unable to build/enqueue block Rollup__InvalidArchive(0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be, 0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03) 21:29:12 [21:29:12.841] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:12 [21:29:12.905] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x1ac5bd1bbd916357cd7a5a37654a93078fa6271b69ee7c28ef7886de14b51fba","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":2574150199,"blockNumber":2,"slotNumber":68,"timestamp":1748988802,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":40820},"archiveRoot":"0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be","archiveNextLeafIndex":3} 21:29:12 [21:29:12.931] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 21:29:12 [21:29:12.935] INFO: world_state:prover-node World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":14.142273999997997,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1748988802,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 21:29:12 [21:29:12.947] VERBOSE: sequencer Unable to build/enqueue block Rollup__InvalidArchive(0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be, 0x018d9fd6ea12e12ff7310c43b646dbb0ce5a64d9e953ef564f59afc812884f03) 21:29:12 [21:29:12.959] INFO: archiver:prover-node Updated proven chain to block 2 {"provenBlockNumber":2} 21:29:13 [21:29:13.061] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:13 [21:29:13.095] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":12.833929000000353,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1748988802,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 21:29:13 [21:29:13.174] VERBOSE: p2p Synched to latest block 2 21:29:13 [21:29:13.265] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:13 [21:29:13.336] VERBOSE: prover-node:prover-node Fetching 1 tx hashes for block number 2 from coordination 21:29:13 [21:29:13.337] INFO: prover-node:combined-prover-coordination:prover-node Check for 1 txs found 1 missing. Will gather from nodes and p2p 21:29:13 [21:29:13.339] VERBOSE: prover-node:combined-prover-coordination:prover-node Gathered 1 of 1 txs from a node 21:29:13 [21:29:13.339] INFO: prover-node:combined-prover-coordination:prover-node Found all 1 txs directly from nodes 21:29:13 [21:29:13.372] VERBOSE: sequencer Not enough txs to build block 3 at slot 69 (got 0 txs, need 1) {"chainTipArchive":"0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be","blockNumber":3,"slot":69} 21:29:13 [21:29:13.501] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:13 [21:29:13.610] VERBOSE: sequencer Not enough txs to build block 3 at slot 69 (got 0 txs, need 1) {"chainTipArchive":"0x0bfb120a4c7f2e5960b92eb562d35d4068d2cecb20f4a800cb39633f288a03be","blockNumber":3,"slot":69} 21:29:13 [21:29:13.612] INFO: node Stopping 21:29:13 [21:29:13.615] INFO: slasher Slasher client stopped. 21:29:13 [21:29:13.617] INFO: sequencer Stopped sequencer 21:29:13 [21:29:13.619] INFO: p2p P2P client stopped. 21:29:13 [21:29:13.622] INFO: world_state Stopped world state synchronizer 21:29:13 [21:29:13.623] INFO: archiver Stopped. 21:29:13 [21:29:13.623] INFO: node Stopped 21:29:13 [21:29:13.623] INFO: prover-node:prover-node Stopping ProverNode 21:29:13 [21:29:13.624] INFO: prover-node:epoch-monitor:prover-node Stopped EpochMonitor 21:29:13 [21:29:13.624] INFO: archiver:prover-node Stopped. 21:29:13 [21:29:13.628] INFO: world_state:prover-node Stopped world state synchronizer 21:29:13 [21:29:13.628] INFO: prover-node:prover-node Stopped ProverNode 21:29:13 [21:29:13.629] VERBOSE: e2e:e2e_multi_validator_node Cleaning up ACVM state 21:29:13 [21:29:13.629] INFO: e2e:e2e_multi_validator_node Cleaning up ACVM temp directory /tmp/c4cbc664 21:29:13 [21:29:13.632] VERBOSE: e2e:e2e_multi_validator_node Cleaning up BB state 21:29:13 [21:29:13.647] INFO: blob-sink:server Stopping blob sink 21:29:13 [21:29:13.648] INFO: blob-sink:server Blob sink stopped 21:29:13 [21:29:13.648] VERBOSE: e2e:e2e_multi_validator_node Cleaning up data directory at /tmp/f3c035ca14017ae4 21:29:13 [21:29:13.672] INFO: e2e:e2e_multi_validator_node Running test: e2e_multi_validator_node should attest ONLY with the correct validator keys 21:29:13 [21:29:13.958] INFO: e2e:e2e_multi_validator_node Logging metrics to log/e2e_multi_validator_node.jsonl 21:29:14 [21:29:14.015] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-1VqXi8/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 21:29:14 [21:29:14.059] INFO: e2e:e2e_multi_validator_node Calling anvil_setBlockTimestampInterval with params: [12] 21:29:14 [21:29:14.063] WARN: e2e:e2e_multi_validator_node Set block interval to 12 21:29:14 [21:29:14.063] VERBOSE: e2e:e2e_multi_validator_node Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 21:29:14 [21:29:14.080] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 21:29:14 [21:29:14.086] VERBOSE: e2e:e2e_multi_validator_node Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 21:29:14 [21:29:14.110] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 21:29:14 [21:29:14.118] VERBOSE: e2e:e2e_multi_validator_node Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 21:29:14 [21:29:14.156] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x15a910452a602ec46131d746d66411db81d7fc72b90362a00ee4d18cc6abe51b {"gasLimit":3064716,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 21:29:14 [21:29:14.164] VERBOSE: e2e:e2e_multi_validator_node Deployed GSE at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 21:29:14 [21:29:14.183] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x263f559e4ac0e4916749f1cfef6c86ab177d4ed50b4e9dd5b714b2907c3ea485 {"gasLimit":1014492,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 21:29:14 [21:29:14.188] VERBOSE: e2e:e2e_multi_validator_node Deployed Registry at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 21:29:14 [21:29:14.206] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x20a9b0b462f62bc3be8ca34c505c34cf2a4c1358e5584da2d561d72abc96aed1 {"gasLimit":1945066,"maxFeePerGas":"3.602461376","maxPriorityFeePerGas":"2.48832"} 21:29:14 [21:29:14.211] VERBOSE: e2e:e2e_multi_validator_node Deployed GovernanceProposer at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 21:29:14 [21:29:14.234] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x28a12a8c995db07c5f5cafec9b82f038e63dc5e97dcd98daa9cfc9f4a5ab8de7 {"gasLimit":3043143,"maxFeePerGas":"3.968706922","maxPriorityFeePerGas":"2.985984"} 21:29:14 [21:29:14.239] VERBOSE: e2e:e2e_multi_validator_node Deployed Governance at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 21:29:14 [21:29:14.266] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f {"gasLimit":55548,"maxFeePerGas":"4.456337397","maxPriorityFeePerGas":"3.5831808"} 21:29:14 [21:29:14.267] VERBOSE: e2e:e2e_multi_validator_node Set governance on GSE in 0xc1f7b41d75ca1c1d5ae465e404bbd8504ed5ce0a989a57516345a51ea9ed0c0f 21:29:14 [21:29:14.283] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x5d35a155eec0ed0c7deff752275abe3c6d5f448014ad234660feb820ef8620df {"gasLimit":391705,"maxFeePerGas":"5.082281352","maxPriorityFeePerGas":"4.29981696"} 21:29:14 [21:29:14.293] VERBOSE: e2e:e2e_multi_validator_node Deployed CoinIssuer at 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 21:29:14 [21:29:14.294] VERBOSE: e2e:e2e_multi_validator_node Waiting for deployments to complete 21:29:14 [21:29:14.294] INFO: e2e:e2e_multi_validator_node Waiting for 7 transactions to be mined... 21:29:14 [21:29:14.318] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:29:14 [21:29:14.337] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed {"gasLimit":56804,"maxFeePerGas":"5.84473853","maxPriorityFeePerGas":"5.159780352"} 21:29:14 [21:29:14.338] VERBOSE: e2e:e2e_multi_validator_node Added coin issuer 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 as minter on fee asset in 0xc37d056a228d0e8692a00fea58194c1400046912bffddf048101dc45c4dd47ed 21:29:14 [21:29:14.354] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x8e92b552cc623d62987e85e1df7c096f9eae4836710a4f62deed3668e8d02fa6 {"gasLimit":56661,"maxFeePerGas":"6.792938035","maxPriorityFeePerGas":"6.191736422"} 21:29:14 [21:29:14.371] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2c50b6224c5aaa3d953e1f1029dcd0ea319c195a3237972ff224e8c29a1434cd {"gasLimit":339691,"maxFeePerGas":"7.956372276","maxPriorityFeePerGas":"7.430083706"} 21:29:14 [21:29:14.379] VERBOSE: e2e:e2e_multi_validator_node Deployed FeeAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 21:29:14 [21:29:14.395] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c {"gasLimit":56804,"maxFeePerGas":"9.376810034","maxPriorityFeePerGas":"8.916100447"} 21:29:14 [21:29:14.395] VERBOSE: e2e:e2e_multi_validator_node Added fee asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on fee asset in 0xf95284a2480bb65b51365bc5970e8e5f171ce8b045dbcb10348d28e0f1ce646c 21:29:14 [21:29:14.412] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x3bf548a99420047e1164bf2dfa64c5fc4ed32845f7019722b8db00da065120d2 {"gasLimit":1089256,"maxFeePerGas":"11.103528222","maxPriorityFeePerGas":"10.699320536"} 21:29:14 [21:29:14.434] VERBOSE: e2e:e2e_multi_validator_node Deployed StakingAssetHandler at 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 21:29:14 [21:29:14.461] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 {"gasLimit":56804,"maxFeePerGas":"13.193025819","maxPriorityFeePerGas":"12.839184643"} 21:29:14 [21:29:14.461] VERBOSE: e2e:e2e_multi_validator_node Added staking asset handler 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 as minter on staking asset in 0x2197583f4a2bcdf7d6daa20a2db857e2546f0103d8255cb45b2685bf59dd1510 21:29:14 [21:29:14.462] VERBOSE: e2e:e2e_multi_validator_node Waiting for deployments to complete 21:29:14 [21:29:14.462] INFO: e2e:e2e_multi_validator_node Waiting for 9 transactions to be mined... 21:29:14 [21:29:14.546] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:29:14 [21:29:14.643] VERBOSE: e2e:e2e_multi_validator_node Deployed shared contracts 21:29:14 [21:29:14.726] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 {"gasLimit":84837,"maxFeePerGas":"15.719309155","maxPriorityFeePerGas":"15.407021571"} 21:29:14 [21:29:14.726] VERBOSE: e2e:e2e_multi_validator_node Funded reward distributor with 10000000000000000000000000 fee asset in 0x9961b6827a28c68d0c814620c5debe7fce125c59adfd8478604c1d94d12b26b3 21:29:14 [21:29:14.728] 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} 21:29:14 [21:29:14.741] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x4b00bf010016af1a686fa82a49ffcd696fe6567f2fafe88a5077a3196ed3622b {"gasLimit":86397,"maxFeePerGas":"18.761800709","maxPriorityFeePerGas":"18.488425885"} 21:29:14 [21:29:14.780] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x5ab770e71a294d59a299893ceef963b3326d216a75236785d0f3ad5811b5880e {"gasLimit":6285180,"maxFeePerGas":"22.425475094","maxPriorityFeePerGas":"22.186111062"} 21:29:14 [21:29:14.792] VERBOSE: e2e:e2e_multi_validator_node Awaiting for linked libraries to be deployed 21:29:14 [21:29:14.850] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xf0f887d017cc20dfdc067a3304a53ca12e2b1f86ae32b17195ca8425c0d2a714 {"gasLimit":10716795,"maxFeePerGas":"26.832920416","maxPriorityFeePerGas":"26.623333274"} 21:29:14 [21:29:14.857] 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} 21:29:14 [21:29:14.858] INFO: e2e:e2e_multi_validator_node Waiting for 10 transactions to be mined... 21:29:14 [21:29:14.882] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:29:14 [21:29:14.883] VERBOSE: e2e:e2e_multi_validator_node All core contracts have been deployed 21:29:14 [21:29:14.900] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a {"gasLimit":64317,"maxFeePerGas":"32.140536546","maxPriorityFeePerGas":"31.947999928"} 21:29:14 [21:29:14.901] VERBOSE: e2e:e2e_multi_validator_node Funding fee juice portal with 10000000000000000000000 fee juice in 0x876c00941611afeae461e4b49171cda3531c73fd49dd55a0d5ee01076127408a (accelerated test deployments) 21:29:14 [21:29:14.921] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0xd413488be28de232ee67a932daca30e5e7bc4e1ad751dedc3910bbbe185aafe4 {"gasLimit":1040116,"maxFeePerGas":"38.520398451","maxPriorityFeePerGas":"38.337599913"} 21:29:14 [21:29:14.927] VERBOSE: e2e:e2e_multi_validator_node Deployed SlashFactory at 0x3aa5ebb10dc797cac828524e59a333d0a371443c 21:29:14 [21:29:14.963] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x9cf2e8cc19e5a14c92d6ffc7b824d5af7abcd5e99d83ce8f9d8f18174a0f48ec {"gasLimit":116846,"maxFeePerGas":"46.165150264","maxPriorityFeePerGas":"46.005119895"} 21:29:14 [21:29:14.964] VERBOSE: e2e:e2e_multi_validator_node Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to registry 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 in tx 0x9cf2e8cc19e5a14c92d6ffc7b824d5af7abcd5e99d83ce8f9d8f18174a0f48ec 21:29:15 [21:29:15.005] VERBOSE: e2e:e2e_multi_validator_node Sent L1 transaction 0x2a5b9e16c5abd0a75879e886c16c1174a5bec7f4574004840187d6fdb1602285 {"gasLimit":109478,"maxFeePerGas":"55.34732635","maxPriorityFeePerGas":"55.206143874"} 21:29:15 [21:29:15.006] VERBOSE: e2e:e2e_multi_validator_node Adding rollup 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae to GSE 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x2a5b9e16c5abd0a75879e886c16c1174a5bec7f4574004840187d6fdb1602285 21:29:15 [21:29:15.020] INFO: e2e:e2e_multi_validator_node Waiting for 11 transactions to be mined... 21:29:15 [21:29:15.051] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:29:15 [21:29:15.059] VERBOSE: e2e:e2e_multi_validator_node Rollup deployed 21:29:15 [21:29:15.060] VERBOSE: e2e:e2e_multi_validator_node Waiting for rollup and slash factory to be deployed 21:29:15 [21:29:15.060] INFO: e2e:e2e_multi_validator_node Waiting for 11 transactions to be mined... 21:29:15 [21:29:15.088] INFO: e2e:e2e_multi_validator_node All transactions mined successfully 21:29:15 [21:29:15.088] VERBOSE: e2e:e2e_multi_validator_node All transactions for L1 deployment have been mined 21:29:15 [21:29:15.108] 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"} 21:29:15 [21:29:15.108] INFO: e2e:e2e_multi_validator_node Handing over to governance 21:29:15 [21:29:15.114] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 21:29:15 [21:29:15.116] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:15 [21:29:15.117] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x9A9f2CCfdE556A7E9Ff0848998Aa4a0CFD8863AE 21:29:15 [21:29:15.121] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/5cf49ceb642a3996/blob-sink with map size 134217728 KB (LMDB v2) 21:29:15 [21:29:15.122] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 21:29:15 [21:29:15.122] WARN: foundation:test-date-provider Time set to 2025-06-03T21:33:37.000Z {"offset":261878,"timeMs":1748986417000} 21:29:15 [21:29:15.126] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 21:29:15 [21:29:15.131] INFO: blob-sink:server Server is running on http://localhost:44717 21:29:15 [21:29:15.132] VERBOSE: e2e:e2e_multi_validator_node Creating and synching an aztec node... 21:29:15 [21:29:15.133] VERBOSE: e2e:e2e_multi_validator_node Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/9708920c/acvm 21:29:15 [21:29:15.134] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:44717"} 21:29:15 [21:29:15.161] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 21:29:15 [21:29:15.162] INFO: archiver:lmdb Creating archiver data store at directory /tmp/5cf49ceb642a3996/archiver with map size 134217728 KB (LMDB v2) 21:29:15 [21:29:15.164] INFO: archiver:lmdb Starting data store with maxReaders 16 21:29:15 [21:29:15.363] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:15 [21:29:15.470] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:44717"} 21:29:15 [21:29:15.474] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:44717"} 21:29:15 [21:29:15.474] WARN: node:blob-sink:client No L1 consensus host urls configured 21:29:15 [21:29:15.475] WARN: node:blob-sink:client No archive client configured 21:29:15 [21:29:15.476] INFO: archiver Starting archiver sync to rollup contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae from L1 block 18 to current L1 block 22 with hash 0x437ede8fb34b4400a62669e6646caf7987a0c0dbe829bc0ec7886e27b9ef6a2b {"blocksSynchedTo":18,"messagesSynchedTo":{"l1BlockHash":"0xfac6c3d8e46cbec45ea38fd8d6984dc7f3c4b5c7a4df1bf969886e4a96a25f7b","l1BlockNumber":18}} 21:29:15 [21:29:15.489] INFO: archiver Initial archiver sync to L1 block 22 complete. {"l1BlockNumber":22,"syncPoint":{"blocksSynchedTo":22,"messagesSynchedTo":{"l1BlockHash":"0x437ede8fb34b4400a62669e6646caf7987a0c0dbe829bc0ec7886e27b9ef6a2b","l1BlockNumber":22}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 21:29:15 [21:29:15.496] INFO: world-state:database Creating world state data store at directory /tmp/5cf49ceb642a3996/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 21:29:15 [21:29:15.526] INFO: world_state Created world state synchroniser with block history of 64 21:29:15 [21:29:15.526] WARN: node Aztec node is accepting fake proofs 21:29:15 [21:29:15.537] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/5cf49ceb642a3996/p2p with map size 134217728 KB (LMDB v2) 21:29:15 [21:29:15.539] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 21:29:15 [21:29:15.542] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/5cf49ceb642a3996/p2p-archive with map size 134217728 KB (LMDB v2) 21:29:15 [21:29:15.544] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 21:29:15 [21:29:15.547] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/5cf49ceb642a3996/p2p-peers with map size 134217728 KB (LMDB v2) 21:29:15 [21:29:15.548] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 21:29:15 [21:29:15.550] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 21:29:15 [21:29:15.551] VERBOSE: p2p P2P is disabled. Using dummy P2P service 21:29:15 [21:29:15.552] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":200} 21:29:15 [21:29:15.553] INFO: world_state Started world state synchronizer from block 1 21:29:15 [21:29:15.554] VERBOSE: world-state:block_stream Starting sync from block number 0 21:29:15 [21:29:15.555] INFO: p2p Starting p2p client from block 0 with empty mempools 21:29:15 [21:29:15.555] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":200,"startingBlock":0} 21:29:15 [21:29:15.555] INFO: epoch-prune-watcher EpochPruneWatcher initialized 21:29:15 [21:29:15.559] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 21:29:15 [21:29:15.560] INFO: slasher Starting Slasher client... 21:29:15 [21:29:15.565] VERBOSE: validator Initialized validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 21:29:15 [21:29:15.565] VERBOSE: node All Aztec Node subsystems synced 21:29:15 [21:29:15.569] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:15 [21:29:15.572] INFO: sequencer-client Deploying forwarder contract 21:29:15 [21:29:15.588] VERBOSE: sequencer-client Sent L1 transaction 0x602f411c9ae84deb9da90dad04b3e6b54d91158c82d01934d2b760f3de926311 {"gasLimit":323704,"maxFeePerGas":"66.371021876","maxPriorityFeePerGas":"66.247372648"} 21:29:15 [21:29:15.588] VERBOSE: sequencer-client Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000042 to address 0x83ac846879C81f414bDf47961839F4E842ead017 in tx 0x602f411c9ae84deb9da90dad04b3e6b54d91158c82d01934d2b760f3de926311. 21:29:15 [21:29:15.594] INFO: sequencer-client Forwarder contract deployed at 0x83ac846879c81f414bdf47961839f4e842ead017 21:29:15 [21:29:15.600] INFO: validator Started validator with addresses: 21:29:15 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 21:29:15 [21:29:15.601] VERBOSE: validator Validators 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266, 0x70997970c51812dc3a010c7d01b50e0d17dc79c8, 0x3c44cdddb6a900fa2b585dd299e03d12fa4293bc, 0x90f79bf6eb2c4f870365e785982e1f101e93b906, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65 are not on the validator committee for epoch 0 21:29:15 [21:29:15.601] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":200,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/9708920c/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 21:29:15 [21:29:15.601] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 21:29:15 [21:29:15.602] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 21:29:15 [21:29:15.603] INFO: node Aztec Node version: 0.87.6 21:29:15 [21:29:15.603] 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"} 21:29:15 [21:29:15.604] VERBOSE: e2e:e2e_multi_validator_node Creating and syncing a simulated prover node... 21:29:15 [21:29:15.628] INFO: blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:44717"} 21:29:15 [21:29:15.632] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/5cf49ceb642a3996/f8e193d225b60518/archiver with map size 134217728 KB (LMDB v2) 21:29:15 [21:29:15.638] VERBOSE: sequencer Not enough txs to build block 1 at slot 1 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":1} 21:29:15 [21:29:15.639] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 21:29:15 [21:29:15.861] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:15 [21:29:15.868] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 21:29:15 [21:29:15.868] WARN: foundation:test-date-provider Time set to 2025-06-03T21:33:49.000Z {"offset":273132,"timeMs":1748986429000} 21:29:15 [21:29:15.895] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:15 [21:29:15.976] INFO: blob-sink:client:prover-node Testing configured blob sources {"blobSinkUrl":"http://localhost:44717"} 21:29:15 [21:29:15.979] INFO: blob-sink:client:prover-node Blob sink is reachable {"blobSinkUrl":"http://localhost:44717"} 21:29:15 [21:29:15.979] WARN: blob-sink:client:prover-node No L1 consensus host urls configured 21:29:15 [21:29:15.979] WARN: blob-sink:client:prover-node No archive client configured 21:29:15 [21:29:15.981] INFO: archiver:prover-node Starting archiver sync to rollup contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae from L1 block 18 to current L1 block 23 with hash 0x0d07262c8d1f4bbfa0e15cbe761db595c1acae046c52722996be594d48b9abb4 {"blocksSynchedTo":18,"messagesSynchedTo":{"l1BlockHash":"0xfac6c3d8e46cbec45ea38fd8d6984dc7f3c4b5c7a4df1bf969886e4a96a25f7b","l1BlockNumber":18}} 21:29:15 [21:29:15.994] INFO: archiver:prover-node Initial archiver sync to L1 block 23 complete. {"l1BlockNumber":23,"syncPoint":{"blocksSynchedTo":23,"messagesSynchedTo":{"l1BlockHash":"0x0d07262c8d1f4bbfa0e15cbe761db595c1acae046c52722996be594d48b9abb4","l1BlockNumber":23}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 21:29:15 [21:29:15.996] INFO: prover-node:blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:44717"} 21:29:15 [21:29:15.996] VERBOSE: prover-node:prover-node Snapshot sync is disabled. No snapshots URL provided. 21:29:15 [21:29:15.997] VERBOSE: prover-node:prover-node Created archiver and synced to block 0 21:29:16 [21:29:16.002] INFO: world-state:database:prover-node Creating world state data store at directory /tmp/5cf49ceb642a3996/f8e193d225b60518/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 21:29:16 [21:29:16.051] INFO: world_state:prover-node Created world state synchroniser with block history of 64 21:29:16 [21:29:16.052] VERBOSE: world-state:block_stream:prover-node Starting L2 block stream {"proven":false,"pollIntervalMS":200} 21:29:16 [21:29:16.053] INFO: world_state:prover-node Started world state synchronizer from block 1 21:29:16 [21:29:16.054] VERBOSE: world-state:block_stream:prover-node Starting sync from block number 0 21:29:16 [21:29:16.055] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/5cf49ceb642a3996/f8e193d225b60518/archiver when loading epoch databases, ignoring 21:29:16 [21:29:16.055] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/5cf49ceb642a3996/f8e193d225b60518/world_state when loading epoch databases, ignoring 21:29:16 [21:29:16.055] INFO: prover-client:proving-broker:prover-node Proving Broker started 21:29:16 [21:29:16.067] INFO: prover-node:prover-coordination:prover-node Using prover coordination via aztec node 21:29:16 [21:29:16.068] INFO: e2e:e2e_multi_validator_node:prover-node Created and synced prover node {"publisherAddress":"0x3C44CdDdB6a900fa2b585dd299e03d12FA4293BC"} 21:29:16 [21:29:16.069] INFO: prover-node:epoch-monitor:prover-node Started EpochMonitor {"pollingIntervalMs":200} 21:29:16 [21:29:16.072] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:16 [21:29:16.076] 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/5cf49ceb642a3996/f8e193d225b60518","l1ChainId":31337,"rollupVersion":400004724} 21:29:16 [21:29:16.077] VERBOSE: e2e:e2e_multi_validator_node Creating a pxe... 21:29:16 [21:29:16.080] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/5cef4ccf5ec5b92a/pxe_data with map size 134217728 KB (LMDB v2) 21:29:16 [21:29:16.082] INFO: pxe:data:lmdb Starting data store with maxReaders 16 21:29:16 [21:29:16.202] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:16 [21:29:16.322] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:16 [21:29:16.415] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 21:29:16 [21:29:16.416] INFO: pxe:service Started PXE connected to chain 31337 version 400004724 21:29:16 [21:29:16.460] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:16 [21:29:16.464] INFO: pxe:service Registered account 0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec 21:29:16 [21:29:16.516] WARN: pxe:service No artifact found for contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a when looking for its metadata 21:29:16 [21:29:16.517] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a as part of deployment for 0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec 21:29:16 [21:29:16.556] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:16 [21:29:16.673] INFO: pxe:service Added contract SchnorrAccount at 0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec with class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 21:29:16 [21:29:16.683] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:16 [21:29:16.704] VERBOSE: pxe:block_stream Starting sync from block number 0 21:29:16 [21:29:16.782] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 21:29:16 [21:29:16.812] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:16 [21:29:16.823] WARN: sequencer:block-builder Error closing forks for block processing {"generatedMessage":false,"code":"ERR_ASSERTION","actual":false,"expected":true,"operator":"=="} 21:29:16 [21:29:16.883] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 21:29:17 [21:29:17.410] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a,0x2f6652d5f6110255f6132bc8be0dd74c664523f8808ebbc3eb467252a0b5e74a,0x1d9edc9e571a9e7ab4d2aa454dd024ee819579e3e22ae0fadf28089b29f3f0bc,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 21:29:17 [21:29:17.521] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 21:29:17 [21:29:17.629] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:17 [21:29:17.666] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:17 [21:29:17.877] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:17 [21:29:17.937] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec"} 21:29:17 [21:29:17.986] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 21:29:17 [21:29:17.986] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec"} 21:29:17 [21:29:17.996] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:18 [21:29:18.046] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec"} 21:29:18 [21:29:18.055] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 21:29:18 [21:29:18.189] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:18 [21:29:18.294] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec"} 21:29:18 [21:29:18.354] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 21:29:18 [21:29:18.354] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec"} 21:29:18 [21:29:18.355] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:18 [21:29:18.395] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:18 [21:29:18.426] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec"} 21:29:18 [21:29:18.429] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 21:29:18 [21:29:18.446] VERBOSE: simulator:client_execution_context:debug_log Setting 0x08c1e6e2564af780b7ef53772e2cb459650d08e6e3ed3a25ea8c0ce6b42c6fec as fee payer 21:29:18 [21:29:18.612] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:18 [21:29:18.738] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:18 [21:29:18.899] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:19 [21:29:19.020] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:19 [21:29:19.169] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:19 [21:29:19.283] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:19 [21:29:19.719] INFO: pxe:service Proving completed in 3018.0949020000007ms {"timings":{"total":3018.0949020000007,"sync":2.796883999999409,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":204.78762899999856,"oracles":{"callPrivateFunction":{"times":[795.113110000002,576.0293319999982,280.60674500000096]}}},{"functionName":"private_kernel_init","time":83.80544799999916},{"functionName":"ContractClassRegisterer:register","time":776.6082610000012,"oracles":{"loadCapsule":{"times":[12.769136999999319]},"notifyCreatedNullifier":{"times":[1.1011890000008862]},"debugLog":{"times":[1.0278270000017073]},"notifyCreatedContractClassLog":{"times":[11.187425999996776]}}},{"functionName":"private_kernel_inner","time":151.30775300000096},{"functionName":"SchnorrAccount:constructor","time":507.3934459999982,"oracles":{"getContractInstance":{"times":[8.711811000001035]},"debugLog":{"times":[0.582285000000411,0.6430970000001253]},"fetchTaggedLogs":{"times":[65.63550699999905]},"loadCapsule":{"times":[1.1395899999988615,0.40717099999892525,0.29670700000133365]},"validateEnqueuedNotes":{"times":[1.6630240000013146]},"notifyCreatedNullifier":{"times":[0.7195990000000165,0.3400389999987965]},"notifyCreatedNote":{"times":[0.17267500000161817]},"getRandomField":{"times":[0.25497600000016973,0.2608670000008715,0.13953400000173133,0.062251999999716645,0.05836099999942235,0.04300200000216137,0.045830999999452615,0.05321100000219303,0.07537199999933364,0.052102000001468696,0.05341200000111712]},"getIndexedTaggingSecretAsSender":{"times":[48.23245700000189]},"incrementAppTaggingSecretIndexAsSender":{"times":[39.51610599999913]}}},{"functionName":"private_kernel_inner","time":138.09129399999802},{"functionName":"SchnorrAccount:entrypoint","time":241.92149100000097,"oracles":{"debugLog":{"times":[0.5961850000021514,0.36344000000099186,0.8331619999989925]},"fetchTaggedLogs":{"times":[73.82475400000112]},"loadCapsule":{"times":[0.2770669999990787,0.2004960000012943,0.20117499999832944]},"validateEnqueuedNotes":{"times":[0.8444619999972929]},"getNotes":{"times":[1.0508279999994556]},"getAuthWitness":{"times":[0.2970179999974789]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.6186859999979788]}}},{"functionName":"private_kernel_inner","time":126.56318900000042},{"functionName":"private_kernel_reset","time":224.99924300000202},{"functionName":"private_kernel_tail","time":77.31449599999905}],"unaccounted":482.5057680000027}} 21:29:19 [21:29:19.736] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:19 [21:29:19.802] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105","blockNumber":1,"slot":2} 21:29:19 [21:29:19.808] VERBOSE: p2p:tx_pool Adding tx 0x1b532e889e1a73046a9ebba3ca5c55a119408c04fc16a923578457ee49ca81b7 to pool {"eventName":"tx-added-to-pool","txHash":"0x1b532e889e1a73046a9ebba3ca5c55a119408c04fc16a923578457ee49ca81b7","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 21:29:19 [21:29:19.825] INFO: node Received tx 0x1b532e889e1a73046a9ebba3ca5c55a119408c04fc16a923578457ee49ca81b7 {"txHash":"0x1b532e889e1a73046a9ebba3ca5c55a119408c04fc16a923578457ee49ca81b7"} 21:29:19 [21:29:19.825] INFO: pxe:service Sent transaction 0x1b532e889e1a73046a9ebba3ca5c55a119408c04fc16a923578457ee49ca81b7 21:29:19 [21:29:19.949] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:20 [21:29:20.029] VERBOSE: sequencer:block-builder Building block 1 for slot 2 {"slot":2,"slotStart":"2025-06-03T21:34:01.000Z","now":"2025-06-03T21:33:53.161Z","blockNumber":1,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000},"txPublicSetupAllowList":[{"address":"0x0000000000000000000000000000000000000000000000000000000000000001"},{"address":"0x0000000000000000000000000000000000000000000000000000000000000005"},{"classId":"0x1e3aef33989764dcfb6810f6472a042297977115273a0fdb35027f7ea637b996"},{"classId":"0x05920012da9559f0ec2f21e8b87dc62fd75973f2d285c9f6b84407387f5a6bac"}]}} 21:29:20 [21:29:20.081] VERBOSE: simulator:public-processor Processed tx 0x1b532e889e1a73046a9ebba3ca5c55a119408c04fc16a923578457ee49ca81b7 with no public calls in 22.40547300000253ms {"txHash":"0x1b532e889e1a73046a9ebba3ca5c55a119408c04fc16a923578457ee49ca81b7","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":22.40547300000253} 21:29:20 [21:29:20.083] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.04545452300000034s {"duration":0.04545452300000034,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 21:29:20 [21:29:20.172] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:20 [21:29:20.181] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x273ddeb4d9ba71d6d764d71e069274a7449647833a6c2bb8214b7f898019f0ad","globalVariables":{"chainId":31337,"version":400004724,"blockNumber":1,"slotNumber":2,"timestamp":1748986441,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1b532e889e1a73046a9ebba3ca5c55a119408c04fc16a923578457ee49ca81b7"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":159.71979700000156,"publicProcessDuration":46.0489579999994,"rollupCircuitsDuration":152.49250499999835,"txCount":1,"blockNumber":1,"blockTimestamp":1748986441,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 21:29:20 [21:29:20.183] VERBOSE: sequencer Attesting committee is empty 21:29:20 [21:29:20.378] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:20 [21:29:20.543] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x86988c7d93f0484c3e65c3209eab1adef9f88cd1266167343d26cdef44b0b85c 21:29:20 [21:29:20.543] VERBOSE: node Sent L1 transaction 0x86988c7d93f0484c3e65c3209eab1adef9f88cd1266167343d26cdef44b0b85c {"gasLimit":555058,"maxFeePerGas":"79.605134257","maxPriorityFeePerGas":"79.496847177","maxFeePerBlobGas":"0.000000001"} 21:29:20 [21:29:20.557] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 21:29:20 [21:29:20.557] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x86988c7d93f0484c3e65c3209eab1adef9f88cd1266167343d26cdef44b0b85c","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":1,"blockTimestamp":1748986441,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 21:29:20 [21:29:20.558] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 21:29:20 [21:29:20.569] INFO: blob-sink:server Received blob sidecar for block 0xf8436c730fe9d10b7011051f56aff29777dc0b84edd184c5df706f5e164adf68 21:29:20 [21:29:20.576] INFO: blob-sink:server Blob sidecar stored successfully for block 0xf8436c730fe9d10b7011051f56aff29777dc0b84edd184c5df706f5e164adf68 21:29:20 [21:29:20.581] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:20 [21:29:20.587] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 21:29:20 [21:29:20.587] WARN: foundation:test-date-provider Time set to 2025-06-03T21:34:01.000Z {"offset":280413,"timeMs":1748986441000} 21:29:20 [21:29:20.589] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 21:29:20 [21:29:20.591] WARN: ethereum:cheat_codes Set L1 storage for contract 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 21:29:20 [21:29:20.595] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 21:29:20 [21:29:20.734] VERBOSE: archiver:block-helper:prover-node Store contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 21:29:20 [21:29:20.740] INFO: archiver:prover-node Downloaded L2 block 1 {"blockHash":"0x273ddeb4d9ba71d6d764d71e069274a7449647833a6c2bb8214b7f898019f0ad","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":400004724,"blockNumber":1,"slotNumber":2,"timestamp":1748986441,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x3058f3a7a68c3f63ad5b63e0444b6c89c423dda06e4eb5aa697c403d5f52137d","archiveNextLeafIndex":2} 21:29:20 [21:29:20.750] INFO: archiver:prover-node Updated proven chain to block 1 {"provenBlockNumber":1} 21:29:20 [21:29:20.761] VERBOSE: prover-node:prover-node Fetching 1 tx hashes for block number 1 from coordination 21:29:20 [21:29:20.761] INFO: prover-node:combined-prover-coordination:prover-node Check for 1 txs found 1 missing. Will gather from nodes and p2p 21:29:20 [21:29:20.768] VERBOSE: prover-node:combined-prover-coordination:prover-node Gathered 1 of 1 txs from a node 21:29:20 [21:29:20.768] INFO: prover-node:combined-prover-coordination:prover-node Found all 1 txs directly from nodes 21:29:20 [21:29:20.777] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1748986477] on http://127.0.0.1:8545 21:29:20 [21:29:20.780] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 21:29:20 [21:29:20.789] WARN: ethereum:cheat_codes Warped L1 timestamp to 1748986477 21:29:20 [21:29:20.790] WARN: foundation:test-date-provider Time set to 2025-06-03T21:34:37.000Z {"offset":316210,"timeMs":1748986477000} 21:29:20 [21:29:20.790] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 21:29:20 [21:29:20.791] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:20 [21:29:20.806] VERBOSE: sequencer Unable to build/enqueue block Rollup__InvalidArchive(0x3058f3a7a68c3f63ad5b63e0444b6c89c423dda06e4eb5aa697c403d5f52137d, 0x27cbeaa13ead0d5c7fde746fd63a43c49f264b6b575ab87d978f41327d545105) 21:29:20 [21:29:20.821] INFO: world_state:prover-node World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":9.857099999997445,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1748986441,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 21:29:20 [21:29:20.919] VERBOSE: archiver:block-helper Store contract class 0x04520ed62a6cc23133af49ef93ebc0105307ae7a33eba882228e7635e9534e8a 21:29:20 [21:29:20.923] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x273ddeb4d9ba71d6d764d71e069274a7449647833a6c2bb8214b7f898019f0ad","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":400004724,"blockNumber":1,"slotNumber":2,"timestamp":1748986441,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x3058f3a7a68c3f63ad5b63e0444b6c89c423dda06e4eb5aa697c403d5f52137d","archiveNextLeafIndex":2} 21:29:20 [21:29:20.932] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 21:29:21 [21:29:21.005] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 21:29:21 [21:29:21.008] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":13.87721799999781,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1748986441,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 21:29:21 [21:29:21.036] VERBOSE: p2p Synched to latest block 1 21:29:21 [21:29:21.041] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1748988781] on http://127.0.0.1:8545 21:29:21 [21:29:21.042] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 21:29:21 [21:29:21.043] WARN: ethereum:cheat_codes Warped L1 timestamp to 1748988781 21:29:21 [21:29:21.057] INFO: node Stopping 21:29:21 [21:29:21.058] INFO: slasher Slasher client stopped. 21:29:21 [21:29:21.059] INFO: sequencer Stopped sequencer 21:29:21 [21:29:21.059] INFO: p2p P2P client stopped. 21:29:21 [21:29:21.061] INFO: world_state Stopped world state synchronizer 21:29:21 [21:29:21.062] INFO: archiver Stopped. 21:29:21 [21:29:21.062] INFO: node Stopped 21:29:21 [21:29:21.062] INFO: prover-node:prover-node Stopping ProverNode 21:29:21 [21:29:21.062] INFO: prover-node:epoch-monitor:prover-node Stopped EpochMonitor 21:29:21 [21:29:21.062] INFO: archiver:prover-node Stopped. 21:29:21 [21:29:21.064] INFO: world_state:prover-node Stopped world state synchronizer 21:29:21 [21:29:21.064] INFO: prover-node:prover-node Stopped ProverNode 21:29:21 [21:29:21.064] VERBOSE: e2e:e2e_multi_validator_node Cleaning up ACVM state 21:29:21 [21:29:21.064] INFO: e2e:e2e_multi_validator_node Cleaning up ACVM temp directory /tmp/9708920c 21:29:21 [21:29:21.065] VERBOSE: e2e:e2e_multi_validator_node Cleaning up BB state 21:29:21 [21:29:21.068] INFO: blob-sink:server Stopping blob sink 21:29:21 [21:29:21.069] INFO: blob-sink:server Blob sink stopped 21:29:21 [21:29:21.069] VERBOSE: e2e:e2e_multi_validator_node Cleaning up data directory at /tmp/5cf49ceb642a3996 21:29:21 FAIL src/e2e_multi_validator_node.test.ts 21:29:21 e2e_multi_validator_node 21:29:21 ✓ should build blocks & attest with multiple validator keys (15650 ms) 21:29:21 ✕ should attest ONLY with the correct validator keys (7404 ms) 21:29:21 21:29:21 ● e2e_multi_validator_node › should attest ONLY with the correct validator keys 21:29:21 21:29:21 ContractFunctionExecutionError: The contract function "initiateWithdraw" reverted. 21:29:21 21:29:21 Error: Staking__NothingToExit(address) 21:29:21 (0x15d34AAf54267DB7D7c367839AAf71A00a2C6A65) 21:29:21 21:29:21 Contract Call: 21:29:21 address: 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 21:29:21 function: initiateWithdraw(address _attester, address _recipient) 21:29:21 args: (0x15d34aaf54267db7d7c367839aaf71a00a2c6a65, 0x15d34aaf54267db7d7c367839aaf71a00a2c6a65) 21:29:21 sender: 0x15d34AAf54267DB7D7c367839AAf71A00a2C6A65 21:29:21 21:29:21 Docs: https://viem.sh/docs/contract/writeContract 21:29:21 Version: viem@2.23.7 21:29:21 21:29:21 142 | client: createExtendedL1Client(config.l1RpcUrls, initialValidatorPrivateKeys[VALIDATOR_COUNT - 1]), 21:29:21 143 | }); 21:29:21 > 144 | await rollupContract1.write.initiateWithdraw([ 21:29:21 | ^ 21:29:21 145 | validatorAddresses[VALIDATOR_COUNT - 1], 21:29:21 146 | validatorAddresses[VALIDATOR_COUNT - 1], 21:29:21 147 | ]); 21:29:21 21:29:21 at getContractError (../../node_modules/viem/utils/errors/getContractError.ts:78:10) 21:29:21 at writeContract (../../node_modules/viem/actions/wallet/writeContract.ts:208:11) 21:29:21 at Object.<anonymous> (e2e_multi_validator_node.test.ts:144:5) 21:29:21 21:29:21 Cause: 21:29:21 ContractFunctionRevertedError: The contract function "initiateWithdraw" reverted. 21:29:21 21:29:21 Error: Staking__NothingToExit(address) 21:29:21 (0x15d34AAf54267DB7D7c367839AAf71A00a2C6A65) 21:29:21 21:29:21 Version: viem@2.23.7 21:29:21 21:29:21 142 | client: createExtendedL1Client(config.l1RpcUrls, initialValidatorPrivateKeys[VALIDATOR_COUNT - 1]), 21:29:21 143 | }); 21:29:21 > 144 | await rollupContract1.write.initiateWithdraw([ 21:29:21 | ^ 21:29:21 145 | validatorAddresses[VALIDATOR_COUNT - 1], 21:29:21 146 | validatorAddresses[VALIDATOR_COUNT - 1], 21:29:21 147 | ]); 21:29:21 21:29:21 at ../../node_modules/viem/utils/errors/getContractError.ts:65:14 21:29:21 at getContractError (../../node_modules/viem/utils/errors/getContractError.ts:76:5) 21:29:21 at writeContract (../../node_modules/viem/actions/wallet/writeContract.ts:208:11) 21:29:21 at Object.<anonymous> (e2e_multi_validator_node.test.ts:144:5) 21:29:21 21:29:21 Test Suites: 1 failed, 1 total 21:29:21 Tests: 1 failed, 1 passed, 2 total 21:29:21 Snapshots: 0 total 21:29:21 Time: 30.262 s 21:29:21 Ran all test suites matching /src\/e2e_multi_validator_node.test.ts/i. 21:29:21 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?