Parent Log:
http://ci.aztec-labs.com/a6ad5c00f5faa201
Command: fb2ea99c25083717 playground/scripts/run_test.sh firefox
Commit:
https://github.com/AztecProtocol/aztec-packages/commit/420e0a54fb4bfe8b04d7dd479f59ed0fe3ad3b74
Env: REF_NAME=gh-readonly-queue/next/pr-14922-106f897363bb5aee27ddd091b5be9641b011c5bb CURRENT_VERSION=0.87.6 CI_FULL=0
Date: Thu Jun 12 16:17:06 UTC 2025
System: ARCH=arm64 CPUS=64 MEM=247Gi HOSTNAME=pr-14922_arm64_a1-fast
Resources: CPU_LIST=0-63 CPUS=2 MEM=8g TIMEOUT=600s
History:
http://ci.aztec-labs.com/list/history_23123200780498f7_next
16:17:07 Network playground-firefox_default Creating
16:17:07 Network playground-firefox_default Created
16:17:07 Container playground-firefox-ethereum-1 Creating
16:17:07 Container playground-firefox-ethereum-1 Created
16:17:07 Container playground-firefox-aztec-1 Creating
16:17:07 Container playground-firefox-aztec-1 Created
16:17:07 Container playground-firefox-playground-1 Creating
16:17:07 Container playground-firefox-playground-1 Created
16:17:07 Attaching to aztec-1, ethereum-1, playground-1
16:17:10 aztec-1 |
16:17:10 aztec-1 | _
16:17:10 aztec-1 | /\ | |
16:17:10 aztec-1 | / \ ___| |_ ___ ___
16:17:10 aztec-1 | / /\ \ |_ / __/ _ \/ __|
16:17:10 aztec-1 | / ____ \ / /| || __/ (__
16:17:10 aztec-1 | /_/___ \_\/___|\__\___|\___|
16:17:10 aztec-1 |
16:17:10 aztec-1 |
https://github.com/AztecProtocol
16:17:10 aztec-1 |
16:17:10 aztec-1 |
16:17:10 aztec-1 | Setting up Aztec Sandbox 0.87.6, please stand by...
16:17:10 aztec-1 | Setting up test accounts
16:17:11 aztec-1 | [16:17:11.455]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-XKd7iS/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
16:17:11 aztec-1 | [16:17:11.638]
INFO:
sandbox Calling anvil_setBlockTimestampInterval with params: [12]
16:17:11 aztec-1 | [16:17:11.643]
WARN:
sandbox Set block interval to 12
16:17:11 aztec-1 | [16:17:11.798]
INFO:
sandbox Waiting for 7 transactions to be mined...
16:17:11 aztec-1 | [16:17:11.819]
INFO:
sandbox All transactions mined successfully
16:17:11 aztec-1 | [16:17:11.888]
INFO:
sandbox Waiting for 9 transactions to be mined...
16:17:11 aztec-1 | [16:17:11.908]
INFO:
sandbox All transactions mined successfully
16:17:12 aztec-1 | [16:17:12.017]
INFO:
sandbox Waiting for 11 transactions to be mined...
16:17:12 aztec-1 | [16:17:12.036]
INFO:
sandbox All transactions mined successfully
16:17:12 aztec-1 | [16:17:12.093]
INFO:
sandbox Waiting for 12 transactions to be mined...
16:17:12 aztec-1 | [16:17:12.113]
INFO:
sandbox All transactions mined successfully
16:17:12 aztec-1 | [16:17:12.117]
INFO:
sandbox Waiting for 12 transactions to be mined...
16:17:12 aztec-1 | [16:17:12.140]
INFO:
sandbox All transactions mined successfully
16:17:12 aztec-1 | [16:17:12.156]
INFO:
sandbox Aztec L1 contracts initialized
{"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x68b1d87f95878fe05b998f19b66f4baba5de1aed","inboxAddress":"0xa12ffa0b9f159bb4c54bce579611927addc51610","outboxAddress":"0x53839913417ebc7171723489f29b9b54f49b4eea","feeJuicePortalAddress":"0x67671478a98e62381bf90cdd328cffcddd26c83a","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"}
16:17:12 aztec-1 | [16:17:12.157]
INFO:
sandbox Handing over to governance
16:17:12 aztec-1 | [16:17:12.163]
INFO:
aztecjs:utils:watcher Watcher started for rollup at 0x68B1D87F95878fE05B998F19b66F4baba5De1aed
16:17:12 aztec-1 | [16:17:12.164]
INFO:
telemetry:client Using NoopTelemetryClient
16:17:12 aztec-1 | [16:17:12.164]
INFO:
blob-sink:client Creating local blob sink client.
16:17:12 aztec-1 | [16:17:12.167]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
16:17:12 aztec-1 | [16:17:12.167]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:21:43.000Z
{"offset":270833,"timeMs":1749745303000}
16:17:12 aztec-1 | [16:17:12.184]
INFO:
archiver:lmdb Starting data store with maxReaders 16
16:17:12 aztec-1 | [16:17:12.506]
INFO:
archiver Starting archiver sync to rollup contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed from L1 block 19 to current L1 block 23 with hash 0xff3048f6026608816d1a1c14df781bf6e12acfb6907203c8c1e2251120b11d9f
{"blocksSynchedTo":19,"messagesSynchedTo":{"l1BlockHash":"0x1b800ed1d130cdc684bbcf42a389475735aa6f2b1e0e4e2b5bccbdbd7e5903e6","l1BlockNumber":19}}
16:17:12 aztec-1 | [16:17:12.526]
INFO:
archiver Initial archiver sync to L1 block 23 complete.
{"l1BlockNumber":23,"syncPoint":{"blocksSynchedTo":23,"messagesSynchedTo":{"l1BlockHash":"0xff3048f6026608816d1a1c14df781bf6e12acfb6907203c8c1e2251120b11d9f","l1BlockNumber":23}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}}
16:17:12 aztec-1 | [16:17:12.531]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-Ce0oH3/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 4 threads.
16:17:12 aztec-1 | [16:17:12.745]
INFO:
world_state Created world state synchroniser with block history of 64
16:17:12 aztec-1 | [16:17:12.745]
WARN:
node Aztec node is accepting fake proofs
16:17:12 aztec-1 | [16:17:12.758]
INFO:
p2p:lmdb-v2 Starting data store with maxReaders 16
16:17:12 aztec-1 | [16:17:12.770]
INFO:
p2p-archive:lmdb-v2 Starting data store with maxReaders 16
16:17:12 aztec-1 | [16:17:12.779]
INFO:
p2p-peer:lmdb-v2 Starting data store with maxReaders 16
16:17:12 aztec-1 | [16:17:12.789]
INFO:
p2p:tx_pool Setting maximum tx mempool size
{"maxTxPoolSize":100000000}
16:17:12 aztec-1 | [16:17:12.793]
INFO:
world_state Started world state synchronizer from block 1
16:17:13 aztec-1 | [16:17:12.799]
INFO:
p2p Starting p2p client from block 0 with empty mempools
16:17:13 aztec-1 | [16:17:13.038]
INFO:
epoch-prune-watcher EpochPruneWatcher initialized
16:17:13 aztec-1 | [16:17:13.091]
INFO:
slasher Starting Slasher client...
16:17:13 aztec-1 | [16:17:13.102]
INFO:
sequencer-client Deploying forwarder contract
16:17:13 aztec-1 | [16:17:13.133]
INFO:
sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac
16:17:13 aztec-1 | [16:17:13.140]
INFO:
validator Started validator with addresses: 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
16:17:13 aztec-1 | [16:17:13.141]
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}
16:17:13 aztec-1 | [16:17:13.142]
INFO:
sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
16:17:13 aztec-1 | [16:17:13.143]
INFO:
node Aztec Node version: 0.87.6
16:17:13 aztec-1 | [16:17:13.143]
INFO:
node Aztec Node started on chain 0x7a69
{"rollupAddress":"0x68b1d87f95878fe05b998f19b66f4baba5de1aed","registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","inboxAddress":"0xa12ffa0b9f159bb4c54bce579611927addc51610","outboxAddress":"0x53839913417ebc7171723489f29b9b54f49b4eea","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","feeJuicePortalAddress":"0x67671478a98e62381bf90cdd328cffcddd26c83a","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","slashFactoryAddress":"0xc6e7df5e7b4f2a278906862b61205850344d4e7d","feeAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788","stakingAssetHandlerAddress":"0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"}
16:17:13 aztec-1 | [16:17:13.150]
INFO:
pxe:data:lmdb Starting data store with maxReaders 16
16:17:13 aztec-1 | [16:17:13.163]
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}
16:17:13 aztec-1 | [16:17:13.223]
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}
16:17:13 aztec-1 | [16:17:13.360]
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}
16:17:13 aztec-1 | [16:17:13.367]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
16:17:13 aztec-1 | [16:17:13.367]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:21:55.000Z
{"offset":281633,"timeMs":1749745315000}
16:17:13 aztec-1 | Setting up funded test accounts...
16:17:13 aztec-1 | [16:17:13.481]
INFO:
pxe:service Started PXE connected to chain 31337 version 1785303287
16:17:13 aztec-1 | [16:17:13.551]
INFO:
pxe:service Registered account 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
16:17:13 aztec-1 | [16:17:13.605]
WARN:
pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata
16:17:13 aztec-1 | [16:17:13.608]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
16:17:13 aztec-1 | [16:17:13.739]
INFO:
pxe:service Added contract SchnorrAccount at 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
16:17:15 aztec-1 | [16:17:15.734]
INFO:
pxe:service Proving completed in 1966.4336210000001ms
{"timings":{"total":1966.4336210000001,"sync":3.809223000000202,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":118.09556500000144,"oracles":{"callPrivateFunction":{"times":[536.637095,263.7893949999998,152.18974900000012]}}},{"functionName":"private_kernel_init","time":82.30538399999932},{"functionName":"ContractClassRegisterer:register","time":522.696480999999,"oracles":{"loadCapsule":{"times":[8.95290599999953]},"notifyCreatedNullifier":{"times":[0.4915609999998196]},"debugLog":{"times":[0.366504999999961]},"notifyCreatedContractClassLog":{"times":[4.70142400000077]}}},{"functionName":"private_kernel_inner","time":109.54420100000061},{"functionName":"SchnorrAccount:constructor","time":245.0622569999996,"oracles":{"getContractInstance":{"times":[1.6899290000001201]},"debugLog":{"times":[0.136026000000129,0.09562000000005355]},"fetchTaggedLogs":{"times":[44.52964900000006]},"loadCapsule":{"times":[0.31470100000024104,0.21012100000007194,0.17200599999978294]},"validateEnqueuedNotesAndEvents":{"times":[5.6688810000005105]},"notifyCreatedNullifier":{"times":[0.21673900000041613,0.21345099999962258]},"notifyCreatedNote":{"times":[0.5554590000001554]},"getRandomField":{"times":[0.23638000000028114,0.1534189999993032,0.09587000000010448,0.09200500000042666,0.23614100000031613,0.08887799999956769,0.06362300000000687,0.07307700000001205,0.06063500000072963,0.0785650000007081,0.052458999999544176]},"getIndexedTaggingSecretAsSender":{"times":[37.50071399999979]},"incrementAppTaggingSecretIndexAsSender":{"times":[25.604935999999725]}}},{"functionName":"private_kernel_inner","time":96.514768},{"functionName":"SchnorrAccount:entrypoint","time":133.02938600000016,"oracles":{"debugLog":{"times":[0.1649120000001858,0.0810750000000553,0.23724700000002485]},"fetchTaggedLogs":{"times":[33.762173000000075]},"loadCapsule":{"times":[0.1787770000000819,0.14459400000032474,0.15729499999997643]},"validateEnqueuedNotesAndEvents":{"times":[4.8684409999996205]},"getNotes":{"times":[1.3421829999997499]},"getAuthWitness":{"times":[0.24347099999977218]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.7539960000003703]}}},{"functionName":"private_kernel_inner","time":95.44552599999952},{"functionName":"private_kernel_reset","time":163.85248799999954},{"functionName":"private_kernel_tail","time":63.18065399999978}],"unaccounted":332.8976880000009}}
16:17:15 aztec-1 | [16:17:15.826]
INFO:
node Received tx 0x0e9f1a2f8b2793a78dc654f8576d485f6726c1554e4b3675a213a9644027c7aa
{"txHash":"0x0e9f1a2f8b2793a78dc654f8576d485f6726c1554e4b3675a213a9644027c7aa"}
16:17:15 aztec-1 | [16:17:15.826]
INFO:
pxe:service Sent transaction 0x0e9f1a2f8b2793a78dc654f8576d485f6726c1554e4b3675a213a9644027c7aa
16:17:15 aztec-1 | [16:17:15.914]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03223606999999902s
{"duration":0.03223606999999902,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344}
16:17:15 aztec-1 | [16:17:15.936]
INFO:
sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x1d2cc82ef3f2e9a8142836acb935e921a91d6f188507499a02e2935bb8d42626","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749745327,"version":1785303287},"txHashes":["0x0e9f1a2f8b2793a78dc654f8576d485f6726c1554e4b3675a213a9644027c7aa"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":64.72963099999924,"publicProcessDuration":32.69177000000036,"rollupCircuitsDuration":55.53615699999955,"txCount":1,"blockNumber":1,"blockTimestamp":1749745327,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
16:17:19 aztec-1 | [16:17:18.641]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1
16:17:19 aztec-1 | [16:17:18.644]
INFO:
aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1.
16:17:19 aztec-1 | [16:17:18.647]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
16:17:19 aztec-1 | [16:17:18.724]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
16:17:19 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)"
16:17:19 aztec-1 | [16:17:18.724]
WARN:
sequencer Cannot propose block 1 at slot 2 due to failed rollup contract check
16:17:19 aztec-1 | [16:17:18.726]
INFO:
archiver Downloaded L2 block 1
{"blockHash":"0x1d2cc82ef3f2e9a8142836acb935e921a91d6f188507499a02e2935bb8d42626","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749745327,"version":1785303287},"archiveRoot":"0x1c585debd85043970980e9a03f40969066bf013666e652dfa1b4d81c5663f265","archiveNextLeafIndex":2}
16:17:19 aztec-1 | [16:17:18.758]
INFO:
archiver Updated proven chain to block 1
{"provenBlockNumber":1}
16:17:19 aztec-1 | [16:17:18.785]
INFO:
world_state World state updated with L2 block 1
{"eventName":"l2-block-handled","duration":16.3612720000001,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749745327,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
16:17:19 aztec-1 | [16:17:18.803]
INFO:
pxe:service Registered account 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
16:17:19 aztec-1 | [16:17:18.894]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
16:17:19 aztec-1 | [16:17:18.894]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:22:07.000Z
{"offset":288106,"timeMs":1749745327000}
16:17:19 aztec-1 | [16:17:18.908]
INFO:
pxe:service Added contract SchnorrAccount at 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
16:17:19 aztec-1 | [16:17:18.916]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
16:17:19 aztec-1 | error: "Rollup__SlotAlreadyInChain(2, 2)"
16:17:19 aztec-1 | [16:17:18.916]
WARN:
sequencer Cannot propose block 2 at slot 2 due to failed rollup contract check
16:17:19 aztec-1 | [16:17:18.928]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749745363
16:17:19 aztec-1 | [16:17:18.928]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:22:43.000Z
{"offset":324072,"timeMs":1749745363000}
16:17:19 aztec-1 | [16:17:18.928]
INFO:
aztecjs:utils:watcher Slot 2 was filled, jumped to next slot
16:17:21 aztec-1 | [16:17:20.148]
INFO:
pxe:service Proving completed in 1208.0895679999994ms
{"timings":{"total":1208.0895679999994,"sync":17.872489999999743,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":78.77799299999788,"oracles":{"callPrivateFunction":{"times":[281.6925629999987,174.47234200000094]}}},{"functionName":"private_kernel_init","time":46.058154000000286},{"functionName":"SchnorrAccount:constructor","time":266.3917480000018,"oracles":{"getContractInstance":{"times":[1.846797999998671]},"debugLog":{"times":[0.12822500000038417,0.08976000000075146]},"fetchTaggedLogs":{"times":[79.33089500000096]},"loadCapsule":{"times":[0.22340000000076543,0.2889920000015991,0.20305199999893375]},"validateEnqueuedNotesAndEvents":{"times":[4.927147000000332]},"notifyCreatedNullifier":{"times":[0.20401499999934458,0.33202300000084506]},"notifyCreatedNote":{"times":[0.09729599999991478]},"getRandomField":{"times":[0.09780200000022887,0.13039199999911943,0.06682800000089628,0.0643980000004376,0.06170100000053935,0.05862100000013015,0.05745100000058301,0.051789999999527936,0.05877900000086811,0.04925799999909941,0.05573199999889766]},"getIndexedTaggingSecretAsSender":{"times":[52.79920200000015]},"incrementAppTaggingSecretIndexAsSender":{"times":[24.00476399999934]}}},{"functionName":"private_kernel_inner","time":95.20556199999919},{"functionName":"SchnorrAccount:entrypoint","time":154.35986100000082,"oracles":{"debugLog":{"times":[0.14382999999907042,0.09693000000152097,0.13466700000026322]},"fetchTaggedLogs":{"times":[58.68079100000068]},"loadCapsule":{"times":[0.38796999999976833,1.1812930000014603,0.13111899999967136]},"validateEnqueuedNotesAndEvents":{"times":[5.0067849999995815]},"getNotes":{"times":[0.8979500000004919]},"getAuthWitness":{"times":[0.14489700000012817]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.34047999999893364]}}},{"functionName":"private_kernel_inner","time":95.16861900000004},{"functionName":"private_kernel_reset","time":159.86905399999887},{"functionName":"private_kernel_tail","time":65.37679099999878}],"unaccounted":229.009296000002}}
16:17:21 aztec-1 | [16:17:20.195]
INFO:
node Received tx 0x1885eb4a64ad7694375ac3d84959573a4a09af76e83c08b2663558e50f1aa7e1
{"txHash":"0x1885eb4a64ad7694375ac3d84959573a4a09af76e83c08b2663558e50f1aa7e1"}
16:17:21 aztec-1 | [16:17:20.195]
INFO:
pxe:service Sent transaction 0x1885eb4a64ad7694375ac3d84959573a4a09af76e83c08b2663558e50f1aa7e1
16:17:21 aztec-1 | [16:17:20.259]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.020804250999999566s
{"duration":0.020804250999999566,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
16:17:21 aztec-1 | [16:17:20.279]
INFO:
sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x13002a39e122aa829b9631de807a4ee340244b39e0b7a42fa8d12a77238b17d0","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749745363,"version":1785303287},"txHashes":["0x1885eb4a64ad7694375ac3d84959573a4a09af76e83c08b2663558e50f1aa7e1"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":47.11514200000056,"publicProcessDuration":20.88792999999896,"rollupCircuitsDuration":42.54727300000013,"txCount":1,"blockNumber":2,"blockTimestamp":1749745363,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
16:17:21 aztec-1 | [16:17:20.453]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2
16:17:21 aztec-1 | [16:17:20.459]
INFO:
aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2.
16:17:21 aztec-1 | [16:17:20.465]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
16:17:21 aztec-1 | [16:17:20.466]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749745399
16:17:21 aztec-1 | [16:17:20.466]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:23:19.000Z
{"offset":358534,"timeMs":1749745399000}
16:17:21 aztec-1 | [16:17:20.467]
INFO:
aztecjs:utils:watcher Slot 3 was filled, jumped to next slot
16:17:21 aztec-1 | [16:17:20.564]
INFO:
archiver Downloaded L2 block 2
{"blockHash":"0x13002a39e122aa829b9631de807a4ee340244b39e0b7a42fa8d12a77238b17d0","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749745363,"version":1785303287},"archiveRoot":"0x0adbc64ddb63aefd53595130a3140c33a53c68fea31e8ab559402c1c8852909d","archiveNextLeafIndex":3}
16:17:21 aztec-1 | [16:17:20.568]
INFO:
archiver Updated proven chain to block 2
{"provenBlockNumber":2}
16:17:21 aztec-1 | [16:17:20.596]
INFO:
world_state World state updated with L2 block 2
{"eventName":"l2-block-handled","duration":14.547051999999894,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749745363,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
16:17:21 aztec-1 | [16:17:20.723]
INFO:
pxe:service Registered account 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
16:17:21 aztec-1 | [16:17:20.816]
INFO:
pxe:service Added contract SchnorrAccount at 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7 with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097
16:17:22 aztec-1 | [16:17:22.027]
INFO:
pxe:service Proving completed in 1203.3602809999993ms
{"timings":{"total":1203.3602809999993,"sync":21.862559999999576,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":67.7446749999981,"oracles":{"callPrivateFunction":{"times":[275.39813199999844,209.87249599999996]}}},{"functionName":"private_kernel_init","time":45.819477000000916},{"functionName":"SchnorrAccount:constructor","time":257.2617020000016,"oracles":{"getContractInstance":{"times":[3.9797840000010183]},"debugLog":{"times":[0.12290500000017346,0.07214500000009139]},"fetchTaggedLogs":{"times":[79.87329399999908]},"loadCapsule":{"times":[0.16116800000054354,0.870028999999704,0.3018420000007609]},"validateEnqueuedNotesAndEvents":{"times":[6.019588999999542]},"notifyCreatedNullifier":{"times":[0.2085159999987809,0.2103770000012446]},"notifyCreatedNote":{"times":[0.09392099999968195]},"getRandomField":{"times":[0.09502600000087114,0.14046899999993911,0.09877199999937147,0.06613900000047579,0.07960500000081083,0.05676099999982398,0.06974300000001676,0.05280099999981758,0.04697599999963131,0.05801700000120036,0.05101199999990058]},"getIndexedTaggingSecretAsSender":{"times":[34.01872900000126]},"incrementAppTaggingSecretIndexAsSender":{"times":[28.123556000000463]}}},{"functionName":"private_kernel_inner","time":94.4462739999999},{"functionName":"SchnorrAccount:entrypoint","time":189.66177900000002,"oracles":{"debugLog":{"times":[0.11805800000001909,0.0685670000002574,0.10764600000038627]},"fetchTaggedLogs":{"times":[80.859817999999]},"loadCapsule":{"times":[0.18785099999877275,0.13086900000052992,0.11339700000098674]},"validateEnqueuedNotesAndEvents":{"times":[7.549204000000827]},"getNotes":{"times":[11.909396000000925]},"getAuthWitness":{"times":[0.16127899999992223]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.35423399999854155]}}},{"functionName":"private_kernel_inner","time":93.87595200000032},{"functionName":"private_kernel_reset","time":158.98767899999984},{"functionName":"private_kernel_tail","time":61.90884100000039}],"unaccounted":211.79134199999862}}
16:17:22 aztec-1 | [16:17:22.080]
INFO:
node Received tx 0x2359f67d6b3c0779ca81a3cde22dda9b82f03726ded3dac77f9e9f4e4922fdfd
{"txHash":"0x2359f67d6b3c0779ca81a3cde22dda9b82f03726ded3dac77f9e9f4e4922fdfd"}
16:17:22 aztec-1 | [16:17:22.080]
INFO:
pxe:service Sent transaction 0x2359f67d6b3c0779ca81a3cde22dda9b82f03726ded3dac77f9e9f4e4922fdfd
16:17:22 aztec-1 | [16:17:22.139]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.02281567900000118s
{"duration":0.02281567900000118,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":4814},"totalSizeInBytes":992}
16:17:22 aztec-1 | [16:17:22.158]
INFO:
sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x0cb57425c8bd49a81aa09917a4b3381037482bce14ef70a3ea67b5ec96eeb704","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749745399,"version":1785303287},"txHashes":["0x2359f67d6b3c0779ca81a3cde22dda9b82f03726ded3dac77f9e9f4e4922fdfd"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":48.06686400000035,"publicProcessDuration":22.920820999999705,"rollupCircuitsDuration":43.139664000000266,"txCount":1,"blockNumber":3,"blockTimestamp":1749745399,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
16:17:22 aztec-1 | [16:17:22.340]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
16:17:22 aztec-1 | [16:17:22.345]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749745435
16:17:22 aztec-1 | [16:17:22.345]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:23:55.000Z
{"offset":392655,"timeMs":1749745435000}
16:17:22 aztec-1 | [16:17:22.345]
INFO:
aztecjs:utils:watcher Slot 4 was filled, jumped to next slot
16:17:22 aztec-1 | [16:17:22.429]
INFO:
archiver Downloaded L2 block 3
{"blockHash":"0x0cb57425c8bd49a81aa09917a4b3381037482bce14ef70a3ea67b5ec96eeb704","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749745399,"version":1785303287},"archiveRoot":"0x1f3f4fb7063cd26c982bea69de3f721da07521390b3918457c092b1b48076757","archiveNextLeafIndex":4}
16:17:22 aztec-1 | [16:17:22.460]
INFO:
world_state World state updated with L2 block 3
{"eventName":"l2-block-handled","duration":13.028190999999424,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749745399,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
16:17:22 aztec-1 | [16:17:22.528]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3
16:17:22 aztec-1 | [16:17:22.529]
INFO:
aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3.
16:17:22 aztec-1 | Initial Accounts:
16:17:22 aztec-1 |
16:17:22 aztec-1 | Address: 0x1935497c24c66f601a7fd9b6b1e6b1f5f667f04034c621bde89755ba273e844e
16:17:22 aztec-1 | Partial Address: 0x27b4888363d0f9eb0d43aa0b2ff08f21fbc6867b8e0baf8ec6062c4623c1eefc
16:17:22 aztec-1 | Secret Key: 0x2153536ff6628eee01cf4024889ff977a18d9fa61d0e414422f7681cf085c281
16:17:22 aztec-1 | Master nullifier public key: 0x1997041f7bef378b53f778a7e6dbe318581a74046c6d89d2a5c3541d3bd6499f2756b249eea865f51139100ec94a5f52e4e818dd0ebf4e276353fbd54d26047f
16:17:22 aztec-1 | Master incoming viewing public key: 0x16f717457ac6823102f11889f50751329847d5aeeb3ba846b7f0c337cc66de97154f56206fb1079022acad62e1b9af9392baca2c9f995d302b37923ee598025f
16:17:22 aztec-1 |
16:17:22 aztec-1 | Master outgoing viewing public key: 0x04d3de24dac85211b9726c43b7029e039491f5e9f60551f30eaa37956ad74f2f2b8e64db900901c2b5c0f6e6e6644f480e31387dc5769078e16a937e08f2d1c5
16:17:22 aztec-1 |
16:17:22 aztec-1 | Master tagging public key: 0x1bdebe239b3152cbfba5b6b1ea7e5ad98736d9ef92b6ccef8129e9ea59dbed4a0599b2ab4ed94c01fdddc06c4b8115a24e3dae480fb0e182c17cb5cfa545cb89
16:17:22 aztec-1 |
16:17:22 aztec-1 | Address: 0x103e82c3da93031a6f52a945d8e3bc181f8e104e48672ada425382af022bbb3f
16:17:22 aztec-1 | Partial Address: 0x2e19b56de1617cb3179d81be0173c7301621a2e820da7f2754fcfccdfc4418e0
16:17:22 aztec-1 | Secret Key: 0x0aebd1b4be76efa44f5ee655c20bf9ea60f7ae44b9a7fd1fd9f189c7a0b0cdae
16:17:22 aztec-1 | Master nullifier public key: 0x0dfb2c1f9425ac05d57c24a0aaf313ab9abb70def4c7fa973b5695a877528de8058c109f9c8851f67ff1a34e81683fb20baf87182ab8cc0b44bcddf3f705da57
16:17:22 aztec-1 | Master incoming viewing public key: 0x118abb6cbfb12f6d61f7c837d3a38a00938073002990388406714e3a30ef000e272f398119b8635dd875c8b35a0d5c2e7a739eefebf89e9172951b92abfb1f4d
16:17:22 aztec-1 |
16:17:22 aztec-1 | Master outgoing viewing public key: 0x07b57b36fb6585f72636d7741cb49fcbe6d49c2151fee2098859c1f5c94113ce197e3bdc18ff96f058ff0d9b77e741a17e257e6ff1fc03b8753ead015757de4e
16:17:22 aztec-1 |
16:17:22 aztec-1 | Master tagging public key: 0x05c11aef8517bdfa57b1ad0e12fccd75f7a6225eb651c8dafa894a87768863ad213d2de9a62211c1651f8ecb5638d4bcecefa23631b87b901c3e5f8869c0708d
16:17:22 aztec-1 |
16:17:22 aztec-1 | Address: 0x280737c4106e70a3391b796f1a2bbbdcb0b2fea06f75a7a5a4bfcb33c02727b7
16:17:22 aztec-1 | Partial Address: 0x2451b67547512eaaaad5ded43156c5b80220b7d5145ebefdd57295a9e05a610d
16:17:22 aztec-1 | Secret Key: 0x0f6addf0da06c33293df974a565b03d1ab096090d907d98055a8b7f4954e120c
16:17:22 aztec-1 | Master nullifier public key: 0x12087794c56b60efc7d90af9cc366b4a53f702ff08e18c2839a384dffc0afa2a21da640447f79013922b5028da3b3461c601d66a2f723d36b7437a709a02b3b3
16:17:22 aztec-1 | Master incoming viewing public key: 0x26ba3ba4980fb7b0d09936aec4b50839a652151afbb6eaac03a017132d41dae52f2ec73c5fa77d24999da6b4b5a5c98bdd9609dd940de6ea1b397bb21c2edbae
16:17:22 aztec-1 |
16:17:22 aztec-1 | Master outgoing viewing public key: 0x046da42846259b1e229fb0675b7848186ebdc5df9a6d05808338142737a8accd11fc12819daaa9ba316e79412da522a2805dc6ceee04e22f666f1d54694f05da
16:17:22 aztec-1 |
16:17:22 aztec-1 | Master tagging public key: 0x0d7423bb9647cebcbbf1a4212e9cf3e2d56dfcd9b5b7300814ec4513734e6d6230365eb863aedc1e62de64d0f14479cf8ac23e461530acc6e7f9dfc0528e32ba
16:17:22 aztec-1 |
16:17:22 aztec-1 |
16:17:23 aztec-1 | [16:17:23.141]
WARN:
pxe:service No artifact found for contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 when looking for its metadata
16:17:23 aztec-1 | [16:17:23.141]
WARN:
pxe:service No artifact found for contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 when looking for its metadata
16:17:23 aztec-1 | [16:17:23.145]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1 as part of deployment for 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
16:17:23 aztec-1 | [16:17:23.169]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645 as part of deployment for 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
16:17:23 aztec-1 | [16:17:23.358]
INFO:
archiver Updated proven chain to block 3
{"provenBlockNumber":3}
16:17:24 aztec-1 | [16:17:23.831]
INFO:
pxe:service Added contract FPC at 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 with class 0x151d86e8f05858be4838e2ca89859d01b6f0c70d521866c53ec7b9181b9a60d1
16:17:24 aztec-1 | [16:17:23.835]
INFO:
pxe:service Added contract Token at 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf with class 0x1ecd38c4c0a093bb1edb23d8d306929d405875fdc7e9c53762c43d19dfb04645
16:17:26 aztec-1 | [16:17:25.551]
INFO:
pxe:service Proving completed in 1699.2117170000001ms
{"timings":{"total":1699.2117170000001,"sync":15.38926899999933,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":259.2283529999986,"oracles":{"debugLog":{"times":[0.13483199999973294,0.07935700000052748,0.07918199999949138,0.13742099999944912,0.08646199999930104,0.0972860000001674,0.06447499999922002,0.1252969999986817]},"fetchTaggedLogs":{"times":[102.00811499999872]},"loadCapsule":{"times":[0.2029980000006617,0.14105500000005122,0.2888009999987844,0.16342299999996612,0.1680209999994986,0.17526700000053097,0.16223399999944377]},"getSharedSecret":{"times":[4.333776000001308]},"aes128Decrypt":{"times":[0.8717739999992773,0.5594069999988278]},"getPublicKeysAndPartialAddress":{"times":[1.0811629999989236]},"getKeyValidationRequest":{"times":[1.657643000000462]},"storeCapsule":{"times":[2.3816069999993488,2.186384000000544,2.307278000000224]},"deleteCapsule":{"times":[2.861985999999888]},"validateEnqueuedNotesAndEvents":{"times":[12.187558999999965]},"getNotes":{"times":[5.848690999999235]},"getAuthWitness":{"times":[0.16488699999899836]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.03846299999713665]},"callPrivateFunction":{"times":[465.2417580000001,93.90903999999864]},"notifyEnqueuedPublicFunctionCall":{"times":[0.22605699999985518]}}},{"functionName":"private_kernel_init","time":47.0715130000026},{"functionName":"ContractClassRegisterer:register","time":455.30719300000055,"oracles":{"loadCapsule":{"times":[2.1443340000005264]},"notifyCreatedNullifier":{"times":[0.2525070000010601]},"debugLog":{"times":[0.10974899999928311]},"notifyCreatedContractClassLog":{"times":[3.4215360000016517]}}},{"functionName":"private_kernel_inner","time":93.80713100000139},{"functionName":"ContractInstanceDeployer:deploy","time":57.84345100000064,"oracles":{"storeInExecutionCache":{"times":[0.17699000000357046]},"callPrivateFunction":{"times":[33.84394500000053]},"notifyCreatedNullifier":{"times":[0.2082589999990887]},"debugLog":{"times":[0.11099999999714782]}}},{"functionName":"private_kernel_inner","time":96.51420499999949},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":22.479561000000103,"oracles":{}},{"functionName":"private_kernel_inner","time":98.75966799999878},{"functionName":"private_kernel_reset","time":164.92295100000047},{"functionName":"private_kernel_tail","time":76.3179569999993}],"unaccounted":311.5704649999989}}
16:17:26 aztec-1 | [16:17:25.682]
INFO:
node Received tx 0x0267425960d6870401f37fd44a8c576aaa83a1dae54ea92a76cee703f453d6f0
{"txHash":"0x0267425960d6870401f37fd44a8c576aaa83a1dae54ea92a76cee703f453d6f0"}
16:17:26 aztec-1 | [16:17:25.682]
INFO:
pxe:service Sent transaction 0x0267425960d6870401f37fd44a8c576aaa83a1dae54ea92a76cee703f453d6f0
16:17:26 aztec-1 | [16:17:26.588]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.7963511190000027s
{"duration":0.7963511190000027,"rate":44583.34916962662,"totalPublicGas":{"daGas":4096,"l2Gas":35504},"totalBlockGas":{"daGas":166912,"l2Gas":60546},"totalSizeInBytes":10688}
16:17:26 aztec-1 | [16:17:26.687]
INFO:
sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 39164.71666736677 mana/s
{"blockHash":"0x218623e95bad26c79ae2936fc182579e5e1af8c362a66bb817e5eac8c8747b2e","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749745435,"version":1785303287},"txHashes":["0x0267425960d6870401f37fd44a8c576aaa83a1dae54ea92a76cee703f453d6f0"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":906.4724179999976,"publicProcessDuration":796.4529739999998,"rollupCircuitsDuration":897.398508000002,"txCount":1,"blockNumber":4,"blockTimestamp":1749745435,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
16:17:27 aztec-1 | [16:17:27.229]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
16:17:27 aztec-1 | [16:17:27.229]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:24:07.000Z
{"offset":399771,"timeMs":1749745447000}
16:17:27 aztec-1 | [16:17:27.233]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4
16:17:27 aztec-1 | [16:17:27.240]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749745471
16:17:27 aztec-1 | [16:17:27.240]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:24:31.000Z
{"offset":423760,"timeMs":1749745471000}
16:17:27 aztec-1 | [16:17:27.240]
INFO:
aztecjs:utils:watcher Slot 5 was filled, jumped to next slot
16:17:27 aztec-1 | [16:17:27.241]
INFO:
aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4.
16:17:27 aztec-1 | [16:17:27.245]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
16:17:27 aztec-1 | [16:17:27.585]
INFO:
pxe:service Proving completed in 2033.4332240000003ms
{"timings":{"total":2033.4332240000003,"sync":102.20563500000208,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":312.87584199999765,"oracles":{"debugLog":{"times":[0.12238699999943492,0.06599799999821698,0.07180000000153086,0.12710800000058953,0.09583799999745679,0.06854400000156602,0.07133299999986775,0.09393600000112201]},"fetchTaggedLogs":{"times":[125.31995399999869]},"loadCapsule":{"times":[0.192417000002024,0.19002399999953923,0.26110800000242307,0.6063460000004852,0.997720000003028,0.3493769999986398,0.3812079999988782]},"getSharedSecret":{"times":[2.6690359999993234]},"aes128Decrypt":{"times":[0.1726760000019567,0.46596499999941443]},"getPublicKeysAndPartialAddress":{"times":[4.175673999998253]},"getKeyValidationRequest":{"times":[2.3973230000010517]},"storeCapsule":{"times":[18.34208800000124,2.421782000001258,2.712219000000914]},"deleteCapsule":{"times":[2.190259999999398]},"validateEnqueuedNotesAndEvents":{"times":[24.236227999997936]},"getNotes":{"times":[1.2536139999974694]},"getAuthWitness":{"times":[0.12292899999738438]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.03584000000046217]},"callPrivateFunction":{"times":[457.08833199999935,106.7968550000005]},"notifyEnqueuedPublicFunctionCall":{"times":[0.062111999999615364]}}},{"functionName":"private_kernel_init","time":46.07750600000145},{"functionName":"ContractClassRegisterer:register","time":447.230563000001,"oracles":{"loadCapsule":{"times":[1.7961890000005951]},"notifyCreatedNullifier":{"times":[0.2707810000028985]},"debugLog":{"times":[0.0916030000007595]},"notifyCreatedContractClassLog":{"times":[3.394909999999072]}}},{"functionName":"private_kernel_inner","time":93.78235900000072},{"functionName":"ContractInstanceDeployer:deploy","time":60.95870599999398,"oracles":{"storeInExecutionCache":{"times":[0.06929800000216346]},"callPrivateFunction":{"times":[49.275768999999855]},"notifyCreatedNullifier":{"times":[0.21502999999938766]},"debugLog":{"times":[0.132011000001512]}}},{"functionName":"private_kernel_inner","time":93.58531399999993},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":36.28798000000461,"oracles":{}},{"functionName":"private_kernel_inner","time":93.6169860000009},{"functionName":"private_kernel_reset","time":162.75990499999898},{"functionName":"private_kernel_tail","time":77.53997199999867}],"unaccounted":506.5124560000004}}
16:17:27 aztec-1 | [16:17:27.727]
INFO:
node Received tx 0x09dd13bbc20b0b2ffd4b2ae5684c97cdf382fb69122dfc385435f105224c4672
{"txHash":"0x09dd13bbc20b0b2ffd4b2ae5684c97cdf382fb69122dfc385435f105224c4672"}
16:17:27 aztec-1 | [16:17:27.727]
INFO:
pxe:service Sent transaction 0x09dd13bbc20b0b2ffd4b2ae5684c97cdf382fb69122dfc385435f105224c4672
16:17:27 aztec-1 | [16:17:27.761]
INFO:
archiver Downloaded L2 block 4
{"blockHash":"0x218623e95bad26c79ae2936fc182579e5e1af8c362a66bb817e5eac8c8747b2e","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749745435,"version":1785303287},"archiveRoot":"0x115ca3192ed032c7cd5f81805e320f4ad65f9ce0bfdc069fb848ec7e726e00a3","archiveNextLeafIndex":5}
16:17:27 aztec-1 | [16:17:27.767]
INFO:
archiver Updated proven chain to block 4
{"provenBlockNumber":4}
16:17:27 aztec-1 | [16:17:27.786]
INFO:
world_state World state updated with L2 block 4
{"eventName":"l2-block-handled","duration":11.46116500000062,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749745435,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":296}
16:17:27 aztec-1 | [16:17:27.815]
INFO:
aztecjs:deploy_sent_tx Contract 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45 successfully deployed.
16:17:28 aztec-1 | [16:17:28.120]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.22943896799999858s
{"duration":0.22943896799999858,"rate":1204019.5369079662,"totalPublicGas":{"daGas":10240,"l2Gas":276249},"totalBlockGas":{"daGas":380928,"l2Gas":301291},"totalSizeInBytes":24064}
16:17:28 aztec-1 | [16:17:28.162]
INFO:
sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 982805.0929247506 mana/s
{"blockHash":"0x03ff61200aabf327c4015ca63ad1f6d814646d890229539ecfeb06b896d79b91","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749745471,"version":1785303287},"txHashes":["0x09dd13bbc20b0b2ffd4b2ae5684c97cdf382fb69122dfc385435f105224c4672"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":281.06053800000154,"publicProcessDuration":229.83938000000126,"rollupCircuitsDuration":275.98998500000016,"txCount":1,"blockNumber":5,"blockTimestamp":1749745471,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
16:17:28 aztec-1 | [16:17:28.367]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
16:17:28 aztec-1 | [16:17:28.376]
WARN:
archiver Failed to reach L2 block 5 at 32 (latest is 4). Rolling back last synched L1 block number to 31.
{"latestLocalL2BlockNumber":4,"latestLocalL2BlockArchive":"0x115ca3192ed032c7cd5f81805e320f4ad65f9ce0bfdc069fb848ec7e726e00a3","blocksSynchedTo":31,"currentL1BlockNumber":32,"provenBlockNumber":4,"provenArchive":"0x115ca3192ed032c7cd5f81805e320f4ad65f9ce0bfdc069fb848ec7e726e00a3","pendingBlockNumber":5,"pendingArchive":"0x25d45914d7e50cb1d64a434e50074c423a23765e6049318db11b6f4eb1a632bc"}
16:17:28 aztec-1 | [16:17:28.427]
WARN:
sequencer:publisher Failed canProposeAtTime check with SlotAlreadyInChain
16:17:28 aztec-1 | error: "Rollup__SlotAlreadyInChain(6, 6)"
16:17:28 aztec-1 | [16:17:28.427]
WARN:
sequencer Cannot propose block 5 at slot 6 due to failed rollup contract check
16:17:28 aztec-1 | [16:17:28.433]
WARN:
aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time
16:17:28 aztec-1 | [16:17:28.434]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:24:43.000Z
{"offset":434567,"timeMs":1749745483000}
16:17:28 aztec-1 | [16:17:28.441]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5
16:17:28 aztec-1 | [16:17:28.442]
INFO:
aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5.
16:17:28 aztec-1 | [16:17:28.522]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749745507
16:17:28 aztec-1 | [16:17:28.522]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:25:07.000Z
{"offset":458478,"timeMs":1749745507000}
16:17:28 aztec-1 | [16:17:28.522]
INFO:
aztecjs:utils:watcher Slot 6 was filled, jumped to next slot
16:17:28 aztec-1 | [16:17:28.526]
INFO:
archiver Downloaded L2 block 5
{"blockHash":"0x03ff61200aabf327c4015ca63ad1f6d814646d890229539ecfeb06b896d79b91","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749745471,"version":1785303287},"archiveRoot":"0x25d45914d7e50cb1d64a434e50074c423a23765e6049318db11b6f4eb1a632bc","archiveNextLeafIndex":6}
16:17:28 aztec-1 | [16:17:28.582]
INFO:
world_state World state updated with L2 block 5
{"eventName":"l2-block-handled","duration":11.874552999997832,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749745471,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":702}
16:17:28 aztec-1 | [16:17:28.594]
INFO:
archiver Updated proven chain to block 5
{"provenBlockNumber":5}
16:17:28 aztec-1 | BananaCoin: 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf
16:17:28 aztec-1 | FPC: 0x2207862293647848d5c210b37b67e61cee2fec093491b093b4708e95bf3b4b45
16:17:29 aztec-1 | [16:17:28.911]
INFO:
aztecjs:deploy_sent_tx Contract 0x1fc314b687b4b23399a55ac511deb2e6091f046c7cf5ea375ab5557573e77cbf successfully deployed.
16:17:29 aztec-1 | [16:17:28.938]
WARN:
pxe:service No artifact found for contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e when looking for its metadata
16:17:29 aztec-1 | [16:17:28.940]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e as part of deployment for 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
16:17:29 aztec-1 | [16:17:29.046]
INFO:
pxe:service Added contract SponsoredFPC at 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 with class 0x2dacfd02a8775d3a80d4f185f8765bd3af452cf0b5c53a542ff56b271af4e87e
16:17:31 aztec-1 | [16:17:30.621]
INFO:
pxe:service Proving completed in 1567.120093999998ms
{"timings":{"total":1567.120093999998,"sync":26.345957000001363,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":53.799057000000175,"oracles":{"callPrivateFunction":{"times":[446.55026799999905,99.98221999999805,24.06167600000117]}}},{"functionName":"private_kernel_init","time":45.562738000000536},{"functionName":"ContractClassRegisterer:register","time":437.3522010000015,"oracles":{"loadCapsule":{"times":[4.915936000001238]},"notifyCreatedNullifier":{"times":[0.2352869999995164]},"debugLog":{"times":[0.09149700000125449]},"notifyCreatedContractClassLog":{"times":[3.359266999999818]}}},{"functionName":"private_kernel_inner","time":93.39829800000007},{"functionName":"ContractInstanceDeployer:deploy","time":66.91388399999778,"oracles":{"storeInExecutionCache":{"times":[0.059569000000919914]},"callPrivateFunction":{"times":[34.1608559999986]},"notifyCreatedNullifier":{"times":[0.1922759999979462]},"debugLog":{"times":[0.1081640000011248]}}},{"functionName":"private_kernel_inner","time":93.25384900000063},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":23.392212000002473,"oracles":{}},{"functionName":"private_kernel_inner","time":95.8527929999982},{"functionName":"SponsoredFPC:sponsor_unconditionally","time":16.667132999999012,"oracles":{"debugLog":{"times":[0.10116399999969872]}}},{"functionName":"private_kernel_inner","time":92.71486199999708},{"functionName":"private_kernel_reset","time":157.03114800000185},{"functionName":"private_kernel_tail","time":61.54725300000064}],"unaccounted":303.28870899999674}}
16:17:31 aztec-1 | [16:17:30.711]
INFO:
node Received tx 0x0047e9386b4721bb025675a8444b927e1dd44cddc9fafc56f5a3a39d69dc88e4
{"txHash":"0x0047e9386b4721bb025675a8444b927e1dd44cddc9fafc56f5a3a39d69dc88e4"}
16:17:31 aztec-1 | [16:17:30.711]
INFO:
pxe:service Sent transaction 0x0047e9386b4721bb025675a8444b927e1dd44cddc9fafc56f5a3a39d69dc88e4
16:17:31 aztec-1 | [16:17:30.805]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.02152912700000161s
{"duration":0.02152912700000161,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14336,"l2Gas":3532},"totalSizeInBytes":1152}
16:17:31 aztec-1 | [16:17:30.823]
INFO:
sequencer Built block 6 for slot 7 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x1ed8e012f4c2133ae34853bf6260d1abb034e79bb4e4a168a8c23ebdf9070bba","globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749745507,"version":1785303287},"txHashes":["0x0047e9386b4721bb025675a8444b927e1dd44cddc9fafc56f5a3a39d69dc88e4"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":44.78394000000117,"publicProcessDuration":21.617537999998603,"rollupCircuitsDuration":40.1047209999997,"txCount":1,"blockNumber":6,"blockTimestamp":1749745507,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
16:17:31 aztec-1 | [16:17:30.992]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
16:17:31 aztec-1 | [16:17:30.997]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749745543
16:17:31 aztec-1 | [16:17:30.997]
WARN:
foundation:test-date-provider Time set to 2025-06-12T16:25:43.000Z
{"offset":492003,"timeMs":1749745543000}
16:17:31 aztec-1 | [16:17:30.997]
INFO:
aztecjs:utils:watcher Slot 7 was filled, jumped to next slot
16:17:31 aztec-1 | [16:17:31.055]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x68b1d87f95878fe05b998f19b66f4baba5de1aed at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 6
16:17:31 aztec-1 | [16:17:31.057]
INFO:
aztecjs:cheat_codes Proven tip moved: 5 -> 6. Pending tip: 6.
16:17:31 aztec-1 | [16:17:31.088]
INFO:
archiver Downloaded L2 block 6
{"blockHash":"0x1ed8e012f4c2133ae34853bf6260d1abb034e79bb4e4a168a8c23ebdf9070bba","blockNumber":6,"txCount":1,"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":36260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749745507,"version":1785303287},"archiveRoot":"0x1b6771dececdd6d085e322417f9f673bbd19674244292a26df94a972d978301b","archiveNextLeafIndex":7}
16:17:31 aztec-1 | [16:17:31.093]
INFO:
archiver Updated proven chain to block 6
{"provenBlockNumber":6}
16:17:31 aztec-1 | [16:17:31.130]
INFO:
world_state World state updated with L2 block 6
{"eventName":"l2-block-handled","duration":12.311825999997382,"unfinalisedBlockNumber":6,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":6,"blockTimestamp":1749745507,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
16:17:31 aztec-1 | SponsoredFPC: 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354
16:17:32 aztec-1 | [16:17:31.720]
INFO:
aztecjs:deploy_sent_tx Contract 0x0e68a7c0b68b13ddc5b1413195d70fd0611642afefe799a41314e1c70ade6354 successfully deployed.
16:17:32 aztec-1 | [16:17:31.724]
INFO:
cli Aztec Server listening on port 8080
{"l2CircuitsVkTreeRoot":"0x2bf2a95060ddbd049be7f501ee6d0dd0fde85c50e642e13a84f201e420805b66","l2ProtocolContractsTreeRoot":"0x1e17c1ee24567253f9251a751f3b28eb73726471df70ecc9852eef905056b57e"}
16:17:36 playground-1 | \
16:17:36 playground-1 |
Running 1
test using 1
worker
16:17:36 playground-1 |
16:17:36 playground-1 |
✓ 1 [firefox] › browser.spec.ts:3:1 › test
(8.1s)
16:17:48 playground-1 |
1 passed (12.9s)
16:17:48 playground-1 | \
16:17:48
playground-1 exited with code 0
16:17:48 Aborting on container exit...
16:17:48 Container playground-firefox-playground-1 Stopping
16:17:48 Container playground-firefox-playground-1 Stopped
16:17:48 Container playground-firefox-aztec-1 Stopping
16:17:50 Container playground-firefox-aztec-1 Stopped
16:17:50 Container playground-firefox-ethereum-1 Stopping
16:17:50 Container playground-firefox-ethereum-1 Stopped