Parent Log: http://ci.aztec-labs.com/9e3ec241cb0caf21 Command: 9b0f2b09b6684c8a playground/scripts/run_test.sh chromium Commit: https://github.com/AztecProtocol/aztec-packages/commit/a907d3a9f40aa473deec5c77c658a80089184fa8 Env: REF_NAME=gh-readonly-queue/next/pr-14989-ada03190841b1923625905b0e6ea3bdc4a3b3e84 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Thu Jun 12 10:57:14 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14989_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_3af8716c4dfeb01b_next 10:57:14 Network playground-chromium_default Creating 10:57:14 Network playground-chromium_default Created 10:57:14 Container playground-chromium-ethereum-1 Creating 10:57:16 Container playground-chromium-ethereum-1 Created 10:57:16 Container playground-chromium-aztec-1 Creating 10:57:16 Container playground-chromium-aztec-1 Created 10:57:16 Container playground-chromium-playground-1 Creating 10:57:17 Container playground-chromium-playground-1 Created 10:57:17 Attaching to aztec-1, ethereum-1, playground-1 10:57:23 aztec-1 | 10:57:23 aztec-1 | _ 10:57:23 aztec-1 | /\ | | 10:57:23 aztec-1 | / \ ___| |_ ___ ___ 10:57:23 aztec-1 | / /\ \ |_ / __/ _ \/ __| 10:57:23 aztec-1 | / ____ \ / /| || __/ (__ 10:57:23 aztec-1 | /_/___ \_\/___|\__\___|\___| 10:57:23 aztec-1 | 10:57:23 aztec-1 | https://github.com/AztecProtocol 10:57:23 aztec-1 | 10:57:23 aztec-1 | 10:57:23 aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by... 10:57:23 aztec-1 | Setting up test accounts 10:57:24 aztec-1 | [10:57:23.803] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-kFB0j8/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 10:57:24 aztec-1 | [10:57:24.094] INFO: sandbox Calling anvil_setBlockTimestampInterval with params: [12] 10:57:24 aztec-1 | [10:57:24.099] WARN: sandbox Set block interval to 12 10:57:24 aztec-1 | [10:57:24.330] INFO: sandbox Waiting for 7 transactions to be mined... 10:57:24 aztec-1 | [10:57:24.362] INFO: sandbox All transactions mined successfully 10:57:24 aztec-1 | [10:57:24.465] INFO: sandbox Waiting for 9 transactions to be mined... 10:57:24 aztec-1 | [10:57:24.485] INFO: sandbox All transactions mined successfully 10:57:24 aztec-1 | [10:57:24.640] INFO: sandbox Waiting for 11 transactions to be mined... 10:57:24 aztec-1 | [10:57:24.666] INFO: sandbox All transactions mined successfully 10:57:24 aztec-1 | [10:57:24.751] INFO: sandbox Waiting for 12 transactions to be mined... 10:57:24 aztec-1 | [10:57:24.773] INFO: sandbox All transactions mined successfully 10:57:24 aztec-1 | [10:57:24.779] INFO: sandbox Waiting for 12 transactions to be mined... 10:57:24 aztec-1 | [10:57:24.810] INFO: sandbox All transactions mined successfully 10:57:24 aztec-1 | [10:57:24.827] INFO: sandbox Aztec L1 contracts initialized {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x68b1d87f95878fe05b998f19b66f4baba5de1aed","inboxAddress":"0xa12ffa0b9f159bb4c54bce579611927addc51610","outboxAddress":"0x53839913417ebc7171723489f29b9b54f49b4eea","feeJuicePortalAddress":"0x67671478a98e62381bf90cdd328cffcddd26c83a","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 10:57:24 aztec-1 | [10:57:24.828] INFO: sandbox Handing over to governance 10:57:24 aztec-1 | [10:57:24.837] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x68B1D87F95878fE05B998F19b66F4baba5De1aed 10:57:24 aztec-1 | [10:57:24.838] INFO: telemetry:client Using NoopTelemetryClient 10:57:24 aztec-1 | [10:57:24.838] INFO: blob-sink:client Creating local blob sink client. 10:57:24 aztec-1 | [10:57:24.844] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 10:57:24 aztec-1 | [10:57:24.844] WARN: foundation:test-date-provider Time set to 2025-06-12T11:01:54.000Z {"offset":269156,"timeMs":1749726114000} 10:57:24 aztec-1 | [10:57:24.866] INFO: archiver:lmdb Starting data store with maxReaders 16 10:57:25 aztec-1 | [10:57:25.245] INFO: archiver Starting archiver sync to rollup contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed from L1 block 19 to current L1 block 23 with hash 0xdbd57c7e255d001e003c000e4f6366207336cc80e128fc6cefd9530d53f2d7d1 {"blocksSynchedTo":19,"messagesSynchedTo":{"l1BlockHash":"0x362ed11c17f7271bd10a6c6b80046c6e9b0808aa635dc2a66de94e74150807d1","l1BlockNumber":19}} 10:57:25 aztec-1 | [10:57:25.274] INFO: archiver Initial archiver sync to L1 block 23 complete. {"l1BlockNumber":23,"syncPoint":{"blocksSynchedTo":23,"messagesSynchedTo":{"l1BlockHash":"0xdbd57c7e255d001e003c000e4f6366207336cc80e128fc6cefd9530d53f2d7d1","l1BlockNumber":23}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 10:57:25 aztec-1 | [10:57:25.281] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-nXb6wb/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 10:57:25 aztec-1 | [10:57:25.519] INFO: world_state Created world state synchroniser with block history of 64 10:57:25 aztec-1 | [10:57:25.519] WARN: node Aztec node is accepting fake proofs 10:57:25 aztec-1 | [10:57:25.530] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 10:57:25 aztec-1 | [10:57:25.545] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 10:57:25 aztec-1 | [10:57:25.560] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 10:57:25 aztec-1 | [10:57:25.576] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 10:57:25 aztec-1 | [10:57:25.579] INFO: world_state Started world state synchronizer from block 1 10:57:26 aztec-1 | [10:57:25.582] INFO: p2p Starting p2p client from block 0 with empty mempools 10:57:26 aztec-1 | [10:57:25.851] INFO: epoch-prune-watcher EpochPruneWatcher initialized 10:57:26 aztec-1 | [10:57:25.919] INFO: slasher Starting Slasher client... 10:57:26 aztec-1 | [10:57:25.929] INFO: sequencer-client Deploying forwarder contract 10:57:26 aztec-1 | [10:57:25.951] INFO: sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac 10:57:26 aztec-1 | [10:57:25.959] INFO: validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:57:26 aztec-1 | [10:57:25.960] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":50,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":true} 10:57:26 aztec-1 | [10:57:25.962] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:57:26 aztec-1 | [10:57:25.963] INFO: node Aztec Node version: 0.87.6 10:57:26 aztec-1 | [10:57:25.963] INFO: node Aztec Node started on chain 0x7a69 {"rollupAddress":"0x68b1d87f95878fe05b998f19b66f4baba5de1aed","registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","inboxAddress":"0xa12ffa0b9f159bb4c54bce579611927addc51610","outboxAddress":"0x53839913417ebc7171723489f29b9b54f49b4eea","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","feeJuicePortalAddress":"0x67671478a98e62381bf90cdd328cffcddd26c83a","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","slashFactoryAddress":"0xc6e7df5e7b4f2a278906862b61205850344d4e7d","feeAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788","stakingAssetHandlerAddress":"0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"} 10:57:26 aztec-1 | [10:57:25.967] INFO: pxe:data:lmdb Starting data store with maxReaders 16 10:57:26 aztec-1 | [10:57:25.977] 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:57:26 aztec-1 | [10:57:26.048] 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:57:26 aztec-1 | [10:57:26.120] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 10:57:26 aztec-1 | [10:57:26.120] WARN: foundation:test-date-provider Time set to 2025-06-12T11:02:06.000Z {"offset":279880,"timeMs":1749726126000} 10:57:26 aztec-1 | [10:57:26.121] 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:57:26 aztec-1 | Setting up funded test accounts... 10:57:26 aztec-1 | [10:57:26.383] INFO: pxe:service Started PXE connected to chain 31337 version 3573608979 10:57:26 aztec-1 | [10:57:26.482] INFO: pxe:service Registered account 0x2098b7a51b5545e6dcbf3d1f703b1fa961aee3fd4afa74cfc5fcdaaf9473ddef 10:57:26 aztec-1 | [10:57:26.560] WARN: pxe:service No artifact found for contract class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f when looking for its metadata 10:57:26 aztec-1 | [10:57:26.563] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f as part of deployment for 0x2098b7a51b5545e6dcbf3d1f703b1fa961aee3fd4afa74cfc5fcdaaf9473ddef 10:57:26 aztec-1 | [10:57:26.738] INFO: pxe:service Added contract SchnorrAccount at 0x2098b7a51b5545e6dcbf3d1f703b1fa961aee3fd4afa74cfc5fcdaaf9473ddef with class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f 10:57:29 aztec-1 | [10:57:29.350] INFO: pxe:service Proving completed in 2561.364960000001ms {"timings":{"total":2561.364960000001,"sync":18.794630000000325,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":160.66729499999838,"oracles":{"callPrivateFunction":{"times":[801.1175169999988,367.2817869999999,208.3064409999988]}}},{"functionName":"private_kernel_init","time":90.18806399999994},{"functionName":"ContractClassRegisterer:register","time":778.0018170000021,"oracles":{"loadCapsule":{"times":[13.449220999998943]},"notifyCreatedNullifier":{"times":[22.394399000000703]},"debugLog":{"times":[1.0292779999999766]},"notifyCreatedContractClassLog":{"times":[7.369834000000992]}}},{"functionName":"private_kernel_inner","time":160.08133799999996},{"functionName":"SchnorrAccount:constructor","time":340.80022500000086,"oracles":{"getContractInstance":{"times":[1.547962999999072]},"debugLog":{"times":[0.18819500000063272,0.133614000000307]},"fetchTaggedLogs":{"times":[72.17126599999938]},"loadCapsule":{"times":[0.5508150000005116,0.3824800000002142,0.4363119999998162]},"validateEnqueuedNotesAndEvents":{"times":[7.798736000000645]},"notifyCreatedNullifier":{"times":[0.4366119999995135,0.37082100000043283]},"notifyCreatedNote":{"times":[0.6813089999996009]},"getRandomField":{"times":[0.4508219999988796,0.21048599999994622,0.11308400000052643,0.09436300000015763,0.10057299999971292,0.1885550000006333,0.06082099999912316,0.05648199999995995,0.05399100000067847,0.06169199999931152,0.04173199999968347]},"getIndexedTaggingSecretAsSender":{"times":[45.79290700000092]},"incrementAppTaggingSecretIndexAsSender":{"times":[30.69133899999906]}}},{"functionName":"private_kernel_inner","time":107.29787800000122},{"functionName":"SchnorrAccount:entrypoint","time":187.84114500000032,"oracles":{"debugLog":{"times":[0.21525599999949918,0.08117200000015146,0.3162179999999353]},"fetchTaggedLogs":{"times":[53.326325]},"loadCapsule":{"times":[0.24913700000070094,0.1421940000000177,0.13443399999960093]},"validateEnqueuedNotesAndEvents":{"times":[6.103949000000284]},"getNotes":{"times":[3.348312000000078]},"getAuthWitness":{"times":[0.24704600000040955]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.7592709999989893]}}},{"functionName":"private_kernel_inner","time":102.53045600000041},{"functionName":"private_kernel_reset","time":164.0839479999995},{"functionName":"private_kernel_tail","time":71.91120899999987}],"unaccounted":379.16695499999787}} 10:57:29 aztec-1 | [10:57:29.445] INFO: node Received tx 0x1b78ea59a2c2f513c640eb4895c7ad5e264f78bf7e721e18c093d34629233765 {"txHash":"0x1b78ea59a2c2f513c640eb4895c7ad5e264f78bf7e721e18c093d34629233765"} 10:57:29 aztec-1 | [10:57:29.445] INFO: pxe:service Sent transaction 0x1b78ea59a2c2f513c640eb4895c7ad5e264f78bf7e721e18c093d34629233765 10:57:29 aztec-1 | [10:57:29.547] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.04033181500000137s {"duration":0.04033181500000137,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 10:57:29 aztec-1 | [10:57:29.576] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x29c8e127ccc206cf7ada2e22b7ca827e92f7a92ba303ae885688a98c2ce4f3d9","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749726138,"version":3573608979},"txHashes":["0x1b78ea59a2c2f513c640eb4895c7ad5e264f78bf7e721e18c093d34629233765"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":79.76426600000013,"publicProcessDuration":40.95759299999918,"rollupCircuitsDuration":71.6424709999992,"txCount":1,"blockNumber":1,"blockTimestamp":1749726138,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:57:33 aztec-1 | [10:57:33.061] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 10:57:33 aztec-1 | [10:57:33.063] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 10:57:33 aztec-1 | [10:57:33.068] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:57:33 aztec-1 | [10:57:33.162] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 10:57:33 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)" 10:57:33 aztec-1 | [10:57:33.162] WARN: sequencer Cannot propose block 1 at slot 2 due to failed rollup contract check 10:57:33 aztec-1 | [10:57:33.171] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x29c8e127ccc206cf7ada2e22b7ca827e92f7a92ba303ae885688a98c2ce4f3d9","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749726138,"version":3573608979},"archiveRoot":"0x01a6690e8ba23f5fb00c9ceaa220f213c3b5af6ecbf4f5e65d71915b2dbdbc18","archiveNextLeafIndex":2} 10:57:33 aztec-1 | [10:57:33.178] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 10:57:33 aztec-1 | [10:57:33.219] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":17.93899600000077,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749726138,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:57:33 aztec-1 | [10:57:33.275] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 10:57:33 aztec-1 | [10:57:33.275] WARN: foundation:test-date-provider Time set to 2025-06-12T11:02:18.000Z {"offset":284725,"timeMs":1749726138000} 10:57:33 aztec-1 | [10:57:33.293] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749726174 10:57:33 aztec-1 | [10:57:33.293] WARN: foundation:test-date-provider Time set to 2025-06-12T11:02:54.000Z {"offset":320707,"timeMs":1749726174000} 10:57:33 aztec-1 | [10:57:33.294] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 10:57:33 aztec-1 | [10:57:33.319] INFO: pxe:service Registered account 0x0ab5671f409a3df349e09dcdba57de3eab9811dbb53fdc7040509b7f514dd741 10:57:33 aztec-1 | [10:57:33.441] INFO: pxe:service Added contract SchnorrAccount at 0x0ab5671f409a3df349e09dcdba57de3eab9811dbb53fdc7040509b7f514dd741 with class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f 10:57:35 aztec-1 | [10:57:34.918] INFO: pxe:service Proving completed in 1452.3612479999993ms {"timings":{"total":1452.3612479999993,"sync":27.951473000000988,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":112.77647999999681,"oracles":{"callPrivateFunction":{"times":[348.22719099999995,216.9713010000014]}}},{"functionName":"private_kernel_init","time":70.30087400000048},{"functionName":"SchnorrAccount:constructor","time":322.97250200000053,"oracles":{"getContractInstance":{"times":[4.191826000000219]},"debugLog":{"times":[0.13658399999985704,0.1032630000008794]},"fetchTaggedLogs":{"times":[87.03238700000111]},"loadCapsule":{"times":[0.22183600000062142,0.24172700000053737,2.3658750000013242]},"validateEnqueuedNotesAndEvents":{"times":[10.735927000001539]},"notifyCreatedNullifier":{"times":[0.33099899999979243,0.19537599999966915]},"notifyCreatedNote":{"times":[0.11530299999867566]},"getRandomField":{"times":[0.20608499999980268,0.2004659999984142,0.08455200000025798,0.07584200000019337,0.06386199999906239,0.05350199999884353,0.055272000001423294,0.07539200000064739,0.06340199999976903,0.05760100000043167,0.0410810000012134]},"getIndexedTaggingSecretAsSender":{"times":[40.78024800000094]},"incrementAppTaggingSecretIndexAsSender":{"times":[20.52550700000029]}}},{"functionName":"private_kernel_inner","time":138.3787570000004},{"functionName":"SchnorrAccount:entrypoint","time":189.96118300000126,"oracles":{"debugLog":{"times":[0.18684599999869533,0.08959300000060466,0.2774279999994178]},"fetchTaggedLogs":{"times":[69.65707699999984]},"loadCapsule":{"times":[0.19769500000074913,0.2354670000004262,0.1509939999996277]},"validateEnqueuedNotesAndEvents":{"times":[7.357323000000179]},"getNotes":{"times":[1.0218580000000657]},"getAuthWitness":{"times":[0.15063399999962712]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.4625930000001972]}}},{"functionName":"private_kernel_inner","time":122.33256400000027},{"functionName":"private_kernel_reset","time":149.26635800000076},{"functionName":"private_kernel_tail","time":62.217321000000084}],"unaccounted":256.20373599999766}} 10:57:35 aztec-1 | [10:57:34.961] INFO: node Received tx 0x2b5eb9dbaee5f3add7498dd25d3f918f5478ddd0553dc038d64a6a6e79d088e6 {"txHash":"0x2b5eb9dbaee5f3add7498dd25d3f918f5478ddd0553dc038d64a6a6e79d088e6"} 10:57:35 aztec-1 | [10:57:34.961] INFO: pxe:service Sent transaction 0x2b5eb9dbaee5f3add7498dd25d3f918f5478ddd0553dc038d64a6a6e79d088e6 10:57:35 aztec-1 | [10:57:35.049] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.018968473999999333s {"duration":0.018968473999999333,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 10:57:35 aztec-1 | [10:57:35.081] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1d063ea95fed735c832dc6b4bf3cecbc28fc1f6cf4508a970a928f1348c7b196","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749726174,"version":3573608979},"txHashes":["0x2b5eb9dbaee5f3add7498dd25d3f918f5478ddd0553dc038d64a6a6e79d088e6"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":57.840799000001425,"publicProcessDuration":19.110607999999047,"rollupCircuitsDuration":53.185591000001295,"txCount":1,"blockNumber":2,"blockTimestamp":1749726174,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:57:35 aztec-1 | [10:57:35.264] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:57:35 aztec-1 | [10:57:35.328] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 10:57:35 aztec-1 | error: "Rollup__SlotAlreadyInChain(3, 3)" 10:57:35 aztec-1 | [10:57:35.328] WARN: sequencer Cannot propose block 2 at slot 3 due to failed rollup contract check 10:57:35 aztec-1 | [10:57:35.345] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 10:57:35 aztec-1 | [10:57:35.345] WARN: foundation:test-date-provider Time set to 2025-06-12T11:03:06.000Z {"offset":330655,"timeMs":1749726186000} 10:57:35 aztec-1 | [10:57:35.351] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 10:57:35 aztec-1 | [10:57:35.362] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 10:57:35 aztec-1 | [10:57:35.373] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x1d063ea95fed735c832dc6b4bf3cecbc28fc1f6cf4508a970a928f1348c7b196","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749726174,"version":3573608979},"archiveRoot":"0x056ae8167cb164fdfb9ac02368205c2b4f93a447eed876842057fb5d81ea26cc","archiveNextLeafIndex":3} 10:57:35 aztec-1 | [10:57:35.377] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749726210 10:57:35 aztec-1 | [10:57:35.377] WARN: foundation:test-date-provider Time set to 2025-06-12T11:03:30.000Z {"offset":354623,"timeMs":1749726210000} 10:57:35 aztec-1 | [10:57:35.377] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 10:57:35 aztec-1 | [10:57:35.437] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":17.042311000001064,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749726174,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:57:35 aztec-1 | [10:57:35.450] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 10:57:35 aztec-1 | [10:57:35.540] INFO: pxe:service Registered account 0x14e6e67f5fc67ae70f2e78fe6cd60b034b690c8d3f90eab69609bfb283fdc923 10:57:35 aztec-1 | [10:57:35.642] INFO: pxe:service Added contract SchnorrAccount at 0x14e6e67f5fc67ae70f2e78fe6cd60b034b690c8d3f90eab69609bfb283fdc923 with class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f 10:57:37 aztec-1 | Initial Accounts: 10:57:37 aztec-1 | 10:57:37 aztec-1 | Address: 0x2098b7a51b5545e6dcbf3d1f703b1fa961aee3fd4afa74cfc5fcdaaf9473ddef 10:57:37 aztec-1 | Partial Address: 0x25cdbb7a5be4a2b0e9adb1e87d0b5f372a1fa87051c9bcfee1d2c12805bf4aac 10:57:37 aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281 10:57:37 aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f 10:57:37 aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f 10:57:37 aztec-1 | 10:57:37 aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5 10:57:37 aztec-1 | 10:57:37 aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89 10:57:37 aztec-1 | 10:57:37 aztec-1 | Address: 0x0ab5671f409a3df349e09dcdba57de3eab9811dbb53fdc7040509b7f514dd741 10:57:37 aztec-1 | Partial Address: 0x0c7f265c4adbd783aa791ae3f6859e620def9316c9013a9a72c68d3df8514776 10:57:37 aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae 10:57:37 aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57 10:57:37 aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d 10:57:37 aztec-1 | 10:57:37 aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e 10:57:37 aztec-1 | 10:57:37 aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d 10:57:37 aztec-1 | 10:57:37 aztec-1 | Address: 0x14e6e67f5fc67ae70f2e78fe6cd60b034b690c8d3f90eab69609bfb283fdc923 10:57:37 aztec-1 | Partial Address: 0x26ee9b51c1ec4a4c6810ea237b0a33367ab5d0c7fd08e07761ac9c429a28ef0a 10:57:37 aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c 10:57:37 aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3 10:57:37 aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae 10:57:37 aztec-1 | 10:57:37 aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da 10:57:37 aztec-1 | 10:57:37 aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba 10:57:37 aztec-1 | 10:57:37 aztec-1 | 10:57:37 aztec-1 | [10:57:37.015] INFO: pxe:service Proving completed in 1365.6688500000018ms {"timings":{"total":1365.6688500000018,"sync":25.08656400000109,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":80.48551500000394,"oracles":{"callPrivateFunction":{"times":[342.5393929999991,229.18861900000047]}}},{"functionName":"private_kernel_init","time":52.49705200000244},{"functionName":"SchnorrAccount:constructor","time":317.05491899999834,"oracles":{"getContractInstance":{"times":[3.6865119999965827]},"debugLog":{"times":[0.21253599999909056,0.08513199999651988]},"fetchTaggedLogs":{"times":[116.90570399999706]},"loadCapsule":{"times":[0.17537499999889405,0.12616300000081537,0.11437300000034156]},"validateEnqueuedNotesAndEvents":{"times":[5.322947000000568]},"notifyCreatedNullifier":{"times":[0.1904260000010254,0.18438500000047497]},"notifyCreatedNote":{"times":[0.10462299999926472]},"getRandomField":{"times":[0.08836199999859673,0.13636400000177673,0.0370809999985795,0.03958099999726983,0.03185200000007171,0.02760100000159582,0.026370999999926426,0.06091200000082608,0.03148000000146567,0.031111000000237254,0.029010999998718034]},"getIndexedTaggingSecretAsSender":{"times":[28.702793999997084]},"incrementAppTaggingSecretIndexAsSender":{"times":[25.50317499999801]}}},{"functionName":"private_kernel_inner","time":109.62972199999786},{"functionName":"SchnorrAccount:entrypoint","time":208.88079800000196,"oracles":{"debugLog":{"times":[0.1352630000001227,0.0939330000001064,0.13908399999854737]},"fetchTaggedLogs":{"times":[93.54022699999769]},"loadCapsule":{"times":[0.17707399999926565,0.12320299999919371,0.5158139999984996]},"validateEnqueuedNotesAndEvents":{"times":[15.443776999996771]},"getNotes":{"times":[2.6312529999995604]},"getAuthWitness":{"times":[0.17012400000021444]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.3789710000019113]}}},{"functionName":"private_kernel_inner","time":107.60422599999947},{"functionName":"private_kernel_reset","time":172.02890800000023},{"functionName":"private_kernel_tail","time":64.88978499999939}],"unaccounted":227.51136099999712}} 10:57:37 aztec-1 | [10:57:37.065] INFO: node Received tx 0x262a977329728a2320bfe0998c13fc18e03b3892bb3610919e7e9f2fa6f5194c {"txHash":"0x262a977329728a2320bfe0998c13fc18e03b3892bb3610919e7e9f2fa6f5194c"} 10:57:37 aztec-1 | [10:57:37.065] INFO: pxe:service Sent transaction 0x262a977329728a2320bfe0998c13fc18e03b3892bb3610919e7e9f2fa6f5194c 10:57:37 aztec-1 | [10:57:37.161] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.028564119999999093s {"duration":0.028564119999999093,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 10:57:37 aztec-1 | [10:57:37.182] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x0c88f7fab0f8836bd766adeba243ad671d49a4cda801b803c105c0868930d7aa","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749726210,"version":3573608979},"txHashes":["0x262a977329728a2320bfe0998c13fc18e03b3892bb3610919e7e9f2fa6f5194c"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":58.10856700000295,"publicProcessDuration":28.712094000002253,"rollupCircuitsDuration":52.25972500000353,"txCount":1,"blockNumber":3,"blockTimestamp":1749726210,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:57:37 aztec-1 | [10:57:37.354] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:57:37 aztec-1 | [10:57:37.419] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 10:57:37 aztec-1 | error: "Rollup__SlotAlreadyInChain(4, 4)" 10:57:37 aztec-1 | [10:57:37.419] WARN: sequencer Cannot propose block 3 at slot 4 due to failed rollup contract check 10:57:37 aztec-1 | [10:57:37.448] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 10:57:37 aztec-1 | [10:57:37.448] WARN: foundation:test-date-provider Time set to 2025-06-12T11:03:42.000Z {"offset":364552,"timeMs":1749726222000} 10:57:37 aztec-1 | [10:57:37.453] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 10:57:37 aztec-1 | [10:57:37.454] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 10:57:37 aztec-1 | [10:57:37.455] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x0c88f7fab0f8836bd766adeba243ad671d49a4cda801b803c105c0868930d7aa","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749726210,"version":3573608979},"archiveRoot":"0x2fe83187657d92e9de2d008f3d443799460aee4565751f3f56d4ff963be5ef8f","archiveNextLeafIndex":4} 10:57:37 aztec-1 | [10:57:37.467] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749726246 10:57:37 aztec-1 | [10:57:37.467] WARN: foundation:test-date-provider Time set to 2025-06-12T11:04:06.000Z {"offset":388533,"timeMs":1749726246000} 10:57:37 aztec-1 | [10:57:37.467] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 10:57:37 aztec-1 | [10:57:37.493] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":17.266827000003104,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749726210,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:57:37 aztec-1 | [10:57:37.531] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 10:57:38 aztec-1 | [10:57:38.261] WARN: pxe:service No artifact found for contract class 0x2183e5f752f839cfb2ba67a84dc13934d6d807278ff767a694fe49778e7ce0b3 when looking for its metadata 10:57:38 aztec-1 | [10:57:38.261] WARN: pxe:service No artifact found for contract class 0x20e76625b255753931a4abe202b444d4fcd0107fe0372bbed9061ac25dbff249 when looking for its metadata 10:57:38 aztec-1 | [10:57:38.265] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x2183e5f752f839cfb2ba67a84dc13934d6d807278ff767a694fe49778e7ce0b3 as part of deployment for 0x2c3af751391c30b4ff51722fb1c59d9380a64d0a06990f1810963afda65b9cae 10:57:38 aztec-1 | [10:57:38.299] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x20e76625b255753931a4abe202b444d4fcd0107fe0372bbed9061ac25dbff249 as part of deployment for 0x16b930d76fe7a01a12e8f5fa8bfd7f6350e3383b4f34620955e665fa15ac51d7 10:57:39 aztec-1 | [10:57:39.123] INFO: pxe:service Added contract FPC at 0x2c3af751391c30b4ff51722fb1c59d9380a64d0a06990f1810963afda65b9cae with class 0x2183e5f752f839cfb2ba67a84dc13934d6d807278ff767a694fe49778e7ce0b3 10:57:39 aztec-1 | [10:57:39.149] INFO: pxe:service Added contract Token at 0x16b930d76fe7a01a12e8f5fa8bfd7f6350e3383b4f34620955e665fa15ac51d7 with class 0x20e76625b255753931a4abe202b444d4fcd0107fe0372bbed9061ac25dbff249 10:57:41 aztec-1 | [10:57:41.579] INFO: pxe:service Proving completed in 2401.0202180000015ms {"timings":{"total":2401.0202180000015,"sync":20.73022299999866,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":390.7336899999973,"oracles":{"debugLog":{"times":[0.267507000000478,0.1063830000020971,0.07110200000170153,0.1354639999990468,0.09571199999845703,0.08319300000221119,0.13170399999944493,0.12459400000079768]},"fetchTaggedLogs":{"times":[151.48495999999795]},"loadCapsule":{"times":[0.19760500000120373,0.12805400000070222,0.20071599999937462,0.17930499999783933,0.43197199999849545,1.9421330000004673,0.3184690000016417]},"getSharedSecret":{"times":[7.908469000001787]},"aes128Decrypt":{"times":[1.1185409999998228,0.5724259999988135]},"getPublicKeysAndPartialAddress":{"times":[2.3797059999997145]},"getKeyValidationRequest":{"times":[2.510299000001396]},"storeCapsule":{"times":[2.5576809999984107,4.195655999999872,2.1977509999996983]},"deleteCapsule":{"times":[2.3190639999993437]},"validateEnqueuedNotesAndEvents":{"times":[22.239935000001424]},"getNotes":{"times":[11.315552000000025]},"getAuthWitness":{"times":[0.172405000001163]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.045311000001674984]},"callPrivateFunction":{"times":[687.9388350000008,133.1056040000003]},"notifyEnqueuedPublicFunctionCall":{"times":[0.4078609999996843]}}},{"functionName":"private_kernel_init","time":76.4029929999997},{"functionName":"ContractClassRegisterer:register","time":677.2067890000035,"oracles":{"loadCapsule":{"times":[2.8992699999980687]},"notifyCreatedNullifier":{"times":[0.370520000000397]},"debugLog":{"times":[0.15422399999806657]},"notifyCreatedContractClassLog":{"times":[4.590786000000662]}}},{"functionName":"private_kernel_inner","time":147.416326999999},{"functionName":"ContractInstanceDeployer:deploy","time":82.83857999999964,"oracles":{"storeInExecutionCache":{"times":[0.3564299999998184]},"callPrivateFunction":{"times":[49.23631600000226]},"notifyCreatedNullifier":{"times":[0.2945079999990412]},"debugLog":{"times":[0.15793400000256952]}}},{"functionName":"private_kernel_inner","time":98.40204900000026},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":36.542215000001306,"oracles":{}},{"functionName":"private_kernel_inner","time":137.6085779999994},{"functionName":"private_kernel_reset","time":193.95617800000036},{"functionName":"private_kernel_tail","time":109.77575200000138}],"unaccounted":429.406844000001}} 10:57:41 aztec-1 | [10:57:41.743] INFO: node Received tx 0x088d8fcab6f7b4092be52dc30c50cdc38d7796ea3cd4a73847c3105773ffc14f {"txHash":"0x088d8fcab6f7b4092be52dc30c50cdc38d7796ea3cd4a73847c3105773ffc14f"} 10:57:41 aztec-1 | [10:57:41.743] INFO: pxe:service Sent transaction 0x088d8fcab6f7b4092be52dc30c50cdc38d7796ea3cd4a73847c3105773ffc14f 10:57:43 aztec-1 | [10:57:43.299] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 1.4343507190000018s {"duration":1.4343507190000018,"rate":24752.663020068496,"totalPublicGas":{"daGas":4096,"l2Gas":35504},"totalBlockGas":{"daGas":166912,"l2Gas":60546},"totalSizeInBytes":10688} 10:57:43 aztec-1 | [10:57:43.577] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 20611.989127075878 mana/s {"blockHash":"0x16be4d85523e83773d0bd22086c059c03edebd2d1c76b549c8692c5cfef76378","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749726246,"version":3573608979},"txHashes":["0x088d8fcab6f7b4092be52dc30c50cdc38d7796ea3cd4a73847c3105773ffc14f"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":1722.402188,"publicProcessDuration":1434.4682129999965,"rollupCircuitsDuration":1713.9532550000004,"txCount":1,"blockNumber":4,"blockTimestamp":1749726246,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296} 10:57:44 aztec-1 | [10:57:44.150] INFO: pxe:service Proving completed in 2569.957566000001ms {"timings":{"total":2569.957566000001,"sync":130.66264600000068,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":371.5894060000064,"oracles":{"debugLog":{"times":[0.16853400000036345,0.12382300000172108,0.13040300000284333,0.22155600000041886,0.13841300000058254,0.13198299999930896,0.08660299999974086,0.14785399999891524]},"fetchTaggedLogs":{"times":[145.12809500000003]},"loadCapsule":{"times":[2.371994999997696,0.38006099999984144,0.5632149999983085,0.5864460000011604,0.3586700000014389,0.5568850000017846,3.3871330000001763]},"getSharedSecret":{"times":[6.059606999999232]},"aes128Decrypt":{"times":[0.32962899999984074,0.7574910000003001]},"getPublicKeysAndPartialAddress":{"times":[4.868913999998767]},"getKeyValidationRequest":{"times":[7.881356999998388]},"storeCapsule":{"times":[2.9260810000014317,2.830767999999807,3.8431949999976496]},"deleteCapsule":{"times":[27.98518100000001]},"validateEnqueuedNotesAndEvents":{"times":[12.990497000002506]},"getNotes":{"times":[5.214003999997658]},"getAuthWitness":{"times":[0.20335500000146567]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.054962000001978595]},"callPrivateFunction":{"times":[656.1991120000021,119.9364319999986]},"notifyEnqueuedPublicFunctionCall":{"times":[0.04579199999716366]}}},{"functionName":"private_kernel_init","time":66.65581499999826},{"functionName":"ContractClassRegisterer:register","time":645.4092159999964,"oracles":{"loadCapsule":{"times":[1.6712659999975585]},"notifyCreatedNullifier":{"times":[0.44276299999910407]},"debugLog":{"times":[0.11161300000094343]},"notifyCreatedContractClassLog":{"times":[3.752653999999893]}}},{"functionName":"private_kernel_inner","time":138.2138840000007},{"functionName":"ContractInstanceDeployer:deploy","time":75.95121000000654,"oracles":{"storeInExecutionCache":{"times":[0.09883300000001327]},"callPrivateFunction":{"times":[41.12667199999851]},"notifyCreatedNullifier":{"times":[0.2108459999981278]},"debugLog":{"times":[0.1299539999999979]}}},{"functionName":"private_kernel_inner","time":139.94457200000033},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":33.127911999996286,"oracles":{}},{"functionName":"private_kernel_inner","time":151.48859000000084},{"functionName":"private_kernel_reset","time":246.54169600000023},{"functionName":"private_kernel_tail","time":114.62107499999911}],"unaccounted":455.7515439999952}} 10:57:44 aztec-1 | [10:57:44.324] INFO: node Received tx 0x2c23d1eae5ac126aef66505348c15ef45d78de10ea63998e3dccf8843cfac78c {"txHash":"0x2c23d1eae5ac126aef66505348c15ef45d78de10ea63998e3dccf8843cfac78c"} 10:57:44 aztec-1 | [10:57:44.324] INFO: pxe:service Sent transaction 0x2c23d1eae5ac126aef66505348c15ef45d78de10ea63998e3dccf8843cfac78c 10:57:44 aztec-1 | [10:57:44.471] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 10:57:44 aztec-1 | [10:57:44.471] WARN: foundation:test-date-provider Time set to 2025-06-12T11:04:18.000Z {"offset":393529,"timeMs":1749726258000} 10:57:44 aztec-1 | [10:57:44.480] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 10:57:44 aztec-1 | [10:57:44.485] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 10:57:44 aztec-1 | [10:57:44.491] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:57:44 aztec-1 | [10:57:44.572] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749726282 10:57:44 aztec-1 | [10:57:44.572] WARN: foundation:test-date-provider Time set to 2025-06-12T11:04:42.000Z {"offset":417428,"timeMs":1749726282000} 10:57:44 aztec-1 | [10:57:44.572] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 10:57:44 aztec-1 | [10:57:44.585] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x16be4d85523e83773d0bd22086c059c03edebd2d1c76b549c8692c5cfef76378","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749726246,"version":3573608979},"archiveRoot":"0x1e92306e1c31058817347f2f9494da9fb30737e6777a0cd10f7716a5d9615659","archiveNextLeafIndex":5} 10:57:44 aztec-1 | [10:57:44.591] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 10:57:44 aztec-1 | [10:57:44.669] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":20.685378999998647,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749726246,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296} 10:57:45 aztec-1 | [10:57:45.150] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.3675060449999983s {"duration":0.3675060449999983,"rate":751685.5947226699,"totalPublicGas":{"daGas":10240,"l2Gas":276249},"totalBlockGas":{"daGas":380928,"l2Gas":301291},"totalSizeInBytes":24064} 10:57:45 aztec-1 | [10:57:45.201] INFO: sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 645548.6940614813 mana/s {"blockHash":"0x16cea67ee00bacd02c272980530f750a2cd15d7080d49fd7f6ea12d23700ca4a","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749726282,"version":3573608979},"txHashes":["0x2c23d1eae5ac126aef66505348c15ef45d78de10ea63998e3dccf8843cfac78c"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":427.90689799999745,"publicProcessDuration":367.85456500000146,"rollupCircuitsDuration":422.8986100000002,"txCount":1,"blockNumber":5,"blockTimestamp":1749726282,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 10:57:45 aztec-1 | [10:57:45.331] INFO: aztecjs:deploy_sent_tx Contract 0x2c3af751391c30b4ff51722fb1c59d9380a64d0a06990f1810963afda65b9cae successfully deployed. 10:57:45 aztec-1 | [10:57:45.541] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:57:45 aztec-1 | [10:57:45.548] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 10:57:45 aztec-1 | [10:57:45.548] WARN: foundation:test-date-provider Time set to 2025-06-12T11:04:54.000Z {"offset":428452,"timeMs":1749726294000} 10:57:45 aztec-1 | [10:57:45.551] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749726318 10:57:45 aztec-1 | [10:57:45.551] WARN: foundation:test-date-provider Time set to 2025-06-12T11:05:18.000Z {"offset":452449,"timeMs":1749726318000} 10:57:45 aztec-1 | [10:57:45.551] INFO: aztecjs:utils:watcher Slot 6 was filled, jumped to next slot 10:57:45 aztec-1 | [10:57:45.557] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5 10:57:45 aztec-1 | [10:57:45.560] INFO: aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5. 10:57:45 aztec-1 | [10:57:45.564] WARN: archiver Failed to reach L2 block 5 at 32 (latest is 4). Rolling back last synched L1 block number to 31. {"latestLocalL2BlockNumber":4,"latestLocalL2BlockArchive":"0x1e92306e1c31058817347f2f9494da9fb30737e6777a0cd10f7716a5d9615659","blocksSynchedTo":31,"currentL1BlockNumber":32,"provenBlockNumber":4,"provenArchive":"0x1e92306e1c31058817347f2f9494da9fb30737e6777a0cd10f7716a5d9615659","pendingBlockNumber":5,"pendingArchive":"0x16c12f20ed2fccf7dc730c89e2704c2fd0b39304121d9ca68d70af2460681c96"} 10:57:45 aztec-1 | [10:57:45.750] INFO: archiver Downloaded L2 block 5 {"blockHash":"0x16cea67ee00bacd02c272980530f750a2cd15d7080d49fd7f6ea12d23700ca4a","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749726282,"version":3573608979},"archiveRoot":"0x16c12f20ed2fccf7dc730c89e2704c2fd0b39304121d9ca68d70af2460681c96","archiveNextLeafIndex":6} 10:57:45 aztec-1 | [10:57:45.758] INFO: archiver Updated proven chain to block 5 {"provenBlockNumber":5} 10:57:45 aztec-1 | [10:57:45.822] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":19.912498000001506,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749726282,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 10:57:46 aztec-1 | BananaCoin: 0x16b930d76fe7a01a12e8f5fa8bfd7f6350e3383b4f34620955e665fa15ac51d7 10:57:46 aztec-1 | FPC: 0x2c3af751391c30b4ff51722fb1c59d9380a64d0a06990f1810963afda65b9cae 10:57:46 aztec-1 | [10:57:46.780] INFO: aztecjs:deploy_sent_tx Contract 0x16b930d76fe7a01a12e8f5fa8bfd7f6350e3383b4f34620955e665fa15ac51d7 successfully deployed. 10:57:46 aztec-1 | [10:57:46.806] WARN: pxe:service No artifact found for contract class 0x04193fd6314e8d0af283de6f2769212e2122e260c6e55154e9d94857c84e9523 when looking for its metadata 10:57:46 aztec-1 | [10:57:46.810] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x04193fd6314e8d0af283de6f2769212e2122e260c6e55154e9d94857c84e9523 as part of deployment for 0x22932b1f5008b7d2e5562a9f582fe1caf230663d744283f1e92ff8a41544cbfc 10:57:46 aztec-1 | [10:57:46.942] INFO: pxe:service Added contract SponsoredFPC at 0x22932b1f5008b7d2e5562a9f582fe1caf230663d744283f1e92ff8a41544cbfc with class 0x04193fd6314e8d0af283de6f2769212e2122e260c6e55154e9d94857c84e9523 10:57:50 aztec-1 | [10:57:49.305] INFO: pxe:service Proving completed in 2335.081962ms {"timings":{"total":2335.081962,"sync":31.414364000000205,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":75.51385800000207,"oracles":{"callPrivateFunction":{"times":[856.6757489999982,138.88453200000004,34.16048999999839]}}},{"functionName":"private_kernel_init","time":78.01078699999925},{"functionName":"ContractClassRegisterer:register","time":842.5241199999982,"oracles":{"loadCapsule":{"times":[2.6155820000021777]},"notifyCreatedNullifier":{"times":[0.9285960000015621]},"debugLog":{"times":[0.2680270000018936]},"notifyCreatedContractClassLog":{"times":[6.681613999997353]}}},{"functionName":"private_kernel_inner","time":137.62355799999932},{"functionName":"ContractInstanceDeployer:deploy","time":73.92421399999876,"oracles":{"storeInExecutionCache":{"times":[0.14715400000204681]},"callPrivateFunction":{"times":[61.64911700000084]},"notifyCreatedNullifier":{"times":[0.22815600000103586]},"debugLog":{"times":[0.16755499999999302]}}},{"functionName":"private_kernel_inner","time":156.13337800000227},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":52.365381999999954,"oracles":{}},{"functionName":"private_kernel_inner","time":119.8774599999997},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":29.237356000001455,"oracles":{"debugLog":{"times":[0.11989299999913783]}}},{"functionName":"private_kernel_inner","time":123.03777700000137},{"functionName":"private_kernel_reset","time":187.74068800000168},{"functionName":"private_kernel_tail","time":76.18270699999994}],"unaccounted":351.496312999996}} 10:57:50 aztec-1 | [10:57:49.425] INFO: node Received tx 0x1c05b622ff471362a328ca39a190b783e8d5f72788f35d264c34a9b10a54b785 {"txHash":"0x1c05b622ff471362a328ca39a190b783e8d5f72788f35d264c34a9b10a54b785"} 10:57:50 aztec-1 | [10:57:49.425] INFO: pxe:service Sent transaction 0x1c05b622ff471362a328ca39a190b783e8d5f72788f35d264c34a9b10a54b785 10:57:50 aztec-1 | [10:57:49.541] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.0386731550000004s {"duration":0.0386731550000004,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152} 10:57:50 aztec-1 | [10:57:49.572] INFO: sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x26fcf8c4dcc51606471e185c380c4bb123fa64c0019af39a31c80d2a4f3f189f","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749726318,"version":3573608979},"txHashes":["0x1c05b622ff471362a328ca39a190b783e8d5f72788f35d264c34a9b10a54b785"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":77.24209699999847,"publicProcessDuration":38.83646999999837,"rollupCircuitsDuration":72.21143699999811,"txCount":1,"blockNumber":6,"blockTimestamp":1749726318,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:57:50 aztec-1 | [10:57:49.779] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6 10:57:50 aztec-1 | [10:57:49.783] INFO: aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6. 10:57:50 aztec-1 | [10:57:49.785] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:57:50 aztec-1 | [10:57:49.793] WARN: archiver Failed to reach L2 block 6 at 34 (latest is 5). Rolling back last synched L1 block number to 33. {"latestLocalL2BlockNumber":5,"latestLocalL2BlockArchive":"0x16c12f20ed2fccf7dc730c89e2704c2fd0b39304121d9ca68d70af2460681c96","blocksSynchedTo":33,"currentL1BlockNumber":34,"provenBlockNumber":5,"provenArchive":"0x16c12f20ed2fccf7dc730c89e2704c2fd0b39304121d9ca68d70af2460681c96","pendingBlockNumber":6,"pendingArchive":"0x0dd54e34c4a611037135482e32c9721c70fbf415c7e25d12fea155a7997b7ff3"} 10:57:50 aztec-1 | [10:57:49.856] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749726354 10:57:50 aztec-1 | [10:57:49.856] WARN: foundation:test-date-provider Time set to 2025-06-12T11:05:54.000Z {"offset":484144,"timeMs":1749726354000} 10:57:50 aztec-1 | [10:57:49.856] INFO: aztecjs:utils:watcher Slot 7 was filled, jumped to next slot 10:57:50 aztec-1 | [10:57:49.861] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 10:57:50 aztec-1 | error: "Rollup__SlotAlreadyInChain(7, 7)" 10:57:50 aztec-1 | [10:57:49.861] WARN: sequencer Cannot propose block 6 at slot 7 due to failed rollup contract check 10:57:50 aztec-1 | [10:57:49.907] INFO: archiver Downloaded L2 block 6 {"blockHash":"0x26fcf8c4dcc51606471e185c380c4bb123fa64c0019af39a31c80d2a4f3f189f","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749726318,"version":3573608979},"archiveRoot":"0x0dd54e34c4a611037135482e32c9721c70fbf415c7e25d12fea155a7997b7ff3","archiveNextLeafIndex":7} 10:57:50 aztec-1 | [10:57:49.915] INFO: archiver Updated proven chain to block 6 {"provenBlockNumber":6} 10:57:50 aztec-1 | [10:57:49.932] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":12.181565000002593,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749726318,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:57:50 aztec-1 | SponsoredFPC: 0x22932b1f5008b7d2e5562a9f582fe1caf230663d744283f1e92ff8a41544cbfc 10:57:50 aztec-1 | [10:57:50.453] INFO: aztecjs:deploy_sent_tx Contract 0x22932b1f5008b7d2e5562a9f582fe1caf230663d744283f1e92ff8a41544cbfc successfully deployed. 10:57:50 aztec-1 | [10:57:50.459] INFO: cli Aztec Server listening on port 8080 {"l2CircuitsVkTreeRoot":"0x08c6b24bd9c265f8c6596b12f540d93a53f5bcd1502d78957c8614575c61c9f4","l2ProtocolContractsTreeRoot":"0x052cc6e2bd1171363e72fb45b9a78fb0ebacedb96c8f1148b3903c3bafa8c458"} 10:58:00 playground-1 | \ 10:58:00 playground-1 | Running 1 test using 1 worker 10:58:00 playground-1 | 10:58:01 playground-1 | ✓ 1 [chromium] › browser.spec.ts:3:1 › test (10.1s) 10:58:12 playground-1 | 1 passed (13.8s) 10:58:12 playground-1 | \ 10:58:13 playground-1 exited with code 0 10:58:13 Aborting on container exit... 10:58:13 Container playground-chromium-playground-1 Stopping 10:58:13 Container playground-chromium-playground-1 Stopped 10:58:13 Container playground-chromium-aztec-1 Stopping 10:58:14 Container playground-chromium-aztec-1 Stopped 10:58:14 Container playground-chromium-ethereum-1 Stopping 10:58:15 Container playground-chromium-ethereum-1 Stopped