Parent Log: http://ci.aztec-labs.com/ec53a48cfa0deda9 Command: f5e85dbc8a1d83c5:ISOLATE=1:NAME=e2e_snapshot_sync yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_snapshot_sync.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/26c5a39fe03723d11540f721293d7aebd1f478d9 Env: REF_NAME=gh-readonly-queue/next/pr-14995-75d792847d8434a0c504e7adf5c102a913065272 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 13 08:59:18 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14995_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_eb8f79a88ef38365_next 08:59:18 +++ id -u 08:59:18 +++ id -g 08:59:18 ++ docker run -d --name e2e_snapshot_sync --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_snapshot_sync.test.ts'\''' 08:59:18 + cid=9e989f4a55c59d62de5b1be4531056ffd8faeeb0c5f544dd949e07ef9c7a6eb6 08:59:18 + set +x 08:59:19 {"level":25,"time":1749805159483,"pid":12,"hostname":"9e989f4a55c5","module":"logger","msg":"Logger initialized with level verbose"} 08:59:25 [08:59:25.764] INFO: e2e:e2e_snapshot_sync Logging metrics to log/unknown.jsonl 08:59:25 [08:59:25.946] INFO: e2e:e2e_snapshot_sync Calling anvil_setBlockTimestampInterval with params: [8] 08:59:25 [08:59:25.974] WARN: e2e:e2e_snapshot_sync Set block interval to 8 08:59:25 [08:59:25.975] VERBOSE: e2e:e2e_snapshot_sync Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 08:59:26 [08:59:26.030] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xff8f3047f70ec9e63079d7903c02a47802dc292d5660d2da825ac209bbd9cd89 {"gasLimit":867658,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:26 [08:59:26.031] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xd8B56421e9b573AE62f2997B7b58858a4413A967 in tx 0xff8f3047f70ec9e63079d7903c02a47802dc292d5660d2da825ac209bbd9cd89. 08:59:26 [08:59:26.033] VERBOSE: e2e:e2e_snapshot_sync Deployed Fee Asset at 0xd8b56421e9b573ae62f2997b7b58858a4413a967 08:59:26 [08:59:26.053] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xb8a727e5dcee31d2ee7a6a94b8b01df7952fdc685dfb1b5bb36a0dbda9d08d89 {"gasLimit":867644,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:26 [08:59:26.054] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x026208feE3F4461826C8838C3569272E8C08E131 in tx 0xb8a727e5dcee31d2ee7a6a94b8b01df7952fdc685dfb1b5bb36a0dbda9d08d89. 08:59:26 [08:59:26.055] VERBOSE: e2e:e2e_snapshot_sync Deployed Staking Asset at 0x026208fee3f4461826c8838c3569272e8c08e131 08:59:26 [08:59:26.078] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x84345970a64bc89d244665c6d19bcf62f7a1fb0c5497fd61c66569ea96442f1f {"gasLimit":3118894,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:26 [08:59:26.079] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xad2120681B02De3e9b8aFd325977CDeDDf15f01C in tx 0x84345970a64bc89d244665c6d19bcf62f7a1fb0c5497fd61c66569ea96442f1f. 08:59:26 [08:59:26.080] VERBOSE: e2e:e2e_snapshot_sync Deployed GSE at 0xad2120681b02de3e9b8afd325977cdeddf15f01c 08:59:26 [08:59:26.102] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x96c1e759b945754976d026659a519c1c5d05a738916e69d742cdec67a5482765 {"gasLimit":1030513,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:26 [08:59:26.103] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x992D1f24CC5f368188d936cf0b2A111cEE586d4e in tx 0x96c1e759b945754976d026659a519c1c5d05a738916e69d742cdec67a5482765. 08:59:26 [08:59:26.104] VERBOSE: e2e:e2e_snapshot_sync Deployed Registry at 0x992d1f24cc5f368188d936cf0b2a111cee586d4e 08:59:26 [08:59:26.158] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x35838f7740ede8daad92b7b8589b59625f0100193e252bc6ce3862a65d23892c {"gasLimit":1998672,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:26 [08:59:26.159] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xfDB60896B1920A10Fd3AA268A292E51ebD5568A8 in tx 0x35838f7740ede8daad92b7b8589b59625f0100193e252bc6ce3862a65d23892c. 08:59:26 [08:59:26.161] VERBOSE: e2e:e2e_snapshot_sync Deployed GovernanceProposer at 0xfdb60896b1920a10fd3aa268a292e51ebd5568a8 08:59:26 [08:59:26.203] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x4dbcecd47a2081d10a38e4cf385714d92e542e5a44eb5a37a3810c6c6738dc4e {"gasLimit":3092007,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:26 [08:59:26.204] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x506150CaB49DBC09C8B79D20ac5c79CA28220AEA in tx 0x4dbcecd47a2081d10a38e4cf385714d92e542e5a44eb5a37a3810c6c6738dc4e. 08:59:26 [08:59:26.205] VERBOSE: e2e:e2e_snapshot_sync Deployed Governance at 0x506150cab49dbc09c8b79d20ac5c79ca28220aea 08:59:26 [08:59:26.220] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xf2ecc0d2040ae9928a4b186c21f02e611c2904f24b196eed69d646e9333cc00d {"gasLimit":100000,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:26 [08:59:26.221] VERBOSE: e2e:e2e_snapshot_sync Set governance on GSE in 0xf2ecc0d2040ae9928a4b186c21f02e611c2904f24b196eed69d646e9333cc00d 08:59:26 [08:59:26.241] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x77f9fa97fbbcb04392e88d456c198520b3425518c3d2d4146393099192372448 {"gasLimit":397390,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:26 [08:59:26.241] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x1649EeAD62C64Db7F63182989C8c41Ab25025C21 in tx 0x77f9fa97fbbcb04392e88d456c198520b3425518c3d2d4146393099192372448. 08:59:26 [08:59:26.242] VERBOSE: e2e:e2e_snapshot_sync Deployed CoinIssuer at 0x1649eead62c64db7f63182989c8c41ab25025c21 08:59:26 [08:59:26.243] VERBOSE: e2e:e2e_snapshot_sync Waiting for deployments to complete 08:59:26 [08:59:26.243] INFO: e2e:e2e_snapshot_sync Waiting for 7 transactions to be mined... 08:59:34 [08:59:34.272] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 08:59:34 [08:59:34.291] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xc033579f2357cfd2f6fa38d1ef9986415f4ed794add3fff64984bc3f1257e970 {"gasLimit":100000,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:34 [08:59:34.292] VERBOSE: e2e:e2e_snapshot_sync Added coin issuer 0x1649eead62c64db7f63182989c8c41ab25025c21 as minter on fee asset in 0xc033579f2357cfd2f6fa38d1ef9986415f4ed794add3fff64984bc3f1257e970 08:59:34 [08:59:34.306] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xd6a54e4eed6f07d2b8dcaafbd3883b3558583e43612f444af113d489245a2bb1 {"gasLimit":56661,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:34 [08:59:34.329] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xa67ff7f4001dc5703375e116caba00222d15ec13472e10af8018f62318b463cb {"gasLimit":344517,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:34 [08:59:34.329] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x3607dbAfDe17871E0D6A8f929151C30b83510D39 in tx 0xa67ff7f4001dc5703375e116caba00222d15ec13472e10af8018f62318b463cb. 08:59:34 [08:59:34.330] VERBOSE: e2e:e2e_snapshot_sync Deployed FeeAssetHandler at 0x3607dbafde17871e0d6a8f929151c30b83510d39 08:59:34 [08:59:34.352] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xa32bc1b02f5f02b41626611ec0e0056cad989330fbf908181b5d4f7678377aae {"gasLimit":56804,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:34 [08:59:34.353] VERBOSE: e2e:e2e_snapshot_sync Added fee asset handler 0x3607dbafde17871e0d6a8f929151c30b83510d39 as minter on fee asset in 0xa32bc1b02f5f02b41626611ec0e0056cad989330fbf908181b5d4f7678377aae 08:59:34 [08:59:34.380] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xadb884185b00b3cf3fb2aac4753fbbc2bbb58dfbab2a937d61ea0e950d96b91b {"gasLimit":2514669,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:34 [08:59:34.381] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x499C89D6b68cBdcA67C96Bff13Eb5B5a8fDb25a2 in tx 0xadb884185b00b3cf3fb2aac4753fbbc2bbb58dfbab2a937d61ea0e950d96b91b. 08:59:34 [08:59:34.382] VERBOSE: e2e:e2e_snapshot_sync Deployed StakingAssetHandler at 0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2 08:59:34 [08:59:34.407] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x2b32bbf996c6d3500330bc3ce74137b03f19074efe8ad0d3e14e6dea009f12e4 {"gasLimit":56804,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:34 [08:59:34.407] VERBOSE: e2e:e2e_snapshot_sync Added staking asset handler 0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2 as minter on staking asset in 0x2b32bbf996c6d3500330bc3ce74137b03f19074efe8ad0d3e14e6dea009f12e4 08:59:34 [08:59:34.408] VERBOSE: e2e:e2e_snapshot_sync Waiting for deployments to complete 08:59:34 [08:59:34.408] INFO: e2e:e2e_snapshot_sync Waiting for 9 transactions to be mined... 08:59:42 [08:59:42.432] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 08:59:42 [08:59:42.451] VERBOSE: e2e:e2e_snapshot_sync Deployed shared contracts 08:59:42 [08:59:42.474] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x1f3bf003fea3920e27f0ab1b5a7c080b356836c498ba06ccbbb2f38e05cac9a5 {"gasLimit":84823,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:42 [08:59:42.474] VERBOSE: e2e:e2e_snapshot_sync Funded reward distributor with 10000000000000000000000000 fee asset in 0x1f3bf003fea3920e27f0ab1b5a7c080b356836c498ba06ccbbb2f38e05cac9a5 08:59:42 [08:59:42.496] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x8ddfc36dd291a7e69dbb1d1c4b25283f53b9e73f733772b984e1b7cef75ca71d {"gasLimit":154658,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:42 [08:59:42.497] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x277E22B0C9698d7f2A1cD7C6d857511617587309 in tx 0x8ddfc36dd291a7e69dbb1d1c4b25283f53b9e73f733772b984e1b7cef75ca71d. 08:59:42 [08:59:42.497] VERBOSE: e2e:e2e_snapshot_sync Rollup will use the mock verifier at 0x277e22b0c9698d7f2a1cd7c6d857511617587309 08:59:42 [08:59:42.497] VERBOSE: e2e:e2e_snapshot_sync Rollup config args {"aztecSlotDuration":16,"aztecEpochDuration":64,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100,"rewardConfig":{"sequencerBps":5000,"increment":200000,"maxScore":5000000,"a":5000,"k":1000000,"minimum":100000}} 08:59:42 [08:59:42.519] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x1244dd983dfa48995970c0afd0fb79b5391ff919edd2ee7939902fc973eec3e1 {"gasLimit":86992,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:42 [08:59:42.520] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xbba8820C80d584aEc2Cb092C3a0bE628aAB41fe3 in tx 0x1244dd983dfa48995970c0afd0fb79b5391ff919edd2ee7939902fc973eec3e1. 08:59:42 [08:59:42.572] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xc613ac3e45b25859ad92f225b053c859a6b60b0ddf525799f26696c2568a98fe {"gasLimit":5159372,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:42 [08:59:42.573] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x95bA1C62f973621Af2A511A8010d3F1aC1062C33 in tx 0xc613ac3e45b25859ad92f225b053c859a6b60b0ddf525799f26696c2568a98fe. 08:59:42 [08:59:42.608] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x605c36a0608e211905ee60f09d2a342acfff4cfa28b000496717a06c835d1381 {"gasLimit":2478502,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:42 [08:59:42.609] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xfF6eB969c88E1ba744A420c6E9a2C9e471dB1811 in tx 0x605c36a0608e211905ee60f09d2a342acfff4cfa28b000496717a06c835d1381. 08:59:42 [08:59:42.610] VERBOSE: e2e:e2e_snapshot_sync Awaiting for linked libraries to be deployed 08:59:50 [08:59:50.673] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x383f74e13d10ed985b1695df5dd168caa25c398df810ca82361e334e256b8c14 {"gasLimit":10676151,"maxFeePerGas":"3.44200128","maxPriorityFeePerGas":"2.0736"} 08:59:50 [08:59:50.673] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x82Be125243429b0F0b6B42bF668aEB1B0A498d36 in tx 0x383f74e13d10ed985b1695df5dd168caa25c398df810ca82361e334e256b8c14. 08:59:50 [08:59:50.674] VERBOSE: e2e:e2e_snapshot_sync Deployed Rollup at 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 {"aztecSlotDuration":16,"aztecEpochDuration":64,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100,"rewardConfig":{"sequencerBps":5000,"increment":200000,"maxScore":5000000,"a":5000,"k":1000000,"minimum":100000}} 08:59:50 [08:59:50.674] INFO: e2e:e2e_snapshot_sync Waiting for 11 transactions to be mined... 08:59:58 [08:59:58.687] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 08:59:58 [08:59:58.687] VERBOSE: e2e:e2e_snapshot_sync All core contracts have been deployed 08:59:58 [08:59:58.703] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xa646e395d19938af66e8b8c1a46ef88aca49783152a68a536f794b0216a007f8 {"gasLimit":1056496,"maxFeePerGas":"3.760309518","maxPriorityFeePerGas":"2.48832"} 08:59:58 [08:59:58.703] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xeFBa96d6D8A7783972bedFc1c73935c3Fe218d91 in tx 0xa646e395d19938af66e8b8c1a46ef88aca49783152a68a536f794b0216a007f8. 08:59:58 [08:59:58.704] VERBOSE: e2e:e2e_snapshot_sync Deployed SlashFactory at 0xefba96d6d8a7783972bedfc1c73935c3fe218d91 08:59:58 [08:59:58.729] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xfc751ada7f7bb3ad53733649c166d16601a1b74f320bdb636751f13cae858a48 {"gasLimit":116846,"maxFeePerGas":"3.760309518","maxPriorityFeePerGas":"2.48832"} 08:59:58 [08:59:58.730] VERBOSE: e2e:e2e_snapshot_sync Adding rollup 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 to registry 0x992d1f24cc5f368188d936cf0b2a111cee586d4e in tx 0xfc751ada7f7bb3ad53733649c166d16601a1b74f320bdb636751f13cae858a48 08:59:58 [08:59:58.744] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x4a1e134b475962fe7e269ec1fb6752e72041a80494f25446c60e25f6963a45f8 {"gasLimit":109478,"maxFeePerGas":"3.760309518","maxPriorityFeePerGas":"2.48832"} 08:59:58 [08:59:58.744] VERBOSE: e2e:e2e_snapshot_sync Adding rollup 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 to GSE 0xad2120681b02de3e9b8afd325977cdeddf15f01c in tx 0x4a1e134b475962fe7e269ec1fb6752e72041a80494f25446c60e25f6963a45f8 09:00:06 [09:00:06.778] INFO: e2e:e2e_snapshot_sync Waiting for 12 transactions to be mined... 09:00:06 [09:00:06.878] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 09:00:06 [09:00:06.900] VERBOSE: e2e:e2e_snapshot_sync Rollup deployed 09:00:06 [09:00:06.900] VERBOSE: e2e:e2e_snapshot_sync Waiting for rollup and slash factory to be deployed 09:00:06 [09:00:06.903] INFO: e2e:e2e_snapshot_sync Waiting for 12 transactions to be mined... 09:00:06 [09:00:06.958] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 09:00:06 [09:00:06.958] VERBOSE: e2e:e2e_snapshot_sync All transactions for L1 deployment have been mined 09:00:07 [09:00:07.038] INFO: e2e:e2e_snapshot_sync Aztec L1 contracts initialized {"registryAddress":"0x992d1f24cc5f368188d936cf0b2a111cee586d4e","governanceAddress":"0x506150cab49dbc09c8b79d20ac5c79ca28220aea","governanceProposerAddress":"0xfdb60896b1920a10fd3aa268a292e51ebd5568a8","rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36","inboxAddress":"0x612598d664a21aa9baac2ae3f9fc2e6c41abb37d","outboxAddress":"0x014f72a899dd14c0b660e4b95bae8f8691946997","feeJuicePortalAddress":"0x56244b274ed63bd682acae796efe16586ed21a45","feeJuiceAddress":"0xd8b56421e9b573ae62f2997b7b58858a4413a967","stakingAssetAddress":"0x026208fee3f4461826c8838c3569272e8c08e131","rewardDistributorAddress":"0xd462e23c2c8a9b4f83de76004a170d4cebb5d3e1","gseAddress":"0xad2120681b02de3e9b8afd325977cdeddf15f01c","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 09:00:07 [09:00:07.038] INFO: e2e:e2e_snapshot_sync Handing over to governance 09:00:07 [09:00:07.070] INFO: e2e:e2e_snapshot_sync Calling evm_setNextBlockTimestamp with params: [1749805213] 09:00:07 [09:00:07.073] INFO: e2e:e2e_snapshot_sync Calling hardhat_mine with params: [1] 09:00:07 [09:00:07.079] INFO: e2e:e2e_snapshot_sync Jumped to slot 1 09:00:07 [09:00:07.095] INFO: aztecjs:utils:watcher Watcher not started because not auto mining 09:00:07 [09:00:07.096] INFO: telemetry:client Using NoopTelemetryClient 09:00:07 [09:00:07.100] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/874331ef34afe4fd/blob-sink with map size 134217728 KB (LMDB v2) 09:00:07 [09:00:07.106] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 09:00:07 [09:00:07.119] INFO: blob-sink:server Server is running on http://localhost:33057 09:00:07 [09:00:07.119] VERBOSE: e2e:e2e_snapshot_sync Creating and synching an aztec node... 09:00:07 [09:00:07.120] VERBOSE: e2e:e2e_snapshot_sync Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/dafbd44f/acvm 09:00:07 [09:00:07.122] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:33057"} 09:00:07 [09:00:07.151] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 09:00:07 [09:00:07.151] INFO: archiver:lmdb Creating archiver data store at directory /tmp/874331ef34afe4fd/archiver with map size 134217728 KB (LMDB v2) 09:00:07 [09:00:07.157] INFO: archiver:lmdb Starting data store with maxReaders 16 09:00:08 [09:00:08.178] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:33057"} 09:00:08 [09:00:08.209] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:33057"} 09:00:08 [09:00:08.209] WARN: node:blob-sink:client No L1 consensus host urls configured 09:00:08 [09:00:08.210] WARN: node:blob-sink:client No archive client configured 09:00:08 [09:00:08.215] INFO: archiver Starting archiver sync to rollup contract 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 from L1 block 4 to current L1 block 6 with hash 0x594b68a679d9a1b0865c011c2e78dbf92191a214e935d4f74751239d6f806711 {"blocksSynchedTo":4,"messagesSynchedTo":{"l1BlockHash":"0x58a78c19cdb3514cb83906a71930c512e3b996a3d555a191a2dc57d4776fc273","l1BlockNumber":4}} 09:00:08 [09:00:08.240] INFO: archiver Initial archiver sync to L1 block 6 complete. {"l1BlockNumber":6,"syncPoint":{"blocksSynchedTo":6,"messagesSynchedTo":{"l1BlockHash":"0x594b68a679d9a1b0865c011c2e78dbf92191a214e935d4f74751239d6f806711","l1BlockNumber":6}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 09:00:08 [09:00:08.259] INFO: world-state:database Creating world state data store at directory /tmp/874331ef34afe4fd/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 09:00:08 [09:00:08.303] INFO: world_state Created world state synchroniser with block history of 64 09:00:08 [09:00:08.303] WARN: node Aztec node is accepting fake proofs 09:00:08 [09:00:08.327] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/874331ef34afe4fd/p2p with map size 134217728 KB (LMDB v2) 09:00:08 [09:00:08.332] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 09:00:08 [09:00:08.335] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/874331ef34afe4fd/p2p-archive with map size 134217728 KB (LMDB v2) 09:00:08 [09:00:08.338] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 09:00:08 [09:00:08.340] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/874331ef34afe4fd/p2p-peers with map size 134217728 KB (LMDB v2) 09:00:08 [09:00:08.344] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 09:00:08 [09:00:08.345] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 09:00:08 [09:00:08.346] VERBOSE: p2p P2P is disabled. Using dummy P2P service 09:00:08 [09:00:08.352] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 09:00:08 [09:00:08.354] INFO: world_state Started world state synchronizer from block 1 09:00:08 [09:00:08.358] VERBOSE: world-state:block_stream Starting sync from block number 0 09:00:08 [09:00:08.360] INFO: p2p Starting p2p client from block 0 with empty mempools 09:00:08 [09:00:08.360] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 09:00:08 [09:00:08.711] INFO: epoch-prune-watcher EpochPruneWatcher initialized 09:00:08 [09:00:08.753] VERBOSE: validator Initialized validator with addresses: 0x9d392243bfb86b6e547f95250922dbbb45132723 09:00:08 [09:00:08.754] VERBOSE: node All Aztec Node subsystems synced 09:00:08 [09:00:08.766] INFO: slasher Starting Slasher client... 09:00:08 [09:00:08.777] INFO: sequencer-client Deploying forwarder contract 09:00:08 [09:00:08.779] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 09:00:08 [09:00:08.792] VERBOSE: sequencer-client Sent L1 transaction 0x3e1ef8d23ca5a131e6a5da8b4a3fb4e426e92bd5eb69ea0c6492670affab3fb2 {"gasLimit":323704,"maxFeePerGas":"2.265827991","maxPriorityFeePerGas":"1.2"} 09:00:08 [09:00:08.793] VERBOSE: sequencer-client Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000042 to address 0x0fe4d493871B0C51332De8A330B02BA8172Da4aC in tx 0x3e1ef8d23ca5a131e6a5da8b4a3fb4e426e92bd5eb69ea0c6492670affab3fb2. 09:00:16 [09:00:16.804] INFO: sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac 09:00:16 [09:00:16.816] INFO: validator Started validator with addresses: 0x9d392243bfb86b6e547f95250922dbbb45132723 09:00:16 [09:00:16.817] VERBOSE: validator Validators 0x9d392243bfb86b6e547f95250922dbbb45132723 are not on the validator committee for epoch 0 09:00:16 [09:00:16.817] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":0,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/dafbd44f/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":8,"enforceTimeTable":false} 09:00:16 [09:00:16.818] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 09:00:16 [09:00:16.820] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 09:00:16 [09:00:16.821] INFO: node Aztec Node version: 0.87.6 09:00:16 [09:00:16.821] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0x992d1f24cc5f368188d936cf0b2a111cee586d4e","governanceAddress":"0x506150cab49dbc09c8b79d20ac5c79ca28220aea","governanceProposerAddress":"0xfdb60896b1920a10fd3aa268a292e51ebd5568a8","rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36","inboxAddress":"0x612598d664a21aa9baac2ae3f9fc2e6c41abb37d","outboxAddress":"0x014f72a899dd14c0b660e4b95bae8f8691946997","feeJuicePortalAddress":"0x56244b274ed63bd682acae796efe16586ed21a45","feeJuiceAddress":"0xd8b56421e9b573ae62f2997b7b58858a4413a967","stakingAssetAddress":"0x026208fee3f4461826c8838c3569272e8c08e131","rewardDistributorAddress":"0xd462e23c2c8a9b4f83de76004a170d4cebb5d3e1","gseAddress":"0xad2120681b02de3e9b8afd325977cdeddf15f01c","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0xefba96d6d8a7783972bedfc1c73935c3fe218d91","feeAssetHandlerAddress":"0x3607dbafde17871e0d6a8f929151c30b83510d39","stakingAssetHandlerAddress":"0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"} 09:00:16 [09:00:16.822] VERBOSE: e2e:e2e_snapshot_sync Creating a pxe... 09:00:16 [09:00:16.829] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/30e81fc0dab60380/pxe_data with map size 134217728 KB (LMDB v2) 09:00:16 [09:00:16.835] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:16 [09:00:16.838] INFO: pxe:data:lmdb Starting data store with maxReaders 16 09:00:17 [09:00:17.318] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 09:00:17 [09:00:17.319] INFO: pxe:service Started PXE connected to chain 31337 version 788754797 09:00:17 [09:00:17.322] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync waits until a few L2 blocks have been mined and purges blobs 09:00:17 [09:00:17.323] WARN: e2e:e2e_snapshot_sync Waiting for L2 blocks to be mined 09:00:17 [09:00:17.343] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:17 [09:00:17.549] INFO: e2e:e2e_snapshot_sync L1 block 7 mined at 09:00:21 with new L2 block 0 for epoch 0 with proof up to L2 block 0 for epoch 0 {"l1Timestamp":1749805221,"l1BlockNumber":7,"l2SlotNumber":1,"l2BlockNumber":0,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:17 [09:00:17.850] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:18 [09:00:18.357] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:18 [09:00:18.864] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:19 [09:00:19.374] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:19 [09:00:19.883] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:20 [09:00:20.393] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:20 [09:00:20.901] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:21 [09:00:21.419] VERBOSE: sequencer Preparing proposal for block 1 at slot 2 {"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749805229,"version":788754797},"chainTipArchive":"0x1f9c798be7975bb34c3e605a4c92c75796eae7b9a08644bc9a6a55354ed470be","blockNumber":1,"slot":2} 09:00:21 [09:00:21.432] VERBOSE: block-builder Building block 1 for slot 2 {"slot":2,"slotStart":"2025-06-13T09:00:29.000Z","now":"2025-06-13T09:00:21.432Z","blockNumber":1,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:00:21 [09:00:21.436] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.0011525429999965126s {"duration":0.0011525429999965126,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 09:00:21 [09:00:21.451] INFO: sequencer Built block 1 for slot 2 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x2c48f3b6c2dafd8d7d4511e3424750a3f44504f692bca29325d4363dbc808d07","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749805229,"version":788754797},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":26.72592600000644,"publicProcessDuration":1.9108980000019073,"rollupCircuitsDuration":20.20528299999569,"txCount":0,"blockNumber":1,"blockTimestamp":1749805229,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:21 [09:00:21.454] VERBOSE: sequencer Attesting committee is empty 09:00:21 [09:00:21.943] INFO: e2e:e2e_snapshot_sync L1 block 8 mined at 09:00:29 {"l1Timestamp":1749805229,"l1BlockNumber":8,"l2SlotNumber":2,"l2BlockNumber":0,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:23 [09:00:23.954] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x7d0b0a60f433c2112e368773e2e646ff5316c7175213c893c533bb6c4e60e725 09:00:23 [09:00:23.955] VERBOSE: node Sent L1 transaction 0x7d0b0a60f433c2112e368773e2e646ff5316c7175213c893c533bb6c4e60e725 {"gasLimit":485524,"maxFeePerGas":"2.37259949","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 09:00:29 [09:00:29.958] INFO: e2e:e2e_snapshot_sync L1 block 9 mined at 09:00:37 with new L2 block 1 for epoch 0 {"l1Timestamp":1749805237,"l1BlockNumber":9,"l2SlotNumber":2,"l2BlockNumber":1,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:30 [09:00:30.024] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:00:30 [09:00:30.025] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x7d0b0a60f433c2112e368773e2e646ff5316c7175213c893c533bb6c4e60e725","calldataSize":1220,"calldataGas":8840,"txCount":0,"blockNumber":1,"blockTimestamp":1749805229,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:30 [09:00:30.026] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:00:30 [09:00:30.060] INFO: blob-sink:server Received blob sidecar for block 0xcde9254822de3f2fee01cfaf66e2fb55074c50e7e4ede0cbdac60da5d3c29696 09:00:30 [09:00:30.073] INFO: blob-sink:server Blob sidecar stored successfully for block 0xcde9254822de3f2fee01cfaf66e2fb55074c50e7e4ede0cbdac60da5d3c29696 09:00:30 [09:00:30.150] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x2c48f3b6c2dafd8d7d4511e3424750a3f44504f692bca29325d4363dbc808d07","blockNumber":1,"txCount":0,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749805229,"version":788754797},"archiveRoot":"0x0fa29aeb06918cdeaef2f52560cefb5deb9a85de95c468063930903164725395","archiveNextLeafIndex":2} 09:00:30 [09:00:30.212] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":8.630386000004364,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":1,"blockTimestamp":1749805229,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:30 [09:00:30.224] VERBOSE: p2p Synched to latest block 1 09:00:30 [09:00:30.557] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:31 [09:00:31.072] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:31 [09:00:31.589] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:32 [09:00:32.100] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:32 [09:00:32.610] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:33 [09:00:33.124] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:33 [09:00:33.635] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:34 [09:00:34.147] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:34 [09:00:34.663] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:35 [09:00:35.184] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:35 [09:00:35.697] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:36 [09:00:36.206] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:36 [09:00:36.719] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 09:00:37 [09:00:37.234] VERBOSE: sequencer Preparing proposal for block 2 at slot 3 {"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749805245,"version":788754797},"chainTipArchive":"0x0fa29aeb06918cdeaef2f52560cefb5deb9a85de95c468063930903164725395","blockNumber":2,"slot":3} 09:00:37 [09:00:37.246] VERBOSE: block-builder Building block 2 for slot 3 {"slot":3,"slotStart":"2025-06-13T09:00:45.000Z","now":"2025-06-13T09:00:37.246Z","blockNumber":2,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:00:37 [09:00:37.250] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.0004939460000023246s {"duration":0.0004939460000023246,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 09:00:37 [09:00:37.268] INFO: sequencer Built block 2 for slot 3 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x301e138a366ca8d05af769fc5ee85898bd774b5ae108f1a5d9b2a8027cbfd73f","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749805245,"version":788754797},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":27.184823000003234,"publicProcessDuration":0.9790110000030836,"rollupCircuitsDuration":21.823944000003394,"txCount":0,"blockNumber":2,"blockTimestamp":1749805245,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:37 [09:00:37.270] VERBOSE: sequencer Attesting committee is empty 09:00:37 [09:00:37.341] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xfdf5aeb97d34aef4f31e30313710c58a53bae5ac76aa42d8326978bb92b3fe25 09:00:37 [09:00:37.341] VERBOSE: node Sent L1 transaction 0xfdf5aeb97d34aef4f31e30313710c58a53bae5ac76aa42d8326978bb92b3fe25 {"gasLimit":414472,"maxFeePerGas":"2.443834904","maxPriorityFeePerGas":"1.728","maxFeePerBlobGas":"0.000000001"} 09:00:37 [09:00:37.965] INFO: e2e:e2e_snapshot_sync L1 block 10 mined at 09:00:45 with new L2 block 2 for epoch 0 {"l1Timestamp":1749805245,"l1BlockNumber":10,"l2SlotNumber":3,"l2BlockNumber":2,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:38 [09:00:38.276] WARN: node:blob-sink:client Unable to get blob sidecar for 0x17d1f5d75350af8c518fc12aff675ef180226df2d53766bec2c2ee3febb4b84e: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 09:00:38 [09:00:38.277] WARN: node:blob-sink:client Failed to fetch blobs for 0x17d1f5d75350af8c518fc12aff675ef180226df2d53766bec2c2ee3febb4b84e from all blob sources {"blobSinkUrl":"http://localhost:33057"} 09:00:38 [09:00:38.371] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:00:38 [09:00:38.372] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xfdf5aeb97d34aef4f31e30313710c58a53bae5ac76aa42d8326978bb92b3fe25","calldataSize":1220,"calldataGas":8828,"txCount":0,"blockNumber":2,"blockTimestamp":1749805245,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:38 [09:00:38.372] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:00:38 [09:00:38.389] INFO: blob-sink:server Received blob sidecar for block 0x17d1f5d75350af8c518fc12aff675ef180226df2d53766bec2c2ee3febb4b84e 09:00:38 [09:00:38.407] INFO: blob-sink:server Blob sidecar stored successfully for block 0x17d1f5d75350af8c518fc12aff675ef180226df2d53766bec2c2ee3febb4b84e 09:00:38 [09:00:38.858] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x301e138a366ca8d05af769fc5ee85898bd774b5ae108f1a5d9b2a8027cbfd73f","blockNumber":2,"txCount":0,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749805245,"version":788754797},"archiveRoot":"0x24a647bfd9b2201d00d60ae7f0be37f5d8d78c0dc7394543c94ebdceb09990b7","archiveNextLeafIndex":3} 09:00:38 [09:00:38.878] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":8.066175999992993,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":2,"blockTimestamp":1749805245,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:38 [09:00:38.900] VERBOSE: p2p Synched to latest block 2 09:00:38 [09:00:38.923] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:38 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:38 [09:00:38.924] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:39 [09:00:39.438] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:39 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:39 [09:00:39.439] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:39 [09:00:39.963] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:39 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:39 [09:00:39.963] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:40 [09:00:40.479] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:40 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:40 [09:00:40.480] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:40 [09:00:40.999] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:40 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:40 [09:00:41.000] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:41 [09:00:41.524] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:41 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:41 [09:00:41.526] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:42 [09:00:42.041] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:42 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:42 [09:00:42.042] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:42 [09:00:42.564] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:42 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:42 [09:00:42.565] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:43 [09:00:43.081] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:43 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:43 [09:00:43.082] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:43 [09:00:43.600] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:43 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:43 [09:00:43.600] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:44 [09:00:44.146] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:44 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:44 [09:00:44.147] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:44 [09:00:44.664] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:44 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:44 [09:00:44.664] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:45 [09:00:45.183] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:45 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:45 [09:00:45.183] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:45 [09:00:45.699] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:45 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:45 [09:00:45.700] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:45 [09:00:45.783] INFO: e2e:e2e_snapshot_sync L1 block 11 mined at 09:00:53 {"l1Timestamp":1749805253,"l1BlockNumber":11,"l2SlotNumber":3,"l2BlockNumber":2,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:46 [09:00:46.237] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:46 [09:00:46.765] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:47 [09:00:47.291] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:47 [09:00:47.802] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:48 [09:00:48.348] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:48 [09:00:48.872] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:49 [09:00:49.387] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:49 [09:00:49.929] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:50 [09:00:50.440] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:50 [09:00:50.957] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:51 [09:00:51.470] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:51 [09:00:51.987] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:52 [09:00:52.499] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 09:00:53 [09:00:53.020] VERBOSE: sequencer Preparing proposal for block 3 at slot 4 {"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749805261,"version":788754797},"chainTipArchive":"0x24a647bfd9b2201d00d60ae7f0be37f5d8d78c0dc7394543c94ebdceb09990b7","blockNumber":3,"slot":4} 09:00:53 [09:00:53.038] VERBOSE: block-builder Building block 3 for slot 4 {"slot":4,"slotStart":"2025-06-13T09:01:01.000Z","now":"2025-06-13T09:00:53.038Z","blockNumber":3,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:00:53 [09:00:53.043] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.0017646789999998874s {"duration":0.0017646789999998874,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 09:00:53 [09:00:53.064] INFO: sequencer Built block 3 for slot 4 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749805261,"version":788754797},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":36.07488000000012,"publicProcessDuration":2.6003210000053514,"rollupCircuitsDuration":27.310138999993796,"txCount":0,"blockNumber":3,"blockTimestamp":1749805261,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:53 [09:00:53.067] VERBOSE: sequencer Attesting committee is empty 09:00:53 [09:00:53.146] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x574c05100c19bac5918b74ba40410e0756de57fce3676c0e170652e2ce8b30fe 09:00:53 [09:00:53.146] VERBOSE: node Sent L1 transaction 0x574c05100c19bac5918b74ba40410e0756de57fce3676c0e170652e2ce8b30fe {"gasLimit":416872,"maxFeePerGas":"1.751318704","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 09:00:53 [09:00:53.802] INFO: e2e:e2e_snapshot_sync L1 block 12 mined at 09:01:01 with new L2 block 3 for epoch 0 {"l1Timestamp":1749805261,"l1BlockNumber":12,"l2SlotNumber":4,"l2BlockNumber":3,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:54 [09:00:54.177] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:00:54 [09:00:54.178] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x574c05100c19bac5918b74ba40410e0756de57fce3676c0e170652e2ce8b30fe","calldataSize":1220,"calldataGas":8828,"txCount":0,"blockNumber":3,"blockTimestamp":1749805261,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:54 [09:00:54.178] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:00:54 [09:00:54.254] INFO: blob-sink:server Received blob sidecar for block 0x2ade9d344fc63664900c03682bc829a5f2071f33ef2ff24654e5ef3cee60bf06 09:00:54 [09:00:54.267] INFO: blob-sink:server Blob sidecar stored successfully for block 0x2ade9d344fc63664900c03682bc829a5f2071f33ef2ff24654e5ef3cee60bf06 09:00:54 [09:00:54.455] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c","blockNumber":3,"txCount":0,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749805261,"version":788754797},"archiveRoot":"0x14eabb01492e25bae5015e9149d08a87f944c214c798148ec98977b80a5af46b","archiveNextLeafIndex":4} 09:00:54 [09:00:54.468] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":3.8566220000066096,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":3,"blockTimestamp":1749805261,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:54 [09:00:54.558] VERBOSE: p2p Synched to latest block 3 09:00:54 [09:00:54.768] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:54 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:54 [09:00:54.769] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:55 [09:00:55.293] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:55 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:55 [09:00:55.294] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:55 [09:00:55.806] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:55 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:55 [09:00:55.806] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:56 [09:00:56.323] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:56 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:56 [09:00:56.324] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:56 [09:00:56.843] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:56 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:56 [09:00:56.844] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:57 [09:00:57.375] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:57 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:57 [09:00:57.375] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:57 [09:00:57.894] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:57 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:57 [09:00:57.895] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:58 [09:00:58.413] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:58 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:58 [09:00:58.413] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:58 [09:00:58.928] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:58 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:58 [09:00:58.929] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:59 [09:00:59.444] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:59 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:59 [09:00:59.445] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:59 [09:00:59.977] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:59 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:59 [09:00:59.977] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:01:00 [09:01:00.491] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:00 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:01:00 [09:01:00.491] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:01:01 [09:01:01.005] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:01 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:01:01 [09:01:01.005] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:01:01 [09:01:01.518] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:01 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:01:01 [09:01:01.518] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:01:01 [09:01:01.808] INFO: e2e:e2e_snapshot_sync L1 block 13 mined at 09:01:09 {"l1Timestamp":1749805269,"l1BlockNumber":13,"l2SlotNumber":4,"l2BlockNumber":3,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:01:02 [09:01:02.032] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:02 [09:01:02.549] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:03 [09:01:03.064] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:03 [09:01:03.574] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:04 [09:01:04.084] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:04 [09:01:04.593] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:05 [09:01:05.103] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:05 [09:01:05.612] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:06 [09:01:06.125] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:06 [09:01:06.639] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:07 [09:01:07.166] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:07 [09:01:07.675] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:08 [09:01:08.184] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:08 [09:01:08.696] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:01:09 [09:01:09.214] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":251640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805277,"version":788754797},"chainTipArchive":"0x14eabb01492e25bae5015e9149d08a87f944c214c798148ec98977b80a5af46b","blockNumber":4,"slot":5} 09:01:09 [09:01:09.227] VERBOSE: block-builder Building block 4 for slot 5 {"slot":5,"slotStart":"2025-06-13T09:01:17.000Z","now":"2025-06-13T09:01:09.227Z","blockNumber":4,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:01:09 [09:01:09.230] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.0002828309999895282s {"duration":0.0002828309999895282,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 09:01:09 [09:01:09.249] INFO: sequencer Built block 4 for slot 5 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x00841f40f3952a5b18bb301a3091f41277ee9aa612554fe26ef810b140805791","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":251640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805277,"version":788754797},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":27.82546700000239,"publicProcessDuration":0.9435490000032587,"rollupCircuitsDuration":22.61077299999306,"txCount":0,"blockNumber":4,"blockTimestamp":1749805277,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:09 [09:01:09.260] VERBOSE: sequencer Attesting committee is empty 09:01:09 [09:01:09.351] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x464f02892cb8aa38803beafde2bb04e5c5ca4abc0678ffef35bd5798f59fb184 09:01:09 [09:01:09.352] VERBOSE: node Sent L1 transaction 0x464f02892cb8aa38803beafde2bb04e5c5ca4abc0678ffef35bd5798f59fb184 {"gasLimit":416899,"maxFeePerGas":"1.623245031","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 09:01:09 [09:01:09.817] INFO: e2e:e2e_snapshot_sync L1 block 14 mined at 09:01:17 with new L2 block 4 for epoch 0 {"l1Timestamp":1749805277,"l1BlockNumber":14,"l2SlotNumber":5,"l2BlockNumber":4,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:01:09 [09:01:09.852] WARN: node:blob-sink:client Unable to get blob sidecar for 0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 09:01:09 [09:01:09.852] WARN: node:blob-sink:client Failed to fetch blobs for 0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c from all blob sources {"blobSinkUrl":"http://localhost:33057"} 09:01:10 [09:01:10.366] WARN: e2e:e2e_snapshot_sync L2 block height is now 4. Purging all blobs from sink so snapshot is required. 09:01:10 [09:01:10.371] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync creates a snapshot 09:01:10 [09:01:10.371] WARN: e2e:e2e_snapshot_sync Creating snapshot 09:01:10 [09:01:10.377] INFO: stdlib:file-store Creating local file file store at /tmp/snapshots-wYD6jQ 09:01:10 [09:01:10.378] INFO: node Pausing archiver and world state sync to start snapshot upload 09:01:10 [09:01:10.399] WARN: node:blob-sink:client Unable to get blob sidecar for 0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 09:01:10 [09:01:10.400] WARN: node:blob-sink:client Failed to fetch blobs for 0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c from all blob sources {"blobSinkUrl":"http://localhost:33057"} 09:01:10 [09:01:10.400] INFO: archiver Stopped. 09:01:10 [09:01:10.401] INFO: world_state Stopped sync 09:01:10 [09:01:10.401] INFO: node Creating backups of lmdb environments to /tmp/874331ef34afe4fd/snapshot-fNiMqV 09:01:10 [09:01:10.404] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:01:10 [09:01:10.405] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x464f02892cb8aa38803beafde2bb04e5c5ca4abc0678ffef35bd5798f59fb184","calldataSize":1220,"calldataGas":8840,"txCount":0,"blockNumber":4,"blockTimestamp":1749805277,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:10 [09:01:10.405] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:01:10 [09:01:10.409] INFO: node Data stores backed up to /tmp/874331ef34afe4fd/snapshot-fNiMqV {"paths":{"l1-to-l2-message-tree":"/tmp/874331ef34afe4fd/snapshot-fNiMqV/world-state/L1ToL2MessageTree/data.mdb","archive-tree":"/tmp/874331ef34afe4fd/snapshot-fNiMqV/world-state/ArchiveTree/data.mdb","public-data-tree":"/tmp/874331ef34afe4fd/snapshot-fNiMqV/world-state/PublicDataTree/data.mdb","note-hash-tree":"/tmp/874331ef34afe4fd/snapshot-fNiMqV/world-state/NoteHashTree/data.mdb","nullifier-tree":"/tmp/874331ef34afe4fd/snapshot-fNiMqV/world-state/NullifierTree/data.mdb","archiver":"/tmp/874331ef34afe4fd/snapshot-fNiMqV/archiver/data.mdb"}} 09:01:10 [09:01:10.409] INFO: node Resuming archiver and world state sync 09:01:10 [09:01:10.409] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 09:01:10 [09:01:10.410] INFO: world_state Resumed sync 09:01:10 [09:01:10.410] INFO: archiver Restarting archiver 09:01:10 [09:01:10.411] INFO: blob-sink:server Received blob sidecar for block 0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c 09:01:10 [09:01:10.421] INFO: node Uploading snapshot to file:///tmp/snapshots-wYD6jQ {"snapshot":{"l1ChainId":31337,"rollupVersion":788754797,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36","l2BlockNumber":3,"l2BlockHash":"0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c","l1BlockNumber":13}} 09:01:10 [09:01:10.423] INFO: blob-sink:server Blob sidecar stored successfully for block 0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c 09:01:10 [09:01:10.446] INFO: node Snapshot uploaded successfully {"snapshot":{"l1BlockNumber":13,"l2BlockHash":"0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c","l2BlockNumber":3,"schemaVersions":{"archiver":2,"worldState":1},"timestamp":1749805270421,"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archive-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","public-data-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","note-hash-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","nullifier-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archiver":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db"}}} 09:01:10 [09:01:10.446] INFO: node Cleaning up backup dir /tmp/874331ef34afe4fd/snapshot-fNiMqV 09:01:10 [09:01:10.488] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x00841f40f3952a5b18bb301a3091f41277ee9aa612554fe26ef810b140805791","blockNumber":4,"txCount":0,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":251640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805277,"version":788754797},"archiveRoot":"0x2f3aa5abbd18a2a7838bc0c3e4b6fca9018573cc6b6203f2ac24170d33eecff6","archiveNextLeafIndex":5} 09:01:10 [09:01:10.530] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":4.874878999995417,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":4,"blockTimestamp":1749805277,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:10 [09:01:10.543] VERBOSE: p2p Synched to latest block 4 09:01:10 [09:01:10.928] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:10 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:10 [09:01:10.928] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:11 [09:01:11.379] WARN: e2e:e2e_snapshot_sync Snapshot created 09:01:11 [09:01:11.380] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync downloads snapshot when syncing new node 09:01:11 [09:01:11.381] WARN: e2e:e2e_snapshot_sync Syncing brand new node with snapshot sync 09:01:11 [09:01:11.381] WARN: e2e:e2e_snapshot_sync Creating and syncing a node without a validator... 09:01:11 [09:01:11.382] INFO: node:blob-sink:client:1 Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:33057"} 09:01:11 [09:01:11.423] INFO: node:1 Creating local file file store at /tmp/snapshots-wYD6jQ 09:01:11 [09:01:11.423] VERBOSE: node:1 Creating temporary archiver data store 09:01:11 [09:01:11.424] INFO: archiver:lmdb:1 Creating archiver data store at directory /tmp/874331ef34afe4fd/482d366b97bceb0c/archiver with map size 134217728 KB (LMDB v2) 09:01:11 [09:01:11.426] INFO: archiver:lmdb:1 Starting data store with maxReaders 16 09:01:11 [09:01:11.428] VERBOSE: node:1 Closing temporary archiver data store {"archiverL2BlockNumber":0} 09:01:11 [09:01:11.437] INFO: node:1 Syncing from snapshot at L1 block 13 L2 block 3 {"snapshot":{"l2BlockNumber":3,"l2BlockHash":"0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c","l1BlockNumber":13,"timestamp":1749805270421,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archive-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","public-data-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","note-hash-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","nullifier-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archiver":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db"}},"snapshotsUrl":"file:///tmp/snapshots-wYD6jQ"} 09:01:11 [09:01:11.438] INFO: node:1 Creating local file file store at /tmp/snapshots-wYD6jQ 09:01:11 [09:01:11.440] INFO: node:1 Downloading snapshot to /tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv {"snapshot":{"l2BlockNumber":3,"l2BlockHash":"0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c","l1BlockNumber":13,"timestamp":1749805270421,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archive-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","public-data-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","note-hash-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","nullifier-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archiver":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db"}},"downloadPaths":{"archiver":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/archiver.db","nullifier-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/nullifier-tree.db","public-data-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/public-data-tree.db","note-hash-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/note-hash-tree.db","archive-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/archive-tree.db","l1-to-l2-message-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/l1-to-l2-message-tree.db"}} 09:01:11 [09:01:11.447] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:11 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:11 [09:01:11.447] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:11 [09:01:11.455] INFO: node:1 Snapshot downloaded at /tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv {"snapshot":{"l2BlockNumber":3,"l2BlockHash":"0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c","l1BlockNumber":13,"timestamp":1749805270421,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archive-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","public-data-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","note-hash-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","nullifier-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archiver":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db"}},"downloadPaths":{"archiver":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/archiver.db","nullifier-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/nullifier-tree.db","public-data-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/public-data-tree.db","note-hash-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/note-hash-tree.db","archive-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/archive-tree.db","l1-to-l2-message-tree":"/tmp/874331ef34afe4fd/482d366b97bceb0c/download-BVFAIv/l1-to-l2-message-tree.db"}} 09:01:11 [09:01:11.463] INFO: node:1 Archiver database set up from snapshot {"path":"/tmp/874331ef34afe4fd/482d366b97bceb0c/archiver","dbVersion":2,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:11 [09:01:11.468] INFO: node:1 World state database l1-to-l2-message-tree set up from snapshot {"path":"/tmp/874331ef34afe4fd/482d366b97bceb0c/world_state/L1ToL2MessageTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:11 [09:01:11.469] INFO: node:1 World state database archive-tree set up from snapshot {"path":"/tmp/874331ef34afe4fd/482d366b97bceb0c/world_state/ArchiveTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:11 [09:01:11.470] INFO: node:1 World state database public-data-tree set up from snapshot {"path":"/tmp/874331ef34afe4fd/482d366b97bceb0c/world_state/PublicDataTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:11 [09:01:11.471] INFO: node:1 World state database note-hash-tree set up from snapshot {"path":"/tmp/874331ef34afe4fd/482d366b97bceb0c/world_state/NoteHashTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:11 [09:01:11.472] INFO: node:1 World state database nullifier-tree set up from snapshot {"path":"/tmp/874331ef34afe4fd/482d366b97bceb0c/world_state/NullifierTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:11 [09:01:11.473] INFO: node:1 P2P database cleared {"path":"/tmp/874331ef34afe4fd/482d366b97bceb0c/p2p"} 09:01:11 [09:01:11.476] INFO: node:1 Snapshot synced to L1 block 13 L2 block 3 {"snapshot":{"l2BlockNumber":3,"l2BlockHash":"0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c","l1BlockNumber":13,"timestamp":1749805270421,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archive-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","public-data-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","note-hash-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","nullifier-tree":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db","archiver":"file:///tmp/snapshots-wYD6jQ/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090110-0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c.db"}}} 09:01:11 [09:01:11.479] INFO: archiver:lmdb:1 Creating archiver data store at directory /tmp/874331ef34afe4fd/482d366b97bceb0c/archiver with map size 134217728 KB (LMDB v2) 09:01:11 [09:01:11.481] INFO: archiver:lmdb:1 Starting data store with maxReaders 16 09:01:11 [09:01:11.827] INFO: node:blob-sink:client:1 Testing configured blob sources {"blobSinkUrl":"http://localhost:33057"} 09:01:11 [09:01:11.830] INFO: node:blob-sink:client:1 Blob sink is reachable {"blobSinkUrl":"http://localhost:33057"} 09:01:11 [09:01:11.831] WARN: node:blob-sink:client:1 No L1 consensus host urls configured 09:01:11 [09:01:11.831] WARN: node:blob-sink:client:1 No archive client configured 09:01:11 [09:01:11.841] INFO: archiver:1 Starting archiver sync to rollup contract 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 from L1 block 12 to current L1 block 14 with hash 0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c {"blocksSynchedTo":12,"messagesSynchedTo":{"l1BlockHash":"0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c","l1BlockNumber":14}} 09:01:11 [09:01:11.928] INFO: archiver:1 Downloaded L2 block 4 {"blockHash":"0x00841f40f3952a5b18bb301a3091f41277ee9aa612554fe26ef810b140805791","blockNumber":4,"txCount":0,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":251640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805277,"version":788754797},"archiveRoot":"0x2f3aa5abbd18a2a7838bc0c3e4b6fca9018573cc6b6203f2ac24170d33eecff6","archiveNextLeafIndex":5} 09:01:11 [09:01:11.935] INFO: archiver:1 Initial archiver sync to L1 block 14 complete. {"l1BlockNumber":14,"syncPoint":{"blocksSynchedTo":14,"messagesSynchedTo":{"l1BlockHash":"0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c","l1BlockNumber":14}},"latest":{"number":4,"hash":"0x00841f40f3952a5b18bb301a3091f41277ee9aa612554fe26ef810b140805791"},"proven":{"number":0},"finalized":{"number":0}} 09:01:11 [09:01:11.943] INFO: world-state:database:1 Creating world state data store at directory /tmp/874331ef34afe4fd/482d366b97bceb0c/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 09:01:11 [09:01:11.965] INFO: world_state:1 Created world state synchroniser with block history of 64 09:01:11 [09:01:11.966] WARN: node:1 Aztec node is accepting fake proofs 09:01:11 [09:01:11.984] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:11 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:11 [09:01:11.984] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:11 [09:01:11.985] INFO: p2p:lmdb-v2:1 Creating p2p data store at directory /tmp/874331ef34afe4fd/482d366b97bceb0c/p2p with map size 134217728 KB (LMDB v2) 09:01:11 [09:01:11.989] INFO: p2p:lmdb-v2:1 Starting data store with maxReaders 16 09:01:11 [09:01:11.991] INFO: p2p-archive:lmdb-v2:1 Creating p2p-archive data store at directory /tmp/874331ef34afe4fd/482d366b97bceb0c/p2p-archive with map size 134217728 KB (LMDB v2) 09:01:11 [09:01:11.995] INFO: p2p-archive:lmdb-v2:1 Starting data store with maxReaders 16 09:01:11 [09:01:11.997] INFO: p2p-peer:lmdb-v2:1 Creating p2p-peers data store at directory /tmp/874331ef34afe4fd/482d366b97bceb0c/p2p-peers with map size 134217728 KB (LMDB v2) 09:01:12 [09:01:12.000] INFO: p2p-peer:lmdb-v2:1 Starting data store with maxReaders 16 09:01:12 [09:01:12.004] INFO: p2p:tx_pool:1 Setting maximum tx mempool size {"maxTxPoolSize":100000000} 09:01:12 [09:01:12.005] VERBOSE: p2p:1 P2P is disabled. Using dummy P2P service 09:01:12 [09:01:12.006] VERBOSE: world_state:1 Starting sync from 4 to latest block 4 09:01:12 [09:01:12.007] VERBOSE: world-state:block_stream:1 Starting L2 block stream {"proven":false,"pollIntervalMS":100} 09:01:12 [09:01:12.007] INFO: world_state:1 Started world state synchronizer from block 4 09:01:12 [09:01:12.010] VERBOSE: world-state:block_stream:1 Starting sync from block number 3 09:01:12 [09:01:12.015] INFO: world_state:1 World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":3.564822999993339,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":4,"blockTimestamp":1749805277,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:12 [09:01:12.019] INFO: p2p:1 Starting p2p client from block 4 with empty mempools 09:01:12 [09:01:12.019] VERBOSE: p2p:l2-block-stream:1 Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":4} 09:01:12 [09:01:12.020] INFO: epoch-prune-watcher:1 EpochPruneWatcher initialized 09:01:12 [09:01:12.020] VERBOSE: node:1 All Aztec Node subsystems synced 09:01:12 [09:01:12.029] INFO: slasher:1 Starting Slasher client... 09:01:12 [09:01:12.032] INFO: node:1 Aztec Node version: 0.87.6 09:01:12 [09:01:12.032] INFO: node:1 Aztec Node started on chain 0x7a69 {"registryAddress":"0x992d1f24cc5f368188d936cf0b2a111cee586d4e","governanceAddress":"0x506150cab49dbc09c8b79d20ac5c79ca28220aea","governanceProposerAddress":"0xfdb60896b1920a10fd3aa268a292e51ebd5568a8","rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36","inboxAddress":"0x612598d664a21aa9baac2ae3f9fc2e6c41abb37d","outboxAddress":"0x014f72a899dd14c0b660e4b95bae8f8691946997","feeJuicePortalAddress":"0x56244b274ed63bd682acae796efe16586ed21a45","feeJuiceAddress":"0xd8b56421e9b573ae62f2997b7b58858a4413a967","stakingAssetAddress":"0x026208fee3f4461826c8838c3569272e8c08e131","rewardDistributorAddress":"0xd462e23c2c8a9b4f83de76004a170d4cebb5d3e1","gseAddress":"0xad2120681b02de3e9b8afd325977cdeddf15f01c","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0xefba96d6d8a7783972bedfc1c73935c3fe218d91","feeAssetHandlerAddress":"0x3607dbafde17871e0d6a8f929151c30b83510d39","stakingAssetHandlerAddress":"0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"} 09:01:12 [09:01:12.034] WARN: e2e:e2e_snapshot_sync New node synced 09:01:12 [09:01:12.036] VERBOSE: p2p:l2-block-stream:1 Starting sync from block number 3 09:01:12 [09:01:12.049] WARN: e2e:e2e_snapshot_sync Checking for L2 block 3 with hash 0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c on both nodes 09:01:12 [09:01:12.050] VERBOSE: p2p:1 Synched to latest block 4 09:01:12 [09:01:12.055] WARN: e2e:e2e_snapshot_sync Stopping new node 09:01:12 [09:01:12.056] INFO: node:1 Stopping Aztec Node 09:01:12 [09:01:12.497] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:12 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:12 [09:01:12.498] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:13 [09:01:13.013] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:13 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:13 [09:01:13.013] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:13 [09:01:13.527] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:13 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:13 [09:01:13.527] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:14 [09:01:14.038] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:14 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:14 [09:01:14.038] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:14 [09:01:14.058] INFO: slasher:1 Slasher client stopped. 09:01:14 [09:01:14.059] INFO: p2p:1 P2P client stopped 09:01:14 [09:01:14.061] INFO: world_state:1 Stopped world state synchronizer 09:01:14 [09:01:14.061] INFO: archiver:1 Stopped. 09:01:14 [09:01:14.062] INFO: node:1 Stopped Aztec Node 09:01:14 [09:01:14.063] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync downloads snapshot when syncing new prover node 09:01:14 [09:01:14.063] WARN: e2e:e2e_snapshot_sync Syncing brand new prover node with snapshot sync 09:01:14 [09:01:14.063] WARN: e2e:e2e_snapshot_sync Creating and syncing a prover node... 09:01:14 [09:01:14.121] INFO: blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:33057"} 09:01:14 [09:01:14.126] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/874331ef34afe4fd/d54d739e9fb6e9e5/archiver with map size 134217728 KB (LMDB v2) 09:01:14 [09:01:14.128] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 09:01:14 [09:01:14.476] INFO: blob-sink:client:prover-node Testing configured blob sources {"blobSinkUrl":"http://localhost:33057"} 09:01:14 [09:01:14.480] INFO: blob-sink:client:prover-node Blob sink is reachable {"blobSinkUrl":"http://localhost:33057"} 09:01:14 [09:01:14.480] WARN: blob-sink:client:prover-node No L1 consensus host urls configured 09:01:14 [09:01:14.480] WARN: blob-sink:client:prover-node No archive client configured 09:01:14 [09:01:14.486] INFO: archiver:prover-node Starting archiver sync to rollup contract 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 from L1 block 4 to current L1 block 14 with hash 0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c {"blocksSynchedTo":4,"messagesSynchedTo":{"l1BlockHash":"0x58a78c19cdb3514cb83906a71930c512e3b996a3d555a191a2dc57d4776fc273","l1BlockNumber":4}} 09:01:14 [09:01:14.659] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:14 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:14 [09:01:14.660] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:14 [09:01:14.669] INFO: archiver:prover-node Downloaded L2 block 1 {"blockHash":"0x2c48f3b6c2dafd8d7d4511e3424750a3f44504f692bca29325d4363dbc808d07","blockNumber":1,"txCount":0,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749805229,"version":788754797},"archiveRoot":"0x0fa29aeb06918cdeaef2f52560cefb5deb9a85de95c468063930903164725395","archiveNextLeafIndex":2} 09:01:14 [09:01:14.669] INFO: archiver:prover-node Downloaded L2 block 2 {"blockHash":"0x301e138a366ca8d05af769fc5ee85898bd774b5ae108f1a5d9b2a8027cbfd73f","blockNumber":2,"txCount":0,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749805245,"version":788754797},"archiveRoot":"0x24a647bfd9b2201d00d60ae7f0be37f5d8d78c0dc7394543c94ebdceb09990b7","archiveNextLeafIndex":3} 09:01:14 [09:01:14.670] INFO: archiver:prover-node Downloaded L2 block 3 {"blockHash":"0x15bd24df652e00c4b0f499f3cca6dc72769d9a573b0d5d5ca99e46bf00b6347c","blockNumber":3,"txCount":0,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749805261,"version":788754797},"archiveRoot":"0x14eabb01492e25bae5015e9149d08a87f944c214c798148ec98977b80a5af46b","archiveNextLeafIndex":4} 09:01:14 [09:01:14.670] INFO: archiver:prover-node Downloaded L2 block 4 {"blockHash":"0x00841f40f3952a5b18bb301a3091f41277ee9aa612554fe26ef810b140805791","blockNumber":4,"txCount":0,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":251640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805277,"version":788754797},"archiveRoot":"0x2f3aa5abbd18a2a7838bc0c3e4b6fca9018573cc6b6203f2ac24170d33eecff6","archiveNextLeafIndex":5} 09:01:14 [09:01:14.676] INFO: archiver:prover-node Initial archiver sync to L1 block 14 complete. {"l1BlockNumber":14,"syncPoint":{"blocksSynchedTo":14,"messagesSynchedTo":{"l1BlockHash":"0x09d3961f2fb94bc5e7d94a54a990ac4187b74ab469033501450cedb644ca162c","l1BlockNumber":14}},"latest":{"number":4,"hash":"0x00841f40f3952a5b18bb301a3091f41277ee9aa612554fe26ef810b140805791"},"proven":{"number":0},"finalized":{"number":0}} 09:01:14 [09:01:14.680] INFO: prover-node:blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:33057"} 09:01:14 [09:01:14.680] INFO: prover-node:prover-node Creating local file file store at /tmp/snapshots-wYD6jQ 09:01:14 [09:01:14.681] VERBOSE: prover-node:prover-node Creating temporary archiver data store 09:01:14 [09:01:14.682] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/874331ef34afe4fd/d54d739e9fb6e9e5/archiver with map size 134217728 KB (LMDB v2) 09:01:14 [09:01:14.687] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 09:01:14 [09:01:14.690] VERBOSE: prover-node:prover-node Closing temporary archiver data store {"archiverL1BlockNumber":14,"archiverL2BlockNumber":4} 09:01:14 [09:01:14.690] VERBOSE: prover-node:prover-node Skipping non-forced snapshot sync as archiver is already synced to L2 block 4. 09:01:14 [09:01:14.691] VERBOSE: prover-node:prover-node Created archiver and synced to block 4 09:01:14 [09:01:14.696] INFO: world-state:database:prover-node Creating world state data store at directory /tmp/874331ef34afe4fd/d54d739e9fb6e9e5/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 09:01:14 [09:01:14.719] INFO: world_state:prover-node Created world state synchroniser with block history of 64 09:01:14 [09:01:14.720] VERBOSE: world_state:prover-node Starting sync from 1 to latest block 4 09:01:14 [09:01:14.720] VERBOSE: world-state:block_stream:prover-node Starting L2 block stream {"proven":false,"pollIntervalMS":100} 09:01:14 [09:01:14.720] INFO: world_state:prover-node Started world state synchronizer from block 1 09:01:14 [09:01:14.722] VERBOSE: world-state:block_stream:prover-node Starting sync from block number 0 09:01:14 [09:01:14.728] INFO: world_state:prover-node World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":3.3540959999954794,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":1,"blockTimestamp":1749805229,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:14 [09:01:14.731] INFO: world_state:prover-node World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.7561529999948107,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":2,"blockTimestamp":1749805245,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:14 [09:01:14.734] INFO: world_state:prover-node World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.392305999994278,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":3,"blockTimestamp":1749805261,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:14 [09:01:14.737] INFO: world_state:prover-node World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.681937999994261,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":4,"blockTimestamp":1749805277,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:14 [09:01:14.738] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/874331ef34afe4fd/d54d739e9fb6e9e5/archiver when loading epoch databases, ignoring 09:01:14 [09:01:14.738] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/874331ef34afe4fd/d54d739e9fb6e9e5/world_state when loading epoch databases, ignoring 09:01:14 [09:01:14.739] INFO: prover-client:proving-broker:prover-node Proving Broker started 09:01:14 [09:01:14.751] INFO: prover-node:prover-coordination:prover-node Using prover coordination via aztec node 09:01:14 [09:01:14.753] INFO: e2e:e2e_snapshot_sync:prover-node Created and synced prover node {"publisherAddress":"0x9965507D1a55bcC2695C58ba16FB37d819B0A4dc"} 09:01:14 [09:01:14.753] INFO: prover-node:epoch-monitor:prover-node Started EpochMonitor {"pollingIntervalMs":200} 09:01:14 [09:01:14.757] INFO: prover-node:prover-node Started Prover Node with prover id 0x0000000000000000000000009965507d1a55bcc2695c58ba16fb37d819b0a4dc {"proverNodePollingIntervalMs":200,"proverNodeMaxPendingJobs":10,"proverNodeMaxParallelBlocksPerEpoch":32,"txGatheringIntervalMs":1000,"txGatheringBatchSize":10,"txGatheringMaxParallelRequestsPerNode":100,"dataDirectory":"/tmp/874331ef34afe4fd/d54d739e9fb6e9e5","l1ChainId":31337,"rollupVersion":788754797} 09:01:14 [09:01:14.757] WARN: e2e:e2e_snapshot_sync New node prover synced 09:01:14 [09:01:14.758] VERBOSE: prover-node:prover-node Fetching 0 tx hashes for block number 4 from coordination 09:01:14 [09:01:14.759] INFO: prover-node:combined-prover-coordination:prover-node Check for 0 txs found all in the pool 09:01:14 [09:01:14.761] WARN: e2e:e2e_snapshot_sync Stopping new prover node 09:01:14 [09:01:14.762] INFO: prover-node:prover-node Stopping ProverNode 09:01:14 [09:01:14.762] INFO: prover-node:epoch-monitor:prover-node Stopped EpochMonitor 09:01:14 [09:01:14.762] INFO: archiver:prover-node Stopped. 09:01:14 [09:01:14.764] INFO: world_state:prover-node Stopped world state synchronizer 09:01:14 [09:01:14.764] INFO: prover-node:prover-node Stopped ProverNode 09:01:14 [09:01:14.767] INFO: node Stopping Aztec Node 09:01:15 [09:01:15.172] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:15 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:15 [09:01:15.172] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:15 [09:01:15.694] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:15 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:15 [09:01:15.694] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:16 [09:01:16.211] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:16 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:16 [09:01:16.211] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:16 [09:01:16.728] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:16 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:16 [09:01:16.729] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:16 [09:01:16.768] INFO: slasher Slasher client stopped. 09:01:16 [09:01:16.771] INFO: sequencer Stopped sequencer 09:01:16 [09:01:16.771] INFO: p2p P2P client stopped 09:01:16 [09:01:16.774] INFO: world_state Stopped world state synchronizer 09:01:16 [09:01:16.774] INFO: archiver Stopped. 09:01:16 [09:01:16.775] INFO: node Stopped Aztec Node 09:01:16 [09:01:16.789] INFO: blob-sink:server Stopping blob sink 09:01:16 [09:01:16.790] INFO: blob-sink:server Blob sink stopped 09:01:16 PASS src/e2e_snapshot_sync.test.ts 09:01:16 e2e_snapshot_sync 09:01:16 ✓ waits until a few L2 blocks have been mined and purges blobs (53046 ms) 09:01:16 ✓ creates a snapshot (1009 ms) 09:01:16 ✓ downloads snapshot when syncing new node (2682 ms) 09:01:16 ✓ downloads snapshot when syncing new prover node (702 ms) 09:01:16 09:01:16 Test Suites: 1 passed, 1 total 09:01:16 Tests: 4 passed, 4 total 09:01:16 Snapshots: 0 total 09:01:16 Time: 117.563 s 09:01:16 Ran all test suites matching /src\/e2e_snapshot_sync.test.ts/i. 09:01:16 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?