Parent Log:
http://ci.aztec-labs.com/cfa3a08d8c538c28
Command: 096728d2a1186953 playground/scripts/run_test.sh chromium
Commit:
https://github.com/AztecProtocol/aztec-packages/commit/30660269b33bab8cca354c41659533acf4d48e07
Env: REF_NAME=gh-readonly-queue/next/pr-15026-d96baf1c44329e8b2e3a432ad803f702f5184a62 CURRENT_VERSION=0.87.6 CI_FULL=0
Date: Fri Jun 13 09:37:20 UTC 2025
System: ARCH=arm64 CPUS=64 MEM=247Gi HOSTNAME=pr-15026_arm64_a1-fast
Resources: CPU_LIST=0-63 CPUS=2 MEM=8g TIMEOUT=600s
History:
http://ci.aztec-labs.com/list/history_3af8716c4dfeb01b_next
09:37:20 Network playground-chromium_default Creating
09:37:20 Network playground-chromium_default Created
09:37:20 Container playground-chromium-ethereum-1 Creating
09:37:20 Container playground-chromium-ethereum-1 Created
09:37:20 Container playground-chromium-aztec-1 Creating
09:37:20 Container playground-chromium-aztec-1 Created
09:37:20 Container playground-chromium-playground-1 Creating
09:37:20 Container playground-chromium-playground-1 Created
09:37:20 Attaching to aztec-1, ethereum-1, playground-1
09:37:24 aztec-1 |
09:37:24 aztec-1 | _
09:37:24 aztec-1 | /\ | |
09:37:24 aztec-1 | / \ ___| |_ ___ ___
09:37:24 aztec-1 | / /\ \ |_ / __/ _ \/ __|
09:37:24 aztec-1 | / ____ \ / /| || __/ (__
09:37:24 aztec-1 | /_/___ \_\/___|\__\___|\___|
09:37:24 aztec-1 |
09:37:24 aztec-1 |
https://github.com/AztecProtocol
09:37:24 aztec-1 |
09:37:24 aztec-1 |
09:37:24 aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by...
09:37:25 aztec-1 | Setting up test accounts
09:37:26 aztec-1 | [09:37:25.599]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-PWKSZ7/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
09:37:26 aztec-1 | [09:37:25.868]
INFO:
sandbox Calling anvil_setBlockTimestampInterval with params: [12]
09:37:26 aztec-1 | [09:37:25.872]
WARN:
sandbox Set block interval to 12
09:37:26 aztec-1 | [09:37:26.042]
INFO:
sandbox Waiting for 7 transactions to be mined...
09:37:26 aztec-1 | [09:37:26.065]
INFO:
sandbox All transactions mined successfully
09:37:26 aztec-1 | [09:37:26.141]
INFO:
sandbox Waiting for 9 transactions to be mined...
09:37:26 aztec-1 | [09:37:26.161]
INFO:
sandbox All transactions mined successfully
09:37:26 aztec-1 | [09:37:26.290]
INFO:
sandbox Waiting for 11 transactions to be mined...
09:37:26 aztec-1 | [09:37:26.312]
INFO:
sandbox All transactions mined successfully
09:37:26 aztec-1 | [09:37:26.370]
INFO:
sandbox Waiting for 12 transactions to be mined...
09:37:26 aztec-1 | [09:37:26.392]
INFO:
sandbox All transactions mined successfully
09:37:26 aztec-1 | [09:37:26.397]
INFO:
sandbox Waiting for 12 transactions to be mined...
09:37:26 aztec-1 | [09:37:26.416]
INFO:
sandbox All transactions mined successfully
09:37:26 aztec-1 | [09:37:26.430]
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:37:26 aztec-1 | [09:37:26.431]
INFO:
sandbox Handing over to governance
09:37:26 aztec-1 | [09:37:26.438]
INFO:
aztecjs:utils:watcher Watcher started for rollup at 0x3Aa5ebB10DC797CAC828524e59A333d0A371443c
09:37:26 aztec-1 | [09:37:26.438]
INFO:
telemetry:client Using NoopTelemetryClient
09:37:26 aztec-1 | [09:37:26.438]
INFO:
blob-sink:client Creating local blob sink client.
09:37:26 aztec-1 | [09:37:26.442]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:37:26 aztec-1 | [09:37:26.442]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:42:09.000Z
{"offset":282558,"timeMs":1749807729000}
09:37:26 aztec-1 | [09:37:26.464]
INFO:
archiver:lmdb Starting data store with maxReaders 16
09:37:26 aztec-1 | [09:37:26.787]
INFO:
archiver Starting archiver sync to rollup contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c from L1 block 20 to current L1 block 24 with hash 0x993c1344d92f14fdb668a5b091a4ebbc301f4e54e85127cf946f6af2a397c058
{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0xf73397cb280291115b9e993313dcebcd0fc49f09befa649b1e2bd80f33e37737","l1BlockNumber":20}}
09:37:26 aztec-1 | [09:37:26.807]
INFO:
archiver Initial archiver sync to L1 block 24 complete.
{"l1BlockNumber":24,"syncPoint":{"blocksSynchedTo":24,"messagesSynchedTo":{"l1BlockHash":"0x993c1344d92f14fdb668a5b091a4ebbc301f4e54e85127cf946f6af2a397c058","l1BlockNumber":24}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}}
09:37:27 aztec-1 | [09:37:26.813]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-khN7LF/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
09:37:27 aztec-1 | [09:37:27.036]
INFO:
world_state Created world state synchroniser with block history of 64
09:37:27 aztec-1 | [09:37:27.036]
WARN:
node Aztec node is accepting fake proofs
09:37:27 aztec-1 | [09:37:27.044]
INFO:
p2p:lmdb-v2 Starting data store with maxReaders 16
09:37:27 aztec-1 | [09:37:27.060]
INFO:
p2p-archive:lmdb-v2 Starting data store with maxReaders 16
09:37:27 aztec-1 | [09:37:27.072]
INFO:
p2p-peer:lmdb-v2 Starting data store with maxReaders 16
09:37:27 aztec-1 | [09:37:27.085]
INFO:
p2p:tx_pool Setting maximum tx mempool size
{"maxTxPoolSize":100000000}
09:37:27 aztec-1 | [09:37:27.087]
INFO:
world_state Started world state synchronizer from block 1
09:37:27 aztec-1 | [09:37:27.090]
INFO:
p2p Starting p2p client from block 0 with empty mempools
09:37:27 aztec-1 | [09:37:27.287]
INFO:
epoch-prune-watcher EpochPruneWatcher initialized
09:37:27 aztec-1 | [09:37:27.332]
INFO:
slasher Starting Slasher client...
09:37:27 aztec-1 | [09:37:27.341]
INFO:
sequencer-client Deploying forwarder contract
09:37:27 aztec-1 | [09:37:27.359]
INFO:
sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac
09:37:27 aztec-1 | [09:37:27.365]
INFO:
validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
09:37:27 aztec-1 | [09:37:27.365]
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:37:27 aztec-1 | [09:37:27.366]
INFO:
sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
09:37:27 aztec-1 | [09:37:27.367]
INFO:
node Aztec Node version: 0.87.6
09:37:27 aztec-1 | [09:37:27.367]
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:37:27 aztec-1 | [09:37:27.372]
INFO:
pxe:data:lmdb Starting data store with maxReaders 16
09:37:27 aztec-1 | [09:37:27.375]
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:37:27 aztec-1 | [09:37:27.434]
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:37:27 aztec-1 | [09:37:27.564]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:37:27 aztec-1 | [09:37:27.564]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:42:21.000Z
{"offset":293436,"timeMs":1749807741000}
09:37:27 aztec-1 | Setting up funded test accounts...
09:37:27 aztec-1 | [09:37:27.676]
INFO:
pxe:service Started PXE connected to chain 31337 version 3326517204
09:37:27 aztec-1 | [09:37:27.756]
INFO:
pxe:service Registered account 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:37:27 aztec-1 | [09:37:27.811]
WARN:
pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata
09:37:27 aztec-1 | [09:37:27.815]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:37:27 aztec-1 | [09:37:27.948]
INFO:
pxe:service Added contract SchnorrAccount at 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:37:30 aztec-1 | [09:37:29.955]
INFO:
pxe:service Proving completed in 1977.012694ms
{"timings":{"total":1977.012694,"sync":3.968116000000009,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":116.49861399999827,"oracles":{"callPrivateFunction":{"times":[531.9316400000007,263.9843409999994,154.83461499999976]}}},{"functionName":"private_kernel_init","time":84.2761929999997},{"functionName":"ContractClassRegisterer:register","time":518.2248470000004,"oracles":{"loadCapsule":{"times":[8.504329000000325]},"notifyCreatedNullifier":{"times":[0.5100529999999708]},"debugLog":{"times":[0.3520429999998669]},"notifyCreatedContractClassLog":{"times":[4.633767000000262]}}},{"functionName":"private_kernel_inner","time":110.4440110000005},{"functionName":"SchnorrAccount:constructor","time":244.96134000000075,"oracles":{"getContractInstance":{"times":[1.7307739999996556]},"debugLog":{"times":[0.1425939999999173,0.11607600000024831]},"fetchTaggedLogs":{"times":[44.461701999999605]},"loadCapsule":{"times":[0.364477000000079,0.26343999999971857,0.18600599999990663]},"validateEnqueuedNotesAndEvents":{"times":[6.098133000000416]},"notifyCreatedNullifier":{"times":[0.2460060000003068,0.2412880000001678]},"notifyCreatedNote":{"times":[0.6127890000007028]},"getRandomField":{"times":[0.2383799999997791,0.14281099999971048,0.10817799999949784,0.10687800000050629,0.2743959999997969,0.09225900000001275,0.07940299999972922,0.06343000000015309,0.0704409999998461,0.07987799999955314,0.08190100000047096]},"getIndexedTaggingSecretAsSender":{"times":[42.42822400000023]},"incrementAppTaggingSecretIndexAsSender":{"times":[21.16722000000027]}}},{"functionName":"private_kernel_inner","time":96.4547279999997},{"functionName":"SchnorrAccount:entrypoint","time":135.899096000001,"oracles":{"debugLog":{"times":[0.14769299999989016,0.11554100000012113,0.22758799999974144]},"fetchTaggedLogs":{"times":[37.3188339999997]},"loadCapsule":{"times":[0.19611600000007456,0.16132000000015978,0.17096099999980652]},"validateEnqueuedNotesAndEvents":{"times":[5.790628999999171]},"getNotes":{"times":[1.462160999999469]},"getAuthWitness":{"times":[0.3280220000006011]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.7351129999997283]}}},{"functionName":"private_kernel_inner","time":95.46238700000049},{"functionName":"private_kernel_reset","time":163.52800899999966},{"functionName":"private_kernel_tail","time":63.09076699999969}],"unaccounted":344.20458599999984}}
09:37:30 aztec-1 | [09:37:30.050]
INFO:
node Received tx 0x1e22e13d0a16004ceba192ce3b8e90aa25348cf3082fc8b006fe2138613085fa
{"txHash":"0x1e22e13d0a16004ceba192ce3b8e90aa25348cf3082fc8b006fe2138613085fa"}
09:37:30 aztec-1 | [09:37:30.050]
INFO:
pxe:service Sent transaction 0x1e22e13d0a16004ceba192ce3b8e90aa25348cf3082fc8b006fe2138613085fa
09:37:30 aztec-1 | [09:37:30.134]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.029365832999999838s
{"duration":0.029365832999999838,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344}
09:37:30 aztec-1 | [09:37:30.157]
INFO:
sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x291f3e235e29fb8150296037c418951bbdc3aa85af29f54403d8f842e1a788ac","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807753,"version":3326517204},"txHashes":["0x1e22e13d0a16004ceba192ce3b8e90aa25348cf3082fc8b006fe2138613085fa"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":60.24088699999993,"publicProcessDuration":29.78092900000047,"rollupCircuitsDuration":54.205486999999266,"txCount":1,"blockNumber":1,"blockTimestamp":1749807753,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:37:33 aztec-1 | [09:37:32.863]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1
09:37:33 aztec-1 | [09:37:32.865]
INFO:
aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1.
09:37:33 aztec-1 | [09:37:32.866]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:37:33 aztec-1 | [09:37:32.944]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:37:33 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)"
09:37:33 aztec-1 | [09:37:32.944]
WARN:
sequencer Cannot propose block 1 at slot 2 due to failed rollup contract check
09:37:33 aztec-1 | [09:37:32.944]
INFO:
archiver Downloaded L2 block 1
{"blockHash":"0x291f3e235e29fb8150296037c418951bbdc3aa85af29f54403d8f842e1a788ac","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807753,"version":3326517204},"archiveRoot":"0x2fd79c45cafa0e217e3a96df011f57b4bfdaba17f78fb044af9662dbbe102ff4","archiveNextLeafIndex":2}
09:37:33 aztec-1 | [09:37:32.950]
INFO:
archiver Updated proven chain to block 1
{"provenBlockNumber":1}
09:37:33 aztec-1 | [09:37:33.004]
INFO:
world_state World state updated with L2 block 1
{"eventName":"l2-block-handled","duration":16.513035000001764,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749807753,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:37:33 aztec-1 | [09:37:33.073]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:37:33 aztec-1 | [09:37:33.073]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:42:33.000Z
{"offset":299927,"timeMs":1749807753000}
09:37:33 aztec-1 | [09:37:33.100]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:37:33 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)"
09:37:33 aztec-1 | [09:37:33.100]
WARN:
sequencer Cannot propose block 2 at slot 2 due to failed rollup contract check
09:37:33 aztec-1 | [09:37:33.101]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807789
09:37:33 aztec-1 | [09:37:33.101]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:43:09.000Z
{"offset":335899,"timeMs":1749807789000}
09:37:33 aztec-1 | [09:37:33.101]
INFO:
aztecjs:utils:watcher Slot 2 was filled, jumped to next slot
09:37:33 aztec-1 | [09:37:33.123]
INFO:
pxe:service Registered account 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
09:37:33 aztec-1 | [09:37:33.223]
INFO:
pxe:service Added contract SchnorrAccount at 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:37:35 aztec-1 | [09:37:34.472]
INFO:
pxe:service Proving completed in 1229.966260000001ms
{"timings":{"total":1229.966260000001,"sync":27.692332000000533,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":88.73119000000042,"oracles":{"callPrivateFunction":{"times":[278.3646979999994,177.5484780000006]}}},{"functionName":"private_kernel_init","time":48.742104000000836},{"functionName":"SchnorrAccount:constructor","time":257.8257719999983,"oracles":{"getContractInstance":{"times":[2.818875000000844]},"debugLog":{"times":[0.1389709999984916,0.08870999999999185]},"fetchTaggedLogs":{"times":[68.13731299999927]},"loadCapsule":{"times":[0.28426000000035856,0.3696139999992738,0.18703800000002957]},"validateEnqueuedNotesAndEvents":{"times":[5.312486999999237]},"notifyCreatedNullifier":{"times":[0.2609730000003765,0.2556120000008377]},"notifyCreatedNote":{"times":[0.0986720000000787]},"getRandomField":{"times":[0.1035570000003645,0.14169000000038068,0.07454599999982747,0.08010199999989709,0.07853799999975308,0.05865100000119128,0.057425000000876025,0.052089999999225256,0.05682700000033947,0.05313600000044971,0.05930199999966135]},"getIndexedTaggingSecretAsSender":{"times":[48.50030700000025]},"incrementAppTaggingSecretIndexAsSender":{"times":[23.52097800000047]}}},{"functionName":"private_kernel_inner","time":94.34961600000133},{"functionName":"SchnorrAccount:entrypoint","time":158.33982600000127,"oracles":{"debugLog":{"times":[0.13983200000075158,0.09086999999999534,0.12279599999965285]},"fetchTaggedLogs":{"times":[62.11101000000053]},"loadCapsule":{"times":[0.20317200000135927,0.30027200000040466,0.38269400000172027]},"validateEnqueuedNotesAndEvents":{"times":[7.838958000000275]},"getNotes":{"times":[0.9310260000002017]},"getAuthWitness":{"times":[0.15441699999973935]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.3766139999988809]}}},{"functionName":"private_kernel_inner","time":94.55781000000025},{"functionName":"private_kernel_reset","time":159.14718700000049},{"functionName":"private_kernel_tail","time":65.22922799999833}],"unaccounted":235.35119499999928}}
09:37:35 aztec-1 | [09:37:34.519]
INFO:
node Received tx 0x00468809deaea0d47552ff08c09cda9019b3808564bf808f960a1bf5fd434188
{"txHash":"0x00468809deaea0d47552ff08c09cda9019b3808564bf808f960a1bf5fd434188"}
09:37:35 aztec-1 | [09:37:34.519]
INFO:
pxe:service Sent transaction 0x00468809deaea0d47552ff08c09cda9019b3808564bf808f960a1bf5fd434188
09:37:35 aztec-1 | [09:37:34.610]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.013853944000000411s
{"duration":0.013853944000000411,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:37:35 aztec-1 | [09:37:34.630]
INFO:
sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x1bbe16ebd34e1f3f79e19144b89c8efac6f078a123ed61b5ce234d4f0b1ff20c","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807789,"version":3326517204},"txHashes":["0x00468809deaea0d47552ff08c09cda9019b3808564bf808f960a1bf5fd434188"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":39.84672800000044,"publicProcessDuration":13.938553999998476,"rollupCircuitsDuration":35.257800999999745,"txCount":1,"blockNumber":2,"blockTimestamp":1749807789,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:37:35 aztec-1 | [09:37:34.808]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:37:35 aztec-1 | [09:37:34.811]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807825
09:37:35 aztec-1 | [09:37:34.811]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:43:45.000Z
{"offset":370189,"timeMs":1749807825000}
09:37:35 aztec-1 | [09:37:34.811]
INFO:
aztecjs:utils:watcher Slot 3 was filled, jumped to next slot
09:37:35 aztec-1 | [09:37:34.817]
WARN:
archiver Failed to reach L2 block 2 at 27 (latest is 1). Rolling back last synched L1 block number to 26.
{"latestLocalL2BlockNumber":1,"latestLocalL2BlockArchive":"0x2fd79c45cafa0e217e3a96df011f57b4bfdaba17f78fb044af9662dbbe102ff4","blocksSynchedTo":26,"currentL1BlockNumber":27,"provenBlockNumber":1,"provenArchive":"0x2fd79c45cafa0e217e3a96df011f57b4bfdaba17f78fb044af9662dbbe102ff4","pendingBlockNumber":2,"pendingArchive":"0x1ef3ba1b574ed2ecfbbf6fe5c9baa340d3853afd791e606fdd53cecd9dc277b5"}
09:37:35 aztec-1 | [09:37:34.896]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2
09:37:35 aztec-1 | [09:37:34.897]
INFO:
aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2.
09:37:35 aztec-1 | [09:37:34.914]
INFO:
archiver Downloaded L2 block 2
{"blockHash":"0x1bbe16ebd34e1f3f79e19144b89c8efac6f078a123ed61b5ce234d4f0b1ff20c","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807789,"version":3326517204},"archiveRoot":"0x1ef3ba1b574ed2ecfbbf6fe5c9baa340d3853afd791e606fdd53cecd9dc277b5","archiveNextLeafIndex":3}
09:37:35 aztec-1 | [09:37:34.962]
INFO:
world_state World state updated with L2 block 2
{"eventName":"l2-block-handled","duration":13.9707870000002,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749807789,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:37:35 aztec-1 | [09:37:34.982]
INFO:
archiver Updated proven chain to block 2
{"provenBlockNumber":2}
09:37:35 aztec-1 | [09:37:35.067]
INFO:
pxe:service Registered account 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
09:37:35 aztec-1 | [09:37:35.167]
INFO:
pxe:service Added contract SchnorrAccount at 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:37:36 aztec-1 | Initial Accounts:
09:37:36 aztec-1 |
09:37:36 aztec-1 | Address: 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:37:36 aztec-1 | Partial Address: 0x27b4888363d0f9eb0d43aa0b2ff08f21fbc6867b8e0baf8ec6062c4623c1eefc
09:37:36 aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281
09:37:36 aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f
09:37:36 aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f
09:37:36 aztec-1 |
09:37:36 aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5
09:37:36 aztec-1 |
09:37:36 aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89
09:37:36 aztec-1 |
09:37:36 aztec-1 | Address: 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
09:37:36 aztec-1 | Partial Address: 0x2e19b56de1617cb3179d81be0173c7301621a2e820da7f2754fcfccdfc4418e0
09:37:36 aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae
09:37:36 aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57
09:37:36 aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d
09:37:36 aztec-1 |
09:37:36 aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e
09:37:36 aztec-1 |
09:37:36 aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d
09:37:36 aztec-1 |
09:37:36 aztec-1 | Address: 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
09:37:36 aztec-1 | Partial Address: 0x2451b67547512eaaaad5ded43156c5b80220b7d5145ebefdd57295a9e05a610d
09:37:36 aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c
09:37:36 aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3
09:37:36 aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae
09:37:36 aztec-1 |
09:37:36 aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da
09:37:36 aztec-1 |
09:37:36 aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba
09:37:36 aztec-1 |
09:37:36 aztec-1 |
09:37:37 aztec-1 | [09:37:36.377]
INFO:
pxe:service Proving completed in 1202.020571000001ms
{"timings":{"total":1202.020571000001,"sync":19.928830000000744,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":67.39294800000425,"oracles":{"callPrivateFunction":{"times":[277.0607409999993,191.0266470000006]}}},{"functionName":"private_kernel_init","time":49.49199799999951},{"functionName":"SchnorrAccount:constructor","time":257.10154899999725,"oracles":{"getContractInstance":{"times":[1.860977999998795]},"debugLog":{"times":[0.11227899999903457,0.06784500000139815]},"fetchTaggedLogs":{"times":[82.85809900000095]},"loadCapsule":{"times":[0.15548599999965518,0.15024499999890395,0.1412079999990965]},"validateEnqueuedNotesAndEvents":{"times":[5.11319100000037]},"notifyCreatedNullifier":{"times":[0.2006920000003447,0.22862799999893468]},"notifyCreatedNote":{"times":[0.10876099999950384]},"getRandomField":{"times":[0.09494599999925413,0.11851600000045437,0.08368300000074669,0.062022000000069966,0.06470099999933154,0.0550070000008418,0.060613999999986845,0.05160799999976007,0.0553330000002461,0.05361000000084459,0.049418999999033986]},"getIndexedTaggingSecretAsSender":{"times":[24.310959000000366]},"incrementAppTaggingSecretIndexAsSender":{"times":[27.640172000001257]}}},{"functionName":"private_kernel_inner","time":94.37203799999952},{"functionName":"SchnorrAccount:entrypoint","time":172.05853999999817,"oracles":{"debugLog":{"times":[0.11331699999936973,0.0684320000000298,0.11376399999971909]},"fetchTaggedLogs":{"times":[70.40822900000057]},"loadCapsule":{"times":[0.17103700000006938,0.13364000000001397,0.118492999999944]},"validateEnqueuedNotesAndEvents":{"times":[4.898502000000008]},"getNotes":{"times":[0.8988119999994524]},"getAuthWitness":{"times":[0.14020499999969616]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.3223949999992328]}}},{"functionName":"private_kernel_inner","time":93.7351090000011},{"functionName":"private_kernel_reset","time":159.1042980000002},{"functionName":"private_kernel_tail","time":65.12195999999858}],"unaccounted":223.7133010000016}}
09:37:37 aztec-1 | [09:37:36.428]
INFO:
node Received tx 0x0bcb280757535b14b0f85299c5b42cf3219f1180fe31ae42cf968d0c016acda8
{"txHash":"0x0bcb280757535b14b0f85299c5b42cf3219f1180fe31ae42cf968d0c016acda8"}
09:37:37 aztec-1 | [09:37:36.428]
INFO:
pxe:service Sent transaction 0x0bcb280757535b14b0f85299c5b42cf3219f1180fe31ae42cf968d0c016acda8
09:37:37 aztec-1 | [09:37:36.504]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.013724887000000309s
{"duration":0.013724887000000309,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:37:37 aztec-1 | [09:37:36.523]
INFO:
sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x2d614b34edbd7e90f6999084d962de815c5685cd186d9a389c6ba3655f9ec80f","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807825,"version":3326517204},"txHashes":["0x0bcb280757535b14b0f85299c5b42cf3219f1180fe31ae42cf968d0c016acda8"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":38.31475099999989,"publicProcessDuration":13.810396999999284,"rollupCircuitsDuration":33.577947000001586,"txCount":1,"blockNumber":3,"blockTimestamp":1749807825,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:37:37 aztec-1 | [09:37:36.688]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3
09:37:37 aztec-1 | [09:37:36.691]
INFO:
aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3.
09:37:37 aztec-1 | [09:37:36.696]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:37:37 aztec-1 | [09:37:36.697]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807861
09:37:37 aztec-1 | [09:37:36.697]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:44:21.000Z
{"offset":404303,"timeMs":1749807861000}
09:37:37 aztec-1 | [09:37:36.697]
INFO:
aztecjs:utils:watcher Slot 4 was filled, jumped to next slot
09:37:37 aztec-1 | [09:37:36.781]
INFO:
archiver Downloaded L2 block 3
{"blockHash":"0x2d614b34edbd7e90f6999084d962de815c5685cd186d9a389c6ba3655f9ec80f","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807825,"version":3326517204},"archiveRoot":"0x0fc319c26a3542587b846e607ecfc491b6874ee4e5c5acbd5c4f427b31b959e0","archiveNextLeafIndex":4}
09:37:37 aztec-1 | [09:37:36.784]
INFO:
archiver Updated proven chain to block 3
{"provenBlockNumber":3}
09:37:37 aztec-1 | [09:37:36.819]
INFO:
world_state World state updated with L2 block 3
{"eventName":"l2-block-handled","duration":13.200972000000547,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749807825,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:37:37 aztec-1 | [09:37:37.502]
WARN:
pxe:service No artifact found for contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 when looking for its metadata
09:37:37 aztec-1 | [09:37:37.503]
WARN:
pxe:service No artifact found for contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 when looking for its metadata
09:37:37 aztec-1 | [09:37:37.504]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 as part of deployment for 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
09:37:37 aztec-1 | [09:37:37.529]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 as part of deployment for 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
09:37:38 aztec-1 | [09:37:38.204]
INFO:
pxe:service Added contract Token at 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf with class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645
09:37:38 aztec-1 | [09:37:38.207]
INFO:
pxe:service Added contract FPC at 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 with class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1
09:37:40 aztec-1 | [09:37:39.910]
INFO:
pxe:service Proving completed in 1683.3426809999983ms
{"timings":{"total":1683.3426809999983,"sync":15.425791000001482,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":246.47183600000062,"oracles":{"debugLog":{"times":[0.13373399999909452,0.08147399999870686,0.07572500000242144,0.1315969999996014,0.09474300000147196,0.07591599999796017,0.06876800000100047,0.14196000000083586]},"fetchTaggedLogs":{"times":[78.65308799999912]},"loadCapsule":{"times":[0.18151599999691825,1.1066050000008545,0.27582799999800045,0.21679199999925913,0.16257799999948475,0.15286299999934272,0.1471640000017942]},"getSharedSecret":{"times":[15.493728000001283]},"aes128Decrypt":{"times":[0.8968109999987064,0.6030370000007679]},"getPublicKeysAndPartialAddress":{"times":[4.79056700000001]},"getKeyValidationRequest":{"times":[3.3428789999998116]},"storeCapsule":{"times":[2.759949000002962,2.544034999998985,2.1697339999991527]},"deleteCapsule":{"times":[2.460244000001694]},"validateEnqueuedNotesAndEvents":{"times":[9.956448000000819]},"getNotes":{"times":[1.5715669999990496]},"getAuthWitness":{"times":[0.15288499999951455]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.03987399999823538]},"callPrivateFunction":{"times":[452.39229500000147,96.07601000000068]},"notifyEnqueuedPublicFunctionCall":{"times":[0.2610180000010587]}}},{"functionName":"private_kernel_init","time":46.974890000001324},{"functionName":"ContractClassRegisterer:register","time":442.03629199999705,"oracles":{"loadCapsule":{"times":[2.2508530000013707]},"notifyCreatedNullifier":{"times":[0.23761999999987893]},"debugLog":{"times":[0.12700699999913923]},"notifyCreatedContractClassLog":{"times":[3.4141989999989164]}}},{"functionName":"private_kernel_inner","time":98.85239899999942},{"functionName":"ContractInstanceDeployer:deploy","time":61.7910890000021,"oracles":{"storeInExecutionCache":{"times":[0.1639710000017658]},"callPrivateFunction":{"times":[35.4214209999991]},"notifyCreatedNullifier":{"times":[0.21898199999850476]},"debugLog":{"times":[0.12496800000008079]}}},{"functionName":"private_kernel_inner","time":93.47505100000126},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":23.970398999997997,"oracles":{}},{"functionName":"private_kernel_inner","time":93.61537699999826},{"functionName":"private_kernel_reset","time":164.1215779999984},{"functionName":"private_kernel_tail","time":78.61710499999754}],"unaccounted":317.9908740000028}}
09:37:40 aztec-1 | [09:37:40.081]
INFO:
node Received tx 0x057b75baa843c44ded362992e5c72e9b45c906b0ecc947b8f2ebe9133f3651eb
{"txHash":"0x057b75baa843c44ded362992e5c72e9b45c906b0ecc947b8f2ebe9133f3651eb"}
09:37:40 aztec-1 | [09:37:40.081]
INFO:
pxe:service Sent transaction 0x057b75baa843c44ded362992e5c72e9b45c906b0ecc947b8f2ebe9133f3651eb
09:37:42 aztec-1 | [09:37:41.889]
INFO:
pxe:service Proving completed in 1977.9350630000008ms
{"timings":{"total":1977.9350630000008,"sync":121.70149800000218,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":441.81118600000264,"oracles":{"debugLog":{"times":[0.11025800000061281,0.08645099999921513,0.08286000000225613,0.14310200000181794,0.08700299999691197,0.06665799999973387,0.07547600000179955,0.1300840000003518]},"fetchTaggedLogs":{"times":[166.73418599999786]},"loadCapsule":{"times":[0.2869109999992361,0.3044179999997141,1.1127710000000661,0.35970300000190036,1.501589999999851,0.4158449999995355,0.3136190000004717]},"getSharedSecret":{"times":[8.713313000000198]},"aes128Decrypt":{"times":[0.19170500000109314,0.5442759999968985]},"getPublicKeysAndPartialAddress":{"times":[2.855914000003395]},"getKeyValidationRequest":{"times":[3.1754500000024564]},"storeCapsule":{"times":[56.328160999997635,5.472577000000456,2.978121000000101]},"deleteCapsule":{"times":[2.4735380000020086]},"validateEnqueuedNotesAndEvents":{"times":[42.23027799999909]},"getNotes":{"times":[15.236664000000019]},"getAuthWitness":{"times":[0.1775420000012673]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.044965000000956934]},"callPrivateFunction":{"times":[458.79620799999975,103.00851599999805]},"notifyEnqueuedPublicFunctionCall":{"times":[0.07323099999848637]}}},{"functionName":"private_kernel_init","time":46.33377499999915},{"functionName":"ContractClassRegisterer:register","time":448.3284730000014,"oracles":{"loadCapsule":{"times":[1.7458909999986645]},"notifyCreatedNullifier":{"times":[0.28051200000118115]},"debugLog":{"times":[0.11251300000003539]},"notifyCreatedContractClassLog":{"times":[3.508772000001045]}}},{"functionName":"private_kernel_inner","time":100.61965600000258},{"functionName":"ContractInstanceDeployer:deploy","time":61.54441799999768,"oracles":{"storeInExecutionCache":{"times":[0.06989700000121957]},"callPrivateFunction":{"times":[38.71143800000209]},"notifyCreatedNullifier":{"times":[0.23697099999844795]},"debugLog":{"times":[0.11803100000179256]}}},{"functionName":"private_kernel_inner","time":95.94472900000255},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":28.92112999999881,"oracles":{}},{"functionName":"private_kernel_inner","time":98.90566100000069},{"functionName":"private_kernel_reset","time":166.82456499999898},{"functionName":"private_kernel_tail","time":75.23954099999901}],"unaccounted":291.76043099999515}}
09:37:42 aztec-1 | [09:37:41.926]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 1.7453760670000011s
{"duration":1.7453760670000011,"rate":189901.19451431654,"totalPublicGas":{"daGas":10240,"l2Gas":331449},"totalBlockGas":{"daGas":380928,"l2Gas":356491},"totalSizeInBytes":24064}
09:37:42 aztec-1 | [09:37:42.034]
INFO:
sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 177759.5729687426 mana/s
{"blockHash":"0x2e1834f136dbf0e3b9bfbd7358aa9c546d51e9aed9e3a94668aa9871052326cb","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807861,"version":3326517204},"txHashes":["0x057b75baa843c44ded362992e5c72e9b45c906b0ecc947b8f2ebe9133f3651eb"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":1864.5331279999991,"publicProcessDuration":1745.4668139999994,"rollupCircuitsDuration":1855.3893419999986,"txCount":1,"blockNumber":4,"blockTimestamp":1749807861,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
09:37:42 aztec-1 | [09:37:42.072]
INFO:
node Received tx 0x22a84baa565fadb6cf74d42f687eb95d4b99fb4ea67cb352f5ec45cd8d441863
{"txHash":"0x22a84baa565fadb6cf74d42f687eb95d4b99fb4ea67cb352f5ec45cd8d441863"}
09:37:42 aztec-1 | [09:37:42.072]
INFO:
pxe:service Sent transaction 0x22a84baa565fadb6cf74d42f687eb95d4b99fb4ea67cb352f5ec45cd8d441863
09:37:42 aztec-1 | [09:37:42.242]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4
09:37:42 aztec-1 | [09:37:42.245]
INFO:
aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4.
09:37:42 aztec-1 | [09:37:42.249]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:37:42 aztec-1 | [09:37:42.250]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807897
09:37:42 aztec-1 | [09:37:42.250]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:44:57.000Z
{"offset":434750,"timeMs":1749807897000}
09:37:42 aztec-1 | [09:37:42.250]
INFO:
aztecjs:utils:watcher Slot 5 was filled, jumped to next slot
09:37:42 aztec-1 | [09:37:42.394]
INFO:
archiver Downloaded L2 block 4
{"blockHash":"0x2e1834f136dbf0e3b9bfbd7358aa9c546d51e9aed9e3a94668aa9871052326cb","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807861,"version":3326517204},"archiveRoot":"0x11dee8f6905fb64b3dbfa8511ea6f71ded9cb2ea9e8269048af9f237cf3f59ad","archiveNextLeafIndex":5}
09:37:42 aztec-1 | [09:37:42.398]
INFO:
archiver Updated proven chain to block 4
{"provenBlockNumber":4}
09:37:42 aztec-1 | [09:37:42.456]
INFO:
world_state World state updated with L2 block 4
{"eventName":"l2-block-handled","duration":11.843100999998569,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749807861,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
09:37:42 aztec-1 | [09:37:42.642]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.12425096399999894s
{"duration":0.12425096399999894,"rate":332102.05113579927,"totalPublicGas":{"daGas":4096,"l2Gas":41264},"totalBlockGas":{"daGas":166912,"l2Gas":66306},"totalSizeInBytes":10688}
09:37:42 aztec-1 | [09:37:42.679]
INFO:
sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 247043.1306868816 mana/s
{"blockHash":"0x1f05b34b2180bc590f23c3194e3d26299089d1753fb383c8523bf93ad5302eee","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807897,"version":3326517204},"txHashes":["0x22a84baa565fadb6cf74d42f687eb95d4b99fb4ea67cb352f5ec45cd8d441863"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":167.00793200000044,"publicProcessDuration":124.76295000000027,"rollupCircuitsDuration":162.46299900000304,"txCount":1,"blockNumber":5,"blockTimestamp":1749807897,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
09:37:42 aztec-1 | [09:37:42.869]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:37:42 aztec-1 | [09:37:42.876]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5
09:37:42 aztec-1 | [09:37:42.878]
INFO:
aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5.
09:37:42 aztec-1 | [09:37:42.888]
WARN:
archiver Failed to reach L2 block 5 at 33 (latest is 4). Rolling back last synched L1 block number to 32.
{"latestLocalL2BlockNumber":4,"latestLocalL2BlockArchive":"0x11dee8f6905fb64b3dbfa8511ea6f71ded9cb2ea9e8269048af9f237cf3f59ad","blocksSynchedTo":32,"currentL1BlockNumber":33,"provenBlockNumber":4,"provenArchive":"0x11dee8f6905fb64b3dbfa8511ea6f71ded9cb2ea9e8269048af9f237cf3f59ad","pendingBlockNumber":5,"pendingArchive":"0x05cef8c750064ca3fa5a6ea07bfde1fc39d26222d81dd9c466df7be639a3e2ed"}
09:37:42 aztec-1 | [09:37:42.889]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807933
09:37:42 aztec-1 | [09:37:42.889]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:45:33.000Z
{"offset":470111,"timeMs":1749807933000}
09:37:42 aztec-1 | [09:37:42.889]
INFO:
aztecjs:utils:watcher Slot 6 was filled, jumped to next slot
09:37:43 aztec-1 | [09:37:43.005]
INFO:
archiver Downloaded L2 block 5
{"blockHash":"0x1f05b34b2180bc590f23c3194e3d26299089d1753fb383c8523bf93ad5302eee","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807897,"version":3326517204},"archiveRoot":"0x05cef8c750064ca3fa5a6ea07bfde1fc39d26222d81dd9c466df7be639a3e2ed","archiveNextLeafIndex":6}
09:37:43 aztec-1 | [09:37:43.009]
INFO:
archiver Updated proven chain to block 5
{"provenBlockNumber":5}
09:37:43 aztec-1 | [09:37:43.025]
INFO:
world_state World state updated with L2 block 5
{"eventName":"l2-block-handled","duration":10.631600000000617,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749807897,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
09:37:43 aztec-1 | [09:37:43.107]
INFO:
aztecjs:deploy_sent_tx Contract 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 successfully deployed.
09:37:43 aztec-1 | BananaCoin: 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
09:37:43 aztec-1 | FPC: 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
09:37:43 aztec-1 | [09:37:43.411]
INFO:
aztecjs:deploy_sent_tx Contract 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf successfully deployed.
09:37:43 aztec-1 | [09:37:43.438]
WARN:
pxe:service No artifact found for contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e when looking for its metadata
09:37:43 aztec-1 | [09:37:43.441]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e as part of deployment for 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
09:37:43 aztec-1 | [09:37:43.555]
INFO:
pxe:service Added contract SponsoredFPC at 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 with class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e
09:37:45 aztec-1 | [09:37:45.209]
INFO:
pxe:service Proving completed in 1639.073852999998ms
{"timings":{"total":1639.073852999998,"sync":42.4518399999979,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":57.89163300000291,"oracles":{"callPrivateFunction":{"times":[463.13499299999967,90.47042399999918,39.356776000000536]}}},{"functionName":"private_kernel_init","time":45.50497000000178},{"functionName":"ContractClassRegisterer:register","time":450.0550989999974,"oracles":{"loadCapsule":{"times":[1.782219000000623]},"notifyCreatedNullifier":{"times":[0.241386000001512]},"debugLog":{"times":[0.09690899999986868]},"notifyCreatedContractClassLog":{"times":[3.382497999999032]}}},{"functionName":"private_kernel_inner","time":93.55829000000085},{"functionName":"ContractInstanceDeployer:deploy","time":56.78597699999955,"oracles":{"storeInExecutionCache":{"times":[0.08120400000188965]},"callPrivateFunction":{"times":[35.86944200000289]},"notifyCreatedNullifier":{"times":[0.20154099999854225]},"debugLog":{"times":[2.577981999998883]}}},{"functionName":"private_kernel_inner","time":93.21212799999921},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":24.148895000002085,"oracles":{}},{"functionName":"private_kernel_inner","time":92.87541600000259},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":32.63036799999827,"oracles":{"debugLog":{"times":[0.12864600000102655]}}},{"functionName":"private_kernel_inner","time":93.77175199999692},{"functionName":"private_kernel_reset","time":157.191942999998},{"functionName":"private_kernel_tail","time":64.54522300000099}],"unaccounted":334.4503189999996}}
09:37:45 aztec-1 | [09:37:45.299]
INFO:
node Received tx 0x18674061e0fb1a199fd02592fbc546d2837ba72123383a0e61055a17f2c2500f
{"txHash":"0x18674061e0fb1a199fd02592fbc546d2837ba72123383a0e61055a17f2c2500f"}
09:37:45 aztec-1 | [09:37:45.299]
INFO:
pxe:service Sent transaction 0x18674061e0fb1a199fd02592fbc546d2837ba72123383a0e61055a17f2c2500f
09:37:45 aztec-1 | [09:37:45.387]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.02432499100000132s
{"duration":0.02432499100000132,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152}
09:37:45 aztec-1 | [09:37:45.418]
INFO:
sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x1533946a9c1fd53831d601c8cb52ff46646ceb6ac17fce41c9fd991cf6ec0bfb","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749807933,"version":3326517204},"txHashes":["0x18674061e0fb1a199fd02592fbc546d2837ba72123383a0e61055a17f2c2500f"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":61.07045099999959,"publicProcessDuration":24.416943000000174,"rollupCircuitsDuration":56.35538799999995,"txCount":1,"blockNumber":6,"blockTimestamp":1749807933,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:37:45 aztec-1 | [09:37:45.586]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:37:45 aztec-1 | [09:37:45.589]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807969
09:37:45 aztec-1 | [09:37:45.589]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:46:09.000Z
{"offset":503411,"timeMs":1749807969000}
09:37:45 aztec-1 | [09:37:45.589]
INFO:
aztecjs:utils:watcher Slot 7 was filled, jumped to next slot
09:37:45 aztec-1 | [09:37:45.641]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6
09:37:45 aztec-1 | [09:37:45.645]
INFO:
aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6.
09:37:45 aztec-1 | [09:37:45.678]
INFO:
archiver Downloaded L2 block 6
{"blockHash":"0x1533946a9c1fd53831d601c8cb52ff46646ceb6ac17fce41c9fd991cf6ec0bfb","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749807933,"version":3326517204},"archiveRoot":"0x0379912d9d61ea34b9a54741783a6a111ddd5d55d832c4adc62564e859e91d3d","archiveNextLeafIndex":7}
09:37:45 aztec-1 | [09:37:45.682]
INFO:
archiver Updated proven chain to block 6
{"provenBlockNumber":6}
09:37:45 aztec-1 | [09:37:45.739]
INFO:
world_state World state updated with L2 block 6
{"eventName":"l2-block-handled","duration":11.327488999999332,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749807933,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:37:46 aztec-1 | SponsoredFPC: 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
09:37:46 aztec-1 | [09:37:46.313]
INFO:
aztecjs:deploy_sent_tx Contract 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 successfully deployed.
09:37:46 aztec-1 | [09:37:46.317]
INFO:
cli Aztec Server listening on port 8080
{"l2CircuitsVkTreeRoot":"0x2bf2a95060ddbd049be7f501ee6d0dd0fde85c50e642e13a84f201e420805b66","l2ProtocolContractsTreeRoot":"0x222b5a95b10e0a2dfba4648739629a01cecd69a0bef23a9fc22f846735d7cc71"}
09:37:50 playground-1 | \
09:37:50 playground-1 |
Running 1
test using 1
worker
09:37:50 playground-1 |
09:37:51 playground-1 |
✓ 1 [chromium] › browser.spec.ts:3:1 › test
(5.6s)
09:37:57 playground-1 |
1 passed (8.3s)
09:37:58 playground-1 | \
09:37:58
playground-1 exited with code 0
09:37:58 Aborting on container exit...
09:37:58 Container playground-chromium-playground-1 Stopping
09:37:58 Container playground-chromium-playground-1 Stopped
09:37:58 Container playground-chromium-aztec-1 Stopping
09:37:59 Container playground-chromium-aztec-1 Stopped
09:37:59 Container playground-chromium-ethereum-1 Stopping
09:37:59 Container playground-chromium-ethereum-1 Stopped