Parent Log:
http://ci.aztec-labs.com/bba6ba24268b9dc1
Command: 804ef67700b4328b playground/scripts/run_test.sh firefox
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=1
Date: Fri Jun 13 09:39:13 UTC 2025
System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15026_amd64_x4-full
Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s
History:
http://ci.aztec-labs.com/list/history_23123200780498f7_next
09:39:13 Network playground-firefox_default Creating
09:39:13 Network playground-firefox_default Created
09:39:13 Container playground-firefox-ethereum-1 Creating
09:39:14 Container playground-firefox-ethereum-1 Created
09:39:14 Container playground-firefox-aztec-1 Creating
09:39:14 Container playground-firefox-aztec-1 Created
09:39:14 Container playground-firefox-playground-1 Creating
09:39:14 Container playground-firefox-playground-1 Created
09:39:14 Attaching to aztec-1, ethereum-1, playground-1
09:39:19 aztec-1 |
09:39:19 aztec-1 | _
09:39:19 aztec-1 | /\ | |
09:39:19 aztec-1 | / \ ___| |_ ___ ___
09:39:19 aztec-1 | / /\ \ |_ / __/ _ \/ __|
09:39:19 aztec-1 | / ____ \ / /| || __/ (__
09:39:19 aztec-1 | /_/___ \_\/___|\__\___|\___|
09:39:19 aztec-1 |
09:39:19 aztec-1 |
https://github.com/AztecProtocol
09:39:19 aztec-1 |
09:39:19 aztec-1 |
09:39:19 aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by...
09:39:19 aztec-1 | Setting up test accounts
09:39:21 aztec-1 | [09:39:20.414]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-m3Ewjj/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
09:39:21 aztec-1 | [09:39:20.719]
INFO:
sandbox Calling anvil_setBlockTimestampInterval with params: [12]
09:39:21 aztec-1 | [09:39:20.723]
WARN:
sandbox Set block interval to 12
09:39:21 aztec-1 | [09:39:20.893]
INFO:
sandbox Waiting for 7 transactions to be mined...
09:39:21 aztec-1 | [09:39:20.911]
INFO:
sandbox All transactions mined successfully
09:39:21 aztec-1 | [09:39:20.991]
INFO:
sandbox Waiting for 9 transactions to be mined...
09:39:21 aztec-1 | [09:39:21.006]
INFO:
sandbox All transactions mined successfully
09:39:21 aztec-1 | [09:39:21.171]
INFO:
sandbox Waiting for 11 transactions to be mined...
09:39:21 aztec-1 | [09:39:21.197]
INFO:
sandbox All transactions mined successfully
09:39:21 aztec-1 | [09:39:21.261]
INFO:
sandbox Waiting for 12 transactions to be mined...
09:39:21 aztec-1 | [09:39:21.278]
INFO:
sandbox All transactions mined successfully
09:39:21 aztec-1 | [09:39:21.286]
INFO:
sandbox Waiting for 12 transactions to be mined...
09:39:21 aztec-1 | [09:39:21.304]
INFO:
sandbox All transactions mined successfully
09:39:21 aztec-1 | [09:39:21.319]
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:39:21 aztec-1 | [09:39:21.319]
INFO:
sandbox Handing over to governance
09:39:21 aztec-1 | [09:39:21.326]
INFO:
aztecjs:utils:watcher Watcher started for rollup at 0x3Aa5ebB10DC797CAC828524e59A333d0A371443c
09:39:21 aztec-1 | [09:39:21.327]
INFO:
telemetry:client Using NoopTelemetryClient
09:39:21 aztec-1 | [09:39:21.327]
INFO:
blob-sink:client Creating local blob sink client.
09:39:21 aztec-1 | [09:39:21.330]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:39:21 aztec-1 | [09:39:21.331]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:44:03.000Z
{"offset":281670,"timeMs":1749807843000}
09:39:21 aztec-1 | [09:39:21.353]
INFO:
archiver:lmdb Starting data store with maxReaders 16
09:39:21 aztec-1 | [09:39:21.734]
INFO:
archiver Starting archiver sync to rollup contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c from L1 block 20 to current L1 block 24 with hash 0xe2dc89fa6e4cd00b55a656e4c8df70a27e6fa76164d84e00385621f913c62c27
{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0x365f2afa43d4fa80e0b1722e3f9ac5ee3dbf8c12bbc0cfc457ca0f5f1b0618f2","l1BlockNumber":20}}
09:39:21 aztec-1 | [09:39:21.759]
INFO:
archiver Initial archiver sync to L1 block 24 complete.
{"l1BlockNumber":24,"syncPoint":{"blocksSynchedTo":24,"messagesSynchedTo":{"l1BlockHash":"0xe2dc89fa6e4cd00b55a656e4c8df70a27e6fa76164d84e00385621f913c62c27","l1BlockNumber":24}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}}
09:39:22 aztec-1 | [09:39:21.765]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-gDXlYQ/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
09:39:22 aztec-1 | [09:39:22.020]
INFO:
world_state Created world state synchroniser with block history of 64
09:39:22 aztec-1 | [09:39:22.020]
WARN:
node Aztec node is accepting fake proofs
09:39:22 aztec-1 | [09:39:22.030]
INFO:
p2p:lmdb-v2 Starting data store with maxReaders 16
09:39:22 aztec-1 | [09:39:22.045]
INFO:
p2p-archive:lmdb-v2 Starting data store with maxReaders 16
09:39:22 aztec-1 | [09:39:22.057]
INFO:
p2p-peer:lmdb-v2 Starting data store with maxReaders 16
09:39:22 aztec-1 | [09:39:22.073]
INFO:
p2p:tx_pool Setting maximum tx mempool size
{"maxTxPoolSize":100000000}
09:39:22 aztec-1 | [09:39:22.077]
INFO:
world_state Started world state synchronizer from block 1
09:39:22 aztec-1 | [09:39:22.082]
INFO:
p2p Starting p2p client from block 0 with empty mempools
09:39:22 aztec-1 | [09:39:22.319]
INFO:
epoch-prune-watcher EpochPruneWatcher initialized
09:39:22 aztec-1 | [09:39:22.369]
INFO:
slasher Starting Slasher client...
09:39:22 aztec-1 | [09:39:22.385]
INFO:
sequencer-client Deploying forwarder contract
09:39:22 aztec-1 | [09:39:22.406]
INFO:
sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac
09:39:22 aztec-1 | [09:39:22.415]
INFO:
validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
09:39:22 aztec-1 | [09:39:22.415]
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:39:22 aztec-1 | [09:39:22.417]
INFO:
sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
09:39:22 aztec-1 | [09:39:22.418]
INFO:
node Aztec Node version: 0.87.6
09:39:22 aztec-1 | [09:39:22.418]
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:39:22 aztec-1 | [09:39:22.425]
INFO:
pxe:data:lmdb Starting data store with maxReaders 16
09:39:22 aztec-1 | [09:39:22.435]
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:39:22 aztec-1 | [09:39:22.498]
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:39:22 aztec-1 | [09:39:22.566]
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:39:22 aztec-1 | [09:39:22.568]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:39:22 aztec-1 | [09:39:22.568]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:44:15.000Z
{"offset":292432,"timeMs":1749807855000}
09:39:22 aztec-1 | Setting up funded test accounts...
09:39:22 aztec-1 | [09:39:22.806]
INFO:
pxe:service Started PXE connected to chain 31337 version 2094187016
09:39:22 aztec-1 | [09:39:22.898]
INFO:
pxe:service Registered account 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:39:22 aztec-1 | [09:39:22.967]
WARN:
pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata
09:39:22 aztec-1 | [09:39:22.969]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:39:23 aztec-1 | [09:39:23.124]
INFO:
pxe:service Added contract SchnorrAccount at 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:39:26 aztec-1 | [09:39:25.802]
INFO:
pxe:service Proving completed in 2635.311057ms
{"timings":{"total":2635.311057,"sync":6.14698800000042,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":142.78417399999762,"oracles":{"callPrivateFunction":{"times":[775.781699000001,356.53392600000006,183.97759700000097]}}},{"functionName":"private_kernel_init","time":80.82047500000044},{"functionName":"ContractClassRegisterer:register","time":759.756453,"oracles":{"loadCapsule":{"times":[12.315308000000186]},"notifyCreatedNullifier":{"times":[28.29881100000057]},"debugLog":{"times":[0.6268989999989572]},"notifyCreatedContractClassLog":{"times":[7.356321999999636]}}},{"functionName":"private_kernel_inner","time":115.99438999999984},{"functionName":"SchnorrAccount:constructor","time":323.7254440000015,"oracles":{"getContractInstance":{"times":[3.010844000000361]},"debugLog":{"times":[0.23563800000010815,0.1680529999994178]},"fetchTaggedLogs":{"times":[99.11705799999982]},"loadCapsule":{"times":[0.4604360000012093,0.25842000000011467,0.2302270000000135]},"validateEnqueuedNotesAndEvents":{"times":[6.335872999999992]},"notifyCreatedNullifier":{"times":[0.27746099999967555,0.15493200000128127]},"notifyCreatedNote":{"times":[0.46969699999863224]},"getRandomField":{"times":[0.3002329999999347,0.23877900000115915,0.05320399999982328,0.05546499999945809,0.08098599999902945,0.17975399999886577,0.05375400000048103,0.03767300000072282,0.04259300000012445,0.060064999999667634,0.041413000000829925]},"getIndexedTaggingSecretAsSender":{"times":[32.898237999999765]},"incrementAppTaggingSecretIndexAsSender":{"times":[24.751994000000195]}}},{"functionName":"private_kernel_inner","time":144.27808000000005},{"functionName":"SchnorrAccount:entrypoint","time":163.94808899999953,"oracles":{"debugLog":{"times":[0.200725999999122,0.09276700000009441,0.24947900000006484]},"fetchTaggedLogs":{"times":[49.33947700000135]},"loadCapsule":{"times":[0.2680609999988519,0.254479999999603,0.18056400000023132]},"validateEnqueuedNotesAndEvents":{"times":[6.332162000000608]},"getNotes":{"times":[2.5144059999984165]},"getAuthWitness":{"times":[0.301063000000795]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.6762820000003558]}}},{"functionName":"private_kernel_inner","time":156.3982219999998},{"functionName":"private_kernel_reset","time":208.04758799999945},{"functionName":"private_kernel_tail","time":94.52416099999937}],"unaccounted":438.8869930000019}}
09:39:26 aztec-1 | [09:39:25.919]
INFO:
node Received tx 0x18389e9c57fdf414eb1476e7216b412d5aee01cc93c909117615c0ea225492a8
{"txHash":"0x18389e9c57fdf414eb1476e7216b412d5aee01cc93c909117615c0ea225492a8"}
09:39:26 aztec-1 | [09:39:25.919]
INFO:
pxe:service Sent transaction 0x18389e9c57fdf414eb1476e7216b412d5aee01cc93c909117615c0ea225492a8
09:39:26 aztec-1 | [09:39:26.016]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.04135270599999967s
{"duration":0.04135270599999967,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344}
09:39:26 aztec-1 | [09:39:26.048]
INFO:
sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x18be72ed577697fff755b59820f85ee4b136632667b8b3726cfdb04d946b2d65","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807867,"version":2094187016},"txHashes":["0x18389e9c57fdf414eb1476e7216b412d5aee01cc93c909117615c0ea225492a8"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":84.37681200000043,"publicProcessDuration":41.94713199999933,"rollupCircuitsDuration":75.94065500000033,"txCount":1,"blockNumber":1,"blockTimestamp":1749807867,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:39:29 aztec-1 | [09:39:29.106]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1
09:39:29 aztec-1 | [09:39:29.110]
INFO:
aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1.
09:39:29 aztec-1 | [09:39:29.113]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:39:29 aztec-1 | [09:39:29.198]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:39:29 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)"
09:39:29 aztec-1 | [09:39:29.198]
WARN:
sequencer Cannot propose block 1 at slot 2 due to failed rollup contract check
09:39:29 aztec-1 | [09:39:29.223]
INFO:
archiver Downloaded L2 block 1
{"blockHash":"0x18be72ed577697fff755b59820f85ee4b136632667b8b3726cfdb04d946b2d65","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807867,"version":2094187016},"archiveRoot":"0x099124bde64197335137c50733d764a223511fb5b7eeda8ac0d99219b2f851ef","archiveNextLeafIndex":2}
09:39:29 aztec-1 | [09:39:29.233]
INFO:
archiver Updated proven chain to block 1
{"provenBlockNumber":1}
09:39:29 aztec-1 | [09:39:29.269]
INFO:
world_state World state updated with L2 block 1
{"eventName":"l2-block-handled","duration":17.860858999998527,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749807867,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:39:29 aztec-1 | [09:39:29.298]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:39:29 aztec-1 | [09:39:29.299]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:44:27.000Z
{"offset":297702,"timeMs":1749807867000}
09:39:29 aztec-1 | [09:39:29.330]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:39:29 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)"
09:39:29 aztec-1 | [09:39:29.330]
WARN:
sequencer Cannot propose block 2 at slot 2 due to failed rollup contract check
09:39:29 aztec-1 | [09:39:29.404]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807903
09:39:29 aztec-1 | [09:39:29.404]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:45:03.000Z
{"offset":333596,"timeMs":1749807903000}
09:39:29 aztec-1 | [09:39:29.404]
INFO:
aztecjs:utils:watcher Slot 2 was filled, jumped to next slot
09:39:29 aztec-1 | [09:39:29.438]
INFO:
pxe:service Registered account 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
09:39:29 aztec-1 | [09:39:29.561]
INFO:
pxe:service Added contract SchnorrAccount at 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:39:31 aztec-1 | [09:39:31.333]
INFO:
pxe:service Proving completed in 1751.0079889999997ms
{"timings":{"total":1751.0079889999997,"sync":20.82013899999947,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":154.69490099999894,"oracles":{"callPrivateFunction":{"times":[523.281653,317.0764369999997]}}},{"functionName":"private_kernel_init","time":53.391521999999895},{"functionName":"SchnorrAccount:constructor","time":480.9425910000009,"oracles":{"getContractInstance":{"times":[9.781740999998874]},"debugLog":{"times":[0.30509299999903305,0.13066000000071654]},"fetchTaggedLogs":{"times":[155.1336940000001]},"loadCapsule":{"times":[1.7121530000003986,0.715425000000323,0.42426200000045355]},"validateEnqueuedNotesAndEvents":{"times":[6.113846000000194]},"notifyCreatedNullifier":{"times":[0.4681060000002617,0.25909000000137894]},"notifyCreatedNote":{"times":[0.2328079999988404]},"getRandomField":{"times":[0.3578079999988404,0.3629290000008041,0.05980499999895983,0.04930400000012014,0.048303999999916414,0.038942999999562744,0.04752299999927345,0.06916500000079395,0.04213300000083109,0.045472999998310115,0.03707299999950919]},"getIndexedTaggingSecretAsSender":{"times":[79.88063200000033]},"incrementAppTaggingSecretIndexAsSender":{"times":[44.20524800000021]}}},{"functionName":"private_kernel_inner","time":127.72327199999927},{"functionName":"SchnorrAccount:entrypoint","time":284.5635379999985,"oracles":{"debugLog":{"times":[0.3323359999994864,0.1817640000008396,0.2693710000003193]},"fetchTaggedLogs":{"times":[119.5920100000003]},"loadCapsule":{"times":[1.0966750000006869,0.3893100000004779,0.318185000000085]},"validateEnqueuedNotesAndEvents":{"times":[6.694461000000956]},"getNotes":{"times":[1.6596089999984542]},"getAuthWitness":{"times":[0.21368600000096194]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.6218779999999242]}}},{"functionName":"private_kernel_inner","time":102.86739000000125},{"functionName":"private_kernel_reset","time":189.14309899999898},{"functionName":"private_kernel_tail","time":55.57763200000045}],"unaccounted":281.28390500000205}}
09:39:31 aztec-1 | [09:39:31.390]
INFO:
node Received tx 0x2afa075287278e275c90432a668c4c92b65e62bc64285451d3cf5dd001bb08dd
{"txHash":"0x2afa075287278e275c90432a668c4c92b65e62bc64285451d3cf5dd001bb08dd"}
09:39:31 aztec-1 | [09:39:31.390]
INFO:
pxe:service Sent transaction 0x2afa075287278e275c90432a668c4c92b65e62bc64285451d3cf5dd001bb08dd
09:39:31 aztec-1 | [09:39:31.488]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.018914080000000468s
{"duration":0.018914080000000468,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:39:31 aztec-1 | [09:39:31.507]
INFO:
sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x2927606f96b2fafca23638f3225a8bf720409afa980c9c4ae17a2335099565ef","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807903,"version":2094187016},"txHashes":["0x2afa075287278e275c90432a668c4c92b65e62bc64285451d3cf5dd001bb08dd"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":44.75184999999874,"publicProcessDuration":19.076743000001443,"rollupCircuitsDuration":39.73454000000129,"txCount":1,"blockNumber":2,"blockTimestamp":1749807903,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:39:31 aztec-1 | [09:39:31.699]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:39:31 aztec-1 | [09:39:31.716]
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":"0x099124bde64197335137c50733d764a223511fb5b7eeda8ac0d99219b2f851ef","blocksSynchedTo":26,"currentL1BlockNumber":27,"provenBlockNumber":1,"provenArchive":"0x099124bde64197335137c50733d764a223511fb5b7eeda8ac0d99219b2f851ef","pendingBlockNumber":2,"pendingArchive":"0x2276693dd1896dfb47a097726a21d35c9fb29067ef3c1e999d2e09e225e6be95"}
09:39:31 aztec-1 | [09:39:31.760]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:39:31 aztec-1 | [09:39:31.761]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:45:15.000Z
{"offset":343240,"timeMs":1749807915000}
09:39:31 aztec-1 | [09:39:31.771]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2
09:39:31 aztec-1 | [09:39:31.776]
INFO:
aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2.
09:39:31 aztec-1 | [09:39:31.782]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:39:31 aztec-1 | error: "Rollup__SlotAlreadyInChain(3, 3)"
09:39:31 aztec-1 | [09:39:31.782]
WARN:
sequencer Cannot propose block 2 at slot 3 due to failed rollup contract check
09:39:31 aztec-1 | [09:39:31.805]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807939
09:39:31 aztec-1 | [09:39:31.805]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:45:39.000Z
{"offset":367195,"timeMs":1749807939000}
09:39:31 aztec-1 | [09:39:31.805]
INFO:
aztecjs:utils:watcher Slot 3 was filled, jumped to next slot
09:39:31 aztec-1 | [09:39:31.840]
INFO:
archiver Downloaded L2 block 2
{"blockHash":"0x2927606f96b2fafca23638f3225a8bf720409afa980c9c4ae17a2335099565ef","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807903,"version":2094187016},"archiveRoot":"0x2276693dd1896dfb47a097726a21d35c9fb29067ef3c1e999d2e09e225e6be95","archiveNextLeafIndex":3}
09:39:31 aztec-1 | [09:39:31.843]
INFO:
archiver Updated proven chain to block 2
{"provenBlockNumber":2}
09:39:31 aztec-1 | [09:39:31.868]
INFO:
world_state World state updated with L2 block 2
{"eventName":"l2-block-handled","duration":16.370742999999493,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749807903,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:39:32 aztec-1 | [09:39:31.971]
INFO:
pxe:service Registered account 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
09:39:32 aztec-1 | [09:39:32.100]
INFO:
pxe:service Added contract SchnorrAccount at 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:39:33 aztec-1 | Initial Accounts:
09:39:33 aztec-1 |
09:39:33 aztec-1 | Address: 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:39:33 aztec-1 | Partial Address: 0x27b4888363d0f9eb0d43aa0b2ff08f21fbc6867b8e0baf8ec6062c4623c1eefc
09:39:33 aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281
09:39:33 aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f
09:39:33 aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f
09:39:33 aztec-1 |
09:39:33 aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5
09:39:33 aztec-1 |
09:39:33 aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89
09:39:33 aztec-1 |
09:39:33 aztec-1 | Address: 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
09:39:33 aztec-1 | Partial Address: 0x2e19b56de1617cb3179d81be0173c7301621a2e820da7f2754fcfccdfc4418e0
09:39:33 aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae
09:39:33 aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57
09:39:33 aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d
09:39:33 aztec-1 |
09:39:33 aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e
09:39:33 aztec-1 |
09:39:33 aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d
09:39:33 aztec-1 |
09:39:33 aztec-1 | Address: 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
09:39:33 aztec-1 | Partial Address: 0x2451b67547512eaaaad5ded43156c5b80220b7d5145ebefdd57295a9e05a610d
09:39:33 aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c
09:39:33 aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3
09:39:33 aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae
09:39:33 aztec-1 |
09:39:33 aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da
09:39:33 aztec-1 |
09:39:33 aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba
09:39:33 aztec-1 |
09:39:33 aztec-1 |
09:39:34 aztec-1 | [09:39:33.449]
INFO:
pxe:service Proving completed in 1342.0443859999978ms
{"timings":{"total":1342.0443859999978,"sync":23.258397999998124,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":82.44565300000977,"oracles":{"callPrivateFunction":{"times":[340.7704300000005,231.09828200000265]}}},{"functionName":"private_kernel_init","time":45.493516999998974},{"functionName":"SchnorrAccount:constructor","time":319.0744419999937,"oracles":{"getContractInstance":{"times":[5.910428999999567]},"debugLog":{"times":[0.15891200000260142,0.07197499999892898]},"fetchTaggedLogs":{"times":[124.70327800000086]},"loadCapsule":{"times":[0.17875399999684305,0.20974699999715085,0.14763099999981932]},"validateEnqueuedNotesAndEvents":{"times":[5.673461000002135]},"notifyCreatedNullifier":{"times":[0.24365899999975227,0.26962099999946076]},"notifyCreatedNote":{"times":[0.09710700000141514]},"getRandomField":{"times":[0.11734900000010384,0.16723300000012387,0.07131599999775062,0.03244300000005751,0.059844999999768334,0.05767399999967893,0.02819200000158162,0.03213300000061281,0.05750400000033551,0.028762000001734123,0.024782000000413973]},"getIndexedTaggingSecretAsSender":{"times":[39.445307000001776]},"incrementAppTaggingSecretIndexAsSender":{"times":[33.90314600000056]}}},{"functionName":"private_kernel_inner","time":121.08405599999969},{"functionName":"SchnorrAccount:entrypoint","time":205.07188799999494,"oracles":{"debugLog":{"times":[0.17613400000118418,0.12760999999954947,0.14512200000172015]},"fetchTaggedLogs":{"times":[90.94831300000078]},"loadCapsule":{"times":[2.881063999997423,1.2555480000009993,2.4719919999988633]},"validateEnqueuedNotesAndEvents":{"times":[12.30805699999837]},"getNotes":{"times":[3.228091000000859]},"getAuthWitness":{"times":[0.16690299999754643]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.5200700000023062]}}},{"functionName":"private_kernel_inner","time":88.92164500000217},{"functionName":"private_kernel_reset","time":152.43501500000275},{"functionName":"private_kernel_tail","time":59.8546439999991}],"unaccounted":244.4051279999985}}
09:39:34 aztec-1 | [09:39:33.485]
INFO:
node Received tx 0x03146107e4e7ad5381a17874665d5820bdceeb4858cae43f953cf5e3df1ad57d
{"txHash":"0x03146107e4e7ad5381a17874665d5820bdceeb4858cae43f953cf5e3df1ad57d"}
09:39:34 aztec-1 | [09:39:33.485]
INFO:
pxe:service Sent transaction 0x03146107e4e7ad5381a17874665d5820bdceeb4858cae43f953cf5e3df1ad57d
09:39:34 aztec-1 | [09:39:33.551]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.015629036000002088s
{"duration":0.015629036000002088,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:39:34 aztec-1 | [09:39:33.568]
INFO:
sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x15715ff16e5592f34c836bb5e07422dc166052ac7aba2c4979077633f9325965","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807939,"version":2094187016},"txHashes":["0x03146107e4e7ad5381a17874665d5820bdceeb4858cae43f953cf5e3df1ad57d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":39.50428199999806,"publicProcessDuration":15.737432999998418,"rollupCircuitsDuration":34.77087400000164,"txCount":1,"blockNumber":3,"blockTimestamp":1749807939,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:39:34 aztec-1 | [09:39:33.727]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3
09:39:34 aztec-1 | [09:39:33.730]
INFO:
aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3.
09:39:34 aztec-1 | [09:39:33.734]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:39:34 aztec-1 | [09:39:33.736]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749807975
09:39:34 aztec-1 | [09:39:33.736]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:46:15.000Z
{"offset":401264,"timeMs":1749807975000}
09:39:34 aztec-1 | [09:39:33.736]
INFO:
aztecjs:utils:watcher Slot 4 was filled, jumped to next slot
09:39:34 aztec-1 | [09:39:33.831]
INFO:
archiver Downloaded L2 block 3
{"blockHash":"0x15715ff16e5592f34c836bb5e07422dc166052ac7aba2c4979077633f9325965","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807939,"version":2094187016},"archiveRoot":"0x0532ca578bd892bf4940072108331a0f151628b7741744bf6a7ded98def03119","archiveNextLeafIndex":4}
09:39:34 aztec-1 | [09:39:33.835]
INFO:
archiver Updated proven chain to block 3
{"provenBlockNumber":3}
09:39:34 aztec-1 | [09:39:33.866]
INFO:
world_state World state updated with L2 block 3
{"eventName":"l2-block-handled","duration":15.90169700000115,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749807939,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:39:34 aztec-1 | [09:39:34.593]
WARN:
pxe:service No artifact found for contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 when looking for its metadata
09:39:34 aztec-1 | [09:39:34.593]
WARN:
pxe:service No artifact found for contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 when looking for its metadata
09:39:34 aztec-1 | [09:39:34.595]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 as part of deployment for 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
09:39:34 aztec-1 | [09:39:34.620]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 as part of deployment for 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
09:39:35 aztec-1 | [09:39:35.363]
INFO:
pxe:service Added contract FPC at 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 with class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1
09:39:35 aztec-1 | [09:39:35.367]
INFO:
pxe:service Added contract Token at 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf with class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645
09:39:37 aztec-1 | [09:39:37.615]
INFO:
pxe:service Proving completed in 2212.4827149999983ms
{"timings":{"total":2212.4827149999983,"sync":31.37568900000042,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":285.9969889999993,"oracles":{"debugLog":{"times":[0.17042299999957322,0.08632700000089244,0.06228499999997439,0.12056899999879533,0.08931699999811826,0.09441700000024866,0.05717499999809661,0.18415399999867077]},"fetchTaggedLogs":{"times":[82.60408300000199]},"loadCapsule":{"times":[0.22545699999682256,0.14785199999823817,0.3108639999991283,1.4492820000014035,0.21804700000211596,0.17508399999860558,0.13668999999936204]},"getSharedSecret":{"times":[3.651694000000134]},"aes128Decrypt":{"times":[0.8005919999995967,0.5079890000015439]},"getPublicKeysAndPartialAddress":{"times":[6.1639089999989665]},"getKeyValidationRequest":{"times":[6.490665000001172]},"storeCapsule":{"times":[2.9105359999994107,2.6914289999986067,2.346412999999302]},"deleteCapsule":{"times":[2.84443100000135]},"validateEnqueuedNotesAndEvents":{"times":[11.800067999996827]},"getNotes":{"times":[2.444739999999001]},"getAuthWitness":{"times":[0.27349099999992177]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07209599999987404]},"callPrivateFunction":{"times":[581.5327399999987,117.46298399999796]},"notifyEnqueuedPublicFunctionCall":{"times":[0.2574899999999616]}}},{"functionName":"private_kernel_init","time":52.252463000000716},{"functionName":"ContractClassRegisterer:register","time":568.2890609999995,"oracles":{"loadCapsule":{"times":[2.236064000000624]},"notifyCreatedNullifier":{"times":[0.48085700000228826]},"debugLog":{"times":[0.1134890000030282]},"notifyCreatedContractClassLog":{"times":[2.9701510000013513]}}},{"functionName":"private_kernel_inner","time":144.78411799999958},{"functionName":"ContractInstanceDeployer:deploy","time":73.12663699999757,"oracles":{"storeInExecutionCache":{"times":[0.18666400000074646]},"callPrivateFunction":{"times":[53.07032600000093]},"notifyCreatedNullifier":{"times":[0.17979400000331225]},"debugLog":{"times":[0.08695699999952922]}}},{"functionName":"private_kernel_inner","time":140.75321599999734},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":36.09495700000116,"oracles":{}},{"functionName":"private_kernel_inner","time":139.9945970000008},{"functionName":"private_kernel_reset","time":232.867508000003},{"functionName":"private_kernel_tail","time":109.72468200000003}],"unaccounted":397.2227979999989}}
09:39:37 aztec-1 | [09:39:37.793]
INFO:
node Received tx 0x09f82dfc1dfa5e287a3c028c9667cb1eb5645e0091fc178387e01470bfb0eec3
{"txHash":"0x09f82dfc1dfa5e287a3c028c9667cb1eb5645e0091fc178387e01470bfb0eec3"}
09:39:37 aztec-1 | [09:39:37.793]
INFO:
pxe:service Sent transaction 0x09f82dfc1dfa5e287a3c028c9667cb1eb5645e0091fc178387e01470bfb0eec3
09:39:39 aztec-1 | [09:39:39.304]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 1.3574781089999997s
{"duration":1.3574781089999997,"rate":30397.543596778556,"totalPublicGas":{"daGas":4096,"l2Gas":41264},"totalBlockGas":{"daGas":166912,"l2Gas":66306},"totalSizeInBytes":10688}
09:39:39 aztec-1 | [09:39:39.406]
INFO:
sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 27990.743784459813 mana/s
{"blockHash":"0x0972795db69245b79164964f7fc047139efeb0e11f09d502711045b903a2311a","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807975,"version":2094187016},"txHashes":["0x09f82dfc1dfa5e287a3c028c9667cb1eb5645e0091fc178387e01470bfb0eec3"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":1474.147990999998,"publicProcessDuration":1357.6545420000002,"rollupCircuitsDuration":1461.2715390000012,"txCount":1,"blockNumber":4,"blockTimestamp":1749807975,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
09:39:40 aztec-1 | [09:39:40.184]
INFO:
pxe:service Proving completed in 2567.633582000002ms
{"timings":{"total":2567.633582000002,"sync":134.7885610000012,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":462.3047700000061,"oracles":{"debugLog":{"times":[0.1959759999990638,0.0978770000001532,0.14964099999997416,0.21146600000065519,0.10277900000073714,0.1357499999976426,0.13797100000010687,0.12996999999813852]},"fetchTaggedLogs":{"times":[182.13204300000143]},"loadCapsule":{"times":[1.085175000000163,1.955761999997776,1.1618510000007518,0.3827199999977893,0.9750960000019404,0.33905700000104844,0.7157260000021779]},"getSharedSecret":{"times":[14.575662999999622]},"aes128Decrypt":{"times":[0.37113899999894784,1.547430999999051]},"getPublicKeysAndPartialAddress":{"times":[6.24574500000017]},"getKeyValidationRequest":{"times":[2.283407000002626]},"storeCapsule":{"times":[3.2490230000003066,20.76165399999809,2.7129909999966912]},"deleteCapsule":{"times":[3.370631999998295]},"validateEnqueuedNotesAndEvents":{"times":[17.08285799999794]},"getNotes":{"times":[4.7463690000004135]},"getAuthWitness":{"times":[0.20271599999978207]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.047913999998854706]},"callPrivateFunction":{"times":[778.5240479999993,128.5777280000002]},"notifyEnqueuedPublicFunctionCall":{"times":[0.06671499999720254]}}},{"functionName":"private_kernel_init","time":70.3217889999978},{"functionName":"ContractClassRegisterer:register","time":755.7383669999981,"oracles":{"loadCapsule":{"times":[1.9823240000005171]},"notifyCreatedNullifier":{"times":[0.47866699999940465]},"debugLog":{"times":[0.11326899999767193]},"notifyCreatedContractClassLog":{"times":[8.38448199999766]}}},{"functionName":"private_kernel_inner","time":90.01572999999917},{"functionName":"ContractInstanceDeployer:deploy","time":76.60224700000254,"oracles":{"storeInExecutionCache":{"times":[0.06787500000064028]},"callPrivateFunction":{"times":[52.373232999998436]},"notifyCreatedNullifier":{"times":[0.35299700000177836]},"debugLog":{"times":[0.14742200000182493]}}},{"functionName":"private_kernel_inner","time":102.09160900000279},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":35.33835799999724,"oracles":{}},{"functionName":"private_kernel_inner","time":99.09111499999926},{"functionName":"private_kernel_reset","time":231.8001949999998},{"functionName":"private_kernel_tail","time":65.91265500000009}],"unaccounted":443.6281859999981}}
09:39:40 aztec-1 | [09:39:40.235]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4
09:39:40 aztec-1 | [09:39:40.237]
INFO:
aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4.
09:39:40 aztec-1 | [09:39:40.339]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:39:40 aztec-1 | [09:39:40.341]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808011
09:39:40 aztec-1 | [09:39:40.341]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:46:51.000Z
{"offset":430659,"timeMs":1749808011000}
09:39:40 aztec-1 | [09:39:40.341]
INFO:
aztecjs:utils:watcher Slot 5 was filled, jumped to next slot
09:39:40 aztec-1 | [09:39:40.374]
INFO:
node Received tx 0x217cd6e265d0e07ee6ecaeba83b4f602cc8f9a666ec96f98447b55a5832dacc5
{"txHash":"0x217cd6e265d0e07ee6ecaeba83b4f602cc8f9a666ec96f98447b55a5832dacc5"}
09:39:40 aztec-1 | [09:39:40.374]
INFO:
pxe:service Sent transaction 0x217cd6e265d0e07ee6ecaeba83b4f602cc8f9a666ec96f98447b55a5832dacc5
09:39:40 aztec-1 | [09:39:40.472]
INFO:
archiver Downloaded L2 block 4
{"blockHash":"0x0972795db69245b79164964f7fc047139efeb0e11f09d502711045b903a2311a","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807975,"version":2094187016},"archiveRoot":"0x1b98e98d6c7214cfbc453b8ec3077c1a47080b96e71c514b6425a936d8625676","archiveNextLeafIndex":5}
09:39:40 aztec-1 | [09:39:40.477]
INFO:
archiver Updated proven chain to block 4
{"provenBlockNumber":4}
09:39:40 aztec-1 | [09:39:40.512]
INFO:
world_state World state updated with L2 block 4
{"eventName":"l2-block-handled","duration":16.315459000001283,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749807975,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
09:39:41 aztec-1 | [09:39:40.889]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.2944415360000021s
{"duration":0.2944415360000021,"rate":1125686.9682951171,"totalPublicGas":{"daGas":10240,"l2Gas":331449},"totalBlockGas":{"daGas":380928,"l2Gas":356491},"totalSizeInBytes":24064}
09:39:41 aztec-1 | [09:39:40.929]
INFO:
sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 972582.3670006077 mana/s
{"blockHash":"0x123cdeb21e517b0d49db0ec2f173ebf7eec3d778182f033a8aef49bfa8584e7c","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749808011,"version":2094187016},"txHashes":["0x217cd6e265d0e07ee6ecaeba83b4f602cc8f9a666ec96f98447b55a5832dacc5"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":340.7684580000023,"publicProcessDuration":294.70962699999654,"rollupCircuitsDuration":336.6538790000013,"txCount":1,"blockNumber":5,"blockTimestamp":1749808011,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
09:39:41 aztec-1 | [09:39:41.124]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:39:41 aztec-1 | [09:39:41.125]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5
09:39:41 aztec-1 | [09:39:41.127]
INFO:
aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5.
09:39:41 aztec-1 | [09:39:41.139]
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":"0x1b98e98d6c7214cfbc453b8ec3077c1a47080b96e71c514b6425a936d8625676","blocksSynchedTo":32,"currentL1BlockNumber":33,"provenBlockNumber":4,"provenArchive":"0x1b98e98d6c7214cfbc453b8ec3077c1a47080b96e71c514b6425a936d8625676","pendingBlockNumber":5,"pendingArchive":"0x1156178697f1b3b51116706958ca8dda604545907a696b14aafb255077e90633"}
09:39:41 aztec-1 | [09:39:41.192]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:39:41 aztec-1 | error: "Rollup__SlotAlreadyInChain(6, 6)"
09:39:41 aztec-1 | [09:39:41.192]
WARN:
sequencer Cannot propose block 5 at slot 6 due to failed rollup contract check
09:39:41 aztec-1 | [09:39:41.194]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808047
09:39:41 aztec-1 | [09:39:41.194]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:47:27.000Z
{"offset":465806,"timeMs":1749808047000}
09:39:41 aztec-1 | [09:39:41.194]
INFO:
aztecjs:utils:watcher Slot 6 was filled, jumped to next slot
09:39:41 aztec-1 | [09:39:41.286]
INFO:
archiver Downloaded L2 block 5
{"blockHash":"0x123cdeb21e517b0d49db0ec2f173ebf7eec3d778182f033a8aef49bfa8584e7c","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749808011,"version":2094187016},"archiveRoot":"0x1156178697f1b3b51116706958ca8dda604545907a696b14aafb255077e90633","archiveNextLeafIndex":6}
09:39:41 aztec-1 | [09:39:41.293]
INFO:
archiver Updated proven chain to block 5
{"provenBlockNumber":5}
09:39:41 aztec-1 | [09:39:41.313]
INFO:
world_state World state updated with L2 block 5
{"eventName":"l2-block-handled","duration":15.19622199999867,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749808011,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
09:39:41 aztec-1 | [09:39:41.342]
INFO:
aztecjs:deploy_sent_tx Contract 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 successfully deployed.
09:39:41 aztec-1 | BananaCoin: 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
09:39:41 aztec-1 | FPC: 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
09:39:41 aztec-1 | [09:39:41.636]
INFO:
aztecjs:deploy_sent_tx Contract 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf successfully deployed.
09:39:41 aztec-1 | [09:39:41.671]
WARN:
pxe:service No artifact found for contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e when looking for its metadata
09:39:41 aztec-1 | [09:39:41.676]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e as part of deployment for 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
09:39:41 aztec-1 | [09:39:41.820]
INFO:
pxe:service Added contract SponsoredFPC at 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 with class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e
09:39:44 aztec-1 | [09:39:44.404]
INFO:
pxe:service Proving completed in 2561.6309250000013ms
{"timings":{"total":2561.6309250000013,"sync":28.19452199999796,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":84.0244840000014,"oracles":{"callPrivateFunction":{"times":[949.072701000001,148.88426700000127,46.24837599999955]}}},{"functionName":"private_kernel_init","time":46.790108999997756},{"functionName":"ContractClassRegisterer:register","time":934.9553830000004,"oracles":{"loadCapsule":{"times":[2.7467539999997825]},"notifyCreatedNullifier":{"times":[0.40722200000163866]},"debugLog":{"times":[0.1370909999968717]},"notifyCreatedContractClassLog":{"times":[5.510929000000033]}}},{"functionName":"private_kernel_inner","time":87.9268570000022},{"functionName":"ContractInstanceDeployer:deploy","time":76.097936999995,"oracles":{"storeInExecutionCache":{"times":[0.10644899999897461]},"callPrivateFunction":{"times":[74.15452600000208]},"notifyCreatedNullifier":{"times":[0.2718109999987064]},"debugLog":{"times":[0.15538200000082725]}}},{"functionName":"private_kernel_inner","time":147.4431349999977},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":58.06718600000022,"oracles":{}},{"functionName":"private_kernel_inner","time":147.48556800000006},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":37.074962999999116,"oracles":{"debugLog":{"times":[0.1738329999971029]}}},{"functionName":"private_kernel_inner","time":148.78768000000127},{"functionName":"private_kernel_reset","time":234.757625000002},{"functionName":"private_kernel_tail","time":87.41115699999864}],"unaccounted":442.6143190000075}}
09:39:44 aztec-1 | [09:39:44.487]
INFO:
node Received tx 0x1e05cb95b8305fb844311bb69b6cdce4ec815c486a0d290320854a73431aec60
{"txHash":"0x1e05cb95b8305fb844311bb69b6cdce4ec815c486a0d290320854a73431aec60"}
09:39:44 aztec-1 | [09:39:44.487]
INFO:
pxe:service Sent transaction 0x1e05cb95b8305fb844311bb69b6cdce4ec815c486a0d290320854a73431aec60
09:39:44 aztec-1 | [09:39:44.579]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.028373067000000446s
{"duration":0.028373067000000446,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152}
09:39:44 aztec-1 | [09:39:44.613]
INFO:
sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x1176294191462baf0b503bc30143774438c5f92151ba05fd8c6072831f2aaa49","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749808047,"version":2094187016},"txHashes":["0x1e05cb95b8305fb844311bb69b6cdce4ec815c486a0d290320854a73431aec60"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":69.17470899999898,"publicProcessDuration":28.552249999997002,"rollupCircuitsDuration":63.478235999999015,"txCount":1,"blockNumber":6,"blockTimestamp":1749808047,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:39:44 aztec-1 | [09:39:44.787]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:39:44 aztec-1 | [09:39:44.793]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808083
09:39:44 aztec-1 | [09:39:44.793]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:48:03.000Z
{"offset":498207,"timeMs":1749808083000}
09:39:44 aztec-1 | [09:39:44.793]
INFO:
aztecjs:utils:watcher Slot 7 was filled, jumped to next slot
09:39:44 aztec-1 | [09:39:44.797]
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":"0x1156178697f1b3b51116706958ca8dda604545907a696b14aafb255077e90633","blocksSynchedTo":34,"currentL1BlockNumber":35,"provenBlockNumber":5,"provenArchive":"0x1156178697f1b3b51116706958ca8dda604545907a696b14aafb255077e90633","pendingBlockNumber":6,"pendingArchive":"0x2bfbb0baf367f9adf565d75c3cfb67d8d97e54e99577bbc549613bdfab10964c"}
09:39:44 aztec-1 | [09:39:44.836]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6
09:39:44 aztec-1 | [09:39:44.838]
INFO:
aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6.
09:39:44 aztec-1 | [09:39:44.895]
INFO:
archiver Downloaded L2 block 6
{"blockHash":"0x1176294191462baf0b503bc30143774438c5f92151ba05fd8c6072831f2aaa49","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749808047,"version":2094187016},"archiveRoot":"0x2bfbb0baf367f9adf565d75c3cfb67d8d97e54e99577bbc549613bdfab10964c","archiveNextLeafIndex":7}
09:39:44 aztec-1 | [09:39:44.903]
INFO:
archiver Updated proven chain to block 6
{"provenBlockNumber":6}
09:39:44 aztec-1 | [09:39:44.952]
INFO:
world_state World state updated with L2 block 6
{"eventName":"l2-block-handled","duration":17.46157800000219,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749808047,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:39:45 aztec-1 | SponsoredFPC: 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
09:39:46 aztec-1 | [09:39:45.509]
INFO:
aztecjs:deploy_sent_tx Contract 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 successfully deployed.
09:39:46 aztec-1 | [09:39:45.515]
INFO:
cli Aztec Server listening on port 8080
{"l2CircuitsVkTreeRoot":"0x2d2655c513c2e8feb8c7411b7e807c66287b57bb60572ce85ce1fe0a1e0c925d","l2ProtocolContractsTreeRoot":"0x222b5a95b10e0a2dfba4648739629a01cecd69a0bef23a9fc22f846735d7cc71"}
09:39:50 playground-1 | \
09:39:50 playground-1 |
Running 1
test using 1
worker
09:39:50 playground-1 |
09:39:51 playground-1 |
✓ 1 [firefox] › browser.spec.ts:3:1 › test
(13.6s)
09:40:08 playground-1 |
1 passed (18.5s)
09:40:08 playground-1 | \
09:40:08
playground-1 exited with code 0
09:40:08 Aborting on container exit...
09:40:08 Container playground-firefox-playground-1 Stopping
09:40:08 Container playground-firefox-playground-1 Stopped
09:40:08 Container playground-firefox-aztec-1 Stopping
09:40:10 Container playground-firefox-aztec-1 Stopped
09:40:10 Container playground-firefox-ethereum-1 Stopping
09:40:10 Container playground-firefox-ethereum-1 Stopped