Parent Log: http://ci.aztec-labs.com/c92727f3159b17a7 Command: 5c5470950fd6a230 playground/scripts/run_test.sh chromium Commit: https://github.com/AztecProtocol/aztec-packages/commit/b608f9359219731d3c03407689a4fadaaa5a0d45 Env: REF_NAME=gh-readonly-queue/next/pr-15006-082468f0c1e7e5424b3654b5be0f3560eed7e487 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Thu Jun 12 13:51:41 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15006_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_3af8716c4dfeb01b_next 13:51:42 Network playground-chromium_default Creating 13:51:42 Network playground-chromium_default Created 13:51:42 Container playground-chromium-ethereum-1 Creating 13:51:42 Container playground-chromium-ethereum-1 Created 13:51:42 Container playground-chromium-aztec-1 Creating 13:51:42 Container playground-chromium-aztec-1 Created 13:51:42 Container playground-chromium-playground-1 Creating 13:51:42 Container playground-chromium-playground-1 Created 13:51:42 Attaching to aztec-1, ethereum-1, playground-1 13:51:47 aztec-1 | 13:51:47 aztec-1 | _ 13:51:47 aztec-1 | /\ | | 13:51:47 aztec-1 | / \ ___| |_ ___ ___ 13:51:47 aztec-1 | / /\ \ |_ / __/ _ \/ __| 13:51:47 aztec-1 | / ____ \ / /| || __/ (__ 13:51:47 aztec-1 | /_/___ \_\/___|\__\___|\___| 13:51:47 aztec-1 | 13:51:47 aztec-1 | https://github.com/AztecProtocol 13:51:47 aztec-1 | 13:51:47 aztec-1 | 13:51:47 aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by... 13:51:47 aztec-1 | Setting up test accounts 13:51:48 aztec-1 | [13:51:48.163] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-XD6g8L/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 13:51:48 aztec-1 | [13:51:48.459] INFO: sandbox Calling anvil_setBlockTimestampInterval with params: [12] 13:51:48 aztec-1 | [13:51:48.466] WARN: sandbox Set block interval to 12 13:51:48 aztec-1 | [13:51:48.693] INFO: sandbox Waiting for 7 transactions to be mined... 13:51:48 aztec-1 | [13:51:48.716] INFO: sandbox All transactions mined successfully 13:51:48 aztec-1 | [13:51:48.812] INFO: sandbox Waiting for 9 transactions to be mined... 13:51:48 aztec-1 | [13:51:48.837] INFO: sandbox All transactions mined successfully 13:51:49 aztec-1 | [13:51:48.982] INFO: sandbox Waiting for 11 transactions to be mined... 13:51:49 aztec-1 | [13:51:49.002] INFO: sandbox All transactions mined successfully 13:51:49 aztec-1 | [13:51:49.083] INFO: sandbox Waiting for 12 transactions to be mined... 13:51:49 aztec-1 | [13:51:49.104] INFO: sandbox All transactions mined successfully 13:51:49 aztec-1 | [13:51:49.111] INFO: sandbox Waiting for 12 transactions to be mined... 13:51:49 aztec-1 | [13:51:49.143] INFO: sandbox All transactions mined successfully 13:51:49 aztec-1 | [13:51:49.160] 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"} 13:51:49 aztec-1 | [13:51:49.160] INFO: sandbox Handing over to governance 13:51:49 aztec-1 | [13:51:49.170] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x68B1D87F95878fE05B998F19b66F4baba5De1aed 13:51:49 aztec-1 | [13:51:49.171] INFO: telemetry:client Using NoopTelemetryClient 13:51:49 aztec-1 | [13:51:49.171] INFO: blob-sink:client Creating local blob sink client. 13:51:49 aztec-1 | [13:51:49.179] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 13:51:49 aztec-1 | [13:51:49.179] WARN: foundation:test-date-provider Time set to 2025-06-12T13:56:19.000Z {"offset":269821,"timeMs":1749736579000} 13:51:49 aztec-1 | [13:51:49.203] INFO: archiver:lmdb Starting data store with maxReaders 16 13:51:50 aztec-1 | [13:51:49.790] INFO: archiver Starting archiver sync to rollup contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed from L1 block 19 to current L1 block 23 with hash 0x54eaed5d042434c9cb910d96d8edf8529e0fad067de0b2bad0ea8e9996f4223f {"blocksSynchedTo":19,"messagesSynchedTo":{"l1BlockHash":"0x1e2872116da0b15bbdae972b82f56287cb8875b4bc9218d3ecdb26dcf8356d5a","l1BlockNumber":19}} 13:51:50 aztec-1 | [13:51:49.822] INFO: archiver Initial archiver sync to L1 block 23 complete. {"l1BlockNumber":23,"syncPoint":{"blocksSynchedTo":23,"messagesSynchedTo":{"l1BlockHash":"0x54eaed5d042434c9cb910d96d8edf8529e0fad067de0b2bad0ea8e9996f4223f","l1BlockNumber":23}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 13:51:50 aztec-1 | [13:51:49.831] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-r9RaLM/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads. 13:51:50 aztec-1 | [13:51:50.232] INFO: world_state Created world state synchroniser with block history of 64 13:51:50 aztec-1 | [13:51:50.232] WARN: node Aztec node is accepting fake proofs 13:51:50 aztec-1 | [13:51:50.247] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 13:51:50 aztec-1 | [13:51:50.263] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 13:51:50 aztec-1 | [13:51:50.276] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 13:51:50 aztec-1 | [13:51:50.288] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 13:51:50 aztec-1 | [13:51:50.294] INFO: world_state Started world state synchronizer from block 1 13:51:50 aztec-1 | [13:51:50.298] INFO: p2p Starting p2p client from block 0 with empty mempools 13:51:50 aztec-1 | [13:51:50.546] INFO: epoch-prune-watcher EpochPruneWatcher initialized 13:51:50 aztec-1 | [13:51:50.595] INFO: slasher Starting Slasher client... 13:51:50 aztec-1 | [13:51:50.603] INFO: sequencer-client Deploying forwarder contract 13:51:50 aztec-1 | [13:51:50.622] INFO: sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac 13:51:50 aztec-1 | [13:51:50.629] INFO: validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 13:51:50 aztec-1 | [13:51:50.630] 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} 13:51:50 aztec-1 | [13:51:50.631] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 13:51:50 aztec-1 | [13:51:50.632] INFO: node Aztec Node version: 0.87.6 13:51:50 aztec-1 | [13:51:50.632] 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"} 13:51:50 aztec-1 | [13:51:50.636] INFO: pxe:data:lmdb Starting data store with maxReaders 16 13:51:50 aztec-1 | [13:51:50.643] 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} 13:51:50 aztec-1 | [13:51:50.701] 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} 13:51:50 aztec-1 | [13:51:50.767] 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} 13:51:50 aztec-1 | [13:51:50.882] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 13:51:50 aztec-1 | [13:51:50.882] WARN: foundation:test-date-provider Time set to 2025-06-12T13:56:31.000Z {"offset":280118,"timeMs":1749736591000} 13:51:51 aztec-1 | Setting up funded test accounts... 13:51:51 aztec-1 | [13:51:51.038] INFO: pxe:service Started PXE connected to chain 31337 version 3573608979 13:51:51 aztec-1 | [13:51:51.136] INFO: pxe:service Registered account 0x2098b7a51b5545e6dcbf3d1f703b1fa961aee3fd4afa74cfc5fcdaaf9473ddef 13:51:51 aztec-1 | [13:51:51.203] WARN: pxe:service No artifact found for contract class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f when looking for its metadata 13:51:51 aztec-1 | [13:51:51.206] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f as part of deployment for 0x2098b7a51b5545e6dcbf3d1f703b1fa961aee3fd4afa74cfc5fcdaaf9473ddef 13:51:51 aztec-1 | [13:51:51.360] INFO: pxe:service Added contract SchnorrAccount at 0x2098b7a51b5545e6dcbf3d1f703b1fa961aee3fd4afa74cfc5fcdaaf9473ddef with class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f 13:51:54 aztec-1 | [13:51:53.867] INFO: pxe:service Proving completed in 2467.5947210000004ms {"timings":{"total":2467.5947210000004,"sync":5.625850999999784,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":149.5811130000002,"oracles":{"callPrivateFunction":{"times":[691.1207729999996,352.9578529999999,217.95149800000036]}}},{"functionName":"private_kernel_init","time":90.70977099999982},{"functionName":"ContractClassRegisterer:register","time":674.6621370000003,"oracles":{"loadCapsule":{"times":[12.370429000000513]},"notifyCreatedNullifier":{"times":[18.428289999999834]},"debugLog":{"times":[0.6939870000005612]},"notifyCreatedContractClassLog":{"times":[4.545489000000089]}}},{"functionName":"private_kernel_inner","time":112.8577920000007},{"functionName":"SchnorrAccount:constructor","time":328.65574600000036,"oracles":{"getContractInstance":{"times":[1.8377350000009756]},"debugLog":{"times":[0.1916430000001128,0.08053499999914493]},"fetchTaggedLogs":{"times":[64.92515300000014]},"loadCapsule":{"times":[0.38753599999836297,0.941344000000754,0.8079850000012812]},"validateEnqueuedNotesAndEvents":{"times":[6.816262000000279]},"notifyCreatedNullifier":{"times":[0.2773190000007162,0.3167409999987285]},"notifyCreatedNote":{"times":[0.6472140000005311]},"getRandomField":{"times":[0.38696600000002945,0.4259390000006533,0.07488499999999476,0.20983399999931862,0.07640499999979511,0.062484000000040396,0.06927399999949557,0.0512830000006943,0.1640319999987696,0.11018800000056217,0.09537599999930535]},"getIndexedTaggingSecretAsSender":{"times":[55.71436800000083]},"incrementAppTaggingSecretIndexAsSender":{"times":[26.115970999999263]}}},{"functionName":"private_kernel_inner","time":103.94979799999965},{"functionName":"SchnorrAccount:entrypoint","time":197.00976900000023,"oracles":{"debugLog":{"times":[0.22494499999993423,0.13412900000002992,0.41323799999918265]},"fetchTaggedLogs":{"times":[47.073481999999785]},"loadCapsule":{"times":[0.25173699999868404,0.19935399999849324,0.21170399999937217]},"validateEnqueuedNotesAndEvents":{"times":[6.408074999999371]},"getNotes":{"times":[6.295136999999158]},"getAuthWitness":{"times":[0.4296990000002552]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.1935909999992873]}}},{"functionName":"private_kernel_inner","time":103.83140099999946},{"functionName":"private_kernel_reset","time":192.81124399999862},{"functionName":"private_kernel_tail","time":76.47451600000022}],"unaccounted":431.4255830000011}} 13:51:54 aztec-1 | [13:51:53.983] INFO: node Received tx 0x20b5fc4ea79b7b46e25c7dd8b646736405e60faac31872f3a82fcfe901256e2d {"txHash":"0x20b5fc4ea79b7b46e25c7dd8b646736405e60faac31872f3a82fcfe901256e2d"} 13:51:54 aztec-1 | [13:51:53.983] INFO: pxe:service Sent transaction 0x20b5fc4ea79b7b46e25c7dd8b646736405e60faac31872f3a82fcfe901256e2d 13:51:54 aztec-1 | [13:51:54.077] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03531952499999898s {"duration":0.03531952499999898,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 13:51:54 aztec-1 | [13:51:54.111] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1f905e1cec4f80e84ece9610921c0b76a54efa786e0371557d8e8fa2aa60e15c","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749736603,"version":3573608979},"txHashes":["0x20b5fc4ea79b7b46e25c7dd8b646736405e60faac31872f3a82fcfe901256e2d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":78.62131100000079,"publicProcessDuration":36.01306199999999,"rollupCircuitsDuration":71.74177400000008,"txCount":1,"blockNumber":1,"blockTimestamp":1749736603,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 13:51:58 aztec-1 | [13:51:57.574] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 13:51:58 aztec-1 | [13:51:57.576] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 13:51:58 aztec-1 | [13:51:57.580] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 13:51:58 aztec-1 | [13:51:57.654] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 13:51:58 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)" 13:51:58 aztec-1 | [13:51:57.654] WARN: sequencer Cannot propose block 1 at slot 2 due to failed rollup contract check 13:51:58 aztec-1 | [13:51:57.674] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x1f905e1cec4f80e84ece9610921c0b76a54efa786e0371557d8e8fa2aa60e15c","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749736603,"version":3573608979},"archiveRoot":"0x267e3132d73dff81a3df0daff998a119334eb02d5d260517b9e7120df00b43bf","archiveNextLeafIndex":2} 13:51:58 aztec-1 | [13:51:57.679] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 13:51:58 aztec-1 | [13:51:57.765] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":18.90624199999911,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749736603,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 13:51:58 aztec-1 | [13:51:57.768] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 13:51:58 aztec-1 | [13:51:57.768] WARN: foundation:test-date-provider Time set to 2025-06-12T13:56:43.000Z {"offset":285232,"timeMs":1749736603000} 13:51:58 aztec-1 | [13:51:57.794] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749736639 13:51:58 aztec-1 | [13:51:57.794] WARN: foundation:test-date-provider Time set to 2025-06-12T13:57:19.000Z {"offset":321206,"timeMs":1749736639000} 13:51:58 aztec-1 | [13:51:57.794] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 13:51:58 aztec-1 | [13:51:57.794] INFO: pxe:service Registered account 0x0ab5671f409a3df349e09dcdba57de3eab9811dbb53fdc7040509b7f514dd741 13:51:58 aztec-1 | [13:51:57.923] INFO: pxe:service Added contract SchnorrAccount at 0x0ab5671f409a3df349e09dcdba57de3eab9811dbb53fdc7040509b7f514dd741 with class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f 13:52:00 aztec-1 | [13:51:59.550] INFO: pxe:service Proving completed in 1603.709014ms {"timings":{"total":1603.709014,"sync":18.64113400000133,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":95.3248339999991,"oracles":{"callPrivateFunction":{"times":[376.943679,254.34054600000127]}}},{"functionName":"private_kernel_init","time":70.96954200000073},{"functionName":"SchnorrAccount:constructor","time":349.7304750000003,"oracles":{"getContractInstance":{"times":[6.677733000000444]},"debugLog":{"times":[0.20983499999965716,0.10045700000046054]},"fetchTaggedLogs":{"times":[88.05356099999881]},"loadCapsule":{"times":[0.25895800000034797,0.9284829999996873,0.25769799999943643]},"validateEnqueuedNotesAndEvents":{"times":[10.044740999999703]},"notifyCreatedNullifier":{"times":[0.2654469999997673,0.23849600000175997]},"notifyCreatedNote":{"times":[0.16314100000090548]},"getRandomField":{"times":[0.13621899999998277,0.17199099999925238,0.08741599999848404,0.063335000000734,0.046523000000888715,0.08009499999934633,0.06561499999952503,0.03884300000026997,0.04007300000012037,0.03306200000042736,0.03858299999956216]},"getIndexedTaggingSecretAsSender":{"times":[52.2416630000007]},"incrementAppTaggingSecretIndexAsSender":{"times":[35.60205400000086]}}},{"functionName":"private_kernel_inner","time":138.87703699999838},{"functionName":"SchnorrAccount:entrypoint","time":227.15412199999992,"oracles":{"debugLog":{"times":[0.2736079999995127,0.10332700000071782,0.2397660000005999]},"fetchTaggedLogs":{"times":[77.84336799999983]},"loadCapsule":{"times":[0.1670109999995475,0.13257900000098743,0.14719999999942956]},"validateEnqueuedNotesAndEvents":{"times":[8.362837000000582]},"getNotes":{"times":[3.939888000000792]},"getAuthWitness":{"times":[0.17713199999889184]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.5769689999997354]}}},{"functionName":"private_kernel_inner","time":138.0990239999992},{"functionName":"private_kernel_reset","time":192.99697699999888},{"functionName":"private_kernel_tail","time":60.478701}],"unaccounted":311.4371680000022}} 13:52:00 aztec-1 | [13:51:59.595] INFO: node Received tx 0x250656657d1a0f3a0cb3f9f9c2f133cda2b2ef4dbd0b38c2f099419286849caa {"txHash":"0x250656657d1a0f3a0cb3f9f9c2f133cda2b2ef4dbd0b38c2f099419286849caa"} 13:52:00 aztec-1 | [13:51:59.595] INFO: pxe:service Sent transaction 0x250656657d1a0f3a0cb3f9f9c2f133cda2b2ef4dbd0b38c2f099419286849caa 13:52:00 aztec-1 | [13:51:59.675] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.02038087799999994s {"duration":0.02038087799999994,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 13:52:00 aztec-1 | [13:51:59.707] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x21e5a1e15c8599d57340511fbe4c2f57be9a653f2a1559de40435acbd6435af5","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749736639,"version":3573608979},"txHashes":["0x250656657d1a0f3a0cb3f9f9c2f133cda2b2ef4dbd0b38c2f099419286849caa"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":59.87643900000148,"publicProcessDuration":20.52780900000107,"rollupCircuitsDuration":54.31449700000121,"txCount":1,"blockNumber":2,"blockTimestamp":1749736639,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 13:52:00 aztec-1 | [13:51:59.939] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 13:52:00 aztec-1 | [13:51:59.954] 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":"0x267e3132d73dff81a3df0daff998a119334eb02d5d260517b9e7120df00b43bf","blocksSynchedTo":25,"currentL1BlockNumber":26,"provenBlockNumber":1,"provenArchive":"0x267e3132d73dff81a3df0daff998a119334eb02d5d260517b9e7120df00b43bf","pendingBlockNumber":2,"pendingArchive":"0x2ada31f1967ea74d0a7b89124c5ae460a8c9d10b2dedda1dff2a0d9f81c16472"} 13:52:00 aztec-1 | [13:51:59.979] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 13:52:00 aztec-1 | [13:51:59.979] WARN: foundation:test-date-provider Time set to 2025-06-12T13:57:31.000Z {"offset":331021,"timeMs":1749736651000} 13:52:00 aztec-1 | [13:51:59.987] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 13:52:00 aztec-1 | [13:51:59.988] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 13:52:00 aztec-1 | [13:52:00.008] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 13:52:00 aztec-1 | error: "Rollup__SlotAlreadyInChain(3, 3)" 13:52:00 aztec-1 | [13:52:00.008] WARN: sequencer Cannot propose block 2 at slot 3 due to failed rollup contract check 13:52:00 aztec-1 | [13:52:00.058] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x21e5a1e15c8599d57340511fbe4c2f57be9a653f2a1559de40435acbd6435af5","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749736639,"version":3573608979},"archiveRoot":"0x2ada31f1967ea74d0a7b89124c5ae460a8c9d10b2dedda1dff2a0d9f81c16472","archiveNextLeafIndex":3} 13:52:00 aztec-1 | [13:52:00.065] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 13:52:00 aztec-1 | [13:52:00.104] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":13.687150000001566,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749736639,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 13:52:00 aztec-1 | [13:52:00.171] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749736675 13:52:00 aztec-1 | [13:52:00.171] WARN: foundation:test-date-provider Time set to 2025-06-12T13:57:55.000Z {"offset":354829,"timeMs":1749736675000} 13:52:00 aztec-1 | [13:52:00.171] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 13:52:00 aztec-1 | [13:52:00.219] INFO: pxe:service Registered account 0x14e6e67f5fc67ae70f2e78fe6cd60b034b690c8d3f90eab69609bfb283fdc923 13:52:00 aztec-1 | [13:52:00.328] INFO: pxe:service Added contract SchnorrAccount at 0x14e6e67f5fc67ae70f2e78fe6cd60b034b690c8d3f90eab69609bfb283fdc923 with class 0x2e017ccab24f7995c8c2ec64e81af3047767dca754005fcddf81093b54ed2a4f 13:52:02 aztec-1 | Initial Accounts: 13:52:02 aztec-1 | 13:52:02 aztec-1 | Address: 0x2098b7a51b5545e6dcbf3d1f703b1fa961aee3fd4afa74cfc5fcdaaf9473ddef 13:52:02 aztec-1 | Partial Address: 0x25cdbb7a5be4a2b0e9adb1e87d0b5f372a1fa87051c9bcfee1d2c12805bf4aac 13:52:02 aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281 13:52:02 aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f 13:52:02 aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f 13:52:02 aztec-1 | 13:52:02 aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5 13:52:02 aztec-1 | 13:52:02 aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89 13:52:02 aztec-1 | 13:52:02 aztec-1 | Address: 0x0ab5671f409a3df349e09dcdba57de3eab9811dbb53fdc7040509b7f514dd741 13:52:02 aztec-1 | Partial Address: 0x0c7f265c4adbd783aa791ae3f6859e620def9316c9013a9a72c68d3df8514776 13:52:02 aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae 13:52:02 aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57 13:52:02 aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d 13:52:02 aztec-1 | 13:52:02 aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e 13:52:02 aztec-1 | 13:52:02 aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d 13:52:02 aztec-1 | 13:52:02 aztec-1 | Address: 0x14e6e67f5fc67ae70f2e78fe6cd60b034b690c8d3f90eab69609bfb283fdc923 13:52:02 aztec-1 | Partial Address: 0x26ee9b51c1ec4a4c6810ea237b0a33367ab5d0c7fd08e07761ac9c429a28ef0a 13:52:02 aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c 13:52:02 aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3 13:52:02 aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae 13:52:02 aztec-1 | 13:52:02 aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da 13:52:02 aztec-1 | 13:52:02 aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba 13:52:02 aztec-1 | 13:52:02 aztec-1 | 13:52:02 aztec-1 | [13:52:01.785] INFO: pxe:service Proving completed in 1446.5796139999984ms {"timings":{"total":1446.5796139999984,"sync":23.731799000001047,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":75.9403210000055,"oracles":{"callPrivateFunction":{"times":[333.2135269999999,234.03882099999828]}}},{"functionName":"private_kernel_init","time":46.090603000000556},{"functionName":"SchnorrAccount:constructor","time":309.8490839999977,"oracles":{"getContractInstance":{"times":[1.1786710000014864]},"debugLog":{"times":[0.12812900000062655,0.08420599999953993]},"fetchTaggedLogs":{"times":[110.08598499999789]},"loadCapsule":{"times":[0.20659400000295136,0.1642909999973199,0.1770120000001043]},"validateEnqueuedNotesAndEvents":{"times":[5.9019549999975425]},"notifyCreatedNullifier":{"times":[0.16126100000110455,0.21948499999780324]},"notifyCreatedNote":{"times":[0.13279900000270572]},"getRandomField":{"times":[0.11072799999965355,0.2554969999982859,0.1360099999983504,0.10437799999999697,0.0854460000009567,0.07479500000044936,0.04686299999957555,0.062154999999620486,0.043073000000731554,0.042763000001286855,0.05163400000310503]},"getIndexedTaggingSecretAsSender":{"times":[39.17557800000213]},"incrementAppTaggingSecretIndexAsSender":{"times":[20.776256000001013]}}},{"functionName":"private_kernel_inner","time":123.91539299999931},{"functionName":"SchnorrAccount:entrypoint","time":212.07496399999945,"oracles":{"debugLog":{"times":[0.2116439999990689,0.09210600000005797,0.20393400000102702]},"fetchTaggedLogs":{"times":[82.30436899999768]},"loadCapsule":{"times":[0.20619400000214227,0.17083199999979115,0.17622199999823351]},"validateEnqueuedNotesAndEvents":{"times":[6.0035119999993185]},"getNotes":{"times":[10.63861000000179]},"getAuthWitness":{"times":[0.18644299999868963]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.4664009999978589]}}},{"functionName":"private_kernel_inner","time":134.20858099999896},{"functionName":"private_kernel_reset","time":209.46463399999993},{"functionName":"private_kernel_tail","time":70.920686999998}],"unaccounted":240.38354799999797}} 13:52:02 aztec-1 | [13:52:01.831] INFO: node Received tx 0x07d3babe3b2c2d6d6eca69f7d09213a3e67b6cc0ad8f2bb4e8931a89d13dc32d {"txHash":"0x07d3babe3b2c2d6d6eca69f7d09213a3e67b6cc0ad8f2bb4e8931a89d13dc32d"} 13:52:02 aztec-1 | [13:52:01.831] INFO: pxe:service Sent transaction 0x07d3babe3b2c2d6d6eca69f7d09213a3e67b6cc0ad8f2bb4e8931a89d13dc32d 13:52:02 aztec-1 | [13:52:01.909] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.020498806999999942s {"duration":0.020498806999999942,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992} 13:52:02 aztec-1 | [13:52:01.942] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1aad1125ab12c4cd76983058495f326328169070779f28b10a5591fd9bb97a48","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749736675,"version":3573608979},"txHashes":["0x07d3babe3b2c2d6d6eca69f7d09213a3e67b6cc0ad8f2bb4e8931a89d13dc32d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":60.439948000002914,"publicProcessDuration":20.61444500000289,"rollupCircuitsDuration":55.744425000000774,"txCount":1,"blockNumber":3,"blockTimestamp":1749736675,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 13:52:02 aztec-1 | [13:52:02.116] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 13:52:02 aztec-1 | [13:52:02.123] 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":"0x2ada31f1967ea74d0a7b89124c5ae460a8c9d10b2dedda1dff2a0d9f81c16472","blocksSynchedTo":27,"currentL1BlockNumber":28,"provenBlockNumber":2,"provenArchive":"0x2ada31f1967ea74d0a7b89124c5ae460a8c9d10b2dedda1dff2a0d9f81c16472","pendingBlockNumber":3,"pendingArchive":"0x2b2514567b46b04e4cb2b4c268437aff9d36fb1083242a0cf0c68b9662098f0a"} 13:52:02 aztec-1 | [13:52:02.179] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 13:52:02 aztec-1 | error: "Rollup__SlotAlreadyInChain(4, 4)" 13:52:02 aztec-1 | [13:52:02.179] WARN: sequencer Cannot propose block 3 at slot 4 due to failed rollup contract check 13:52:02 aztec-1 | [13:52:02.210] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 13:52:02 aztec-1 | [13:52:02.210] WARN: foundation:test-date-provider Time set to 2025-06-12T13:58:07.000Z {"offset":364790,"timeMs":1749736687000} 13:52:02 aztec-1 | [13:52:02.213] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x1aad1125ab12c4cd76983058495f326328169070779f28b10a5591fd9bb97a48","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749736675,"version":3573608979},"archiveRoot":"0x2b2514567b46b04e4cb2b4c268437aff9d36fb1083242a0cf0c68b9662098f0a","archiveNextLeafIndex":4} 13:52:02 aztec-1 | [13:52:02.213] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 13:52:02 aztec-1 | [13:52:02.214] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 13:52:02 aztec-1 | [13:52:02.277] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":16.797982000000047,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749736675,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 13:52:02 aztec-1 | [13:52:02.333] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749736711 13:52:02 aztec-1 | [13:52:02.333] WARN: foundation:test-date-provider Time set to 2025-06-12T13:58:31.000Z {"offset":388667,"timeMs":1749736711000} 13:52:02 aztec-1 | [13:52:02.333] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 13:52:03 aztec-1 | [13:52:03.081] WARN: pxe:service No artifact found for contract class 0x2183e5f752f839cfb2ba67a84dc13934d6d807278ff767a694fe49778e7ce0b3 when looking for its metadata 13:52:03 aztec-1 | [13:52:03.081] WARN: pxe:service No artifact found for contract class 0x20e76625b255753931a4abe202b444d4fcd0107fe0372bbed9061ac25dbff249 when looking for its metadata 13:52:03 aztec-1 | [13:52:03.084] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x2183e5f752f839cfb2ba67a84dc13934d6d807278ff767a694fe49778e7ce0b3 as part of deployment for 0x2c3af751391c30b4ff51722fb1c59d9380a64d0a06990f1810963afda65b9cae 13:52:03 aztec-1 | [13:52:03.114] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x20e76625b255753931a4abe202b444d4fcd0107fe0372bbed9061ac25dbff249 as part of deployment for 0x16b930d76fe7a01a12e8f5fa8bfd7f6350e3383b4f34620955e665fa15ac51d7 13:52:04 aztec-1 | [13:52:03.819] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 13:52:04 aztec-1 | [13:52:03.919] INFO: pxe:service Added contract Token at 0x16b930d76fe7a01a12e8f5fa8bfd7f6350e3383b4f34620955e665fa15ac51d7 with class 0x20e76625b255753931a4abe202b444d4fcd0107fe0372bbed9061ac25dbff249 13:52:04 aztec-1 | [13:52:03.922] INFO: pxe:service Added contract FPC at 0x2c3af751391c30b4ff51722fb1c59d9380a64d0a06990f1810963afda65b9cae with class 0x2183e5f752f839cfb2ba67a84dc13934d6d807278ff767a694fe49778e7ce0b3 13:52:06 aztec-1 | [13:52:06.355] INFO: pxe:service Proving completed in 2421.9448790000024ms {"timings":{"total":2421.9448790000024,"sync":16.27097700000013,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":310.4955289999998,"oracles":{"debugLog":{"times":[0.2697389999993902,0.09208600000056322,0.11587800000052084,0.14587000000028638,0.07926500000030501,0.0674940000026254,0.09915700000055949,0.29081999999834807]},"fetchTaggedLogs":{"times":[96.25205500000084]},"loadCapsule":{"times":[0.19323300000178278,0.13344900000083726,0.25780800000211457,0.18475199999738834,0.19088299999930314,0.28419899999789777,0.1592610000006971]},"getSharedSecret":{"times":[4.673510999997234]},"aes128Decrypt":{"times":[1.1847210000014456,0.6565850000006321]},"getPublicKeysAndPartialAddress":{"times":[3.0844209999995655]},"getKeyValidationRequest":{"times":[2.4434580000015558]},"storeCapsule":{"times":[6.102238999999827,3.190528999999515,2.883927999999287]},"deleteCapsule":{"times":[3.9513110000007146]},"validateEnqueuedNotesAndEvents":{"times":[22.285958999997092]},"getNotes":{"times":[7.367966000001616]},"getAuthWitness":{"times":[0.3474930000011227]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05841400000281283]},"callPrivateFunction":{"times":[879.4550640000016,141.03787899999952]},"notifyEnqueuedPublicFunctionCall":{"times":[0.4450600000018312]}}},{"functionName":"private_kernel_init","time":75.30431800000224},{"functionName":"ContractClassRegisterer:register","time":865.4840750000039,"oracles":{"loadCapsule":{"times":[3.6840419999971346]},"notifyCreatedNullifier":{"times":[0.3525840000002063]},"debugLog":{"times":[0.15274000000135857]},"notifyCreatedContractClassLog":{"times":[9.736798000001727]}}},{"functionName":"private_kernel_inner","time":101.91939400000047},{"functionName":"ContractInstanceDeployer:deploy","time":88.28694799998993,"oracles":{"storeInExecutionCache":{"times":[0.4037080000016431]},"callPrivateFunction":{"times":[47.720595000002504]},"notifyCreatedNullifier":{"times":[3.0432290000026114]},"debugLog":{"times":[0.16171100000065053]}}},{"functionName":"private_kernel_inner","time":88.16656999999759},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":34.897915000005014,"oracles":{}},{"functionName":"private_kernel_inner","time":146.22055499999988},{"functionName":"private_kernel_reset","time":227.67159399999946},{"functionName":"private_kernel_tail","time":110.12542799999937}],"unaccounted":357.10157600000457}} 13:52:06 aztec-1 | [13:52:06.555] INFO: node Received tx 0x08571bf961df5e7da2f9e822bceed70a4e6462e5368ec4f38296c01dbbf91b5a {"txHash":"0x08571bf961df5e7da2f9e822bceed70a4e6462e5368ec4f38296c01dbbf91b5a"} 13:52:06 aztec-1 | [13:52:06.555] INFO: pxe:service Sent transaction 0x08571bf961df5e7da2f9e822bceed70a4e6462e5368ec4f38296c01dbbf91b5a 13:52:08 aztec-1 | [13:52:08.602] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 1.8971216920000007s {"duration":1.8971216920000007,"rate":145614.80223694575,"totalPublicGas":{"daGas":10240,"l2Gas":276249},"totalBlockGas":{"daGas":380928,"l2Gas":301291},"totalSizeInBytes":24064} 13:52:09 aztec-1 | [13:52:09.079] INFO: pxe:service Proving completed in 2722.5908710000003ms {"timings":{"total":2722.5908710000003,"sync":162.88252799999827,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":568.5118750000038,"oracles":{"debugLog":{"times":[0.18506300000080955,0.10206699999980628,0.12197799999921699,0.23172600000179955,0.12973799999963376,0.12694900000133202,0.090066000000661,0.09401699999943958]},"fetchTaggedLogs":{"times":[235.77112000000125]},"loadCapsule":{"times":[0.36260500000207685,1.2444350000005215,0.626643999999942,0.4157390000000305,1.1922919999997248,0.7657919999983278,0.35973500000181957]},"getSharedSecret":{"times":[6.798725999997259]},"aes128Decrypt":{"times":[0.2733890000017709,0.7638420000002952]},"getPublicKeysAndPartialAddress":{"times":[1.9395930000027874]},"getKeyValidationRequest":{"times":[3.035168000002159]},"storeCapsule":{"times":[80.93110399999932,5.3937000000005355,3.2969859999975597]},"deleteCapsule":{"times":[3.035168000002159]},"validateEnqueuedNotesAndEvents":{"times":[11.878366000000824]},"getNotes":{"times":[33.57040400000187]},"getAuthWitness":{"times":[0.12401900000259047]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.0361720000000787]},"callPrivateFunction":{"times":[667.5503210000024,123.62792400000035]},"notifyEnqueuedPublicFunctionCall":{"times":[0.07217499999751453]}}},{"functionName":"private_kernel_init","time":73.31353099999978},{"functionName":"ContractClassRegisterer:register","time":655.0753949999962,"oracles":{"loadCapsule":{"times":[1.3999760000006063]},"notifyCreatedNullifier":{"times":[0.4399410000005446]},"debugLog":{"times":[0.14667999999801395]},"notifyCreatedContractClassLog":{"times":[5.472085000001243]}}},{"functionName":"private_kernel_inner","time":154.91837099999975},{"functionName":"ContractInstanceDeployer:deploy","time":63.32989699999962,"oracles":{"storeInExecutionCache":{"times":[0.10368699999889941]},"callPrivateFunction":{"times":[52.74718999999823]},"notifyCreatedNullifier":{"times":[0.3190419999991718]},"debugLog":{"times":[0.17017199999827426]}}},{"functionName":"private_kernel_inner","time":146.45434100000057},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":39.93510999999853,"oracles":{}},{"functionName":"private_kernel_inner","time":119.02368800000113},{"functionName":"private_kernel_reset","time":204.74368999999933},{"functionName":"private_kernel_tail","time":96.3230500000027}],"unaccounted":438.07939500000066}} 13:52:09 aztec-1 | [13:52:09.119] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 114024.4933472838 mana/s {"blockHash":"0x0b6dbcdd51dc8ee9c2f9e857c760103453811a8322c5c3c3eda00259f7d09783","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749736711,"version":3573608979},"txHashes":["0x08571bf961df5e7da2f9e822bceed70a4e6462e5368ec4f38296c01dbbf91b5a"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":2422.632727,"publicProcessDuration":1897.3034549999975,"rollupCircuitsDuration":2415.7102909999994,"txCount":1,"blockNumber":4,"blockTimestamp":1749736711,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 13:52:09 aztec-1 | [13:52:09.332] INFO: node Received tx 0x06813787f14e980235f9bcf2e1a7dfe31601d80ade5f067643514edd49bf72e9 {"txHash":"0x06813787f14e980235f9bcf2e1a7dfe31601d80ade5f067643514edd49bf72e9"} 13:52:09 aztec-1 | [13:52:09.332] INFO: pxe:service Sent transaction 0x06813787f14e980235f9bcf2e1a7dfe31601d80ade5f067643514edd49bf72e9 13:52:09 aztec-1 | [13:52:09.511] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 13:52:09 aztec-1 | [13:52:09.516] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749736747 13:52:09 aztec-1 | [13:52:09.516] WARN: foundation:test-date-provider Time set to 2025-06-12T13:59:07.000Z {"offset":417484,"timeMs":1749736747000} 13:52:09 aztec-1 | [13:52:09.517] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 13:52:09 aztec-1 | [13:52:09.519] WARN: archiver Failed to reach L2 block 4 at 30 (latest is 3). Rolling back last synched L1 block number to 29. {"latestLocalL2BlockNumber":3,"latestLocalL2BlockArchive":"0x2b2514567b46b04e4cb2b4c268437aff9d36fb1083242a0cf0c68b9662098f0a","blocksSynchedTo":29,"currentL1BlockNumber":30,"provenBlockNumber":3,"provenArchive":"0x2b2514567b46b04e4cb2b4c268437aff9d36fb1083242a0cf0c68b9662098f0a","pendingBlockNumber":4,"pendingArchive":"0x0cc9458e48e106119d23d2b163ccee40bfd86d4ff62c1afb18a21559f9fce2dc"} 13:52:09 aztec-1 | [13:52:09.528] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 13:52:09 aztec-1 | [13:52:09.529] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 13:52:09 aztec-1 | [13:52:09.663] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x0b6dbcdd51dc8ee9c2f9e857c760103453811a8322c5c3c3eda00259f7d09783","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749736711,"version":3573608979},"archiveRoot":"0x0cc9458e48e106119d23d2b163ccee40bfd86d4ff62c1afb18a21559f9fce2dc","archiveNextLeafIndex":5} 13:52:09 aztec-1 | [13:52:09.667] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 13:52:09 aztec-1 | [13:52:09.737] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":19.599695000000793,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749736711,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702} 13:52:10 aztec-1 | [13:52:09.960] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.16970472599999994s {"duration":0.16970472599999994,"rate":209210.4376633566,"totalPublicGas":{"daGas":4096,"l2Gas":35504},"totalBlockGas":{"daGas":166912,"l2Gas":60546},"totalSizeInBytes":10688} 13:52:10 aztec-1 | [13:52:09.991] INFO: sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 171176.12383894206 mana/s {"blockHash":"0x0ef6e1ce2a69f642d8a026cbc09d1a0896cc35fc830e8f07158024892ba6f1cc","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749736747,"version":3573608979},"txHashes":["0x06813787f14e980235f9bcf2e1a7dfe31601d80ade5f067643514edd49bf72e9"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":207.38417200000185,"publicProcessDuration":170.02240800000072,"rollupCircuitsDuration":202.59780399999727,"txCount":1,"blockNumber":5,"blockTimestamp":1749736747,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296} 13:52:10 aztec-1 | [13:52:10.212] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5 13:52:10 aztec-1 | [13:52:10.219] INFO: aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5. 13:52:10 aztec-1 | [13:52:10.221] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 13:52:10 aztec-1 | [13:52:10.227] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749736783 13:52:10 aztec-1 | [13:52:10.227] WARN: foundation:test-date-provider Time set to 2025-06-12T13:59:43.000Z {"offset":452773,"timeMs":1749736783000} 13:52:10 aztec-1 | [13:52:10.227] INFO: aztecjs:utils:watcher Slot 6 was filled, jumped to next slot 13:52:10 aztec-1 | [13:52:10.481] INFO: aztecjs:deploy_sent_tx Contract 0x16b930d76fe7a01a12e8f5fa8bfd7f6350e3383b4f34620955e665fa15ac51d7 successfully deployed. 13:52:10 aztec-1 | [13:52:10.631] INFO: archiver Downloaded L2 block 5 {"blockHash":"0x0ef6e1ce2a69f642d8a026cbc09d1a0896cc35fc830e8f07158024892ba6f1cc","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749736747,"version":3573608979},"archiveRoot":"0x1f3ae1429be43ce571c0f3464d1553e92216bed217b5f0ce6c047bb482d1cf81","archiveNextLeafIndex":6} 13:52:10 aztec-1 | [13:52:10.637] INFO: archiver Updated proven chain to block 5 {"provenBlockNumber":5} 13:52:10 aztec-1 | [13:52:10.696] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":14.462233000002016,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749736747,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296} 13:52:11 aztec-1 | BananaCoin: 0x16b930d76fe7a01a12e8f5fa8bfd7f6350e3383b4f34620955e665fa15ac51d7 13:52:11 aztec-1 | FPC: 0x2c3af751391c30b4ff51722fb1c59d9380a64d0a06990f1810963afda65b9cae 13:52:11 aztec-1 | [13:52:11.554] INFO: aztecjs:deploy_sent_tx Contract 0x2c3af751391c30b4ff51722fb1c59d9380a64d0a06990f1810963afda65b9cae successfully deployed. 13:52:11 aztec-1 | [13:52:11.581] WARN: pxe:service No artifact found for contract class 0x04193fd6314e8d0af283de6f2769212e2122e260c6e55154e9d94857c84e9523 when looking for its metadata 13:52:11 aztec-1 | [13:52:11.583] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x04193fd6314e8d0af283de6f2769212e2122e260c6e55154e9d94857c84e9523 as part of deployment for 0x22932b1f5008b7d2e5562a9f582fe1caf230663d744283f1e92ff8a41544cbfc 13:52:11 aztec-1 | [13:52:11.721] INFO: pxe:service Added contract SponsoredFPC at 0x22932b1f5008b7d2e5562a9f582fe1caf230663d744283f1e92ff8a41544cbfc with class 0x04193fd6314e8d0af283de6f2769212e2122e260c6e55154e9d94857c84e9523 13:52:14 aztec-1 | [13:52:13.904] INFO: pxe:service Proving completed in 2154.440870000002ms {"timings":{"total":2154.440870000002,"sync":27.871502999998484,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":97.11787399999594,"oracles":{"callPrivateFunction":{"times":[733.7688750000016,136.30234299999938,29.342054000000644]}}},{"functionName":"private_kernel_init","time":67.03949099999954},{"functionName":"ContractClassRegisterer:register","time":711.9820710000022,"oracles":{"loadCapsule":{"times":[2.8767879999977595]},"notifyCreatedNullifier":{"times":[0.3571639999972831]},"debugLog":{"times":[0.1573209999987739]},"notifyCreatedContractClassLog":{"times":[8.178391999997984]}}},{"functionName":"private_kernel_inner","time":124.00514900000053},{"functionName":"ContractInstanceDeployer:deploy","time":69.61707699999897,"oracles":{"storeInExecutionCache":{"times":[0.1202080000002752]},"callPrivateFunction":{"times":[71.29689299999882]},"notifyCreatedNullifier":{"times":[0.3253230000009353]},"debugLog":{"times":[0.1312790000010864]}}},{"functionName":"private_kernel_inner","time":136.10991099999956},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":53.97643500000049,"oracles":{}},{"functionName":"private_kernel_inner","time":90.59213699999964},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":22.115366999998514,"oracles":{"debugLog":{"times":[0.13780899999983376]}}},{"functionName":"private_kernel_inner","time":143.53625000000102},{"functionName":"private_kernel_reset","time":203.21282599999904},{"functionName":"private_kernel_tail","time":59.3458329999994}],"unaccounted":347.9189460000089}} 13:52:14 aztec-1 | [13:52:14.031] INFO: node Received tx 0x24d360576fb49d66c5df606a5cb09ca4661baf2f8623ff5406b787586b00449b {"txHash":"0x24d360576fb49d66c5df606a5cb09ca4661baf2f8623ff5406b787586b00449b"} 13:52:14 aztec-1 | [13:52:14.031] INFO: pxe:service Sent transaction 0x24d360576fb49d66c5df606a5cb09ca4661baf2f8623ff5406b787586b00449b 13:52:14 aztec-1 | [13:52:14.129] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.028285931999998865s {"duration":0.028285931999998865,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152} 13:52:14 aztec-1 | [13:52:14.147] INFO: sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x121fd36aa09ac33c2eb509b583d69db148dfc2811588c5dac684ac5c9769680d","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749736783,"version":3573608979},"txHashes":["0x24d360576fb49d66c5df606a5cb09ca4661baf2f8623ff5406b787586b00449b"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":54.20090899999923,"publicProcessDuration":28.371706999998423,"rollupCircuitsDuration":48.112442000001465,"txCount":1,"blockNumber":6,"blockTimestamp":1749736783,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 13:52:14 aztec-1 | [13:52:14.361] WARN: ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6 13:52:14 aztec-1 | [13:52:14.363] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 13:52:14 aztec-1 | [13:52:14.364] INFO: aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6. 13:52:14 aztec-1 | [13:52:14.375] 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":"0x1f3ae1429be43ce571c0f3464d1553e92216bed217b5f0ce6c047bb482d1cf81","blocksSynchedTo":33,"currentL1BlockNumber":34,"provenBlockNumber":5,"provenArchive":"0x1f3ae1429be43ce571c0f3464d1553e92216bed217b5f0ce6c047bb482d1cf81","pendingBlockNumber":6,"pendingArchive":"0x25452db883411b4541aaa9759cf6a9e60a398cf658bcc333fd503ae6e15471a4"} 13:52:14 aztec-1 | [13:52:14.432] WARN: sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain 13:52:14 aztec-1 | error: "Rollup__SlotAlreadyInChain(7, 7)" 13:52:14 aztec-1 | [13:52:14.432] WARN: sequencer Cannot propose block 6 at slot 7 due to failed rollup contract check 13:52:14 aztec-1 | [13:52:14.438] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749736819 13:52:14 aztec-1 | [13:52:14.438] WARN: foundation:test-date-provider Time set to 2025-06-12T14:00:19.000Z {"offset":484562,"timeMs":1749736819000} 13:52:14 aztec-1 | [13:52:14.438] INFO: aztecjs:utils:watcher Slot 7 was filled, jumped to next slot 13:52:14 aztec-1 | [13:52:14.483] INFO: archiver Downloaded L2 block 6 {"blockHash":"0x121fd36aa09ac33c2eb509b583d69db148dfc2811588c5dac684ac5c9769680d","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749736783,"version":3573608979},"archiveRoot":"0x25452db883411b4541aaa9759cf6a9e60a398cf658bcc333fd503ae6e15471a4","archiveNextLeafIndex":7} 13:52:14 aztec-1 | [13:52:14.492] INFO: archiver Updated proven chain to block 6 {"provenBlockNumber":6} 13:52:14 aztec-1 | [13:52:14.518] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":13.316494000002422,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749736783,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 13:52:15 aztec-1 | SponsoredFPC: 0x22932b1f5008b7d2e5562a9f582fe1caf230663d744283f1e92ff8a41544cbfc 13:52:15 aztec-1 | [13:52:15.045] INFO: aztecjs:deploy_sent_tx Contract 0x22932b1f5008b7d2e5562a9f582fe1caf230663d744283f1e92ff8a41544cbfc successfully deployed. 13:52:15 aztec-1 | [13:52:15.052] INFO: cli Aztec Server listening on port 8080 {"l2CircuitsVkTreeRoot":"0x08c6b24bd9c265f8c6596b12f540d93a53f5bcd1502d78957c8614575c61c9f4","l2ProtocolContractsTreeRoot":"0x052cc6e2bd1171363e72fb45b9a78fb0ebacedb96c8f1148b3903c3bafa8c458"} 13:52:24 playground-1 | \ 13:52:24 playground-1 | Running 1 test using 1 worker 13:52:24 playground-1 | 13:52:24 playground-1 | ✓ 1 [chromium] › browser.spec.ts:3:1 › test (9.6s) 13:52:34 playground-1 | 1 passed (12.7s) 13:52:34 playground-1 | \ 13:52:35 playground-1 exited with code 0 13:52:35 Aborting on container exit... 13:52:35 Container playground-chromium-playground-1 Stopping 13:52:35 Container playground-chromium-playground-1 Stopped 13:52:35 Container playground-chromium-aztec-1 Stopping 13:52:36 Container playground-chromium-aztec-1 Stopped 13:52:36 Container playground-chromium-ethereum-1 Stopping 13:52:36 Container playground-chromium-ethereum-1 Stopped