Parent Log: http://ci.aztec-labs.com/ec53a48cfa0deda9 Command: ba054b2719216ab8 playground/scripts/run_test.sh firefox Commit: https://github.com/AztecProtocol/aztec-packages/commit/26c5a39fe03723d11540f721293d7aebd1f478d9 Env: REF_NAME=gh-readonly-queue/next/pr-14995-75d792847d8434a0c504e7adf5c102a913065272 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 13 09:04:41 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14995_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_23123200780498f7_next 09:04:41 Network playground-firefox_default Creating 09:04:41 Network playground-firefox_default Created 09:04:41 Container playground-firefox-ethereum-1 Creating 09:04:41 Container playground-firefox-ethereum-1 Created 09:04:41 Container playground-firefox-aztec-1 Creating 09:04:42 Container playground-firefox-aztec-1 Created 09:04:42 Container playground-firefox-playground-1 Creating 09:04:42 Container playground-firefox-playground-1 Created 09:04:42 Attaching to aztec-1, ethereum-1, playground-1 09:04:47 aztec-1 | 09:04:47 aztec-1 | _ 09:04:47 aztec-1 | /\ | | 09:04:47 aztec-1 | / \ ___| |_ ___ ___ 09:04:47 aztec-1 | / /\ \ |_ / __/ _ \/ __| 09:04:47 aztec-1 | / ____ \ / /| || __/ (__ 09:04:47 aztec-1 | /_/___ \_\/___|\__\___|\___| 09:04:47 aztec-1 | 09:04:47 aztec-1 | https://github.com/AztecProtocol 09:04:47 aztec-1 | 09:04:47 aztec-1 | 09:04:47 aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by... 09:04:47 aztec-1 | Setting up test accounts 09:04:48 aztec-1 | [09:04:47.762] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-1Wb1UJ/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 09:04:48 aztec-1 | [09:04:48.037] INFO: sandbox Calling anvil_setBlockTimestampInterval with params: [12] 09:04:48 aztec-1 | [09:04:48.041] WARN: sandbox Set block interval to 12 09:04:48 aztec-1 | [09:04:48.237] INFO: sandbox Waiting for 7 transactions to be mined... 09:04:48 aztec-1 | [09:04:48.259] INFO: sandbox All transactions mined successfully 09:04:48 aztec-1 | [09:04:48.344] INFO: sandbox Waiting for 9 transactions to be mined... 09:04:48 aztec-1 | [09:04:48.370] INFO: sandbox All transactions mined successfully 09:04:48 aztec-1 | [09:04:48.530] INFO: sandbox Waiting for 11 transactions to be mined... 09:04:48 aztec-1 | [09:04:48.553] INFO: sandbox All transactions mined successfully 09:04:48 aztec-1 | [09:04:48.613] INFO: sandbox Waiting for 12 transactions to be mined... 09:04:48 aztec-1 | [09:04:48.637] INFO: sandbox All transactions mined successfully 09:04:48 aztec-1 | [09:04:48.642] INFO: sandbox Waiting for 12 transactions to be mined... 09:04:48 aztec-1 | [09:04:48.664] INFO: sandbox All transactions mined successfully 09:04:48 aztec-1 | [09:04:48.680] INFO: sandbox Aztec L1 contracts initialized {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x3aa5ebb10dc797cac828524e59a333d0a371443c","inboxAddress":"0x4374eecaad0dcaa149cffc160d5a0552b1d092b0","outboxAddress":"0x44bf2a9217a2970a1bcc7529bf1d40828c594320","feeJuicePortalAddress":"0xa0719ec6accbac2301f88bc71e2f5ddc8c29149b","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 09:04:48 aztec-1 | [09:04:48.681] INFO: sandbox Handing over to governance 09:04:48 aztec-1 | [09:04:48.689] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x3Aa5ebB10DC797CAC828524e59A333d0A371443c 09:04:48 aztec-1 | [09:04:48.690] INFO: telemetry:client Using NoopTelemetryClient 09:04:48 aztec-1 | [09:04:48.690] INFO: blob-sink:client Creating local blob sink client. 09:04:48 aztec-1 | [09:04:48.695] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:04:48 aztec-1 | [09:04:48.695] WARN: foundation:test-date-provider Time set to 2025-06-13T09:09:30.000Z {"offset":281305,"timeMs":1749805770000} 09:04:48 aztec-1 | [09:04:48.721] INFO: archiver:lmdb Starting data store with maxReaders 16 09:04:49 aztec-1 | [09:04:49.094] INFO: archiver Starting archiver sync to rollup contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c from L1 block 20 to current L1 block 24 with hash 0xd492e838b433ae3a9afcc5913a88a859a81d57788d17fbde641a66bc7a2e2a2c {"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0xf0c591bf84ce76f7671e457b388244c56aaae480da3db1d3ca3f3162779f9e11","l1BlockNumber":20}} 09:04:49 aztec-1 | [09:04:49.115] INFO: archiver Initial archiver sync to L1 block 24 complete. {"l1BlockNumber":24,"syncPoint":{"blocksSynchedTo":24,"messagesSynchedTo":{"l1BlockHash":"0xd492e838b433ae3a9afcc5913a88a859a81d57788d17fbde641a66bc7a2e2a2c","l1BlockNumber":24}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 09:04:49 aztec-1 | [09:04:49.123] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-6MtaHL/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 09:04:49 aztec-1 | [09:04:49.360] INFO: world_state Created world state synchroniser with block history of 64 09:04:49 aztec-1 | [09:04:49.360] WARN: node Aztec node is accepting fake proofs 09:04:49 aztec-1 | [09:04:49.370] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 09:04:49 aztec-1 | [09:04:49.386] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 09:04:49 aztec-1 | [09:04:49.403] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 09:04:49 aztec-1 | [09:04:49.419] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 09:04:49 aztec-1 | [09:04:49.422] INFO: world_state Started world state synchronizer from block 1 09:04:49 aztec-1 | [09:04:49.426] INFO: p2p Starting p2p client from block 0 with empty mempools 09:04:49 aztec-1 | [09:04:49.666] INFO: epoch-prune-watcher EpochPruneWatcher initialized 09:04:49 aztec-1 | [09:04:49.717] INFO: slasher Starting Slasher client... 09:04:49 aztec-1 | [09:04:49.727] INFO: sequencer-client Deploying forwarder contract 09:04:49 aztec-1 | [09:04:49.749] INFO: sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac 09:04:49 aztec-1 | [09:04:49.755] INFO: validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 09:04:49 aztec-1 | [09:04:49.756] 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} 09:04:49 aztec-1 | [09:04:49.757] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 09:04:49 aztec-1 | [09:04:49.758] INFO: node Aztec Node version: 0.87.6 09:04:49 aztec-1 | [09:04:49.758] INFO: node Aztec Node started on chain 0x7a69 {"rollupAddress":"0x3aa5ebb10dc797cac828524e59a333d0a371443c","registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","inboxAddress":"0x4374eecaad0dcaa149cffc160d5a0552b1d092b0","outboxAddress":"0x44bf2a9217a2970a1bcc7529bf1d40828c594320","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","feeJuicePortalAddress":"0xa0719ec6accbac2301f88bc71e2f5ddc8c29149b","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","slashFactoryAddress":"0x59b670e9fa9d0a427751af201d676719a970857b","feeAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788","stakingAssetHandlerAddress":"0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"} 09:04:49 aztec-1 | [09:04:49.763] INFO: pxe:data:lmdb Starting data store with maxReaders 16 09:04:49 aztec-1 | [09:04:49.771] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:04:49 aztec-1 | [09:04:49.834] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:04:49 aztec-1 | [09:04:49.893] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:04:50 aztec-1 | [09:04:49.999] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:04:50 aztec-1 | [09:04:50.000] WARN: foundation:test-date-provider Time set to 2025-06-13T09:09:42.000Z {"offset":292001,"timeMs":1749805782000} 09:04:50 aztec-1 | Setting up funded test accounts... 09:04:50 aztec-1 | [09:04:50.127] INFO: pxe:service Started PXE connected to chain 31337 version 2094187016 09:04:50 aztec-1 | [09:04:50.223] INFO: pxe:service Registered account 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e 09:04:50 aztec-1 | [09:04:50.284] WARN: pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata 09:04:50 aztec-1 | [09:04:50.286] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e 09:04:50 aztec-1 | [09:04:50.456] INFO: pxe:service Added contract SchnorrAccount at 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 09:04:53 aztec-1 | [09:04:53.065] INFO: pxe:service Proving completed in 2577.142328ms {"timings":{"total":2577.142328,"sync":8.155316000000312,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":143.2745909999967,"oracles":{"callPrivateFunction":{"times":[728.5847000000003,351.3134420000006,198.06013200000052]}}},{"functionName":"private_kernel_init","time":120.23155900000165},{"functionName":"ContractClassRegisterer:register","time":708.8826200000012,"oracles":{"loadCapsule":{"times":[14.604526000000078]},"notifyCreatedNullifier":{"times":[17.653908999999658]},"debugLog":{"times":[0.4863450000002558]},"notifyCreatedContractClassLog":{"times":[7.486547000000428]}}},{"functionName":"private_kernel_inner","time":136.19811499999923},{"functionName":"SchnorrAccount:constructor","time":322.145453000001,"oracles":{"getContractInstance":{"times":[2.6400720000001456]},"debugLog":{"times":[0.22861700000066776,0.19499399999949674]},"fetchTaggedLogs":{"times":[84.47077899999931]},"loadCapsule":{"times":[0.5956640000003972,0.4621530000003986,0.28513099999872793]},"validateEnqueuedNotesAndEvents":{"times":[13.354965000000448]},"notifyCreatedNullifier":{"times":[0.22016599999915343,0.14406999999846448]},"notifyCreatedNote":{"times":[0.5457299999998213]},"getRandomField":{"times":[0.4823849999993399,0.29745200000070326,0.0674749999998312,0.21447600000101374,0.10235800000009476,0.056474000000889646,0.04150300000037532,0.08068600000115111,0.04328399999940302,0.07578599999942526,0.04428299999926821]},"getIndexedTaggingSecretAsSender":{"times":[31.86175700000058]},"incrementAppTaggingSecretIndexAsSender":{"times":[24.235060000000885]}}},{"functionName":"private_kernel_inner","time":133.05318500000067},{"functionName":"SchnorrAccount:entrypoint","time":178.52676600000086,"oracles":{"debugLog":{"times":[0.21200500000122702,0.10789699999986624,0.3074219999998604]},"fetchTaggedLogs":{"times":[66.33203399999911]},"loadCapsule":{"times":[0.22753600000032748,0.23324700000011944,0.24448800000027404]},"validateEnqueuedNotesAndEvents":{"times":[5.549745000000257]},"getNotes":{"times":[1.7408069999983127]},"getAuthWitness":{"times":[0.287200999999186]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.0519770000009885]}}},{"functionName":"private_kernel_inner","time":100.42497199999889},{"functionName":"private_kernel_reset","time":163.2603199999994},{"functionName":"private_kernel_tail","time":77.16318400000091}],"unaccounted":485.82624699999906}} 09:04:53 aztec-1 | [09:04:53.170] INFO: node Received tx 0x0d3b2892049788d097d76b062c478b0118f17920741ccb6e14bbb63a9e4b303e {"txHash":"0x0d3b2892049788d097d76b062c478b0118f17920741ccb6e14bbb63a9e4b303e"} 09:04:53 aztec-1 | [09:04:53.170] INFO: pxe:service Sent transaction 0x0d3b2892049788d097d76b062c478b0118f17920741ccb6e14bbb63a9e4b303e 09:04:53 aztec-1 | [09:04:53.269] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.041075949000000035s {"duration":0.041075949000000035,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 09:04:53 aztec-1 | [09:04:53.293] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1bf75d784040c1014fb3595595beb1ee1d60c4380e1a200329ec0c921014bd74","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749805794,"version":2094187016},"txHashes":["0x0d3b2892049788d097d76b062c478b0118f17920741ccb6e14bbb63a9e4b303e"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":76.02383100000043,"publicProcessDuration":41.689603999999235,"rollupCircuitsDuration":67.8543439999994,"txCount":1,"blockNumber":1,"blockTimestamp":1749805794,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:04:55 aztec-1 | [09:04:55.632] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 09:04:55 aztec-1 | [09:04:55.635] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 09:04:55 aztec-1 | [09:04:55.642] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:04:55 aztec-1 | [09:04:55.644] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749805830 09:04:55 aztec-1 | [09:04:55.644] WARN: foundation:test-date-provider Time set to 2025-06-13T09:10:30.000Z {"offset":334356,"timeMs":1749805830000} 09:04:55 aztec-1 | [09:04:55.644] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 09:04:55 aztec-1 | [09:04:55.732] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x1bf75d784040c1014fb3595595beb1ee1d60c4380e1a200329ec0c921014bd74","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749805794,"version":2094187016},"archiveRoot":"0x107dc048b1cb527fd8d0e631dd6bb008380b25c9ab3bdb78832e53424fef5891","archiveNextLeafIndex":2} 09:04:55 aztec-1 | [09:04:55.739] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 09:04:55 aztec-1 | [09:04:55.827] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":51.19659800000045,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749805794,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:04:55 aztec-1 | [09:04:55.905] INFO: pxe:service Registered account 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f 09:04:56 aztec-1 | [09:04:56.036] INFO: pxe:service Added contract SchnorrAccount at 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 09:04:58 aztec-1 | [09:04:57.579] INFO: pxe:service Proving completed in 1506.410394999999ms {"timings":{"total":1506.410394999999,"sync":33.825378999999884,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":119.26497000000018,"oracles":{"callPrivateFunction":{"times":[435.822302999999,272.95674099999997]}}},{"functionName":"private_kernel_init","time":51.859057000001485},{"functionName":"SchnorrAccount:constructor","time":406.5722969999988,"oracles":{"getContractInstance":{"times":[4.834423000000243]},"debugLog":{"times":[0.16312199999993027,0.14055999999982305]},"fetchTaggedLogs":{"times":[124.89847000000009]},"loadCapsule":{"times":[0.4429420000014943,0.2634390000002895,0.2305770000002667]},"validateEnqueuedNotesAndEvents":{"times":[6.019340000000739]},"notifyCreatedNullifier":{"times":[0.4607240000004822,0.23662700000022596]},"notifyCreatedNote":{"times":[0.25262799999836716]},"getRandomField":{"times":[0.23315700000057404,0.23084600000038336,0.10200699999950302,0.0874870000006922,0.07469599999967613,0.07512599999972736,0.07505499999933818,0.05211400000007416,0.05424399999901652,0.05402400000093621,0.05697399999917252]},"getIndexedTaggingSecretAsSender":{"times":[60.30626400000074]},"incrementAppTaggingSecretIndexAsSender":{"times":[42.03935000000092]}}},{"functionName":"private_kernel_inner","time":93.14423100000022},{"functionName":"SchnorrAccount:entrypoint","time":236.71709499999997,"oracles":{"debugLog":{"times":[0.22911699999895063,0.08771600000000035,0.1314700000002631]},"fetchTaggedLogs":{"times":[96.767256000001]},"loadCapsule":{"times":[0.19191399999908754,0.13352000000122644,0.14942100000007486]},"validateEnqueuedNotesAndEvents":{"times":[6.187261999999464]},"getNotes":{"times":[1.1753959999987273]},"getAuthWitness":{"times":[0.1407199999994191]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.4077900000011141]}}},{"functionName":"private_kernel_inner","time":94.90955000000031},{"functionName":"private_kernel_reset","time":153.8119310000002},{"functionName":"private_kernel_tail","time":65.50290300000052}],"unaccounted":250.80298199999743}} 09:04:58 aztec-1 | [09:04:57.636] INFO: node Received tx 0x13138d6ce3d3081892fbbe17b16045dcd2e244019ddc30121426f598368f6f30 {"txHash":"0x13138d6ce3d3081892fbbe17b16045dcd2e244019ddc30121426f598368f6f30"} 09:04:58 aztec-1 | [09:04:57.636] INFO: pxe:service Sent transaction 0x13138d6ce3d3081892fbbe17b16045dcd2e244019ddc30121426f598368f6f30 09:04:58 aztec-1 | [09:04:57.727] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.019631742999999913s {"duration":0.019631742999999913,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 09:04:58 aztec-1 | [09:04:57.756] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2370284700e7337c5796c3fc2172f05fbad1f2022a02c57a684772056d6bd113","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749805830,"version":2094187016},"txHashes":["0x13138d6ce3d3081892fbbe17b16045dcd2e244019ddc30121426f598368f6f30"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":57.045516000000134,"publicProcessDuration":19.784504999999626,"rollupCircuitsDuration":52.30844999999863,"txCount":1,"blockNumber":2,"blockTimestamp":1749805830,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:04:58 aztec-1 | [09:04:57.941] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:04:58 aztec-1 | [09:04:57.950] WARN: archiver Failed to reach L2 block 2 at 27 (latest is 1). Rolling back last synched L1 block number to 26. {"latestLocalL2BlockNumber":1,"latestLocalL2BlockArchive":"0x107dc048b1cb527fd8d0e631dd6bb008380b25c9ab3bdb78832e53424fef5891","blocksSynchedTo":26,"currentL1BlockNumber":27,"provenBlockNumber":1,"provenArchive":"0x107dc048b1cb527fd8d0e631dd6bb008380b25c9ab3bdb78832e53424fef5891","pendingBlockNumber":2,"pendingArchive":"0x08e7652debefbaca29eb6a4016d6aabeaadec1ccbfcf74a0c98d4642c1f2e0da"} 09:04:58 aztec-1 | [09:04:58.014] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:04:58 aztec-1 | error: "Rollup__SlotAlreadyInChain(3, 3)" 09:04:58 aztec-1 | [09:04:58.014] WARN: sequencer Cannot propose block 2 at slot 3 due to failed rollup contract check 09:04:58 aztec-1 | [09:04:58.025] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:04:58 aztec-1 | [09:04:58.025] WARN: foundation:test-date-provider Time set to 2025-06-13T09:10:42.000Z {"offset":343975,"timeMs":1749805842000} 09:04:58 aztec-1 | [09:04:58.028] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 09:04:58 aztec-1 | [09:04:58.030] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 09:04:58 aztec-1 | [09:04:58.069] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749805866 09:04:58 aztec-1 | [09:04:58.069] WARN: foundation:test-date-provider Time set to 2025-06-13T09:11:06.000Z {"offset":367931,"timeMs":1749805866000} 09:04:58 aztec-1 | [09:04:58.069] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 09:04:58 aztec-1 | [09:04:58.069] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x2370284700e7337c5796c3fc2172f05fbad1f2022a02c57a684772056d6bd113","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749805830,"version":2094187016},"archiveRoot":"0x08e7652debefbaca29eb6a4016d6aabeaadec1ccbfcf74a0c98d4642c1f2e0da","archiveNextLeafIndex":3} 09:04:58 aztec-1 | [09:04:58.075] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 09:04:58 aztec-1 | [09:04:58.106] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":14.80453099999977,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749805830,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:04:58 aztec-1 | [09:04:58.206] INFO: pxe:service Registered account 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 09:04:58 aztec-1 | [09:04:58.327] INFO: pxe:service Added contract SchnorrAccount at 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 09:05:00 aztec-1 | Initial Accounts: 09:05:00 aztec-1 | 09:05:00 aztec-1 | Address: 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e 09:05:00 aztec-1 | Partial Address: 0x27b4888363d0f9eb0d43aa0b2ff08f21fbc6867b8e0baf8ec6062c4623c1eefc 09:05:00 aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281 09:05:00 aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f 09:05:00 aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f 09:05:00 aztec-1 | 09:05:00 aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5 09:05:00 aztec-1 | 09:05:00 aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89 09:05:00 aztec-1 | 09:05:00 aztec-1 | Address: 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f 09:05:00 aztec-1 | Partial Address: 0x2e19b56de1617cb3179d81be0173c7301621a2e820da7f2754fcfccdfc4418e0 09:05:00 aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae 09:05:00 aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57 09:05:00 aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d 09:05:00 aztec-1 | 09:05:00 aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e 09:05:00 aztec-1 | 09:05:00 aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d 09:05:00 aztec-1 | 09:05:00 aztec-1 | Address: 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 09:05:00 aztec-1 | Partial Address: 0x2451b67547512eaaaad5ded43156c5b80220b7d5145ebefdd57295a9e05a610d 09:05:00 aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c 09:05:00 aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3 09:05:00 aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae 09:05:00 aztec-1 | 09:05:00 aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da 09:05:00 aztec-1 | 09:05:00 aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba 09:05:00 aztec-1 | 09:05:00 aztec-1 | 09:05:00 aztec-1 | [09:04:59.770] INFO: pxe:service Proving completed in 1435.557080999999ms {"timings":{"total":1435.557080999999,"sync":20.52520799999911,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":83.56614200000149,"oracles":{"callPrivateFunction":{"times":[323.8190049999994,218.10504599999877]}}},{"functionName":"private_kernel_init","time":60.448984000000564},{"functionName":"SchnorrAccount:constructor","time":297.3512919999994,"oracles":{"getContractInstance":{"times":[5.372932000000219]},"debugLog":{"times":[0.13014900000052876,0.10940800000025774]},"fetchTaggedLogs":{"times":[100.15018299999974]},"loadCapsule":{"times":[0.18726299999980256,0.1564120000002731,0.21031500000026426]},"validateEnqueuedNotesAndEvents":{"times":[6.334063000000242]},"notifyCreatedNullifier":{"times":[0.21661600000152248,0.21312500000021828]},"notifyCreatedNote":{"times":[0.11521800000082294]},"getRandomField":{"times":[0.09748799999943003,0.10018700000000536,0.061134999999922,0.04167299999971874,0.0301419999996142,0.04520299999967392,0.029782000001432607,0.02797199999986333,0.03735199999937322,0.02586200000041572,0.032853000000613974]},"getIndexedTaggingSecretAsSender":{"times":[33.228055999999924]},"incrementAppTaggingSecretIndexAsSender":{"times":[33.128899000001184]}}},{"functionName":"private_kernel_inner","time":102.91298500000084},{"functionName":"SchnorrAccount:entrypoint","time":193.4327749999993,"oracles":{"debugLog":{"times":[0.14447000000109256,0.06696499999998196,0.17684299999928044]},"fetchTaggedLogs":{"times":[77.93063100000109]},"loadCapsule":{"times":[0.13606000000072527,0.12047899999924994,0.1317799999997078]},"validateEnqueuedNotesAndEvents":{"times":[6.939186999999947]},"getNotes":{"times":[11.22306000000026]},"getAuthWitness":{"times":[0.13968000000022585]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.5512299999991228]}}},{"functionName":"private_kernel_inner","time":103.72510400000101},{"functionName":"private_kernel_reset","time":198.10136499999862},{"functionName":"private_kernel_tail","time":78.61291999999958}],"unaccounted":296.8803059999991}} 09:05:00 aztec-1 | [09:04:59.829] INFO: node Received tx 0x125bdd3c554d10d9e6fa76f81bca4c2f7ae87827447c661dfad05b833f4a106e {"txHash":"0x125bdd3c554d10d9e6fa76f81bca4c2f7ae87827447c661dfad05b833f4a106e"} 09:05:00 aztec-1 | [09:04:59.829] INFO: pxe:service Sent transaction 0x125bdd3c554d10d9e6fa76f81bca4c2f7ae87827447c661dfad05b833f4a106e 09:05:00 aztec-1 | [09:04:59.910] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.019919254000000365s {"duration":0.019919254000000365,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 09:05:00 aztec-1 | [09:04:59.941] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x0d38319f55ddfa395f3bb1dfa1a880fb52600d5bb97f9fde200529f7d382088a","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749805866,"version":2094187016},"txHashes":["0x125bdd3c554d10d9e6fa76f81bca4c2f7ae87827447c661dfad05b833f4a106e"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":60.26584999999977,"publicProcessDuration":20.177082999998674,"rollupCircuitsDuration":52.79289499999868,"txCount":1,"blockNumber":3,"blockTimestamp":1749805866,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:05:00 aztec-1 | [09:05:00.168] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:05:00 aztec-1 | [09:05:00.209] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:05:00 aztec-1 | [09:05:00.209] WARN: foundation:test-date-provider Time set to 2025-06-13T09:11:18.000Z {"offset":377791,"timeMs":1749805878000} 09:05:00 aztec-1 | [09:05:00.209] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 09:05:00 aztec-1 | [09:05:00.212] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 09:05:00 aztec-1 | [09:05:00.240] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:05:00 aztec-1 | error: "Rollup__SlotAlreadyInChain(4, 4)" 09:05:00 aztec-1 | [09:05:00.240] WARN: sequencer Cannot propose block 3 at slot 4 due to failed rollup contract check 09:05:00 aztec-1 | [09:05:00.245] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749805902 09:05:00 aztec-1 | [09:05:00.245] WARN: foundation:test-date-provider Time set to 2025-06-13T09:11:42.000Z {"offset":401755,"timeMs":1749805902000} 09:05:00 aztec-1 | [09:05:00.245] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 09:05:00 aztec-1 | [09:05:00.272] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x0d38319f55ddfa395f3bb1dfa1a880fb52600d5bb97f9fde200529f7d382088a","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749805866,"version":2094187016},"archiveRoot":"0x218d01f09668bd3e73faae7e26124e80bb214326582e7710b73c19c287a9df37","archiveNextLeafIndex":4} 09:05:00 aztec-1 | [09:05:00.275] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 09:05:00 aztec-1 | [09:05:00.338] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":16.710730000002513,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749805866,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:05:01 aztec-1 | [09:05:01.177] WARN: pxe:service No artifact found for contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 when looking for its metadata 09:05:01 aztec-1 | [09:05:01.177] WARN: pxe:service No artifact found for contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 when looking for its metadata 09:05:01 aztec-1 | [09:05:01.179] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 as part of deployment for 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 09:05:01 aztec-1 | [09:05:01.217] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 as part of deployment for 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf 09:05:02 aztec-1 | [09:05:02.044] INFO: pxe:service Added contract FPC at 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 with class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 09:05:02 aztec-1 | [09:05:02.047] INFO: pxe:service Added contract Token at 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf with class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 09:05:04 aztec-1 | [09:05:04.359] INFO: pxe:service Proving completed in 2292.840204ms {"timings":{"total":2292.840204,"sync":41.17001600000003,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":338.24083800000153,"oracles":{"debugLog":{"times":[0.16463199999998324,0.11569900000176858,0.15496100000018487,0.26670999999987544,0.12284899999940535,0.12572899999941,0.10950900000170805,0.17033299999820883]},"fetchTaggedLogs":{"times":[111.53813399999854]},"loadCapsule":{"times":[0.24702799999795388,0.21939600000041537,0.3731469999984256,0.25113799999962794,0.23667700000078185,3.1798820000003616,0.9530100000010862]},"getSharedSecret":{"times":[8.475227999999333]},"aes128Decrypt":{"times":[1.2206590000023425,1.69704400000046]},"getPublicKeysAndPartialAddress":{"times":[2.6408830000000307]},"getKeyValidationRequest":{"times":[2.6902569999983825]},"storeCapsule":{"times":[3.4327510000002803,3.0175400000007357,4.549291999999696]},"deleteCapsule":{"times":[8.007255000000441]},"validateEnqueuedNotesAndEvents":{"times":[19.4165780000003]},"getNotes":{"times":[3.837531000001036]},"getAuthWitness":{"times":[0.23046700000122655]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05782499999986612]},"callPrivateFunction":{"times":[890.5489259999995,100.2127049999981]},"notifyEnqueuedPublicFunctionCall":{"times":[0.29080099999919184]}}},{"functionName":"private_kernel_init","time":49.40017599999919},{"functionName":"ContractClassRegisterer:register","time":871.5442579999981,"oracles":{"loadCapsule":{"times":[3.699769999999262]},"notifyCreatedNullifier":{"times":[0.37896700000055716]},"debugLog":{"times":[0.15951199999835808]},"notifyCreatedContractClassLog":{"times":[5.3090679999986605]}}},{"functionName":"private_kernel_inner","time":103.38169600000037},{"functionName":"ContractInstanceDeployer:deploy","time":67.54397000000245,"oracles":{"storeInExecutionCache":{"times":[0.203044999998383]},"callPrivateFunction":{"times":[38.485599000003276]},"notifyCreatedNullifier":{"times":[0.27339000000210945]},"debugLog":{"times":[0.09768700000131503]}}},{"functionName":"private_kernel_inner","time":93.44082100000014},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":23.605422999997245,"oracles":{}},{"functionName":"private_kernel_inner","time":124.77315799999997},{"functionName":"private_kernel_reset","time":150.06284400000004},{"functionName":"private_kernel_tail","time":74.16849400000137}],"unaccounted":355.50850999999966}} 09:05:04 aztec-1 | [09:05:04.513] INFO: node Received tx 0x068a6e35a9fc99679cb7dc2ff4a6afb65f0dbb2fcbb04f34b2a4a70cac4a7805 {"txHash":"0x068a6e35a9fc99679cb7dc2ff4a6afb65f0dbb2fcbb04f34b2a4a70cac4a7805"} 09:05:04 aztec-1 | [09:05:04.513] INFO: pxe:service Sent transaction 0x068a6e35a9fc99679cb7dc2ff4a6afb65f0dbb2fcbb04f34b2a4a70cac4a7805 09:05:06 aztec-1 | [09:05:06.096] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 1.4597747249999993s {"duration":1.4597747249999993,"rate":28267.37529655476,"totalPublicGas":{"daGas":4096,"l2Gas":41264},"totalBlockGas":{"daGas":166912,"l2Gas":66306},"totalSizeInBytes":10688} 09:05:06 aztec-1 | [09:05:06.301] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 24541.933325412414 mana/s {"blockHash":"0x2a5a7cda3efedcbc66391da02a6859391c35108d571f13957bda2517563a81d5","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805902,"version":2094187016},"txHashes":["0x068a6e35a9fc99679cb7dc2ff4a6afb65f0dbb2fcbb04f34b2a4a70cac4a7805"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":1681.3064159999994,"publicProcessDuration":1459.899985,"rollupCircuitsDuration":1668.5503250000002,"txCount":1,"blockNumber":4,"blockTimestamp":1749805902,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296} 09:05:07 aztec-1 | [09:05:06.982] INFO: pxe:service Proving completed in 2621.807087000001ms {"timings":{"total":2621.807087000001,"sync":124.66939000000275,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":333.58974999999555,"oracles":{"debugLog":{"times":[0.11398799999733455,0.06740499999796157,0.0626540000012028,0.14461099999971339,0.06339499999739928,0.05998399999953108,0.05443400000149268,0.07729499999913969]},"fetchTaggedLogs":{"times":[142.9316629999994]},"loadCapsule":{"times":[0.1518209999994724,0.23785700000007637,13.088455000000977,0.2553889999981038,0.7225719999987632,0.2087150000006659,0.9148969999987457]},"getSharedSecret":{"times":[3.3227530000003753]},"aes128Decrypt":{"times":[0.2325970000019879,0.41261999999915133]},"getPublicKeysAndPartialAddress":{"times":[1.1874669999997423]},"getKeyValidationRequest":{"times":[3.898143999998865]},"storeCapsule":{"times":[3.344963999999891,2.739149000000907,1.8181629999999132]},"deleteCapsule":{"times":[15.780072000001383]},"validateEnqueuedNotesAndEvents":{"times":[10.797408000002179]},"getNotes":{"times":[1.5862660000020696]},"getAuthWitness":{"times":[0.11208799999803887]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.03244300000005751]},"callPrivateFunction":{"times":[877.3914750000004,147.55223000000115]},"notifyEnqueuedPublicFunctionCall":{"times":[0.05004399999961606]}}},{"functionName":"private_kernel_init","time":70.75654500000019},{"functionName":"ContractClassRegisterer:register","time":866.0594969999984,"oracles":{"loadCapsule":{"times":[2.962655999999697]},"notifyCreatedNullifier":{"times":[0.5668509999995877]},"debugLog":{"times":[0.16043100000024424]},"notifyCreatedContractClassLog":{"times":[5.679444999997941]}}},{"functionName":"private_kernel_inner","time":112.82952600000135},{"functionName":"ContractInstanceDeployer:deploy","time":84.13147300000492,"oracles":{"storeInExecutionCache":{"times":[0.0710849999995844]},"callPrivateFunction":{"times":[63.95020799999838]},"notifyCreatedNullifier":{"times":[0.2674990000014077]},"debugLog":{"times":[0.09326700000019628]}}},{"functionName":"private_kernel_inner","time":117.68190999999933},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":46.93904499999917,"oracles":{}},{"functionName":"private_kernel_inner","time":120.37618699999803},{"functionName":"private_kernel_reset","time":196.4131669999988},{"functionName":"private_kernel_tail","time":78.84256499999901}],"unaccounted":469.5180320000036}} 09:05:07 aztec-1 | [09:05:07.265] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:05:07 aztec-1 | [09:05:07.266] INFO: node Received tx 0x00f651115e16adc70a8d4190123d9fd050a0ba883c35282eaa093d3fc747a7da {"txHash":"0x00f651115e16adc70a8d4190123d9fd050a0ba883c35282eaa093d3fc747a7da"} 09:05:07 aztec-1 | [09:05:07.266] INFO: pxe:service Sent transaction 0x00f651115e16adc70a8d4190123d9fd050a0ba883c35282eaa093d3fc747a7da 09:05:07 aztec-1 | [09:05:07.329] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:05:07 aztec-1 | error: "Rollup__SlotAlreadyInChain(5, 5)" 09:05:07 aztec-1 | [09:05:07.329] WARN: sequencer Cannot propose block 4 at slot 5 due to failed rollup contract check 09:05:07 aztec-1 | [09:05:07.407] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x2a5a7cda3efedcbc66391da02a6859391c35108d571f13957bda2517563a81d5","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749805902,"version":2094187016},"archiveRoot":"0x2ea63d472808cd2ab678acf1bd5408c2878e0952726cb970922971b8bcbe5951","archiveNextLeafIndex":5} 09:05:07 aztec-1 | [09:05:07.416] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 09:05:07 aztec-1 | error: "Rollup__SlotAlreadyInChain(5, 5)" 09:05:07 aztec-1 | [09:05:07.416] WARN: sequencer Cannot propose block 4 at slot 5 due to failed rollup contract check 09:05:07 aztec-1 | [09:05:07.446] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749805938 09:05:07 aztec-1 | [09:05:07.446] WARN: foundation:test-date-provider Time set to 2025-06-13T09:12:18.000Z {"offset":430554,"timeMs":1749805938000} 09:05:07 aztec-1 | [09:05:07.446] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 09:05:07 aztec-1 | [09:05:07.450] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 09:05:07 aztec-1 | [09:05:07.451] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 09:05:07 aztec-1 | [09:05:07.477] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":15.032767000000604,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749805902,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296} 09:05:07 aztec-1 | [09:05:07.495] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 09:05:08 aztec-1 | [09:05:07.827] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.26905647999999927s {"duration":0.26905647999999927,"rate":1231893.764461651,"totalPublicGas":{"daGas":10240,"l2Gas":331449},"totalBlockGas":{"daGas":380928,"l2Gas":356491},"totalSizeInBytes":24064} 09:05:08 aztec-1 | [09:05:07.892] INFO: sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 967073.0883454455 mana/s {"blockHash":"0x02efe36a607aa87c61e73798acc60db5364219b4f350615d516ebf670eb99034","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749805938,"version":2094187016},"txHashes":["0x00f651115e16adc70a8d4190123d9fd050a0ba883c35282eaa093d3fc747a7da"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":342.6972350000033,"publicProcessDuration":269.34087999999974,"rollupCircuitsDuration":336.86059899999964,"txCount":1,"blockNumber":5,"blockTimestamp":1749805938,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 09:05:08 aztec-1 | [09:05:08.150] INFO: aztecjs:deploy_sent_tx Contract 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 successfully deployed. 09:05:08 aztec-1 | [09:05:08.153] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:05:08 aztec-1 | [09:05:08.157] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:05:08 aztec-1 | [09:05:08.157] WARN: foundation:test-date-provider Time set to 2025-06-13T09:12:30.000Z {"offset":441843,"timeMs":1749805950000} 09:05:08 aztec-1 | [09:05:08.162] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5 09:05:08 aztec-1 | [09:05:08.166] INFO: aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5. 09:05:08 aztec-1 | [09:05:08.173] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749805974 09:05:08 aztec-1 | [09:05:08.173] WARN: foundation:test-date-provider Time set to 2025-06-13T09:12:54.000Z {"offset":465827,"timeMs":1749805974000} 09:05:08 aztec-1 | [09:05:08.173] INFO: aztecjs:utils:watcher Slot 6 was filled, jumped to next slot 09:05:08 aztec-1 | [09:05:08.176] WARN: archiver Failed to reach L2 block 5 at 33 (latest is 4). Rolling back last synched L1 block number to 32. {"latestLocalL2BlockNumber":4,"latestLocalL2BlockArchive":"0x2ea63d472808cd2ab678acf1bd5408c2878e0952726cb970922971b8bcbe5951","blocksSynchedTo":32,"currentL1BlockNumber":33,"provenBlockNumber":4,"provenArchive":"0x2ea63d472808cd2ab678acf1bd5408c2878e0952726cb970922971b8bcbe5951","pendingBlockNumber":5,"pendingArchive":"0x0304824946a8e5bc9c6044519ba8d2aadbc68db0ad620806c850454b08f81d89"} 09:05:08 aztec-1 | [09:05:08.360] INFO: archiver Downloaded L2 block 5 {"blockHash":"0x02efe36a607aa87c61e73798acc60db5364219b4f350615d516ebf670eb99034","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749805938,"version":2094187016},"archiveRoot":"0x0304824946a8e5bc9c6044519ba8d2aadbc68db0ad620806c850454b08f81d89","archiveNextLeafIndex":6} 09:05:08 aztec-1 | [09:05:08.367] INFO: archiver Updated proven chain to block 5 {"provenBlockNumber":5} 09:05:08 aztec-1 | [09:05:08.430] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":16.78221499999927,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749805938,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 09:05:09 aztec-1 | BananaCoin: 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf 09:05:09 aztec-1 | FPC: 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 09:05:10 aztec-1 | [09:05:09.529] INFO: aztecjs:deploy_sent_tx Contract 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf successfully deployed. 09:05:10 aztec-1 | [09:05:09.558] WARN: pxe:service No artifact found for contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e when looking for its metadata 09:05:10 aztec-1 | [09:05:09.561] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e as part of deployment for 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 09:05:10 aztec-1 | [09:05:09.704] INFO: pxe:service Added contract SponsoredFPC at 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 with class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e 09:05:12 aztec-1 | [09:05:12.083] INFO: pxe:service Proving completed in 2350.9634080000033ms {"timings":{"total":2350.9634080000033,"sync":22.5373159999981,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":92.68498499999987,"oracles":{"callPrivateFunction":{"times":[806.0268959999994,132.79803299999912,43.237575999999535]}}},{"functionName":"private_kernel_init","time":69.51812400000199},{"functionName":"ContractClassRegisterer:register","time":785.4297430000006,"oracles":{"loadCapsule":{"times":[1.725456000000122]},"notifyCreatedNullifier":{"times":[0.33815400000094087]},"debugLog":{"times":[0.10246799999731593]},"notifyCreatedContractClassLog":{"times":[5.407794000002468]}}},{"functionName":"private_kernel_inner","time":141.13340199999948},{"functionName":"ContractInstanceDeployer:deploy","time":74.09148799999457,"oracles":{"storeInExecutionCache":{"times":[0.09251600000061444]},"callPrivateFunction":{"times":[57.91881799999828]},"notifyCreatedNullifier":{"times":[0.2964319999991858]},"debugLog":{"times":[0.16572099999757484]}}},{"functionName":"private_kernel_inner","time":150.24565799999982},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":44.723994000003586,"oracles":{}},{"functionName":"private_kernel_inner","time":137.329733999999},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":33.77626600000076,"oracles":{"debugLog":{"times":[0.1475010000031034]}}},{"functionName":"private_kernel_inner","time":88.62103900000147},{"functionName":"private_kernel_reset","time":178.293443999999},{"functionName":"private_kernel_tail","time":75.57093700000041}],"unaccounted":457.0072780000046}} 09:05:12 aztec-1 | [09:05:12.216] INFO: node Received tx 0x2648f1d886776ea977ab5fe62d0ffb3a16f0f1a1ddb23b23d8194c302c2639a1 {"txHash":"0x2648f1d886776ea977ab5fe62d0ffb3a16f0f1a1ddb23b23d8194c302c2639a1"} 09:05:12 aztec-1 | [09:05:12.216] INFO: pxe:service Sent transaction 0x2648f1d886776ea977ab5fe62d0ffb3a16f0f1a1ddb23b23d8194c302c2639a1 09:05:12 aztec-1 | [09:05:12.326] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03765876900000149s {"duration":0.03765876900000149,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152} 09:05:12 aztec-1 | [09:05:12.357] INFO: sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2ab7b56fb6de60ac609703a04c3512a477b2941e928f888f0c474cff2df8c090","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749805974,"version":2094187016},"txHashes":["0x2648f1d886776ea977ab5fe62d0ffb3a16f0f1a1ddb23b23d8194c302c2639a1"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":74.62527699999919,"publicProcessDuration":37.77587700000004,"rollupCircuitsDuration":69.96586699999898,"txCount":1,"blockNumber":6,"blockTimestamp":1749805974,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:05:12 aztec-1 | [09:05:12.577] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:05:12 aztec-1 | [09:05:12.588] WARN: archiver Failed to reach L2 block 6 at 35 (latest is 5). Rolling back last synched L1 block number to 34. {"latestLocalL2BlockNumber":5,"latestLocalL2BlockArchive":"0x0304824946a8e5bc9c6044519ba8d2aadbc68db0ad620806c850454b08f81d89","blocksSynchedTo":34,"currentL1BlockNumber":35,"provenBlockNumber":5,"provenArchive":"0x0304824946a8e5bc9c6044519ba8d2aadbc68db0ad620806c850454b08f81d89","pendingBlockNumber":6,"pendingArchive":"0x2f4ef0a8e0cbc5e461377564861f1fc9c5a2b986a0eb3b433d614ee74a7bac6c"} 09:05:12 aztec-1 | [09:05:12.594] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6 09:05:12 aztec-1 | [09:05:12.595] INFO: aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6. 09:05:12 aztec-1 | [09:05:12.602] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:05:12 aztec-1 | [09:05:12.602] WARN: foundation:test-date-provider Time set to 2025-06-13T09:13:06.000Z {"offset":473398,"timeMs":1749805986000} 09:05:12 aztec-1 | [09:05:12.615] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749806010 09:05:12 aztec-1 | [09:05:12.615] WARN: foundation:test-date-provider Time set to 2025-06-13T09:13:30.000Z {"offset":497385,"timeMs":1749806010000} 09:05:12 aztec-1 | [09:05:12.615] INFO: aztecjs:utils:watcher Slot 7 was filled, jumped to next slot 09:05:12 aztec-1 | [09:05:12.686] INFO: archiver Downloaded L2 block 6 {"blockHash":"0x2ab7b56fb6de60ac609703a04c3512a477b2941e928f888f0c474cff2df8c090","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749805974,"version":2094187016},"archiveRoot":"0x2f4ef0a8e0cbc5e461377564861f1fc9c5a2b986a0eb3b433d614ee74a7bac6c","archiveNextLeafIndex":7} 09:05:12 aztec-1 | [09:05:12.697] INFO: archiver Updated proven chain to block 6 {"provenBlockNumber":6} 09:05:12 aztec-1 | [09:05:12.748] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":20.093425999999454,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749805974,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:05:13 aztec-1 | SponsoredFPC: 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 09:05:13 aztec-1 | [09:05:13.227] INFO: aztecjs:deploy_sent_tx Contract 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 successfully deployed. 09:05:13 aztec-1 | [09:05:13.233] INFO: cli Aztec Server listening on port 8080 {"l2CircuitsVkTreeRoot":"0x2d2655c513c2e8feb8c7411b7e807c66287b57bb60572ce85ce1fe0a1e0c925d","l2ProtocolContractsTreeRoot":"0x222b5a95b10e0a2dfba4648739629a01cecd69a0bef23a9fc22f846735d7cc71"} 09:05:18 playground-1 | \ 09:05:18 playground-1 | Running 1 test using 1 worker 09:05:18 playground-1 | 09:05:19 playground-1 | ✓ 1 [firefox] › browser.spec.ts:3:1 › test (13.8s) 09:05:36 playground-1 | 1 passed (18.6s) 09:05:36 playground-1 | \ 09:05:36 playground-1 exited with code 0 09:05:36 Aborting on container exit... 09:05:36 Container playground-firefox-playground-1 Stopping 09:05:36 Container playground-firefox-playground-1 Stopped 09:05:36 Container playground-firefox-aztec-1 Stopping 09:05:38 Container playground-firefox-aztec-1 Stopped 09:05:38 Container playground-firefox-ethereum-1 Stopping 09:05:39 Container playground-firefox-ethereum-1 Stopped