Parent Log:
http://ci.aztec-labs.com/30fc99b02d171efe
Command: f591aaf8d2a564dc:ONLY_TERM_PARENT=1 BOX=vite BROWSER=webkit run_compose_test vite-webkit box boxes
Commit:
https://github.com/AztecProtocol/aztec-packages/commit/a8c553f203c98e5ca274e763c8e51daaaaf760d6
Env: REF_NAME=gh-readonly-queue/next/pr-15017-30660269b33bab8cca354c41659533acf4d48e07 CURRENT_VERSION=0.87.6 CI_FULL=1
Date: Fri Jun 13 09:41:44 UTC 2025
System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15017_amd64_x2-full
Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s
History: [?25l
[+] Running 4/4
09:41:47
✔ Network vite-webkit_default
Created0.3s
09:41:47
✔ Container vite-webkit-ethereum-1
Created1.0s
09:41:47
✔ Container vite-webkit-aztec-1
Created0.6s
09:41:47
✔ Container vite-webkit-box-1
Created0.5s
09:41:47 [?25hAttaching to aztec-1, box-1, ethereum-1
09:41:52
aztec-1 |
09:41:52
aztec-1 | _
09:41:52
aztec-1 | /\ | |
09:41:52
aztec-1 | / \ ___| |_ ___ ___
09:41:52
aztec-1 | / /\ \ |_ / __/ _ \/ __|
09:41:52
aztec-1 | / ____ \ / /| || __/ (__
09:41:52
aztec-1 | /_/___ \_\/___|\__\___|\___|
09:41:52
aztec-1 |
09:41:52
aztec-1 | https://github.com/AztecProtocol
09:41:52
aztec-1 |
09:41:52
aztec-1 |
09:41:52
aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by...
09:41:52
aztec-1 | Setting up test accounts
09:41:54
aztec-1 | [09:41:53.388]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-dPstz0/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
09:41:54
aztec-1 | [09:41:53.646]
INFO:
sandbox Calling anvil_setBlockTimestampInterval with params: [12]
09:41:54
aztec-1 | [09:41:53.651]
WARN:
sandbox Set block interval to 12
09:41:54
aztec-1 | [09:41:53.836]
INFO:
sandbox Waiting for 7 transactions to be mined...
09:41:54
aztec-1 | [09:41:53.860]
INFO:
sandbox All transactions mined successfully
09:41:54
aztec-1 | [09:41:53.971]
INFO:
sandbox Waiting for 9 transactions to be mined...
09:41:54
aztec-1 | [09:41:54.006]
INFO:
sandbox All transactions mined successfully
09:41:54
aztec-1 | [09:41:54.169]
INFO:
sandbox Waiting for 11 transactions to be mined...
09:41:54
aztec-1 | [09:41:54.195]
INFO:
sandbox All transactions mined successfully
09:41:54
aztec-1 | [09:41:54.269]
INFO:
sandbox Waiting for 12 transactions to be mined...
09:41:54
aztec-1 | [09:41:54.301]
INFO:
sandbox All transactions mined successfully
09:41:54
aztec-1 | [09:41:54.308]
INFO:
sandbox Waiting for 12 transactions to be mined...
09:41:54
aztec-1 | [09:41:54.328]
INFO:
sandbox All transactions mined successfully
09:41:54
aztec-1 | [09:41:54.345]
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:41:54
aztec-1 | [09:41:54.345]
INFO:
sandbox Handing over to governance
09:41:54
aztec-1 | [09:41:54.357]
INFO:
aztecjs:utils:watcher Watcher started for rollup at 0x3Aa5ebB10DC797CAC828524e59A333d0A371443c
09:41:54
aztec-1 | [09:41:54.358]
INFO:
telemetry:client Using NoopTelemetryClient
09:41:54
aztec-1 | [09:41:54.358]
INFO:
blob-sink:client Creating local blob sink client.
09:41:54
aztec-1 | [09:41:54.363]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:41:54
aztec-1 | [09:41:54.363]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:46:35.000Z
{"offset":280637,"timeMs":1749807995000}
09:41:54
aztec-1 | [09:41:54.388]
INFO:
archiver:lmdb Starting data store with maxReaders 16
09:41:54
aztec-1 | [09:41:54.743]
INFO:
archiver Starting archiver sync to rollup contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c from L1 block 20 to current L1 block 24 with hash 0xc98a27803290910d6a9025e8567ef6a92e10322dddee100ecf29a56a92d9e49c
{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0x0a16266286f89ca204b3881bc04e79e5d620637eec0f004b8cf072089a5580da","l1BlockNumber":20}}
09:41:54
aztec-1 | [09:41:54.766]
INFO:
archiver Initial archiver sync to L1 block 24 complete.
{"l1BlockNumber":24,"syncPoint":{"blocksSynchedTo":24,"messagesSynchedTo":{"l1BlockHash":"0xc98a27803290910d6a9025e8567ef6a92e10322dddee100ecf29a56a92d9e49c","l1BlockNumber":24}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}}
09:41:54
aztec-1 | [09:41:54.773]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-CblDT6/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
09:41:54
aztec-1 | [09:41:54.974]
INFO:
world_state Created world state synchroniser with block history of 64
09:41:54
aztec-1 | [09:41:54.974]
WARN:
node Aztec node is accepting fake proofs
09:41:54
aztec-1 | [09:41:54.984]
INFO:
p2p:lmdb-v2 Starting data store with maxReaders 16
09:41:55
aztec-1 | [09:41:55.000]
INFO:
p2p-archive:lmdb-v2 Starting data store with maxReaders 16
09:41:55
aztec-1 | [09:41:55.017]
INFO:
p2p-peer:lmdb-v2 Starting data store with maxReaders 16
09:41:55
aztec-1 | [09:41:55.032]
INFO:
p2p:tx_pool Setting maximum tx mempool size
{"maxTxPoolSize":100000000}
09:41:55
aztec-1 | [09:41:55.035]
INFO:
world_state Started world state synchronizer from block 1
09:41:55
aztec-1 | [09:41:55.038]
INFO:
p2p Starting p2p client from block 0 with empty mempools
09:41:55
aztec-1 | [09:41:55.280]
INFO:
epoch-prune-watcher EpochPruneWatcher initialized
09:41:55
aztec-1 | [09:41:55.336]
INFO:
slasher Starting Slasher client...
09:41:55
aztec-1 | [09:41:55.345]
INFO:
sequencer-client Deploying forwarder contract
09:41:55
aztec-1 | [09:41:55.365]
INFO:
sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac
09:41:55
aztec-1 | [09:41:55.372]
INFO:
validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
09:41:55
aztec-1 | [09:41:55.373]
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:41:55
aztec-1 | [09:41:55.374]
INFO:
sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
09:41:55
aztec-1 | [09:41:55.375]
INFO:
node Aztec Node version: 0.87.6
09:41:55
aztec-1 | [09:41:55.375]
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:41:55
aztec-1 | [09:41:55.381]
INFO:
pxe:data:lmdb Starting data store with maxReaders 16
09:41:55
aztec-1 | [09:41:55.391]
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:41:55
aztec-1 | [09:41:55.449]
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:41:55
aztec-1 | [09:41:55.511]
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:41:55
aztec-1 | [09:41:55.606]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:41:55
aztec-1 | [09:41:55.607]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:46:47.000Z
{"offset":291394,"timeMs":1749808007000}
09:41:55
aztec-1 | Setting up funded test accounts...
09:41:55
aztec-1 | [09:41:55.733]
INFO:
pxe:service Started PXE connected to chain 31337 version 2094187016
09:41:55
aztec-1 | [09:41:55.815]
INFO:
pxe:service Registered account 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:41:55
aztec-1 | [09:41:55.896]
WARN:
pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata
09:41:55
aztec-1 | [09:41:55.898]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:41:56
aztec-1 | [09:41:56.072]
INFO:
pxe:service Added contract SchnorrAccount at 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:42:00
aztec-1 | [09:41:59.324]
INFO:
pxe:service Proving completed in 3209.1233679999996ms
{"timings":{"total":3209.1233679999996,"sync":6.078141000000869,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":231.42762599999696,"oracles":{"callPrivateFunction":{"times":[1001.9174269999994,396.75829800000065,278.61129900000014]}}},{"functionName":"private_kernel_init","time":120.52454399999988},{"functionName":"ContractClassRegisterer:register","time":973.272723,"oracles":{"loadCapsule":{"times":[36.96227700000054]},"notifyCreatedNullifier":{"times":[1.0030930000011722]},"debugLog":{"times":[0.6108039999999164]},"notifyCreatedContractClassLog":{"times":[10.172405999999683]}}},{"functionName":"private_kernel_inner","time":149.80657299999984},{"functionName":"SchnorrAccount:constructor","time":357.4824970000009,"oracles":{"getContractInstance":{"times":[6.904399999999441]},"debugLog":{"times":[0.25109599999996135,0.14669400000093447]},"fetchTaggedLogs":{"times":[73.5102139999999]},"loadCapsule":{"times":[0.3715589999992517,0.2913570000000618,0.33466799999951036]},"validateEnqueuedNotesAndEvents":{"times":[6.568932000000132]},"notifyCreatedNullifier":{"times":[0.2985370000005787,0.28878699999950186]},"notifyCreatedNote":{"times":[0.6678350000001956]},"getRandomField":{"times":[0.43317000000024564,0.20640499999899475,0.06250200000067707,0.0684720000008383,0.0752809999994497,0.21520500000042375,0.05847200000062003,0.044471000001067296,0.04825100000016391,0.08360200000061013,0.03924100000040198]},"getIndexedTaggingSecretAsSender":{"times":[52.51246700000047]},"incrementAppTaggingSecretIndexAsSender":{"times":[33.66693999999916]}}},{"functionName":"private_kernel_inner","time":138.632834},{"functionName":"SchnorrAccount:entrypoint","time":231.10681900000054,"oracles":{"debugLog":{"times":[0.3979090000011638,0.12678299999970477,0.4040390000009211]},"fetchTaggedLogs":{"times":[70.07869500000015]},"loadCapsule":{"times":[0.2083440000005794,0.2406859999991866,0.22646499999973457]},"validateEnqueuedNotesAndEvents":{"times":[6.0671910000000935]},"getNotes":{"times":[3.233986000001096]},"getAuthWitness":{"times":[0.3461479999987205]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.1423670000003767]}}},{"functionName":"private_kernel_inner","time":142.98129399999925},{"functionName":"private_kernel_reset","time":235.70695499999965},{"functionName":"private_kernel_tail","time":98.05596399999922}],"unaccounted":524.0473980000024}}
09:42:00
aztec-1 | [09:41:59.458]
INFO:
node Received tx 0x14f1e479530b186086dc5441cf8e37ed5aed0ce260c2dd56f7690fcdeda3d790
{"txHash":"0x14f1e479530b186086dc5441cf8e37ed5aed0ce260c2dd56f7690fcdeda3d790"}
09:42:00
aztec-1 | [09:41:59.458]
INFO:
pxe:service Sent transaction 0x14f1e479530b186086dc5441cf8e37ed5aed0ce260c2dd56f7690fcdeda3d790
09:42:00
aztec-1 | [09:41:59.566]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.0473481670000001s
{"duration":0.0473481670000001,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344}
09:42:00
aztec-1 | [09:41:59.599]
INFO:
sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x0ad3c2406b804f6b9a7eeb121452ab630236b8f19911949e1a6e9985a0aca9c8","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749808019,"version":2094187016},"txHashes":["0x14f1e479530b186086dc5441cf8e37ed5aed0ce260c2dd56f7690fcdeda3d790"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":89.839113,"publicProcessDuration":48.13045600000078,"rollupCircuitsDuration":82.67872699999862,"txCount":1,"blockNumber":1,"blockTimestamp":1749808019,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:42:03
aztec-1 | [09:42:02.673]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1
09:42:03
aztec-1 | [09:42:02.678]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:42:03
aztec-1 | [09:42:02.681]
INFO:
aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1.
09:42:03
aztec-1 | [09:42:02.762]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:42:03
aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)"
09:42:03
aztec-1 | [09:42:02.762]
WARN:
sequencer Cannot propose block 1 at slot 2 due to failed rollup contract check
09:42:03
aztec-1 | [09:42:02.786]
INFO:
archiver Downloaded L2 block 1
{"blockHash":"0x0ad3c2406b804f6b9a7eeb121452ab630236b8f19911949e1a6e9985a0aca9c8","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749808019,"version":2094187016},"archiveRoot":"0x16a3c0bd4cda7c4935a7cd4ca510df021dbf999c6b17975cbab061d1aa151aed","archiveNextLeafIndex":2}
09:42:03
aztec-1 | [09:42:02.792]
INFO:
archiver Updated proven chain to block 1
{"provenBlockNumber":1}
09:42:03
aztec-1 | [09:42:02.851]
INFO:
world_state World state updated with L2 block 1
{"eventName":"l2-block-handled","duration":16.173663999999917,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749808019,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:42:03
aztec-1 | [09:42:02.865]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:42:03
aztec-1 | [09:42:02.866]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:46:59.000Z
{"offset":296134,"timeMs":1749808019000}
09:42:03
aztec-1 | [09:42:02.891]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:42:03
aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)"
09:42:03
aztec-1 | [09:42:02.891]
WARN:
sequencer Cannot propose block 2 at slot 2 due to failed rollup contract check
09:42:03
aztec-1 | [09:42:02.971]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808055
09:42:03
aztec-1 | [09:42:02.971]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:47:35.000Z
{"offset":332029,"timeMs":1749808055000}
09:42:03
aztec-1 | [09:42:02.971]
INFO:
aztecjs:utils:watcher Slot 2 was filled, jumped to next slot
09:42:03
aztec-1 | [09:42:03.004]
INFO:
pxe:service Registered account 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
09:42:03
aztec-1 | [09:42:03.131]
INFO:
pxe:service Added contract SchnorrAccount at 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:42:05
aztec-1 | [09:42:04.819]
INFO:
pxe:service Proving completed in 1663.9357820000023ms
{"timings":{"total":1663.9357820000023,"sync":21.643581999998787,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":120.13338400000066,"oracles":{"callPrivateFunction":{"times":[360.8892770000002,292.638844000001]}}},{"functionName":"private_kernel_init","time":75.15990199999942},{"functionName":"SchnorrAccount:constructor","time":328.36497299999974,"oracles":{"getContractInstance":{"times":[12.753956000000471]},"debugLog":{"times":[0.1585240000003978,0.08904200000142737]},"fetchTaggedLogs":{"times":[93.58776000000034]},"loadCapsule":{"times":[0.6015540000007604,0.8682509999998729,2.9261179999994056]},"validateEnqueuedNotesAndEvents":{"times":[19.813728999999512]},"notifyCreatedNullifier":{"times":[0.26002599999992526,0.18872499999997672]},"notifyCreatedNote":{"times":[0.13551299999926414]},"getRandomField":{"times":[0.15056399999957648,0.14782300000115356,0.03745099999832746,0.04351000000133354,0.04054099999848404,0.030210999999326305,0.045211999999082764,0.031641000001400243,0.02685100000053353,0.03768099999979313,0.035530999999537016]},"getIndexedTaggingSecretAsSender":{"times":[32.12299499999972]},"incrementAppTaggingSecretIndexAsSender":{"times":[23.450393999999505]}}},{"functionName":"private_kernel_inner","time":143.6433300000008},{"functionName":"SchnorrAccount:entrypoint","time":263.34422599999925,"oracles":{"debugLog":{"times":[0.2491859999990993,0.12091299999883631,0.2585060000001249]},"fetchTaggedLogs":{"times":[100.73232500000086]},"loadCapsule":{"times":[0.1800939999993716,0.16269400000055612,0.20838499999990745]},"validateEnqueuedNotesAndEvents":{"times":[6.150822000001426]},"getNotes":{"times":[1.8260119999995368]},"getAuthWitness":{"times":[0.2238550000001851]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.5433429999993677]}}},{"functionName":"private_kernel_inner","time":122.73563599999943},{"functionName":"private_kernel_reset","time":181.3565890000009},{"functionName":"private_kernel_tail","time":77.07005800000115}],"unaccounted":330.48410200000217}}
09:42:05
aztec-1 | [09:42:04.864]
INFO:
node Received tx 0x143a2ef3fd0f1551c0df23fc16e7124a11feffaadac986739970371b48b8724a
{"txHash":"0x143a2ef3fd0f1551c0df23fc16e7124a11feffaadac986739970371b48b8724a"}
09:42:05
aztec-1 | [09:42:04.864]
INFO:
pxe:service Sent transaction 0x143a2ef3fd0f1551c0df23fc16e7124a11feffaadac986739970371b48b8724a
09:42:05
aztec-1 | [09:42:04.950]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.023021246000000245s
{"duration":0.023021246000000245,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:42:05
aztec-1 | [09:42:04.990]
INFO:
sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x25025f1c0727e808d356117764458b3b19d9877ba3e2caf8afd4a4e8086df9b0","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749808055,"version":2094187016},"txHashes":["0x143a2ef3fd0f1551c0df23fc16e7124a11feffaadac986739970371b48b8724a"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":69.92769799999951,"publicProcessDuration":23.133108999998512,"rollupCircuitsDuration":65.04418900000019,"txCount":1,"blockNumber":2,"blockTimestamp":1749808055,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:42:05
aztec-1 | [09:42:05.196]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:42:05
aztec-1 | [09:42:05.214]
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":"0x16a3c0bd4cda7c4935a7cd4ca510df021dbf999c6b17975cbab061d1aa151aed","blocksSynchedTo":26,"currentL1BlockNumber":27,"provenBlockNumber":1,"provenArchive":"0x16a3c0bd4cda7c4935a7cd4ca510df021dbf999c6b17975cbab061d1aa151aed","pendingBlockNumber":2,"pendingArchive":"0x038d1948f5b1e9b5571d4713e8abf2ae7c9a79aa204687c67adc080705ad5146"}
09:42:05
aztec-1 | [09:42:05.247]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:42:05
aztec-1 | [09:42:05.248]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:47:47.000Z
{"offset":341753,"timeMs":1749808067000}
09:42:05
aztec-1 | [09:42:05.255]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2
09:42:05
aztec-1 | [09:42:05.263]
INFO:
aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2.
09:42:05
aztec-1 | [09:42:05.270]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:42:05
aztec-1 | error: "Rollup__SlotAlreadyInChain(3, 3)"
09:42:05
aztec-1 | [09:42:05.270]
WARN:
sequencer Cannot propose block 2 at slot 3 due to failed rollup contract check
09:42:05
aztec-1 | [09:42:05.280]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808091
09:42:05
aztec-1 | [09:42:05.280]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:48:11.000Z
{"offset":365720,"timeMs":1749808091000}
09:42:05
aztec-1 | [09:42:05.280]
INFO:
aztecjs:utils:watcher Slot 3 was filled, jumped to next slot
09:42:05
aztec-1 | [09:42:05.321]
INFO:
archiver Downloaded L2 block 2
{"blockHash":"0x25025f1c0727e808d356117764458b3b19d9877ba3e2caf8afd4a4e8086df9b0","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749808055,"version":2094187016},"archiveRoot":"0x038d1948f5b1e9b5571d4713e8abf2ae7c9a79aa204687c67adc080705ad5146","archiveNextLeafIndex":3}
09:42:05
aztec-1 | [09:42:05.331]
INFO:
archiver Updated proven chain to block 2
{"provenBlockNumber":2}
09:42:05
aztec-1 | [09:42:05.356]
INFO:
world_state World state updated with L2 block 2
{"eventName":"l2-block-handled","duration":17.166124999999738,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749808055,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:42:05
aztec-1 | [09:42:05.455]
INFO:
pxe:service Registered account 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
09:42:05
aztec-1 | [09:42:05.585]
INFO:
pxe:service Added contract SchnorrAccount at 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
09:42:07
aztec-1 | [09:42:07.378]
INFO:
pxe:service Proving completed in 1782.1225369999993ms
{"timings":{"total":1782.1225369999993,"sync":25.793869000000996,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":110.90247699999964,"oracles":{"callPrivateFunction":{"times":[415.18092199999955,290.9198249999972]}}},{"functionName":"private_kernel_init","time":85.71135199999844},{"functionName":"SchnorrAccount:constructor","time":385.3198620000003,"oracles":{"getContractInstance":{"times":[3.2280329999994137]},"debugLog":{"times":[0.1547039999968547,0.09170200000153272]},"fetchTaggedLogs":{"times":[137.3501299999989]},"loadCapsule":{"times":[0.16335400000025402,0.12591299999985495,0.15057399999932386]},"validateEnqueuedNotesAndEvents":{"times":[6.006065000001399]},"notifyCreatedNullifier":{"times":[0.2941470000005211,0.25461599999835016]},"notifyCreatedNote":{"times":[0.1328940000021248]},"getRandomField":{"times":[0.17990400000053342,0.21194499999910477,0.08563199999844073,0.05224200000156998,0.04206100000010338,0.03604099999938626,0.04112099999838392,0.035809999997582054,0.0534509999997681,0.0417410000009113,0.0472909999989497]},"getIndexedTaggingSecretAsSender":{"times":[44.24894200000199]},"incrementAppTaggingSecretIndexAsSender":{"times":[37.16818300000159]}}},{"functionName":"private_kernel_inner","time":145.13765200000125},{"functionName":"SchnorrAccount:entrypoint","time":256.69841799999995,"oracles":{"debugLog":{"times":[0.21200500000122702,0.08716199999980745,0.15150400000129594]},"fetchTaggedLogs":{"times":[113.50247599999784]},"loadCapsule":{"times":[0.18304400000124588,0.14338299999872106,0.20372400000269408]},"validateEnqueuedNotesAndEvents":{"times":[5.646117000000231]},"getNotes":{"times":[1.2613080000010086]},"getAuthWitness":{"times":[0.1906940000008035]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.5782530000033148]}}},{"functionName":"private_kernel_inner","time":141.91466199999923},{"functionName":"private_kernel_reset","time":227.88492699999915},{"functionName":"private_kernel_tail","time":98.08491700000013}],"unaccounted":304.6744010000002}}
09:42:07
aztec-1 | [09:42:07.432]
INFO:
node Received tx 0x239b1e804d704da76e2ecf92447c2518ae0daa7acb6b4985262f26dfbc2b393d
{"txHash":"0x239b1e804d704da76e2ecf92447c2518ae0daa7acb6b4985262f26dfbc2b393d"}
09:42:07
aztec-1 | [09:42:07.432]
INFO:
pxe:service Sent transaction 0x239b1e804d704da76e2ecf92447c2518ae0daa7acb6b4985262f26dfbc2b393d
09:42:07
aztec-1 | [09:42:07.503]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.02007658199999787s
{"duration":0.02007658199999787,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:42:07
aztec-1 | [09:42:07.533]
INFO:
sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x2108d6532f976c878770aa890c613cbea0f0755bb3e876fb607af40ba9ea7acc","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749808091,"version":2094187016},"txHashes":["0x239b1e804d704da76e2ecf92447c2518ae0daa7acb6b4985262f26dfbc2b393d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":56.27829600000041,"publicProcessDuration":20.189644000001863,"rollupCircuitsDuration":51.540539000001445,"txCount":1,"blockNumber":3,"blockTimestamp":1749808091,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:42:07
aztec-1 | [09:42:07.695]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3
09:42:07
aztec-1 | [09:42:07.699]
INFO:
aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3.
09:42:07
aztec-1 | [09:42:07.703]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:42:07
aztec-1 | [09:42:07.705]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808127
09:42:07
aztec-1 | [09:42:07.705]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:48:47.000Z
{"offset":399295,"timeMs":1749808127000}
09:42:07
aztec-1 | [09:42:07.705]
INFO:
aztecjs:utils:watcher Slot 4 was filled, jumped to next slot
09:42:07
aztec-1 | [09:42:07.805]
INFO:
archiver Downloaded L2 block 3
{"blockHash":"0x2108d6532f976c878770aa890c613cbea0f0755bb3e876fb607af40ba9ea7acc","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749808091,"version":2094187016},"archiveRoot":"0x0a7358721c9c447fb1e19bd50e54674039a98b65ba1e6f6055323c17699b0abd","archiveNextLeafIndex":4}
09:42:07
aztec-1 | [09:42:07.809]
INFO:
archiver Updated proven chain to block 3
{"provenBlockNumber":3}
09:42:07
aztec-1 | [09:42:07.842]
INFO:
world_state World state updated with L2 block 3
{"eventName":"l2-block-handled","duration":15.093088999998145,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749808091,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:42:07
aztec-1 | Initial Accounts:
09:42:07
aztec-1 |
09:42:07
aztec-1 | Address: 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
09:42:07
aztec-1 | Partial Address: 0x27b4888363d0f9eb0d43aa0b2ff08f21fbc6867b8e0baf8ec6062c4623c1eefc
09:42:07
aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281
09:42:07
aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f
09:42:07
aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f
09:42:07
aztec-1 |
09:42:07
aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5
09:42:07
aztec-1 |
09:42:07
aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89
09:42:07
aztec-1 |
09:42:07
aztec-1 | Address: 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
09:42:07
aztec-1 | Partial Address: 0x2e19b56de1617cb3179d81be0173c7301621a2e820da7f2754fcfccdfc4418e0
09:42:07
aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae
09:42:07
aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57
09:42:07
aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d
09:42:07
aztec-1 |
09:42:07
aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e
09:42:07
aztec-1 |
09:42:07
aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d
09:42:07
aztec-1 |
09:42:07
aztec-1 | Address: 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
09:42:07
aztec-1 | Partial Address: 0x2451b67547512eaaaad5ded43156c5b80220b7d5145ebefdd57295a9e05a610d
09:42:07
aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c
09:42:07
aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3
09:42:07
aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae
09:42:07
aztec-1 |
09:42:07
aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da
09:42:07
aztec-1 |
09:42:07
aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba
09:42:07
aztec-1 |
09:42:07
aztec-1 |
09:42:08
aztec-1 | [09:42:08.756]
WARN:
pxe:service No artifact found for contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 when looking for its metadata
09:42:08
aztec-1 | [09:42:08.757]
WARN:
pxe:service No artifact found for contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 when looking for its metadata
09:42:09
aztec-1 | [09:42:08.759]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 as part of deployment for 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
09:42:09
aztec-1 | [09:42:08.791]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 as part of deployment for 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
09:42:10
aztec-1 | [09:42:09.723]
INFO:
pxe:service Added contract FPC at 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 with class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1
09:42:10
aztec-1 | [09:42:09.729]
INFO:
pxe:service Added contract Token at 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf with class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645
09:42:13
aztec-1 | [09:42:12.513]
INFO:
pxe:service Proving completed in 2762.6291610000007ms
{"timings":{"total":2762.6291610000007,"sync":36.44810999999754,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":422.9165439999997,"oracles":{"debugLog":{"times":[0.22307500000169966,0.10803200000009383,0.09063199999945937,0.21272399999725167,0.10140300000057323,0.1192430000010063,0.09652300000016112,0.20173399999839603]},"fetchTaggedLogs":{"times":[150.25752999999895]},"loadCapsule":{"times":[0.23883500000010827,0.20863400000234833,0.3511370000014722,0.9037400000015623,0.21147500000006403,0.2191850000017439,0.19514499999786494]},"getSharedSecret":{"times":[5.008332000001246]},"aes128Decrypt":{"times":[1.1015750000005937,0.8065480000004754]},"getPublicKeysAndPartialAddress":{"times":[1.7019379999983357]},"getKeyValidationRequest":{"times":[2.3060420000001614]},"storeCapsule":{"times":[3.9556590000029246,3.75758400000268,3.3188239999981306]},"deleteCapsule":{"times":[3.3114150000001246]},"validateEnqueuedNotesAndEvents":{"times":[50.13275700000304]},"getNotes":{"times":[7.375005999998393]},"getAuthWitness":{"times":[0.23757599999953527]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05234099999870523]},"callPrivateFunction":{"times":[834.218456999999,137.06514400000015]},"notifyEnqueuedPublicFunctionCall":{"times":[0.3569580000003043]}}},{"functionName":"private_kernel_init","time":73.49869300000137},{"functionName":"ContractClassRegisterer:register","time":815.6513200000009,"oracles":{"loadCapsule":{"times":[3.0873690000007628]},"notifyCreatedNullifier":{"times":[0.3865189999996801]},"debugLog":{"times":[0.18260400000144728]},"notifyCreatedContractClassLog":{"times":[4.97207200000048]}}},{"functionName":"private_kernel_inner","time":146.4253540000027},{"functionName":"ContractInstanceDeployer:deploy","time":78.56004699999903,"oracles":{"storeInExecutionCache":{"times":[0.2795869999972638]},"callPrivateFunction":{"times":[56.36414800000057]},"notifyCreatedNullifier":{"times":[0.28411599999890313]},"debugLog":{"times":[0.15052400000058697]}}},{"functionName":"private_kernel_inner","time":154.80488299999706},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":40.12958300000173,"oracles":{}},{"functionName":"private_kernel_inner","time":152.8559090000017},{"functionName":"private_kernel_reset","time":240.85674799999833},{"functionName":"private_kernel_tail","time":96.47447000000102}],"unaccounted":504.0074999999997}}
09:42:13
aztec-1 | [09:42:12.688]
INFO:
node Received tx 0x191764a6c9967fb9d3a801d6d4d24b96448e473fa634ec4f33eec590a9dca287
{"txHash":"0x191764a6c9967fb9d3a801d6d4d24b96448e473fa634ec4f33eec590a9dca287"}
09:42:13
aztec-1 | [09:42:12.688]
INFO:
pxe:service Sent transaction 0x191764a6c9967fb9d3a801d6d4d24b96448e473fa634ec4f33eec590a9dca287
09:42:15
aztec-1 | [09:42:14.549]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 1.6891866120000014s
{"duration":1.6891866120000014,"rate":24428.32527019813,"totalPublicGas":{"daGas":4096,"l2Gas":41264},"totalBlockGas":{"daGas":166912,"l2Gas":66306},"totalSizeInBytes":10688}
09:42:15
aztec-1 | [09:42:14.780]
INFO:
sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 21304.819042830903 mana/s
{"blockHash":"0x2ecb860ba430e8df19e88d309018900b0c073d5e584944149a0e9305afdbd68e","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749808127,"version":2094187016},"txHashes":["0x191764a6c9967fb9d3a801d6d4d24b96448e473fa634ec4f33eec590a9dca287"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":1936.781712,"publicProcessDuration":1689.3264549999985,"rollupCircuitsDuration":1924.4459339999994,"txCount":1,"blockNumber":4,"blockTimestamp":1749808127,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
09:42:15
aztec-1 | [09:42:15.145]
INFO:
pxe:service Proving completed in 2631.392619000002ms
{"timings":{"total":2631.392619000002,"sync":125.26358799999798,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":469.0273709999965,"oracles":{"debugLog":{"times":[0.1811939999970491,0.09714299999905052,0.09029200000077253,0.21420499999658205,0.13915300000007846,0.12911299999905168,0.10636199999862583,0.1850449999983539]},"fetchTaggedLogs":{"times":[178.99830700000166]},"loadCapsule":{"times":[1.0249430000003485,1.0902639999985695,0.5739030000004277,0.5607720000007248,0.8575899999996182,2.841774999997142,0.4448400000001129]},"getSharedSecret":{"times":[9.52008399999977]},"aes128Decrypt":{"times":[0.21794500000032713,0.7299870000024384]},"getPublicKeysAndPartialAddress":{"times":[9.266827999999805]},"getKeyValidationRequest":{"times":[10.006594999998924]},"storeCapsule":{"times":[2.888104999998177,4.208244000001287,24.796168000000762]},"deleteCapsule":{"times":[4.25204599999779]},"validateEnqueuedNotesAndEvents":{"times":[21.640816999999515]},"getNotes":{"times":[2.111907999998948]},"getAuthWitness":{"times":[0.21450499999991735]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07543199999781791]},"callPrivateFunction":{"times":[849.6883449999987,127.56116000000111]},"notifyEnqueuedPublicFunctionCall":{"times":[0.06616200000280514]}}},{"functionName":"private_kernel_init","time":56.11347199999727},{"functionName":"ContractClassRegisterer:register","time":832.5439400000032,"oracles":{"loadCapsule":{"times":[11.1541810000017]},"notifyCreatedNullifier":{"times":[0.4395699999986391]},"debugLog":{"times":[0.1536740000010468]},"notifyCreatedContractClassLog":{"times":[4.759037000003445]}}},{"functionName":"private_kernel_inner","time":114.63956899999903},{"functionName":"ContractInstanceDeployer:deploy","time":67.27797400000054,"oracles":{"storeInExecutionCache":{"times":[0.05852199999935692]},"callPrivateFunction":{"times":[67.176360999998]},"notifyCreatedNullifier":{"times":[0.22967499999867869]},"debugLog":{"times":[0.13833299999896553]}}},{"functionName":"private_kernel_inner","time":125.72826799999893},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":46.28563099999883,"oracles":{}},{"functionName":"private_kernel_inner","time":93.8276509999996},{"functionName":"private_kernel_reset","time":158.76710100000128},{"functionName":"private_kernel_tail","time":83.20606200000111}],"unaccounted":458.71199200000774}}
09:42:15
aztec-1 | [09:42:15.303]
INFO:
node Received tx 0x166eb525f727c0b650cdc5ab5be0662eb7856c4305a2f8254573fdfcc2db2b81
{"txHash":"0x166eb525f727c0b650cdc5ab5be0662eb7856c4305a2f8254573fdfcc2db2b81"}
09:42:15
aztec-1 | [09:42:15.303]
INFO:
pxe:service Sent transaction 0x166eb525f727c0b650cdc5ab5be0662eb7856c4305a2f8254573fdfcc2db2b81
09:42:15
aztec-1 | [09:42:15.469]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4
09:42:15
aztec-1 | [09:42:15.472]
INFO:
aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4.
09:42:15
aztec-1 | [09:42:15.476]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:42:15
aztec-1 | [09:42:15.482]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808163
09:42:15
aztec-1 | [09:42:15.482]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:49:23.000Z
{"offset":427518,"timeMs":1749808163000}
09:42:15
aztec-1 | [09:42:15.482]
INFO:
aztecjs:utils:watcher Slot 5 was filled, jumped to next slot
09:42:15
aztec-1 | [09:42:15.621]
INFO:
archiver Downloaded L2 block 4
{"blockHash":"0x2ecb860ba430e8df19e88d309018900b0c073d5e584944149a0e9305afdbd68e","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749808127,"version":2094187016},"archiveRoot":"0x190ae829fe73deb04ca8c07753215cec2f2f3c33aed6f27585d435056b5ba9cd","archiveNextLeafIndex":5}
09:42:15
aztec-1 | [09:42:15.626]
INFO:
archiver Updated proven chain to block 4
{"provenBlockNumber":4}
09:42:15
aztec-1 | [09:42:15.646]
INFO:
world_state World state updated with L2 block 4
{"eventName":"l2-block-handled","duration":14.473464999999123,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749808127,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
09:42:16
aztec-1 | [09:42:16.159]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.38538648000000103s
{"duration":0.38538648000000103,"rate":860043.1442223897,"totalPublicGas":{"daGas":10240,"l2Gas":331449},"totalBlockGas":{"daGas":380928,"l2Gas":356491},"totalSizeInBytes":24064}
09:42:16
aztec-1 | [09:42:16.206]
INFO:
sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 748988.6318029157 mana/s
{"blockHash":"0x1e86f23afa7c7b9245688f8d75e8df7d80e8e8529e51a8552c41fe37806248af","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749808163,"version":2094187016},"txHashes":["0x166eb525f727c0b650cdc5ab5be0662eb7856c4305a2f8254573fdfcc2db2b81"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":442.49058499999956,"publicProcessDuration":385.7435579999983,"rollupCircuitsDuration":436.57065200000216,"txCount":1,"blockNumber":5,"blockTimestamp":1749808163,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
09:42:16
aztec-1 | [09:42:16.276]
INFO:
aztecjs:deploy_sent_tx Contract 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 successfully deployed.
09:42:16
aztec-1 | [09:42:16.473]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:42:16
aztec-1 | [09:42:16.478]
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":"0x190ae829fe73deb04ca8c07753215cec2f2f3c33aed6f27585d435056b5ba9cd","blocksSynchedTo":32,"currentL1BlockNumber":33,"provenBlockNumber":4,"provenArchive":"0x190ae829fe73deb04ca8c07753215cec2f2f3c33aed6f27585d435056b5ba9cd","pendingBlockNumber":5,"pendingArchive":"0x0dcdfafd37e1f995441c2c9ab1b195722cca0f8c70708f0dcf0f82aa31fbb1a3"}
09:42:16
aztec-1 | [09:42:16.510]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:42:16
aztec-1 | [09:42:16.510]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:49:35.000Z
{"offset":438490,"timeMs":1749808175000}
09:42:16
aztec-1 | [09:42:16.515]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5
09:42:16
aztec-1 | [09:42:16.517]
INFO:
aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5.
09:42:16
aztec-1 | [09:42:16.538]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:42:16
aztec-1 | error: "Rollup__SlotAlreadyInChain(6, 6)"
09:42:16
aztec-1 | [09:42:16.539]
WARN:
sequencer Cannot propose block 5 at slot 6 due to failed rollup contract check
09:42:16
aztec-1 | [09:42:16.544]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808199
09:42:16
aztec-1 | [09:42:16.544]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:49:59.000Z
{"offset":462456,"timeMs":1749808199000}
09:42:16
aztec-1 | [09:42:16.544]
INFO:
aztecjs:utils:watcher Slot 6 was filled, jumped to next slot
09:42:16
aztec-1 | [09:42:16.680]
INFO:
archiver Downloaded L2 block 5
{"blockHash":"0x1e86f23afa7c7b9245688f8d75e8df7d80e8e8529e51a8552c41fe37806248af","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749808163,"version":2094187016},"archiveRoot":"0x0dcdfafd37e1f995441c2c9ab1b195722cca0f8c70708f0dcf0f82aa31fbb1a3","archiveNextLeafIndex":6}
09:42:16
aztec-1 | [09:42:16.689]
INFO:
archiver Updated proven chain to block 5
{"provenBlockNumber":5}
09:42:16
aztec-1 | [09:42:16.753]
INFO:
world_state World state updated with L2 block 5
{"eventName":"l2-block-handled","duration":16.40342899999814,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749808163,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
09:42:17
aztec-1 | BananaCoin: 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
09:42:17
aztec-1 | FPC: 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
09:42:17
aztec-1 | [09:42:17.569]
INFO:
aztecjs:deploy_sent_tx Contract 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf successfully deployed.
09:42:17
aztec-1 | [09:42:17.603]
WARN:
pxe:service No artifact found for contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e when looking for its metadata
09:42:17
aztec-1 | [09:42:17.607]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e as part of deployment for 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
09:42:17
aztec-1 | [09:42:17.760]
INFO:
pxe:service Added contract SponsoredFPC at 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 with class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e
09:42:20
aztec-1 | [09:42:20.081]
INFO:
pxe:service Proving completed in 2300.907244000002ms
{"timings":{"total":2300.907244000002,"sync":21.274059000003035,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":95.32675399999789,"oracles":{"callPrivateFunction":{"times":[787.0621370000008,138.1227869999966,40.55525200000193]}}},{"functionName":"private_kernel_init","time":55.3867059999975},{"functionName":"ContractClassRegisterer:register","time":769.896560000001,"oracles":{"loadCapsule":{"times":[2.078606999999465]},"notifyCreatedNullifier":{"times":[0.3172470000026806]},"debugLog":{"times":[0.14563400000042748]},"notifyCreatedContractClassLog":{"times":[4.312746999999945]}}},{"functionName":"private_kernel_inner","time":139.68969299999662},{"functionName":"ContractInstanceDeployer:deploy","time":72.53754200000185,"oracles":{"storeInExecutionCache":{"times":[0.0725109999984852]},"callPrivateFunction":{"times":[70.17395899999974]},"notifyCreatedNullifier":{"times":[0.23170499999832828]},"debugLog":{"times":[0.14038399999844842]}}},{"functionName":"private_kernel_inner","time":101.70801800000118},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":51.74691399999938,"oracles":{}},{"functionName":"private_kernel_inner","time":141.45460300000195},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":30.420884999999544,"oracles":{"debugLog":{"times":[0.15019399999800953]}}},{"functionName":"private_kernel_inner","time":112.38093799999842},{"functionName":"private_kernel_reset","time":184.0156390000011},{"functionName":"private_kernel_tail","time":84.6591440000011}],"unaccounted":440.4097890000012}}
09:42:20
aztec-1 | [09:42:20.210]
INFO:
node Received tx 0x158272ae68f37fdccade78e0758788d7f02e07c9c5ae2cb53568481ecce3feed
{"txHash":"0x158272ae68f37fdccade78e0758788d7f02e07c9c5ae2cb53568481ecce3feed"}
09:42:20
aztec-1 | [09:42:20.210]
INFO:
pxe:service Sent transaction 0x158272ae68f37fdccade78e0758788d7f02e07c9c5ae2cb53568481ecce3feed
09:42:20
aztec-1 | [09:42:20.317]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03701538299999811s
{"duration":0.03701538299999811,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152}
09:42:20
aztec-1 | [09:42:20.342]
INFO:
sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x11c96cdb9ca21741951001ee1c4ab9fdb74672e78c1e4e89665212e965246b4d","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749808199,"version":2094187016},"txHashes":["0x158272ae68f37fdccade78e0758788d7f02e07c9c5ae2cb53568481ecce3feed"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":68.70689600000333,"publicProcessDuration":37.14511499999935,"rollupCircuitsDuration":63.30176399999982,"txCount":1,"blockNumber":6,"blockTimestamp":1749808199,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:42:20
aztec-1 | [09:42:20.565]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6
09:42:20
aztec-1 | [09:42:20.568]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:42:20
aztec-1 | [09:42:20.569]
INFO:
aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6.
09:42:20
aztec-1 | [09:42:20.584]
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":"0x0dcdfafd37e1f995441c2c9ab1b195722cca0f8c70708f0dcf0f82aa31fbb1a3","blocksSynchedTo":34,"currentL1BlockNumber":35,"provenBlockNumber":5,"provenArchive":"0x0dcdfafd37e1f995441c2c9ab1b195722cca0f8c70708f0dcf0f82aa31fbb1a3","pendingBlockNumber":6,"pendingArchive":"0x1bd417b6043d2df6e25f7ca81ba63d1ae99947e3d4c177c23ab5a1fcf6fc1c8f"}
09:42:20
aztec-1 | [09:42:20.619]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808235
09:42:20
aztec-1 | [09:42:20.619]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:50:35.000Z
{"offset":494381,"timeMs":1749808235000}
09:42:20
aztec-1 | [09:42:20.619]
INFO:
aztecjs:utils:watcher Slot 7 was filled, jumped to next slot
09:42:20
aztec-1 | [09:42:20.710]
INFO:
archiver Downloaded L2 block 6
{"blockHash":"0x11c96cdb9ca21741951001ee1c4ab9fdb74672e78c1e4e89665212e965246b4d","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749808199,"version":2094187016},"archiveRoot":"0x1bd417b6043d2df6e25f7ca81ba63d1ae99947e3d4c177c23ab5a1fcf6fc1c8f","archiveNextLeafIndex":7}
09:42:20
aztec-1 | [09:42:20.720]
INFO:
archiver Updated proven chain to block 6
{"provenBlockNumber":6}
09:42:20
aztec-1 | [09:42:20.746]
INFO:
world_state World state updated with L2 block 6
{"eventName":"l2-block-handled","duration":14.764501999998174,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749808199,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:42:21
aztec-1 | SponsoredFPC: 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
09:42:21
aztec-1 | [09:42:21.232]
INFO:
aztecjs:deploy_sent_tx Contract 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 successfully deployed.
09:42:21
aztec-1 | [09:42:21.238]
INFO:
cli Aztec Server listening on port 8080
{"l2CircuitsVkTreeRoot":"0x2d2655c513c2e8feb8c7411b7e807c66287b57bb60572ce85ce1fe0a1e0c925d","l2ProtocolContractsTreeRoot":"0x222b5a95b10e0a2dfba4648739629a01cecd69a0bef23a9fc22f846735d7cc71"}
09:42:30
box-1 | \
09:42:30
box-1 | Running 1
test using 1
worker
09:42:30
box-1 |
09:42:31
box-1 | 1 [webkit] › browser.spec.ts:3:1 › test
09:42:57
aztec-1 | [09:42:56.769]
WARN:
aztecjs:utils:watcher L1 is more than 1 L2 slot behind wall time. Warping to wall time
09:42:57
aztec-1 | [09:42:56.772]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808272
09:42:57
aztec-1 | [09:42:56.979]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
09:42:57
aztec-1 | [09:42:56.979]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:51:12.000Z
{"offset":495021,"timeMs":1749808272000}
09:43:09
aztec-1 | [09:43:08.745]
INFO:
node Received tx 0x204b73bbb9d543ba423d70744557f9ce0399fc8dd857b29e675d5f34a5902da0
{"txHash":"0x204b73bbb9d543ba423d70744557f9ce0399fc8dd857b29e675d5f34a5902da0"}
09:43:09
aztec-1 | [09:43:08.869]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.036897496000005046s
{"duration":0.036897496000005046,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":26112,"l2Gas":9344},"totalSizeInBytes":1920}
09:43:09
aztec-1 | [09:43:08.904]
INFO:
sequencer Built block 7 for slot 9 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x28778c52b9dac91a30cc7478db7cacd69e68cb12693d3d4b21b755f4d491879a","globalVariables":{"blockNumber":7,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":9,"timestamp":1749808271,"version":2094187016},"txHashes":["0x204b73bbb9d543ba423d70744557f9ce0399fc8dd857b29e675d5f34a5902da0"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":80.17479299999832,"publicProcessDuration":37.1354699999938,"rollupCircuitsDuration":75.06112899999425,"txCount":1,"blockNumber":7,"blockTimestamp":1749808271,"privateLogCount":2,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:43:09
aztec-1 | [09:43:09.149]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 7
09:43:09
aztec-1 | [09:43:09.155]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:43:09
aztec-1 | [09:43:09.158]
INFO:
aztecjs:cheat_codes Proven tip moved: 6 -> 7. Pending tip: 7.
09:43:09
aztec-1 | [09:43:09.161]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808307
09:43:09
aztec-1 | [09:43:09.161]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:51:47.000Z
{"offset":517839,"timeMs":1749808307000}
09:43:09
aztec-1 | [09:43:09.161]
INFO:
aztecjs:utils:watcher Slot 9 was filled, jumped to next slot
09:43:09
aztec-1 | [09:43:09.258]
INFO:
archiver Downloaded L2 block 7
{"blockHash":"0x28778c52b9dac91a30cc7478db7cacd69e68cb12693d3d4b21b755f4d491879a","blockNumber":7,"txCount":1,"globalVariables":{"blockNumber":7,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":9,"timestamp":1749808271,"version":2094187016},"archiveRoot":"0x1f0eb0fb2b6c820255c406af5975054026e2d495ea416e8744e239a8c1ef28e8","archiveNextLeafIndex":8}
09:43:09
aztec-1 | [09:43:09.264]
INFO:
archiver Updated proven chain to block 7
{"provenBlockNumber":7}
09:43:09
aztec-1 | [09:43:09.320]
INFO:
world_state World state updated with L2 block 7
{"eventName":"l2-block-handled","duration":19.757962000003317,"unfinalisedBlockNumber":7,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":7,"blockTimestamp":1749808271,"privateLogCount":2,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
09:43:19
aztec-1 | [09:43:19.463]
INFO:
node Received tx 0x109f96f216500e4d0bc7e4e76b91872654ef820297ba5131ea5a33cbe772875a
{"txHash":"0x109f96f216500e4d0bc7e4e76b91872654ef820297ba5131ea5a33cbe772875a"}
09:43:19
aztec-1 | [09:43:19.510]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.0197525719999976s
{"duration":0.0197525719999976,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:43:19
aztec-1 | [09:43:19.530]
INFO:
sequencer Built block 8 for slot 10 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x297039eaac8591b36385efb0f830abe6fd23a7f0293a7776406d265a106796ae","globalVariables":{"blockNumber":8,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":10,"timestamp":1749808307,"version":2094187016},"txHashes":["0x109f96f216500e4d0bc7e4e76b91872654ef820297ba5131ea5a33cbe772875a"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":45.990589000008185,"publicProcessDuration":19.895974999992177,"rollupCircuitsDuration":41.73003299999982,"txCount":1,"blockNumber":8,"blockTimestamp":1749808307,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:43:19
aztec-1 | [09:43:19.674]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:43:19
aztec-1 | [09:43:19.678]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808343
09:43:19
aztec-1 | [09:43:19.679]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:52:23.000Z
{"offset":543322,"timeMs":1749808343000}
09:43:19
aztec-1 | [09:43:19.679]
INFO:
aztecjs:utils:watcher Slot 10 was filled, jumped to next slot
09:43:19
aztec-1 | [09:43:19.682]
WARN:
archiver Failed to reach L2 block 8 at 40 (latest is 7). Rolling back last synched L1 block number to 39.
{"latestLocalL2BlockNumber":7,"latestLocalL2BlockArchive":"0x1f0eb0fb2b6c820255c406af5975054026e2d495ea416e8744e239a8c1ef28e8","blocksSynchedTo":39,"currentL1BlockNumber":40,"provenBlockNumber":7,"provenArchive":"0x1f0eb0fb2b6c820255c406af5975054026e2d495ea416e8744e239a8c1ef28e8","pendingBlockNumber":8,"pendingArchive":"0x169bb92bdfbfa7ce804bd8c2f0f28147b4a87b78b4324243e1830b5731b0a342"}
09:43:19
aztec-1 | [09:43:19.721]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 8
09:43:19
aztec-1 | [09:43:19.722]
INFO:
aztecjs:cheat_codes Proven tip moved: 7 -> 8. Pending tip: 8.
09:43:19
aztec-1 | [09:43:19.760]
INFO:
archiver Downloaded L2 block 8
{"blockHash":"0x297039eaac8591b36385efb0f830abe6fd23a7f0293a7776406d265a106796ae","blockNumber":8,"txCount":1,"globalVariables":{"blockNumber":8,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":10,"timestamp":1749808307,"version":2094187016},"archiveRoot":"0x169bb92bdfbfa7ce804bd8c2f0f28147b4a87b78b4324243e1830b5731b0a342","archiveNextLeafIndex":9}
09:43:19
aztec-1 | [09:43:19.763]
INFO:
archiver Updated proven chain to block 8
{"provenBlockNumber":8}
09:43:19
aztec-1 | [09:43:19.784]
INFO:
world_state World state updated with L2 block 8
{"eventName":"l2-block-handled","duration":12.911798999994062,"unfinalisedBlockNumber":8,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":8,"blockTimestamp":1749808307,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:43:55
aztec-1 | [09:43:55.763]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808379
09:43:55
aztec-1 | [09:43:55.763]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:52:59.000Z
{"offset":543237,"timeMs":1749808379000}
09:43:55
aztec-1 | [09:43:55.763]
INFO:
aztecjs:utils:watcher Slot 11 was missed, jumped to next slot
09:44:03
✘ 1 [webkit] › browser.spec.ts:3:1 › test (1.5m) 2 [webkit] › browser.spec.ts:3:1 › test (retry #1)
09:44:26
aztec-1 | [09:44:26.569]
INFO:
node Received tx 0x26219f2b729941b696ce662287da0b1e0391d24ac0d330b853dde147394541b0
{"txHash":"0x26219f2b729941b696ce662287da0b1e0391d24ac0d330b853dde147394541b0"}
09:44:32
aztec-1 | [09:44:31.787]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808415
09:44:32
aztec-1 | [09:44:31.787]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:53:35.000Z
{"offset":543213,"timeMs":1749808415000}
09:44:32
aztec-1 | [09:44:31.787]
INFO:
aztecjs:utils:watcher Slot 12 was missed, jumped to next slot
09:44:32
aztec-1 | [09:44:31.906]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.021407651999994413s
{"duration":0.021407651999994413,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":20992,"l2Gas":7834},"totalSizeInBytes":1568}
09:44:32
aztec-1 | [09:44:31.928]
INFO:
sequencer Built block 9 for slot 13 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x1727085f143d7ae95a5fedb3f234c55efa11494ccc734846848838517caac7c6","globalVariables":{"blockNumber":9,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":3760,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":13,"timestamp":1749808415,"version":2094187016},"txHashes":["0x26219f2b729941b696ce662287da0b1e0391d24ac0d330b853dde147394541b0"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":50.58408800000325,"publicProcessDuration":21.566324999992503,"rollupCircuitsDuration":44.51508099999046,"txCount":1,"blockNumber":9,"blockTimestamp":1749808415,"privateLogCount":2,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:44:32
aztec-1 | [09:44:32.134]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:44:32
aztec-1 | [09:44:32.157]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 9
09:44:32
aztec-1 | [09:44:32.158]
INFO:
aztecjs:cheat_codes Proven tip moved: 8 -> 9. Pending tip: 9.
09:44:32
aztec-1 | [09:44:32.199]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
09:44:32
aztec-1 | error: "Rollup__SlotAlreadyInChain(13, 13)"
09:44:32
aztec-1 | [09:44:32.199]
WARN:
sequencer Cannot propose block 9 at slot 13 due to failed rollup contract check
09:44:32
aztec-1 | [09:44:32.205]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808451
09:44:32
aztec-1 | [09:44:32.205]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:54:11.000Z
{"offset":578795,"timeMs":1749808451000}
09:44:32
aztec-1 | [09:44:32.205]
INFO:
aztecjs:utils:watcher Slot 13 was filled, jumped to next slot
09:44:32
aztec-1 | [09:44:32.226]
INFO:
archiver Downloaded L2 block 9
{"blockHash":"0x1727085f143d7ae95a5fedb3f234c55efa11494ccc734846848838517caac7c6","blockNumber":9,"txCount":1,"globalVariables":{"blockNumber":9,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":3760,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":13,"timestamp":1749808415,"version":2094187016},"archiveRoot":"0x2f32c2c5b650a6417fae65273faab0cccf8a45cadff885f798ab74effe9ea4ee","archiveNextLeafIndex":10}
09:44:32
aztec-1 | [09:44:32.229]
INFO:
archiver Updated proven chain to block 9
{"provenBlockNumber":9}
09:44:32
aztec-1 | [09:44:32.256]
INFO:
world_state World state updated with L2 block 9
{"eventName":"l2-block-handled","duration":15.882337999995798,"unfinalisedBlockNumber":9,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":9,"blockTimestamp":1749808415,"privateLogCount":2,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:44:44
aztec-1 | [09:44:44.252]
INFO:
node Received tx 0x1c92ea3fbc919d7b2c80dc5a485ca77a190d71767d23802a5fe7139ace3f112a
{"txHash":"0x1c92ea3fbc919d7b2c80dc5a485ca77a190d71767d23802a5fe7139ace3f112a"}
09:44:44
aztec-1 | [09:44:44.332]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.014708740000001854s
{"duration":0.014708740000001854,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
09:44:44
aztec-1 | [09:44:44.363]
INFO:
sequencer Built block 10 for slot 14 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x2f29fe24ab891e05d64d5f15f101407af113de7c2623fff0f47e42c3ace85a90","globalVariables":{"blockNumber":10,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":3760,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":14,"timestamp":1749808451,"version":2094187016},"txHashes":["0x1c92ea3fbc919d7b2c80dc5a485ca77a190d71767d23802a5fe7139ace3f112a"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":51.89178400000674,"publicProcessDuration":14.813961999985622,"rollupCircuitsDuration":47.4518649999809,"txCount":1,"blockNumber":10,"blockTimestamp":1749808451,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:44:44
aztec-1 | [09:44:44.609]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 10
09:44:44
aztec-1 | [09:44:44.611]
INFO:
aztecjs:cheat_codes Proven tip moved: 9 -> 10. Pending tip: 10.
09:44:44
aztec-1 | [09:44:44.614]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
09:44:44
aztec-1 | [09:44:44.617]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749808487
09:44:44
aztec-1 | [09:44:44.617]
WARN:
foundation:test-date-provider Time set to 2025-06-13T09:54:47.000Z
{"offset":602383,"timeMs":1749808487000}
09:44:44
aztec-1 | [09:44:44.617]
INFO:
aztecjs:utils:watcher Slot 14 was filled, jumped to next slot
09:44:44
aztec-1 | [09:44:44.709]
INFO:
archiver Downloaded L2 block 10
{"blockHash":"0x2f29fe24ab891e05d64d5f15f101407af113de7c2623fff0f47e42c3ace85a90","blockNumber":10,"txCount":1,"globalVariables":{"blockNumber":10,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":3760,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":14,"timestamp":1749808451,"version":2094187016},"archiveRoot":"0x229eb1705e245a07557ad1ee7f6e4ccce86abd55ce214705507e5194459375ea","archiveNextLeafIndex":11}
09:44:44
aztec-1 | [09:44:44.713]
INFO:
archiver Updated proven chain to block 10
{"provenBlockNumber":10}
09:44:44
aztec-1 | [09:44:44.742]
INFO:
world_state World state updated with L2 block 10
{"eventName":"l2-block-handled","duration":13.03095200000098,"unfinalisedBlockNumber":10,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":10,"blockTimestamp":1749808451,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
09:44:52
✓ 2 [webkit] › browser.spec.ts:3:1 › test
(retry #1) (47.7s)
09:44:52
box-1 |
09:44:52
box-1 | 1) [webkit] › browser.spec.ts:3:1 › test ─────────────────────────────────────────────────────────
09:44:52
box-1 |
09:44:52
box-1 | Test timeout of 90000ms exceeded.
09:44:52
box-1 |
09:44:52
box-1 | Error:
expect(locator).toBeVisible
()
09:44:52
box-1 |
09:44:52
box-1 | Locator: getByText('Number is: 1')
09:44:52
box-1 | Expected: visible
09:44:52
box-1 | Received: <element(s) not found>
09:44:52
box-1 | Call log:
09:44:52
box-1 | - expect.toBeVisible with timeout 90000ms
09:44:52
box-1 | - waiting for getByText('Number is: 1')
09:44:52
box-1 |
09:44:52
box-1 |
09:44:52
box-1 | 22 | // Read number
09:44:52
box-1 | 23 | await page
.getByRole(
'button', { name
: 'Read' })
.click()
;
09:44:52
box-1 | > 24 | await expect(page
.getByText(
'Number is: 1'))
.toBeVisible()
;
09:44:52
box-1 | | ^
09:44:52
box-1 | 25 | })
;
09:44:52
box-1 | 26 |
09:44:52
box-1 | at /root/aztec-packages/boxes/boxes/vite/tests/browser.spec.ts:24:48
09:44:52
box-1 |
09:44:52
box-1 | attachment #1: screenshot (image/png) ──────────────────────────────────────────────────────────
09:44:52
box-1 | test-results/browser-test-webkit/test-failed-1.png
09:44:52
box-1 | ────────────────────────────────────────────────────────────────────────────────────────────────
09:44:52
box-1 |
09:44:52
box-1 | 1 flaky
09:44:52
box-1 | [webkit] › browser.spec.ts:3:1 › test ──────────────────────────────────────────────────────────
09:44:52
box-1 | \
09:44:52 Aborting on container exit...
09:44:52
box-1 exited with code 0
09:44:52 [?25l[+] Stopping 3/3
09:44:53 ✔ Container vite-webkit-box-1 Stopped0.0s
09:44:53 ✔ Container vite-webkit-aztec-1 Stopped1.0s
09:44:53 ✔ Container vite-webkit-ethereum-1 Stopped0.3s