ACI ·

Parent Log: http://ci.aztec-labs.com/1526d186161d462d Command: 581a044a34e123b9: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/98fbe17330964452777de26d56f0a3ed5633aca1 Env: REF_NAME=gh-readonly-queue/next/pr-14921-fa23733be8278348dff0959c8094bcfd89eb44d5 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Thu Jun 12 10:17:40 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14921_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_eb8f79a88ef38365_next 10:17:40 +++ id -u 10:17:40 +++ id -g 10:17:40 ++ 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'\''' 10:17:40 + cid=8266b9a3133cea8d45f188cc740a127d71097801c81cc755d67dfd934d194aff 10:17:40 + set +x 10:17:41 {"level":25,"time":1749723461346,"pid":12,"hostname":"8266b9a3133c","module":"logger","msg":"Logger initialized with level verbose"} 10:17:47 [10:17:47.137] INFO: e2e:e2e_snapshot_sync Logging metrics to log/unknown.jsonl 10:17:47 [10:17:47.262] INFO: e2e:e2e_snapshot_sync Calling anvil_setBlockTimestampInterval with params: [8] 10:17:47 [10:17:47.284] WARN: e2e:e2e_snapshot_sync Set block interval to 8 10:17:47 [10:17:47.285] VERBOSE: e2e:e2e_snapshot_sync Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 10:17:47 [10:17:47.333] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xff8f3047f70ec9e63079d7903c02a47802dc292d5660d2da825ac209bbd9cd89 {"gasLimit":867658,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:17:47 [10:17:47.334] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xd8B56421e9b573AE62f2997B7b58858a4413A967 in tx 0xff8f3047f70ec9e63079d7903c02a47802dc292d5660d2da825ac209bbd9cd89. 10:17:47 [10:17:47.335] VERBOSE: e2e:e2e_snapshot_sync Deployed Fee Asset at 0xd8b56421e9b573ae62f2997b7b58858a4413a967 10:17:47 [10:17:47.356] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xb8a727e5dcee31d2ee7a6a94b8b01df7952fdc685dfb1b5bb36a0dbda9d08d89 {"gasLimit":867644,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:17:47 [10:17:47.356] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x026208feE3F4461826C8838C3569272E8C08E131 in tx 0xb8a727e5dcee31d2ee7a6a94b8b01df7952fdc685dfb1b5bb36a0dbda9d08d89. 10:17:47 [10:17:47.358] VERBOSE: e2e:e2e_snapshot_sync Deployed Staking Asset at 0x026208fee3f4461826c8838c3569272e8c08e131 10:17:47 [10:17:47.379] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x84345970a64bc89d244665c6d19bcf62f7a1fb0c5497fd61c66569ea96442f1f {"gasLimit":3118894,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:17:47 [10:17:47.379] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xad2120681B02De3e9b8aFd325977CDeDDf15f01C in tx 0x84345970a64bc89d244665c6d19bcf62f7a1fb0c5497fd61c66569ea96442f1f. 10:17:47 [10:17:47.380] VERBOSE: e2e:e2e_snapshot_sync Deployed GSE at 0xad2120681b02de3e9b8afd325977cdeddf15f01c 10:17:47 [10:17:47.397] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x96c1e759b945754976d026659a519c1c5d05a738916e69d742cdec67a5482765 {"gasLimit":1030513,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:17:47 [10:17:47.398] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x992D1f24CC5f368188d936cf0b2A111cEE586d4e in tx 0x96c1e759b945754976d026659a519c1c5d05a738916e69d742cdec67a5482765. 10:17:47 [10:17:47.398] VERBOSE: e2e:e2e_snapshot_sync Deployed Registry at 0x992d1f24cc5f368188d936cf0b2a111cee586d4e 10:17:47 [10:17:47.420] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x35838f7740ede8daad92b7b8589b59625f0100193e252bc6ce3862a65d23892c {"gasLimit":1998672,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:17:47 [10:17:47.420] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xfDB60896B1920A10Fd3AA268A292E51ebD5568A8 in tx 0x35838f7740ede8daad92b7b8589b59625f0100193e252bc6ce3862a65d23892c. 10:17:47 [10:17:47.421] VERBOSE: e2e:e2e_snapshot_sync Deployed GovernanceProposer at 0xfdb60896b1920a10fd3aa268a292e51ebd5568a8 10:17:47 [10:17:47.438] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x4dbcecd47a2081d10a38e4cf385714d92e542e5a44eb5a37a3810c6c6738dc4e {"gasLimit":3092007,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:17:47 [10:17:47.438] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x506150CaB49DBC09C8B79D20ac5c79CA28220AEA in tx 0x4dbcecd47a2081d10a38e4cf385714d92e542e5a44eb5a37a3810c6c6738dc4e. 10:17:47 [10:17:47.439] VERBOSE: e2e:e2e_snapshot_sync Deployed Governance at 0x506150cab49dbc09c8b79d20ac5c79ca28220aea 10:17:47 [10:17:47.450] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xf2ecc0d2040ae9928a4b186c21f02e611c2904f24b196eed69d646e9333cc00d {"gasLimit":100000,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:17:47 [10:17:47.451] VERBOSE: e2e:e2e_snapshot_sync Set governance on GSE in 0xf2ecc0d2040ae9928a4b186c21f02e611c2904f24b196eed69d646e9333cc00d 10:17:47 [10:17:47.465] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x77f9fa97fbbcb04392e88d456c198520b3425518c3d2d4146393099192372448 {"gasLimit":397390,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:17:47 [10:17:47.465] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x1649EeAD62C64Db7F63182989C8c41Ab25025C21 in tx 0x77f9fa97fbbcb04392e88d456c198520b3425518c3d2d4146393099192372448. 10:17:47 [10:17:47.466] VERBOSE: e2e:e2e_snapshot_sync Deployed CoinIssuer at 0x1649eead62c64db7f63182989c8c41ab25025c21 10:17:47 [10:17:47.467] VERBOSE: e2e:e2e_snapshot_sync Waiting for deployments to complete 10:17:47 [10:17:47.467] INFO: e2e:e2e_snapshot_sync Waiting for 7 transactions to be mined... 10:17:55 [10:17:55.494] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 10:17:55 [10:17:55.508] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xc033579f2357cfd2f6fa38d1ef9986415f4ed794add3fff64984bc3f1257e970 {"gasLimit":100000,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:17:55 [10:17:55.509] VERBOSE: e2e:e2e_snapshot_sync Added coin issuer 0x1649eead62c64db7f63182989c8c41ab25025c21 as minter on fee asset in 0xc033579f2357cfd2f6fa38d1ef9986415f4ed794add3fff64984bc3f1257e970 10:17:55 [10:17:55.530] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xd6a54e4eed6f07d2b8dcaafbd3883b3558583e43612f444af113d489245a2bb1 {"gasLimit":56661,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:17:55 [10:17:55.547] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xa67ff7f4001dc5703375e116caba00222d15ec13472e10af8018f62318b463cb {"gasLimit":344517,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:17:55 [10:17:55.548] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x3607dbAfDe17871E0D6A8f929151C30b83510D39 in tx 0xa67ff7f4001dc5703375e116caba00222d15ec13472e10af8018f62318b463cb. 10:17:55 [10:17:55.549] VERBOSE: e2e:e2e_snapshot_sync Deployed FeeAssetHandler at 0x3607dbafde17871e0d6a8f929151c30b83510d39 10:17:55 [10:17:55.608] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xa32bc1b02f5f02b41626611ec0e0056cad989330fbf908181b5d4f7678377aae {"gasLimit":56804,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:17:55 [10:17:55.609] VERBOSE: e2e:e2e_snapshot_sync Added fee asset handler 0x3607dbafde17871e0d6a8f929151c30b83510d39 as minter on fee asset in 0xa32bc1b02f5f02b41626611ec0e0056cad989330fbf908181b5d4f7678377aae 10:17:55 [10:17:55.667] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xadb884185b00b3cf3fb2aac4753fbbc2bbb58dfbab2a937d61ea0e950d96b91b {"gasLimit":2514669,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:17:55 [10:17:55.668] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x499C89D6b68cBdcA67C96Bff13Eb5B5a8fDb25a2 in tx 0xadb884185b00b3cf3fb2aac4753fbbc2bbb58dfbab2a937d61ea0e950d96b91b. 10:17:55 [10:17:55.669] VERBOSE: e2e:e2e_snapshot_sync Deployed StakingAssetHandler at 0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2 10:17:55 [10:17:55.690] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x2b32bbf996c6d3500330bc3ce74137b03f19074efe8ad0d3e14e6dea009f12e4 {"gasLimit":56804,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:17:55 [10:17:55.691] VERBOSE: e2e:e2e_snapshot_sync Added staking asset handler 0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2 as minter on staking asset in 0x2b32bbf996c6d3500330bc3ce74137b03f19074efe8ad0d3e14e6dea009f12e4 10:17:55 [10:17:55.691] VERBOSE: e2e:e2e_snapshot_sync Waiting for deployments to complete 10:17:55 [10:17:55.691] INFO: e2e:e2e_snapshot_sync Waiting for 9 transactions to be mined... 10:18:03 [10:18:03.708] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 10:18:03 [10:18:03.723] VERBOSE: e2e:e2e_snapshot_sync Deployed shared contracts 10:18:03 [10:18:03.761] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x1f3bf003fea3920e27f0ab1b5a7c080b356836c498ba06ccbbb2f38e05cac9a5 {"gasLimit":84823,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 10:18:03 [10:18:03.762] VERBOSE: e2e:e2e_snapshot_sync Funded reward distributor with 10000000000000000000000000 fee asset in 0x1f3bf003fea3920e27f0ab1b5a7c080b356836c498ba06ccbbb2f38e05cac9a5 10:18:03 [10:18:03.779] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x8ddfc36dd291a7e69dbb1d1c4b25283f53b9e73f733772b984e1b7cef75ca71d {"gasLimit":154658,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 10:18:03 [10:18:03.779] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x277E22B0C9698d7f2A1cD7C6d857511617587309 in tx 0x8ddfc36dd291a7e69dbb1d1c4b25283f53b9e73f733772b984e1b7cef75ca71d. 10:18:03 [10:18:03.779] VERBOSE: e2e:e2e_snapshot_sync Rollup will use the mock verifier at 0x277e22b0c9698d7f2a1cd7c6d857511617587309 10:18:03 [10:18:03.780] 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}} 10:18:03 [10:18:03.793] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x1244dd983dfa48995970c0afd0fb79b5391ff919edd2ee7939902fc973eec3e1 {"gasLimit":86992,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 10:18:03 [10:18:03.793] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xbba8820C80d584aEc2Cb092C3a0bE628aAB41fe3 in tx 0x1244dd983dfa48995970c0afd0fb79b5391ff919edd2ee7939902fc973eec3e1. 10:18:03 [10:18:03.817] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x0785920f3b1808bbb32fbbb9fc7abf594c7e8c615f21bf1b26859927d6d53edc {"gasLimit":6301243,"maxFeePerGas":"3.2549605","maxPriorityFeePerGas":"1.728"} 10:18:03 [10:18:03.818] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x0130CEd3370Af8E822F3D9076177E04F1cA75D3e in tx 0x0785920f3b1808bbb32fbbb9fc7abf594c7e8c615f21bf1b26859927d6d53edc. 10:18:03 [10:18:03.818] VERBOSE: e2e:e2e_snapshot_sync Awaiting for linked libraries to be deployed 10:18:11 [10:18:11.882] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x3daeef5eb107a28a4b51e0a0e6e54c1f9c1f559fddc1056421a65d8125bfb9ef {"gasLimit":11236180,"maxFeePerGas":"3.44200128","maxPriorityFeePerGas":"2.0736"} 10:18:11 [10:18:11.882] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0xe6681269F70341c9c915830CE8d82ba708f1e38d in tx 0x3daeef5eb107a28a4b51e0a0e6e54c1f9c1f559fddc1056421a65d8125bfb9ef. 10:18:11 [10:18:11.883] VERBOSE: e2e:e2e_snapshot_sync Deployed Rollup at 0xe6681269f70341c9c915830ce8d82ba708f1e38d {"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}} 10:18:11 [10:18:11.883] INFO: e2e:e2e_snapshot_sync Waiting for 11 transactions to be mined... 10:18:19 [10:18:19.921] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 10:18:19 [10:18:19.922] VERBOSE: e2e:e2e_snapshot_sync All core contracts have been deployed 10:18:20 [10:18:20.002] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x98c6311c69d385401dea725bc9c7d23328c18bbbb662d8dd754e6f044f87e0d2 {"gasLimit":1056496,"maxFeePerGas":"3.747782171","maxPriorityFeePerGas":"2.48832"} 10:18:20 [10:18:20.003] VERBOSE: e2e:e2e_snapshot_sync Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000001 to address 0x82480c7ECc8424FF3C3a8c8EA4bEd5dde484C60f in tx 0x98c6311c69d385401dea725bc9c7d23328c18bbbb662d8dd754e6f044f87e0d2. 10:18:20 [10:18:20.003] VERBOSE: e2e:e2e_snapshot_sync Deployed SlashFactory at 0x82480c7ecc8424ff3c3a8c8ea4bed5dde484c60f 10:18:20 [10:18:20.045] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0x68ed0163d692bc0e7f6be9ba63d66de99049d92adc66a11c16015eefd50924d9 {"gasLimit":116846,"maxFeePerGas":"3.747782171","maxPriorityFeePerGas":"2.48832"} 10:18:20 [10:18:20.045] VERBOSE: e2e:e2e_snapshot_sync Adding rollup 0xe6681269f70341c9c915830ce8d82ba708f1e38d to registry 0x992d1f24cc5f368188d936cf0b2a111cee586d4e in tx 0x68ed0163d692bc0e7f6be9ba63d66de99049d92adc66a11c16015eefd50924d9 10:18:20 [10:18:20.060] VERBOSE: e2e:e2e_snapshot_sync Sent L1 transaction 0xe53d85f89cbf044333ae771369b0627dd9a07aef9d34856298e48be4e06e01e5 {"gasLimit":109478,"maxFeePerGas":"3.747782171","maxPriorityFeePerGas":"2.48832"} 10:18:20 [10:18:20.060] VERBOSE: e2e:e2e_snapshot_sync Adding rollup 0xe6681269f70341c9c915830ce8d82ba708f1e38d to GSE 0xad2120681b02de3e9b8afd325977cdeddf15f01c in tx 0xe53d85f89cbf044333ae771369b0627dd9a07aef9d34856298e48be4e06e01e5 10:18:28 [10:18:28.072] INFO: e2e:e2e_snapshot_sync Waiting for 12 transactions to be mined... 10:18:28 [10:18:28.105] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 10:18:28 [10:18:28.109] VERBOSE: e2e:e2e_snapshot_sync Rollup deployed 10:18:28 [10:18:28.109] VERBOSE: e2e:e2e_snapshot_sync Waiting for rollup and slash factory to be deployed 10:18:28 [10:18:28.109] INFO: e2e:e2e_snapshot_sync Waiting for 12 transactions to be mined... 10:18:28 [10:18:28.135] INFO: e2e:e2e_snapshot_sync All transactions mined successfully 10:18:28 [10:18:28.135] VERBOSE: e2e:e2e_snapshot_sync All transactions for L1 deployment have been mined 10:18:28 [10:18:28.152] INFO: e2e:e2e_snapshot_sync Aztec L1 contracts initialized {"registryAddress":"0x992d1f24cc5f368188d936cf0b2a111cee586d4e","governanceAddress":"0x506150cab49dbc09c8b79d20ac5c79ca28220aea","governanceProposerAddress":"0xfdb60896b1920a10fd3aa268a292e51ebd5568a8","rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d","inboxAddress":"0xfcced8e5514ae59a06e23e98ccb966ecaf9660c9","outboxAddress":"0xec831096e06a109ba31fd68520e139fbff3cf016","feeJuicePortalAddress":"0xf20e4ae9a8de22863311e278e150d00f7cecae2f","feeJuiceAddress":"0xd8b56421e9b573ae62f2997b7b58858a4413a967","stakingAssetAddress":"0x026208fee3f4461826c8838c3569272e8c08e131","rewardDistributorAddress":"0xd462e23c2c8a9b4f83de76004a170d4cebb5d3e1","gseAddress":"0xad2120681b02de3e9b8afd325977cdeddf15f01c","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 10:18:28 [10:18:28.152] INFO: e2e:e2e_snapshot_sync Handing over to governance 10:18:28 [10:18:28.155] INFO: e2e:e2e_snapshot_sync Calling evm_setNextBlockTimestamp with params: [1749723515] 10:18:28 [10:18:28.157] INFO: e2e:e2e_snapshot_sync Calling hardhat_mine with params: [1] 10:18:28 [10:18:28.160] INFO: e2e:e2e_snapshot_sync Jumped to slot 1 10:18:28 [10:18:28.165] INFO: aztecjs:utils:watcher Watcher not started because not auto mining 10:18:28 [10:18:28.166] INFO: telemetry:client Using NoopTelemetryClient 10:18:28 [10:18:28.168] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/6accea321b33ec9d/blob-sink with map size 134217728 KB (LMDB v2) 10:18:28 [10:18:28.171] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 10:18:28 [10:18:28.180] INFO: blob-sink:server Server is running on http://localhost:39183 10:18:28 [10:18:28.180] VERBOSE: e2e:e2e_snapshot_sync Creating and synching an aztec node... 10:18:28 [10:18:28.181] VERBOSE: e2e:e2e_snapshot_sync Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/48001cc6/acvm 10:18:28 [10:18:28.182] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:39183"} 10:18:28 [10:18:28.209] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 10:18:28 [10:18:28.209] INFO: archiver:lmdb Creating archiver data store at directory /tmp/6accea321b33ec9d/archiver with map size 134217728 KB (LMDB v2) 10:18:28 [10:18:28.211] INFO: archiver:lmdb Starting data store with maxReaders 16 10:18:29 [10:18:29.091] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:39183"} 10:18:29 [10:18:29.108] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:39183"} 10:18:29 [10:18:29.108] WARN: node:blob-sink:client No L1 consensus host urls configured 10:18:29 [10:18:29.108] WARN: node:blob-sink:client No archive client configured 10:18:29 [10:18:29.112] INFO: archiver Starting archiver sync to rollup contract 0xe6681269f70341c9c915830ce8d82ba708f1e38d from L1 block 4 to current L1 block 6 with hash 0x11f352d8be9943463b22fa4dfe1606630d4da6c98cd589e989059df67cd25b4f {"blocksSynchedTo":4,"messagesSynchedTo":{"l1BlockHash":"0xf6bd51f89282414ff3320101e3e6839677121e5a8cb934cea625bd2eeb4c0dfe","l1BlockNumber":4}} 10:18:29 [10:18:29.126] INFO: archiver Initial archiver sync to L1 block 6 complete. {"l1BlockNumber":6,"syncPoint":{"blocksSynchedTo":6,"messagesSynchedTo":{"l1BlockHash":"0x11f352d8be9943463b22fa4dfe1606630d4da6c98cd589e989059df67cd25b4f","l1BlockNumber":6}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 10:18:29 [10:18:29.133] INFO: world-state:database Creating world state data store at directory /tmp/6accea321b33ec9d/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 10:18:29 [10:18:29.168] INFO: world_state Created world state synchroniser with block history of 64 10:18:29 [10:18:29.168] WARN: node Aztec node is accepting fake proofs 10:18:29 [10:18:29.179] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/6accea321b33ec9d/p2p with map size 134217728 KB (LMDB v2) 10:18:29 [10:18:29.180] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 10:18:29 [10:18:29.181] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/6accea321b33ec9d/p2p-archive with map size 134217728 KB (LMDB v2) 10:18:29 [10:18:29.182] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 10:18:29 [10:18:29.184] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/6accea321b33ec9d/p2p-peers with map size 134217728 KB (LMDB v2) 10:18:29 [10:18:29.184] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 10:18:29 [10:18:29.185] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 10:18:29 [10:18:29.186] VERBOSE: p2p P2P is disabled. Using dummy P2P service 10:18:29 [10:18:29.189] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 10:18:29 [10:18:29.189] INFO: world_state Started world state synchronizer from block 1 10:18:29 [10:18:29.192] VERBOSE: world-state:block_stream Starting sync from block number 0 10:18:29 [10:18:29.193] INFO: p2p Starting p2p client from block 0 with empty mempools 10:18:29 [10:18:29.194] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 10:18:29 [10:18:29.519] INFO: epoch-prune-watcher EpochPruneWatcher initialized 10:18:29 [10:18:29.568] VERBOSE: validator Initialized validator with addresses: 0x3b860c294c96162e675511a6a6f9ede643051e93 10:18:29 [10:18:29.569] VERBOSE: node All Aztec Node subsystems synced 10:18:29 [10:18:29.583] INFO: slasher Starting Slasher client... 10:18:29 [10:18:29.593] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 10:18:29 [10:18:29.595] INFO: sequencer-client Deploying forwarder contract 10:18:29 [10:18:29.612] VERBOSE: sequencer-client Sent L1 transaction 0xda888973b4ad2d3d163799c42065a89b2c05bcb621343d65d24f9b27b4d854fa {"gasLimit":323704,"maxFeePerGas":"2.259597543","maxPriorityFeePerGas":"1.2"} 10:18:29 [10:18:29.612] VERBOSE: sequencer-client Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000042 to address 0x0fe4d493871B0C51332De8A330B02BA8172Da4aC in tx 0xda888973b4ad2d3d163799c42065a89b2c05bcb621343d65d24f9b27b4d854fa. 10:18:37 [10:18:37.627] INFO: sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac 10:18:37 [10:18:37.635] INFO: validator Started validator with addresses: 0x3b860c294c96162e675511a6a6f9ede643051e93 10:18:37 [10:18:37.635] VERBOSE: validator Validators 0x3b860c294c96162e675511a6a6f9ede643051e93 are not on the validator committee for epoch 0 10:18:37 [10:18:37.636] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":0,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/48001cc6/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":8,"enforceTimeTable":false} 10:18:37 [10:18:37.636] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 10:18:37 [10:18:37.638] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:18:37 [10:18:37.638] INFO: node Aztec Node version: 0.87.6 10:18:37 [10:18:37.639] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0x992d1f24cc5f368188d936cf0b2a111cee586d4e","governanceAddress":"0x506150cab49dbc09c8b79d20ac5c79ca28220aea","governanceProposerAddress":"0xfdb60896b1920a10fd3aa268a292e51ebd5568a8","rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d","inboxAddress":"0xfcced8e5514ae59a06e23e98ccb966ecaf9660c9","outboxAddress":"0xec831096e06a109ba31fd68520e139fbff3cf016","feeJuicePortalAddress":"0xf20e4ae9a8de22863311e278e150d00f7cecae2f","feeJuiceAddress":"0xd8b56421e9b573ae62f2997b7b58858a4413a967","stakingAssetAddress":"0x026208fee3f4461826c8838c3569272e8c08e131","rewardDistributorAddress":"0xd462e23c2c8a9b4f83de76004a170d4cebb5d3e1","gseAddress":"0xad2120681b02de3e9b8afd325977cdeddf15f01c","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x82480c7ecc8424ff3c3a8c8ea4bed5dde484c60f","feeAssetHandlerAddress":"0x3607dbafde17871e0d6a8f929151c30b83510d39","stakingAssetHandlerAddress":"0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"} 10:18:37 [10:18:37.640] VERBOSE: e2e:e2e_snapshot_sync Creating a pxe... 10:18:37 [10:18:37.645] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/817035fac47cf4ff/pxe_data with map size 134217728 KB (LMDB v2) 10:18:37 [10:18:37.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 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 10:18:37 [10:18:37.649] INFO: pxe:data:lmdb Starting data store with maxReaders 16 10:18:37 [10:18:37.946] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 10:18:37 [10:18:37.947] INFO: pxe:service Started PXE connected to chain 31337 version 2100458793 10:18:37 [10:18:37.950] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync waits until a few L2 blocks have been mined and purges blobs 10:18:37 [10:18:37.950] WARN: e2e:e2e_snapshot_sync Waiting for L2 blocks to be mined 10:18:38 [10:18:38.160] 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} 10:18:38 [10:18:38.182] INFO: e2e:e2e_snapshot_sync L1 block 7 mined at 10:18:43 with new L2 block 0 for epoch 0 with proof up to L2 block 0 for epoch 0 {"l1Timestamp":1749723523,"l1BlockNumber":7,"l2SlotNumber":1,"l2BlockNumber":0,"l2ProvenBlockNumber":0,"totalL2Messages":0} 10:18:38 [10:18:38.666] 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} 10:18:39 [10:18:39.173] 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} 10:18:39 [10:18:39.679] 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} 10:18:40 [10:18:40.187] 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} 10:18:40 [10:18:40.695] 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} 10:18:41 [10:18:41.203] 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} 10:18:41 [10:18:41.711] 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} 10:18:42 [10:18:42.217] 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} 10:18:42 [10:18:42.723] 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} 10:18:43 [10:18:43.164] INFO: e2e:e2e_snapshot_sync L1 block 8 mined at 10:18:51 {"l1Timestamp":1749723531,"l1BlockNumber":8,"l2SlotNumber":2,"l2BlockNumber":0,"l2ProvenBlockNumber":0,"totalL2Messages":0} 10:18:43 [10:18:43.237] 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":1749723531,"version":2100458793},"chainTipArchive":"0x1f9c798be7975bb34c3e605a4c92c75796eae7b9a08644bc9a6a55354ed470be","blockNumber":1,"slot":2} 10:18:43 [10:18:43.255] VERBOSE: block-builder Building block 1 for slot 2 {"slot":2,"slotStart":"2025-06-12T10:18:51.000Z","now":"2025-06-12T10:18:43.255Z","blockNumber":1,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 10:18:43 [10:18:43.260] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.0017593110000016167s {"duration":0.0017593110000016167,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 10:18:43 [10:18:43.275] INFO: sequencer Built block 1 for slot 2 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x1beee7ae235b7c176283f2ebb38c5bb36db5fa3aa8460b776e3799c81ed200d0","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749723531,"version":2100458793},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":29.38245000000461,"publicProcessDuration":2.5714570000054664,"rollupCircuitsDuration":21.172339999997348,"txCount":0,"blockNumber":1,"blockTimestamp":1749723531,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:18:43 [10:18:43.277] VERBOSE: sequencer Attesting committee is empty 10:18:45 [10:18:45.701] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xc02ae1e85bb62f5f8ee56c154bfd340fb4048d244776331f6dc6b943d300d4eb 10:18:45 [10:18:45.701] VERBOSE: node Sent L1 transaction 0xc02ae1e85bb62f5f8ee56c154bfd340fb4048d244776331f6dc6b943d300d4eb {"gasLimit":485800,"maxFeePerGas":"2.013314732","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:18:51 [10:18:51.173] INFO: e2e:e2e_snapshot_sync L1 block 9 mined at 10:18:59 with new L2 block 1 for epoch 0 {"l1Timestamp":1749723539,"l1BlockNumber":9,"l2SlotNumber":2,"l2BlockNumber":1,"l2ProvenBlockNumber":0,"totalL2Messages":0} 10:18:51 [10:18:51.343] WARN: node:blob-sink:client Unable to get blob sidecar for 0xab0621d2525f6143139bf7ee461a6bbda98522b0a1235b25e97015f1a43cec46: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 10:18:51 [10:18:51.343] WARN: node:blob-sink:client Failed to fetch blobs for 0xab0621d2525f6143139bf7ee461a6bbda98522b0a1235b25e97015f1a43cec46 from all blob sources {"blobSinkUrl":"http://localhost:39183"} 10:18:51 [10:18:51.765] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:18:51 [10:18:51.766] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xc02ae1e85bb62f5f8ee56c154bfd340fb4048d244776331f6dc6b943d300d4eb","calldataSize":1220,"calldataGas":8840,"txCount":0,"blockNumber":1,"blockTimestamp":1749723531,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:18:51 [10:18:51.767] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:18:51 [10:18:51.790] INFO: blob-sink:server Received blob sidecar for block 0xab0621d2525f6143139bf7ee461a6bbda98522b0a1235b25e97015f1a43cec46 10:18:51 [10:18:51.798] INFO: blob-sink:server Blob sidecar stored successfully for block 0xab0621d2525f6143139bf7ee461a6bbda98522b0a1235b25e97015f1a43cec46 10:18:51 [10:18:51.910] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x1beee7ae235b7c176283f2ebb38c5bb36db5fa3aa8460b776e3799c81ed200d0","blockNumber":1,"txCount":0,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749723531,"version":2100458793},"archiveRoot":"0x19d11bd0a5cdb139ab617771999411f45e7b94a797e8b766e40934b8eaf4f476","archiveNextLeafIndex":2} 10:18:51 [10:18:51.935] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":4.5212159999937285,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":1,"blockTimestamp":1749723531,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:18:51 [10:18:51.936] VERBOSE: p2p Synched to latest block 1 10:18:52 [10:18:52.312] 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} 10:18:52 [10:18:52.823] 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} 10:18:53 [10:18:53.332] 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} 10:18:53 [10:18:53.840] 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} 10:18:54 [10:18:54.348] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 2 but got 3. {"rollup":[3,2],"newBlockNumber":2,"expectedSlot":2} 10:18:54 [10:18:54.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} 10:18:55 [10:18:55.366] 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} 10:18:55 [10:18:55.875] 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} 10:18:56 [10:18:56.385] 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} 10:18:56 [10:18:56.895] 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} 10:18:57 [10:18:57.404] 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} 10:18:57 [10:18:57.913] 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} 10:18:58 [10:18:58.426] 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} 10:18:58 [10:18:58.940] 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} 10:18:59 [10:18:59.184] INFO: e2e:e2e_snapshot_sync L1 block 10 mined at 10:19:07 {"l1Timestamp":1749723547,"l1BlockNumber":10,"l2SlotNumber":3,"l2BlockNumber":1,"l2ProvenBlockNumber":0,"totalL2Messages":0} 10:18:59 [10:18:59.460] 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":1749723547,"version":2100458793},"chainTipArchive":"0x19d11bd0a5cdb139ab617771999411f45e7b94a797e8b766e40934b8eaf4f476","blockNumber":2,"slot":3} 10:18:59 [10:18:59.485] VERBOSE: block-builder Building block 2 for slot 3 {"slot":3,"slotStart":"2025-06-12T10:19:07.000Z","now":"2025-06-12T10:18:59.485Z","blockNumber":2,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 10:18:59 [10:18:59.489] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.0014046999999991385s {"duration":0.0014046999999991385,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 10:18:59 [10:18:59.525] INFO: sequencer Built block 2 for slot 3 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x064b42107014bd12041bf4100cd28faaa9ed7a826d225ef7407c20f3497911ee","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749723547,"version":2100458793},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":48.45980399999826,"publicProcessDuration":1.8247940000001108,"rollupCircuitsDuration":42.66323399999237,"txCount":0,"blockNumber":2,"blockTimestamp":1749723547,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:18:59 [10:18:59.538] VERBOSE: sequencer Attesting committee is empty 10:18:59 [10:18:59.639] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x73e0150daefd42707ccbbae34c17e6fc3a8d24375a0e7a737ff4f64308902a2e 10:18:59 [10:18:59.639] VERBOSE: node Sent L1 transaction 0x73e0150daefd42707ccbbae34c17e6fc3a8d24375a0e7a737ff4f64308902a2e {"gasLimit":414577,"maxFeePerGas":"1.824718914","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:19:07 [10:19:07.205] INFO: e2e:e2e_snapshot_sync L1 block 11 mined at 10:19:15 with new L2 block 2 for epoch 0 {"l1Timestamp":1749723555,"l1BlockNumber":11,"l2SlotNumber":3,"l2BlockNumber":2,"l2ProvenBlockNumber":0,"totalL2Messages":0} 10:19:07 [10:19:07.352] WARN: node:blob-sink:client Unable to get blob sidecar for 0xf5c67f9378cb271a6c086b533c5efefa7614d58bd2945ba913a44ab63781f50f: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 10:19:07 [10:19:07.353] WARN: node:blob-sink:client Failed to fetch blobs for 0xf5c67f9378cb271a6c086b533c5efefa7614d58bd2945ba913a44ab63781f50f from all blob sources {"blobSinkUrl":"http://localhost:39183"} 10:19:07 [10:19:07.749] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:19:07 [10:19:07.750] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x73e0150daefd42707ccbbae34c17e6fc3a8d24375a0e7a737ff4f64308902a2e","calldataSize":1220,"calldataGas":8840,"txCount":0,"blockNumber":2,"blockTimestamp":1749723547,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:07 [10:19:07.751] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:19:07 [10:19:07.769] INFO: blob-sink:server Received blob sidecar for block 0xf5c67f9378cb271a6c086b533c5efefa7614d58bd2945ba913a44ab63781f50f 10:19:07 [10:19:07.779] INFO: blob-sink:server Blob sidecar stored successfully for block 0xf5c67f9378cb271a6c086b533c5efefa7614d58bd2945ba913a44ab63781f50f 10:19:07 [10:19:07.972] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x064b42107014bd12041bf4100cd28faaa9ed7a826d225ef7407c20f3497911ee","blockNumber":2,"txCount":0,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749723547,"version":2100458793},"archiveRoot":"0x1ae9c10e4375ebe43b92b2b167c53d96dabe42580be2c7f8c3e3a6d0d3052d52","archiveNextLeafIndex":3} 10:19:08 [10:19:08.052] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":5.526480000000447,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":2,"blockTimestamp":1749723547,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:08 [10:19:08.089] VERBOSE: p2p Synched to latest block 2 10:19:08 [10:19:08.305] 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} 10:19:08 [10:19:08.825] 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} 10:19:09 [10:19:09.353] 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} 10:19:09 [10:19:09.864] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 3 but got 4. {"rollup":[4,3],"newBlockNumber":3,"expectedSlot":3} 10:19:10 [10:19:10.378] 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} 10:19:10 [10:19:10.889] 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} 10:19:11 [10:19:11.401] 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} 10:19:11 [10:19:11.911] 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} 10:19:12 [10:19:12.433] 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} 10:19:12 [10:19:12.952] 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} 10:19:13 [10:19:13.462] 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} 10:19:14 [10:19:14.004] 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} 10:19:14 [10:19:14.528] 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} 10:19:15 [10:19:15.047] 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":1749723563,"version":2100458793},"chainTipArchive":"0x1ae9c10e4375ebe43b92b2b167c53d96dabe42580be2c7f8c3e3a6d0d3052d52","blockNumber":3,"slot":4} 10:19:15 [10:19:15.059] VERBOSE: block-builder Building block 3 for slot 4 {"slot":4,"slotStart":"2025-06-12T10:19:23.000Z","now":"2025-06-12T10:19:15.059Z","blockNumber":3,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 10:19:15 [10:19:15.062] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.0004093939999875147s {"duration":0.0004093939999875147,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 10:19:15 [10:19:15.077] INFO: sequencer Built block 3 for slot 4 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x10d42343b6ff921a62693bd4b1fbcbb5a8aa6a7b198483d24f23cd1863d59d7e","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749723563,"version":2100458793},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":24.346940000003087,"publicProcessDuration":0.8017359999939799,"rollupCircuitsDuration":18.730215000003227,"txCount":0,"blockNumber":3,"blockTimestamp":1749723563,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:15 [10:19:15.080] VERBOSE: sequencer Attesting committee is empty 10:19:15 [10:19:15.172] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xb1b022c084985175719cca574198ee558c298340ae330df24db531df819b72aa 10:19:15 [10:19:15.173] VERBOSE: node Sent L1 transaction 0xb1b022c084985175719cca574198ee558c298340ae330df24db531df819b72aa {"gasLimit":416962,"maxFeePerGas":"1.679585347","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:19:15 [10:19:15.257] INFO: e2e:e2e_snapshot_sync L1 block 12 mined at 10:19:23 {"l1Timestamp":1749723563,"l1BlockNumber":12,"l2SlotNumber":4,"l2BlockNumber":2,"l2ProvenBlockNumber":0,"totalL2Messages":0} 10:19:23 [10:19:23.262] INFO: e2e:e2e_snapshot_sync L1 block 13 mined at 10:19:31 with new L2 block 3 for epoch 0 {"l1Timestamp":1749723571,"l1BlockNumber":13,"l2SlotNumber":4,"l2BlockNumber":3,"l2ProvenBlockNumber":0,"totalL2Messages":0} 10:19:23 [10:19:23.279] WARN: node:blob-sink:client Unable to get blob sidecar for 0xcbfe24286363f6c912a8cd89c08780e07a640410c0d9ef016c2086d5d24119c7: Not Found (404) {"status":404,"statusText":"Not Found","body":"{\"error\":\"Blob not found\"}"} 10:19:23 [10:19:23.279] WARN: node:blob-sink:client Failed to fetch blobs for 0xcbfe24286363f6c912a8cd89c08780e07a640410c0d9ef016c2086d5d24119c7 from all blob sources {"blobSinkUrl":"http://localhost:39183"} 10:19:23 [10:19:23.306] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:19:23 [10:19:23.306] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xb1b022c084985175719cca574198ee558c298340ae330df24db531df819b72aa","calldataSize":1220,"calldataGas":8828,"txCount":0,"blockNumber":3,"blockTimestamp":1749723563,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:23 [10:19:23.307] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:19:23 [10:19:23.311] INFO: blob-sink:server Received blob sidecar for block 0xcbfe24286363f6c912a8cd89c08780e07a640410c0d9ef016c2086d5d24119c7 10:19:23 [10:19:23.319] INFO: blob-sink:server Blob sidecar stored successfully for block 0xcbfe24286363f6c912a8cd89c08780e07a640410c0d9ef016c2086d5d24119c7 10:19:23 [10:19:23.854] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x10d42343b6ff921a62693bd4b1fbcbb5a8aa6a7b198483d24f23cd1863d59d7e","blockNumber":3,"txCount":0,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749723563,"version":2100458793},"archiveRoot":"0x2d0cfbed64b863ff2cfabc003eea3e98e35b43d1a4c57d126c8a1fa989e9df9b","archiveNextLeafIndex":4} 10:19:23 [10:19:23.867] WARN: sequencer:publisher Failed canProposeAtTime check with InvalidArchive 10:19:23 error: "Rollup__InvalidArchive(0x2d0cfbed64b863ff2cfabc003eea3e98e35b43d1a4c57d126c8a1fa989e9df9b, 0x1ae9c10e4375ebe43b92b2b167c53d96dabe42580be2c7f8c3e3a6d0d3052d52)" 10:19:23 [10:19:23.868] WARN: sequencer Cannot propose block 3 at slot 4 due to failed rollup contract check 10:19:23 [10:19:23.961] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":4.77778699999908,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":3,"blockTimestamp":1749723563,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:23 [10:19:23.976] VERBOSE: p2p Synched to latest block 3 10:19:24 [10:19:24.376] 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} 10:19:24 [10:19:24.890] 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} 10:19:25 [10:19:25.402] 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} 10:19:25 [10:19:25.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 4 but got 5. {"rollup":[5,4],"newBlockNumber":4,"expectedSlot":4} 10:19:26 [10:19:26.441] 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} 10:19:26 [10:19:26.953] 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} 10:19:27 [10:19:27.462] 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} 10:19:27 [10:19:27.973] 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} 10:19:28 [10:19:28.484] 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} 10:19:28 [10:19:28.997] 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} 10:19:29 [10:19:29.508] 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} 10:19:30 [10:19:30.016] 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} 10:19:30 [10:19:30.537] 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} 10:19:31 [10:19:31.049] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":249180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749723579,"version":2100458793},"chainTipArchive":"0x2d0cfbed64b863ff2cfabc003eea3e98e35b43d1a4c57d126c8a1fa989e9df9b","blockNumber":4,"slot":5} 10:19:31 [10:19:31.057] VERBOSE: block-builder Building block 4 for slot 5 {"slot":5,"slotStart":"2025-06-12T10:19:39.000Z","now":"2025-06-12T10:19:31.057Z","blockNumber":4,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 10:19:31 [10:19:31.059] INFO: simulator:public-processor Processed 0 successful txs and 0 failed txs in 0.00021579200000269337s {"duration":0.00021579200000269337,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":0,"l2Gas":0},"totalSizeInBytes":0} 10:19:31 [10:19:31.072] INFO: sequencer Built block 4 for slot 5 with 0 txs and 0 messages. 0 mana/s {"blockHash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":249180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749723579,"version":2100458793},"txHashes":[],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":19.22118700000283,"publicProcessDuration":0.6555179999995744,"rollupCircuitsDuration":15.023388000001432,"txCount":0,"blockNumber":4,"blockTimestamp":1749723579,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:31 [10:19:31.075] VERBOSE: sequencer Attesting committee is empty 10:19:31 [10:19:31.138] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xc493291747bf72966cc5e92134e364a5ad896832f07f50615e3605370ad9f123 10:19:31 [10:19:31.138] VERBOSE: node Sent L1 transaction 0xc493291747bf72966cc5e92134e364a5ad896832f07f50615e3605370ad9f123 {"gasLimit":416974,"maxFeePerGas":"1.85963718","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 10:19:31 [10:19:31.264] INFO: e2e:e2e_snapshot_sync L1 block 14 mined at 10:19:39 {"l1Timestamp":1749723579,"l1BlockNumber":14,"l2SlotNumber":5,"l2BlockNumber":3,"l2ProvenBlockNumber":0,"totalL2Messages":0} 10:19:39 [10:19:39.198] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:19:39 [10:19:39.198] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xc493291747bf72966cc5e92134e364a5ad896832f07f50615e3605370ad9f123","calldataSize":1220,"calldataGas":8828,"txCount":0,"blockNumber":4,"blockTimestamp":1749723579,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:39 [10:19:39.199] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:19:39 [10:19:39.202] INFO: blob-sink:server Received blob sidecar for block 0x45374264657f9e4b80846d4629b47722b95261555fdd807fd7b76d02ecc06959 10:19:39 [10:19:39.205] INFO: blob-sink:server Blob sidecar stored successfully for block 0x45374264657f9e4b80846d4629b47722b95261555fdd807fd7b76d02ecc06959 10:19:39 [10:19:39.282] INFO: e2e:e2e_snapshot_sync L1 block 15 mined at 10:19:47 with new L2 block 4 for epoch 0 {"l1Timestamp":1749723587,"l1BlockNumber":15,"l2SlotNumber":5,"l2BlockNumber":4,"l2ProvenBlockNumber":0,"totalL2Messages":0} 10:19:39 [10:19:39.646] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778","blockNumber":4,"txCount":0,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":249180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749723579,"version":2100458793},"archiveRoot":"0x0c8e3aeaa0136973add42f48189a5a4f387bae2482faa1c8b7d9b20d37f5e410","archiveNextLeafIndex":5} 10:19:39 [10:19:39.657] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.497023000003537,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":4,"blockTimestamp":1749723579,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:39 [10:19:39.696] VERBOSE: p2p Synched to latest block 4 10:19:39 [10:19:39.708] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:39 [10:19:39.986] WARN: e2e:e2e_snapshot_sync L2 block height is now 4. Purging all blobs from sink so snapshot is required. 10:19:39 [10:19:39.990] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync creates a snapshot 10:19:39 [10:19:39.990] WARN: e2e:e2e_snapshot_sync Creating snapshot 10:19:39 [10:19:39.992] INFO: stdlib:file-store Creating local file file store at /tmp/snapshots-qwqbV4 10:19:39 [10:19:39.993] INFO: node Pausing archiver and world state sync to start snapshot upload 10:19:39 [10:19:39.993] INFO: archiver Stopped. 10:19:39 [10:19:39.994] INFO: world_state Stopped sync 10:19:39 [10:19:39.994] INFO: node Creating backups of lmdb environments to /tmp/6accea321b33ec9d/snapshot-NoR7lG 10:19:39 [10:19:39.997] INFO: node Data stores backed up to /tmp/6accea321b33ec9d/snapshot-NoR7lG {"paths":{"l1-to-l2-message-tree":"/tmp/6accea321b33ec9d/snapshot-NoR7lG/world-state/L1ToL2MessageTree/data.mdb","archive-tree":"/tmp/6accea321b33ec9d/snapshot-NoR7lG/world-state/ArchiveTree/data.mdb","public-data-tree":"/tmp/6accea321b33ec9d/snapshot-NoR7lG/world-state/PublicDataTree/data.mdb","note-hash-tree":"/tmp/6accea321b33ec9d/snapshot-NoR7lG/world-state/NoteHashTree/data.mdb","nullifier-tree":"/tmp/6accea321b33ec9d/snapshot-NoR7lG/world-state/NullifierTree/data.mdb","archiver":"/tmp/6accea321b33ec9d/snapshot-NoR7lG/archiver/data.mdb"}} 10:19:39 [10:19:39.998] INFO: node Resuming archiver and world state sync 10:19:39 [10:19:39.998] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 10:19:39 [10:19:39.999] INFO: world_state Resumed sync 10:19:39 [10:19:39.999] INFO: archiver Restarting archiver 10:19:40 [10:19:40.003] INFO: node Uploading snapshot to file:///tmp/snapshots-qwqbV4 {"snapshot":{"l1ChainId":31337,"rollupVersion":2100458793,"rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d","l2BlockNumber":4,"l2BlockHash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778","l1BlockNumber":15}} 10:19:40 [10:19:40.009] INFO: node Snapshot uploaded successfully {"snapshot":{"l1BlockNumber":15,"l2BlockHash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778","l2BlockNumber":4,"schemaVersions":{"archiver":2,"worldState":1},"timestamp":1749723580004,"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/l1-to-l2-message-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archive-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archive-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","public-data-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/public-data-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","note-hash-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/note-hash-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","nullifier-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/nullifier-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archiver":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archiver-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db"}}} 10:19:40 [10:19:40.009] INFO: node Cleaning up backup dir /tmp/6accea321b33ec9d/snapshot-NoR7lG 10:19:40 [10:19:40.217] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:40 [10:19:40.733] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:40 [10:19:40.995] WARN: e2e:e2e_snapshot_sync Snapshot created 10:19:40 [10:19:40.997] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync downloads snapshot when syncing new node 10:19:40 [10:19:40.997] WARN: e2e:e2e_snapshot_sync Syncing brand new node with snapshot sync 10:19:40 [10:19:40.998] WARN: e2e:e2e_snapshot_sync Creating and syncing a node without a validator... 10:19:40 [10:19:40.999] INFO: node:blob-sink:client:1 Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:39183"} 10:19:41 [10:19:41.024] INFO: node:1 Creating local file file store at /tmp/snapshots-qwqbV4 10:19:41 [10:19:41.025] VERBOSE: node:1 Creating temporary archiver data store 10:19:41 [10:19:41.030] INFO: archiver:lmdb:1 Creating archiver data store at directory /tmp/6accea321b33ec9d/87a3559599be7c78/archiver with map size 134217728 KB (LMDB v2) 10:19:41 [10:19:41.036] INFO: archiver:lmdb:1 Starting data store with maxReaders 16 10:19:41 [10:19:41.038] VERBOSE: node:1 Closing temporary archiver data store {"archiverL2BlockNumber":0} 10:19:41 [10:19:41.041] INFO: node:1 Syncing from snapshot at L1 block 15 L2 block 4 {"snapshot":{"l2BlockNumber":4,"l2BlockHash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778","l1BlockNumber":15,"timestamp":1749723580004,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/l1-to-l2-message-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archive-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archive-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","public-data-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/public-data-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","note-hash-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/note-hash-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","nullifier-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/nullifier-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archiver":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archiver-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db"}},"snapshotsUrl":"file:///tmp/snapshots-qwqbV4"} 10:19:41 [10:19:41.042] INFO: node:1 Creating local file file store at /tmp/snapshots-qwqbV4 10:19:41 [10:19:41.042] INFO: node:1 Downloading snapshot to /tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn {"snapshot":{"l2BlockNumber":4,"l2BlockHash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778","l1BlockNumber":15,"timestamp":1749723580004,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/l1-to-l2-message-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archive-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archive-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","public-data-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/public-data-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","note-hash-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/note-hash-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","nullifier-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/nullifier-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archiver":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archiver-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db"}},"downloadPaths":{"archiver":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/archiver.db","nullifier-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/nullifier-tree.db","public-data-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/public-data-tree.db","note-hash-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/note-hash-tree.db","archive-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/archive-tree.db","l1-to-l2-message-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/l1-to-l2-message-tree.db"}} 10:19:41 [10:19:41.046] INFO: node:1 Snapshot downloaded at /tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn {"snapshot":{"l2BlockNumber":4,"l2BlockHash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778","l1BlockNumber":15,"timestamp":1749723580004,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/l1-to-l2-message-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archive-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archive-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","public-data-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/public-data-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","note-hash-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/note-hash-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","nullifier-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/nullifier-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archiver":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archiver-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db"}},"downloadPaths":{"archiver":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/archiver.db","nullifier-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/nullifier-tree.db","public-data-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/public-data-tree.db","note-hash-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/note-hash-tree.db","archive-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/archive-tree.db","l1-to-l2-message-tree":"/tmp/6accea321b33ec9d/87a3559599be7c78/download-glcRNn/l1-to-l2-message-tree.db"}} 10:19:41 [10:19:41.048] INFO: node:1 Archiver database set up from snapshot {"path":"/tmp/6accea321b33ec9d/87a3559599be7c78/archiver","dbVersion":2,"rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d"} 10:19:41 [10:19:41.049] INFO: node:1 World state database l1-to-l2-message-tree set up from snapshot {"path":"/tmp/6accea321b33ec9d/87a3559599be7c78/world_state/L1ToL2MessageTree","dbVersion":1,"rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d"} 10:19:41 [10:19:41.049] INFO: node:1 World state database archive-tree set up from snapshot {"path":"/tmp/6accea321b33ec9d/87a3559599be7c78/world_state/ArchiveTree","dbVersion":1,"rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d"} 10:19:41 [10:19:41.049] INFO: node:1 World state database public-data-tree set up from snapshot {"path":"/tmp/6accea321b33ec9d/87a3559599be7c78/world_state/PublicDataTree","dbVersion":1,"rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d"} 10:19:41 [10:19:41.050] INFO: node:1 World state database note-hash-tree set up from snapshot {"path":"/tmp/6accea321b33ec9d/87a3559599be7c78/world_state/NoteHashTree","dbVersion":1,"rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d"} 10:19:41 [10:19:41.050] INFO: node:1 World state database nullifier-tree set up from snapshot {"path":"/tmp/6accea321b33ec9d/87a3559599be7c78/world_state/NullifierTree","dbVersion":1,"rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d"} 10:19:41 [10:19:41.050] INFO: node:1 P2P database cleared {"path":"/tmp/6accea321b33ec9d/87a3559599be7c78/p2p"} 10:19:41 [10:19:41.051] INFO: node:1 Snapshot synced to L1 block 15 L2 block 4 {"snapshot":{"l2BlockNumber":4,"l2BlockHash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778","l1BlockNumber":15,"timestamp":1749723580004,"schemaVersions":{"archiver":2,"worldState":1},"dataUrls":{"l1-to-l2-message-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/l1-to-l2-message-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archive-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archive-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","public-data-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/public-data-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","note-hash-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/note-hash-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","nullifier-tree":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/nullifier-tree-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db","archiver":"file:///tmp/snapshots-qwqbV4/aztec-31337-2100458793-0xe6681269f70341c9c915830ce8d82ba708f1e38d/archiver-20250612101940-0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778.db"}}} 10:19:41 [10:19:41.051] INFO: archiver:lmdb:1 Creating archiver data store at directory /tmp/6accea321b33ec9d/87a3559599be7c78/archiver with map size 134217728 KB (LMDB v2) 10:19:41 [10:19:41.053] INFO: archiver:lmdb:1 Starting data store with maxReaders 16 10:19:41 [10:19:41.262] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:41 [10:19:41.335] INFO: node:blob-sink:client:1 Testing configured blob sources {"blobSinkUrl":"http://localhost:39183"} 10:19:41 [10:19:41.337] INFO: node:blob-sink:client:1 Blob sink is reachable {"blobSinkUrl":"http://localhost:39183"} 10:19:41 [10:19:41.337] WARN: node:blob-sink:client:1 No L1 consensus host urls configured 10:19:41 [10:19:41.337] WARN: node:blob-sink:client:1 No archive client configured 10:19:41 [10:19:41.339] INFO: archiver:1 Starting archiver sync to rollup contract 0xe6681269f70341c9c915830ce8d82ba708f1e38d from L1 block 15 to current L1 block 15 with hash 0x45374264657f9e4b80846d4629b47722b95261555fdd807fd7b76d02ecc06959 {"blocksSynchedTo":15,"messagesSynchedTo":{"l1BlockHash":"0x45374264657f9e4b80846d4629b47722b95261555fdd807fd7b76d02ecc06959","l1BlockNumber":15}} 10:19:41 [10:19:41.343] INFO: archiver:1 Initial archiver sync to L1 block 15 complete. {"l1BlockNumber":15,"syncPoint":{"blocksSynchedTo":15,"messagesSynchedTo":{"l1BlockHash":"0x45374264657f9e4b80846d4629b47722b95261555fdd807fd7b76d02ecc06959","l1BlockNumber":15}},"latest":{"number":4,"hash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778"},"proven":{"number":0},"finalized":{"number":0}} 10:19:41 [10:19:41.353] INFO: world-state:database:1 Creating world state data store at directory /tmp/6accea321b33ec9d/87a3559599be7c78/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 10:19:41 [10:19:41.363] INFO: world_state:1 Created world state synchroniser with block history of 64 10:19:41 [10:19:41.363] WARN: node:1 Aztec node is accepting fake proofs 10:19:41 [10:19:41.375] INFO: p2p:lmdb-v2:1 Creating p2p data store at directory /tmp/6accea321b33ec9d/87a3559599be7c78/p2p with map size 134217728 KB (LMDB v2) 10:19:41 [10:19:41.376] INFO: p2p:lmdb-v2:1 Starting data store with maxReaders 16 10:19:41 [10:19:41.378] INFO: p2p-archive:lmdb-v2:1 Creating p2p-archive data store at directory /tmp/6accea321b33ec9d/87a3559599be7c78/p2p-archive with map size 134217728 KB (LMDB v2) 10:19:41 [10:19:41.379] INFO: p2p-archive:lmdb-v2:1 Starting data store with maxReaders 16 10:19:41 [10:19:41.380] INFO: p2p-peer:lmdb-v2:1 Creating p2p-peers data store at directory /tmp/6accea321b33ec9d/87a3559599be7c78/p2p-peers with map size 134217728 KB (LMDB v2) 10:19:41 [10:19:41.381] INFO: p2p-peer:lmdb-v2:1 Starting data store with maxReaders 16 10:19:41 [10:19:41.382] INFO: p2p:tx_pool:1 Setting maximum tx mempool size {"maxTxPoolSize":100000000} 10:19:41 [10:19:41.383] VERBOSE: p2p:1 P2P is disabled. Using dummy P2P service 10:19:41 [10:19:41.384] VERBOSE: world-state:block_stream:1 Starting L2 block stream {"proven":false,"pollIntervalMS":100} 10:19:41 [10:19:41.385] INFO: world_state:1 Started world state synchronizer from block 5 10:19:41 [10:19:41.387] VERBOSE: world-state:block_stream:1 Starting sync from block number 4 10:19:41 [10:19:41.388] INFO: p2p:1 Starting p2p client from block 4 with empty mempools 10:19:41 [10:19:41.389] VERBOSE: p2p:l2-block-stream:1 Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":4} 10:19:41 [10:19:41.389] INFO: epoch-prune-watcher:1 EpochPruneWatcher initialized 10:19:41 [10:19:41.389] VERBOSE: node:1 All Aztec Node subsystems synced 10:19:41 [10:19:41.395] INFO: slasher:1 Starting Slasher client... 10:19:41 [10:19:41.397] INFO: node:1 Aztec Node version: 0.87.6 10:19:41 [10:19:41.397] INFO: node:1 Aztec Node started on chain 0x7a69 {"registryAddress":"0x992d1f24cc5f368188d936cf0b2a111cee586d4e","governanceAddress":"0x506150cab49dbc09c8b79d20ac5c79ca28220aea","governanceProposerAddress":"0xfdb60896b1920a10fd3aa268a292e51ebd5568a8","rollupAddress":"0xe6681269f70341c9c915830ce8d82ba708f1e38d","inboxAddress":"0xfcced8e5514ae59a06e23e98ccb966ecaf9660c9","outboxAddress":"0xec831096e06a109ba31fd68520e139fbff3cf016","feeJuicePortalAddress":"0xf20e4ae9a8de22863311e278e150d00f7cecae2f","feeJuiceAddress":"0xd8b56421e9b573ae62f2997b7b58858a4413a967","stakingAssetAddress":"0x026208fee3f4461826c8838c3569272e8c08e131","rewardDistributorAddress":"0xd462e23c2c8a9b4f83de76004a170d4cebb5d3e1","gseAddress":"0xad2120681b02de3e9b8afd325977cdeddf15f01c","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x82480c7ecc8424ff3c3a8c8ea4bed5dde484c60f","feeAssetHandlerAddress":"0x3607dbafde17871e0d6a8f929151c30b83510d39","stakingAssetHandlerAddress":"0x499c89d6b68cbdca67c96bff13eb5b5a8fdb25a2","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"} 10:19:41 [10:19:41.398] WARN: e2e:e2e_snapshot_sync New node synced 10:19:41 [10:19:41.405] VERBOSE: p2p:l2-block-stream:1 Starting sync from block number 3 10:19:41 [10:19:41.412] WARN: e2e:e2e_snapshot_sync Checking for L2 block 3 with hash 0x10d42343b6ff921a62693bd4b1fbcbb5a8aa6a7b198483d24f23cd1863d59d7e on both nodes 10:19:41 [10:19:41.419] VERBOSE: p2p:1 Synched to latest block 4 10:19:41 [10:19:41.429] WARN: e2e:e2e_snapshot_sync Stopping new node 10:19:41 [10:19:41.430] INFO: node:1 Stopping Aztec Node 10:19:41 [10:19:41.772] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:42 [10:19:42.285] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:42 [10:19:42.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 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:43 [10:19:43.312] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:43 [10:19:43.431] INFO: slasher:1 Slasher client stopped. 10:19:43 [10:19:43.432] INFO: p2p:1 P2P client stopped 10:19:43 [10:19:43.433] INFO: world_state:1 Stopped world state synchronizer 10:19:43 [10:19:43.433] INFO: archiver:1 Stopped. 10:19:43 [10:19:43.433] INFO: node:1 Stopped Aztec Node 10:19:43 [10:19:43.434] INFO: e2e:e2e_snapshot_sync Running test: e2e_snapshot_sync downloads snapshot when syncing new prover node 10:19:43 [10:19:43.434] WARN: e2e:e2e_snapshot_sync Syncing brand new prover node with snapshot sync 10:19:43 [10:19:43.434] WARN: e2e:e2e_snapshot_sync Creating and syncing a prover node... 10:19:43 [10:19:43.508] INFO: blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:39183"} 10:19:43 [10:19:43.518] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/6accea321b33ec9d/550666cc5a37970c/archiver with map size 134217728 KB (LMDB v2) 10:19:43 [10:19:43.529] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 10:19:43 [10:19:43.850] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:43 [10:19:43.851] INFO: blob-sink:client:prover-node Testing configured blob sources {"blobSinkUrl":"http://localhost:39183"} 10:19:43 [10:19:43.854] INFO: blob-sink:client:prover-node Blob sink is reachable {"blobSinkUrl":"http://localhost:39183"} 10:19:43 [10:19:43.854] WARN: blob-sink:client:prover-node No L1 consensus host urls configured 10:19:43 [10:19:43.854] WARN: blob-sink:client:prover-node No archive client configured 10:19:43 [10:19:43.856] INFO: archiver:prover-node Starting archiver sync to rollup contract 0xe6681269f70341c9c915830ce8d82ba708f1e38d from L1 block 4 to current L1 block 15 with hash 0x45374264657f9e4b80846d4629b47722b95261555fdd807fd7b76d02ecc06959 {"blocksSynchedTo":4,"messagesSynchedTo":{"l1BlockHash":"0xf6bd51f89282414ff3320101e3e6839677121e5a8cb934cea625bd2eeb4c0dfe","l1BlockNumber":4}} 10:19:44 [10:19:44.032] INFO: archiver:prover-node Downloaded L2 block 1 {"blockHash":"0x1beee7ae235b7c176283f2ebb38c5bb36db5fa3aa8460b776e3799c81ed200d0","blockNumber":1,"txCount":0,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749723531,"version":2100458793},"archiveRoot":"0x19d11bd0a5cdb139ab617771999411f45e7b94a797e8b766e40934b8eaf4f476","archiveNextLeafIndex":2} 10:19:44 [10:19:44.032] INFO: archiver:prover-node Downloaded L2 block 2 {"blockHash":"0x064b42107014bd12041bf4100cd28faaa9ed7a826d225ef7407c20f3497911ee","blockNumber":2,"txCount":0,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749723547,"version":2100458793},"archiveRoot":"0x1ae9c10e4375ebe43b92b2b167c53d96dabe42580be2c7f8c3e3a6d0d3052d52","archiveNextLeafIndex":3} 10:19:44 [10:19:44.033] INFO: archiver:prover-node Downloaded L2 block 3 {"blockHash":"0x10d42343b6ff921a62693bd4b1fbcbb5a8aa6a7b198483d24f23cd1863d59d7e","blockNumber":3,"txCount":0,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":316640,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749723563,"version":2100458793},"archiveRoot":"0x2d0cfbed64b863ff2cfabc003eea3e98e35b43d1a4c57d126c8a1fa989e9df9b","archiveNextLeafIndex":4} 10:19:44 [10:19:44.033] INFO: archiver:prover-node Downloaded L2 block 4 {"blockHash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778","blockNumber":4,"txCount":0,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":249180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749723579,"version":2100458793},"archiveRoot":"0x0c8e3aeaa0136973add42f48189a5a4f387bae2482faa1c8b7d9b20d37f5e410","archiveNextLeafIndex":5} 10:19:44 [10:19:44.041] INFO: archiver:prover-node Initial archiver sync to L1 block 15 complete. {"l1BlockNumber":15,"syncPoint":{"blocksSynchedTo":15,"messagesSynchedTo":{"l1BlockHash":"0x45374264657f9e4b80846d4629b47722b95261555fdd807fd7b76d02ecc06959","l1BlockNumber":15}},"latest":{"number":4,"hash":"0x244ba47edc91e352da4bdf05803f03858e24e7498b043faeed8758737bf61778"},"proven":{"number":0},"finalized":{"number":0}} 10:19:44 [10:19:44.045] INFO: prover-node:blob-sink:client:prover-node Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:39183"} 10:19:44 [10:19:44.045] INFO: prover-node:prover-node Creating local file file store at /tmp/snapshots-qwqbV4 10:19:44 [10:19:44.045] VERBOSE: prover-node:prover-node Creating temporary archiver data store 10:19:44 [10:19:44.047] INFO: archiver:lmdb:prover-node Creating archiver data store at directory /tmp/6accea321b33ec9d/550666cc5a37970c/archiver with map size 134217728 KB (LMDB v2) 10:19:44 [10:19:44.050] INFO: archiver:lmdb:prover-node Starting data store with maxReaders 16 10:19:44 [10:19:44.054] VERBOSE: prover-node:prover-node Closing temporary archiver data store {"archiverL1BlockNumber":15,"archiverL2BlockNumber":4} 10:19:44 [10:19:44.055] VERBOSE: prover-node:prover-node Skipping non-forced snapshot sync as archiver is already synced to L2 block 4. 10:19:44 [10:19:44.055] VERBOSE: prover-node:prover-node Created archiver and synced to block 4 10:19:44 [10:19:44.060] INFO: world-state:database:prover-node Creating world state data store at directory /tmp/6accea321b33ec9d/550666cc5a37970c/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 10:19:44 [10:19:44.095] INFO: world_state:prover-node Created world state synchroniser with block history of 64 10:19:44 [10:19:44.097] VERBOSE: world_state:prover-node Starting sync from 1 to latest block 4 10:19:44 [10:19:44.098] VERBOSE: world-state:block_stream:prover-node Starting L2 block stream {"proven":false,"pollIntervalMS":100} 10:19:44 [10:19:44.098] INFO: world_state:prover-node Started world state synchronizer from block 1 10:19:44 [10:19:44.100] VERBOSE: world-state:block_stream:prover-node Starting sync from block number 0 10:19:44 [10:19:44.107] INFO: world_state:prover-node World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.130413999999291,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":1,"blockTimestamp":1749723531,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:44 [10:19:44.115] INFO: world_state:prover-node World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":6.602268999995431,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":2,"blockTimestamp":1749723547,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:44 [10:19:44.122] INFO: world_state:prover-node World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":7.109007999999449,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":3,"blockTimestamp":1749723563,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:44 [10:19:44.127] INFO: world_state:prover-node World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":3.7545260000042617,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":0,"blockNumber":4,"blockTimestamp":1749723579,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:19:44 [10:19:44.128] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/6accea321b33ec9d/550666cc5a37970c/archiver when loading epoch databases, ignoring 10:19:44 [10:19:44.128] WARN: prover-client:proving-broker-database:prover-node Found invalid epoch directory /tmp/6accea321b33ec9d/550666cc5a37970c/world_state when loading epoch databases, ignoring 10:19:44 [10:19:44.129] INFO: prover-client:proving-broker:prover-node Proving Broker started 10:19:44 [10:19:44.142] INFO: prover-node:prover-coordination:prover-node Using prover coordination via aztec node 10:19:44 [10:19:44.144] INFO: e2e:e2e_snapshot_sync:prover-node Created and synced prover node {"publisherAddress":"0x9965507D1a55bcC2695C58ba16FB37d819B0A4dc"} 10:19:44 [10:19:44.145] INFO: prover-node:epoch-monitor:prover-node Started EpochMonitor {"pollingIntervalMs":200} 10:19:44 [10:19:44.148] 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/6accea321b33ec9d/550666cc5a37970c","l1ChainId":31337,"rollupVersion":2100458793} 10:19:44 [10:19:44.148] WARN: e2e:e2e_snapshot_sync New node prover synced 10:19:44 [10:19:44.149] VERBOSE: prover-node:prover-node Fetching 0 tx hashes for block number 4 from coordination 10:19:44 [10:19:44.150] INFO: prover-node:combined-prover-coordination:prover-node Check for 0 txs found all in the pool 10:19:44 [10:19:44.153] WARN: e2e:e2e_snapshot_sync Stopping new prover node 10:19:44 [10:19:44.153] INFO: prover-node:prover-node Stopping ProverNode 10:19:44 [10:19:44.153] INFO: prover-node:epoch-monitor:prover-node Stopped EpochMonitor 10:19:44 [10:19:44.154] INFO: archiver:prover-node Stopped. 10:19:44 [10:19:44.155] INFO: world_state:prover-node Stopped world state synchronizer 10:19:44 [10:19:44.155] INFO: prover-node:prover-node Stopped ProverNode 10:19:44 [10:19:44.157] INFO: node Stopping Aztec Node 10:19:44 [10:19:44.367] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:44 [10:19:44.880] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:45 [10:19:45.390] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:45 [10:19:45.899] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 5 but got 6. {"rollup":[6,5],"newBlockNumber":5,"expectedSlot":5} 10:19:46 [10:19:46.158] INFO: slasher Slasher client stopped. 10:19:46 [10:19:46.160] INFO: sequencer Stopped sequencer 10:19:46 [10:19:46.160] INFO: p2p P2P client stopped 10:19:46 [10:19:46.162] INFO: world_state Stopped world state synchronizer 10:19:46 [10:19:46.162] INFO: archiver Stopped. 10:19:46 [10:19:46.162] INFO: node Stopped Aztec Node 10:19:46 [10:19:46.175] INFO: blob-sink:server Stopping blob sink 10:19:46 [10:19:46.175] INFO: blob-sink:server Blob sink stopped 10:19:46 PASS src/e2e_snapshot_sync.test.ts 10:19:46 e2e_snapshot_sync 10:19:46 ✓ waits until a few L2 blocks have been mined and purges blobs (62039 ms) 10:19:46 ✓ creates a snapshot (1006 ms) 10:19:46 ✓ downloads snapshot when syncing new node (2437 ms) 10:19:46 ✓ downloads snapshot when syncing new prover node (721 ms) 10:19:46 10:19:46 Test Suites: 1 passed, 1 total 10:19:46 Tests: 4 passed, 4 total 10:19:46 Snapshots: 0 total 10:19:46 Time: 125.047 s 10:19:46 Ran all test suites matching /src\/e2e_snapshot_sync.test.ts/i. 10:19:46 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?