Parent Log: http://ci.aztec-labs.com/84a46107c517e2c5 Command: c7bc0fc28b03aa40 playground/scripts/run_test.sh firefox Commit: https://github.com/AztecProtocol/aztec-packages/commit/a45107e7f95b675cb2768b6bcb06483b511141f4 Env: REF_NAME=gh-readonly-queue/next/pr-15018-2731d9a8aebf63d05db1a786296abdf897c49490 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Thu Jun 12 17:47:49 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15018_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_23123200780498f7_next 17:47:50 Network playground-firefox_default Creating 17:47:50 Network playground-firefox_default Created 17:47:50 Container playground-firefox-ethereum-1 Creating 17:47:51 Container playground-firefox-ethereum-1 Created 17:47:51 Container playground-firefox-aztec-1 Creating 17:47:51 Container playground-firefox-aztec-1 Created 17:47:51 Container playground-firefox-playground-1 Creating 17:47:51 Container playground-firefox-playground-1 Created 17:47:51 Attaching to aztec-1, ethereum-1, playground-1 17:47:57 aztec-1 | 17:47:57 aztec-1 | _ 17:47:57 aztec-1 | /\ | | 17:47:57 aztec-1 | / \ ___| |_ ___ ___ 17:47:57 aztec-1 | / /\ \ |_ / __/ _ \/ __| 17:47:57 aztec-1 | / ____ \ / /| || __/ (__ 17:47:57 aztec-1 | /_/___ \_\/___|\__\___|\___| 17:47:57 aztec-1 | 17:47:57 aztec-1 | https://github.com/AztecProtocol 17:47:57 aztec-1 | 17:47:57 aztec-1 | 17:47:57 aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by... 17:47:57 aztec-1 | Setting up test accounts 17:47:58 aztec-1 | [17:47:57.995] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-QPD01S/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 17:47:58 aztec-1 | [17:47:58.253] INFO: sandbox Calling anvil_setBlockTimestampInterval with params: [12] 17:47:58 aztec-1 | [17:47:58.258] WARN: sandbox Set block interval to 12 17:47:58 aztec-1 | [17:47:58.446] INFO: sandbox Waiting for 7 transactions to be mined... 17:47:58 aztec-1 | [17:47:58.464] INFO: sandbox All transactions mined successfully 17:47:58 aztec-1 | [17:47:58.540] INFO: sandbox Waiting for 9 transactions to be mined... 17:47:58 aztec-1 | [17:47:58.563] INFO: sandbox All transactions mined successfully 17:47:58 aztec-1 | [17:47:58.693] INFO: sandbox Waiting for 11 transactions to be mined... 17:47:58 aztec-1 | [17:47:58.714] INFO: sandbox All transactions mined successfully 17:47:58 aztec-1 | [17:47:58.795] INFO: sandbox Waiting for 12 transactions to be mined... 17:47:58 aztec-1 | [17:47:58.820] INFO: sandbox All transactions mined successfully 17:47:58 aztec-1 | [17:47:58.826] INFO: sandbox Waiting for 12 transactions to be mined... 17:47:58 aztec-1 | [17:47:58.851] INFO: sandbox All transactions mined successfully 17:47:58 aztec-1 | [17:47:58.870] INFO: sandbox Aztec L1 contracts initialized {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x68b1d87f95878fe05b998f19b66f4baba5de1aed","inboxAddress":"0xa12ffa0b9f159bb4c54bce579611927addc51610","outboxAddress":"0x53839913417ebc7171723489f29b9b54f49b4eea","feeJuicePortalAddress":"0x67671478a98e62381bf90cdd328cffcddd26c83a","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 17:47:58 aztec-1 | [17:47:58.870] INFO: sandbox Handing over to governance 17:47:58 aztec-1 | [17:47:58.879] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x68B1D87F95878fE05B998F19b66F4baba5De1aed 17:47:58 aztec-1 | [17:47:58.879] INFO: telemetry:client Using NoopTelemetryClient 17:47:58 aztec-1 | [17:47:58.879] INFO: blob-sink:client Creating local blob sink client. 17:47:58 aztec-1 | [17:47:58.884] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 17:47:58 aztec-1 | [17:47:58.884] WARN: foundation:test-date-provider Time set to 2025-06-12T17:52:28.000Z {"offset":269116,"timeMs":1749750748000} 17:47:58 aztec-1 | [17:47:58.908] INFO: archiver:lmdb Starting data store with maxReaders 16 17:47:59 aztec-1 | [17:47:59.288] INFO: archiver Starting archiver sync to rollup contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed from L1 block 19 to current L1 block 23 with hash 0x01a10ae2028a46ffe75d67899c8d5fcc9cb82a5531e3e74eb3518183faa25f83 {"blocksSynchedTo":19,"messagesSynchedTo":{"l1BlockHash":"0x94af64b013cdc1441f5c16348c00b938500ba6136636936537153247da81f9e3","l1BlockNumber":19}} 17:47:59 aztec-1 | [17:47:59.314] INFO: archiver Initial archiver sync to L1 block 23 complete. {"l1BlockNumber":23,"syncPoint":{"blocksSynchedTo":23,"messagesSynchedTo":{"l1BlockHash":"0x01a10ae2028a46ffe75d67899c8d5fcc9cb82a5531e3e74eb3518183faa25f83","l1BlockNumber":23}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 17:47:59 aztec-1 | [17:47:59.322] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-1MjWk9/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 17:47:59 aztec-1 | [17:47:59.569] INFO: world_state Created world state synchroniser with block history of 64 17:47:59 aztec-1 | [17:47:59.569] WARN: node Aztec node is accepting fake proofs 17:47:59 aztec-1 | [17:47:59.580] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 17:47:59 aztec-1 | [17:47:59.598] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 17:47:59 aztec-1 | [17:47:59.611] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 17:47:59 aztec-1 | [17:47:59.622] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 17:47:59 aztec-1 | [17:47:59.626] INFO: world_state Started world state synchronizer from block 1 17:48:00 aztec-1 | [17:47:59.629] INFO: p2p Starting p2p client from block 0 with empty mempools 17:48:00 aztec-1 | [17:47:59.882] INFO: epoch-prune-watcher EpochPruneWatcher initialized 17:48:00 aztec-1 | [17:47:59.934] INFO: slasher Starting Slasher client... 17:48:00 aztec-1 | [17:47:59.943] INFO: sequencer-client Deploying forwarder contract 17:48:00 aztec-1 | [17:47:59.963] INFO: sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac 17:48:00 aztec-1 | [17:47:59.969] INFO: validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 17:48:00 aztec-1 | [17:47:59.969] 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} 17:48:00 aztec-1 | [17:47:59.971] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 17:48:00 aztec-1 | [17:47:59.972] INFO: node Aztec Node version: 0.87.6 17:48:00 aztec-1 | [17:47:59.972] INFO: node Aztec Node started on chain 0x7a69 {"rollupAddress":"0x68b1d87f95878fe05b998f19b66f4baba5de1aed","registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","inboxAddress":"0xa12ffa0b9f159bb4c54bce579611927addc51610","outboxAddress":"0x53839913417ebc7171723489f29b9b54f49b4eea","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","feeJuicePortalAddress":"0x67671478a98e62381bf90cdd328cffcddd26c83a","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","slashFactoryAddress":"0xc6e7df5e7b4f2a278906862b61205850344d4e7d","feeAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788","stakingAssetHandlerAddress":"0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"} 17:48:00 aztec-1 | [17:47:59.976] INFO: pxe:data:lmdb Starting data store with maxReaders 16 17:48:00 aztec-1 | [17:47:59.983] 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} 17:48:00 aztec-1 | [17:48:00.042] 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} 17:48:00 aztec-1 | [17:48:00.101] 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} 17:48:00 aztec-1 | [17:48:00.217] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 17:48:00 aztec-1 | [17:48:00.218] WARN: foundation:test-date-provider Time set to 2025-06-12T17:52:40.000Z {"offset":279783,"timeMs":1749750760000} 17:48:00 aztec-1 | Setting up funded test accounts... 17:48:00 aztec-1 | [17:48:00.353] INFO: pxe:service Started PXE connected to chain 31337 version 1767261948 17:48:00 aztec-1 | [17:48:00.439] INFO: pxe:service Registered account 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e 17:48:00 aztec-1 | [17:48:00.509] WARN: pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata 17:48:00 aztec-1 | [17:48:00.512] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e 17:48:00 aztec-1 | [17:48:00.676] INFO: pxe:service Added contract SchnorrAccount at 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 17:48:03 aztec-1 | [17:48:03.238] INFO: pxe:service Proving completed in 2522.385628ms {"timings":{"total":2522.385628,"sync":5.762003999999251,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":164.7530080000015,"oracles":{"callPrivateFunction":{"times":[784.7674539999998,330.7296299999998,232.5346840000002]}}},{"functionName":"private_kernel_init","time":89.05865199999971},{"functionName":"ContractClassRegisterer:register","time":760.5961989999987,"oracles":{"loadCapsule":{"times":[12.031799999999748]},"notifyCreatedNullifier":{"times":[20.72091200000068]},"debugLog":{"times":[0.4276820000013686]},"notifyCreatedContractClassLog":{"times":[5.541907999999239]}}},{"functionName":"private_kernel_inner","time":114.98213299999952},{"functionName":"SchnorrAccount:constructor","time":311.251991000001,"oracles":{"getContractInstance":{"times":[3.155424000000494]},"debugLog":{"times":[0.16821400000117137,0.15981399999873247]},"fetchTaggedLogs":{"times":[70.27745199999845]},"loadCapsule":{"times":[0.5191940000004251,0.2313560000002326,0.17466400000012072]},"validateEnqueuedNotesAndEvents":{"times":[5.978300000000672]},"notifyCreatedNullifier":{"times":[0.18505499999992026,0.23529600000074424]},"notifyCreatedNote":{"times":[0.34308899999996356]},"getRandomField":{"times":[0.24151600000004692,0.2230359999994107,0.05634199999985867,0.057021000000531785,0.07055200000104378,0.18550500000128523,0.06512199999997392,0.05287099999986822,0.04926100000011502,0.07734200000049896,0.043841999999131076]},"getIndexedTaggingSecretAsSender":{"times":[54.5284929999998]},"incrementAppTaggingSecretIndexAsSender":{"times":[30.16275300000052]}}},{"functionName":"private_kernel_inner","time":116.01952100000017},{"functionName":"SchnorrAccount:entrypoint","time":202.37491099999897,"oracles":{"debugLog":{"times":[0.23972599999979138,0.11125299999912386,0.3687499999996362]},"fetchTaggedLogs":{"times":[52.2407519999997]},"loadCapsule":{"times":[0.25497700000050827,0.17375499999980093,0.21293600000171864]},"validateEnqueuedNotesAndEvents":{"times":[6.778000999998767]},"getNotes":{"times":[3.9793659999995725]},"getAuthWitness":{"times":[0.37771000000066124]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.0037069999998494]}}},{"functionName":"private_kernel_inner","time":97.48372899999958},{"functionName":"private_kernel_reset","time":155.1772379999984},{"functionName":"private_kernel_tail","time":76.75182700000005}],"unaccounted":428.17441500000314}} 17:48:03 aztec-1 | [17:48:03.366] INFO: node Received tx 0x0cf7203d75c9eef954efef4adb8399cff4a0128de2880a4cfe8bfb7a7e072a1a {"txHash":"0x0cf7203d75c9eef954efef4adb8399cff4a0128de2880a4cfe8bfb7a7e072a1a"} 17:48:03 aztec-1 | [17:48:03.366] INFO: pxe:service Sent transaction 0x0cf7203d75c9eef954efef4adb8399cff4a0128de2880a4cfe8bfb7a7e072a1a 17:48:03 aztec-1 | [17:48:03.467] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.04474168300000019s {"duration":0.04474168300000019,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 17:48:03 aztec-1 | [17:48:03.500] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x26c543245703a63c68473272fc60d62f059df4b8efb8191f43e8e6307dfbec61","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749750772,"version":1767261948},"txHashes":["0x0cf7203d75c9eef954efef4adb8399cff4a0128de2880a4cfe8bfb7a7e072a1a"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":88.8419589999994,"publicProcessDuration":45.41925200000151,"rollupCircuitsDuration":80.90953799999988,"txCount":1,"blockNumber":1,"blockTimestamp":1749750772,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 17:48:06 aztec-1 | [17:48:06.281] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 17:48:06 aztec-1 | [17:48:06.284] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 17:48:06 aztec-1 | [17:48:06.285] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 17:48:06 aztec-1 | [17:48:06.364] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 17:48:06 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)" 17:48:06 aztec-1 | [17:48:06.364] WARN: sequencer Cannot propose block 1 at slot 2 due to failed rollup contract check 17:48:06 aztec-1 | [17:48:06.366] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x26c543245703a63c68473272fc60d62f059df4b8efb8191f43e8e6307dfbec61","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749750772,"version":1767261948},"archiveRoot":"0x1c185c556aacdb8b97fada85d7c506f256736790607372f44ad065e12d6f59cc","archiveNextLeafIndex":2} 17:48:06 aztec-1 | [17:48:06.374] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 17:48:06 aztec-1 | [17:48:06.434] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":18.612565999999788,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749750772,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 17:48:06 aztec-1 | [17:48:06.519] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 17:48:06 aztec-1 | [17:48:06.520] WARN: foundation:test-date-provider Time set to 2025-06-12T17:52:52.000Z {"offset":285480,"timeMs":1749750772000} 17:48:06 aztec-1 | [17:48:06.540] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 17:48:06 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)" 17:48:06 aztec-1 | [17:48:06.540] WARN: sequencer Cannot propose block 2 at slot 2 due to failed rollup contract check 17:48:06 aztec-1 | [17:48:06.543] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749750808 17:48:06 aztec-1 | [17:48:06.543] WARN: foundation:test-date-provider Time set to 2025-06-12T17:53:28.000Z {"offset":321457,"timeMs":1749750808000} 17:48:06 aztec-1 | [17:48:06.543] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 17:48:06 aztec-1 | [17:48:06.562] INFO: pxe:service Registered account 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f 17:48:06 aztec-1 | [17:48:06.676] INFO: pxe:service Added contract SchnorrAccount at 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 17:48:08 aztec-1 | [17:48:08.164] INFO: pxe:service Proving completed in 1471.7356089999994ms {"timings":{"total":1471.7356089999994,"sync":19.461229000000458,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":90.29473699999835,"oracles":{"callPrivateFunction":{"times":[340.85008000000016,230.73053299999992]}}},{"functionName":"private_kernel_init","time":69.95417500000076},{"functionName":"SchnorrAccount:constructor","time":317.5225580000024,"oracles":{"getContractInstance":{"times":[1.1645709999993414]},"debugLog":{"times":[0.11181300000134797,0.10373299999992014]},"fetchTaggedLogs":{"times":[91.71567600000162]},"loadCapsule":{"times":[0.2131859999990411,0.24874699999963923,0.18014399999992747]},"validateEnqueuedNotesAndEvents":{"times":[5.445695000000342]},"notifyCreatedNullifier":{"times":[0.3037880000010773,0.18338500000027125]},"notifyCreatedNote":{"times":[0.10844299999916984]},"getRandomField":{"times":[0.10625299999992421,0.16572400000040943,0.09809200000017881,0.08766300000024785,0.06255100000089442,0.06176199999936216,0.0502120000001014,0.05386199999884411,0.04865099999915401,0.09086199999910605,0.0639919999994163]},"getIndexedTaggingSecretAsSender":{"times":[51.776160999999775]},"incrementAppTaggingSecretIndexAsSender":{"times":[39.30948800000078]}}},{"functionName":"private_kernel_inner","time":97.13824100000056},{"functionName":"SchnorrAccount:entrypoint","time":202.65079499999956,"oracles":{"debugLog":{"times":[0.25238699999863456,0.13397399999848858,0.22455599999921105]},"fetchTaggedLogs":{"times":[77.65581100000054]},"loadCapsule":{"times":[0.2225959999996121,0.1450039999999717,0.17353499999990163]},"validateEnqueuedNotesAndEvents":{"times":[5.723982999999862]},"getNotes":{"times":[1.099858999999924]},"getAuthWitness":{"times":[0.1908050000001822]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.5406739999998535]}}},{"functionName":"private_kernel_inner","time":101.05603500000143},{"functionName":"private_kernel_reset","time":175.16375100000005},{"functionName":"private_kernel_tail","time":89.52265699999953}],"unaccounted":308.9714309999963}} 17:48:08 aztec-1 | [17:48:08.209] INFO: node Received tx 0x06ed9ffd108d2f40944f7e3d85a3534f59bc13720a83e23cc4fcc16d5ddb971c {"txHash":"0x06ed9ffd108d2f40944f7e3d85a3534f59bc13720a83e23cc4fcc16d5ddb971c"} 17:48:08 aztec-1 | [17:48:08.209] INFO: pxe:service Sent transaction 0x06ed9ffd108d2f40944f7e3d85a3534f59bc13720a83e23cc4fcc16d5ddb971c 17:48:08 aztec-1 | [17:48:08.287] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.019524570999999925s {"duration":0.019524570999999925,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 17:48:08 aztec-1 | [17:48:08.321] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x223dcaf69945d585daca89422a2f723efb52cc5839b40d4b61203fe54735ae05","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749750808,"version":1767261948},"txHashes":["0x06ed9ffd108d2f40944f7e3d85a3534f59bc13720a83e23cc4fcc16d5ddb971c"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":59.49178700000084,"publicProcessDuration":19.67918500000087,"rollupCircuitsDuration":55.15106099999866,"txCount":1,"blockNumber":2,"blockTimestamp":1749750808,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 17:48:08 aztec-1 | [17:48:08.569] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 17:48:08 aztec-1 | [17:48:08.582] WARN: archiver Failed to reach L2 block 2 at 26 (latest is 1). Rolling back last synched L1 block number to 25. {"latestLocalL2BlockNumber":1,"latestLocalL2BlockArchive":"0x1c185c556aacdb8b97fada85d7c506f256736790607372f44ad065e12d6f59cc","blocksSynchedTo":25,"currentL1BlockNumber":26,"provenBlockNumber":1,"provenArchive":"0x1c185c556aacdb8b97fada85d7c506f256736790607372f44ad065e12d6f59cc","pendingBlockNumber":2,"pendingArchive":"0x01bb0ebb453b6bea6cb5f8b37be25645cb46f5b8da9dec256b61ff397a597cda"} 17:48:08 aztec-1 | [17:48:08.594] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 17:48:08 aztec-1 | [17:48:08.594] WARN: foundation:test-date-provider Time set to 2025-06-12T17:53:40.000Z {"offset":331406,"timeMs":1749750820000} 17:48:08 aztec-1 | [17:48:08.596] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 17:48:08 aztec-1 | [17:48:08.597] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 17:48:08 aztec-1 | [17:48:08.629] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749750844 17:48:08 aztec-1 | [17:48:08.629] WARN: foundation:test-date-provider Time set to 2025-06-12T17:54:04.000Z {"offset":355371,"timeMs":1749750844000} 17:48:08 aztec-1 | [17:48:08.629] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 17:48:08 aztec-1 | [17:48:08.634] WARN: sequencer:publisher Failed canProposeAtTime check with InvalidArchive 17:48:08 aztec-1 | error: "Rollup__InvalidArchive(0x01bb0ebb453b6bea6cb5f8b37be25645cb46f5b8da9dec256b61ff397a597cda, 0x1c185c556aacdb8b97fada85d7c506f256736790607372f44ad065e12d6f59cc)" 17:48:08 aztec-1 | [17:48:08.634] WARN: sequencer Cannot propose block 2 at slot 3 due to failed rollup contract check 17:48:08 aztec-1 | [17:48:08.676] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x223dcaf69945d585daca89422a2f723efb52cc5839b40d4b61203fe54735ae05","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749750808,"version":1767261948},"archiveRoot":"0x01bb0ebb453b6bea6cb5f8b37be25645cb46f5b8da9dec256b61ff397a597cda","archiveNextLeafIndex":3} 17:48:08 aztec-1 | [17:48:08.679] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 17:48:08 aztec-1 | [17:48:08.722] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":14.177797999998802,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749750808,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 17:48:08 aztec-1 | [17:48:08.841] INFO: pxe:service Registered account 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 17:48:08 aztec-1 | [17:48:08.967] INFO: pxe:service Added contract SchnorrAccount at 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 17:48:10 aztec-1 | Initial Accounts: 17:48:10 aztec-1 | 17:48:10 aztec-1 | Address: 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e 17:48:10 aztec-1 | Partial Address: 0x27b4888363d0f9eb0d43aa0b2ff08f21fbc6867b8e0baf8ec6062c4623c1eefc 17:48:10 aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281 17:48:10 aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f 17:48:10 aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f 17:48:10 aztec-1 | 17:48:10 aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5 17:48:10 aztec-1 | 17:48:10 aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89 17:48:10 aztec-1 | 17:48:10 aztec-1 | Address: 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f 17:48:10 aztec-1 | Partial Address: 0x2e19b56de1617cb3179d81be0173c7301621a2e820da7f2754fcfccdfc4418e0 17:48:10 aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae 17:48:10 aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57 17:48:10 aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d 17:48:10 aztec-1 | 17:48:10 aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e 17:48:10 aztec-1 | 17:48:10 aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d 17:48:10 aztec-1 | 17:48:10 aztec-1 | Address: 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 17:48:10 aztec-1 | Partial Address: 0x2451b67547512eaaaad5ded43156c5b80220b7d5145ebefdd57295a9e05a610d 17:48:10 aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c 17:48:10 aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3 17:48:10 aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae 17:48:10 aztec-1 | 17:48:10 aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da 17:48:10 aztec-1 | 17:48:10 aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba 17:48:10 aztec-1 | 17:48:10 aztec-1 | 17:48:11 aztec-1 | [17:48:10.360] INFO: pxe:service Proving completed in 1382.4654679999985ms {"timings":{"total":1382.4654679999985,"sync":27.320299000000887,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":97.91994299999533,"oracles":{"callPrivateFunction":{"times":[364.0033169999988,286.3282760000002]}}},{"functionName":"private_kernel_init","time":50.63507000000027},{"functionName":"SchnorrAccount:constructor","time":337.3314559999999,"oracles":{"getContractInstance":{"times":[1.512960000000021]},"debugLog":{"times":[0.14959400000043388,0.14200399999936053]},"fetchTaggedLogs":{"times":[116.4997370000001]},"loadCapsule":{"times":[0.23039599999901839,0.16973399999915273,0.17118499999924097]},"validateEnqueuedNotesAndEvents":{"times":[6.811741999999867]},"notifyCreatedNullifier":{"times":[0.38213100000029954,0.1695739999995567]},"notifyCreatedNote":{"times":[0.17354399999931047]},"getRandomField":{"times":[0.19952499999999418,0.1793150000012247,0.043570999998337356,0.044550999999046326,0.03868099999999686,0.0394109999997454,0.03903100000025006,0.09115200000087498,0.04484099999899627,0.04077099999994971,0.04283200000099896]},"getIndexedTaggingSecretAsSender":{"times":[49.53870099999949]},"incrementAppTaggingSecretIndexAsSender":{"times":[24.631327000002784]}}},{"functionName":"private_kernel_inner","time":90.00214000000051},{"functionName":"SchnorrAccount:entrypoint","time":257.4772460000022,"oracles":{"debugLog":{"times":[0.19126500000129454,0.08589199999914854,0.21507600000040838]},"fetchTaggedLogs":{"times":[114.09314300000187]},"loadCapsule":{"times":[0.21994599999743514,0.15446399999927962,0.22927699999854667]},"validateEnqueuedNotesAndEvents":{"times":[6.359510000002047]},"getNotes":{"times":[1.625092999998742]},"getAuthWitness":{"times":[0.2437370000006922]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.5219440000000759]}}},{"functionName":"private_kernel_inner","time":89.55036800000016},{"functionName":"private_kernel_reset","time":143.5638390000022},{"functionName":"private_kernel_tail","time":59.88847700000042}],"unaccounted":228.77662999999666}} 17:48:11 aztec-1 | [17:48:10.413] INFO: node Received tx 0x1152529fb07021d0deddc72faa63c7bc0671a7e03c5ee993305dd09b109f815a {"txHash":"0x1152529fb07021d0deddc72faa63c7bc0671a7e03c5ee993305dd09b109f815a"} 17:48:11 aztec-1 | [17:48:10.413] INFO: pxe:service Sent transaction 0x1152529fb07021d0deddc72faa63c7bc0671a7e03c5ee993305dd09b109f815a 17:48:11 aztec-1 | [17:48:10.480] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.014944418999999471s {"duration":0.014944418999999471,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 17:48:11 aztec-1 | [17:48:10.497] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x0432d5edb362dc26bd67e544118c1605601705112a556a1c3ee94faa98b71b26","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749750844,"version":1767261948},"txHashes":["0x1152529fb07021d0deddc72faa63c7bc0671a7e03c5ee993305dd09b109f815a"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":39.304378000000725,"publicProcessDuration":15.035760999999184,"rollupCircuitsDuration":34.20448199999737,"txCount":1,"blockNumber":3,"blockTimestamp":1749750844,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 17:48:11 aztec-1 | [17:48:10.679] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 17:48:11 aztec-1 | [17:48:10.686] WARN: archiver Failed to reach L2 block 3 at 28 (latest is 2). Rolling back last synched L1 block number to 27. {"latestLocalL2BlockNumber":2,"latestLocalL2BlockArchive":"0x01bb0ebb453b6bea6cb5f8b37be25645cb46f5b8da9dec256b61ff397a597cda","blocksSynchedTo":27,"currentL1BlockNumber":28,"provenBlockNumber":2,"provenArchive":"0x01bb0ebb453b6bea6cb5f8b37be25645cb46f5b8da9dec256b61ff397a597cda","pendingBlockNumber":3,"pendingArchive":"0x0fe54c1e9684c0a95a16b161a48db43678fa967afbf8d91d7717c663a9a74d95"} 17:48:11 aztec-1 | [17:48:10.744] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 17:48:11 aztec-1 | error: "Rollup__SlotAlreadyInChain(4, 4)" 17:48:11 aztec-1 | [17:48:10.744] WARN: sequencer Cannot propose block 3 at slot 4 due to failed rollup contract check 17:48:11 aztec-1 | [17:48:10.782] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x0432d5edb362dc26bd67e544118c1605601705112a556a1c3ee94faa98b71b26","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749750844,"version":1767261948},"archiveRoot":"0x0fe54c1e9684c0a95a16b161a48db43678fa967afbf8d91d7717c663a9a74d95","archiveNextLeafIndex":4} 17:48:11 aztec-1 | [17:48:10.846] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":16.30043499999738,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749750844,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 17:48:11 aztec-1 | [17:48:10.860] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 17:48:11 aztec-1 | [17:48:10.860] WARN: foundation:test-date-provider Time set to 2025-06-12T17:54:16.000Z {"offset":365140,"timeMs":1749750856000} 17:48:11 aztec-1 | [17:48:10.863] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 17:48:11 aztec-1 | [17:48:10.865] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 17:48:12 aztec-1 | [17:48:11.738] WARN: pxe:service No artifact found for contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 when looking for its metadata 17:48:12 aztec-1 | [17:48:11.738] WARN: pxe:service No artifact found for contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 when looking for its metadata 17:48:12 aztec-1 | [17:48:11.741] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 17:48:12 aztec-1 | [17:48:11.741] WARN: foundation:test-date-provider Time set to 2025-06-12T17:54:40.000Z {"offset":388259,"timeMs":1749750880000} 17:48:12 aztec-1 | [17:48:11.741] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749750880 17:48:12 aztec-1 | [17:48:11.741] WARN: foundation:test-date-provider Time set to 2025-06-12T17:54:40.000Z {"offset":388259,"timeMs":1749750880000} 17:48:12 aztec-1 | [17:48:11.741] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 17:48:12 aztec-1 | [17:48:11.741] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 as part of deployment for 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 17:48:12 aztec-1 | [17:48:11.774] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 as part of deployment for 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf 17:48:12 aztec-1 | [17:48:12.201] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 17:48:12 aztec-1 | [17:48:12.548] INFO: pxe:service Added contract FPC at 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 with class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 17:48:12 aztec-1 | [17:48:12.552] INFO: pxe:service Added contract Token at 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf with class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 17:48:15 aztec-1 | [17:48:14.948] INFO: pxe:service Proving completed in 2357.264073999999ms {"timings":{"total":2357.264073999999,"sync":35.188257999998314,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":375.26833899999474,"oracles":{"debugLog":{"times":[0.24899699999878067,0.062402000003203284,0.13528299999961746,0.20920599999953993,0.14310399999885703,0.12163299999883748,0.1403040000004694,0.18825500000093598]},"fetchTaggedLogs":{"times":[131.0761249999996]},"loadCapsule":{"times":[0.18532499999855645,0.14877400000113994,0.20637599999827216,0.21201600000131293,0.20588499999939813,0.21277499999996508,0.5399639999995998]},"getSharedSecret":{"times":[4.0855589999991935]},"aes128Decrypt":{"times":[1.1061590000026627,0.7924610000009125]},"getPublicKeysAndPartialAddress":{"times":[3.468141999997897]},"getKeyValidationRequest":{"times":[2.0523140000004787]},"storeCapsule":{"times":[2.9507490000032703,4.189472000001842,2.50366700000086]},"deleteCapsule":{"times":[2.690371000000596]},"validateEnqueuedNotesAndEvents":{"times":[22.85928899999999]},"getNotes":{"times":[9.652717000000848]},"getAuthWitness":{"times":[0.18485599999985425]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06523200000083307]},"callPrivateFunction":{"times":[813.0041820000006,165.7992320000012]},"notifyEnqueuedPublicFunctionCall":{"times":[0.3014179999991029]}}},{"functionName":"private_kernel_init","time":60.69308799999999},{"functionName":"ContractClassRegisterer:register","time":794.0735659999991,"oracles":{"loadCapsule":{"times":[3.8484220000027562]},"notifyCreatedNullifier":{"times":[0.48894300000029034]},"debugLog":{"times":[0.16819499999837717]},"notifyCreatedContractClassLog":{"times":[10.279733999999735]}}},{"functionName":"private_kernel_inner","time":92.76238399999784},{"functionName":"ContractInstanceDeployer:deploy","time":96.86295400000381,"oracles":{"storeInExecutionCache":{"times":[0.2985670000016398]},"callPrivateFunction":{"times":[60.20866499999829]},"notifyCreatedNullifier":{"times":[0.257727000000159]},"debugLog":{"times":[0.1367740000023332]}}},{"functionName":"private_kernel_inner","time":95.4400650000025},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":47.067534999998315,"oracles":{}},{"functionName":"private_kernel_inner","time":89.62296100000094},{"functionName":"private_kernel_reset","time":155.90696799999932},{"functionName":"private_kernel_tail","time":96.70073899999988}],"unaccounted":417.677217000004}} 17:48:15 aztec-1 | [17:48:15.098] INFO: node Received tx 0x1e84b3e128f43316f9cc6465508796c47c17f2eaa7f0cd50b691013f92c90126 {"txHash":"0x1e84b3e128f43316f9cc6465508796c47c17f2eaa7f0cd50b691013f92c90126"} 17:48:15 aztec-1 | [17:48:15.098] INFO: pxe:service Sent transaction 0x1e84b3e128f43316f9cc6465508796c47c17f2eaa7f0cd50b691013f92c90126 17:48:16 aztec-1 | [17:48:16.356] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 1.0814069689999997s {"duration":1.0814069689999997,"rate":38157.697502317475,"totalPublicGas":{"daGas":4096,"l2Gas":41264},"totalBlockGas":{"daGas":166912,"l2Gas":66306},"totalSizeInBytes":10688} 17:48:16 aztec-1 | [17:48:16.461] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 34308.10182823218 mana/s {"blockHash":"0x0064e7aa24d2b00e6e747de37114f632fe93af6f8f801e972faa801bd77420f3","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749750880,"version":1767261948},"txHashes":["0x1e84b3e128f43316f9cc6465508796c47c17f2eaa7f0cd50b691013f92c90126"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":1202.6742630000008,"publicProcessDuration":1081.5544630000004,"rollupCircuitsDuration":1191.2486180000014,"txCount":1,"blockNumber":4,"blockTimestamp":1749750880,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296} 17:48:17 aztec-1 | [17:48:17.037] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 17:48:17 aztec-1 | [17:48:17.157] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749750916 17:48:17 aztec-1 | [17:48:17.157] WARN: foundation:test-date-provider Time set to 2025-06-12T17:55:16.000Z {"offset":418843,"timeMs":1749750916000} 17:48:17 aztec-1 | [17:48:17.157] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 17:48:17 aztec-1 | [17:48:17.427] INFO: pxe:service Proving completed in 2478.090716999999ms {"timings":{"total":2478.090716999999,"sync":129.3697600000014,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":434.66260100000363,"oracles":{"debugLog":{"times":[0.19171500000084052,0.09518300000127056,0.1318439999995462,0.1916349999992235,0.16105400000014924,0.10147299999880488,0.0600510000003851,0.18985500000053435]},"fetchTaggedLogs":{"times":[173.80525500000294]},"loadCapsule":{"times":[0.2785679999979038,0.3972199999989243,1.1141000000025088,1.506280000001425,0.4840729999996256,0.2842080000009446,0.1407039999976405]},"getSharedSecret":{"times":[9.652727999997296]},"aes128Decrypt":{"times":[0.19436599999971804,0.7281890000012936]},"getPublicKeysAndPartialAddress":{"times":[6.067472000002454]},"getKeyValidationRequest":{"times":[5.7036819999993895]},"storeCapsule":{"times":[2.578918999999587,23.317591999999422,2.2364699999998265]},"deleteCapsule":{"times":[2.280101000000286]},"validateEnqueuedNotesAndEvents":{"times":[10.062848000001395]},"getNotes":{"times":[19.229143000000477]},"getAuthWitness":{"times":[0.20973599999706494]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07627200000206358]},"callPrivateFunction":{"times":[634.5605429999996,123.09709199999998]},"notifyEnqueuedPublicFunctionCall":{"times":[0.09465300000010757]}}},{"functionName":"private_kernel_init","time":52.419268000001466},{"functionName":"ContractClassRegisterer:register","time":623.0684969999966,"oracles":{"loadCapsule":{"times":[1.8383790000007139]},"notifyCreatedNullifier":{"times":[0.5179140000000189]},"debugLog":{"times":[0.16083399999843095]},"notifyCreatedContractClassLog":{"times":[3.3435590000008233]}}},{"functionName":"private_kernel_inner","time":100.09837899999911},{"functionName":"ContractInstanceDeployer:deploy","time":71.56462799999645,"oracles":{"storeInExecutionCache":{"times":[0.06649100000140606]},"callPrivateFunction":{"times":[56.178147999999055]},"notifyCreatedNullifier":{"times":[0.2813179999975546]},"debugLog":{"times":[0.15175399999861838]}}},{"functionName":"private_kernel_inner","time":111.39953100000275},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":40.27411399999983,"oracles":{}},{"functionName":"private_kernel_inner","time":113.25948100000096},{"functionName":"private_kernel_reset","time":177.08019200000126},{"functionName":"private_kernel_tail","time":93.05048100000204}],"unaccounted":531.8437849999937}} 17:48:17 aztec-1 | [17:48:17.461] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 17:48:17 aztec-1 | [17:48:17.465] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 17:48:17 aztec-1 | [17:48:17.597] INFO: node Received tx 0x0908ac95f17d73a9e29181f4018d0cf1f9de8313a97e33b6966181803527814b {"txHash":"0x0908ac95f17d73a9e29181f4018d0cf1f9de8313a97e33b6966181803527814b"} 17:48:17 aztec-1 | [17:48:17.597] INFO: pxe:service Sent transaction 0x0908ac95f17d73a9e29181f4018d0cf1f9de8313a97e33b6966181803527814b 17:48:17 aztec-1 | [17:48:17.660] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x0064e7aa24d2b00e6e747de37114f632fe93af6f8f801e972faa801bd77420f3","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749750880,"version":1767261948},"archiveRoot":"0x00673f9895d9424c752781f5b689230f83e25b0e9aa7c6e179994538b0a4c9cb","archiveNextLeafIndex":5} 17:48:17 aztec-1 | [17:48:17.667] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 17:48:17 aztec-1 | [17:48:17.695] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":14.0963359999987,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749750880,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296} 17:48:18 aztec-1 | [17:48:18.158] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.3665541650000014s {"duration":0.3665541650000014,"rate":904229.2562683028,"totalPublicGas":{"daGas":10240,"l2Gas":331449},"totalBlockGas":{"daGas":380928,"l2Gas":356491},"totalSizeInBytes":24064} 17:48:18 aztec-1 | [17:48:18.205] INFO: sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 786529.6585531647 mana/s {"blockHash":"0x15d4af8f6023dcee9a8ee3b09e59d159a330cda6babef5f1f816f790220344a2","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749750916,"version":1767261948},"txHashes":["0x0908ac95f17d73a9e29181f4018d0cf1f9de8313a97e33b6966181803527814b"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":421.38093800000206,"publicProcessDuration":366.9074139999975,"rollupCircuitsDuration":415.1091409999972,"txCount":1,"blockNumber":5,"blockTimestamp":1749750916,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 17:48:18 aztec-1 | [17:48:18.430] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 17:48:18 aztec-1 | [17:48:18.433] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749750952 17:48:18 aztec-1 | [17:48:18.433] WARN: foundation:test-date-provider Time set to 2025-06-12T17:55:52.000Z {"offset":453567,"timeMs":1749750952000} 17:48:18 aztec-1 | [17:48:18.433] INFO: aztecjs:utils:watcher Slot 6 was filled, jumped to next slot 17:48:18 aztec-1 | [17:48:18.497] INFO: aztecjs:deploy_sent_tx Contract 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 successfully deployed. 17:48:18 aztec-1 | [17:48:18.514] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5 17:48:18 aztec-1 | [17:48:18.517] INFO: aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5. 17:48:18 aztec-1 | [17:48:18.647] INFO: archiver Downloaded L2 block 5 {"blockHash":"0x15d4af8f6023dcee9a8ee3b09e59d159a330cda6babef5f1f816f790220344a2","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749750916,"version":1767261948},"archiveRoot":"0x13756166bc6f53a529bcd1bcd50fe7793d957a11532b244ba62197c6bba62b91","archiveNextLeafIndex":6} 17:48:18 aztec-1 | [17:48:18.655] INFO: archiver Updated proven chain to block 5 {"provenBlockNumber":5} 17:48:18 aztec-1 | [17:48:18.712] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":14.34863299999779,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749750916,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 17:48:19 aztec-1 | BananaCoin: 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf 17:48:19 aztec-1 | FPC: 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 17:48:20 aztec-1 | [17:48:19.902] INFO: aztecjs:deploy_sent_tx Contract 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf successfully deployed. 17:48:20 aztec-1 | [17:48:19.928] WARN: pxe:service No artifact found for contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e when looking for its metadata 17:48:20 aztec-1 | [17:48:19.930] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e as part of deployment for 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 17:48:20 aztec-1 | [17:48:20.040] INFO: pxe:service Added contract SponsoredFPC at 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 with class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e 17:48:22 aztec-1 | [17:48:22.463] INFO: pxe:service Proving completed in 2415.002192ms {"timings":{"total":2415.002192,"sync":27.97898499999792,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":69.42247399999178,"oracles":{"callPrivateFunction":{"times":[783.9885409999988,143.23625899999752,54.368774000002304]}}},{"functionName":"private_kernel_init","time":71.07768900000156},{"functionName":"ContractClassRegisterer:register","time":772.867634000002,"oracles":{"loadCapsule":{"times":[5.121595000000525]},"notifyCreatedNullifier":{"times":[0.4070609999980661]},"debugLog":{"times":[0.17286500000045635]},"notifyCreatedContractClassLog":{"times":[5.210897999997542]}}},{"functionName":"private_kernel_inner","time":141.83762900000147},{"functionName":"ContractInstanceDeployer:deploy","time":90.47642800000176,"oracles":{"storeInExecutionCache":{"times":[0.08670300000085263]},"callPrivateFunction":{"times":[51.33169800000178]},"notifyCreatedNullifier":{"times":[0.27898799999820767]},"debugLog":{"times":[0.17113399999652756]}}},{"functionName":"private_kernel_inner","time":141.6780949999993},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":35.42129699999714,"oracles":{}},{"functionName":"private_kernel_inner","time":143.28125},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":43.65039000000252,"oracles":{"debugLog":{"times":[0.1228530000007595]}}},{"functionName":"private_kernel_inner","time":141.73462599999766},{"functionName":"private_kernel_reset","time":219.583208},{"functionName":"private_kernel_tail","time":100.88759600000049}],"unaccounted":415.1048910000063}} 17:48:22 aztec-1 | [17:48:22.601] INFO: node Received tx 0x1ea698c1c20992717d1080ad9d3f472bb26782dc4a3818766d890c730709b52c {"txHash":"0x1ea698c1c20992717d1080ad9d3f472bb26782dc4a3818766d890c730709b52c"} 17:48:22 aztec-1 | [17:48:22.601] INFO: pxe:service Sent transaction 0x1ea698c1c20992717d1080ad9d3f472bb26782dc4a3818766d890c730709b52c 17:48:22 aztec-1 | [17:48:22.715] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.040102419000002554s {"duration":0.040102419000002554,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152} 17:48:22 aztec-1 | [17:48:22.745] INFO: sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x04e98cc3ce474c0d724c2b5d02e72f10a6fb62fe275875dac1a6bcbe82fd255c","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749750952,"version":1767261948},"txHashes":["0x1ea698c1c20992717d1080ad9d3f472bb26782dc4a3818766d890c730709b52c"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":75.6571380000023,"publicProcessDuration":40.31855500000165,"rollupCircuitsDuration":71.19955299999856,"txCount":1,"blockNumber":6,"blockTimestamp":1749750952,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 17:48:23 aztec-1 | [17:48:22.994] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 17:48:23 aztec-1 | [17:48:23.001] WARN: archiver Failed to reach L2 block 6 at 34 (latest is 5). Rolling back last synched L1 block number to 33. {"latestLocalL2BlockNumber":5,"latestLocalL2BlockArchive":"0x13756166bc6f53a529bcd1bcd50fe7793d957a11532b244ba62197c6bba62b91","blocksSynchedTo":33,"currentL1BlockNumber":34,"provenBlockNumber":5,"provenArchive":"0x13756166bc6f53a529bcd1bcd50fe7793d957a11532b244ba62197c6bba62b91","pendingBlockNumber":6,"pendingArchive":"0x1098ec096fc0584d69e387930faf390e493d4289a07419245c48c062c3c27e62"} 17:48:23 aztec-1 | [17:48:23.021] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749750988 17:48:23 aztec-1 | [17:48:23.021] WARN: foundation:test-date-provider Time set to 2025-06-12T17:56:28.000Z {"offset":484979,"timeMs":1749750988000} 17:48:23 aztec-1 | [17:48:23.021] INFO: aztecjs:utils:watcher Slot 7 was filled, jumped to next slot 17:48:23 aztec-1 | [17:48:23.108] INFO: archiver Downloaded L2 block 6 {"blockHash":"0x04e98cc3ce474c0d724c2b5d02e72f10a6fb62fe275875dac1a6bcbe82fd255c","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749750952,"version":1767261948},"archiveRoot":"0x1098ec096fc0584d69e387930faf390e493d4289a07419245c48c062c3c27e62","archiveNextLeafIndex":7} 17:48:23 aztec-1 | [17:48:23.158] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":13.738840000001801,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749750952,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 17:48:23 aztec-1 | [17:48:23.185] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6 17:48:23 aztec-1 | [17:48:23.186] INFO: aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6. 17:48:23 aztec-1 | [17:48:23.241] INFO: archiver Updated proven chain to block 6 {"provenBlockNumber":6} 17:48:23 aztec-1 | SponsoredFPC: 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 17:48:23 aztec-1 | [17:48:23.615] INFO: aztecjs:deploy_sent_tx Contract 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 successfully deployed. 17:48:23 aztec-1 | [17:48:23.620] INFO: cli Aztec Server listening on port 8080 {"l2CircuitsVkTreeRoot":"0x2d2655c513c2e8feb8c7411b7e807c66287b57bb60572ce85ce1fe0a1e0c925d","l2ProtocolContractsTreeRoot":"0x222b5a95b10e0a2dfba4648739629a01cecd69a0bef23a9fc22f846735d7cc71"} 17:48:33 playground-1 | \ 17:48:33 playground-1 | Running 1 test using 1 worker 17:48:33 playground-1 | 17:48:34 playground-1 | ✓ 1 [firefox] › browser.spec.ts:3:1 › test (13.5s) 17:48:50 playground-1 | 1 passed (19.0s) 17:48:50 playground-1 | \ 17:48:50 playground-1 exited with code 0 17:48:50 Aborting on container exit... 17:48:50 Container playground-firefox-playground-1 Stopping 17:48:50 Container playground-firefox-playground-1 Stopped 17:48:50 Container playground-firefox-aztec-1 Stopping 17:48:51 Container playground-firefox-aztec-1 Stopped 17:48:52 Container playground-firefox-ethereum-1 Stopping 17:48:52 Container playground-firefox-ethereum-1 Stopped