Parent Log:
http://ci.aztec-labs.com/1c420a977384450c
Command: be4ea31cc49b62d3 playground/scripts/run_test.sh chromium
Commit:
https://github.com/AztecProtocol/aztec-packages/commit/d96baf1c44329e8b2e3a432ad803f702f5184a62
Env: REF_NAME=gh-readonly-queue/next/pr-15025-26c5a39fe03723d11540f721293d7aebd1f478d9 CURRENT_VERSION=0.87.6 CI_FULL=1
Date: Fri Jun 13 09:36:31 UTC 2025
System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15025_amd64_x3-full
Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s
History:
http://ci.aztec-labs.com/list/history_3af8716c4dfeb01b_next
09:36:31 Network playground-chromium_default Creating
09:36:31 Network playground-chromium_default Created
09:36:31 Container playground-chromium-ethereum-1 Creating
09:36:32 Container playground-chromium-ethereum-1 Created
09:36:32 Container playground-chromium-aztec-1 Creating
09:36:32 Container playground-chromium-aztec-1 Created
09:36:32 Container playground-chromium-playground-1 Creating
09:36:33 Container playground-chromium-playground-1 Created
09:36:33 Attaching to aztec-1, ethereum-1, playground-1
09:36:37 aztec-1 |
09:36:37 aztec-1 | _
09:36:37 aztec-1 | /\ | |
09:36:37 aztec-1 | / \ ___| |_ ___ ___
09:36:37 aztec-1 | / /\ \ |_ / __/ _ \/ __|
09:36:37 aztec-1 | / ____ \ / /| || __/ (__
09:36:37 aztec-1 | /_/___ \_\/___|\__\___|\___|
09:36:37 aztec-1 |
09:36:37 aztec-1 |
https://github.com/AztecProtocol
09:36:37 aztec-1 |
09:36:37 aztec-1 |
09:36:37 aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by...
09:36:38 aztec-1 | Setting up test accounts
09:36:39 aztec-1 | [09:36:38.834]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-K4X6LZ/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
09:36:39 aztec-1 | [09:36:39.157]
INFO:
sandbox Calling anvil_setBlockTimestampInterval with params: [12]
09:36:39 aztec-1 | [09:36:39.163]
WARN:
sandbox Set block interval to 12
09:36:39 aztec-1 | [09:36:39.399]
INFO:
sandbox Waiting for 7 transactions to be mined...
09:36:39 aztec-1 | [09:36:39.426]
INFO:
sandbox All transactions mined successfully
09:36:39 aztec-1 | [09:36:39.523]
INFO:
sandbox Waiting for 9 transactions to be mined...
09:36:39 aztec-1 | [09:36:39.550]
INFO:
sandbox All transactions mined successfully
09:36:39 aztec-1 | [09:36:39.712]
INFO:
sandbox Waiting for 11 transactions to be mined...
09:36:39 aztec-1 | [09:36:39.748]
INFO:
sandbox All transactions mined successfully
09:36:39 aztec-1 | [09:36:39.823]
INFO:
sandbox Waiting for 12 transactions to be mined...
09:36:39 aztec-1 | [09:36:39.844]
INFO:
sandbox All transactions mined successfully
09:36:39 aztec-1 | [09:36:39.854]
INFO:
sandbox Waiting for 12 transactions to be mined...
09:36:39 aztec-1 | [09:36:39.879]
INFO:
sandbox All transactions mined successfully
09:36:39 aztec-1 | [09:36:39.895]
INFO:
sandbox Aztec L1 contracts initialized
{"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x3aa5ebb10dc797cac828524e59a333d0a371443c","inboxAddress":"0x4374eecaad0dcaa149cffc160d5a0552b1d092b0","outboxAddress":"0x44bf2a9217a2970a1bcc7529bf1d40828c594320","feeJuicePortalAddress":"0xa0719ec6accbac2301f88bc71e2f5ddc8c29149b","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"}
09:36:39 aztec-1 | [09:36:39.895]
INFO:
sandbox Handing over to governance
09:36:39 aztec-1 | [09:36:39.906]
INFO:
aztecjs:utils:watcher Watcher started for rollup at 0x3Aa5ebB10DC797CAC828524e59A333d0A371443c
09:36:39 aztec-1 | [09:36:39.906]
INFO:
telemetry:client Using NoopTelemetryClient
09:36:39 aztec-1 | [09:36:39.907]
INFO:
blob-sink:client Creating local blob sink client.
09:36:39 aztec-1 | [09:36:39.911]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:36:39 aztec-1 | [09:36:39.912]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:41:21.000Z
{"offset":281088,"timeMs":1749807681000}
09:36:39 aztec-1 | [09:36:39.944]
INFO:
archiver:lmdb Starting data store with maxReaders 16
09:36:40 aztec-1 | [09:36:40.347]
INFO:
archiver Starting archiver sync to rollup contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c from L1 block 20 to current L1 block 24 with hash 0x2e987dac95b55aa4000da9ed3913804d6fa404469cd2c1dbcad636ceaf8e3a12
{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0xd51cb3928f7f13c14d1be59656c65d137d7a5fd3617217968dbfa8f6dbe87610","l1BlockNumber":20}}
09:36:40 aztec-1 | [09:36:40.369]
INFO:
archiver Initial archiver sync to L1 block 24 complete.
{"l1BlockNumber":24,"syncPoint":{"blocksSynchedTo":24,"messagesSynchedTo":{"l1BlockHash":"0x2e987dac95b55aa4000da9ed3913804d6fa404469cd2c1dbcad636ceaf8e3a12","l1BlockNumber":24}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}}
09:36:40 aztec-1 | [09:36:40.377]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-iziSXx/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
09:36:40 aztec-1 | [09:36:40.638]
INFO:
world_state Created world state synchroniser with block history of 64
09:36:40 aztec-1 | [09:36:40.638]
WARN:
node Aztec node is accepting fake proofs
09:36:40 aztec-1 | [09:36:40.647]
INFO:
p2p:lmdb-v2 Starting data store with maxReaders 16
09:36:40 aztec-1 | [09:36:40.660]
INFO:
p2p-archive:lmdb-v2 Starting data store with maxReaders 16
09:36:40 aztec-1 | [09:36:40.676]
INFO:
p2p-peer:lmdb-v2 Starting data store with maxReaders 16
09:36:40 aztec-1 | [09:36:40.695]
INFO:
p2p:tx_pool Setting maximum tx mempool size
{"maxTxPoolSize":100000000}
09:36:40 aztec-1 | [09:36:40.699]
INFO:
world_state Started world state synchronizer from block 1
09:36:41 aztec-1 | [09:36:40.703]
INFO:
p2p Starting p2p client from block 0 with empty mempools
09:36:41 aztec-1 | [09:36:40.992]
INFO:
epoch-prune-watcher EpochPruneWatcher initialized
09:36:41 aztec-1 | [09:36:41.054]
INFO:
slasher Starting Slasher client...
09:36:41 aztec-1 | [09:36:41.070]
INFO:
sequencer-client Deploying forwarder contract
09:36:41 aztec-1 | [09:36:41.097]
INFO:
sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac
09:36:41 aztec-1 | [09:36:41.106]
INFO:
validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
09:36:41 aztec-1 | [09:36:41.106]
INFO:
sequencer Sequencer config set
{"transactionPollingIntervalMS":50,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":true}
09:36:41 aztec-1 | [09:36:41.108]
INFO:
sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
09:36:41 aztec-1 | [09:36:41.109]
INFO:
node Aztec Node version: 0.87.6
09:36:41 aztec-1 | [09:36:41.109]
INFO:
node Aztec Node started on chain 0x7a69
{"rollupAddress":"0x3aa5ebb10dc797cac828524e59a333d0a371443c","registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","inboxAddress":"0x4374eecaad0dcaa149cffc160d5a0552b1d092b0","outboxAddress":"0x44bf2a9217a2970a1bcc7529bf1d40828c594320","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","feeJuicePortalAddress":"0xa0719ec6accbac2301f88bc71e2f5ddc8c29149b","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","slashFactoryAddress":"0x59b670e9fa9d0a427751af201d676719a970857b","feeAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788","stakingAssetHandlerAddress":"0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"}
09:36:41 aztec-1 | [09:36:41.113]
INFO:
pxe:data:lmdb Starting data store with maxReaders 16
09:36:41 aztec-1 | [09:36:41.127]
WARN:
sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2.
{"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1}
09:36:41 aztec-1 | [09:36:41.204]
WARN:
sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2.
{"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1}
09:36:41 aztec-1 | [09:36:41.257]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:36:41 aztec-1 | [09:36:41.257]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:41:33.000Z
{"offset":291743,"timeMs":1749807693000}
09:36:41 aztec-1 | Setting up funded test accounts...
09:36:41 aztec-1 | [09:36:41.521]
INFO:
pxe:service Started PXE connected to chain 31337 version 2094187016
09:36:41 aztec-1 | [09:36:41.624]
INFO:
pxe:service Registered account 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:36:41 aztec-1 | [09:36:41.701]
WARN:
pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata
09:36:41 aztec-1 | [09:36:41.705]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:36:41 aztec-1 | [09:36:41.845]
INFO:
pxe:service Added contract SchnorrAccount at 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:36:45 aztec-1 | [09:36:45.198]
INFO:
pxe:service Proving completed in 3314.481321ms
{"timings":{"total":3314.481321,"sync":7.274057000000539,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":175.85273300000244,"oracles":{"callPrivateFunction":{"times":[1031.3512229999997,508.83204300000034,230.1893770000006]}}},{"functionName":"private_kernel_init","time":119.81973400000061},{"functionName":"ContractClassRegisterer:register","time":1006.6439719999989,"oracles":{"loadCapsule":{"times":[42.93350400000054]},"notifyCreatedNullifier":{"times":[1.2418400000005931]},"debugLog":{"times":[0.7208769999997457]},"notifyCreatedContractClassLog":{"times":[8.8319649999994]}}},{"functionName":"private_kernel_inner","time":153.14763400000083},{"functionName":"SchnorrAccount:constructor","time":474.69755500000065,"oracles":{"getContractInstance":{"times":[12.51809399999911]},"debugLog":{"times":[0.3531490000004851,0.17660499999874446]},"fetchTaggedLogs":{"times":[132.9016210000009]},"loadCapsule":{"times":[0.7245380000003934,0.8068899999998393,0.7367379999996047]},"validateEnqueuedNotesAndEvents":{"times":[12.366640000000189]},"notifyCreatedNullifier":{"times":[0.9635039999993751,0.2637960000010935]},"notifyCreatedNote":{"times":[1.2893019999992248]},"getRandomField":{"times":[0.9755640000003041,0.29863800000021,0.06598099999973783,0.06204099999922619,0.11556299999938346,0.20804499999940163,0.06341199999951641,0.05314099999850441,0.04144100000121398,0.1207830000003014,0.04141100000015285]},"getIndexedTaggingSecretAsSender":{"times":[50.837006000001566]},"incrementAppTaggingSecretIndexAsSender":{"times":[36.140659000000596]}}},{"functionName":"private_kernel_inner","time":154.6557410000005},{"functionName":"SchnorrAccount:entrypoint","time":206.6837059999998,"oracles":{"debugLog":{"times":[0.25299700000141456,0.13255299999946146,0.43675099999927625]},"fetchTaggedLogs":{"times":[66.7756730000001]},"loadCapsule":{"times":[0.2791269999997894,0.20977500000117288,0.23440600000139966]},"validateEnqueuedNotesAndEvents":{"times":[6.364284999999654]},"getNotes":{"times":[2.8290689999994356]},"getAuthWitness":{"times":[0.4327400000001944]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.3204220000006899]}}},{"functionName":"private_kernel_inner","time":137.68797799999993},{"functionName":"private_kernel_reset","time":259.4932710000012},{"functionName":"private_kernel_tail","time":94.8184850000016}],"unaccounted":523.7064549999932}}
09:36:45 aztec-1 | [09:36:45.350]
INFO:
node Received tx 0x10fc5c6fb30b31bf94f00820d866017ba1b7533ce424e93fa66aa77e8eaef88a
{"txHash":"0x10fc5c6fb30b31bf94f00820d866017ba1b7533ce424e93fa66aa77e8eaef88a"}
09:36:45 aztec-1 | [09:36:45.350]
INFO:
pxe:service Sent transaction 0x10fc5c6fb30b31bf94f00820d866017ba1b7533ce424e93fa66aa77e8eaef88a
09:36:45 aztec-1 | [09:36:45.495]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.06110584600000038s
{"duration":0.06110584600000038,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344}
09:36:45 aztec-1 | [09:36:45.522]
INFO:
sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x2c04a33a3c2f5e98681c06f92f4d42eb8afb42455c2947a693e94b7ab811211b","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807705,"version":2094187016},"txHashes":["0x10fc5c6fb30b31bf94f00820d866017ba1b7533ce424e93fa66aa77e8eaef88a"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":101.7634049999997,"publicProcessDuration":62.00102800000059,"rollupCircuitsDuration":92.977621,"txCount":1,"blockNumber":1,"blockTimestamp":1749807705,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:36:48 aztec-1 | [09:36:48.752]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:36:48 aztec-1 | [09:36:48.754]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1
09:36:48 aztec-1 | [09:36:48.757]
INFO:
aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1.
09:36:48 aztec-1 | [09:36:48.824]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:36:48 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)"
09:36:48 aztec-1 | [09:36:48.824]
WARN:
sequencer Cannot propose block 1 at slot 2 due to failed rollup contract check
09:36:48 aztec-1 | [09:36:48.853]
INFO:
archiver Downloaded L2 block 1
{"blockHash":"0x2c04a33a3c2f5e98681c06f92f4d42eb8afb42455c2947a693e94b7ab811211b","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807705,"version":2094187016},"archiveRoot":"0x0cf357f315c14639b017c157963ba0200b1e1d1e8818794a18f805e095931efa","archiveNextLeafIndex":2}
09:36:48 aztec-1 | [09:36:48.860]
INFO:
archiver Updated proven chain to block 1
{"provenBlockNumber":1}
09:36:48 aztec-1 | [09:36:48.941]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:36:48 aztec-1 | [09:36:48.941]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:41:45.000Z
{"offset":296059,"timeMs":1749807705000}
09:36:48 aztec-1 | [09:36:48.945]
INFO:
world_state World state updated with L2 block 1
{"eventName":"l2-block-handled","duration":17.33078099999875,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749807705,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:36:48 aztec-1 | [09:36:48.977]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807741
09:36:48 aztec-1 | [09:36:48.977]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:42:21.000Z
{"offset":332023,"timeMs":1749807741000}
09:36:48 aztec-1 | [09:36:48.977]
INFO:
aztecjs:utils:watcher Slot 2 was filled, jumped to next slot
09:36:48 aztec-1 | [09:36:48.981]
INFO:
pxe:service Registered account 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
09:36:49 aztec-1 | [09:36:49.125]
INFO:
pxe:service Added contract SchnorrAccount at 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:36:51 aztec-1 | [09:36:51.019]
INFO:
pxe:service Proving completed in 1866.979089999999ms
{"timings":{"total":1866.979089999999,"sync":42.4946230000005,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":148.37250799999674,"oracles":{"callPrivateFunction":{"times":[488.0972390000006,292.85547100000076]}}},{"functionName":"private_kernel_init","time":52.43176500000118},{"functionName":"SchnorrAccount:constructor","time":449.79446800000187,"oracles":{"getContractInstance":{"times":[7.275236000001314]},"debugLog":{"times":[0.13415300000087882,0.1100220000007539]},"fetchTaggedLogs":{"times":[130.32656999999926]},"loadCapsule":{"times":[0.2935569999990548,0.31132699999943725,0.22656500000084634]},"validateEnqueuedNotesAndEvents":{"times":[9.204662999998618]},"notifyCreatedNullifier":{"times":[0.29754700000012235,1.106487000000925]},"notifyCreatedNote":{"times":[0.19805600000108825]},"getRandomField":{"times":[0.1792650000006688,0.1625739999999496,0.04594100000031176,0.036810999999943306,0.039980999999897904,0.057371999999304535,0.032241000000794884,0.03353100000094855,0.028941000000486383,0.038311000000248896,0.04428100000041013]},"getIndexedTaggingSecretAsSender":{"times":[74.57736300000033]},"incrementAppTaggingSecretIndexAsSender":{"times":[43.369424999998955]}}},{"functionName":"private_kernel_inner","time":138.01908600000024},{"functionName":"SchnorrAccount:entrypoint","time":255.73229900000115,"oracles":{"debugLog":{"times":[0.2738070000013977,0.14088300000003073,0.185605000000578]},"fetchTaggedLogs":{"times":[97.91430099999889]},"loadCapsule":{"times":[0.21435500000006869,0.1974339999997028,0.19110399999954097]},"validateEnqueuedNotesAndEvents":{"times":[5.870221999999558]},"getNotes":{"times":[2.8422590000009222]},"getAuthWitness":{"times":[0.19270499999947788]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.519482000001517]}}},{"functionName":"private_kernel_inner","time":138.8318560000007},{"functionName":"private_kernel_reset","time":199.78488800000196},{"functionName":"private_kernel_tail","time":94.04020699999819}],"unaccounted":347.4773899999964}}
09:36:51 aztec-1 | [09:36:51.072]
INFO:
node Received tx 0x09d97247f407be5cb8db6c0556089c38781596d81e6f08c7e685a00b58bb5d2d
{"txHash":"0x09d97247f407be5cb8db6c0556089c38781596d81e6f08c7e685a00b58bb5d2d"}
09:36:51 aztec-1 | [09:36:51.072]
INFO:
pxe:service Sent transaction 0x09d97247f407be5cb8db6c0556089c38781596d81e6f08c7e685a00b58bb5d2d
09:36:51 aztec-1 | [09:36:51.161]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03642519600000014s
{"duration":0.03642519600000014,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:36:51 aztec-1 | [09:36:51.189]
INFO:
sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x1c489d8e141b667cb47c2a0ce7951631e25785b21576eb2bea5a7d0f67fe1588","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807741,"version":2094187016},"txHashes":["0x09d97247f407be5cb8db6c0556089c38781596d81e6f08c7e685a00b58bb5d2d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":71.90702899999815,"publicProcessDuration":36.63456099999894,"rollupCircuitsDuration":66.89730700000291,"txCount":1,"blockNumber":2,"blockTimestamp":1749807741,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:36:51 aztec-1 | [09:36:51.369]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807777
09:36:51 aztec-1 | [09:36:51.369]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:42:57.000Z
{"offset":365631,"timeMs":1749807777000}
09:36:51 aztec-1 | [09:36:51.369]
INFO:
aztecjs:utils:watcher Slot 3 was filled, jumped to next slot
09:36:51 aztec-1 | [09:36:51.370]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:36:51 aztec-1 | [09:36:51.464]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2
09:36:51 aztec-1 | [09:36:51.467]
INFO:
aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2.
09:36:51 aztec-1 | [09:36:51.499]
INFO:
archiver Downloaded L2 block 2
{"blockHash":"0x1c489d8e141b667cb47c2a0ce7951631e25785b21576eb2bea5a7d0f67fe1588","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807741,"version":2094187016},"archiveRoot":"0x0ff85652056767e2577c1668febc55cf7d374f83ad10f7e5e1c2995e67b834b4","archiveNextLeafIndex":3}
09:36:51 aztec-1 | [09:36:51.534]
INFO:
world_state World state updated with L2 block 2
{"eventName":"l2-block-handled","duration":20.486347999998543,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749807741,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:36:51 aztec-1 | [09:36:51.638]
INFO:
archiver Updated proven chain to block 2
{"provenBlockNumber":2}
09:36:51 aztec-1 | [09:36:51.666]
INFO:
pxe:service Registered account 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
09:36:51 aztec-1 | [09:36:51.806]
INFO:
pxe:service Added contract SchnorrAccount at 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:36:53 aztec-1 | [09:36:53.699]
INFO:
pxe:service Proving completed in 1880.7572349999973ms
{"timings":{"total":1880.7572349999973,"sync":36.439706000001024,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":133.91554499999256,"oracles":{"callPrivateFunction":{"times":[571.2941930000015,337.04165600000124]}}},{"functionName":"private_kernel_init","time":47.24432799999704},{"functionName":"SchnorrAccount:constructor","time":528.5271930000017,"oracles":{"getContractInstance":{"times":[22.185849000001326]},"debugLog":{"times":[0.2561859999987064,0.09327199999825098]},"fetchTaggedLogs":{"times":[217.77097499999945]},"loadCapsule":{"times":[0.2527059999993071,0.18092500000057044,0.8212700000003679]},"validateEnqueuedNotesAndEvents":{"times":[10.948366999997234]},"notifyCreatedNullifier":{"times":[0.2808970000005502,0.29791800000020885]},"notifyCreatedNote":{"times":[0.16128399999797693]},"getRandomField":{"times":[0.18662400000175694,0.2557570000026317,0.15225399999690126,0.10959299999740324,0.10246299999926123,0.08491199999843957,0.08952200000203447,0.10371300000042538,0.08935199999905308,0.09364199999981793,0.067112000000634]},"getIndexedTaggingSecretAsSender":{"times":[63.92930499999784]},"incrementAppTaggingSecretIndexAsSender":{"times":[41.29177400000117]}}},{"functionName":"private_kernel_inner","time":134.24877399999968},{"functionName":"SchnorrAccount:entrypoint","time":298.1977420000003,"oracles":{"debugLog":{"times":[0.19422499999927823,0.09461199999714154,0.27408700000160025]},"fetchTaggedLogs":{"times":[130.14519400000063]},"loadCapsule":{"times":[0.20885500000076718,0.16087399999742047,0.1620839999995951]},"validateEnqueuedNotesAndEvents":{"times":[6.982340000002296]},"getNotes":{"times":[3.384262999999919]},"getAuthWitness":{"times":[0.2819369999997434]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.9031020000002172]}}},{"functionName":"private_kernel_inner","time":124.41911499999696},{"functionName":"private_kernel_reset","time":170.19527899999957},{"functionName":"private_kernel_tail","time":91.35930100000041}],"unaccounted":316.21025200000804}}
09:36:53 aztec-1 | [09:36:53.747]
INFO:
node Received tx 0x24355eac89005e5478f05ed4a0d00293ab3c86f40503433ff6d4145653f57771
{"txHash":"0x24355eac89005e5478f05ed4a0d00293ab3c86f40503433ff6d4145653f57771"}
09:36:53 aztec-1 | [09:36:53.747]
INFO:
pxe:service Sent transaction 0x24355eac89005e5478f05ed4a0d00293ab3c86f40503433ff6d4145653f57771
09:36:53 aztec-1 | [09:36:53.815]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.01820383199999924s
{"duration":0.01820383199999924,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:36:53 aztec-1 | [09:36:53.842]
INFO:
sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x15558a403fd219085f2be08649d51f85e705b31537074bf267cd7eed078ef4e8","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807777,"version":2094187016},"txHashes":["0x24355eac89005e5478f05ed4a0d00293ab3c86f40503433ff6d4145653f57771"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":52.217029999999795,"publicProcessDuration":18.346456999999646,"rollupCircuitsDuration":47.3950130000012,"txCount":1,"blockNumber":3,"blockTimestamp":1749807777,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:36:54 aztec-1 | [09:36:54.086]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:36:54 aztec-1 | [09:36:54.095]
WARN:
archiver Failed to reach L2 block 3 at 29 (latest is 2). Rolling back last synched L1 block number to 28.
{"latestLocalL2BlockNumber":2,"latestLocalL2BlockArchive":"0x0ff85652056767e2577c1668febc55cf7d374f83ad10f7e5e1c2995e67b834b4","blocksSynchedTo":28,"currentL1BlockNumber":29,"provenBlockNumber":2,"provenArchive":"0x0ff85652056767e2577c1668febc55cf7d374f83ad10f7e5e1c2995e67b834b4","pendingBlockNumber":3,"pendingArchive":"0x16e555575c079ab31bbe9161138daf27f9d1609d162b21fb6077578f5dbad259"}
09:36:54 aztec-1 | [09:36:54.134]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:36:54 aztec-1 | [09:36:54.134]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:43:09.000Z
{"offset":374866,"timeMs":1749807789000}
09:36:54 aztec-1 | [09:36:54.136]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3
09:36:54 aztec-1 | [09:36:54.139]
INFO:
aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3.
09:36:54 aztec-1 | [09:36:54.153]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:36:54 aztec-1 | error: "Rollup__SlotAlreadyInChain(4, 4)"
09:36:54 aztec-1 | [09:36:54.153]
WARN:
sequencer Cannot propose block 3 at slot 4 due to failed rollup contract check
09:36:54 aztec-1 | [09:36:54.206]
INFO:
archiver Downloaded L2 block 3
{"blockHash":"0x15558a403fd219085f2be08649d51f85e705b31537074bf267cd7eed078ef4e8","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807777,"version":2094187016},"archiveRoot":"0x16e555575c079ab31bbe9161138daf27f9d1609d162b21fb6077578f5dbad259","archiveNextLeafIndex":4}
09:36:54 aztec-1 | [09:36:54.214]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:36:54 aztec-1 | error: "Rollup__SlotAlreadyInChain(4, 4)"
09:36:54 aztec-1 | [09:36:54.214]
WARN:
sequencer Cannot propose block 3 at slot 4 due to failed rollup contract check
09:36:54 aztec-1 | [09:36:54.215]
INFO:
archiver Updated proven chain to block 3
{"provenBlockNumber":3}
09:36:54 aztec-1 | [09:36:54.272]
INFO:
world_state World state updated with L2 block 3
{"eventName":"l2-block-handled","duration":22.78159400000004,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749807777,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:36:54 aztec-1 | Initial Accounts:
09:36:54 aztec-1 |
09:36:54 aztec-1 | Address: 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:36:54 aztec-1 | Partial Address: 0x27b4888363d0f9eb0d43aa0b2ff08f21fbc6867b8e0baf8ec6062c4623c1eefc
09:36:54 aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281
09:36:54 aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f
09:36:54 aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f
09:36:54 aztec-1 |
09:36:54 aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5
09:36:54 aztec-1 |
09:36:54 aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89
09:36:54 aztec-1 |
09:36:54 aztec-1 | Address: 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
09:36:54 aztec-1 | Partial Address: 0x2e19b56de1617cb3179d81be0173c7301621a2e820da7f2754fcfccdfc4418e0
09:36:54 aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae
09:36:54 aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57
09:36:54 aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d
09:36:54 aztec-1 |
09:36:54 aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e
09:36:54 aztec-1 |
09:36:54 aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d
09:36:54 aztec-1 |
09:36:54 aztec-1 | Address: 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
09:36:54 aztec-1 | Partial Address: 0x2451b67547512eaaaad5ded43156c5b80220b7d5145ebefdd57295a9e05a610d
09:36:54 aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c
09:36:54 aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3
09:36:54 aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae
09:36:54 aztec-1 |
09:36:54 aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da
09:36:54 aztec-1 |
09:36:54 aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba
09:36:54 aztec-1 |
09:36:54 aztec-1 |
09:36:54 aztec-1 | [09:36:54.316]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807813
09:36:54 aztec-1 | [09:36:54.316]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:43:33.000Z
{"offset":398684,"timeMs":1749807813000}
09:36:54 aztec-1 | [09:36:54.317]
INFO:
aztecjs:utils:watcher Slot 4 was filled, jumped to next slot
09:36:55 aztec-1 | [09:36:55.118]
WARN:
pxe:service No artifact found for contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 when looking for its metadata
09:36:55 aztec-1 | [09:36:55.118]
WARN:
pxe:service No artifact found for contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 when looking for its metadata
09:36:55 aztec-1 | [09:36:55.121]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 as part of deployment for 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
09:36:55 aztec-1 | [09:36:55.159]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 as part of deployment for 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
09:36:56 aztec-1 | [09:36:56.183]
INFO:
pxe:service Added contract FPC at 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 with class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1
09:36:56 aztec-1 | [09:36:56.187]
INFO:
pxe:service Added contract Token at 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf with class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645
09:36:59 aztec-1 | [09:36:58.835]
INFO:
pxe:service Proving completed in 2578.4052599999995ms
{"timings":{"total":2578.4052599999995,"sync":41.77727600000071,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":323.584047999997,"oracles":{"debugLog":{"times":[0.25269699999989825,0.10820299999977578,0.06371199999921373,0.12055300000065472,0.0725319999983185,0.06651200000123936,0.0940820000032545,0.21074500000031549]},"fetchTaggedLogs":{"times":[88.7422379999989]},"loadCapsule":{"times":[0.20406499999808148,0.1979749999991327,0.2688770000022487,0.17791399999987334,0.2958969999999681,0.1794740000004822,0.19525399999838555]},"getSharedSecret":{"times":[12.430961999998544]},"aes128Decrypt":{"times":[1.0807060000006459,0.4911820000015723]},"getPublicKeysAndPartialAddress":{"times":[4.435617999999522]},"getKeyValidationRequest":{"times":[4.07122900000104]},"storeCapsule":{"times":[2.8773700000019744,2.7105960000008054,2.2951759999996284]},"deleteCapsule":{"times":[2.6746259999999893]},"validateEnqueuedNotesAndEvents":{"times":[11.619971999996778]},"getNotes":{"times":[2.0695210000012594]},"getAuthWitness":{"times":[0.16694400000051246]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06754100000034668]},"callPrivateFunction":{"times":[705.8635349999968,147.51385800000207]},"notifyEnqueuedPublicFunctionCall":{"times":[0.3774389999998675]}}},{"functionName":"private_kernel_init","time":78.25257300000158},{"functionName":"ContractClassRegisterer:register","time":690.5630830000046,"oracles":{"loadCapsule":{"times":[3.7740319999975327]},"notifyCreatedNullifier":{"times":[0.5129930000002787]},"debugLog":{"times":[0.19522500000311993]},"notifyCreatedContractClassLog":{"times":[3.704009999997652]}}},{"functionName":"private_kernel_inner","time":143.62046199999895},{"functionName":"ContractInstanceDeployer:deploy","time":76.00287800000297,"oracles":{"storeInExecutionCache":{"times":[0.2520460000014282]},"callPrivateFunction":{"times":[73.05289600000106]},"notifyCreatedNullifier":{"times":[0.2832970000017667]},"debugLog":{"times":[0.14088300000003073]}}},{"functionName":"private_kernel_inner","time":138.7732649999998},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":58.76024899999902,"oracles":{}},{"functionName":"private_kernel_inner","time":139.43686000000162},{"functionName":"private_kernel_reset","time":222.75880699999834},{"functionName":"private_kernel_tail","time":116.27122700000109}],"unaccounted":548.6045319999939}}
09:36:59 aztec-1 | [09:36:59.026]
INFO:
node Received tx 0x0cf9ed07c7d6a553fd6abbc0a6a21294885e992a10c520b0dd437f147b167283
{"txHash":"0x0cf9ed07c7d6a553fd6abbc0a6a21294885e992a10c520b0dd437f147b167283"}
09:36:59 aztec-1 | [09:36:59.026]
INFO:
pxe:service Sent transaction 0x0cf9ed07c7d6a553fd6abbc0a6a21294885e992a10c520b0dd437f147b167283
09:37:01 aztec-1 | [09:37:00.902]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 1.708201269000001s
{"duration":1.708201269000001,"rate":24156.404019156584,"totalPublicGas":{"daGas":4096,"l2Gas":41264},"totalBlockGas":{"daGas":166912,"l2Gas":66306},"totalSizeInBytes":10688}
09:37:01 aztec-1 | [09:37:01.181]
INFO:
sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 20523.79529816651 mana/s
{"blockHash":"0x1aa7487b3594fd8a8954dd1bb011e72f803ec6fa1ec965a6dddfa18fd0f91999","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807813,"version":2094187016},"txHashes":["0x0cf9ed07c7d6a553fd6abbc0a6a21294885e992a10c520b0dd437f147b167283"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":2010.4720750000015,"publicProcessDuration":1708.348861999999,"rollupCircuitsDuration":1997.6612410000016,"txCount":1,"blockNumber":4,"blockTimestamp":1749807813,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
09:37:01 aztec-1 | [09:37:01.673]
INFO:
pxe:service Proving completed in 2835.939398999999ms
{"timings":{"total":2835.939398999999,"sync":148.55865300000005,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":468.7961359999972,"oracles":{"debugLog":{"times":[0.24963600000046426,0.11267299999963143,0.17417500000010477,0.2895280000011553,0.10566299999845796,0.06276100000104634,0.1022819999998319,0.1634139999987383]},"fetchTaggedLogs":{"times":[170.16989699999976]},"loadCapsule":{"times":[0.2886579999976675,20.722470000000612,0.5728139999991981,0.27060700000220095,0.4140899999983958,0.5726839999988442,0.291428000000451]},"getSharedSecret":{"times":[11.973135000000184]},"aes128Decrypt":{"times":[0.36316900000019814,0.984653999999864]},"getPublicKeysAndPartialAddress":{"times":[9.4810830000024]},"getKeyValidationRequest":{"times":[5.460954000001948]},"storeCapsule":{"times":[2.497020999999222,2.603094999998575,2.445170999999391]},"deleteCapsule":{"times":[26.924512000001414]},"validateEnqueuedNotesAndEvents":{"times":[21.986510999999155]},"getNotes":{"times":[6.064228999999614]},"getAuthWitness":{"times":[0.18494400000054156]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05945199999769102]},"callPrivateFunction":{"times":[783.3941249999989,147.27915399999983]},"notifyEnqueuedPublicFunctionCall":{"times":[0.05434099999911268]}}},{"functionName":"private_kernel_init","time":71.52482999999847},{"functionName":"ContractClassRegisterer:register","time":770.049586000001,"oracles":{"loadCapsule":{"times":[2.72491699999955]},"notifyCreatedNullifier":{"times":[0.41656000000148197]},"debugLog":{"times":[0.09922200000073644]},"notifyCreatedContractClassLog":{"times":[5.523925999998028]}}},{"functionName":"private_kernel_inner","time":143.97208200000023},{"functionName":"ContractInstanceDeployer:deploy","time":86.216121999998,"oracles":{"storeInExecutionCache":{"times":[0.0967129999989993]},"callPrivateFunction":{"times":[53.58820800000103]},"notifyCreatedNullifier":{"times":[0.1701840000023367]},"debugLog":{"times":[0.1401530000002822]}}},{"functionName":"private_kernel_inner","time":142.7281120000007},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":42.05888500000219,"oracles":{}},{"functionName":"private_kernel_inner","time":127.26172099999894},{"functionName":"private_kernel_reset","time":212.99636100000134},{"functionName":"private_kernel_tail","time":113.78274899999815}],"unaccounted":507.9941620000027}}
09:37:02 aztec-1 | [09:37:01.884]
INFO:
node Received tx 0x2f6a6dc0c462a92c413cb2b96dd164894eee1b6c642fa88e86bbffdc845688b5
{"txHash":"0x2f6a6dc0c462a92c413cb2b96dd164894eee1b6c642fa88e86bbffdc845688b5"}
09:37:02 aztec-1 | [09:37:01.884]
INFO:
pxe:service Sent transaction 0x2f6a6dc0c462a92c413cb2b96dd164894eee1b6c642fa88e86bbffdc845688b5
09:37:02 aztec-1 | [09:37:02.034]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4
09:37:02 aztec-1 | [09:37:02.037]
INFO:
aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4.
09:37:02 aztec-1 | [09:37:02.040]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:37:02 aztec-1 | [09:37:02.051]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807849
09:37:02 aztec-1 | [09:37:02.051]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:44:09.000Z
{"offset":426949,"timeMs":1749807849000}
09:37:02 aztec-1 | [09:37:02.051]
INFO:
aztecjs:utils:watcher Slot 5 was filled, jumped to next slot
09:37:02 aztec-1 | [09:37:02.185]
INFO:
archiver Downloaded L2 block 4
{"blockHash":"0x1aa7487b3594fd8a8954dd1bb011e72f803ec6fa1ec965a6dddfa18fd0f91999","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807813,"version":2094187016},"archiveRoot":"0x11d6328ccd4c78e1a46dd5d304c625bad86d91cc76e82d48ab384c14d786ab87","archiveNextLeafIndex":5}
09:37:02 aztec-1 | [09:37:02.192]
INFO:
archiver Updated proven chain to block 4
{"provenBlockNumber":4}
09:37:02 aztec-1 | [09:37:02.275]
INFO:
world_state World state updated with L2 block 4
{"eventName":"l2-block-handled","duration":28.038689999997587,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749807813,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
09:37:02 aztec-1 | [09:37:02.751]
INFO:
aztecjs:deploy_sent_tx Contract 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 successfully deployed.
09:37:02 aztec-1 | [09:37:02.820]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.4380101259999974s
{"duration":0.4380101259999974,"rate":756715.3824201818,"totalPublicGas":{"daGas":10240,"l2Gas":331449},"totalBlockGas":{"daGas":380928,"l2Gas":356491},"totalSizeInBytes":24064}
09:37:02 aztec-1 | [09:37:02.860]
INFO:
sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 682013.9128912294 mana/s
{"blockHash":"0x22d830822cebe10e75451e14f46ed9e91770cca29603d561603d0baad5ae9d85","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807849,"version":2094187016},"txHashes":["0x2f6a6dc0c462a92c413cb2b96dd164894eee1b6c642fa88e86bbffdc845688b5"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":485.96026699999857,"publicProcessDuration":438.29627400000027,"rollupCircuitsDuration":479.9577390000013,"txCount":1,"blockNumber":5,"blockTimestamp":1749807849,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
09:37:03 aztec-1 | [09:37:03.126]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5
09:37:03 aztec-1 | [09:37:03.132]
INFO:
aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5.
09:37:03 aztec-1 | [09:37:03.135]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:37:03 aztec-1 | [09:37:03.148]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807885
09:37:03 aztec-1 | [09:37:03.148]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:44:45.000Z
{"offset":461852,"timeMs":1749807885000}
09:37:03 aztec-1 | [09:37:03.148]
INFO:
aztecjs:utils:watcher Slot 6 was filled, jumped to next slot
09:37:03 aztec-1 | [09:37:03.310]
INFO:
archiver Downloaded L2 block 5
{"blockHash":"0x22d830822cebe10e75451e14f46ed9e91770cca29603d561603d0baad5ae9d85","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807849,"version":2094187016},"archiveRoot":"0x09343763fe30ba101af28766c77ae5229c7b318011f1f8cccbbedf90eab5c12a","archiveNextLeafIndex":6}
09:37:03 aztec-1 | [09:37:03.317]
INFO:
archiver Updated proven chain to block 5
{"provenBlockNumber":5}
09:37:03 aztec-1 | [09:37:03.382]
INFO:
world_state World state updated with L2 block 5
{"eventName":"l2-block-handled","duration":14.798833999997441,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749807849,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
09:37:04 aztec-1 | BananaCoin: 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
09:37:04 aztec-1 | FPC: 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
09:37:04 aztec-1 | [09:37:04.120]
INFO:
aztecjs:deploy_sent_tx Contract 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf successfully deployed.
09:37:04 aztec-1 | [09:37:04.146]
WARN:
pxe:service No artifact found for contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e when looking for its metadata
09:37:04 aztec-1 | [09:37:04.150]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e as part of deployment for 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
09:37:04 aztec-1 | [09:37:04.267]
INFO:
pxe:service Added contract SponsoredFPC at 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 with class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e
09:37:07 aztec-1 | [09:37:06.683]
INFO:
pxe:service Proving completed in 2397.283542000001ms
{"timings":{"total":2397.283542000001,"sync":34.67940400000225,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":81.18463699999484,"oracles":{"callPrivateFunction":{"times":[881.880667999998,140.4727460000031,39.01929999999993]}}},{"functionName":"private_kernel_init","time":45.77766700000211},{"functionName":"ContractClassRegisterer:register","time":865.1078850000013,"oracles":{"loadCapsule":{"times":[2.5465929999991204]},"notifyCreatedNullifier":{"times":[0.7823989999997139]},"debugLog":{"times":[0.22617499999978463]},"notifyCreatedContractClassLog":{"times":[6.1655920000011974]}}},{"functionName":"private_kernel_inner","time":134.46892899999875},{"functionName":"ContractInstanceDeployer:deploy","time":80.99353300000439,"oracles":{"storeInExecutionCache":{"times":[0.06762200000230223]},"callPrivateFunction":{"times":[66.96080799999982]},"notifyCreatedNullifier":{"times":[0.24851699999999255]},"debugLog":{"times":[0.1041430000004766]}}},{"functionName":"private_kernel_inner","time":152.57951400000093},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":48.153355999998894,"oracles":{}},{"functionName":"private_kernel_inner","time":90.64446999999927},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":30.636443000003055,"oracles":{"debugLog":{"times":[0.1756340000029013]}}},{"functionName":"private_kernel_inner","time":113.438051000001},{"functionName":"private_kernel_reset","time":182.22345300000234},{"functionName":"private_kernel_tail","time":80.88115000000107}],"unaccounted":456.5150499999909}}
09:37:07 aztec-1 | [09:37:06.817]
INFO:
node Received tx 0x2102caaa68e71adf6c077536f4dfd19ba2847060ac1860d1b5831d9b6844923f
{"txHash":"0x2102caaa68e71adf6c077536f4dfd19ba2847060ac1860d1b5831d9b6844923f"}
09:37:07 aztec-1 | [09:37:06.817]
INFO:
pxe:service Sent transaction 0x2102caaa68e71adf6c077536f4dfd19ba2847060ac1860d1b5831d9b6844923f
09:37:07 aztec-1 | [09:37:06.934]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03231384500000422s
{"duration":0.03231384500000422,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152}
09:37:07 aztec-1 | [09:37:06.955]
INFO:
sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x2087be627063a703669fb4316e81de520d42d879db61f63cfdada6a353daa5e2","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749807885,"version":2094187016},"txHashes":["0x2102caaa68e71adf6c077536f4dfd19ba2847060ac1860d1b5831d9b6844923f"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":60.79954600000201,"publicProcessDuration":32.47006800000236,"rollupCircuitsDuration":55.619118000002345,"txCount":1,"blockNumber":6,"blockTimestamp":1749807885,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:37:07 aztec-1 | [09:37:07.134]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:37:07 aztec-1 | [09:37:07.138]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6
09:37:07 aztec-1 | [09:37:07.140]
INFO:
aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6.
09:37:07 aztec-1 | [09:37:07.155]
WARN:
archiver Failed to reach L2 block 6 at 35 (latest is 5). Rolling back last synched L1 block number to 34.
{"latestLocalL2BlockNumber":5,"latestLocalL2BlockArchive":"0x09343763fe30ba101af28766c77ae5229c7b318011f1f8cccbbedf90eab5c12a","blocksSynchedTo":34,"currentL1BlockNumber":35,"provenBlockNumber":5,"provenArchive":"0x09343763fe30ba101af28766c77ae5229c7b318011f1f8cccbbedf90eab5c12a","pendingBlockNumber":6,"pendingArchive":"0x17896654a74e84331ae64452cf4acff7606aaeda62808d8c2bc765d676a9fdb5"}
09:37:07 aztec-1 | [09:37:07.209]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:37:07 aztec-1 | error: "Rollup__SlotAlreadyInChain(7, 7)"
09:37:07 aztec-1 | [09:37:07.209]
WARN:
sequencer Cannot propose block 6 at slot 7 due to failed rollup contract check
09:37:07 aztec-1 | [09:37:07.222]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807921
09:37:07 aztec-1 | [09:37:07.222]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:45:21.000Z
{"offset":493778,"timeMs":1749807921000}
09:37:07 aztec-1 | [09:37:07.222]
INFO:
aztecjs:utils:watcher Slot 7 was filled, jumped to next slot
09:37:07 aztec-1 | [09:37:07.287]
INFO:
archiver Downloaded L2 block 6
{"blockHash":"0x2087be627063a703669fb4316e81de520d42d879db61f63cfdada6a353daa5e2","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749807885,"version":2094187016},"archiveRoot":"0x17896654a74e84331ae64452cf4acff7606aaeda62808d8c2bc765d676a9fdb5","archiveNextLeafIndex":7}
09:37:07 aztec-1 | [09:37:07.294]
INFO:
archiver Updated proven chain to block 6
{"provenBlockNumber":6}
09:37:07 aztec-1 | [09:37:07.333]
INFO:
world_state World state updated with L2 block 6
{"eventName":"l2-block-handled","duration":16.95750699999917,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749807885,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:37:07 aztec-1 | SponsoredFPC: 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
09:37:08 aztec-1 | [09:37:07.850]
INFO:
aztecjs:deploy_sent_tx Contract 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 successfully deployed.
09:37:08 aztec-1 | [09:37:07.858]
INFO:
cli Aztec Server listening on port 8080
{"l2CircuitsVkTreeRoot":"0x2d2655c513c2e8feb8c7411b7e807c66287b57bb60572ce85ce1fe0a1e0c925d","l2ProtocolContractsTreeRoot":"0x222b5a95b10e0a2dfba4648739629a01cecd69a0bef23a9fc22f846735d7cc71"}
09:37:14 playground-1 | \
09:37:14 playground-1 |
Running 1
test using 1
worker
09:37:14 playground-1 |
09:37:15 playground-1 |
✓ 1 [chromium] › browser.spec.ts:3:1 › test
(6.4s)
09:37:21 playground-1 |
1 passed (8.5s)
09:37:22 playground-1 | \
09:37:22
playground-1 exited with code 0
09:37:22 Aborting on container exit...
09:37:22 Container playground-chromium-playground-1 Stopping
09:37:22 Container playground-chromium-playground-1 Stopped
09:37:22 Container playground-chromium-aztec-1 Stopping
09:37:23 Container playground-chromium-aztec-1 Stopped
09:37:23 Container playground-chromium-ethereum-1 Stopping
09:37:24 Container playground-chromium-ethereum-1 Stopped