Parent Log: http://ci.aztec-labs.com/2df2f23eb2ea1efb 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:08 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14995_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_eb8f79a88ef38365_next 08:59:08 +++ id -u 08:59:08 +++ id -g 08:59:08 ++ 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:09 + cid=5c4fea552c7389239e0474782b0b6fd56bf91106154c70f0673439d9c3741874 08:59:09 + set +x 08:59:09 {"level":25,"time":1749805149850,"pid":13,"hostname":"5c4fea552c73","module":"logger","msg":"Logger initialized with level verbose"} 08:59:16 [08:59:16.807] INFO: e2e:e2e_snapshot_sync Logging metrics to log/unknown.jsonl 08:59:17 [08:59:17.004] INFO: e2e:e2e_snapshot_sync Calling anvil_setBlockTimestampInterval with params: [8] 08:59:17 [08:59:17.039] WARN: e2e:e2e_snapshot_sync Set block interval to 8 08:59:17 [08:59:17.040] VERBOSE: e2e:e2e_snapshot_sync Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 08:59:17 [08:59:17.097] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xff8f3047f70ec9e63079d7903c02a47802dc292d5660d2da825ac209bbd9cd89 {"gasLimit":867658,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:17 [08:59:17.099] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xd8B56421e9b573AE62f2997B7b58858a4413A967 in tx 0xff8f3047f70ec9e63079d7903c02a47802dc292d5660d2da825ac209bbd9cd89. 08:59:17 [08:59:17.101] VERBOSE: e2e:e2e_snapshot_sync Deployed Fee Asset at 0xd8b56421e9b573ae62f2997b7b58858a4413a967 08:59:17 [08:59:17.130] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xb8a727e5dcee31d2ee7a6a94b8b01df7952fdc685dfb1b5bb36a0dbda9d08d89 {"gasLimit":867644,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:17 [08:59:17.131] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x026208feE3F4461826C8838C3569272E8C08E131 in tx 0xb8a727e5dcee31d2ee7a6a94b8b01df7952fdc685dfb1b5bb36a0dbda9d08d89. 08:59:17 [08:59:17.132] VERBOSE: e2e:e2e_snapshot_sync Deployed Staking Asset at 0x026208fee3f4461826c8838c3569272e8c08e131 08:59:17 [08:59:17.155] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x84345970a64bc89d244665c6d19bcf62f7a1fb0c5497fd61c66569ea96442f1f {"gasLimit":3118894,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:17 [08:59:17.155] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xad2120681B02De3e9b8aFd325977CDeDDf15f01C in tx 0x84345970a64bc89d244665c6d19bcf62f7a1fb0c5497fd61c66569ea96442f1f. 08:59:17 [08:59:17.156] VERBOSE: e2e:e2e_snapshot_sync Deployed GSE at 0xad2120681b02de3e9b8afd325977cdeddf15f01c 08:59:17 [08:59:17.175] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x96c1e759b945754976d026659a519c1c5d05a738916e69d742cdec67a5482765 {"gasLimit":1030513,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:17 [08:59:17.176] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x992D1f24CC5f368188d936cf0b2A111cEE586d4e in tx 0x96c1e759b945754976d026659a519c1c5d05a738916e69d742cdec67a5482765. 08:59:17 [08:59:17.176] VERBOSE: e2e:e2e_snapshot_sync Deployed Registry at 0x992d1f24cc5f368188d936cf0b2a111cee586d4e 08:59:17 [08:59:17.200] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x35838f7740ede8daad92b7b8589b59625f0100193e252bc6ce3862a65d23892c {"gasLimit":1998672,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:17 [08:59:17.200] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xfDB60896B1920A10Fd3AA268A292E51ebD5568A8 in tx 0x35838f7740ede8daad92b7b8589b59625f0100193e252bc6ce3862a65d23892c. 08:59:17 [08:59:17.201] VERBOSE: e2e:e2e_snapshot_sync Deployed GovernanceProposer at 0xfdb60896b1920a10fd3aa268a292e51ebd5568a8 08:59:17 [08:59:17.228] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x4dbcecd47a2081d10a38e4cf385714d92e542e5a44eb5a37a3810c6c6738dc4e {"gasLimit":3092007,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:17 [08:59:17.228] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x506150CaB49DBC09C8B79D20ac5c79CA28220AEA in tx 0x4dbcecd47a2081d10a38e4cf385714d92e542e5a44eb5a37a3810c6c6738dc4e. 08:59:17 [08:59:17.229] VERBOSE: e2e:e2e_snapshot_sync Deployed Governance at 0x506150cab49dbc09c8b79d20ac5c79ca28220aea 08:59:17 [08:59:17.238] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xf2ecc0d2040ae9928a4b186c21f02e611c2904f24b196eed69d646e9333cc00d {"gasLimit":100000,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:17 [08:59:17.239] VERBOSE: e2e:e2e_snapshot_sync Set governance on GSE in 0xf2ecc0d2040ae9928a4b186c21f02e611c2904f24b196eed69d646e9333cc00d 08:59:17 [08:59:17.257] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x77f9fa97fbbcb04392e88d456c198520b3425518c3d2d4146393099192372448 {"gasLimit":397390,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 08:59:17 [08:59:17.258] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x1649EeAD62C64Db7F63182989C8c41Ab25025C21 in tx 0x77f9fa97fbbcb04392e88d456c198520b3425518c3d2d4146393099192372448. 08:59:17 [08:59:17.258] VERBOSE: e2e:e2e_snapshot_sync Deployed CoinIssuer at 0x1649eead62c64db7f63182989c8c41ab25025c21 08:59:17 [08:59:17.259] VERBOSE: e2e:e2e_snapshot_sync Waiting for deployments to complete 08:59:17 [08:59:17.259] INFO: e2e:e2e_snapshot_sync Waiting for 7 transactions to be mined... 08:59:25 [08:59:25.286] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 08:59:25 [08:59:25.299] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xc033579f2357cfd2f6fa38d1ef9986415f4ed794add3fff64984bc3f1257e970 {"gasLimit":100000,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:25 [08:59:25.300] VERBOSE: e2e:e2e_snapshot_sync Added coin issuer 0x1649eead62c64db7f63182989c8c41ab25025c21 as minter on fee asset in 0xc033579f2357cfd2f6fa38d1ef9986415f4ed794add3fff64984bc3f1257e970 08:59:25 [08:59:25.319] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xd6a54e4eed6f07d2b8dcaafbd3883b3558583e43612f444af113d489245a2bb1 {"gasLimit":56661,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:25 [08:59:25.374] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xa67ff7f4001dc5703375e116caba00222d15ec13472e10af8018f62318b463cb {"gasLimit":344517,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:25 [08:59:25.374] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x3607dbAfDe17871E0D6A8f929151C30b83510D39 in tx 0xa67ff7f4001dc5703375e116caba00222d15ec13472e10af8018f62318b463cb. 08:59:25 [08:59:25.378] VERBOSE: e2e:e2e_snapshot_sync Deployed FeeAssetHandler at 0x3607dbafde17871e0d6a8f929151c30b83510d39 08:59:25 [08:59:25.405] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xa32bc1b02f5f02b41626611ec0e0056cad989330fbf908181b5d4f7678377aae {"gasLimit":56804,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:25 [08:59:25.405] VERBOSE: e2e:e2e_snapshot_sync Added fee asset handler 0x3607dbafde17871e0d6a8f929151c30b83510d39 as minter on fee asset in 0xa32bc1b02f5f02b41626611ec0e0056cad989330fbf908181b5d4f7678377aae 08:59:25 [08:59:25.433] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xadb884185b00b3cf3fb2aac4753fbbc2bbb58dfbab2a937d61ea0e950d96b91b {"gasLimit":2514669,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:25 [08:59:25.434] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x499C89D6b68cBdcA67C96Bff13Eb5B5a8fDb25a2 in tx 0xadb884185b00b3cf3fb2aac4753fbbc2bbb58dfbab2a937d61ea0e950d96b91b. 08:59:25 [08:59:25.435] VERBOSE: e2e:e2e_snapshot_sync Deployed StakingAssetHandler at 0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2 08:59:25 [08:59:25.454] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x2b32bbf996c6d3500330bc3ce74137b03f19074efe8ad0d3e14e6dea009f12e4 {"gasLimit":56804,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 08:59:25 [08:59:25.455] VERBOSE: e2e:e2e_snapshot_sync Added staking asset handler 0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2 as minter on staking asset in 0x2b32bbf996c6d3500330bc3ce74137b03f19074efe8ad0d3e14e6dea009f12e4 08:59:25 [08:59:25.455] VERBOSE: e2e:e2e_snapshot_sync Waiting for deployments to complete 08:59:25 [08:59:25.455] INFO: e2e:e2e_snapshot_sync Waiting for 9 transactions to be mined... 08:59:33 [08:59:33.470] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 08:59:33 [08:59:33.489] VERBOSE: e2e:e2e_snapshot_sync Deployed shared contracts 08:59:33 [08:59:33.508] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x1f3bf003fea3920e27f0ab1b5a7c080b356836c498ba06ccbbb2f38e05cac9a5 {"gasLimit":84823,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:33 [08:59:33.509] VERBOSE: e2e:e2e_snapshot_sync Funded reward distributor with 10000000000000000000000000 fee asset in 0x1f3bf003fea3920e27f0ab1b5a7c080b356836c498ba06ccbbb2f38e05cac9a5 08:59:33 [08:59:33.523] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x8ddfc36dd291a7e69dbb1d1c4b25283f53b9e73f733772b984e1b7cef75ca71d {"gasLimit":154658,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:33 [08:59:33.523] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x277E22B0C9698d7f2A1cD7C6d857511617587309 in tx 0x8ddfc36dd291a7e69dbb1d1c4b25283f53b9e73f733772b984e1b7cef75ca71d. 08:59:33 [08:59:33.524] VERBOSE: e2e:e2e_snapshot_sync Rollup will use the mock verifier at 0x277e22b0c9698d7f2a1cd7c6d857511617587309 08:59:33 [08:59:33.524] 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:33 [08:59:33.536] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x1244dd983dfa48995970c0afd0fb79b5391ff919edd2ee7939902fc973eec3e1 {"gasLimit":86992,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:33 [08:59:33.536] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xbba8820C80d584aEc2Cb092C3a0bE628aAB41fe3 in tx 0x1244dd983dfa48995970c0afd0fb79b5391ff919edd2ee7939902fc973eec3e1. 08:59:33 [08:59:33.559] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xc613ac3e45b25859ad92f225b053c859a6b60b0ddf525799f26696c2568a98fe {"gasLimit":5159372,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:33 [08:59:33.559] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x95bA1C62f973621Af2A511A8010d3F1aC1062C33 in tx 0xc613ac3e45b25859ad92f225b053c859a6b60b0ddf525799f26696c2568a98fe. 08:59:33 [08:59:33.628] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x605c36a0608e211905ee60f09d2a342acfff4cfa28b000496717a06c835d1381 {"gasLimit":2478502,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 08:59:33 [08:59:33.629] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xfF6eB969c88E1ba744A420c6E9a2C9e471dB1811 in tx 0x605c36a0608e211905ee60f09d2a342acfff4cfa28b000496717a06c835d1381. 08:59:33 [08:59:33.632] VERBOSE: e2e:e2e_snapshot_sync Awaiting for linked libraries to be deployed 08:59:41 [08:59:41.695] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x383f74e13d10ed985b1695df5dd168caa25c398df810ca82361e334e256b8c14 {"gasLimit":10676151,"maxFeePerGas":"3.44200128","maxPriorityFeePerGas":"2.0736"} 08:59:41 [08:59:41.695] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x82Be125243429b0F0b6B42bF668aEB1B0A498d36 in tx 0x383f74e13d10ed985b1695df5dd168caa25c398df810ca82361e334e256b8c14. 08:59:41 [08:59:41.696] 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:41 [08:59:41.696] INFO: e2e:e2e_snapshot_sync Waiting for 11 transactions to be mined... 08:59:49 [08:59:49.709] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 08:59:49 [08:59:49.709] VERBOSE: e2e:e2e_snapshot_sync All core contracts have been deployed 08:59:49 [08:59:49.730] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xa646e395d19938af66e8b8c1a46ef88aca49783152a68a536f794b0216a007f8 {"gasLimit":1056496,"maxFeePerGas":"3.760309518","maxPriorityFeePerGas":"2.48832"} 08:59:49 [08:59:49.730] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xeFBa96d6D8A7783972bedFc1c73935c3Fe218d91 in tx 0xa646e395d19938af66e8b8c1a46ef88aca49783152a68a536f794b0216a007f8. 08:59:49 [08:59:49.730] VERBOSE: e2e:e2e_snapshot_sync Deployed SlashFactory at 0xefba96d6d8a7783972bedfc1c73935c3fe218d91 08:59:49 [08:59:49.751] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xfc751ada7f7bb3ad53733649c166d16601a1b74f320bdb636751f13cae858a48 {"gasLimit":116846,"maxFeePerGas":"3.760309518","maxPriorityFeePerGas":"2.48832"} 08:59:49 [08:59:49.751] VERBOSE: e2e:e2e_snapshot_sync Adding rollup 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 to registry 0x992d1f24cc5f368188d936cf0b2a111cee586d4e in tx 0xfc751ada7f7bb3ad53733649c166d16601a1b74f320bdb636751f13cae858a48 08:59:49 [08:59:49.765] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x4a1e134b475962fe7e269ec1fb6752e72041a80494f25446c60e25f6963a45f8 {"gasLimit":109478,"maxFeePerGas":"3.760309518","maxPriorityFeePerGas":"2.48832"} 08:59:49 [08:59:49.765] VERBOSE: e2e:e2e_snapshot_sync Adding rollup 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 to GSE 0xad2120681b02de3e9b8afd325977cdeddf15f01c in tx 0x4a1e134b475962fe7e269ec1fb6752e72041a80494f25446c60e25f6963a45f8 08:59:57 [08:59:57.779] INFO: e2e:e2e_snapshot_sync Waiting for 12 transactions to be mined... 08:59:57 [08:59:57.836] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 08:59:57 [08:59:57.858] VERBOSE: e2e:e2e_snapshot_sync Rollup deployed 08:59:57 [08:59:57.861] VERBOSE: e2e:e2e_snapshot_sync Waiting for rollup and slash factory to be deployed 08:59:57 [08:59:57.862] INFO: e2e:e2e_snapshot_sync Waiting for 12 transactions to be mined... 08:59:57 [08:59:57.907] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 08:59:57 [08:59:57.908] VERBOSE: e2e:e2e_snapshot_sync All transactions for L1 deployment have been mined 08:59:57 [08:59:57.946] 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"} 08:59:57 [08:59:57.946] INFO: e2e:e2e_snapshot_sync Handing over to governance 08:59:57 [08:59:57.951] INFO: e2e:e2e_snapshot_sync Calling evm_setNextBlockTimestamp with params: [1749805204] 08:59:57 [08:59:57.953] INFO: e2e:e2e_snapshot_sync Calling hardhat_mine with params: [1] 08:59:57 [08:59:57.957] INFO: e2e:e2e_snapshot_sync Jumped to slot 1 08:59:57 [08:59:57.984] INFO: aztecjs:utils:watcher Watcher not started because not auto mining 08:59:57 [08:59:57.989] INFO: telemetry:client Using NoopTelemetryClient 08:59:58 [08:59:58.008] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/e2c669da8f2f457b/blob-sink with map size 134217728 KB (LMDB v2) 08:59:58 [08:59:58.044] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 08:59:58 [08:59:58.071] INFO: blob-sink:server Server is running on http://localhost:40389 08:59:58 [08:59:58.072] VERBOSE: e2e:e2e_snapshot_sync Creating and synching an aztec node... 08:59:58 [08:59:58.073] VERBOSE: e2e:e2e_snapshot_sync Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/95a08ea1/acvm 08:59:58 [08:59:58.085] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:40389"} 08:59:58 [08:59:58.140] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 08:59:58 [08:59:58.141] INFO: archiver:lmdb Creating archiver data store at directory /tmp/e2c669da8f2f457b/archiver with map size 134217728 KB (LMDB v2) 08:59:58 [08:59:58.142] INFO: archiver:lmdb Starting data store with maxReaders 16 08:59:59 [08:59:59.191] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:40389"} 08:59:59 [08:59:59.211] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:40389"} 08:59:59 [08:59:59.212] WARN: node:blob-sink:client No L1 consensus host urls configured 08:59:59 [08:59:59.212] WARN: node:blob-sink:client No archive client configured 08:59:59 [08:59:59.217] INFO: archiver Starting archiver sync to rollup contract 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 from L1 block 4 to current L1 block 6 with hash 0xfbdd3f146fc9f864a5547e32511c77b1353a272c29ce7da3381d2602254137fa {"blocksSynchedTo":4,"messagesSynchedTo":{"l1BlockHash":"0x9dfa3528488b1f6a2e9a9ce9f88d796591d09fb42a2a65a6e01654c3907a5d2a","l1BlockNumber":4}} 08:59:59 [08:59:59.242] INFO: archiver Initial archiver sync to L1 block 6 complete. {"l1BlockNumber":6,"syncPoint":{"blocksSynchedTo":6,"messagesSynchedTo":{"l1BlockHash":"0xfbdd3f146fc9f864a5547e32511c77b1353a272c29ce7da3381d2602254137fa","l1BlockNumber":6}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 08:59:59 [08:59:59.254] INFO: world-state:database Creating world state data store at directory /tmp/e2c669da8f2f457b/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 08:59:59 [08:59:59.312] INFO: world_state Created world state synchroniser with block history of 64 08:59:59 [08:59:59.313] WARN: node Aztec node is accepting fake proofs 08:59:59 [08:59:59.392] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/e2c669da8f2f457b/p2p with map size 134217728 KB (LMDB v2) 08:59:59 [08:59:59.406] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 08:59:59 [08:59:59.408] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/e2c669da8f2f457b/p2p-archive with map size 134217728 KB (LMDB v2) 08:59:59 [08:59:59.414] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 08:59:59 [08:59:59.416] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/e2c669da8f2f457b/p2p-peers with map size 134217728 KB (LMDB v2) 08:59:59 [08:59:59.423] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 08:59:59 [08:59:59.426] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 08:59:59 [08:59:59.427] VERBOSE: p2p P2P is disabled. Using dummy P2P service 08:59:59 [08:59:59.434] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 08:59:59 [08:59:59.435] INFO: world_state Started world state synchronizer from block 1 08:59:59 [08:59:59.438] VERBOSE: world-state:block_stream Starting sync from block number 0 08:59:59 [08:59:59.442] INFO: p2p Starting p2p client from block 0 with empty mempools 08:59:59 [08:59:59.443] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 08:59:59 [08:59:59.800] INFO: epoch-prune-watcher EpochPruneWatcher initialized 08:59:59 [08:59:59.835] VERBOSE: validator Initialized validator with addresses: 0x2d3fac85379d9bd08f097ac3efee6666a2902c33 08:59:59 [08:59:59.835] VERBOSE: node All Aztec Node subsystems synced 08:59:59 [08:59:59.843] INFO: slasher Starting Slasher client... 08:59:59 [08:59:59.853] INFO: sequencer-client Deploying forwarder contract 08:59:59 [08:59:59.855] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 08:59:59 [08:59:59.868] VERBOSE: sequencer-client Sent L1 transaction 0x3e1ef8d23ca5a131e6a5da8b4a3fb4e426e92bd5eb69ea0c6492670affab3fb2 {"gasLimit":323704,"maxFeePerGas":"2.265827991","maxPriorityFeePerGas":"1.2"} 08:59:59 [08:59:59.869] VERBOSE: sequencer-client Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000042 to address 0x0fe4d493871B0C51332De8A330B02BA8172Da4aC in tx 0x3e1ef8d23ca5a131e6a5da8b4a3fb4e426e92bd5eb69ea0c6492670affab3fb2. 09:00:07 [09:00:07.881] INFO: sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac 09:00:07 [09:00:07.890] INFO: validator Started validator with addresses: 0x2d3fac85379d9bd08f097ac3efee6666a2902c33 09:00:07 [09:00:07.891] VERBOSE: validator Validators 0x2d3fac85379d9bd08f097ac3efee6666a2902c33 are not on the validator committee for epoch 0 09:00:07 [09:00:07.891] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":0,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/95a08ea1/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":8,"enforceTimeTable":false} 09:00:07 [09:00:07.892] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 09:00:07 [09:00:07.894] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 09:00:07 [09:00:07.895] INFO: node Aztec Node version: 0.87.6 09:00:07 [09:00:07.895] 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:07 [09:00:07.896] VERBOSE: e2e:e2e_snapshot_sync Creating a pxe... 09:00:07 [09:00:07.902] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/ad2d10f5c572150d/pxe_data with map size 134217728 KB (LMDB v2) 09:00:07 [09:00:07.904] 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:07 [09:00:07.905] INFO: pxe:data:lmdb Starting data store with maxReaders 16 09:00:08 [09:00:08.257] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 09:00:08 [09:00:08.258] INFO: pxe:service Started PXE connected to chain 31337 version 788754797 09:00:08 [09:00:08.263] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync waits until a few L2 blocks have been mined and purges blobs 09:00:08 [09:00:08.264] WARN: e2e:e2e_snapshot_sync Waiting for L2 blocks to be mined 09:00:08 [09:00:08.415] 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:08 [09:00:08.534] INFO: e2e:e2e_snapshot_sync L1 block 7 mined at 09:00:12 with new L2 block 0 for epoch 0 with proof up to L2 block 0 for epoch 0 {"l1Timestamp":1749805212,"l1BlockNumber":7,"l2SlotNumber":1,"l2BlockNumber":0,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:08 [09:00:08.924] 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:09 [09:00:09.435] 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:09 [09:00:09.951] 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:10 [09:00:10.468] 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:10 [09:00:10.985] 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:11 [09:00:11.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 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:00:12 [09:00:12.017] 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":1749805220,"version":788754797},"chainTipArchive":"0x1f9c798be7975bb34c3e605a4c92c75796eae7b9a08644bc9a6a55354ed470be","blockNumber":1,"slot":2} 09:00:12 [09:00:12.037] VERBOSE: block-builder Building block 1 for slot 2 {"slot":2,"slotStart":"2025-06-13T09:00:20.000Z","now":"2025-06-13T09:00:12.037Z","blockNumber":1,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:00:12 [09:00:12.044] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.001077001999998174s {"duration":0.001077001999998174,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 09:00:12 [09:00:12.068] INFO: sequencer Built block 1 for slot 2 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x00a6b448225f4fe13d23b0883e5907fa15e3a21530c296f0e1a60e528356b3f6","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749805220,"version":788754797},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":39.63841700000194,"publicProcessDuration":2.695053999996162,"rollupCircuitsDuration":31.622747000001254,"txCount":0,"blockNumber":1,"blockTimestamp":1749805220,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:12 [09:00:12.071] VERBOSE: sequencer Attesting committee is empty 09:00:12 [09:00:12.883] INFO: e2e:e2e_snapshot_sync L1 block 8 mined at 09:00:20 {"l1Timestamp":1749805220,"l1BlockNumber":8,"l2SlotNumber":2,"l2BlockNumber":0,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:14 [09:00:14.664] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xe3bd491f208ff6e9d9e93f7031e560830f181feee4678bea13f33e51debd8795 09:00:14 [09:00:14.665] VERBOSE: node Sent L1 transaction 0xe3bd491f208ff6e9d9e93f7031e560830f181feee4678bea13f33e51debd8795 {"gasLimit":485524,"maxFeePerGas":"2.37259949","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 09:00:20 [09:00:20.888] INFO: e2e:e2e_snapshot_sync L1 block 9 mined at 09:00:28 with new L2 block 1 for epoch 0 {"l1Timestamp":1749805228,"l1BlockNumber":9,"l2SlotNumber":2,"l2BlockNumber":1,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:21 [09:00:21.171] WARN: node:blob-sink:client Unable to get blob sidecar for 0x41a42178ba4ccdce950914600aad2a0febb20aa9c83066f21142b36d261c2190: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 09:00:21 [09:00:21.171] WARN: node:blob-sink:client Failed to fetch blobs for 0x41a42178ba4ccdce950914600aad2a0febb20aa9c83066f21142b36d261c2190 from all blob sources {"blobSinkUrl":"http://localhost:40389"} 09:00:21 [09:00:21.694] WARN: node:blob-sink:client Unable to get blob sidecar for 0x41a42178ba4ccdce950914600aad2a0febb20aa9c83066f21142b36d261c2190: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 09:00:21 [09:00:21.694] WARN: node:blob-sink:client Failed to fetch blobs for 0x41a42178ba4ccdce950914600aad2a0febb20aa9c83066f21142b36d261c2190 from all blob sources {"blobSinkUrl":"http://localhost:40389"} 09:00:21 [09:00:21.726] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:00:21 [09:00:21.727] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xe3bd491f208ff6e9d9e93f7031e560830f181feee4678bea13f33e51debd8795","calldataSize":1220,"calldataGas":8840,"txCount":0,"blockNumber":1,"blockTimestamp":1749805220,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:21 [09:00:21.728] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:00:21 [09:00:21.739] INFO: blob-sink:server Received blob sidecar for block 0x41a42178ba4ccdce950914600aad2a0febb20aa9c83066f21142b36d261c2190 09:00:21 [09:00:21.746] INFO: blob-sink:server Blob sidecar stored successfully for block 0x41a42178ba4ccdce950914600aad2a0febb20aa9c83066f21142b36d261c2190 09:00:22 [09:00:22.242] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x00a6b448225f4fe13d23b0883e5907fa15e3a21530c296f0e1a60e528356b3f6","blockNumber":1,"txCount":0,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749805220,"version":788754797},"archiveRoot":"0x0aa2a7f9499983aa14709f11528c16bedbc985904158646a730b65148797f829","archiveNextLeafIndex":2} 09:00:22 [09:00:22.286] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":4.95420799999556,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":1,"blockTimestamp":1749805220,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:22 [09:00:22.287] VERBOSE: p2p Synched to latest block 1 09:00:22 [09:00:22.766] 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:23 [09:00:23.288] 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:23 [09:00:23.798] 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:24 [09:00:24.310] 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:24 [09:00:24.834] 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:25 [09:00:25.345] 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:25 [09:00:25.857] 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:26 [09:00:26.369] 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:26 [09:00:26.878] 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:27 [09:00:27.386] 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:27 [09:00:27.904] 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:28 [09:00:28.420] 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":1749805236,"version":788754797},"chainTipArchive":"0x0aa2a7f9499983aa14709f11528c16bedbc985904158646a730b65148797f829","blockNumber":2,"slot":3} 09:00:28 [09:00:28.435] VERBOSE: block-builder Building block 2 for slot 3 {"slot":3,"slotStart":"2025-06-13T09:00:36.000Z","now":"2025-06-13T09:00:28.435Z","blockNumber":2,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:00:28 [09:00:28.440] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.0013100559999875258s {"duration":0.0013100559999875258,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 09:00:28 [09:00:28.463] INFO: sequencer Built block 2 for slot 3 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x17b080660ba1001bf74384c5993aeb38128ffdbedad45dcaddf2980764e53156","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749805236,"version":788754797},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":34.83224899999914,"publicProcessDuration":2.0734210000082385,"rollupCircuitsDuration":27.570025999986683,"txCount":0,"blockNumber":2,"blockTimestamp":1749805236,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:28 [09:00:28.468] VERBOSE: sequencer Attesting committee is empty 09:00:28 [09:00:28.572] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x9c45b3f76b02f73805eedcf0799bbe26a8223129eb78982b8a555abde4ccd61c 09:00:28 [09:00:28.572] VERBOSE: node Sent L1 transaction 0x9c45b3f76b02f73805eedcf0799bbe26a8223129eb78982b8a555abde4ccd61c {"gasLimit":414487,"maxFeePerGas":"2.443834904","maxPriorityFeePerGas":"1.728","maxFeePerBlobGas":"0.000000001"} 09:00:28 [09:00:28.837] WARN: node:blob-sink:client Unable to get blob sidecar for 0xf3bbad787fcd92498ecbe7e7a26465e10dd1af51a34976ed2d2b1077c03415fe: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 09:00:28 [09:00:28.837] WARN: node:blob-sink:client Failed to fetch blobs for 0xf3bbad787fcd92498ecbe7e7a26465e10dd1af51a34976ed2d2b1077c03415fe from all blob sources {"blobSinkUrl":"http://localhost:40389"} 09:00:28 [09:00:28.898] INFO: e2e:e2e_snapshot_sync L1 block 10 mined at 09:00:36 with new L2 block 2 for epoch 0 {"l1Timestamp":1749805236,"l1BlockNumber":10,"l2SlotNumber":3,"l2BlockNumber":2,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:29 [09:00:29.378] WARN: node:blob-sink:client Unable to get blob sidecar for 0xf3bbad787fcd92498ecbe7e7a26465e10dd1af51a34976ed2d2b1077c03415fe: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 09:00:29 [09:00:29.379] WARN: node:blob-sink:client Failed to fetch blobs for 0xf3bbad787fcd92498ecbe7e7a26465e10dd1af51a34976ed2d2b1077c03415fe from all blob sources {"blobSinkUrl":"http://localhost:40389"} 09:00:29 [09:00:29.598] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:00:29 [09:00:29.598] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x9c45b3f76b02f73805eedcf0799bbe26a8223129eb78982b8a555abde4ccd61c","calldataSize":1220,"calldataGas":8840,"txCount":0,"blockNumber":2,"blockTimestamp":1749805236,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:29 [09:00:29.599] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:00:29 [09:00:29.602] INFO: blob-sink:server Received blob sidecar for block 0xf3bbad787fcd92498ecbe7e7a26465e10dd1af51a34976ed2d2b1077c03415fe 09:00:29 [09:00:29.607] INFO: blob-sink:server Blob sidecar stored successfully for block 0xf3bbad787fcd92498ecbe7e7a26465e10dd1af51a34976ed2d2b1077c03415fe 09:00:29 [09:00:29.927] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x17b080660ba1001bf74384c5993aeb38128ffdbedad45dcaddf2980764e53156","blockNumber":2,"txCount":0,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749805236,"version":788754797},"archiveRoot":"0x0d958d04672d80ccb96cec409d15cdfc681ed918ce7b2fb16bbcf68c8367a3c8","archiveNextLeafIndex":3} 09:00:30 [09:00:30.014] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":3.8349160000070697,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":2,"blockTimestamp":1749805236,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:30 [09:00:30.036] VERBOSE: p2p Synched to latest block 2 09:00:30 [09:00:30.120] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:30 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:30 [09:00:30.121] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:30 [09:00:30.636] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:30 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:30 [09:00:30.637] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:31 [09:00:31.172] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:31 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:31 [09:00:31.173] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:31 [09:00:31.689] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:31 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:31 [09:00:31.690] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:32 [09:00:32.211] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:32 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:32 [09:00:32.211] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:32 [09:00:32.734] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:32 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:32 [09:00:32.735] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:33 [09:00:33.262] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:33 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:33 [09:00:33.262] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:33 [09:00:33.776] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:33 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:33 [09:00:33.777] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:34 [09:00:34.312] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:34 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:34 [09:00:34.313] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:34 [09:00:34.828] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:34 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:34 [09:00:34.829] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:35 [09:00:35.354] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:35 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:35 [09:00:35.354] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:35 [09:00:35.882] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:35 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:35 [09:00:35.882] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:36 [09:00:36.421] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:36 error: "Rollup__SlotAlreadyInChain(3, 3)" 09:00:36 [09:00:36.422] WARN: sequencer Cannot propose block 3 at slot 3 due to failed rollup contract check 09:00:36 [09:00:36.915] INFO: e2e:e2e_snapshot_sync L1 block 11 mined at 09:00:44 {"l1Timestamp":1749805244,"l1BlockNumber":11,"l2SlotNumber":3,"l2BlockNumber":2,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:36 [09:00:36.935] 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:37 [09:00:37.452] 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:37 [09:00:37.963] 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:38 [09:00:38.473] 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:38 [09:00:38.984] 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:39 [09:00:39.493] 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:40 [09:00:40.031] 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:40 [09:00:40.551] 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:41 [09:00:41.073] 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:41 [09:00:41.586] 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:42 [09:00:42.105] 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:42 [09:00:42.617] 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:43 [09:00:43.135] 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:43 [09:00:43.648] 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:44 [09:00:44.160] 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":1749805252,"version":788754797},"chainTipArchive":"0x0d958d04672d80ccb96cec409d15cdfc681ed918ce7b2fb16bbcf68c8367a3c8","blockNumber":3,"slot":4} 09:00:44 [09:00:44.170] VERBOSE: block-builder Building block 3 for slot 4 {"slot":4,"slotStart":"2025-06-13T09:00:52.000Z","now":"2025-06-13T09:00:44.170Z","blockNumber":3,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:00:44 [09:00:44.172] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.00029099599999608475s {"duration":0.00029099599999608475,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 09:00:44 [09:00:44.188] INFO: sequencer Built block 3 for slot 4 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749805252,"version":788754797},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":23.13628800000879,"publicProcessDuration":0.7491250000020955,"rollupCircuitsDuration":18.063257000001613,"txCount":0,"blockNumber":3,"blockTimestamp":1749805252,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:44 [09:00:44.190] VERBOSE: sequencer Attesting committee is empty 09:00:44 [09:00:44.264] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x68a929fe5cc80d03f5dd9b4dfd4d878fb3963ee4ca6218b2a74ba8beb6744893 09:00:44 [09:00:44.265] VERBOSE: node Sent L1 transaction 0x68a929fe5cc80d03f5dd9b4dfd4d878fb3963ee4ca6218b2a74ba8beb6744893 {"gasLimit":416887,"maxFeePerGas":"1.751318767","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 09:00:44 [09:00:44.968] INFO: e2e:e2e_snapshot_sync L1 block 12 mined at 09:00:52 with new L2 block 3 for epoch 0 {"l1Timestamp":1749805252,"l1BlockNumber":12,"l2SlotNumber":4,"l2BlockNumber":3,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:45 [09:00:45.290] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:00:45 [09:00:45.291] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x68a929fe5cc80d03f5dd9b4dfd4d878fb3963ee4ca6218b2a74ba8beb6744893","calldataSize":1220,"calldataGas":8840,"txCount":0,"blockNumber":3,"blockTimestamp":1749805252,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:45 [09:00:45.292] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:00:45 [09:00:45.304] INFO: blob-sink:server Received blob sidecar for block 0x5ec00d7052884315bc71d7cbc928c5e4d96489b2f65e403d32b2fe8ab729f2d5 09:00:45 [09:00:45.330] INFO: blob-sink:server Blob sidecar stored successfully for block 0x5ec00d7052884315bc71d7cbc928c5e4d96489b2f65e403d32b2fe8ab729f2d5 09:00:45 [09:00:45.392] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09","blockNumber":3,"txCount":0,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749805252,"version":788754797},"archiveRoot":"0x2df711f4af3c070b791eb22f175f47423012e3dccc67d13ec6c3bf3baca59455","archiveNextLeafIndex":4} 09:00:45 [09:00:45.422] VERBOSE: p2p Synched to latest block 3 09:00:45 [09:00:45.463] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":8.438237000009394,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":3,"blockTimestamp":1749805252,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:00:45 [09:00:45.811] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:45 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:45 [09:00:45.811] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:46 [09:00:46.344] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:46 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:46 [09:00:46.345] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:46 [09:00:46.872] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:46 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:46 [09:00:46.872] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:47 [09:00:47.395] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:47 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:47 [09:00:47.396] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:47 [09:00:47.910] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:47 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:47 [09:00:47.910] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:48 [09:00:48.439] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:48 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:48 [09:00:48.439] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:48 [09:00:48.954] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:48 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:48 [09:00:48.954] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:49 [09:00:49.472] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:49 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:49 [09:00:49.473] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:49 [09:00:49.992] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:49 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:49 [09:00:49.992] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:50 [09:00:50.506] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:50 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:50 [09:00:50.506] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:51 [09:00:51.021] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:51 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:51 [09:00:51.021] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:51 [09:00:51.532] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:51 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:51 [09:00:51.532] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:52 [09:00:52.044] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:52 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:52 [09:00:52.044] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:52 [09:00:52.562] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:00:52 error: "Rollup__SlotAlreadyInChain(4, 4)" 09:00:52 [09:00:52.563] WARN: sequencer Cannot propose block 4 at slot 4 due to failed rollup contract check 09:00:52 [09:00:52.961] INFO: e2e:e2e_snapshot_sync L1 block 13 mined at 09:01:00 {"l1Timestamp":1749805260,"l1BlockNumber":13,"l2SlotNumber":4,"l2BlockNumber":3,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:00:53 [09:00:53.071] 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:00:53 [09:00:53.586] 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:00:54 [09:00:54.094] 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:00:54 [09:00:54.604] 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:00:55 [09:00:55.112] 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:00:55 [09:00:55.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 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 09:00:56 [09:00:56.143] 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:00:56 [09:00:56.652] 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:00:57 [09:00:57.162] 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:00:57 [09:00:57.674] 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:00:58 [09:00:58.186] 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:00:58 [09:00:58.699] 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:00:59 [09:00:59.212] 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:00:59 [09:00:59.741] 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:00 [09:01:00.259] 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":1749805268,"version":788754797},"chainTipArchive":"0x2df711f4af3c070b791eb22f175f47423012e3dccc67d13ec6c3bf3baca59455","blockNumber":4,"slot":5} 09:01:00 [09:01:00.273] VERBOSE: block-builder Building block 4 for slot 5 {"slot":5,"slotStart":"2025-06-13T09:01:08.000Z","now":"2025-06-13T09:01:00.273Z","blockNumber":4,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:01:00 [09:01:00.276] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.0005543710000056308s {"duration":0.0005543710000056308,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 09:01:00 [09:01:00.289] INFO: sequencer Built block 4 for slot 5 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x10178bd164c360a93f2251daee3e80f14224e6a723a930fd97d29b926c9adafa","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":251640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805268,"version":788754797},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":23.083722999988822,"publicProcessDuration":1.1545230000047013,"rollupCircuitsDuration":17.63475300000573,"txCount":0,"blockNumber":4,"blockTimestamp":1749805268,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:00 [09:01:00.291] VERBOSE: sequencer Attesting committee is empty 09:01:00 [09:01:00.353] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x32ab14d7fb90cf671b17bc43967a1c791e277701d4726e821901b7e003ac789f 09:01:00 [09:01:00.353] VERBOSE: node Sent L1 transaction 0x32ab14d7fb90cf671b17bc43967a1c791e277701d4726e821901b7e003ac789f {"gasLimit":416884,"maxFeePerGas":"1.623245128","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 09:01:00 [09:01:00.974] INFO: e2e:e2e_snapshot_sync L1 block 14 mined at 09:01:08 with new L2 block 4 for epoch 0 {"l1Timestamp":1749805268,"l1BlockNumber":14,"l2SlotNumber":5,"l2BlockNumber":4,"l2ProvenBlockNumber":0,"totalL2Messages":0} 09:01:01 [09:01:01.221] WARN: node:blob-sink:client Unable to get blob sidecar for 0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 09:01:01 [09:01:01.221] WARN: node:blob-sink:client Failed to fetch blobs for 0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3 from all blob sources {"blobSinkUrl":"http://localhost:40389"} 09:01:01 [09:01:01.284] WARN: e2e:e2e_snapshot_sync L2 block height is now 4. Purging all blobs from sink so snapshot is required. 09:01:01 [09:01:01.286] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync creates a snapshot 09:01:01 [09:01:01.287] WARN: e2e:e2e_snapshot_sync Creating snapshot 09:01:01 [09:01:01.289] INFO: stdlib:file-store Creating local file file store at /tmp/snapshots-QZBkOM 09:01:01 [09:01:01.290] INFO: node Pausing archiver and world state sync to start snapshot upload 09:01:01 [09:01:01.290] INFO: archiver Stopped. 09:01:01 [09:01:01.290] INFO: world_state Stopped sync 09:01:01 [09:01:01.290] INFO: node Creating backups of lmdb environments to /tmp/e2c669da8f2f457b/snapshot-BCFfNJ 09:01:01 [09:01:01.293] INFO: node Data stores backed up to /tmp/e2c669da8f2f457b/snapshot-BCFfNJ {"paths":{"l1-to-l2-message-tree":"/tmp/e2c669da8f2f457b/snapshot-BCFfNJ/world-state/L1ToL2MessageTree/data.mdb","archive-tree":"/tmp/e2c669da8f2f457b/snapshot-BCFfNJ/world-state/ArchiveTree/data.mdb","public-data-tree":"/tmp/e2c669da8f2f457b/snapshot-BCFfNJ/world-state/PublicDataTree/data.mdb","note-hash-tree":"/tmp/e2c669da8f2f457b/snapshot-BCFfNJ/world-state/NoteHashTree/data.mdb","nullifier-tree":"/tmp/e2c669da8f2f457b/snapshot-BCFfNJ/world-state/NullifierTree/data.mdb","archiver":"/tmp/e2c669da8f2f457b/snapshot-BCFfNJ/archiver/data.mdb"}} 09:01:01 [09:01:01.293] INFO: node Resuming archiver and world state sync 09:01:01 [09:01:01.293] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 09:01:01 [09:01:01.294] INFO: world_state Resumed sync 09:01:01 [09:01:01.294] INFO: archiver Restarting archiver 09:01:01 [09:01:01.298] INFO: node Uploading snapshot to file:///tmp/snapshots-QZBkOM {"snapshot":{"l1ChainId":31337,"rollupVersion":788754797,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36","l2BlockNumber":3,"l2BlockHash":"0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09","l1BlockNumber":13}} 09:01:01 [09:01:01.314] INFO: node Snapshot uploaded successfully {"snapshot":{"l1BlockNumber":13,"l2BlockHash":"0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09","l2BlockNumber":3,"schemaVersions":{"archiver":2,"worldState":1},"timestamp":1749805261299,"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archive-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","public-data-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","note-hash-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","nullifier-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archiver":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db"}}} 09:01:01 [09:01:01.315] INFO: node Cleaning up backup dir /tmp/e2c669da8f2f457b/snapshot-BCFfNJ 09:01:01 [09:01:01.325] WARN: node:blob-sink:client Unable to get blob sidecar for 0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 09:01:01 [09:01:01.326] WARN: node:blob-sink:client Failed to fetch blobs for 0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3 from all blob sources {"blobSinkUrl":"http://localhost:40389"} 09:01:01 [09:01:01.369] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:01:01 [09:01:01.370] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x32ab14d7fb90cf671b17bc43967a1c791e277701d4726e821901b7e003ac789f","calldataSize":1220,"calldataGas":8828,"txCount":0,"blockNumber":4,"blockTimestamp":1749805268,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:01 [09:01:01.370] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:01:01 [09:01:01.373] INFO: blob-sink:server Received blob sidecar for block 0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3 09:01:01 [09:01:01.381] INFO: blob-sink:server Blob sidecar stored successfully for block 0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3 09:01:01 [09:01:01.879] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x10178bd164c360a93f2251daee3e80f14224e6a723a930fd97d29b926c9adafa","blockNumber":4,"txCount":0,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":251640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805268,"version":788754797},"archiveRoot":"0x12f4c03b08012776994df0fe0097a287b62a8769f60824a19b36d263edf782e7","archiveNextLeafIndex":5} 09:01:01 [09:01:01.892] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:01 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:01 [09:01:01.892] WARN: sequencer Cannot propose block 4 at slot 5 due to failed rollup contract check 09:01:01 [09:01:01.919] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":4.597502999997232,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":4,"blockTimestamp":1749805268,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:01 [09:01:01.947] VERBOSE: p2p Synched to latest block 4 09:01:02 [09:01:02.292] WARN: e2e:e2e_snapshot_sync Snapshot created 09:01:02 [09:01:02.293] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync downloads snapshot when syncing new node 09:01:02 [09:01:02.294] WARN: e2e:e2e_snapshot_sync Syncing brand new node with snapshot sync 09:01:02 [09:01:02.295] WARN: e2e:e2e_snapshot_sync Creating and syncing a node without a validator... 09:01:02 [09:01:02.296] INFO: node:blob-sink:client:1 Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:40389"} 09:01:02 [09:01:02.320] INFO: node:1 Creating local file file store at /tmp/snapshots-QZBkOM 09:01:02 [09:01:02.321] VERBOSE: node:1 Creating temporary archiver data store 09:01:02 [09:01:02.321] INFO: archiver:lmdb:1 Creating archiver data store at directory /tmp/e2c669da8f2f457b/a8fbded24288dd6a/archiver with map size 134217728 KB (LMDB v2) 09:01:02 [09:01:02.323] INFO: archiver:lmdb:1 Starting data store with maxReaders 16 09:01:02 [09:01:02.325] VERBOSE: node:1 Closing temporary archiver data store {"archiverL2BlockNumber":0} 09:01:02 [09:01:02.330] INFO: node:1 Syncing from snapshot at L1 block 13 L2 block 3 {"snapshot":{"l2BlockNumber":3,"l2BlockHash":"0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09","l1BlockNumber":13,"timestamp":1749805261299,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archive-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","public-data-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","note-hash-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","nullifier-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archiver":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db"}},"snapshotsUrl":"file:///tmp/snapshots-QZBkOM"} 09:01:02 [09:01:02.330] INFO: node:1 Creating local file file store at /tmp/snapshots-QZBkOM 09:01:02 [09:01:02.331] INFO: node:1 Downloading snapshot to /tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo {"snapshot":{"l2BlockNumber":3,"l2BlockHash":"0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09","l1BlockNumber":13,"timestamp":1749805261299,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archive-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","public-data-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","note-hash-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","nullifier-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archiver":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db"}},"downloadPaths":{"archiver":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/archiver.db","nullifier-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/nullifier-tree.db","public-data-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/public-data-tree.db","note-hash-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/note-hash-tree.db","archive-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/archive-tree.db","l1-to-l2-message-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/l1-to-l2-message-tree.db"}} 09:01:02 [09:01:02.333] INFO: node:1 Snapshot downloaded at /tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo {"snapshot":{"l2BlockNumber":3,"l2BlockHash":"0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09","l1BlockNumber":13,"timestamp":1749805261299,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archive-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","public-data-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","note-hash-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","nullifier-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archiver":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db"}},"downloadPaths":{"archiver":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/archiver.db","nullifier-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/nullifier-tree.db","public-data-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/public-data-tree.db","note-hash-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/note-hash-tree.db","archive-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/archive-tree.db","l1-to-l2-message-tree":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/download-EowNjo/l1-to-l2-message-tree.db"}} 09:01:02 [09:01:02.334] INFO: node:1 Archiver database set up from snapshot {"path":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/archiver","dbVersion":2,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:02 [09:01:02.335] INFO: node:1 World state database l1-to-l2-message-tree set up from snapshot {"path":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/world_state/L1ToL2MessageTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:02 [09:01:02.336] INFO: node:1 World state database archive-tree set up from snapshot {"path":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/world_state/ArchiveTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:02 [09:01:02.336] INFO: node:1 World state database public-data-tree set up from snapshot {"path":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/world_state/PublicDataTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:02 [09:01:02.336] INFO: node:1 World state database note-hash-tree set up from snapshot {"path":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/world_state/NoteHashTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:02 [09:01:02.337] INFO: node:1 World state database nullifier-tree set up from snapshot {"path":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/world_state/NullifierTree","dbVersion":1,"rollupAddress":"0x82be125243429b0f0b6b42bf668aeb1b0a498d36"} 09:01:02 [09:01:02.337] INFO: node:1 P2P database cleared {"path":"/tmp/e2c669da8f2f457b/a8fbded24288dd6a/p2p"} 09:01:02 [09:01:02.337] INFO: node:1 Snapshot synced to L1 block 13 L2 block 3 {"snapshot":{"l2BlockNumber":3,"l2BlockHash":"0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09","l1BlockNumber":13,"timestamp":1749805261299,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/l1-to-l2-message-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archive-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archive-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","public-data-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/public-data-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","note-hash-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/note-hash-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","nullifier-tree":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/nullifier-tree-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db","archiver":"file:///tmp/snapshots-QZBkOM/aztec-31337-788754797-0x82be125243429b0f0b6b42bf668aeb1b0a498d36/archiver-20250613090101-0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09.db"}}} 09:01:02 [09:01:02.338] INFO: archiver:lmdb:1 Creating archiver data store at directory /tmp/e2c669da8f2f457b/a8fbded24288dd6a/archiver with map size 134217728 KB (LMDB v2) 09:01:02 [09:01:02.339] INFO: archiver:lmdb:1 Starting data store with maxReaders 16 09:01:02 [09:01:02.537] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:02 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:02 [09:01:02.537] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:02 [09:01:02.639] INFO: node:blob-sink:client:1 Testing configured blob sources {"blobSinkUrl":"http://localhost:40389"} 09:01:02 [09:01:02.646] INFO: node:blob-sink:client:1 Blob sink is reachable {"blobSinkUrl":"http://localhost:40389"} 09:01:02 [09:01:02.646] WARN: node:blob-sink:client:1 No L1 consensus host urls configured 09:01:02 [09:01:02.646] WARN: node:blob-sink:client:1 No archive client configured 09:01:02 [09:01:02.652] INFO: archiver:1 Starting archiver sync to rollup contract 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 from L1 block 12 to current L1 block 14 with hash 0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3 {"blocksSynchedTo":12,"messagesSynchedTo":{"l1BlockHash":"0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3","l1BlockNumber":14}} 09:01:02 [09:01:02.706] INFO: archiver:1 Downloaded L2 block 4 {"blockHash":"0x10178bd164c360a93f2251daee3e80f14224e6a723a930fd97d29b926c9adafa","blockNumber":4,"txCount":0,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":251640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805268,"version":788754797},"archiveRoot":"0x12f4c03b08012776994df0fe0097a287b62a8769f60824a19b36d263edf782e7","archiveNextLeafIndex":5} 09:01:02 [09:01:02.714] INFO: archiver:1 Initial archiver sync to L1 block 14 complete. {"l1BlockNumber":14,"syncPoint":{"blocksSynchedTo":14,"messagesSynchedTo":{"l1BlockHash":"0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3","l1BlockNumber":14}},"latest":{"number":4,"hash":"0x10178bd164c360a93f2251daee3e80f14224e6a723a930fd97d29b926c9adafa"},"proven":{"number":0},"finalized":{"number":0}} 09:01:02 [09:01:02.722] INFO: world-state:database:1 Creating world state data store at directory /tmp/e2c669da8f2f457b/a8fbded24288dd6a/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 09:01:02 [09:01:02.732] INFO: world_state:1 Created world state synchroniser with block history of 64 09:01:02 [09:01:02.733] WARN: node:1 Aztec node is accepting fake proofs 09:01:02 [09:01:02.742] INFO: p2p:lmdb-v2:1 Creating p2p data store at directory /tmp/e2c669da8f2f457b/a8fbded24288dd6a/p2p with map size 134217728 KB (LMDB v2) 09:01:02 [09:01:02.744] INFO: p2p:lmdb-v2:1 Starting data store with maxReaders 16 09:01:02 [09:01:02.746] INFO: p2p-archive:lmdb-v2:1 Creating p2p-archive data store at directory /tmp/e2c669da8f2f457b/a8fbded24288dd6a/p2p-archive with map size 134217728 KB (LMDB v2) 09:01:02 [09:01:02.749] INFO: p2p-archive:lmdb-v2:1 Starting data store with maxReaders 16 09:01:02 [09:01:02.750] INFO: p2p-peer:lmdb-v2:1 Creating p2p-peers data store at directory /tmp/e2c669da8f2f457b/a8fbded24288dd6a/p2p-peers with map size 134217728 KB (LMDB v2) 09:01:02 [09:01:02.751] INFO: p2p-peer:lmdb-v2:1 Starting data store with maxReaders 16 09:01:02 [09:01:02.752] INFO: p2p:tx_pool:1 Setting maximum tx mempool size {"maxTxPoolSize":100000000} 09:01:02 [09:01:02.752] VERBOSE: p2p:1 P2P is disabled. Using dummy P2P service 09:01:02 [09:01:02.754] VERBOSE: world_state:1 Starting sync from 4 to latest block 4 09:01:02 [09:01:02.754] VERBOSE: world-state:block_stream:1 Starting L2 block stream {"proven":false,"pollIntervalMS":100} 09:01:02 [09:01:02.754] INFO: world_state:1 Started world state synchronizer from block 4 09:01:02 [09:01:02.756] VERBOSE: world-state:block_stream:1 Starting sync from block number 3 09:01:02 [09:01:02.763] INFO: world_state:1 World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":4.485971999994945,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":4,"blockTimestamp":1749805268,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:02 [09:01:02.768] INFO: p2p:1 Starting p2p client from block 4 with empty mempools 09:01:02 [09:01:02.769] VERBOSE: p2p:l2-block-stream:1 Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":4} 09:01:02 [09:01:02.769] INFO: epoch-prune-watcher:1 EpochPruneWatcher initialized 09:01:02 [09:01:02.769] VERBOSE: node:1 All Aztec Node subsystems synced 09:01:02 [09:01:02.776] INFO: slasher:1 Starting Slasher client... 09:01:02 [09:01:02.779] INFO: node:1 Aztec Node version: 0.87.6 09:01:02 [09:01:02.779] 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:02 [09:01:02.781] WARN: e2e:e2e_snapshot_sync New node synced 09:01:02 [09:01:02.789] VERBOSE: p2p:l2-block-stream:1 Starting sync from block number 3 09:01:02 [09:01:02.792] WARN: e2e:e2e_snapshot_sync Checking for L2 block 3 with hash 0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09 on both nodes 09:01:02 [09:01:02.795] VERBOSE: p2p:1 Synched to latest block 4 09:01:02 [09:01:02.800] WARN: e2e:e2e_snapshot_sync Stopping new node 09:01:02 [09:01:02.801] INFO: node:1 Stopping Aztec Node 09:01:03 [09:01:03.051] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:03 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:03 [09:01:03.051] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:03 [09:01:03.564] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:03 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:03 [09:01:03.565] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:04 [09:01:04.077] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:04 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:04 [09:01:04.077] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:04 [09:01:04.596] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:04 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:04 [09:01:04.597] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:04 [09:01:04.802] INFO: slasher:1 Slasher client stopped. 09:01:04 [09:01:04.803] INFO: p2p:1 P2P client stopped 09:01:04 [09:01:04.805] INFO: world_state:1 Stopped world state synchronizer 09:01:04 [09:01:04.806] INFO: archiver:1 Stopped. 09:01:04 [09:01:04.806] INFO: node:1 Stopped Aztec Node 09:01:04 [09:01:04.807] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync downloads snapshot when syncing new prover node 09:01:04 [09:01:04.807] WARN: e2e:e2e_snapshot_sync Syncing brand new prover node with snapshot sync 09:01:04 [09:01:04.808] WARN: e2e:e2e_snapshot_sync Creating and syncing a prover node... 09:01:04 [09:01:04.879] INFO: blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:40389"} 09:01:04 [09:01:04.885] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/e2c669da8f2f457b/c631afe5051b53eb/archiver with map size 134217728 KB (LMDB v2) 09:01:04 [09:01:04.887] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 09:01:05 [09:01:05.149] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:05 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:05 [09:01:05.149] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:05 [09:01:05.241] INFO: blob-sink:client:prover-node Testing configured blob sources {"blobSinkUrl":"http://localhost:40389"} 09:01:05 [09:01:05.244] INFO: blob-sink:client:prover-node Blob sink is reachable {"blobSinkUrl":"http://localhost:40389"} 09:01:05 [09:01:05.245] WARN: blob-sink:client:prover-node No L1 consensus host urls configured 09:01:05 [09:01:05.245] WARN: blob-sink:client:prover-node No archive client configured 09:01:05 [09:01:05.248] INFO: archiver:prover-node Starting archiver sync to rollup contract 0x82be125243429b0f0b6b42bf668aeb1b0a498d36 from L1 block 4 to current L1 block 14 with hash 0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3 {"blocksSynchedTo":4,"messagesSynchedTo":{"l1BlockHash":"0x9dfa3528488b1f6a2e9a9ce9f88d796591d09fb42a2a65a6e01654c3907a5d2a","l1BlockNumber":4}} 09:01:05 [09:01:05.395] INFO: archiver:prover-node Downloaded L2 block 1 {"blockHash":"0x00a6b448225f4fe13d23b0883e5907fa15e3a21530c296f0e1a60e528356b3f6","blockNumber":1,"txCount":0,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749805220,"version":788754797},"archiveRoot":"0x0aa2a7f9499983aa14709f11528c16bedbc985904158646a730b65148797f829","archiveNextLeafIndex":2} 09:01:05 [09:01:05.396] INFO: archiver:prover-node Downloaded L2 block 2 {"blockHash":"0x17b080660ba1001bf74384c5993aeb38128ffdbedad45dcaddf2980764e53156","blockNumber":2,"txCount":0,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749805236,"version":788754797},"archiveRoot":"0x0d958d04672d80ccb96cec409d15cdfc681ed918ce7b2fb16bbcf68c8367a3c8","archiveNextLeafIndex":3} 09:01:05 [09:01:05.396] INFO: archiver:prover-node Downloaded L2 block 3 {"blockHash":"0x2bf39cedf17bbf26669830d4916795072e0e0170fecb9303f626adbb00c8db09","blockNumber":3,"txCount":0,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749805252,"version":788754797},"archiveRoot":"0x2df711f4af3c070b791eb22f175f47423012e3dccc67d13ec6c3bf3baca59455","archiveNextLeafIndex":4} 09:01:05 [09:01:05.396] INFO: archiver:prover-node Downloaded L2 block 4 {"blockHash":"0x10178bd164c360a93f2251daee3e80f14224e6a723a930fd97d29b926c9adafa","blockNumber":4,"txCount":0,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":251640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805268,"version":788754797},"archiveRoot":"0x12f4c03b08012776994df0fe0097a287b62a8769f60824a19b36d263edf782e7","archiveNextLeafIndex":5} 09:01:05 [09:01:05.403] INFO: archiver:prover-node Initial archiver sync to L1 block 14 complete. {"l1BlockNumber":14,"syncPoint":{"blocksSynchedTo":14,"messagesSynchedTo":{"l1BlockHash":"0xf7eb94c260a362aeedec16bcce3e3c32caae24db5ea6eeff182a4dac2b898ea3","l1BlockNumber":14}},"latest":{"number":4,"hash":"0x10178bd164c360a93f2251daee3e80f14224e6a723a930fd97d29b926c9adafa"},"proven":{"number":0},"finalized":{"number":0}} 09:01:05 [09:01:05.406] INFO: prover-node:blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:40389"} 09:01:05 [09:01:05.407] INFO: prover-node:prover-node Creating local file file store at /tmp/snapshots-QZBkOM 09:01:05 [09:01:05.407] VERBOSE: prover-node:prover-node Creating temporary archiver data store 09:01:05 [09:01:05.408] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/e2c669da8f2f457b/c631afe5051b53eb/archiver with map size 134217728 KB (LMDB v2) 09:01:05 [09:01:05.411] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 09:01:05 [09:01:05.416] VERBOSE: prover-node:prover-node Closing temporary archiver data store {"archiverL1BlockNumber":14,"archiverL2BlockNumber":4} 09:01:05 [09:01:05.418] VERBOSE: prover-node:prover-node Skipping non-forced snapshot sync as archiver is already synced to L2 block 4. 09:01:05 [09:01:05.419] VERBOSE: prover-node:prover-node Created archiver and synced to block 4 09:01:05 [09:01:05.435] INFO: world-state:database:prover-node Creating world state data store at directory /tmp/e2c669da8f2f457b/c631afe5051b53eb/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 09:01:05 [09:01:05.460] INFO: world_state:prover-node Created world state synchroniser with block history of 64 09:01:05 [09:01:05.461] VERBOSE: world_state:prover-node Starting sync from 1 to latest block 4 09:01:05 [09:01:05.461] VERBOSE: world-state:block_stream:prover-node Starting L2 block stream {"proven":false,"pollIntervalMS":100} 09:01:05 [09:01:05.462] INFO: world_state:prover-node Started world state synchronizer from block 1 09:01:05 [09:01:05.464] VERBOSE: world-state:block_stream:prover-node Starting sync from block number 0 09:01:05 [09:01:05.471] INFO: world_state:prover-node World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":3.0232920000125887,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":1,"blockTimestamp":1749805220,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:05 [09:01:05.475] INFO: world_state:prover-node World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":3.685945000004722,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":2,"blockTimestamp":1749805236,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:05 [09:01:05.479] INFO: world_state:prover-node World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":3.933251000009477,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":3,"blockTimestamp":1749805252,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:05 [09:01:05.485] INFO: world_state:prover-node World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":4.461081000001286,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":4,"blockTimestamp":1749805268,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:01:05 [09:01:05.486] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/e2c669da8f2f457b/c631afe5051b53eb/archiver when loading epoch databases, ignoring 09:01:05 [09:01:05.486] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/e2c669da8f2f457b/c631afe5051b53eb/world_state when loading epoch databases, ignoring 09:01:05 [09:01:05.488] INFO: prover-client:proving-broker:prover-node Proving Broker started 09:01:05 [09:01:05.500] INFO: prover-node:prover-coordination:prover-node Using prover coordination via aztec node 09:01:05 [09:01:05.502] INFO: e2e:e2e_snapshot_sync:prover-node Created and synced prover node {"publisherAddress":"0x9965507D1a55bcC2695C58ba16FB37d819B0A4dc"} 09:01:05 [09:01:05.502] INFO: prover-node:epoch-monitor:prover-node Started EpochMonitor {"pollingIntervalMs":200} 09:01:05 [09:01:05.506] 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/e2c669da8f2f457b/c631afe5051b53eb","l1ChainId":31337,"rollupVersion":788754797} 09:01:05 [09:01:05.507] WARN: e2e:e2e_snapshot_sync New node prover synced 09:01:05 [09:01:05.509] VERBOSE: prover-node:prover-node Fetching 0 tx hashes for block number 4 from coordination 09:01:05 [09:01:05.510] INFO: prover-node:combined-prover-coordination:prover-node Check for 0 txs found all in the pool 09:01:05 [09:01:05.515] WARN: e2e:e2e_snapshot_sync Stopping new prover node 09:01:05 [09:01:05.515] INFO: prover-node:prover-node Stopping ProverNode 09:01:05 [09:01:05.515] INFO: prover-node:epoch-monitor:prover-node Stopped EpochMonitor 09:01:05 [09:01:05.516] INFO: archiver:prover-node Stopped. 09:01:05 [09:01:05.517] INFO: world_state:prover-node Stopped world state synchronizer 09:01:05 [09:01:05.518] INFO: prover-node:prover-node Stopped ProverNode 09:01:05 [09:01:05.521] INFO: node Stopping Aztec Node 09:01:05 [09:01:05.664] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:05 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:05 [09:01:05.664] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:06 [09:01:06.174] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:06 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:06 [09:01:06.175] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:06 [09:01:06.689] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:06 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:06 [09:01:06.689] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:07 [09:01:07.205] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:01:07 error: "Rollup__SlotAlreadyInChain(5, 5)" 09:01:07 [09:01:07.205] WARN: sequencer Cannot propose block 5 at slot 5 due to failed rollup contract check 09:01:07 [09:01:07.522] INFO: slasher Slasher client stopped. 09:01:07 [09:01:07.525] INFO: sequencer Stopped sequencer 09:01:07 [09:01:07.525] INFO: p2p P2P client stopped 09:01:07 [09:01:07.531] INFO: world_state Stopped world state synchronizer 09:01:07 [09:01:07.532] INFO: archiver Stopped. 09:01:07 [09:01:07.533] INFO: node Stopped Aztec Node 09:01:07 [09:01:07.550] INFO: blob-sink:server Stopping blob sink 09:01:07 [09:01:07.551] INFO: blob-sink:server Blob sink stopped 09:01:07 PASS src/e2e_snapshot_sync.test.ts 09:01:07 e2e_snapshot_sync 09:01:07 ✓ waits until a few L2 blocks have been mined and purges blobs (53023 ms) 09:01:07 ✓ creates a snapshot (1006 ms) 09:01:07 ✓ downloads snapshot when syncing new node (2513 ms) 09:01:07 ✓ downloads snapshot when syncing new prover node (711 ms) 09:01:07 09:01:07 Test Suites: 1 passed, 1 total 09:01:07 Tests: 4 passed, 4 total 09:01:07 Snapshots: 0 total 09:01:07 Time: 117.918 s 09:01:07 Ran all test suites matching /src\/e2e_snapshot_sync.test.ts/i. 09:01:07 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?