Parent Log:
http://ci.aztec-labs.com/47a60f1790b9fea0
Command: 1d4bec6adaae2249:ONLY_TERM_PARENT=1 BOX=vite BROWSER=firefox run_compose_test vite-firefox box boxes
Commit:
https://github.com/AztecProtocol/aztec-packages/commit/fa159c5d52a45358479f951a547fed37f141a6ed
Env: REF_NAME=gh-readonly-queue/next/pr-15054-184d5f7d956dd7251670a2dd6728f313cfdf3caf CURRENT_VERSION=0.87.6 CI_FULL=0
Date: Fri Jun 13 22:40:16 UTC 2025
System: ARCH=arm64 CPUS=64 MEM=247Gi HOSTNAME=pr-15054_arm64_a1-fast
Resources: CPU_LIST=0-63 CPUS=2 MEM=8g TIMEOUT=600s
History: [?25l
[+] Running 4/4
22:40:17
✔ Network vite-firefox_default
Created0.1s
22:40:17
✔ Container vite-firefox-ethereum-1
Created0.1s
22:40:17
✔ Container vite-firefox-aztec-1
Created0.2s
22:40:17
✔ Container vite-firefox-box-1
Created0.2s
22:40:17 [?25hAttaching to aztec-1, box-1, ethereum-1
22:40:21
aztec-1 |
22:40:21
aztec-1 | _
22:40:21
aztec-1 | /\ | |
22:40:21
aztec-1 | / \ ___| |_ ___ ___
22:40:21
aztec-1 | / /\ \ |_ / __/ _ \/ __|
22:40:21
aztec-1 | / ____ \ / /| || __/ (__
22:40:21
aztec-1 | /_/___ \_\/___|\__\___|\___|
22:40:21
aztec-1 |
22:40:21
aztec-1 | https://github.com/AztecProtocol
22:40:21
aztec-1 |
22:40:21
aztec-1 |
22:40:21
aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by...
22:40:21
aztec-1 | Setting up test accounts
22:40:23
aztec-1 | [22:40:21.898]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-KmVl1P/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
22:40:23
aztec-1 | [22:40:22.159]
INFO:
sandbox Calling anvil_setBlockTimestampInterval with params: [12]
22:40:23
aztec-1 | [22:40:22.163]
WARN:
sandbox Set block interval to 12
22:40:23
aztec-1 | [22:40:22.330]
INFO:
sandbox Waiting for 7 transactions to be mined...
22:40:23
aztec-1 | [22:40:22.355]
INFO:
sandbox All transactions mined successfully
22:40:23
aztec-1 | [22:40:22.434]
INFO:
sandbox Waiting for 9 transactions to be mined...
22:40:23
aztec-1 | [22:40:22.457]
INFO:
sandbox All transactions mined successfully
22:40:23
aztec-1 | [22:40:22.589]
INFO:
sandbox Waiting for 11 transactions to be mined...
22:40:23
aztec-1 | [22:40:22.610]
INFO:
sandbox All transactions mined successfully
22:40:23
aztec-1 | [22:40:22.671]
INFO:
sandbox Waiting for 12 transactions to be mined...
22:40:23
aztec-1 | [22:40:22.693]
INFO:
sandbox All transactions mined successfully
22:40:23
aztec-1 | [22:40:22.698]
INFO:
sandbox Waiting for 12 transactions to be mined...
22:40:23
aztec-1 | [22:40:22.718]
INFO:
sandbox All transactions mined successfully
22:40:23
aztec-1 | [22:40:22.732]
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"}
22:40:23
aztec-1 | [22:40:22.732]
INFO:
sandbox Handing over to governance
22:40:23
aztec-1 | [22:40:22.739]
INFO:
aztecjs:utils:watcher Watcher started for rollup at 0x3Aa5ebB10DC797CAC828524e59A333d0A371443c
22:40:23
aztec-1 | [22:40:22.740]
INFO:
telemetry:client Using NoopTelemetryClient
22:40:23
aztec-1 | [22:40:22.740]
INFO:
blob-sink:client Creating local blob sink client.
22:40:23
aztec-1 | [22:40:22.743]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:40:23
aztec-1 | [22:40:22.743]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:45:05.000Z
{"offset":282257,"timeMs":1749854705000}
22:40:23
aztec-1 | [22:40:22.765]
INFO:
archiver:lmdb Starting data store with maxReaders 16
22:40:23
aztec-1 | [22:40:23.089]
INFO:
archiver Starting archiver sync to rollup contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c from L1 block 20 to current L1 block 24 with hash 0x6194637197b56893052917b592b726fe862a96badcb5232794ec2a827f9e50bb
{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0xf0dce62f28ba15fa30e0f69fffe2a1400fffdd56be6b3632bda317c1174c156b","l1BlockNumber":20}}
22:40:23
aztec-1 | [22:40:23.109]
INFO:
archiver Initial archiver sync to L1 block 24 complete.
{"l1BlockNumber":24,"syncPoint":{"blocksSynchedTo":24,"messagesSynchedTo":{"l1BlockHash":"0x6194637197b56893052917b592b726fe862a96badcb5232794ec2a827f9e50bb","l1BlockNumber":24}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}}
22:40:23
aztec-1 | [22:40:23.114]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-PjbG6D/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
22:40:23
aztec-1 | [22:40:23.341]
INFO:
world_state Created world state synchroniser with block history of 64
22:40:23
aztec-1 | [22:40:23.341]
WARN:
node Aztec node is accepting fake proofs
22:40:23
aztec-1 | [22:40:23.349]
INFO:
p2p:lmdb-v2 Starting data store with maxReaders 16
22:40:23
aztec-1 | [22:40:23.361]
INFO:
p2p-archive:lmdb-v2 Starting data store with maxReaders 16
22:40:23
aztec-1 | [22:40:23.375]
INFO:
p2p-peer:lmdb-v2 Starting data store with maxReaders 16
22:40:23
aztec-1 | [22:40:23.388]
INFO:
p2p:tx_pool Setting maximum tx mempool size
{"maxTxPoolSize":100000000}
22:40:23
aztec-1 | [22:40:23.391]
INFO:
world_state Started world state synchronizer from block 1
22:40:23
aztec-1 | [22:40:23.396]
INFO:
p2p Starting p2p client from block 0 with empty mempools
22:40:23
aztec-1 | [22:40:23.602]
INFO:
epoch-prune-watcher EpochPruneWatcher initialized
22:40:23
aztec-1 | [22:40:23.646]
INFO:
slasher Starting Slasher client...
22:40:23
aztec-1 | [22:40:23.654]
INFO:
sequencer-client Deploying forwarder contract
22:40:23
aztec-1 | [22:40:23.672]
INFO:
sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac
22:40:23
aztec-1 | [22:40:23.677]
INFO:
validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
22:40:23
aztec-1 | [22:40:23.678]
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}
22:40:23
aztec-1 | [22:40:23.679]
INFO:
sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
22:40:23
aztec-1 | [22:40:23.680]
INFO:
node Aztec Node version: 0.87.6
22:40:23
aztec-1 | [22:40:23.680]
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"}
22:40:23
aztec-1 | [22:40:23.682]
INFO:
pxe:data:lmdb Starting data store with maxReaders 16
22:40:23
aztec-1 | [22:40:23.688]
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.
{"now":1749854705,"syncedToL1Ts":1749854705,"syncedToL2Slot":1,"nextL2Slot":1,"nextL2SlotTs":1749854693,"l1SlotDuration":12,"rollup":{"blockNumber":1,"slot":2,"timeOfNextL1Slot":1749854729},"newBlockNumber":1,"expectedSlot":1}
22:40:23
aztec-1 | [22:40:23.788]
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.
{"now":1749854705,"syncedToL1Ts":1749854705,"syncedToL2Slot":1,"nextL2Slot":1,"nextL2SlotTs":1749854693,"l1SlotDuration":12,"rollup":{"blockNumber":1,"slot":2,"timeOfNextL1Slot":1749854729},"newBlockNumber":1,"expectedSlot":1}
22:40:23
aztec-1 | [22:40:23.876]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:40:23
aztec-1 | [22:40:23.876]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:45:17.000Z
{"offset":293124,"timeMs":1749854717000}
22:40:23
aztec-1 | Setting up funded test accounts...
22:40:24
aztec-1 | [22:40:23.993]
INFO:
pxe:service Started PXE connected to chain 31337 version 17452536
22:40:24
aztec-1 | [22:40:24.067]
INFO:
pxe:service Registered account 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
22:40:24
aztec-1 | [22:40:24.122]
WARN:
pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata
22:40:24
aztec-1 | [22:40:24.125]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
22:40:24
aztec-1 | [22:40:24.260]
INFO:
pxe:service Added contract SchnorrAccount at 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
22:40:26
aztec-1 | [22:40:26.289]
INFO:
pxe:service Proving completed in 1998.9045810000007ms
{"timings":{"total":1998.9045810000007,"sync":3.9488949999995384,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":118.2913320000016,"oracles":{"callPrivateFunction":{"times":[543.9657770000003,251.39017000000058,159.496349]}}},{"functionName":"private_kernel_init","time":84.69176299999981},{"functionName":"ContractClassRegisterer:register","time":530.4571539999997,"oracles":{"loadCapsule":{"times":[6.061681999999564]},"notifyCreatedNullifier":{"times":[0.6007229999995616]},"debugLog":{"times":[0.3976839999995718]},"notifyCreatedContractClassLog":{"times":[5.2260639999994964]}}},{"functionName":"private_kernel_inner","time":117.15149100000053},{"functionName":"SchnorrAccount:constructor","time":232.64206300000023,"oracles":{"getContractInstance":{"times":[4.4619659999998476]},"debugLog":{"times":[0.14181700000062847,0.09868999999980588]},"fetchTaggedLogs":{"times":[31.540121]},"loadCapsule":{"times":[0.36848899999949936,0.21747299999969982,0.19809199999963312]},"validateEnqueuedNotesAndEvents":{"times":[5.873257000000194]},"notifyCreatedNullifier":{"times":[0.2821850000000268,0.23272799999995186]},"notifyCreatedNote":{"times":[0.591005999999652]},"getRandomField":{"times":[0.23919699999987643,0.13585599999987608,0.0951919999997699,0.10569399999985762,0.2561709999999948,0.08631500000046799,0.07203200000003562,0.06398499999977503,0.07203099999969709,0.07741700000042329,0.06023399999958201]},"getIndexedTaggingSecretAsSender":{"times":[37.75545199999942]},"incrementAppTaggingSecretIndexAsSender":{"times":[19.89264699999967]}}},{"functionName":"private_kernel_inner","time":104.83206700000028},{"functionName":"SchnorrAccount:entrypoint","time":139.42500599999948,"oracles":{"debugLog":{"times":[0.14506600000004255,0.09360199999991892,0.24852800000007846]},"fetchTaggedLogs":{"times":[31.491758000000118]},"loadCapsule":{"times":[0.23214999999981956,0.18387299999994866,0.19532100000014907]},"validateEnqueuedNotesAndEvents":{"times":[6.874274000000696]},"getNotes":{"times":[1.4350789999998597]},"getAuthWitness":{"times":[0.29291899999952875]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.7348179999999047]}}},{"functionName":"private_kernel_inner","time":102.31157100000019},{"functionName":"private_kernel_reset","time":165.16421000000082},{"functionName":"private_kernel_tail","time":66.87170499999957}],"unaccounted":333.1173239999989}}
22:40:26
aztec-1 | [22:40:26.383]
INFO:
node Received tx 0x094ce59c6c89649aa4898e4cdd88a4a71309529646205202421d46b8f9373a78
{"txHash":"0x094ce59c6c89649aa4898e4cdd88a4a71309529646205202421d46b8f9373a78"}
22:40:26
aztec-1 | [22:40:26.383]
INFO:
pxe:service Sent transaction 0x094ce59c6c89649aa4898e4cdd88a4a71309529646205202421d46b8f9373a78
22:40:26
aztec-1 | [22:40:26.465]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.028843933999998624s
{"duration":0.028843933999998624,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344}
22:40:26
aztec-1 | [22:40:26.488]
INFO:
sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x19f54c0dd768713d3376e905deca4f1f46d49f6eaa5e5f06cacd8b6012ed0623","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749854729,"version":17452536},"txHashes":["0x094ce59c6c89649aa4898e4cdd88a4a71309529646205202421d46b8f9373a78"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":60.04768899999908,"publicProcessDuration":29.254789000000528,"rollupCircuitsDuration":53.839083000000755,"txCount":1,"blockNumber":1,"blockTimestamp":1749854729,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
22:40:29
aztec-1 | [22:40:29.192]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1
22:40:29
aztec-1 | [22:40:29.195]
INFO:
aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1.
22:40:29
aztec-1 | [22:40:29.197]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
22:40:29
aztec-1 | [22:40:29.278]
INFO:
archiver Downloaded L2 block 1
{"blockHash":"0x19f54c0dd768713d3376e905deca4f1f46d49f6eaa5e5f06cacd8b6012ed0623","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749854729,"version":17452536},"archiveRoot":"0x208860c499cae85881566a9f8f89a0cdabc8114c42e6ea5d4c8b30b6fd77e511","archiveNextLeafIndex":2}
22:40:29
aztec-1 | [22:40:29.285]
INFO:
archiver Updated proven chain to block 1
{"provenBlockNumber":1}
22:40:29
aztec-1 | [22:40:29.335]
INFO:
world_state World state updated with L2 block 1
{"eventName":"l2-block-handled","duration":15.416064999999435,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749854729,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
22:40:29
aztec-1 | [22:40:29.410]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:40:29
aztec-1 | [22:40:29.410]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:45:29.000Z
{"offset":299590,"timeMs":1749854729000}
22:40:29
aztec-1 | [22:40:29.428]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749854765
22:40:29
aztec-1 | [22:40:29.428]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:46:05.000Z
{"offset":335572,"timeMs":1749854765000}
22:40:29
aztec-1 | [22:40:29.428]
INFO:
aztecjs:utils:watcher Slot 2 was filled, jumped to next slot
22:40:29
aztec-1 | [22:40:29.456]
INFO:
pxe:service Registered account 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
22:40:29
aztec-1 | [22:40:29.558]
INFO:
pxe:service Added contract SchnorrAccount at 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
22:40:31
aztec-1 | [22:40:30.825]
INFO:
pxe:service Proving completed in 1248.0515769999984ms
{"timings":{"total":1248.0515769999984,"sync":23.07052300000032,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":75.34696200000144,"oracles":{"callPrivateFunction":{"times":[302.6814059999997,185.1063639999993]}}},{"functionName":"private_kernel_init","time":51.03501900000083},{"functionName":"SchnorrAccount:constructor","time":283.98287899999923,"oracles":{"getContractInstance":{"times":[3.279644999998709]},"debugLog":{"times":[0.14389800000026298,0.09021099999881699]},"fetchTaggedLogs":{"times":[71.90485699999954]},"loadCapsule":{"times":[0.3552530000015395,0.31048399999963294,0.20831100000032166]},"validateEnqueuedNotesAndEvents":{"times":[6.580160999999862]},"notifyCreatedNullifier":{"times":[0.2878999999993539,0.17743400000108522]},"notifyCreatedNote":{"times":[0.14083300000129384]},"getRandomField":{"times":[0.11503799999991315,0.12096099999871512,0.09275400000115042,0.0943890000016836,0.07807499999944412,0.06606800000008661,0.064899000000878,0.07247599999936938,0.06687500000043656,0.06143800000063493,0.06876100000044971]},"getIndexedTaggingSecretAsSender":{"times":[52.21093699999983]},"incrementAppTaggingSecretIndexAsSender":{"times":[26.125824000000648]}}},{"functionName":"private_kernel_inner","time":99.20630699999856},{"functionName":"SchnorrAccount:entrypoint","time":163.3855709999989,"oracles":{"debugLog":{"times":[0.141896999999517,0.09770000000025902,0.13651200000094832]},"fetchTaggedLogs":{"times":[58.46270899999945]},"loadCapsule":{"times":[0.17161099999975704,0.13543100000060804,0.1529129999998986]},"validateEnqueuedNotesAndEvents":{"times":[9.317767999998978]},"getNotes":{"times":[3.282783999999083]},"getAuthWitness":{"times":[0.17481000000043423]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.3577129999994213]}}},{"functionName":"private_kernel_inner","time":99.34239200000047},{"functionName":"private_kernel_reset","time":160.93665200000032},{"functionName":"private_kernel_tail","time":63.56290099999933}],"unaccounted":228.18237099999897}}
22:40:31
aztec-1 | [22:40:30.866]
INFO:
node Received tx 0x1c7da37e21a5c262d46d8588392042cd721e60d1434a2947d51882ed2640391a
{"txHash":"0x1c7da37e21a5c262d46d8588392042cd721e60d1434a2947d51882ed2640391a"}
22:40:31
aztec-1 | [22:40:30.866]
INFO:
pxe:service Sent transaction 0x1c7da37e21a5c262d46d8588392042cd721e60d1434a2947d51882ed2640391a
22:40:31
aztec-1 | [22:40:30.931]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.019963305999999646s
{"duration":0.019963305999999646,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
22:40:31
aztec-1 | [22:40:30.949]
INFO:
sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x13550de2bd6a3cd026d7624b4b2da54cc2ae65000f089379ec9bbc6e9f453d55","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749854765,"version":17452536},"txHashes":["0x1c7da37e21a5c262d46d8588392042cd721e60d1434a2947d51882ed2640391a"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":45.11229500000081,"publicProcessDuration":20.042073000000528,"rollupCircuitsDuration":40.51498300000094,"txCount":1,"blockNumber":2,"blockTimestamp":1749854765,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
22:40:31
aztec-1 | [22:40:31.124]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2
22:40:31
aztec-1 | [22:40:31.128]
INFO:
aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2.
22:40:31
aztec-1 | [22:40:31.133]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749854801
22:40:31
aztec-1 | [22:40:31.133]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:46:41.000Z
{"offset":369867,"timeMs":1749854801000}
22:40:31
aztec-1 | [22:40:31.133]
INFO:
aztecjs:utils:watcher Slot 3 was filled, jumped to next slot
22:40:31
aztec-1 | [22:40:31.136]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
22:40:31
aztec-1 | [22:40:31.160]
INFO:
archiver Downloaded L2 block 2
{"blockHash":"0x13550de2bd6a3cd026d7624b4b2da54cc2ae65000f089379ec9bbc6e9f453d55","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749854765,"version":17452536},"archiveRoot":"0x08e584f8b74263dd84a2b4ea009029152dd082a8f20d6fb6744c4d45fdd1db73","archiveNextLeafIndex":3}
22:40:31
aztec-1 | [22:40:31.163]
INFO:
archiver Updated proven chain to block 2
{"provenBlockNumber":2}
22:40:31
aztec-1 | [22:40:31.181]
INFO:
world_state World state updated with L2 block 2
{"eventName":"l2-block-handled","duration":12.813998999999967,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749854765,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
22:40:31
aztec-1 | [22:40:31.297]
INFO:
pxe:service Registered account 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
22:40:31
aztec-1 | [22:40:31.402]
INFO:
pxe:service Added contract SchnorrAccount at 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
22:40:32
aztec-1 | [22:40:32.608]
INFO:
pxe:service Proving completed in 1198.7994199999994ms
{"timings":{"total":1198.7994199999994,"sync":23.125895000001037,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":66.41434899999695,"oracles":{"callPrivateFunction":{"times":[276.39870599999995,193.0900619999993]}}},{"functionName":"private_kernel_init","time":49.59989399999904},{"functionName":"SchnorrAccount:constructor","time":259.1327160000019,"oracles":{"getContractInstance":{"times":[1.938441000000239]},"debugLog":{"times":[0.12302900000031514,0.0745839999999589]},"fetchTaggedLogs":{"times":[82.251522999999]},"loadCapsule":{"times":[0.1501719999996567,0.1518869999999879,0.12660700000014913]},"validateEnqueuedNotesAndEvents":{"times":[6.941839999999502]},"notifyCreatedNullifier":{"times":[0.20913499999915075,0.22652100000050268]},"notifyCreatedNote":{"times":[0.1057199999995646]},"getRandomField":{"times":[0.10155099999974482,0.12036199999965902,0.06770200000028126,0.06476699999984703,0.054487999999764725,0.04676600000129838,0.05422299999918323,0.05306100000052538,0.05910099999891827,0.047693999998955405,0.05737599999883969]},"getIndexedTaggingSecretAsSender":{"times":[28.58886899999925]},"incrementAppTaggingSecretIndexAsSender":{"times":[29.0560749999986]}}},{"functionName":"private_kernel_inner","time":98.76810099999966},{"functionName":"SchnorrAccount:entrypoint","time":173.57584999999926,"oracles":{"debugLog":{"times":[0.11879299999964132,0.06759600000077626,0.13565299999936542]},"fetchTaggedLogs":{"times":[75.31696000000011]},"loadCapsule":{"times":[0.14370400000007066,0.14452600000004168,0.12749100000110047]},"validateEnqueuedNotesAndEvents":{"times":[5.109951000000365]},"getNotes":{"times":[1.0074430000004213]},"getAuthWitness":{"times":[0.15534400000069581]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.34950800000115123]}}},{"functionName":"private_kernel_inner","time":98.11540100000093},{"functionName":"private_kernel_reset","time":159.7343810000002},{"functionName":"private_kernel_tail","time":62.76570100000026}],"unaccounted":207.56713200000013}}
22:40:32
aztec-1 | [22:40:32.661]
INFO:
node Received tx 0x0f6ba805a1fe5afdc30fa4db3e56b29a02727f98f7cf17a1c56156307ff77fbf
{"txHash":"0x0f6ba805a1fe5afdc30fa4db3e56b29a02727f98f7cf17a1c56156307ff77fbf"}
22:40:32
aztec-1 | [22:40:32.661]
INFO:
pxe:service Sent transaction 0x0f6ba805a1fe5afdc30fa4db3e56b29a02727f98f7cf17a1c56156307ff77fbf
22:40:32
aztec-1 | [22:40:32.736]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.013811659000000873s
{"duration":0.013811659000000873,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
22:40:32
aztec-1 | [22:40:32.755]
INFO:
sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x0420a5e83b5b26fd4d5d618f5c9a25174ea6e95f494c12bb959334836075f94e","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749854801,"version":17452536},"txHashes":["0x0f6ba805a1fe5afdc30fa4db3e56b29a02727f98f7cf17a1c56156307ff77fbf"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":38.96121900000071,"publicProcessDuration":13.900884999999107,"rollupCircuitsDuration":34.23885999999948,"txCount":1,"blockNumber":3,"blockTimestamp":1749854801,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
22:40:33
aztec-1 | [22:40:32.930]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
22:40:33
aztec-1 | [22:40:32.935]
WARN:
archiver Failed to reach L2 block 3 at 29 (latest is 2). Rolling back last synched L1 block number to 28.
{"latestLocalL2BlockNumber":2,"latestLocalL2BlockArchive":"0x08e584f8b74263dd84a2b4ea009029152dd082a8f20d6fb6744c4d45fdd1db73","blocksSynchedTo":28,"currentL1BlockNumber":29,"provenBlockNumber":2,"provenArchive":"0x08e584f8b74263dd84a2b4ea009029152dd082a8f20d6fb6744c4d45fdd1db73","pendingBlockNumber":3,"pendingArchive":"0x26f94e0c313b78007126eb7db71e3b7cd8f21d5fd194a36020a09ec14bbf37dc"}
22:40:33
aztec-1 | [22:40:33.019]
INFO:
archiver Downloaded L2 block 3
{"blockHash":"0x0420a5e83b5b26fd4d5d618f5c9a25174ea6e95f494c12bb959334836075f94e","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749854801,"version":17452536},"archiveRoot":"0x26f94e0c313b78007126eb7db71e3b7cd8f21d5fd194a36020a09ec14bbf37dc","archiveNextLeafIndex":4}
22:40:33
aztec-1 | [22:40:33.054]
INFO:
world_state World state updated with L2 block 3
{"eventName":"l2-block-handled","duration":15.759232000000338,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749854801,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
22:40:33
aztec-1 | [22:40:33.115]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:40:33
aztec-1 | [22:40:33.115]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:46:53.000Z
{"offset":379885,"timeMs":1749854813000}
22:40:33
aztec-1 | [22:40:33.115]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3
22:40:33
aztec-1 | [22:40:33.117]
INFO:
aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3.
22:40:33
aztec-1 | Initial Accounts:
22:40:33
aztec-1 |
22:40:33
aztec-1 | Address: 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
22:40:33
aztec-1 | Partial Address: 0x27b4888363d0f9eb0d43aa0b2ff08f21fbc6867b8e0baf8ec6062c4623c1eefc
22:40:33
aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281
22:40:33
aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f
22:40:33
aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f
22:40:33
aztec-1 |
22:40:33
aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5
22:40:33
aztec-1 |
22:40:33
aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89
22:40:33
aztec-1 |
22:40:33
aztec-1 | Address: 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
22:40:33
aztec-1 | Partial Address: 0x2e19b56de1617cb3179d81be0173c7301621a2e820da7f2754fcfccdfc4418e0
22:40:33
aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae
22:40:33
aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57
22:40:33
aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d
22:40:33
aztec-1 |
22:40:33
aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e
22:40:33
aztec-1 |
22:40:33
aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d
22:40:33
aztec-1 |
22:40:33
aztec-1 | Address: 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
22:40:33
aztec-1 | Partial Address: 0x2451b67547512eaaaad5ded43156c5b80220b7d5145ebefdd57295a9e05a610d
22:40:33
aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c
22:40:33
aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3
22:40:33
aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae
22:40:33
aztec-1 |
22:40:33
aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da
22:40:33
aztec-1 |
22:40:33
aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba
22:40:33
aztec-1 |
22:40:33
aztec-1 |
22:40:34
aztec-1 | [22:40:33.747]
WARN:
pxe:service No artifact found for contract class 0x29dcd4953013697442c3dd70078007da6d27e978e725c3471dcafb67969f2164 when looking for its metadata
22:40:34
aztec-1 | [22:40:33.747]
WARN:
pxe:service No artifact found for contract class 0x02c03e14465d82e13534072f58c1e39d320b13cf92255a90ff4c99e1fca555a2 when looking for its metadata
22:40:34
aztec-1 | [22:40:33.749]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749854837
22:40:34
aztec-1 | [22:40:33.749]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:47:17.000Z
{"offset":403251,"timeMs":1749854837000}
22:40:34
aztec-1 | [22:40:33.749]
INFO:
aztecjs:utils:watcher Slot 4 was filled, jumped to next slot
22:40:34
aztec-1 | [22:40:33.750]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x29dcd4953013697442c3dd70078007da6d27e978e725c3471dcafb67969f2164 as part of deployment for 0x0723941b267b3ced905d304c517042f5c3f85d0f13232460da9a9f4d2a6b1c13
22:40:34
aztec-1 | [22:40:33.773]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x02c03e14465d82e13534072f58c1e39d320b13cf92255a90ff4c99e1fca555a2 as part of deployment for 0x131b1ba6febb2758f11effaa18660d879c3614cb411076276dfd197b94530547
22:40:34
aztec-1 | [22:40:34.161]
INFO:
archiver Updated proven chain to block 3
{"provenBlockNumber":3}
22:40:34
aztec-1 | [22:40:34.463]
INFO:
pxe:service Added contract FPC at 0x0723941b267b3ced905d304c517042f5c3f85d0f13232460da9a9f4d2a6b1c13 with class 0x29dcd4953013697442c3dd70078007da6d27e978e725c3471dcafb67969f2164
22:40:34
aztec-1 | [22:40:34.467]
INFO:
pxe:service Added contract Token at 0x131b1ba6febb2758f11effaa18660d879c3614cb411076276dfd197b94530547 with class 0x02c03e14465d82e13534072f58c1e39d320b13cf92255a90ff4c99e1fca555a2
22:40:36
aztec-1 | [22:40:36.263]
INFO:
pxe:service Proving completed in 1779.7824360000013ms
{"timings":{"total":1779.7824360000013,"sync":25.07794400000057,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":254.0068910000009,"oracles":{"debugLog":{"times":[0.1992820000014035,0.08057400000325288,0.08132500000283471,0.13851399999839487,0.08777999999801978,0.07393400000000838,0.06681699999899138,0.12034900000071502]},"fetchTaggedLogs":{"times":[83.82389799999874]},"loadCapsule":{"times":[0.1718269999983022,0.1422839999977441,0.23856100000193692,0.3452900000011141,0.20951500000228407,0.18806000000040513,0.1251589999992575]},"getSharedSecret":{"times":[12.332708999998431]},"aes128Decrypt":{"times":[0.8563949999988836,0.5926159999980882]},"getPublicKeysAndPartialAddress":{"times":[4.389027999997779]},"getKeyValidationRequest":{"times":[5.4997479999983625]},"storeCapsule":{"times":[3.004817999997613,1.589245999999548,2.2313470000008238]},"deleteCapsule":{"times":[4.102803000001586]},"validateEnqueuedNotesAndEvents":{"times":[10.217056000001321]},"getNotes":{"times":[1.4732010000006994]},"getAuthWitness":{"times":[0.14975499999854947]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.04410300000017742]},"callPrivateFunction":{"times":[465.63819200000216,109.34840199999962]},"notifyEnqueuedPublicFunctionCall":{"times":[0.2729650000001129]}}},{"functionName":"private_kernel_init","time":48.590572000000975},{"functionName":"ContractClassRegisterer:register","time":455.4667279999994,"oracles":{"loadCapsule":{"times":[2.1914900000010675]},"notifyCreatedNullifier":{"times":[0.2842339999988326]},"debugLog":{"times":[0.11096399999951245]},"notifyCreatedContractClassLog":{"times":[3.642345000000205]}}},{"functionName":"private_kernel_inner","time":97.22193799999877},{"functionName":"ContractInstanceDeployer:deploy","time":68.46567199999845,"oracles":{"storeInExecutionCache":{"times":[0.1930720000018482]},"callPrivateFunction":{"times":[42.27750600000218]},"notifyCreatedNullifier":{"times":[0.2809140000026673]},"debugLog":{"times":[0.12684499999886611]}}},{"functionName":"private_kernel_inner","time":97.58104600000297},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":31.09214300000167,"oracles":{}},{"functionName":"private_kernel_inner","time":99.2876780000006},{"functionName":"private_kernel_reset","time":168.04490299999816},{"functionName":"private_kernel_tail","time":77.49562900000092}],"unaccounted":357.4512919999979}}
22:40:36
aztec-1 | [22:40:36.400]
INFO:
node Received tx 0x266c5923dc257d5636942584340830dff448c25c42184d4a1d90be6d6970cd9d
{"txHash":"0x266c5923dc257d5636942584340830dff448c25c42184d4a1d90be6d6970cd9d"}
22:40:36
aztec-1 | [22:40:36.400]
INFO:
pxe:service Sent transaction 0x266c5923dc257d5636942584340830dff448c25c42184d4a1d90be6d6970cd9d
22:40:37
aztec-1 | [22:40:37.332]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.8113321109999997s
{"duration":0.8113321109999997,"rate":49044.034447195714,"totalPublicGas":{"daGas":4096,"l2Gas":39791},"totalBlockGas":{"daGas":136192,"l2Gas":64833},"totalSizeInBytes":8768}
22:40:37
aztec-1 | [22:40:37.429]
INFO:
sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 43290.74212329943 mana/s
{"blockHash":"0x281bb675a1773caee11dc1039b4d871763110ea4dfd446eeb087958a1dcfa194","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749854837,"version":17452536},"txHashes":["0x266c5923dc257d5636942584340830dff448c25c42184d4a1d90be6d6970cd9d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":919.1002970000009,"publicProcessDuration":811.4389679999986,"rollupCircuitsDuration":911.6854510000012,"txCount":1,"blockNumber":4,"blockTimestamp":1749854837,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":236}
22:40:37
aztec-1 | [22:40:37.921]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:40:37
aztec-1 | [22:40:37.921]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:47:29.000Z
{"offset":411079,"timeMs":1749854849000}
22:40:37
aztec-1 | [22:40:37.968]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
22:40:37
aztec-1 | [22:40:37.971]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4
22:40:37
aztec-1 | [22:40:37.974]
INFO:
aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4.
22:40:37
aztec-1 | [22:40:37.975]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749854873
22:40:37
aztec-1 | [22:40:37.975]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:47:53.000Z
{"offset":435025,"timeMs":1749854873000}
22:40:37
aztec-1 | [22:40:37.975]
INFO:
aztecjs:utils:watcher Slot 5 was filled, jumped to next slot
22:40:38
aztec-1 | [22:40:38.322]
INFO:
pxe:service Proving completed in 2057.4795889999987ms
{"timings":{"total":2057.4795889999987,"sync":107.77867999999944,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":335.7237729999979,"oracles":{"debugLog":{"times":[0.11755700000139768,0.08191100000112783,0.08743300000060117,0.1705599999986589,0.08708100000148988,0.0681380000023637,0.06905399999959627,0.1303619999998773]},"fetchTaggedLogs":{"times":[133.96511100000134]},"loadCapsule":{"times":[0.217571000001044,0.2592000000004191,0.2910279999996419,0.4085140000024694,0.27941500000088126,0.5065429999995104,1.370506999999634]},"getSharedSecret":{"times":[7.605728000002273]},"aes128Decrypt":{"times":[0.2174699999995937,0.5803419999974722]},"getPublicKeysAndPartialAddress":{"times":[2.890596999997797]},"getKeyValidationRequest":{"times":[3.5323520000019926]},"storeCapsule":{"times":[2.376037999998516,16.35443899999882,2.5247490000001562]},"deleteCapsule":{"times":[2.4784190000027593]},"validateEnqueuedNotesAndEvents":{"times":[22.247395000002143]},"getNotes":{"times":[4.3052239999997255]},"getAuthWitness":{"times":[0.13532699999996112]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.04615700000067591]},"callPrivateFunction":{"times":[466.7199279999986,91.1104099999975]},"notifyEnqueuedPublicFunctionCall":{"times":[0.08342000000266125]}}},{"functionName":"private_kernel_init","time":47.440850999999384},{"functionName":"ContractClassRegisterer:register","time":453.0600740000009,"oracles":{"loadCapsule":{"times":[1.7673570000006293]},"notifyCreatedNullifier":{"times":[0.30345399999714573]},"debugLog":{"times":[0.09727100000236533]},"notifyCreatedContractClassLog":{"times":[3.4422689999992144]}}},{"functionName":"private_kernel_inner","time":97.73356800000329},{"functionName":"ContractInstanceDeployer:deploy","time":53.724045999995724,"oracles":{"storeInExecutionCache":{"times":[0.08252299999730894]},"callPrivateFunction":{"times":[40.31146399999852]},"notifyCreatedNullifier":{"times":[0.23512599999958184]},"debugLog":{"times":[0.12069899999914924]}}},{"functionName":"private_kernel_inner","time":95.30402600000161},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":27.546496000002662,"oracles":{}},{"functionName":"private_kernel_inner","time":98.38776900000084},{"functionName":"private_kernel_reset","time":164.63331599999947},{"functionName":"private_kernel_tail","time":76.88550000000032}],"unaccounted":499.26148999999714}}
22:40:38
aztec-1 | [22:40:38.491]
INFO:
node Received tx 0x0384a55af2afa41e2da5bbfc3dc54feccd108b40d020979fd879f6b1cdaf6b53
{"txHash":"0x0384a55af2afa41e2da5bbfc3dc54feccd108b40d020979fd879f6b1cdaf6b53"}
22:40:38
aztec-1 | [22:40:38.491]
INFO:
pxe:service Sent transaction 0x0384a55af2afa41e2da5bbfc3dc54feccd108b40d020979fd879f6b1cdaf6b53
22:40:38
aztec-1 | [22:40:38.493]
INFO:
archiver Downloaded L2 block 4
{"blockHash":"0x281bb675a1773caee11dc1039b4d871763110ea4dfd446eeb087958a1dcfa194","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749854837,"version":17452536},"archiveRoot":"0x12b646dd126cc5471820ee5541ff671c4119f96679afcfded812aac314e78086","archiveNextLeafIndex":5}
22:40:38
aztec-1 | [22:40:38.497]
INFO:
archiver Updated proven chain to block 4
{"provenBlockNumber":4}
22:40:38
aztec-1 | [22:40:38.529]
INFO:
world_state World state updated with L2 block 4
{"eventName":"l2-block-handled","duration":14.536395000002813,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749854837,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":236}
22:40:38
aztec-1 | [22:40:38.553]
INFO:
aztecjs:deploy_sent_tx Contract 0x0723941b267b3ced905d304c517042f5c3f85d0f13232460da9a9f4d2a6b1c13 successfully deployed.
22:40:39
aztec-1 | [22:40:38.902]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.26191434899999877s
{"duration":0.26191434899999877,"rate":1208227.0452467706,"totalPublicGas":{"daGas":10240,"l2Gas":316452},"totalBlockGas":{"daGas":380928,"l2Gas":341494},"totalSizeInBytes":24064}
22:40:39
aztec-1 | [22:40:38.943]
INFO:
sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 1007960.050213773 mana/s
{"blockHash":"0x23cb8ee4426fbbd32f8a0a176b3ae216e4281c740d46d1be1d2bb9715f9314af","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749854873,"version":17452536},"txHashes":["0x0384a55af2afa41e2da5bbfc3dc54feccd108b40d020979fd879f6b1cdaf6b53"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":313.92729600000166,"publicProcessDuration":262.3807880000022,"rollupCircuitsDuration":307.14390300000014,"txCount":1,"blockNumber":5,"blockTimestamp":1749854873,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
22:40:39
aztec-1 | [22:40:39.149]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
22:40:39
aztec-1 | [22:40:39.160]
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":"0x12b646dd126cc5471820ee5541ff671c4119f96679afcfded812aac314e78086","blocksSynchedTo":32,"currentL1BlockNumber":33,"provenBlockNumber":4,"provenArchive":"0x12b646dd126cc5471820ee5541ff671c4119f96679afcfded812aac314e78086","pendingBlockNumber":5,"pendingArchive":"0x1d3ed5d0e806473bcaf02e7a4cfb3d391f714b37667f724b2377b61483dc7dda"}
22:40:39
aztec-1 | [22:40:39.187]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:40:39
aztec-1 | [22:40:39.187]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:48:05.000Z
{"offset":445813,"timeMs":1749854885000}
22:40:39
aztec-1 | [22:40:39.192]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5
22:40:39
aztec-1 | [22:40:39.194]
INFO:
aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5.
22:40:39
aztec-1 | [22:40:39.230]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749854909
22:40:39
aztec-1 | [22:40:39.230]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:48:29.000Z
{"offset":469770,"timeMs":1749854909000}
22:40:39
aztec-1 | [22:40:39.230]
INFO:
aztecjs:utils:watcher Slot 6 was filled, jumped to next slot
22:40:39
aztec-1 | [22:40:39.314]
INFO:
archiver Downloaded L2 block 5
{"blockHash":"0x23cb8ee4426fbbd32f8a0a176b3ae216e4281c740d46d1be1d2bb9715f9314af","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749854873,"version":17452536},"archiveRoot":"0x1d3ed5d0e806473bcaf02e7a4cfb3d391f714b37667f724b2377b61483dc7dda","archiveNextLeafIndex":6}
22:40:39
aztec-1 | [22:40:39.320]
INFO:
archiver Updated proven chain to block 5
{"provenBlockNumber":5}
22:40:39
aztec-1 | [22:40:39.372]
INFO:
world_state World state updated with L2 block 5
{"eventName":"l2-block-handled","duration":14.373531999997795,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749854873,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
22:40:39
aztec-1 | BananaCoin: 0x131b1ba6febb2758f11effaa18660d879c3614cb411076276dfd197b94530547
22:40:39
aztec-1 | FPC: 0x0723941b267b3ced905d304c517042f5c3f85d0f13232460da9a9f4d2a6b1c13
22:40:39
aztec-1 | [22:40:39.675]
INFO:
aztecjs:deploy_sent_tx Contract 0x131b1ba6febb2758f11effaa18660d879c3614cb411076276dfd197b94530547 successfully deployed.
22:40:39
aztec-1 | [22:40:39.701]
WARN:
pxe:service No artifact found for contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e when looking for its metadata
22:40:39
aztec-1 | [22:40:39.703]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e as part of deployment for 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
22:40:39
aztec-1 | [22:40:39.811]
INFO:
pxe:service Added contract SponsoredFPC at 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 with class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e
22:40:41
aztec-1 | [22:40:41.464]
INFO:
pxe:service Proving completed in 1644.4868540000025ms
{"timings":{"total":1644.4868540000025,"sync":35.16577200000029,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":62.37050600000293,"oracles":{"callPrivateFunction":{"times":[457.7795100000003,104.76813600000241,38.4079410000013]}}},{"functionName":"private_kernel_init","time":47.01187199999913},{"functionName":"ContractClassRegisterer:register","time":445.2346910000015,"oracles":{"loadCapsule":{"times":[1.7666879999997036]},"notifyCreatedNullifier":{"times":[0.24811299999782932]},"debugLog":{"times":[0.09319799999866518]},"notifyCreatedContractClassLog":{"times":[5.604395999998815]}}},{"functionName":"private_kernel_inner","time":101.72111500000028},{"functionName":"ContractInstanceDeployer:deploy","time":62.753562999998394,"oracles":{"storeInExecutionCache":{"times":[0.07208000000173342]},"callPrivateFunction":{"times":[38.11018100000001]},"notifyCreatedNullifier":{"times":[0.20426399999996647]},"debugLog":{"times":[0.1105089999982738]}}},{"functionName":"private_kernel_inner","time":97.64739299999928},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":28.56723300000158,"oracles":{}},{"functionName":"private_kernel_inner","time":101.12192399999913},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":31.09574799999973,"oracles":{"debugLog":{"times":[0.10395399999833899]}}},{"functionName":"private_kernel_inner","time":97.45452700000169},{"functionName":"private_kernel_reset","time":158.56756300000052},{"functionName":"private_kernel_tail","time":64.51266199999736}],"unaccounted":311.2622850000007}}
22:40:41
aztec-1 | [22:40:41.556]
INFO:
node Received tx 0x266cd843ff250ea9c2d8ab04f66583d5a06cdeca7edea3ae31ffff10a529f8a1
{"txHash":"0x266cd843ff250ea9c2d8ab04f66583d5a06cdeca7edea3ae31ffff10a529f8a1"}
22:40:41
aztec-1 | [22:40:41.556]
INFO:
pxe:service Sent transaction 0x266cd843ff250ea9c2d8ab04f66583d5a06cdeca7edea3ae31ffff10a529f8a1
22:40:41
aztec-1 | [22:40:41.649]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.021982939999998053s
{"duration":0.021982939999998053,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152}
22:40:41
aztec-1 | [22:40:41.667]
INFO:
sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x2ab498bf96e452a1978f7b009709978f7db47e4e000af4ab4bedd299049a686b","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749854909,"version":17452536},"txHashes":["0x266cd843ff250ea9c2d8ab04f66583d5a06cdeca7edea3ae31ffff10a529f8a1"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":45.62107799999649,"publicProcessDuration":22.079040000000532,"rollupCircuitsDuration":41.07281800000055,"txCount":1,"blockNumber":6,"blockTimestamp":1749854909,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
22:40:41
aztec-1 | [22:40:41.840]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
22:40:41
aztec-1 | [22:40:41.845]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749854945
22:40:41
aztec-1 | [22:40:41.845]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:49:05.000Z
{"offset":503155,"timeMs":1749854945000}
22:40:41
aztec-1 | [22:40:41.845]
INFO:
aztecjs:utils:watcher Slot 7 was filled, jumped to next slot
22:40:41
aztec-1 | [22:40:41.894]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6
22:40:41
aztec-1 | [22:40:41.898]
INFO:
aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6.
22:40:41
aztec-1 | [22:40:41.936]
INFO:
archiver Downloaded L2 block 6
{"blockHash":"0x2ab498bf96e452a1978f7b009709978f7db47e4e000af4ab4bedd299049a686b","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749854909,"version":17452536},"archiveRoot":"0x280da40701a61853bc4ac78d53698445abf075b84d220605dbb56909afd7b92d","archiveNextLeafIndex":7}
22:40:41
aztec-1 | [22:40:41.941]
INFO:
archiver Updated proven chain to block 6
{"provenBlockNumber":6}
22:40:42
aztec-1 | [22:40:41.978]
INFO:
world_state World state updated with L2 block 6
{"eventName":"l2-block-handled","duration":13.641101000001072,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749854909,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
22:40:42
aztec-1 | SponsoredFPC: 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
22:40:43
aztec-1 | [22:40:42.576]
INFO:
aztecjs:deploy_sent_tx Contract 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 successfully deployed.
22:40:43
aztec-1 | [22:40:42.580]
INFO:
cli Aztec Server listening on port 8080
{"l2CircuitsVkTreeRoot":"0x2bf2a95060ddbd049be7f501ee6d0dd0fde85c50e642e13a84f201e420805b66","l2ProtocolContractsTreeRoot":"0x222b5a95b10e0a2dfba4648739629a01cecd69a0bef23a9fc22f846735d7cc71"}
22:40:47
box-1 | \
22:40:47
box-1 | Running 1
test using 1
worker
22:40:47
box-1 |
22:40:47
box-1 | 1 [firefox] › browser.spec.ts:3:1 › test
22:41:18
aztec-1 | [22:41:17.847]
WARN:
aztecjs:utils:watcher L1 is more than 1 L2 slot behind wall time. Warping to wall time
22:41:18
aztec-1 | [22:41:17.849]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749854982
22:41:18
aztec-1 | [22:41:18.050]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:41:18
aztec-1 | [22:41:18.050]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:49:42.000Z
{"offset":503950,"timeMs":1749854982000}
22:41:53
aztec-1 | [22:41:53.082]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749855017
22:41:53
aztec-1 | [22:41:53.082]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:50:17.000Z
{"offset":503918,"timeMs":1749855017000}
22:41:53
aztec-1 | [22:41:53.082]
INFO:
aztecjs:utils:watcher Slot 9 was missed, jumped to next slot
22:42:20
✘ 1 [firefox] › browser.spec.ts:3:1 › test (1.5m) 2 [firefox] › browser.spec.ts:3:1 › test (retry #1)
22:42:29
aztec-1 | [22:42:29.192]
WARN:
aztecjs:utils:watcher L1 is more than 1 L2 slot behind wall time. Warping to wall time
22:42:29
aztec-1 | [22:42:29.195]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749855054
22:42:29
aztec-1 | [22:42:29.397]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:42:29
aztec-1 | [22:42:29.397]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:50:54.000Z
{"offset":504603,"timeMs":1749855054000}
22:43:05
aztec-1 | [22:43:04.549]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749855089
22:43:05
aztec-1 | [22:43:04.549]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:51:29.000Z
{"offset":504451,"timeMs":1749855089000}
22:43:05
aztec-1 | [22:43:04.549]
INFO:
aztecjs:utils:watcher Slot 11 was missed, jumped to next slot
22:43:41
aztec-1 | [22:43:40.619]
WARN:
aztecjs:utils:watcher L1 is more than 1 L2 slot behind wall time. Warping to wall time
22:43:41
aztec-1 | [22:43:40.621]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749855126
22:43:41
aztec-1 | [22:43:40.822]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:43:41
aztec-1 | [22:43:40.822]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:52:06.000Z
{"offset":505178,"timeMs":1749855126000}
22:43:52
✘ 2 [firefox] › browser.spec.ts:3:1 › test (retry #1) (1.5m) 3 [firefox] › browser.spec.ts:3:1 › test (retry #2)
22:44:16
aztec-1 | [22:44:15.934]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749855161
22:44:16
aztec-1 | [22:44:15.934]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:52:41.000Z
{"offset":505066,"timeMs":1749855161000}
22:44:16
aztec-1 | [22:44:15.934]
INFO:
aztecjs:utils:watcher Slot 13 was missed, jumped to next slot
22:44:52
aztec-1 | [22:44:52.013]
WARN:
aztecjs:utils:watcher L1 is more than 1 L2 slot behind wall time. Warping to wall time
22:44:52
aztec-1 | [22:44:52.015]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749855198
22:44:52
aztec-1 | [22:44:52.215]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:44:52
aztec-1 | [22:44:52.215]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:53:18.000Z
{"offset":505785,"timeMs":1749855198000}
22:45:23
✘ 3 [firefox] › browser.spec.ts:3:1 › test (retry #2) (1.5m) 4 [firefox] › browser.spec.ts:3:1 › test (retry #3)
22:45:27
aztec-1 | [22:45:27.321]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749855233
22:45:27
aztec-1 | [22:45:27.321]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:53:53.000Z
{"offset":505679,"timeMs":1749855233000}
22:45:27
aztec-1 | [22:45:27.321]
INFO:
aztecjs:utils:watcher Slot 15 was missed, jumped to next slot
22:46:03
aztec-1 | [22:46:03.370]
WARN:
aztecjs:utils:watcher L1 is more than 1 L2 slot behind wall time. Warping to wall time
22:46:03
aztec-1 | [22:46:03.371]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749855270
22:46:03
aztec-1 | [22:46:03.572]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
22:46:03
aztec-1 | [22:46:03.572]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:54:30.000Z
{"offset":506428,"timeMs":1749855270000}
22:46:38
aztec-1 | [22:46:38.684]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749855305
22:46:38
aztec-1 | [22:46:38.684]
WARN:
foundation:test-date-provider Time set to 2025-06-13T22:55:05.000Z
{"offset":506316,"timeMs":1749855305000}
22:46:38
aztec-1 | [22:46:38.684]
INFO:
aztecjs:utils:watcher Slot 17 was missed, jumped to next slot
22:46:54
✘ 4 [firefox] › browser.spec.ts:3:1 › test (retry #3) (1.5m)
22:46:54
box-1 |
22:46:54
box-1 | 1) [firefox] › browser.spec.ts:3:1 › test ────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | Test timeout of 90000ms exceeded.
22:46:54
box-1 |
22:46:54
box-1 | Error: locator.click: Test timeout of 90000ms exceeded.
22:46:54
box-1 | Call log:
22:46:54
box-1 | - waiting for getByRole('button', { name: 'Deploy dummy contract' })
22:46:54
box-1 |
22:46:54
box-1 |
22:46:54
box-1 | 6 |
22:46:54
box-1 | 7 | // Deploy contract
22:46:54
box-1 | > 8 | await page
.getByRole(
'button', { name
: 'Deploy dummy contract' })
.click()
;
22:46:54
box-1 | | ^
22:46:54
box-1 | 9 | await expect(page
.getByText(
'Deploying contract...'))
.toBeVisible()
;
22:46:54
box-1 | 10 | await expect(page
.getByText(
'Address:'))
.toBeVisible()
;
22:46:54
box-1 | 11 |
22:46:54
box-1 | at /root/aztec-packages/boxes/boxes/vite/tests/browser.spec.ts:8:69
22:46:54
box-1 |
22:46:54
box-1 | attachment #1: screenshot (image/png) ──────────────────────────────────────────────────────────
22:46:54
box-1 | test-results/browser-test-firefox/test-failed-1.png
22:46:54
box-1 | ────────────────────────────────────────────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | Retry #1 ───────────────────────────────────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | Test timeout of 90000ms exceeded.
22:46:54
box-1 |
22:46:54
box-1 | Error: locator.click: Test timeout of 90000ms exceeded.
22:46:54
box-1 | Call log:
22:46:54
box-1 | - waiting for getByRole('button', { name: 'Deploy dummy contract' })
22:46:54
box-1 |
22:46:54
box-1 |
22:46:54
box-1 | 6 |
22:46:54
box-1 | 7 | // Deploy contract
22:46:54
box-1 | > 8 | await page
.getByRole(
'button', { name
: 'Deploy dummy contract' })
.click()
;
22:46:54
box-1 | | ^
22:46:54
box-1 | 9 | await expect(page
.getByText(
'Deploying contract...'))
.toBeVisible()
;
22:46:54
box-1 | 10 | await expect(page
.getByText(
'Address:'))
.toBeVisible()
;
22:46:54
box-1 | 11 |
22:46:54
box-1 | at /root/aztec-packages/boxes/boxes/vite/tests/browser.spec.ts:8:69
22:46:54
box-1 |
22:46:54
box-1 | attachment #1: screenshot (image/png) ──────────────────────────────────────────────────────────
22:46:54
box-1 | test-results/browser-test-firefox-retry1/test-failed-1.png
22:46:54
box-1 | ────────────────────────────────────────────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | attachment #2: video (video/webm) ──────────────────────────────────────────────────────────────
22:46:54
box-1 | test-results/browser-test-firefox-retry1/video.webm
22:46:54
box-1 | ────────────────────────────────────────────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | attachment #3: trace (application/zip) ─────────────────────────────────────────────────────────
22:46:54
box-1 | test-results/browser-test-firefox-retry1/trace.zip
22:46:54
box-1 | Usage:
22:46:54
box-1 |
22:46:54
box-1 | yarn playwright show-trace test-results/browser-test-firefox-retry1/trace.zip
22:46:54
box-1 |
22:46:54
box-1 | ────────────────────────────────────────────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | Retry #2 ───────────────────────────────────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | Test timeout of 90000ms exceeded.
22:46:54
box-1 |
22:46:54
box-1 | Error: locator.click: Test timeout of 90000ms exceeded.
22:46:54
box-1 | Call log:
22:46:54
box-1 | - waiting for getByRole('button', { name: 'Deploy dummy contract' })
22:46:54
box-1 |
22:46:54
box-1 |
22:46:54
box-1 | 6 |
22:46:54
box-1 | 7 | // Deploy contract
22:46:54
box-1 | > 8 | await page
.getByRole(
'button', { name
: 'Deploy dummy contract' })
.click()
;
22:46:54
box-1 | | ^
22:46:54
box-1 | 9 | await expect(page
.getByText(
'Deploying contract...'))
.toBeVisible()
;
22:46:54
box-1 | 10 | await expect(page
.getByText(
'Address:'))
.toBeVisible()
;
22:46:54
box-1 | 11 |
22:46:54
box-1 | at /root/aztec-packages/boxes/boxes/vite/tests/browser.spec.ts:8:69
22:46:54
box-1 |
22:46:54
box-1 | attachment #1: screenshot (image/png) ──────────────────────────────────────────────────────────
22:46:54
box-1 | test-results/browser-test-firefox-retry2/test-failed-1.png
22:46:54
box-1 | ────────────────────────────────────────────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | Retry #3 ───────────────────────────────────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | Test timeout of 90000ms exceeded.
22:46:54
box-1 |
22:46:54
box-1 | Error: locator.click: Test timeout of 90000ms exceeded.
22:46:54
box-1 | Call log:
22:46:54
box-1 | - waiting for getByRole('button', { name: 'Deploy dummy contract' })
22:46:54
box-1 |
22:46:54
box-1 |
22:46:54
box-1 | 6 |
22:46:54
box-1 | 7 | // Deploy contract
22:46:54
box-1 | > 8 | await page
.getByRole(
'button', { name
: 'Deploy dummy contract' })
.click()
;
22:46:54
box-1 | | ^
22:46:54
box-1 | 9 | await expect(page
.getByText(
'Deploying contract...'))
.toBeVisible()
;
22:46:54
box-1 | 10 | await expect(page
.getByText(
'Address:'))
.toBeVisible()
;
22:46:54
box-1 | 11 |
22:46:54
box-1 | at /root/aztec-packages/boxes/boxes/vite/tests/browser.spec.ts:8:69
22:46:54
box-1 |
22:46:54
box-1 | attachment #1: screenshot (image/png) ──────────────────────────────────────────────────────────
22:46:54
box-1 | test-results/browser-test-firefox-retry3/test-failed-1.png
22:46:54
box-1 | ────────────────────────────────────────────────────────────────────────────────────────────────
22:46:54
box-1 |
22:46:54
box-1 | 1 failed
22:46:54
box-1 | [firefox] › browser.spec.ts:3:1 › test ─────────────────────────────────────────────────────────
22:46:54
box-1 | \
npm notice
22:46:54
box-1 | npm notice New
major version of npm available!
10.9.2 ->
11.4.2
22:46:54
box-1 | npm notice Changelog:
https://github.com/npm/cli/releases/tag/v11.4.2
22:46:54
box-1 | npm notice To update run:
npm install -g npm@11.4.2
22:46:54
box-1 | npm notice
22:46:54
box-1 | \
22:46:54 Aborting on container exit...
22:46:54
box-1 exited with code 1
22:46:54 [?25l[+] Stopping 3/3
22:46:56 ✔ Container vite-firefox-box-1 Stopped0.0s
22:46:56 ✔ Container vite-firefox-aztec-1 Stopped1.2s
22:46:56 ✔ Container vite-firefox-ethereum-1 Stopped0.1s
22:46:56 [?25h