Parent Log: http://ci.aztec-labs.com/cc97b3c911040387 Command: aff82278d9e14e25 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/f880f14c946fb8464186d4178c5d49e391041988 Env: REF_NAME=gh-readonly-queue/next/pr-14967-b608f9359219731d3c03407689a4fadaaa5a0d45 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Thu Jun 12 14:09:06 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14967_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 14:09:08 [14:09:08.854] INFO: world_state Created world state synchroniser with block history of infinity 14:09:08 [14:09:08.961] INFO: world_state Started world state synchronizer from block 1 14:09:09 [14:09:09.274] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":4.2614440000002105,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.278] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.7301350000002458,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.280] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5217989999996462,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.482] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.8042719999998553,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.485] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5120380000003024,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.487] INFO: world_state Stopped world state synchronizer 14:09:09 [14:09:09.489] INFO: world_state Stopped world state synchronizer 14:09:09 [14:09:09.492] INFO: world_state Created world state synchroniser with block history of infinity 14:09:09 [14:09:09.666] INFO: world_state Started world state synchronizer from block 1 14:09:09 [14:09:09.945] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3944890000002488,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.948] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4441130000000157,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.951] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.274239999999736,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.953] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.551140999999916,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.955] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.370277999999871,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:09 [14:09:09.960] INFO: world_state Stopped world state synchronizer 14:09:09 [14:09:09.961] INFO: world_state Created world state synchroniser with block history of infinity 14:09:09 [14:09:09.962] INFO: world_state Started world state synchronizer from block 1 14:09:09 [14:09:09.963] INFO: world_state Stopped world state synchronizer 14:09:09 [14:09:09.963] INFO: world_state Created world state synchroniser with block history of infinity 14:09:09 [14:09:09.964] INFO: world_state Started world state synchronizer from block 1 14:09:09 [14:09:09.964] INFO: world_state Stopped world state synchronizer 14:09:09 [14:09:09.982] INFO: world_state Stopped world state synchronizer 14:09:09 [14:09:09.983] INFO: world_state Created world state synchroniser with block history of infinity 14:09:10 [14:09:10.166] INFO: world_state Started world state synchronizer from block 1 14:09:10 [14:09:10.446] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2286260000000766,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:10 [14:09:10.448] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9921580000000176,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:10 [14:09:10.449] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9857879999999568,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:10 [14:09:10.451] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9664160000002084,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:10 [14:09:10.452] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.97227600000042,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:10 [14:09:10.656] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":30.731010000000424,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:10 [14:09:10.658] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2620090000000346,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:10 [14:09:10.661] INFO: world_state Stopped world state synchronizer 14:09:10 [14:09:10.664] INFO: world_state Created world state synchroniser with block history of infinity 14:09:10 [14:09:10.840] INFO: world_state Started world state synchronizer from block 1 14:09:11 [14:09:11.198] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2488490000005186,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.201] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8903809999992518,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.202] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7809420000003229,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.203] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8034139999999752,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.204] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8192940000008093,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.481] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.7986430000000837,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.482] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.7615400000004229,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.483] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.6891640000003463,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.486] INFO: world_state Stopped world state synchronizer 14:09:11 [14:09:11.488] INFO: world_state Created world state synchroniser with block history of infinity 14:09:11 [14:09:11.631] INFO: world_state Started world state synchronizer from block 1 14:09:11 [14:09:11.931] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2356780000000072,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.937] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":5.5102850000002945,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.939] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1871340000006967,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.947] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":7.420216999999866,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.949] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.03048200000012,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:11 [14:09:11.951] INFO: world_state Stopped world state synchronizer 14:09:11 [14:09:11.952] INFO: world_state Created world state synchroniser with block history of infinity 14:09:12 [14:09:12.101] INFO: world_state Started world state synchronizer from block 1 14:09:12 [14:09:12.397] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.7574000000004162,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:12 [14:09:12.408] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":10.432615000000624,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:12 [14:09:12.410] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.074004999999488,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:12 [14:09:12.412] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2295070000000123,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:12 [14:09:12.413] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7676910000000134,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:09:12 [14:09:12.415] INFO: world_state Stopped world state synchronizer 14:09:12 [14:09:12.416] INFO: world_state Created world state synchroniser with block history of infinity 14:09:12 [14:09:12.420] INFO: world_state Stopped world state synchronizer 14:09:12 [14:09:12.421] INFO: world_state Created world state synchroniser with block history of infinity 14:09:12 [14:09:12.584] INFO: world_state Started world state synchronizer from block 1 14:09:12 [14:09:12.882] INFO: world_state Stopped world state synchronizer 14:09:12 PASS src/synchronizer/server_world_state_synchronizer.test.ts (5.673 s) 14:09:12 ServerWorldStateSynchronizer 14:09:12 ✓ updates sync progress (639 ms) 14:09:12 ✓ handles multiple calls to start (469 ms) 14:09:12 ✓ immediately syncs if no new blocks (2 ms) 14:09:12 ✓ cannot be started if already stopped (19 ms) 14:09:12 ✓ can immediately sync to latest (679 ms) 14:09:12 ✓ can immediately sync to a minimum block number (823 ms) 14:09:12 ✓ sync returns immediately if block was already synced (463 ms) 14:09:12 ✓ throws if you try to sync to an unavailable block (463 ms) 14:09:12 ✓ throws if you try to immediate sync when not running (5 ms) 14:09:12 ✓ throws if handling blocks fails (462 ms) 14:09:12 14:09:12 Test Suites: 1 passed, 1 total 14:09:12 Tests: 10 passed, 10 total 14:09:12 Snapshots: 0 total 14:09:12 Time: 5.762 s 14:09:12 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 14:09:12 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?