Parent Log: http://ci.aztec-labs.com/42ea70df6ab23912 Command: 859dc4243ba40444 playground/scripts/run_test.sh chromium Commit: https://github.com/AztecProtocol/aztec-packages/commit/60e60f99f2634ad75a76b6e103858ff9ccd041d5 Env: REF_NAME=gh-readonly-queue/next/pr-15081-58b067708e8ff927bf26b2994203134853a7eb71 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 18:15:10 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15081_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_3af8716c4dfeb01b_next 18:15:10 Network playground-chromium_default Creating 18:15:11 Network playground-chromium_default Created 18:15:11 Container playground-chromium-ethereum-1 Creating 18:15:12 Container playground-chromium-ethereum-1 Created 18:15:12 Container playground-chromium-aztec-1 Creating 18:15:13 Container playground-chromium-aztec-1 Created 18:15:13 Container playground-chromium-playground-1 Creating 18:15:13 Container playground-chromium-playground-1 Created 18:15:13 Attaching to aztec-1, ethereum-1, playground-1 18:15:19 aztec-1 | 18:15:19 aztec-1 | _ 18:15:19 aztec-1 | /\ | | 18:15:19 aztec-1 | / \ ___| |_ ___ ___ 18:15:19 aztec-1 | / /\ \ |_ / __/ _ \/ __| 18:15:19 aztec-1 | / ____ \ / /| || __/ (__ 18:15:19 aztec-1 | /_/___ \_\/___|\__\___|\___| 18:15:19 aztec-1 | 18:15:19 aztec-1 | https://github.com/AztecProtocol 18:15:19 aztec-1 | 18:15:19 aztec-1 | 18:15:19 aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by... 18:15:19 aztec-1 | Setting up test accounts 18:15:20 aztec-1 | [18:15:19.937] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-U9u5F6/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 18:15:20 aztec-1 | [18:15:20.192] INFO: sandbox Calling anvil_setBlockTimestampInterval with params: [12] 18:15:20 aztec-1 | [18:15:20.197] WARN: sandbox Set block interval to 12 18:15:20 aztec-1 | [18:15:20.375] INFO: sandbox Waiting for 7 transactions to be mined... 18:15:20 aztec-1 | [18:15:20.396] INFO: sandbox All transactions mined successfully 18:15:20 aztec-1 | [18:15:20.476] INFO: sandbox Waiting for 9 transactions to be mined... 18:15:20 aztec-1 | [18:15:20.499] INFO: sandbox All transactions mined successfully 18:15:20 aztec-1 | [18:15:20.680] INFO: sandbox Waiting for 11 transactions to be mined... 18:15:20 aztec-1 | [18:15:20.710] INFO: sandbox All transactions mined successfully 18:15:20 aztec-1 | [18:15:20.790] INFO: sandbox Waiting for 12 transactions to be mined... 18:15:20 aztec-1 | [18:15:20.815] INFO: sandbox All transactions mined successfully 18:15:20 aztec-1 | [18:15:20.828] INFO: sandbox Waiting for 12 transactions to be mined... 18:15:20 aztec-1 | [18:15:20.856] INFO: sandbox All transactions mined successfully 18:15:20 aztec-1 | [18:15:20.874] 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"} 18:15:20 aztec-1 | [18:15:20.874] INFO: sandbox Handing over to governance 18:15:20 aztec-1 | [18:15:20.886] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x3Aa5ebB10DC797CAC828524e59A333d0A371443c 18:15:20 aztec-1 | [18:15:20.887] INFO: telemetry:client Using NoopTelemetryClient 18:15:20 aztec-1 | [18:15:20.887] INFO: blob-sink:client Creating local blob sink client. 18:15:20 aztec-1 | [18:15:20.893] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 18:15:20 aztec-1 | [18:15:20.893] WARN: foundation:test-date-provider Time set to 2025-06-16T18:20:02.000Z {"offset":281107,"timeMs":1750098002000} 18:15:20 aztec-1 | [18:15:20.923] INFO: archiver:lmdb Starting data store with maxReaders 16 18:15:21 aztec-1 | [18:15:21.299] INFO: archiver Starting archiver sync to rollup contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c from L1 block 20 to current L1 block 24 with hash 0xff9ee35da06d8b197b4a00eec9fe370065159d315303a02767d985e12da86e27 {"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0x35e1e637e13f31dc29a8d8678390573c25ce3c664576716a6df6a38565ce2fe0","l1BlockNumber":20}} 18:15:21 aztec-1 | [18:15:21.328] INFO: archiver Initial archiver sync to L1 block 24 complete. {"l1BlockNumber":24,"syncPoint":{"blocksSynchedTo":24,"messagesSynchedTo":{"l1BlockHash":"0xff9ee35da06d8b197b4a00eec9fe370065159d315303a02767d985e12da86e27","l1BlockNumber":24}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 18:15:21 aztec-1 | [18:15:21.339] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-JvzPAo/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 18:15:21 aztec-1 | [18:15:21.582] INFO: world_state Created world state synchroniser with block history of 64 18:15:21 aztec-1 | [18:15:21.582] WARN: node Aztec node is accepting fake proofs 18:15:21 aztec-1 | [18:15:21.591] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 18:15:21 aztec-1 | [18:15:21.604] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 18:15:21 aztec-1 | [18:15:21.616] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 18:15:21 aztec-1 | [18:15:21.627] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 18:15:21 aztec-1 | [18:15:21.632] INFO: world_state Started world state synchronizer from block 1 18:15:22 aztec-1 | [18:15:21.638] INFO: p2p Starting p2p client from block 0 with empty mempools 18:15:22 aztec-1 | [18:15:21.860] INFO: epoch-prune-watcher EpochPruneWatcher initialized 18:15:22 aztec-1 | [18:15:21.917] INFO: slasher Starting Slasher client... 18:15:22 aztec-1 | [18:15:21.927] INFO: sequencer-client Deploying forwarder contract 18:15:22 aztec-1 | [18:15:21.953] INFO: sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac 18:15:22 aztec-1 | [18:15:21.963] INFO: validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 18:15:22 aztec-1 | [18:15:21.964] 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} 18:15:22 aztec-1 | [18:15:21.966] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 18:15:22 aztec-1 | [18:15:21.967] INFO: node Aztec Node version: 0.87.6 18:15:22 aztec-1 | [18:15:21.967] 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"} 18:15:22 aztec-1 | [18:15:21.971] INFO: pxe:data:lmdb Starting data store with maxReaders 16 18:15:22 aztec-1 | [18:15:21.985] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"now":1750098003,"syncedToL1Ts":1750098002,"syncedToL2Slot":1,"nextL2Slot":1,"nextL2SlotTs":1750097990,"l1SlotDuration":12,"rollup":{"blockNumber":1,"slot":2,"timeOfNextL1Slot":1750098026},"newBlockNumber":1,"expectedSlot":1} 18:15:22 aztec-1 | [18:15:22.044] 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. {"now":1750098003,"syncedToL1Ts":1750098002,"syncedToL2Slot":1,"nextL2Slot":1,"nextL2SlotTs":1750097990,"l1SlotDuration":12,"rollup":{"blockNumber":1,"slot":2,"timeOfNextL1Slot":1750098026},"newBlockNumber":1,"expectedSlot":1} 18:15:22 aztec-1 | [18:15:22.118] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 18:15:22 aztec-1 | [18:15:22.118] WARN: foundation:test-date-provider Time set to 2025-06-16T18:20:14.000Z {"offset":291882,"timeMs":1750098014000} 18:15:22 aztec-1 | [18:15:22.242] 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. {"now":1750098003,"syncedToL1Ts":1750098014,"syncedToL2Slot":1,"nextL2Slot":1,"nextL2SlotTs":1750097990,"l1SlotDuration":12,"rollup":{"blockNumber":1,"slot":2,"timeOfNextL1Slot":1750098026},"newBlockNumber":1,"expectedSlot":1} 18:15:22 aztec-1 | Setting up funded test accounts... 18:15:22 aztec-1 | [18:15:22.412] INFO: pxe:service Started PXE connected to chain 31337 version 3633326044 18:15:22 aztec-1 | [18:15:22.547] INFO: pxe:service Registered account 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e 18:15:22 aztec-1 | [18:15:22.632] WARN: pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata 18:15:22 aztec-1 | [18:15:22.636] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e 18:15:22 aztec-1 | [18:15:22.836] INFO: pxe:service Added contract SchnorrAccount at 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 18:15:25 aztec-1 | [18:15:25.654] INFO: pxe:service Proving completed in 2769.3195730000007ms {"timings":{"total":2769.3195730000007,"sync":13.028519999999844,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":173.9364710000009,"oracles":{"callPrivateFunction":{"times":[882.7417949999999,360.5608690000008,209.57497499999954]}}},{"functionName":"private_kernel_init","time":121.39718399999947},{"functionName":"ContractClassRegisterer:register","time":858.6391240000012,"oracles":{"loadCapsule":{"times":[36.60242699999981]},"notifyCreatedNullifier":{"times":[1.223400999999285]},"debugLog":{"times":[0.6236060000010184]},"notifyCreatedContractClassLog":{"times":[9.074910999999702]}}},{"functionName":"private_kernel_inner","time":153.5752109999994},{"functionName":"SchnorrAccount:constructor","time":339.36213099999986,"oracles":{"getContractInstance":{"times":[2.5701460000000225]},"debugLog":{"times":[0.19455500000003667,0.19778500000029453]},"fetchTaggedLogs":{"times":[87.34700899999916]},"loadCapsule":{"times":[0.46194199999990815,0.2818169999991369,1.6377420000007987]},"validateEnqueuedNotesAndEvents":{"times":[16.601871999999275]},"notifyCreatedNullifier":{"times":[0.2952069999992091,0.27263700000003155]},"notifyCreatedNote":{"times":[0.7318089999989752]},"getRandomField":{"times":[0.3359280000004219,0.23907599999984086,0.061840999998821644,0.04754200000024866,0.17239499999959662,0.06483200000002398,0.09055200000148034,0.04105100000015227,0.04273099999954866,0.10584300000118674,0.03701099999852886]},"getIndexedTaggingSecretAsSender":{"times":[30.65128899999945]},"incrementAppTaggingSecretIndexAsSender":{"times":[33.79176799999914]}}},{"functionName":"private_kernel_inner","time":97.06163600000036},{"functionName":"SchnorrAccount:entrypoint","time":179.30893599999945,"oracles":{"debugLog":{"times":[0.2766970000011497,0.09761299999991024,0.38402900000073714]},"fetchTaggedLogs":{"times":[56.82002300000022]},"loadCapsule":{"times":[0.19923500000004424,0.13187399999878835,0.22147599999880185]},"validateEnqueuedNotesAndEvents":{"times":[6.170766999999614]},"getNotes":{"times":[6.057313999999678]},"getAuthWitness":{"times":[0.3773089999995136]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.0009250000002794]}}},{"functionName":"private_kernel_inner","time":107.72151699999995},{"functionName":"private_kernel_reset","time":200.83065199999874},{"functionName":"private_kernel_tail","time":91.38753200000065}],"unaccounted":433.0706590000009}} 18:15:25 aztec-1 | [18:15:25.750] INFO: node Received tx 0x03a19ab1050ddd7cdf043560b1b2075deecbd4851eb74412d61ec7979f363167 {"txHash":"0x03a19ab1050ddd7cdf043560b1b2075deecbd4851eb74412d61ec7979f363167"} 18:15:25 aztec-1 | [18:15:25.750] INFO: pxe:service Sent transaction 0x03a19ab1050ddd7cdf043560b1b2075deecbd4851eb74412d61ec7979f363167 18:15:25 aztec-1 | [18:15:25.861] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.045183397000000694s {"duration":0.045183397000000694,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 18:15:25 aztec-1 | [18:15:25.899] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x0e9253385ab428f99c0b3437353410afc8de41bf4aa665f2139629ffece4c742","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750098026,"version":3633326044},"txHashes":["0x03a19ab1050ddd7cdf043560b1b2075deecbd4851eb74412d61ec7979f363167"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":94.18699299999935,"publicProcessDuration":45.86394499999915,"rollupCircuitsDuration":85.50675199999932,"txCount":1,"blockNumber":1,"blockTimestamp":1750098026,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 18:15:29 aztec-1 | [18:15:28.814] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 18:15:29 aztec-1 | [18:15:28.817] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 18:15:29 aztec-1 | [18:15:28.821] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 18:15:29 aztec-1 | [18:15:28.824] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750098062 18:15:29 aztec-1 | [18:15:28.824] WARN: foundation:test-date-provider Time set to 2025-06-16T18:21:02.000Z {"offset":333176,"timeMs":1750098062000} 18:15:29 aztec-1 | [18:15:28.824] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 18:15:29 aztec-1 | [18:15:28.913] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x0e9253385ab428f99c0b3437353410afc8de41bf4aa665f2139629ffece4c742","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750098026,"version":3633326044},"archiveRoot":"0x02f2dbafd072c4657351f7e0ca984d2f52d60ade588a4bc949114359f01e9344","archiveNextLeafIndex":2} 18:15:29 aztec-1 | [18:15:28.919] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 18:15:29 aztec-1 | [18:15:28.985] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":21.01189400000112,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750098026,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 18:15:29 aztec-1 | [18:15:29.112] INFO: pxe:service Registered account 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f 18:15:29 aztec-1 | [18:15:29.255] INFO: pxe:service Added contract SchnorrAccount at 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 18:15:31 aztec-1 | [18:15:30.741] INFO: pxe:service Proving completed in 1453.9815859999999ms {"timings":{"total":1453.9815859999999,"sync":32.76990300000034,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":115.26981799999885,"oracles":{"callPrivateFunction":{"times":[384.7851950000004,279.38806699999986]}}},{"functionName":"private_kernel_init","time":54.232388000000356},{"functionName":"SchnorrAccount:constructor","time":355.1966740000007,"oracles":{"getContractInstance":{"times":[12.49906799999917]},"debugLog":{"times":[0.15734399999928428,0.10748200000125507]},"fetchTaggedLogs":{"times":[103.17752200000177]},"loadCapsule":{"times":[0.23753600000054576,0.30826800000068033,0.21910600000046543]},"validateEnqueuedNotesAndEvents":{"times":[6.414543000000776]},"notifyCreatedNullifier":{"times":[0.2532160000009753,0.24273600000014994]},"notifyCreatedNote":{"times":[0.1525739999997313]},"getRandomField":{"times":[0.17099400000006426,0.22469600000113132,0.11713199999940116,0.05100099999981467,0.0394109999997454,0.04093100000136474,0.03423099999963597,0.046651000000565546,0.0320609999998851,0.03693099999873084,0.034701000000495696]},"getIndexedTaggingSecretAsSender":{"times":[50.23495600000024]},"incrementAppTaggingSecretIndexAsSender":{"times":[31.601113000000623]}}},{"functionName":"private_kernel_inner","time":89.88609400000132},{"functionName":"SchnorrAccount:entrypoint","time":252.65988800000014,"oracles":{"debugLog":{"times":[0.2638459999998304,0.12799300000006042,0.20648500000061176]},"fetchTaggedLogs":{"times":[93.6815400000014]},"loadCapsule":{"times":[0.20668499999919732,0.1737250000005588,0.18454499999825202]},"validateEnqueuedNotesAndEvents":{"times":[10.795583999999508]},"getNotes":{"times":[10.474276000000827]},"getAuthWitness":{"times":[0.18351400000028661]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.5833150000016758]}}},{"functionName":"private_kernel_inner","time":85.43459099999927},{"functionName":"private_kernel_reset","time":151.21797200000037},{"functionName":"private_kernel_tail","time":89.3873709999989}],"unaccounted":227.92688699999962}} 18:15:31 aztec-1 | [18:15:30.785] INFO: node Received tx 0x2ad7a16727f91fc3ae1910cd5b31c9ed470130a1fe78439df71767da68df3a75 {"txHash":"0x2ad7a16727f91fc3ae1910cd5b31c9ed470130a1fe78439df71767da68df3a75"} 18:15:31 aztec-1 | [18:15:30.785] INFO: pxe:service Sent transaction 0x2ad7a16727f91fc3ae1910cd5b31c9ed470130a1fe78439df71767da68df3a75 18:15:31 aztec-1 | [18:15:30.868] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.01825810400000046s {"duration":0.01825810400000046,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 18:15:31 aztec-1 | [18:15:30.897] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1f486a626c05658348d61961ae88967eb4bfb7db89fd5112540c3d0ebefd1dea","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750098062,"version":3633326044},"txHashes":["0x2ad7a16727f91fc3ae1910cd5b31c9ed470130a1fe78439df71767da68df3a75"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":53.8991189999997,"publicProcessDuration":18.372287000000142,"rollupCircuitsDuration":48.89932299999964,"txCount":1,"blockNumber":2,"blockTimestamp":1750098062,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 18:15:31 aztec-1 | [18:15:31.134] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 18:15:31 aztec-1 | [18:15:31.147] 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":"0x02f2dbafd072c4657351f7e0ca984d2f52d60ade588a4bc949114359f01e9344","blocksSynchedTo":26,"currentL1BlockNumber":27,"provenBlockNumber":1,"provenArchive":"0x02f2dbafd072c4657351f7e0ca984d2f52d60ade588a4bc949114359f01e9344","pendingBlockNumber":2,"pendingArchive":"0x27cd5d4eb0893a7a0a8b05abcbcc16247550be5a7c399091736ef47ba8c0ca9d"} 18:15:31 aztec-1 | [18:15:31.171] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 18:15:31 aztec-1 | [18:15:31.171] WARN: foundation:test-date-provider Time set to 2025-06-16T18:21:14.000Z {"offset":342829,"timeMs":1750098074000} 18:15:31 aztec-1 | [18:15:31.176] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 18:15:31 aztec-1 | [18:15:31.177] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 18:15:31 aztec-1 | [18:15:31.198] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750098098 18:15:31 aztec-1 | [18:15:31.198] WARN: foundation:test-date-provider Time set to 2025-06-16T18:21:38.000Z {"offset":366802,"timeMs":1750098098000} 18:15:31 aztec-1 | [18:15:31.198] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 18:15:31 aztec-1 | [18:15:31.245] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x1f486a626c05658348d61961ae88967eb4bfb7db89fd5112540c3d0ebefd1dea","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750098062,"version":3633326044},"archiveRoot":"0x27cd5d4eb0893a7a0a8b05abcbcc16247550be5a7c399091736ef47ba8c0ca9d","archiveNextLeafIndex":3} 18:15:31 aztec-1 | [18:15:31.250] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 18:15:31 aztec-1 | [18:15:31.307] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":17.48274400000082,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750098062,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 18:15:31 aztec-1 | [18:15:31.427] INFO: pxe:service Registered account 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 18:15:31 aztec-1 | [18:15:31.554] INFO: pxe:service Added contract SchnorrAccount at 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 18:15:33 aztec-1 | Initial Accounts: 18:15:33 aztec-1 | 18:15:33 aztec-1 | Address: 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e 18:15:33 aztec-1 | Partial Address: 0x27b4888363d0f9eb0d43aa0b2ff08f21fbc6867b8e0baf8ec6062c4623c1eefc 18:15:33 aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281 18:15:33 aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f 18:15:33 aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f 18:15:33 aztec-1 | 18:15:33 aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5 18:15:33 aztec-1 | 18:15:33 aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89 18:15:33 aztec-1 | 18:15:33 aztec-1 | Address: 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f 18:15:33 aztec-1 | Partial Address: 0x2e19b56de1617cb3179d81be0173c7301621a2e820da7f2754fcfccdfc4418e0 18:15:33 aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae 18:15:33 aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57 18:15:33 aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d 18:15:33 aztec-1 | 18:15:33 aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e 18:15:33 aztec-1 | 18:15:33 aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d 18:15:33 aztec-1 | 18:15:33 aztec-1 | Address: 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 18:15:33 aztec-1 | Partial Address: 0x2451b67547512eaaaad5ded43156c5b80220b7d5145ebefdd57295a9e05a610d 18:15:33 aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c 18:15:33 aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3 18:15:33 aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae 18:15:33 aztec-1 | 18:15:33 aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da 18:15:33 aztec-1 | 18:15:33 aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba 18:15:33 aztec-1 | 18:15:33 aztec-1 | 18:15:33 aztec-1 | [18:15:32.984] INFO: pxe:service Proving completed in 1419.0909300000021ms {"timings":{"total":1419.0909300000021,"sync":24.302136999998766,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":84.11474600000656,"oracles":{"callPrivateFunction":{"times":[340.3224650000011,266.0469890000022]}}},{"functionName":"private_kernel_init","time":48.489512000000104},{"functionName":"SchnorrAccount:constructor","time":320.6192149999988,"oracles":{"getContractInstance":{"times":[0.9928450000006706]},"debugLog":{"times":[0.07618200000069919,0.06977199999892036]},"fetchTaggedLogs":{"times":[107.35860700000194]},"loadCapsule":{"times":[0.17684400000143796,0.1177029999998922,0.13340299999981653]},"validateEnqueuedNotesAndEvents":{"times":[8.093785000000935]},"notifyCreatedNullifier":{"times":[0.18361499999809894,0.31453899999905843]},"notifyCreatedNote":{"times":[0.10740300000179559]},"getRandomField":{"times":[0.14911399999982677,0.21723600000041188,0.09430299999803538,0.10148199999821372,0.04890099999829545,0.06761200000255485,0.040120999998180196,0.06267099999968195,0.04487199999857694,0.054460999999719206,0.03580100000181119]},"getIndexedTaggingSecretAsSender":{"times":[46.168203000001085]},"incrementAppTaggingSecretIndexAsSender":{"times":[28.88447399999859]}}},{"functionName":"private_kernel_inner","time":128.0319219999983},{"functionName":"SchnorrAccount:entrypoint","time":238.25071299999763,"oracles":{"debugLog":{"times":[0.1499340000009397,0.0801919999976235,0.08654200000091805]},"fetchTaggedLogs":{"times":[118.45652899999914]},"loadCapsule":{"times":[0.26474700000107987,0.14791299999706098,0.14147400000001653]},"validateEnqueuedNotesAndEvents":{"times":[5.857089000000997]},"getNotes":{"times":[2.3406990000003134]},"getAuthWitness":{"times":[0.14331400000082795]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.28594800000064424]}}},{"functionName":"private_kernel_inner","time":110.15985899999941},{"functionName":"private_kernel_reset","time":161.32290800000192},{"functionName":"private_kernel_tail","time":62.650321000000986}],"unaccounted":241.14959699999963}} 18:15:33 aztec-1 | [18:15:33.049] INFO: node Received tx 0x0c400992a28ee8a35ebb2cad8ba3a7c5e55cca6463e75ed69cc355967535fe27 {"txHash":"0x0c400992a28ee8a35ebb2cad8ba3a7c5e55cca6463e75ed69cc355967535fe27"} 18:15:33 aztec-1 | [18:15:33.049] INFO: pxe:service Sent transaction 0x0c400992a28ee8a35ebb2cad8ba3a7c5e55cca6463e75ed69cc355967535fe27 18:15:33 aztec-1 | [18:15:33.135] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.022027428999997937s {"duration":0.022027428999997937,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 18:15:33 aztec-1 | [18:15:33.161] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2d628ede23e76284ade0752c8ece56586dd502cd82e32155a1c762a4854cb76f","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750098098,"version":3633326044},"txHashes":["0x0c400992a28ee8a35ebb2cad8ba3a7c5e55cca6463e75ed69cc355967535fe27"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":53.96060099999886,"publicProcessDuration":22.19939400000294,"rollupCircuitsDuration":49.69523300000219,"txCount":1,"blockNumber":3,"blockTimestamp":1750098098,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 18:15:33 aztec-1 | [18:15:33.341] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 18:15:33 aztec-1 | [18:15:33.345] WARN: archiver Failed to reach L2 block 3 at 29 (latest is 2). Rolling back last synched L1 block number to 28. {"latestLocalL2BlockNumber":2,"latestLocalL2BlockArchive":"0x27cd5d4eb0893a7a0a8b05abcbcc16247550be5a7c399091736ef47ba8c0ca9d","blocksSynchedTo":28,"currentL1BlockNumber":29,"provenBlockNumber":2,"provenArchive":"0x27cd5d4eb0893a7a0a8b05abcbcc16247550be5a7c399091736ef47ba8c0ca9d","pendingBlockNumber":3,"pendingArchive":"0x1983f4fc03cf34f0940a6eefef34987c65bdceb3a6fdfed9ebda4b5e97148925"} 18:15:33 aztec-1 | [18:15:33.425] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 18:15:33 aztec-1 | [18:15:33.425] WARN: foundation:test-date-provider Time set to 2025-06-16T18:21:50.000Z {"offset":376575,"timeMs":1750098110000} 18:15:33 aztec-1 | [18:15:33.432] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 18:15:33 aztec-1 | [18:15:33.433] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 18:15:33 aztec-1 | [18:15:33.434] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x2d628ede23e76284ade0752c8ece56586dd502cd82e32155a1c762a4854cb76f","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750098098,"version":3633326044},"archiveRoot":"0x1983f4fc03cf34f0940a6eefef34987c65bdceb3a6fdfed9ebda4b5e97148925","archiveNextLeafIndex":4} 18:15:33 aztec-1 | [18:15:33.441] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750098134 18:15:33 aztec-1 | [18:15:33.441] WARN: foundation:test-date-provider Time set to 2025-06-16T18:22:14.000Z {"offset":400559,"timeMs":1750098134000} 18:15:33 aztec-1 | [18:15:33.441] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 18:15:33 aztec-1 | [18:15:33.473] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":16.237511999999697,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750098098,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 18:15:33 aztec-1 | [18:15:33.503] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 18:15:34 aztec-1 | [18:15:34.175] WARN: pxe:service No artifact found for contract class 0x29dcd4953013697442c3dd70078007da6d27e978e725c3471dcafb67969f2164 when looking for its metadata 18:15:34 aztec-1 | [18:15:34.175] WARN: pxe:service No artifact found for contract class 0x02c03e14465d82e13534072f58c1e39d320b13cf92255a90ff4c99e1fca555a2 when looking for its metadata 18:15:34 aztec-1 | [18:15:34.178] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x29dcd4953013697442c3dd70078007da6d27e978e725c3471dcafb67969f2164 as part of deployment for 0x0723941b267b3ced905d304c517042f5c3f85d0f13232460da9a9f4d2a6b1c13 18:15:34 aztec-1 | [18:15:34.200] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x02c03e14465d82e13534072f58c1e39d320b13cf92255a90ff4c99e1fca555a2 as part of deployment for 0x131b1ba6febb2758f11effaa18660d879c3614cb411076276dfd197b94530547 18:15:35 aztec-1 | [18:15:35.101] INFO: pxe:service Added contract Token at 0x131b1ba6febb2758f11effaa18660d879c3614cb411076276dfd197b94530547 with class 0x02c03e14465d82e13534072f58c1e39d320b13cf92255a90ff4c99e1fca555a2 18:15:35 aztec-1 | [18:15:35.106] INFO: pxe:service Added contract FPC at 0x0723941b267b3ced905d304c517042f5c3f85d0f13232460da9a9f4d2a6b1c13 with class 0x29dcd4953013697442c3dd70078007da6d27e978e725c3471dcafb67969f2164 18:15:37 aztec-1 | [18:15:37.405] INFO: pxe:service Proving completed in 2276.375358000001ms {"timings":{"total":2276.375358000001,"sync":18.146991000001435,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":338.1530619999976,"oracles":{"debugLog":{"times":[0.21577500000057626,0.08223200000065845,0.09832200000164448,0.1836540000003879,0.10703300000022864,0.13930400000026566,0.09477300000071409,0.13949399999910383]},"fetchTaggedLogs":{"times":[111.8704719999987]},"loadCapsule":{"times":[0.19644500000140397,0.2528759999986505,0.20236500000100932,0.26009699999849545,0.2868670000025304,0.16863400000147521,0.18298499999946216]},"getSharedSecret":{"times":[4.498294000000897]},"aes128Decrypt":{"times":[1.0191759999979695,0.7287489999980608]},"getPublicKeysAndPartialAddress":{"times":[2.8366119999991497]},"getKeyValidationRequest":{"times":[1.962309999998979]},"storeCapsule":{"times":[3.628602000000683,3.1375200000002224,2.732579999999871]},"deleteCapsule":{"times":[2.9040139999997336]},"validateEnqueuedNotesAndEvents":{"times":[22.060481000000436]},"getNotes":{"times":[4.796041999998124]},"getAuthWitness":{"times":[0.2157260000021779]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05489099999977043]},"callPrivateFunction":{"times":[679.2296549999992,148.46284200000082]},"notifyEnqueuedPublicFunctionCall":{"times":[0.41092099999877973]}}},{"functionName":"private_kernel_init","time":85.46483100000114},{"functionName":"ContractClassRegisterer:register","time":663.7601529999993,"oracles":{"loadCapsule":{"times":[3.3277540000017325]},"notifyCreatedNullifier":{"times":[0.3703400000013062]},"debugLog":{"times":[0.18325499999991735]},"notifyCreatedContractClassLog":{"times":[10.5162670000027]}}},{"functionName":"private_kernel_inner","time":141.44077299999844},{"functionName":"ContractInstanceDeployer:deploy","time":88.80775599999834,"oracles":{"storeInExecutionCache":{"times":[0.28878700000132085]},"callPrivateFunction":{"times":[54.80429300000105]},"notifyCreatedNullifier":{"times":[0.37283000000024913]},"debugLog":{"times":[0.22658600000067963]}}},{"functionName":"private_kernel_inner","time":96.03771000000052},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":39.38622000000032,"oracles":{}},{"functionName":"private_kernel_inner","time":109.53826300000219},{"functionName":"private_kernel_reset","time":185.88224199999968},{"functionName":"private_kernel_tail","time":104.53519500000039}],"unaccounted":405.22216200000184}} 18:15:37 aztec-1 | [18:15:37.625] INFO: node Received tx 0x074b5fffe87299daca1240e6452992c5d64ef1daa678e7e90185a7d4b505bc93 {"txHash":"0x074b5fffe87299daca1240e6452992c5d64ef1daa678e7e90185a7d4b505bc93"} 18:15:37 aztec-1 | [18:15:37.625] INFO: pxe:service Sent transaction 0x074b5fffe87299daca1240e6452992c5d64ef1daa678e7e90185a7d4b505bc93 18:15:40 aztec-1 | [18:15:40.151] INFO: pxe:service Proving completed in 2745.456240000003ms {"timings":{"total":2745.456240000003,"sync":176.43024199999854,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":557.6300149999988,"oracles":{"debugLog":{"times":[0.21979600000122446,0.11472199999843724,0.09569200000260025,0.24647600000025705,0.11056299999836483,0.1696139999985462,0.08802300000024843,0.20819599999958882]},"fetchTaggedLogs":{"times":[226.72290900000007]},"loadCapsule":{"times":[0.5103330000019923,0.5160329999998794,0.5796339999978954,0.29227800000080606,1.300532999997813,0.34814799999730894,0.27588699999978417]},"getSharedSecret":{"times":[7.83136899999954]},"aes128Decrypt":{"times":[0.27462699999887263,0.7772689999983413]},"getPublicKeysAndPartialAddress":{"times":[4.077292999998463]},"getKeyValidationRequest":{"times":[5.546080999996775]},"storeCapsule":{"times":[88.12051900000006,4.9293660000003,2.684737999999925]},"deleteCapsule":{"times":[2.8378819999998086]},"validateEnqueuedNotesAndEvents":{"times":[12.14854799999739]},"getNotes":{"times":[2.7444800000012037]},"getAuthWitness":{"times":[0.29239700000107405]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06607199999780278]},"callPrivateFunction":{"times":[848.2185570000001,139.4351029999998]},"notifyEnqueuedPublicFunctionCall":{"times":[0.057061999999859836]}}},{"functionName":"private_kernel_init","time":55.98545199999717},{"functionName":"ContractClassRegisterer:register","time":829.6877160000004,"oracles":{"loadCapsule":{"times":[2.935635000001639]},"notifyCreatedNullifier":{"times":[0.4755519999998796]},"debugLog":{"times":[0.1707139999998617]},"notifyCreatedContractClassLog":{"times":[5.871139999999286]}}},{"functionName":"private_kernel_inner","time":112.51863800000137},{"functionName":"ContractInstanceDeployer:deploy","time":84.93334700000196,"oracles":{"storeInExecutionCache":{"times":[0.13920299999881536]},"callPrivateFunction":{"times":[52.26661800000147]},"notifyCreatedNullifier":{"times":[0.2455259999987902]},"debugLog":{"times":[0.11239299999942887]}}},{"functionName":"private_kernel_inner","time":122.6223949999985},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":39.284287999998924,"oracles":{}},{"functionName":"private_kernel_inner","time":108.40945400000055},{"functionName":"private_kernel_reset","time":187.24717200000305},{"functionName":"private_kernel_tail","time":80.83233499999915}],"unaccounted":389.87518600000476}} 18:15:40 aztec-1 | [18:15:40.184] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 2.382702387999998s {"duration":2.382702387999998,"rate":132812.22262324786,"totalPublicGas":{"daGas":10240,"l2Gas":316452},"totalBlockGas":{"daGas":380928,"l2Gas":341494},"totalSizeInBytes":24064} 18:15:40 aztec-1 | [18:15:40.306] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 125593.32349873793 mana/s {"blockHash":"0x2628715c800c96a1b840639e8a2f197f5a1fd42f660c73a68bf6fc786f1c90bf","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750098134,"version":3633326044},"txHashes":["0x074b5fffe87299daca1240e6452992c5d64ef1daa678e7e90185a7d4b505bc93"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":2519.572677999997,"publicProcessDuration":2382.853761999999,"rollupCircuitsDuration":2506.828143999999,"txCount":1,"blockNumber":4,"blockTimestamp":1750098134,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 18:15:40 aztec-1 | [18:15:40.311] INFO: node Received tx 0x1a9ad3305fcb08bf1c0d37a53813ccb7a120128356115830f62c718fb4fb8335 {"txHash":"0x1a9ad3305fcb08bf1c0d37a53813ccb7a120128356115830f62c718fb4fb8335"} 18:15:40 aztec-1 | [18:15:40.311] INFO: pxe:service Sent transaction 0x1a9ad3305fcb08bf1c0d37a53813ccb7a120128356115830f62c718fb4fb8335 18:15:40 aztec-1 | [18:15:40.603] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 18:15:40 aztec-1 | [18:15:40.608] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 18:15:40 aztec-1 | [18:15:40.608] WARN: foundation:test-date-provider Time set to 2025-06-16T18:22:26.000Z {"offset":405392,"timeMs":1750098146000} 18:15:40 aztec-1 | [18:15:40.610] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750098170 18:15:40 aztec-1 | [18:15:40.610] WARN: foundation:test-date-provider Time set to 2025-06-16T18:22:50.000Z {"offset":429390,"timeMs":1750098170000} 18:15:40 aztec-1 | [18:15:40.610] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 18:15:40 aztec-1 | [18:15:40.612] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 18:15:40 aztec-1 | [18:15:40.613] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 18:15:40 aztec-1 | [18:15:40.615] WARN: archiver Failed to reach L2 block 4 at 31 (latest is 3). Rolling back last synched L1 block number to 30. {"latestLocalL2BlockNumber":3,"latestLocalL2BlockArchive":"0x1983f4fc03cf34f0940a6eefef34987c65bdceb3a6fdfed9ebda4b5e97148925","blocksSynchedTo":30,"currentL1BlockNumber":31,"provenBlockNumber":3,"provenArchive":"0x1983f4fc03cf34f0940a6eefef34987c65bdceb3a6fdfed9ebda4b5e97148925","pendingBlockNumber":4,"pendingArchive":"0x259adbc4ca65c7e2d7ad277acf4867c4de131a63f4126373beb538e8a830ffe8"} 18:15:40 aztec-1 | [18:15:40.814] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x2628715c800c96a1b840639e8a2f197f5a1fd42f660c73a68bf6fc786f1c90bf","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750098134,"version":3633326044},"archiveRoot":"0x259adbc4ca65c7e2d7ad277acf4867c4de131a63f4126373beb538e8a830ffe8","archiveNextLeafIndex":5} 18:15:40 aztec-1 | [18:15:40.823] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 18:15:40 aztec-1 | [18:15:40.882] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":17.867419000001973,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1750098134,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 18:15:41 aztec-1 | [18:15:41.163] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.22881880099999763s {"duration":0.22881880099999763,"rate":173897.4237523446,"totalPublicGas":{"daGas":4096,"l2Gas":39791},"totalBlockGas":{"daGas":136192,"l2Gas":64833},"totalSizeInBytes":8768} 18:15:41 aztec-1 | [18:15:41.193] INFO: sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 149358.944922198 mana/s {"blockHash":"0x18cbe8c7b3fa4bf4f056b2191ecbefb8645bc1f35bf732163f0c9f1162cb71f7","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1750098170,"version":3633326044},"txHashes":["0x1a9ad3305fcb08bf1c0d37a53813ccb7a120128356115830f62c718fb4fb8335"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":266.3710370000008,"publicProcessDuration":229.33851399999912,"rollupCircuitsDuration":261.3616800000018,"txCount":1,"blockNumber":5,"blockTimestamp":1750098170,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":236} 18:15:41 aztec-1 | [18:15:41.514] INFO: aztecjs:deploy_sent_tx Contract 0x131b1ba6febb2758f11effaa18660d879c3614cb411076276dfd197b94530547 successfully deployed. 18:15:41 aztec-1 | [18:15:41.726] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 18:15:41 aztec-1 | [18:15:41.744] 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":"0x259adbc4ca65c7e2d7ad277acf4867c4de131a63f4126373beb538e8a830ffe8","blocksSynchedTo":32,"currentL1BlockNumber":33,"provenBlockNumber":4,"provenArchive":"0x259adbc4ca65c7e2d7ad277acf4867c4de131a63f4126373beb538e8a830ffe8","pendingBlockNumber":5,"pendingArchive":"0x27b931e9b32bf065a1fb93f0b1650a64a657d41b0063c4ca09f366fd3871bbfa"} 18:15:41 aztec-1 | [18:15:41.755] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 18:15:41 aztec-1 | [18:15:41.755] WARN: foundation:test-date-provider Time set to 2025-06-16T18:23:02.000Z {"offset":440245,"timeMs":1750098182000} 18:15:41 aztec-1 | [18:15:41.761] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5 18:15:41 aztec-1 | [18:15:41.763] INFO: aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5. 18:15:41 aztec-1 | [18:15:41.767] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750098206 18:15:41 aztec-1 | [18:15:41.767] WARN: foundation:test-date-provider Time set to 2025-06-16T18:23:26.000Z {"offset":464233,"timeMs":1750098206000} 18:15:41 aztec-1 | [18:15:41.767] INFO: aztecjs:utils:watcher Slot 6 was filled, jumped to next slot 18:15:41 aztec-1 | [18:15:41.909] INFO: archiver Downloaded L2 block 5 {"blockHash":"0x18cbe8c7b3fa4bf4f056b2191ecbefb8645bc1f35bf732163f0c9f1162cb71f7","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1750098170,"version":3633326044},"archiveRoot":"0x27b931e9b32bf065a1fb93f0b1650a64a657d41b0063c4ca09f366fd3871bbfa","archiveNextLeafIndex":6} 18:15:41 aztec-1 | [18:15:41.930] INFO: archiver Updated proven chain to block 5 {"provenBlockNumber":5} 18:15:41 aztec-1 | [18:15:41.962] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":17.383127000000968,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1750098170,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":236} 18:15:42 aztec-1 | BananaCoin: 0x131b1ba6febb2758f11effaa18660d879c3614cb411076276dfd197b94530547 18:15:42 aztec-1 | FPC: 0x0723941b267b3ced905d304c517042f5c3f85d0f13232460da9a9f4d2a6b1c13 18:15:42 aztec-1 | [18:15:42.608] INFO: aztecjs:deploy_sent_tx Contract 0x0723941b267b3ced905d304c517042f5c3f85d0f13232460da9a9f4d2a6b1c13 successfully deployed. 18:15:42 aztec-1 | [18:15:42.636] WARN: pxe:service No artifact found for contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e when looking for its metadata 18:15:42 aztec-1 | [18:15:42.638] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e as part of deployment for 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 18:15:42 aztec-1 | [18:15:42.795] INFO: pxe:service Added contract SponsoredFPC at 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 with class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e 18:15:46 aztec-1 | [18:15:45.293] INFO: pxe:service Proving completed in 2480.863690000002ms {"timings":{"total":2480.863690000002,"sync":38.700064000000566,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":99.06721299999845,"oracles":{"callPrivateFunction":{"times":[888.4177569999993,143.56564399999843,50.16001300000062]}}},{"functionName":"private_kernel_init","time":51.39547399999719},{"functionName":"ContractClassRegisterer:register","time":869.126612,"oracles":{"loadCapsule":{"times":[2.4807529999998224]},"notifyCreatedNullifier":{"times":[0.4713420000007318]},"debugLog":{"times":[0.1760340000000724]},"notifyCreatedContractClassLog":{"times":[5.823296999999002]}}},{"functionName":"private_kernel_inner","time":137.07258100000035},{"functionName":"ContractInstanceDeployer:deploy","time":85.45646200000192,"oracles":{"storeInExecutionCache":{"times":[0.09543300000223098]},"callPrivateFunction":{"times":[55.245631000001595]},"notifyCreatedNullifier":{"times":[0.21458500000153435]},"debugLog":{"times":[0.1766940000015893]}}},{"functionName":"private_kernel_inner","time":128.52721600000223},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":44.01817899999878,"oracles":{}},{"functionName":"private_kernel_inner","time":120.1860059999999},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":39.91083499999877,"oracles":{"debugLog":{"times":[0.1974549999977171]}}},{"functionName":"private_kernel_inner","time":111.27433100000053},{"functionName":"private_kernel_reset","time":198.98256000000038},{"functionName":"private_kernel_tail","time":95.75169099999766}],"unaccounted":461.3944660000052}} 18:15:46 aztec-1 | [18:15:45.425] INFO: node Received tx 0x1ac4331a3fd6798d18117c9690d84f650d76b9b52969d50deca0291809a9ff76 {"txHash":"0x1ac4331a3fd6798d18117c9690d84f650d76b9b52969d50deca0291809a9ff76"} 18:15:46 aztec-1 | [18:15:45.425] INFO: pxe:service Sent transaction 0x1ac4331a3fd6798d18117c9690d84f650d76b9b52969d50deca0291809a9ff76 18:15:46 aztec-1 | [18:15:45.556] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.05298598399999901s {"duration":0.05298598399999901,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152} 18:15:46 aztec-1 | [18:15:45.595] INFO: sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x0e74f30cebc2051c527d702ba7c3abf7ccc10dab1efde741498a7116756901a5","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1750098206,"version":3633326044},"txHashes":["0x1ac4331a3fd6798d18117c9690d84f650d76b9b52969d50deca0291809a9ff76"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":100.30886499999906,"publicProcessDuration":53.10801799999899,"rollupCircuitsDuration":93.41419200000018,"txCount":1,"blockNumber":6,"blockTimestamp":1750098206,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 18:15:46 aztec-1 | [18:15:45.841] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 18:15:46 aztec-1 | [18:15:45.842] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6 18:15:46 aztec-1 | [18:15:45.844] INFO: aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6. 18:15:46 aztec-1 | [18:15:45.846] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750098242 18:15:46 aztec-1 | [18:15:45.846] WARN: foundation:test-date-provider Time set to 2025-06-16T18:24:02.000Z {"offset":496154,"timeMs":1750098242000} 18:15:46 aztec-1 | [18:15:45.846] INFO: aztecjs:utils:watcher Slot 7 was filled, jumped to next slot 18:15:46 aztec-1 | [18:15:45.925] INFO: archiver Downloaded L2 block 6 {"blockHash":"0x0e74f30cebc2051c527d702ba7c3abf7ccc10dab1efde741498a7116756901a5","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1750098206,"version":3633326044},"archiveRoot":"0x0a21ccf958453061843fc436f48d71dd74fb558780f570199dc9a82109d6a557","archiveNextLeafIndex":7} 18:15:46 aztec-1 | [18:15:45.930] INFO: archiver Updated proven chain to block 6 {"provenBlockNumber":6} 18:15:46 aztec-1 | [18:15:45.954] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":15.083389999999781,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1750098206,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 18:15:46 aztec-1 | SponsoredFPC: 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 18:15:46 aztec-1 | [18:15:46.448] INFO: aztecjs:deploy_sent_tx Contract 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 successfully deployed. 18:15:46 aztec-1 | [18:15:46.453] INFO: cli Aztec Server listening on port 8080 {"l2CircuitsVkTreeRoot":"0x27f24d2a1e99e49d4a27746a8374185b307a07325d0d743c1df56a03e39788d2","l2ProtocolContractsTreeRoot":"0x222b5a95b10e0a2dfba4648739629a01cecd69a0bef23a9fc22f846735d7cc71"} 18:15:55 playground-1 | \ 18:15:55 playground-1 | Running 1 test using 1 worker 18:15:55 playground-1 | 18:15:56 playground-1 | 1 [chromium] › browser.spec.ts:3:1 › test