Parent Log:
http://ci.aztec-labs.com/d6b9e3e678db815e
Command: 857695163c0e152a:ISOLATE=1:NAME=e2e_max_block_number yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_max_block_number.test.ts
Commit:
https://github.com/AztecProtocol/aztec-packages/commit/267ac35e8ccb487bdc79bbe12d428f4c8b4d4d9f
Env: REF_NAME=gh-readonly-queue/master/pr-15002-1338cef09c870378254c56748f972ae827c4d482 CURRENT_VERSION=0.87.8 CI_FULL=0
Date: Fri Jun 13 15:19:09 UTC 2025
System: ARCH=arm64 CPUS=64 MEM=247Gi HOSTNAME=pr-15002_arm64_a1-fast
Resources: CPU_LIST=0-63 CPUS=2 MEM=8g TIMEOUT=600s
History:
http://ci.aztec-labs.com/list/history_d488fdfe97c9ae48_master
15:19:09 +++ id -u
15:19:09 +++ id -g
15:19:09 ++ docker run -d --name e2e_max_block_number --net=none --cpuset-cpus=0-63 --cpus=2 --memory=8g --user 1000:1000 -v/home/aztec-dev:/home/aztec-dev --mount type=tmpfs,target=/tmp,tmpfs-size=1g --workdir /home/aztec-dev/aztec-packages -e HOME -e VERBOSE -e GIT_CONFIG_GLOBAL=/home/aztec-dev/aztec-packages/build-images/src/home/.gitconfig -e FORCE_COLOR=true -e CPUS -e MEM aztecprotocol/build:3.0 /bin/bash -c 'timeout -v 600s bash -c '\''yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_max_block_number.test.ts'\'''
15:19:09 + cid=50190fb9a279810d50fb80375873549fefb340b3f085a65a9cef26206088bb7f
15:19:09 + set +x
15:19:10 {"level":25,"time":1749827950210,"pid":12,"hostname":"50190fb9a279","module":"logger","msg":"Logger initialized with level verbose"}
15:19:14 [15:19:14.373]
INFO:
e2e:e2e_max_block_number Logging metrics to log/unknown.jsonl
15:19:14 [15:19:14.559]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-ZTfPol/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads.
15:19:14 [15:19:14.597]
INFO:
e2e:e2e_max_block_number Calling anvil_setBlockTimestampInterval with params: [12]
15:19:14 [15:19:14.618]
WARN:
e2e:e2e_max_block_number Set block interval to 12
15:19:14 [15:19:14.619]
VERBOSE:
e2e:e2e_max_block_number Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266
15:19:14 [15:19:14.664]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84
{"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"}
15:19:14 [15:19:14.671]
VERBOSE:
e2e:e2e_max_block_number Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3
15:19:14 [15:19:14.687]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb
{"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"}
15:19:14 [15:19:14.691]
VERBOSE:
e2e:e2e_max_block_number Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512
15:19:14 [15:19:14.707]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169
{"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"}
15:19:14 [15:19:14.712]
VERBOSE:
e2e:e2e_max_block_number Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0
15:19:14 [15:19:14.726]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c
{"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"}
15:19:14 [15:19:14.738]
VERBOSE:
e2e:e2e_max_block_number Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9
15:19:14 [15:19:14.756]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e
{"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"}
15:19:14 [15:19:14.760]
VERBOSE:
e2e:e2e_max_block_number Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9
15:19:14 [15:19:14.773]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64
{"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"}
15:19:14 [15:19:14.777]
VERBOSE:
e2e:e2e_max_block_number Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707
15:19:14 [15:19:14.777]
VERBOSE:
e2e:e2e_max_block_number Waiting for deployments to complete
15:19:14 [15:19:14.778]
INFO:
e2e:e2e_max_block_number Waiting for 6 transactions to be mined...
15:19:14 [15:19:14.796]
INFO:
e2e:e2e_max_block_number All transactions mined successfully
15:19:14 [15:19:14.809]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8
{"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"}
15:19:14 [15:19:14.809]
VERBOSE:
e2e:e2e_max_block_number Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8
15:19:14 [15:19:14.820]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63
{"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"}
15:19:14 [15:19:14.832]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf
{"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"}
15:19:14 [15:19:14.837]
VERBOSE:
e2e:e2e_max_block_number Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6
15:19:14 [15:19:14.848]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2
{"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"}
15:19:14 [15:19:14.848]
VERBOSE:
e2e:e2e_max_block_number Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2
15:19:14 [15:19:14.865]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1
{"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"}
15:19:14 [15:19:14.870]
VERBOSE:
e2e:e2e_max_block_number Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788
15:19:14 [15:19:14.881]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a
{"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"}
15:19:14 [15:19:14.881]
VERBOSE:
e2e:e2e_max_block_number Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a
15:19:14 [15:19:14.881]
VERBOSE:
e2e:e2e_max_block_number Waiting for deployments to complete
15:19:14 [15:19:14.881]
INFO:
e2e:e2e_max_block_number Waiting for 8 transactions to be mined...
15:19:14 [15:19:14.898]
INFO:
e2e:e2e_max_block_number All transactions mined successfully
15:19:14 [15:19:14.907]
VERBOSE:
e2e:e2e_max_block_number Deployed shared contracts
15:19:14 [15:19:14.921]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034
{"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"}
15:19:14 [15:19:14.922]
VERBOSE:
e2e:e2e_max_block_number Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034
15:19:14 [15:19:14.924]
VERBOSE:
e2e:e2e_max_block_number Rollup config args
{"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100}
15:19:14 [15:19:14.935]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c
{"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"}
15:19:14 [15:19:14.958]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a
{"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"}
15:19:14 [15:19:14.963]
VERBOSE:
e2e:e2e_max_block_number Awaiting for linked libraries to be deployed
15:19:14 [15:19:14.995]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x265dbd77dab8a00719699a8e4a70c61432cf033b13b86a765d9654bc67839708
{"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"}
15:19:15 [15:19:15.000]
VERBOSE:
e2e:e2e_max_block_number Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016
{"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100}
15:19:15 [15:19:15.001]
INFO:
e2e:e2e_max_block_number Waiting for 9 transactions to be mined...
15:19:15 [15:19:15.018]
INFO:
e2e:e2e_max_block_number All transactions mined successfully
15:19:15 [15:19:15.018]
VERBOSE:
e2e:e2e_max_block_number All core contracts have been deployed
15:19:15 [15:19:15.031]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a
{"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"}
15:19:15 [15:19:15.031]
VERBOSE:
e2e:e2e_max_block_number Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments)
15:19:15 [15:19:15.046]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab
{"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"}
15:19:15 [15:19:15.049]
VERBOSE:
e2e:e2e_max_block_number Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae
15:19:15 [15:19:15.069]
VERBOSE:
e2e:e2e_max_block_number Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d
{"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"}
15:19:15 [15:19:15.069]
VERBOSE:
e2e:e2e_max_block_number Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d
15:19:15 [15:19:15.070]
INFO:
e2e:e2e_max_block_number Waiting for 10 transactions to be mined...
15:19:15 [15:19:15.088]
INFO:
e2e:e2e_max_block_number All transactions mined successfully
15:19:15 [15:19:15.093]
VERBOSE:
e2e:e2e_max_block_number Rollup deployed
15:19:15 [15:19:15.093]
VERBOSE:
e2e:e2e_max_block_number Waiting for rollup and slash factory to be deployed
15:19:15 [15:19:15.094]
INFO:
e2e:e2e_max_block_number Waiting for 10 transactions to be mined...
15:19:15 [15:19:15.110]
INFO:
e2e:e2e_max_block_number All transactions mined successfully
15:19:15 [15:19:15.111]
VERBOSE:
e2e:e2e_max_block_number All transactions for L1 deployment have been mined
15:19:15 [15:19:15.128]
INFO:
e2e:e2e_max_block_number Aztec L1 contracts initialized
{"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"}
15:19:15 [15:19:15.130]
INFO:
ethereum:cheat_codes Calling anvil_getAutomine with params: [] on
http://127.0.0.1:8545
15:19:15 [15:19:15.133]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:15 [15:19:15.134]
INFO:
aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016
15:19:15 [15:19:15.134]
INFO:
telemetry:client Using NoopTelemetryClient
15:19:15 [15:19:15.139]
WARN:
aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks
15:19:15 [15:19:15.140]
INFO:
ethereum:cheat_codes Calling evm_mine with params: [] on
http://127.0.0.1:8545
15:19:15 [15:19:15.140]
INFO:
kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/c4f2767af25d72b3/blob-sink with map size 134217728 KB (LMDB v2)
15:19:15 [15:19:15.142]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:15 [15:19:15.145]
INFO:
aztecjs:utils:watcher New L1 time: 1749828194
15:19:15 [15:19:15.145]
WARN:
foundation:test-date-provider Time set to 2025-06-13T15:23:14.000Z
{"offset":238855,"timeMs":1749828194000}
15:19:15 [15:19:15.147]
INFO:
kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16
15:19:15 [15:19:15.152]
INFO:
blob-sink:server Server is running on
http://localhost:37163
15:19:15 [15:19:15.152]
VERBOSE:
e2e:e2e_max_block_number Creating and synching an aztec node...
15:19:15 [15:19:15.153]
VERBOSE:
e2e:e2e_max_block_number Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/4af658e9/acvm
15:19:15 [15:19:15.154]
INFO:
node:blob-sink:client Creating HTTP blob sink client.
{"blobSinkUrl":"http://localhost:37163"}
15:19:15 [15:19:15.166]
VERBOSE:
node Snapshot sync is disabled. No snapshots URL provided.
15:19:15 [15:19:15.167]
INFO:
archiver:lmdb Creating archiver data store at directory /tmp/c4f2767af25d72b3/archiver with map size 134217728 KB (LMDB v2)
15:19:15 [15:19:15.168]
INFO:
archiver:lmdb Starting data store with maxReaders 16
15:19:15 [15:19:15.351]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:15 [15:19:15.426]
INFO:
node:blob-sink:client Testing configured blob sources
{"blobSinkUrl":"http://localhost:37163"}
15:19:15 [15:19:15.441]
INFO:
node:blob-sink:client Blob sink is reachable
{"blobSinkUrl":"http://localhost:37163"}
15:19:15 [15:19:15.441]
WARN:
node:blob-sink:client No L1 consensus host urls configured
15:19:15 [15:19:15.441]
WARN:
node:blob-sink:client No archive client configured
15:19:15 [15:19:15.444]
INFO:
archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x6e82f74280c9cefeb135a4b4a6c45fafeebcae370396a4a92c0da58426ea4c90
{"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x984be9a9c6c54be9c4f9600856fe4e20b6cd5653555cfbdb5c936ddf9699c564","l1BlockNumber":16}}
15:19:15 [15:19:15.456]
INFO:
archiver Initial archiver sync to L1 block 20 complete.
{"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0x6e82f74280c9cefeb135a4b4a6c45fafeebcae370396a4a92c0da58426ea4c90","l1BlockNumber":20}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}}
15:19:15 [15:19:15.460]
INFO:
world-state:database Creating world state data store at directory /tmp/c4f2767af25d72b3/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads.
15:19:15 [15:19:15.483]
INFO:
world_state Created world state synchroniser with block history of 64
15:19:15 [15:19:15.483]
WARN:
node Aztec node is accepting fake proofs
15:19:15 [15:19:15.492]
INFO:
p2p:lmdb-v2 Creating p2p data store at directory /tmp/c4f2767af25d72b3/p2p with map size 134217728 KB (LMDB v2)
15:19:15 [15:19:15.493]
INFO:
p2p:lmdb-v2 Starting data store with maxReaders 16
15:19:15 [15:19:15.495]
INFO:
p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/c4f2767af25d72b3/p2p-archive with map size 134217728 KB (LMDB v2)
15:19:15 [15:19:15.496]
INFO:
p2p-archive:lmdb-v2 Starting data store with maxReaders 16
15:19:15 [15:19:15.498]
INFO:
p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/c4f2767af25d72b3/p2p-peers with map size 134217728 KB (LMDB v2)
15:19:15 [15:19:15.498]
INFO:
p2p-peer:lmdb-v2 Starting data store with maxReaders 16
15:19:15 [15:19:15.499]
INFO:
p2p:tx_pool Setting maximum tx mempool size
{"maxTxPoolSize":100000000}
15:19:15 [15:19:15.500]
VERBOSE:
p2p P2P is disabled. Using dummy P2P service
15:19:15 [15:19:15.502]
VERBOSE:
world-state:block_stream Starting L2 block stream
{"proven":false,"pollIntervalMS":100}
15:19:15 [15:19:15.503]
INFO:
world_state Started world state synchronizer from block 1
15:19:15 [15:19:15.505]
VERBOSE:
world-state:block_stream Starting sync from block number 0
15:19:15 [15:19:15.507]
INFO:
p2p Starting p2p client from block 0 with empty mempools
15:19:15 [15:19:15.507]
VERBOSE:
p2p:l2-block-stream Starting L2 block stream
{"batchSize":20,"pollIntervalMS":100,"startingBlock":0}
15:19:15 [15:19:15.508]
INFO:
slasher Slasher client initialized
15:19:15 [15:19:15.508]
INFO:
slasher Starting Slasher client...
15:19:15 [15:19:15.536]
VERBOSE:
validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
15:19:15 [15:19:15.537]
VERBOSE:
node All Aztec Node subsystems synced
15:19:15 [15:19:15.543]
INFO:
sequencer-client Deploying forwarder contract
15:19:15 [15:19:15.548]
VERBOSE:
p2p:l2-block-stream Starting sync from block number 0
15:19:15 [15:19:15.556]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:15 [15:19:15.563]
VERBOSE:
sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918
{"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"}
15:19:15 [15:19:15.563]
VERBOSE:
sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918.
15:19:15 [15:19:15.568]
INFO:
sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266
15:19:15 [15:19:15.580]
INFO:
validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
15:19:15 [15:19:15.580]
VERBOSE:
validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0
15:19:15 [15:19:15.580]
INFO:
sequencer Sequencer config set
{"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/4af658e9/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false}
15:19:15 [15:19:15.768]
VERBOSE:
sequencer Sequencer timetable updated
{"enforceTimeTable":false}
15:19:15 [15:19:15.769]
INFO:
sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
15:19:15 [15:19:15.770]
INFO:
node Aztec Node version: 0.87.8
15:19:15 [15:19:15.771]
INFO:
node Aztec Node started on chain 0x7a69
{"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","feeAssetHandlerAddress":"0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6","stakingAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788"}
15:19:15 [15:19:15.772]
VERBOSE:
e2e:e2e_max_block_number Creating a pxe...
15:19:15 [15:19:15.775]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:15 [15:19:15.783]
INFO:
pxe:data:lmdb Creating pxe_data data store at directory /tmp/e6604c965bf96861/pxe_data with map size 134217728 KB (LMDB v2)
15:19:15 [15:19:15.793]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x2d9dc9327195ff13ffbbe2a17347dd0774d123f15512ab4bd62670a10a56aed0","blockNumber":1,"slot":2}
15:19:15 [15:19:15.794]
INFO:
pxe:data:lmdb Starting data store with maxReaders 16
15:19:15 [15:19:15.989]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:16 [15:19:16.017]
VERBOSE:
pxe:service Registered protocol contracts in pxe
{"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"}
15:19:16 [15:19:16.018]
INFO:
pxe:service Started PXE connected to chain 31337 version 149236662
15:19:16 [15:19:16.065]
INFO:
pxe:service Registered account 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64
15:19:16 [15:19:16.119]
WARN:
pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata
15:19:16 [15:19:16.119]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64
15:19:16 [15:19:16.231]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:16 [15:19:16.233]
INFO:
pxe:service Added contract SchnorrAccount at 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a
15:19:16 [15:19:16.270]
VERBOSE:
pxe:block_stream Starting sync from block number 0
15:19:16 [15:19:16.287]
VERBOSE:
simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"}
15:19:16 [15:19:16.371]
VERBOSE:
simulator:private_execution Executing private function ContractClassRegisterer:register
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"}
15:19:16 [15:19:16.802]
VERBOSE:
simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c
15:19:16 [15:19:16.875]
VERBOSE:
simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..."
15:19:16 [15:19:16.931]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x2d9dc9327195ff13ffbbe2a17347dd0774d123f15512ab4bd62670a10a56aed0","blockNumber":1,"slot":2}
15:19:16 [15:19:16.933]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:17 [15:19:17.017]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:constructor
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:17 [15:19:17.056]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:17 [15:19:17.057]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:17 [15:19:17.092]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:17 [15:19:17.095]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:17 [15:19:17.141]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:17 [15:19:17.247]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:17 [15:19:17.283]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:17 [15:19:17.283]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:17 [15:19:17.311]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:17 [15:19:17.314]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:17 [15:19:17.327]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:17 [15:19:17.350]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:17 [15:19:17.652]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:17 [15:19:17.661]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x2d9dc9327195ff13ffbbe2a17347dd0774d123f15512ab4bd62670a10a56aed0","blockNumber":1,"slot":2}
15:19:17 [15:19:17.878]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:18 [15:19:18.427]
INFO:
pxe:service Proving completed in 2164.196710000001ms
{"timings":{"total":2164.196710000001,"sync":7.3843440000000555,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":116.2901809999994,"oracles":{"callPrivateFunction":{"times":[601.9773050000003,251.03533999999945,156.67588700000033]}}},{"functionName":"private_kernel_init","time":95.44354299999941},{"functionName":"ContractClassRegisterer:register","time":588.2195960000008,"oracles":{"loadCapsule":{"times":[17.343657999999778]},"notifyCreatedNullifier":{"times":[0.5554000000001906]},"debugLog":{"times":[0.7095090000002529]},"notifyCreatedContractClassLog":{"times":[7.785323000000062]}}},{"functionName":"private_kernel_inner","time":123.39447000000018},{"functionName":"SchnorrAccount:constructor","time":229.0047820000009,"oracles":{"getContractInstance":{"times":[1.2026089999999385]},"debugLog":{"times":[0.487622000000556,0.42649400000027526]},"fetchTaggedLogs":{"times":[36.82741200000055]},"loadCapsule":{"times":[0.32121099999949365,0.20754499999929976,0.2325380000002042]},"notifyCreatedNullifier":{"times":[0.1883689999995113,0.2379609999998138]},"notifyCreatedNote":{"times":[0.3353350000006685]},"getRandomField":{"times":[0.20137000000067928,0.16500300000006973,0.25592499999947904,0.10971400000016729,0.08228300000064337,0.09366399999998976,0.06616099999973812,0.07502599999952508,0.06345400000009249,0.1028729999998177,0.05862799999977142]},"getIndexedTaggingSecretAsSender":{"times":[28.944892000000436]},"incrementAppTaggingSecretIndexAsSender":{"times":[25.054646000000503]}}},{"functionName":"private_kernel_inner","time":115.1416590000008},{"functionName":"SchnorrAccount:entrypoint","time":134.94209799999953,"oracles":{"debugLog":{"times":[0.45534200000020064,0.3775789999999688,0.5020080000003873]},"fetchTaggedLogs":{"times":[29.616377999999713]},"loadCapsule":{"times":[0.19799199999943085,0.20305300000018178,0.23348799999985204]},"getNotes":{"times":[1.3879210000004605]},"getAuthWitness":{"times":[0.34129899999970803]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.7161200000000463]}}},{"functionName":"private_kernel_inner","time":112.67879300000095},{"functionName":"private_kernel_reset","time":164.6993939999993},{"functionName":"private_kernel_tail","time":69.83229199999914}],"unaccounted":407.1655580000006}}
15:19:18 [15:19:18.443]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:18 [15:19:18.511]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x2d9dc9327195ff13ffbbe2a17347dd0774d123f15512ab4bd62670a10a56aed0","blockNumber":1,"slot":2}
15:19:18 [15:19:18.572]
VERBOSE:
p2p:tx_pool Adding tx 0x2238ecf454db9b65553fe23ebadd7d46a4ebf569de074349d156af5ffe13af7e to pool
{"eventName":"tx-added-to-pool","txHash":"0x2238ecf454db9b65553fe23ebadd7d46a4ebf569de074349d156af5ffe13af7e","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"}
15:19:18 [15:19:18.588]
INFO:
node Received tx 0x2238ecf454db9b65553fe23ebadd7d46a4ebf569de074349d156af5ffe13af7e
{"txHash":"0x2238ecf454db9b65553fe23ebadd7d46a4ebf569de074349d156af5ffe13af7e"}
15:19:18 [15:19:18.589]
INFO:
pxe:service Sent transaction 0x2238ecf454db9b65553fe23ebadd7d46a4ebf569de074349d156af5ffe13af7e
15:19:18 [15:19:18.651]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:18 [15:19:18.854]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:19 [15:19:19.024]
VERBOSE:
sequencer Building block 1 for slot 2
{"slot":2,"blockNumber":1,"msgCount":0}
15:19:19 [15:19:19.036]
VERBOSE:
sequencer Processing pending txs
{"slot":2,"slotStart":"2025-06-13T15:23:38.000Z","now":"2025-06-13T15:23:17.891Z"}
15:19:19 [15:19:19.097]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:19 [15:19:19.270]
VERBOSE:
simulator:public-processor Processed tx 0x2238ecf454db9b65553fe23ebadd7d46a4ebf569de074349d156af5ffe13af7e with no public calls in 157.7334719999999ms
{"txHash":"0x2238ecf454db9b65553fe23ebadd7d46a4ebf569de074349d156af5ffe13af7e","txFee":6794052480,"revertCode":0,"gasUsed":{"totalGas":{"daGas":16896,"l2Gas":20448},"billedGas":{"daGas":16896,"l2Gas":20448},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":3,"noteHashCount":1,"contractClassLogCount":1,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":157.7334719999999}
15:19:19 [15:19:19.271]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.23439457700000094s
{"duration":0.23439457700000094,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344}
15:19:19 [15:19:19.385]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:19 [15:19:19.399]
INFO:
sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x0f753262f1b5807ca954c28eeefcd2bccf507e07b03f34c0d6e44410d3126ae0","globalVariables":{"chainId":31337,"version":149236662,"blockNumber":1,"slotNumber":2,"timestamp":1749828218,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x2238ecf454db9b65553fe23ebadd7d46a4ebf569de074349d156af5ffe13af7e"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":374.935782999999,"publicProcessDuration":235.11365699999988,"rollupCircuitsDuration":363.5674530000015,"txCount":1,"blockNumber":1,"blockTimestamp":1749828218,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
15:19:19 [15:19:19.402]
VERBOSE:
sequencer Attesting committee is empty
15:19:19 [15:19:19.590]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:19 [15:19:19.759]
VERBOSE:
ethereum:tx_delayer Sent tx immediately 0x381d128563c8707a56e091028fe0ca0a0d5c2f9e10a41bca7338ebb256662639
15:19:19 [15:19:19.759]
VERBOSE:
sequencer-client Sent L1 transaction 0x381d128563c8707a56e091028fe0ca0a0d5c2f9e10a41bca7338ebb256662639
{"gasLimit":514585,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"}
15:19:19 [15:19:19.769]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
15:19:19 [15:19:19.770]
VERBOSE:
sequencer:publisher Published L2 block to L1 rollup contract
{"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x381d128563c8707a56e091028fe0ca0a0d5c2f9e10a41bca7338ebb256662639","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":1,"blockTimestamp":1749828218,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
15:19:19 [15:19:19.770]
VERBOSE:
node:blob-sink:client Sending 1 blobs to blob sink
15:19:19 [15:19:19.784]
INFO:
blob-sink:server Received blob sidecar for block 0xbf48b9fdb37a17fd7ee3fd92ddaa13ce490b09584ea0148f0ee063c590dce911
15:19:19 [15:19:19.789]
INFO:
blob-sink:server Blob sidecar stored successfully for block 0xbf48b9fdb37a17fd7ee3fd92ddaa13ce490b09584ea0148f0ee063c590dce911
15:19:19 [15:19:19.792]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:19 [15:19:19.797]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on
http://127.0.0.1:8545
15:19:19 [15:19:19.798]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1
15:19:19 [15:19:19.800]
INFO:
aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1.
15:19:19 [15:19:19.814]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749828254] on
http://127.0.0.1:8545
15:19:19 [15:19:19.815]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
15:19:19 [15:19:19.817]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749828254
15:19:19 [15:19:19.817]
WARN:
foundation:test-date-provider Time set to 2025-06-13T15:24:14.000Z
{"offset":294183,"timeMs":1749828254000}
15:19:19 [15:19:19.817]
INFO:
aztecjs:utils:watcher Slot 2 was filled, jumped to next slot
15:19:19 [15:19:19.996]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:20 [15:19:20.234]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:20 [15:19:20.252]
VERBOSE:
archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a
15:19:20 [15:19:20.261]
INFO:
archiver Downloaded L2 block 1
{"blockHash":"0x0f753262f1b5807ca954c28eeefcd2bccf507e07b03f34c0d6e44410d3126ae0","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":149236662,"blockNumber":1,"slotNumber":2,"timestamp":1749828218,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x1ef241497a4272f2f2d143e8a1b972f0f19e2895cd09ad535f1eb5bac8ab29cf","archiveNextLeafIndex":2}
15:19:20 [15:19:20.266]
INFO:
archiver Updated proven chain to block 1
{"provenBlockNumber":1}
15:19:20 [15:19:20.658]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:20 [15:19:20.661]
INFO:
world_state World state updated with L2 block 1
{"eventName":"l2-block-handled","duration":314.8919999999998,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749828218,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
15:19:20 [15:19:20.662]
WARN:
pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata
15:19:20 [15:19:20.664]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f
15:19:20 [15:19:20.880]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:20 [15:19:20.901]
VERBOSE:
p2p Synched to latest block 1
15:19:21 [15:19:21.125]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:21 [15:19:21.135]
INFO:
pxe:service Added contract Test at 0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f
15:19:21 [15:19:21.154]
VERBOSE:
pxe:service Updated pxe last block to 1
{"blockHash":{},"archive":"0x1ef241497a4272f2f2d143e8a1b972f0f19e2895cd09ad535f1eb5bac8ab29cf","header":{"contentCommitment":{"blobsHash":"0x00fd125731421daa220874e077149c8c9190e190b586ca7ace04d934ffbd5ce5","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749828218,"version":149236662},"lastArchive":"0x2d9dc9327195ff13ffbbe2a17347dd0774d123f15512ab4bd62670a10a56aed0","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x073acebbec7db0c6d7952e31d5f7b378d403e5dfcdd1a94d716d565f365ccc81","nullifierTree":"0x2b9b1ec0e80bdebc2b7b57c489ce0890cde34b8b7a022f897c01a2be0ce03d39","publicDataTree":"0x09c59b2b92adf423f5cdeea5448e9bf9ce0f619e119d9013362202a8a6ed008f"},"totalFees":6794052480,"totalManaUsed":20448}}
15:19:21 [15:19:21.238]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:21 [15:19:21.274]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:21 [15:19:21.274]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:21 [15:19:21.346]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:21 [15:19:21.384]
VERBOSE:
sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1)
{"chainTipArchive":"0x1ef241497a4272f2f2d143e8a1b972f0f19e2895cd09ad535f1eb5bac8ab29cf","blockNumber":2,"slot":3}
15:19:21 [15:19:21.390]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:21 [15:19:21.395]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x094b8c6a3fa03f37b11a60b104d1e7c2e3a80cc95a38af0aee9024457bd98184
15:19:21 [15:19:21.405]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
15:19:21 [15:19:21.406]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
15:19:21 [15:19:21.415]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
15:19:21 [15:19:21.416]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
15:19:21 [15:19:21.428]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2708e07a356af9d7c800639c0593724582be7ebce284aefaad10b41a4254d3c5","nullifier":"0x0ca8b54ce68ff1790d43364ae78c3499b8881cf0c2a9eb47dc4edb221a8bc8c8"}
15:19:21 [15:19:21.431]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:21 [15:19:21.443]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:21 [15:19:21.468]
VERBOSE:
simulator:private_execution Executing private function ContractClassRegisterer:register
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"}
15:19:21 [15:19:21.791]
VERBOSE:
simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e
15:19:21 [15:19:21.861]
VERBOSE:
simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..."
15:19:21 [15:19:21.913]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:21 [15:19:21.971]
VERBOSE:
simulator:private_execution Executing private function ContractInstanceDeployer:deploy
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"}
15:19:21 [15:19:21.987]
VERBOSE:
sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1)
{"chainTipArchive":"0x1ef241497a4272f2f2d143e8a1b972f0f19e2895cd09ad535f1eb5bac8ab29cf","blockNumber":2,"slot":3}
15:19:22 [15:19:22.006]
VERBOSE:
simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"}
15:19:22 [15:19:22.017]
VERBOSE:
simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f,0x0000000000000000000000000000000000000000000000000000000000000001,0x1924221f50b0f289a7b115fc55c1f2ca9760c8c3f3b4f13f483f5ea052e6da20,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64
15:19:22 [15:19:22.138]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:22 [15:19:22.462]
VERBOSE:
simulator:private_execution Executing private function Test:initialize
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:22 [15:19:22.478]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:22 [15:19:22.479]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:22 [15:19:22.588]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:22 [15:19:22.626]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:22 [15:19:22.631]
VERBOSE:
sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1)
{"chainTipArchive":"0x1ef241497a4272f2f2d143e8a1b972f0f19e2895cd09ad535f1eb5bac8ab29cf","blockNumber":2,"slot":3}
15:19:22 [15:19:22.634]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:22 [15:19:22.794]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:23 [15:19:23.132]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:23 [15:19:23.333]
VERBOSE:
sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1)
{"chainTipArchive":"0x1ef241497a4272f2f2d143e8a1b972f0f19e2895cd09ad535f1eb5bac8ab29cf","blockNumber":2,"slot":3}
15:19:23 [15:19:23.434]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:23 [15:19:23.874]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:24 [15:19:24.250]
INFO:
pxe:service Proving completed in 3102.505647ms
{"timings":{"total":3102.505647,"sync":8.661846999999398,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":403.0041540000002,"oracles":{"debugLog":{"times":[0.6523250000009284,0.38915199999973993,0.42958700000053796,0.5017050000005838,0.4134379999995872,0.3369219999985944,0.3470840000009048,0.44826199999988603]},"fetchTaggedLogs":{"times":[118.59665800000039]},"loadCapsule":{"times":[0.19380799999999,0.40033500000026834,0.4061999999994441,0.2834310000016558,0.35551999999916006,0.2016829999993206]},"getSharedSecret":{"times":[3.061234999999215]},"aes128Decrypt":{"times":[0.92463300000054,0.9487719999997353]},"getPublicKeysAndPartialAddress":{"times":[2.9664379999994708]},"getKeyValidationRequest":{"times":[4.0177179999991495]},"deliverNote":{"times":[12.502313999999387]},"deleteCapsule":{"times":[1.2880110000005516]},"storeCapsule":{"times":[0.5146269999986544]},"getNotes":{"times":[1.3294239999995625]},"getAuthWitness":{"times":[0.19700000000011642]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05573700000059034]},"callPrivateFunction":{"times":[465.15505400000075,113.63806499999919,614.3849769999997]}}},{"functionName":"private_kernel_init","time":61.15082900000016},{"functionName":"ContractClassRegisterer:register","time":455.4843299999993,"oracles":{"loadCapsule":{"times":[3.6427530000000843]},"notifyCreatedNullifier":{"times":[0.27923200000077486]},"debugLog":{"times":[0.440349000000424]},"notifyCreatedContractClassLog":{"times":[6.8499400000000605]}}},{"functionName":"private_kernel_inner","time":110.13252300000022},{"functionName":"ContractInstanceDeployer:deploy","time":68.53089100000034,"oracles":{"storeInExecutionCache":{"times":[0.18677100000059]},"callPrivateFunction":{"times":[37.07113600000048]},"notifyCreatedNullifier":{"times":[0.27620000000024447]},"debugLog":{"times":[0.470500000001266]}}},{"functionName":"private_kernel_inner","time":108.08171800000127},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":28.219796999999744,"oracles":{}},{"functionName":"private_kernel_inner","time":107.3081629999997},{"functionName":"Test:initialize","time":497.1581530000003,"oracles":{"getContractInstance":{"times":[2.0609670000012557]},"debugLog":{"times":[0.45630399999936344,0.3799250000010943]},"fetchTaggedLogs":{"times":[154.60384499999964]},"loadCapsule":{"times":[0.21489300000030198,0.15884600000026694,0.1925040000005538]},"notifyCreatedNullifier":{"times":[0.21100099999966915]}}},{"functionName":"private_kernel_inner","time":109.62611799999831},{"functionName":"private_kernel_reset","time":165.79452199999832},{"functionName":"private_kernel_tail","time":70.15384599999925}],"unaccounted":909.1987560000034}}
15:19:24 [15:19:24.264]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:24 [15:19:24.275]
VERBOSE:
sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1)
{"chainTipArchive":"0x1ef241497a4272f2f2d143e8a1b972f0f19e2895cd09ad535f1eb5bac8ab29cf","blockNumber":2,"slot":3}
15:19:24 [15:19:24.409]
VERBOSE:
p2p:tx_pool Adding tx 0x0e7a55fd30ed40045c63b8208092197f4e593fa4e53cba3ba44db419070ae80a to pool
{"eventName":"tx-added-to-pool","txHash":"0x0e7a55fd30ed40045c63b8208092197f4e593fa4e53cba3ba44db419070ae80a","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"}
15:19:24 [15:19:24.425]
INFO:
node Received tx 0x0e7a55fd30ed40045c63b8208092197f4e593fa4e53cba3ba44db419070ae80a
{"txHash":"0x0e7a55fd30ed40045c63b8208092197f4e593fa4e53cba3ba44db419070ae80a"}
15:19:24 [15:19:24.425]
INFO:
pxe:service Sent transaction 0x0e7a55fd30ed40045c63b8208092197f4e593fa4e53cba3ba44db419070ae80a
15:19:24 [15:19:24.473]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:24 [15:19:24.676]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:24 [15:19:24.804]
VERBOSE:
sequencer Building block 2 for slot 3
{"slot":3,"blockNumber":2,"msgCount":0}
15:19:24 [15:19:24.818]
VERBOSE:
sequencer Processing pending txs
{"slot":3,"slotStart":"2025-06-13T15:24:14.000Z","now":"2025-06-13T15:24:19.001Z"}
15:19:24 [15:19:24.901]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:25 [15:19:25.088]
VERBOSE:
simulator:public-processor Processed tx 0x0e7a55fd30ed40045c63b8208092197f4e593fa4e53cba3ba44db419070ae80a with no public calls in 180.2263729999995ms
{"txHash":"0x0e7a55fd30ed40045c63b8208092197f4e593fa4e53cba3ba44db419070ae80a","txFee":7272506880,"revertCode":0,"gasUsed":{"totalGas":{"daGas":310272,"l2Gas":21888},"billedGas":{"daGas":310272,"l2Gas":21888},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":4,"noteHashCount":0,"contractClassLogCount":1,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":180.2263729999995}
15:19:25 [15:19:25.090]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.271397649999999s
{"duration":0.271397649999999,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648}
15:19:25 [15:19:25.130]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:25 [15:19:25.251]
INFO:
sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x1cff24554881a32eac20519935ec5679c144dec3c0cc2a9fae323cfe2ba9a9da","globalVariables":{"chainId":31337,"version":149236662,"blockNumber":2,"slotNumber":3,"timestamp":1749828254,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x0e7a55fd30ed40045c63b8208092197f4e593fa4e53cba3ba44db419070ae80a"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":447.78494300000057,"publicProcessDuration":271.81781000000046,"rollupCircuitsDuration":433.9675439999992,"txCount":1,"blockNumber":2,"blockTimestamp":1749828254,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583}
15:19:25 [15:19:25.253]
VERBOSE:
sequencer Attesting committee is empty
15:19:25 [15:19:25.361]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:25 [15:19:25.562]
VERBOSE:
ethereum:tx_delayer Sent tx immediately 0x06a347cab1658ad955d8f71dbcfa9f87b767c7e53d4c81b46a1ef9e54b0dab30
15:19:25 [15:19:25.562]
VERBOSE:
sequencer-client Sent L1 transaction 0x06a347cab1658ad955d8f71dbcfa9f87b767c7e53d4c81b46a1ef9e54b0dab30
{"gasLimit":470269,"maxFeePerGas":"1.303611208","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"}
15:19:25 [15:19:25.564]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:25 [15:19:25.575]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on
http://127.0.0.1:8545
15:19:25 [15:19:25.577]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2
15:19:25 [15:19:25.579]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
15:19:25 [15:19:25.579]
VERBOSE:
sequencer:publisher Published L2 block to L1 rollup contract
{"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x06a347cab1658ad955d8f71dbcfa9f87b767c7e53d4c81b46a1ef9e54b0dab30","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":2,"blockTimestamp":1749828254,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583}
15:19:25 [15:19:25.580]
VERBOSE:
node:blob-sink:client Sending 1 blobs to blob sink
15:19:25 [15:19:25.582]
INFO:
aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2.
15:19:25 [15:19:25.585]
INFO:
blob-sink:server Received blob sidecar for block 0x5ac3aa71652b482faa53b93ba2f78e7f92b2bc86803f30aba07826bd87aaf8a9
15:19:25 [15:19:25.589]
INFO:
blob-sink:server Blob sidecar stored successfully for block 0x5ac3aa71652b482faa53b93ba2f78e7f92b2bc86803f30aba07826bd87aaf8a9
15:19:25 [15:19:25.658]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749828290] on
http://127.0.0.1:8545
15:19:25 [15:19:25.659]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
15:19:25 [15:19:25.661]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749828290
15:19:25 [15:19:25.661]
WARN:
foundation:test-date-provider Time set to 2025-06-13T15:24:50.000Z
{"offset":324339,"timeMs":1749828290000}
15:19:25 [15:19:25.661]
INFO:
aztecjs:utils:watcher Slot 3 was filled, jumped to next slot
15:19:25 [15:19:25.771]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:26 [15:19:26.056]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:26 [15:19:26.073]
VERBOSE:
archiver:block-helper Store contract instance at 0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f
15:19:26 [15:19:26.094]
VERBOSE:
archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f
15:19:26 [15:19:26.131]
INFO:
archiver Downloaded L2 block 2
{"blockHash":"0x1cff24554881a32eac20519935ec5679c144dec3c0cc2a9fae323cfe2ba9a9da","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":149236662,"blockNumber":2,"slotNumber":3,"timestamp":1749828254,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x230777a4a0053088000430755071ea6c1e363f5de04d745a627075d5779c2f72","archiveNextLeafIndex":3}
15:19:26 [15:19:26.145]
INFO:
archiver Updated proven chain to block 2
{"provenBlockNumber":2}
15:19:26 [15:19:26.174]
INFO:
world_state World state updated with L2 block 2
{"eventName":"l2-block-handled","duration":6.483080999998492,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749828254,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583}
15:19:26 [15:19:26.253]
VERBOSE:
p2p Synched to latest block 2
15:19:26 [15:19:26.261]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:26 [15:19:26.581]
INFO:
aztecjs:deploy_sent_tx Contract 0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f successfully deployed.
15:19:26 [15:19:26.583]
INFO:
e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with no enqueued public calls sets the max block number
15:19:26 [15:19:26.589]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:26 [15:19:26.619]
VERBOSE:
pxe:service Updated pxe last block to 2
{"blockHash":{},"archive":"0x230777a4a0053088000430755071ea6c1e363f5de04d745a627075d5779c2f72","header":{"contentCommitment":{"blobsHash":"0x0088b125633c85f4a7e70d5744fb1b6ec0537c6220bacdb1ac5eb002c1cb2cc3","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749828254,"version":149236662},"lastArchive":"0x1ef241497a4272f2f2d143e8a1b972f0f19e2895cd09ad535f1eb5bac8ab29cf","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x073acebbec7db0c6d7952e31d5f7b378d403e5dfcdd1a94d716d565f365ccc81","nullifierTree":"0x1b4f6a3e58dbd68fe962140abda4e24e651f56143204a0f0d56f0db158758b76","publicDataTree":"0x00fc4b7e61cada7fb4160b1a5f4b838240fb277e5007f7cb9dfbbccce6530f62"},"totalFees":7272506880,"totalManaUsed":21888}}
15:19:26 [15:19:26.684]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:26 [15:19:26.719]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:26 [15:19:26.719]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:26 [15:19:26.767]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x230777a4a0053088000430755071ea6c1e363f5de04d745a627075d5779c2f72","blockNumber":3,"slot":4}
15:19:26 [15:19:26.780]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:26 [15:19:26.784]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x094b8c6a3fa03f37b11a60b104d1e7c2e3a80cc95a38af0aee9024457bd98184
15:19:26 [15:19:26.793]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
15:19:26 [15:19:26.793]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
15:19:26 [15:19:26.796]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:26 [15:19:26.803]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
15:19:26 [15:19:26.804]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
15:19:26 [15:19:26.808]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2708e07a356af9d7c800639c0593724582be7ebce284aefaad10b41a4254d3c5","nullifier":"0x0ca8b54ce68ff1790d43364ae78c3499b8881cf0c2a9eb47dc4edb221a8bc8c8"}
15:19:26 [15:19:26.812]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:26 [15:19:26.824]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:27 [15:19:27.113]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:27 [15:19:27.219]
VERBOSE:
simulator:private_execution Executing private function Test:set_tx_max_block_number
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:27 [15:19:27.231]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:27 [15:19:27.231]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:27 [15:19:27.366]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:27 [15:19:27.372]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x230777a4a0053088000430755071ea6c1e363f5de04d745a627075d5779c2f72","blockNumber":3,"slot":4}
15:19:27 [15:19:27.374]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:27 [15:19:27.453]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:27 [15:19:27.908]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:28 [15:19:28.058]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x230777a4a0053088000430755071ea6c1e363f5de04d745a627075d5779c2f72","blockNumber":3,"slot":4}
15:19:28 [15:19:28.293]
INFO:
pxe:service Proving completed in 1681.9762389999996ms
{"timings":{"total":1681.9762389999996,"sync":9.233209999998508,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":301.0996660000019,"oracles":{"debugLog":{"times":[0.43031300000075134,0.3699909999995725,0.4088719999999739,0.5142720000003465,0.40979000000152155,0.3224550000013551,0.32759400000213645,0.4434739999996964]},"fetchTaggedLogs":{"times":[63.347310000001016]},"loadCapsule":{"times":[0.17593699999997625,0.1352889999980107,0.3261469999997644,0.184041000000434,1.342808999997942,0.22983000000022002]},"getSharedSecret":{"times":[2.5557019999978365]},"aes128Decrypt":{"times":[0.2214720000010857,0.8298119999999471]},"getPublicKeysAndPartialAddress":{"times":[5.779420000002574]},"getKeyValidationRequest":{"times":[1.47599899999841]},"deliverNote":{"times":[4.528272999999899]},"deleteCapsule":{"times":[1.366807999998855]},"storeCapsule":{"times":[0.27413399999932153]},"getNotes":{"times":[1.8000839999986056]},"getAuthWitness":{"times":[0.1821399999971618]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05333000000246102]},"callPrivateFunction":{"times":[554.1311320000023]}}},{"functionName":"private_kernel_init","time":60.086035000000265},{"functionName":"Test:set_tx_max_block_number","time":443.1363119999987,"oracles":{"debugLog":{"times":[0.4311039999993227,0.32940099999905215]},"fetchTaggedLogs":{"times":[142.28455800000302]},"loadCapsule":{"times":[0.1813880000008794,0.1663139999982377,0.17625699999916833]}}},{"functionName":"private_kernel_inner","time":109.05934999999954},{"functionName":"private_kernel_reset","time":175.9247209999994},{"functionName":"private_kernel_tail","time":70.24053400000048}],"unaccounted":513.1964110000008}}
15:19:28 [15:19:28.297]
INFO:
e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with no enqueued public calls does not invalidate the transaction
15:19:28 [15:19:28.298]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:28 [15:19:28.379]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:28 [15:19:28.414]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:28 [15:19:28.414]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:28 [15:19:28.448]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:28 [15:19:28.452]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x094b8c6a3fa03f37b11a60b104d1e7c2e3a80cc95a38af0aee9024457bd98184
15:19:28 [15:19:28.461]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
15:19:28 [15:19:28.462]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
15:19:28 [15:19:28.466]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
15:19:28 [15:19:28.467]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
15:19:28 [15:19:28.469]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2708e07a356af9d7c800639c0593724582be7ebce284aefaad10b41a4254d3c5","nullifier":"0x0ca8b54ce68ff1790d43364ae78c3499b8881cf0c2a9eb47dc4edb221a8bc8c8"}
15:19:28 [15:19:28.471]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:28 [15:19:28.482]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:28 [15:19:28.583]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:28 [15:19:28.836]
VERBOSE:
simulator:private_execution Executing private function Test:set_tx_max_block_number
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:28 [15:19:28.852]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:28 [15:19:28.852]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:29 [15:19:29.002]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:29 [15:19:29.017]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x230777a4a0053088000430755071ea6c1e363f5de04d745a627075d5779c2f72","blockNumber":3,"slot":4}
15:19:29 [15:19:29.024]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:29 [15:19:29.027]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:29 [15:19:29.276]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:29 [15:19:29.559]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:29 [15:19:29.712]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x230777a4a0053088000430755071ea6c1e363f5de04d745a627075d5779c2f72","blockNumber":3,"slot":4}
15:19:29 [15:19:29.945]
INFO:
pxe:service Proving completed in 1628.455371ms
{"timings":{"total":1628.455371,"sync":2.5838309999999183,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":305.21241100000043,"oracles":{"debugLog":{"times":[0.4324540000015986,0.3759360000003653,0.3679069999998319,0.45967899999959627,0.3897209999995539,0.3442380000014964,0.293217000002187,0.40647399999943445]},"fetchTaggedLogs":{"times":[36.64247500000056]},"loadCapsule":{"times":[0.15842300000076648,0.14171899999928428,0.3231530000011844,0.17778999999791267,0.16188899999906425,0.18669100000261096]},"getSharedSecret":{"times":[2.50564200000008]},"aes128Decrypt":{"times":[0.21721099999922444,0.8129149999986112]},"getPublicKeysAndPartialAddress":{"times":[1.1589110000022629]},"getKeyValidationRequest":{"times":[1.2288450000014564]},"deliverNote":{"times":[2.5094499999977415]},"deleteCapsule":{"times":[0.20095199999923352]},"storeCapsule":{"times":[0.1886129999984405]},"getNotes":{"times":[1.0593809999991208]},"getAuthWitness":{"times":[0.1893530000015744]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.0552609999976994]},"callPrivateFunction":{"times":[549.0859469999996]}}},{"functionName":"private_kernel_init","time":55.78287000000273},{"functionName":"Test:set_tx_max_block_number","time":395.00960099999793,"oracles":{"debugLog":{"times":[0.4250109999993583,0.3630410000005213]},"fetchTaggedLogs":{"times":[173.58965799999714]},"loadCapsule":{"times":[0.1681959999987157,0.16300200000114273,0.22094400000059977]}}},{"functionName":"private_kernel_inner","time":109.41096000000107},{"functionName":"private_kernel_reset","time":175.13791700000002},{"functionName":"private_kernel_tail","time":68.44951600000059}],"unaccounted":516.8682649999973}}
15:19:29 [15:19:29.960]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:29 [15:19:29.979]
VERBOSE:
p2p:tx_pool Adding tx 0x0f10e477b6aa10ce8219d8e8c77e73f8e59e59c2295946718b1d51ad12c9b152 to pool
{"eventName":"tx-added-to-pool","txHash":"0x0f10e477b6aa10ce8219d8e8c77e73f8e59e59c2295946718b1d51ad12c9b152","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"}
15:19:29 [15:19:29.983]
INFO:
node Received tx 0x0f10e477b6aa10ce8219d8e8c77e73f8e59e59c2295946718b1d51ad12c9b152
{"txHash":"0x0f10e477b6aa10ce8219d8e8c77e73f8e59e59c2295946718b1d51ad12c9b152"}
15:19:29 [15:19:29.983]
INFO:
pxe:service Sent transaction 0x0f10e477b6aa10ce8219d8e8c77e73f8e59e59c2295946718b1d51ad12c9b152
15:19:30 [15:19:30.163]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:30 [15:19:30.241]
VERBOSE:
sequencer Building block 3 for slot 4
{"slot":4,"blockNumber":3,"msgCount":0}
15:19:30 [15:19:30.254]
VERBOSE:
sequencer Processing pending txs
{"slot":4,"slotStart":"2025-06-13T15:24:50.000Z","now":"2025-06-13T15:24:54.593Z"}
15:19:30 [15:19:30.273]
VERBOSE:
simulator:public-processor Processed tx 0x0f10e477b6aa10ce8219d8e8c77e73f8e59e59c2295946718b1d51ad12c9b152 with no public calls in 12.212886999997863ms
{"txHash":"0x0f10e477b6aa10ce8219d8e8c77e73f8e59e59c2295946718b1d51ad12c9b152","txFee":1945714560,"revertCode":0,"gasUsed":{"totalGas":{"daGas":1024,"l2Gas":5856},"billedGas":{"daGas":1024,"l2Gas":5856},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":1,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":12.212886999997863}
15:19:30 [15:19:30.274]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.018893505000000005s
{"duration":0.018893505000000005,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1024,"l2Gas":5856},"totalSizeInBytes":256}
15:19:30 [15:19:30.379]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:30 [15:19:30.393]
INFO:
sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x0c0d42a6de027eed4abe7dbd2737c87c2c321371775d415027e6ad3165083745","globalVariables":{"chainId":31337,"version":149236662,"blockNumber":3,"slotNumber":4,"timestamp":1749828290,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x0f10e477b6aa10ce8219d8e8c77e73f8e59e59c2295946718b1d51ad12c9b152"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":151.9364800000003,"publicProcessDuration":19.310395000000426,"rollupCircuitsDuration":139.18312700000024,"txCount":1,"blockNumber":3,"blockTimestamp":1749828290,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
15:19:30 [15:19:30.396]
VERBOSE:
sequencer Attesting committee is empty
15:19:30 [15:19:30.646]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:30 [15:19:30.653]
VERBOSE:
ethereum:tx_delayer Sent tx immediately 0x30092fcbe512a9f4b66b5753d838edd422f3a7706fdbbf592e5a185ebe6154bb
15:19:30 [15:19:30.653]
VERBOSE:
sequencer-client Sent L1 transaction 0x30092fcbe512a9f4b66b5753d838edd422f3a7706fdbbf592e5a185ebe6154bb
{"gasLimit":470265,"maxFeePerGas":"1.279583398","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"}
15:19:30 [15:19:30.662]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
15:19:30 [15:19:30.662]
VERBOSE:
sequencer:publisher Published L2 block to L1 rollup contract
{"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x30092fcbe512a9f4b66b5753d838edd422f3a7706fdbbf592e5a185ebe6154bb","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":3,"blockTimestamp":1749828290,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
15:19:30 [15:19:30.662]
VERBOSE:
node:blob-sink:client Sending 1 blobs to blob sink
15:19:30 [15:19:30.667]
INFO:
blob-sink:server Received blob sidecar for block 0x7e5c65c927d97a0be31cc02a2a819ffc304aed33c18b7c4c7780e4da51d1a5e3
15:19:30 [15:19:30.670]
INFO:
blob-sink:server Blob sidecar stored successfully for block 0x7e5c65c927d97a0be31cc02a2a819ffc304aed33c18b7c4c7780e4da51d1a5e3
15:19:30 [15:19:30.687]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749828326] on
http://127.0.0.1:8545
15:19:30 [15:19:30.688]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
15:19:30 [15:19:30.690]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749828326
15:19:30 [15:19:30.690]
WARN:
foundation:test-date-provider Time set to 2025-06-13T15:25:26.000Z
{"offset":355310,"timeMs":1749828326000}
15:19:30 [15:19:30.690]
INFO:
aztecjs:utils:watcher Slot 4 was filled, jumped to next slot
15:19:30 [15:19:30.864]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:30 [15:19:30.879]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on
http://127.0.0.1:8545
15:19:30 [15:19:30.882]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3
15:19:30 [15:19:30.884]
INFO:
archiver Downloaded L2 block 3
{"blockHash":"0x0c0d42a6de027eed4abe7dbd2737c87c2c321371775d415027e6ad3165083745","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":149236662,"blockNumber":3,"slotNumber":4,"timestamp":1749828290,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2bba142454d4456c21acdbe343a7c6266443724ba48bb029f998fec8fc81c86c","archiveNextLeafIndex":4}
15:19:30 [15:19:30.886]
INFO:
aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3.
15:19:30 [15:19:30.986]
INFO:
e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with an enqueued public call sets the max block number
15:19:30 [15:19:30.988]
INFO:
world_state World state updated with L2 block 3
{"eventName":"l2-block-handled","duration":7.316972000000533,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749828290,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
15:19:31 [15:19:31.010]
VERBOSE:
pxe:service Updated pxe last block to 3
{"blockHash":{},"archive":"0x2bba142454d4456c21acdbe343a7c6266443724ba48bb029f998fec8fc81c86c","header":{"contentCommitment":{"blobsHash":"0x00f4b36f5dcdaf2ceb34da75ec4f25991b3252776662160c1622754345ac08b1","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749828290,"version":149236662},"lastArchive":"0x230777a4a0053088000430755071ea6c1e363f5de04d745a627075d5779c2f72","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x073acebbec7db0c6d7952e31d5f7b378d403e5dfcdd1a94d716d565f365ccc81","nullifierTree":"0x09160166797b5998833cf5b5e2ba25a77b503c83a7cac6c2551501c2a443b169","publicDataTree":"0x0daa6010bec274aac5b1224e33f679f463a19625a6072bdd7481a002c88bbddd"},"totalFees":1945714560,"totalManaUsed":5856}}
15:19:31 [15:19:31.011]
VERBOSE:
p2p Synched to latest block 3
15:19:31 [15:19:31.071]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:31 [15:19:31.106]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:31 [15:19:31.106]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:31 [15:19:31.109]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:31 [15:19:31.148]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:31 [15:19:31.152]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x094b8c6a3fa03f37b11a60b104d1e7c2e3a80cc95a38af0aee9024457bd98184
15:19:31 [15:19:31.161]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
15:19:31 [15:19:31.162]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
15:19:31 [15:19:31.170]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
15:19:31 [15:19:31.171]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
15:19:31 [15:19:31.181]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2708e07a356af9d7c800639c0593724582be7ebce284aefaad10b41a4254d3c5","nullifier":"0x0ca8b54ce68ff1790d43364ae78c3499b8881cf0c2a9eb47dc4edb221a8bc8c8"}
15:19:31 [15:19:31.182]
VERBOSE:
sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1)
{"chainTipArchive":"0x2bba142454d4456c21acdbe343a7c6266443724ba48bb029f998fec8fc81c86c","blockNumber":4,"slot":5}
15:19:31 [15:19:31.183]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:31 [15:19:31.194]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:31 [15:19:31.394]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:31 [15:19:31.496]
VERBOSE:
simulator:private_execution Executing private function Test:set_tx_max_block_number
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:31 [15:19:31.507]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:31 [15:19:31.508]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:31 [15:19:31.702]
INFO:
archiver Updated proven chain to block 3
{"provenBlockNumber":3}
15:19:31 [15:19:31.711]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:31 [15:19:31.718]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:31 [15:19:31.721]
VERBOSE:
sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1)
{"chainTipArchive":"0x2bba142454d4456c21acdbe343a7c6266443724ba48bb029f998fec8fc81c86c","blockNumber":4,"slot":5}
15:19:31 [15:19:31.727]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:31 [15:19:31.983]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:32 [15:19:32.264]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:32 [15:19:32.409]
VERBOSE:
sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1)
{"chainTipArchive":"0x2bba142454d4456c21acdbe343a7c6266443724ba48bb029f998fec8fc81c86c","blockNumber":4,"slot":5}
15:19:32 [15:19:32.658]
INFO:
pxe:service Proving completed in 1658.105190000002ms
{"timings":{"total":1658.105190000002,"sync":11.5690820000018,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":277.0304290000022,"oracles":{"debugLog":{"times":[0.43972800000119605,0.3756730000022799,0.3668160000015632,0.47666799999933573,0.37605400000029476,0.31978499999968335,0.2981870000003255,0.42767299999832176]},"fetchTaggedLogs":{"times":[44.61695200000031]},"loadCapsule":{"times":[0.16580900000190013,0.13997999999992317,0.29858900000181166,0.2539600000018254,0.15956700000242563,0.1886899999990419]},"getSharedSecret":{"times":[2.4077279999983148]},"aes128Decrypt":{"times":[0.2538409999979194,0.8611689999997907]},"getPublicKeysAndPartialAddress":{"times":[1.1233180000017455]},"getKeyValidationRequest":{"times":[5.457174000002851]},"deliverNote":{"times":[9.978520000000572]},"deleteCapsule":{"times":[0.2168649999985064]},"storeCapsule":{"times":[0.5940600000030827]},"getNotes":{"times":[1.23765899999853]},"getAuthWitness":{"times":[0.18711999999868567]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05407500000001164]},"callPrivateFunction":{"times":[537.6833129999977]}}},{"functionName":"private_kernel_init","time":62.08078100000057},{"functionName":"Test:set_tx_max_block_number","time":432.11103999999614,"oracles":{"debugLog":{"times":[0.4310399999994843,0.36058200000115903]},"fetchTaggedLogs":{"times":[218.24907200000234]},"loadCapsule":{"times":[0.2037960000016028,0.1543500000007043,0.17861400000037975]},"storeInExecutionCache":{"times":[0.041078999998717336]},"notifyEnqueuedPublicFunctionCall":{"times":[0.18771899999774178]}}},{"functionName":"private_kernel_inner","time":109.98478999999861},{"functionName":"private_kernel_reset","time":173.07929700000022},{"functionName":"private_kernel_tail","time":83.39227000000028}],"unaccounted":508.85750100000223}}
15:19:32 [15:19:32.660]
INFO:
e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers higher than the mined one with an enqueued public call does not invalidate the transaction
15:19:32 [15:19:32.664]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:32 [15:19:32.746]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:32 [15:19:32.781]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:32 [15:19:32.782]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:32 [15:19:32.816]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:32 [15:19:32.820]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x094b8c6a3fa03f37b11a60b104d1e7c2e3a80cc95a38af0aee9024457bd98184
15:19:32 [15:19:32.830]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
15:19:32 [15:19:32.830]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
15:19:32 [15:19:32.835]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
15:19:32 [15:19:32.836]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
15:19:32 [15:19:32.838]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2708e07a356af9d7c800639c0593724582be7ebce284aefaad10b41a4254d3c5","nullifier":"0x0ca8b54ce68ff1790d43364ae78c3499b8881cf0c2a9eb47dc4edb221a8bc8c8"}
15:19:32 [15:19:32.840]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:32 [15:19:32.851]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:32 [15:19:32.954]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:33 [15:19:33.163]
VERBOSE:
simulator:private_execution Executing private function Test:set_tx_max_block_number
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:33 [15:19:33.176]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:33 [15:19:33.177]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:33 [15:19:33.288]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:33 [15:19:33.299]
VERBOSE:
sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1)
{"chainTipArchive":"0x2bba142454d4456c21acdbe343a7c6266443724ba48bb029f998fec8fc81c86c","blockNumber":4,"slot":5}
15:19:33 [15:19:33.304]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:33 [15:19:33.306]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:33 [15:19:33.514]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:33 [15:19:33.896]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:34 [15:19:34.037]
VERBOSE:
sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1)
{"chainTipArchive":"0x2bba142454d4456c21acdbe343a7c6266443724ba48bb029f998fec8fc81c86c","blockNumber":4,"slot":5}
15:19:34 [15:19:34.286]
INFO:
pxe:service Proving completed in 1603.489109000002ms
{"timings":{"total":1603.489109000002,"sync":3.0427620000009483,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":263.1852390000022,"oracles":{"debugLog":{"times":[0.46695099999851664,0.3932349999995495,0.39731100000062725,0.5186290000019653,0.40679399999862653,0.32310299999880954,0.3328220000003057,0.42682000000058906]},"fetchTaggedLogs":{"times":[37.156291000002966]},"loadCapsule":{"times":[0.15897099999710917,0.14860199999748147,0.305382999998983,0.1945290000003297,0.15023199999995995,0.184875999999349]},"getSharedSecret":{"times":[2.519111999998131]},"aes128Decrypt":{"times":[0.3664069999977073,1.0452660000009928]},"getPublicKeysAndPartialAddress":{"times":[1.2193829999996524]},"getKeyValidationRequest":{"times":[1.2949609999996028]},"deliverNote":{"times":[2.4948909999984608]},"deleteCapsule":{"times":[0.22212600000057137]},"storeCapsule":{"times":[0.2140560000007099]},"getNotes":{"times":[1.1111500000006345]},"getAuthWitness":{"times":[0.2109810000001744]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05997700000079931]},"callPrivateFunction":{"times":[459.26970000000074]}}},{"functionName":"private_kernel_init","time":56.17717400000038},{"functionName":"Test:set_tx_max_block_number","time":350.27592000000004,"oracles":{"debugLog":{"times":[0.541892999997799,0.3612590000011551]},"fetchTaggedLogs":{"times":[128.51103099999818]},"loadCapsule":{"times":[0.17587899999853107,0.13886900000215974,0.17743899999913992]},"storeInExecutionCache":{"times":[0.0398929999973916]},"notifyEnqueuedPublicFunctionCall":{"times":[0.05321599999660975]}}},{"functionName":"private_kernel_inner","time":108.61934399999882},{"functionName":"private_kernel_reset","time":171.8255199999985},{"functionName":"private_kernel_tail","time":84.3605349999998}],"unaccounted":566.0026150000012}}
15:19:34 [15:19:34.313]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:34 [15:19:34.334]
VERBOSE:
p2p:tx_pool Adding tx 0x2b5f94ab81c1f51f4078444234d13e358bafae6c115b6b40f258d7cf41163eef to pool
{"eventName":"tx-added-to-pool","txHash":"0x2b5f94ab81c1f51f4078444234d13e358bafae6c115b6b40f258d7cf41163eef","noteHashCount":0,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226281,"feePaymentMethod":"fee_juice"}
15:19:34 [15:19:34.341]
INFO:
node Received tx 0x2b5f94ab81c1f51f4078444234d13e358bafae6c115b6b40f258d7cf41163eef
{"txHash":"0x2b5f94ab81c1f51f4078444234d13e358bafae6c115b6b40f258d7cf41163eef"}
15:19:34 [15:19:34.341]
INFO:
pxe:service Sent transaction 0x2b5f94ab81c1f51f4078444234d13e358bafae6c115b6b40f258d7cf41163eef
15:19:34 [15:19:34.517]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:34 [15:19:34.557]
VERBOSE:
sequencer Building block 4 for slot 5
{"slot":5,"blockNumber":4,"msgCount":0}
15:19:34 [15:19:34.569]
VERBOSE:
sequencer Processing pending txs
{"slot":5,"slotStart":"2025-06-13T15:25:26.000Z","now":"2025-06-13T15:25:29.879Z"}
15:19:34 [15:19:34.605]
VERBOSE:
simulator:public_tx_simulator Simulation of enqueued public call 0x5189a417 completed successfully.
{"eventName":"avm-simulation","appCircuitName":"0x5189a417","duration":17.75495999999839}
15:19:34 [15:19:34.625]
VERBOSE:
simulator:public-processor Processed tx 0x2b5f94ab81c1f51f4078444234d13e358bafae6c115b6b40f258d7cf41163eef with 1 public calls in 45.89308700000038ms
{"txHash":"0x2b5f94ab81c1f51f4078444234d13e358bafae6c115b6b40f258d7cf41163eef","txFee":1487487300,"revertCode":0,"gasUsed":{"totalGas":{"daGas":1024,"l2Gas":29339},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":3483},"billedGas":{"daGas":1024,"l2Gas":29339}},"publicDataWriteCount":1,"nullifierCount":1,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":45.89308700000038}
15:19:34 [15:19:34.626]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.05654819700000007s
{"duration":0.05654819700000007,"rate":61593.47573893462,"totalPublicGas":{"daGas":0,"l2Gas":3483},"totalBlockGas":{"daGas":1024,"l2Gas":29339},"totalSizeInBytes":256}
15:19:34 [15:19:34.730]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:34 [15:19:34.743]
INFO:
sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 18691.66160950694 mana/s
{"blockHash":"0x3042e214d4f111721816bdc738a80b43a0f33223935c47403113181c93cddb05","globalVariables":{"chainId":31337,"version":149236662,"blockNumber":4,"slotNumber":5,"timestamp":1749828326,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x2b5f94ab81c1f51f4078444234d13e358bafae6c115b6b40f258d7cf41163eef"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":185.73575999999957,"publicProcessDuration":56.887706000001344,"rollupCircuitsDuration":175.38003899999967,"txCount":1,"blockNumber":4,"blockTimestamp":1749828326,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
15:19:34 [15:19:34.747]
VERBOSE:
sequencer Attesting committee is empty
15:19:34 [15:19:34.999]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:35 [15:19:35.004]
VERBOSE:
ethereum:tx_delayer Sent tx immediately 0xb7a5362b33256abc6e8a7f49f827bf5c954cf1ee28cdd2386398ca42948bd2b7
15:19:35 [15:19:35.005]
VERBOSE:
sequencer-client Sent L1 transaction 0xb7a5362b33256abc6e8a7f49f827bf5c954cf1ee28cdd2386398ca42948bd2b7
{"gasLimit":470310,"maxFeePerGas":"1.261127735","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"}
15:19:35 [15:19:35.012]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
15:19:35 [15:19:35.013]
VERBOSE:
sequencer:publisher Published L2 block to L1 rollup contract
{"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xb7a5362b33256abc6e8a7f49f827bf5c954cf1ee28cdd2386398ca42948bd2b7","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":4,"blockTimestamp":1749828326,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
15:19:35 [15:19:35.013]
VERBOSE:
node:blob-sink:client Sending 1 blobs to blob sink
15:19:35 [15:19:35.017]
INFO:
blob-sink:server Received blob sidecar for block 0x0234fb3ed640a8967293f9ed9de130d46343b93c90e07a387d031d5164e6fd92
15:19:35 [15:19:35.020]
INFO:
blob-sink:server Blob sidecar stored successfully for block 0x0234fb3ed640a8967293f9ed9de130d46343b93c90e07a387d031d5164e6fd92
15:19:35 [15:19:35.038]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749828362] on
http://127.0.0.1:8545
15:19:35 [15:19:35.039]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
15:19:35 [15:19:35.041]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1749828362
15:19:35 [15:19:35.041]
WARN:
foundation:test-date-provider Time set to 2025-06-13T15:26:02.000Z
{"offset":386959,"timeMs":1749828362000}
15:19:35 [15:19:35.041]
INFO:
aztecjs:utils:watcher Slot 5 was filled, jumped to next slot
15:19:35 [15:19:35.184]
INFO:
archiver Downloaded L2 block 4
{"blockHash":"0x3042e214d4f111721816bdc738a80b43a0f33223935c47403113181c93cddb05","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":149236662,"blockNumber":4,"slotNumber":5,"timestamp":1749828326,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","archiveNextLeafIndex":5}
15:19:35 [15:19:35.202]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:35 [15:19:35.205]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on
http://127.0.0.1:8545
15:19:35 [15:19:35.207]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4
15:19:35 [15:19:35.208]
INFO:
aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4.
15:19:35 [15:19:35.290]
INFO:
world_state World state updated with L2 block 4
{"eventName":"l2-block-handled","duration":6.095600999997259,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749828326,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0}
15:19:35 [15:19:35.295]
VERBOSE:
p2p Synched to latest block 4
15:19:35 [15:19:35.344]
INFO:
e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with no enqueued public calls sets the max block number
15:19:35 [15:19:35.359]
VERBOSE:
pxe:service Updated pxe last block to 4
{"blockHash":{},"archive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","header":{"contentCommitment":{"blobsHash":"0x00fc81759dbfc79a728ff30ce3dc2781e30d90f3f6953664c41dfb3d39823486","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749828326,"version":149236662},"lastArchive":"0x2bba142454d4456c21acdbe343a7c6266443724ba48bb029f998fec8fc81c86c","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x073acebbec7db0c6d7952e31d5f7b378d403e5dfcdd1a94d716d565f365ccc81","nullifierTree":"0x11b1b4e3d671bc7ce722339a22a9529590c15c1f2032b95cf638ec451c8e96a7","publicDataTree":"0x207774fd855f12989f0c4349ff73cbedbd571c7cd0b443085eeb585b50f60ca8"},"totalFees":1487487300,"totalManaUsed":29339}}
15:19:35 [15:19:35.420]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:35 [15:19:35.455]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:35 [15:19:35.456]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:35 [15:19:35.458]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:35 [15:19:35.498]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:35 [15:19:35.502]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x094b8c6a3fa03f37b11a60b104d1e7c2e3a80cc95a38af0aee9024457bd98184
15:19:35 [15:19:35.511]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
15:19:35 [15:19:35.512]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
15:19:35 [15:19:35.520]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
15:19:35 [15:19:35.521]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
15:19:35 [15:19:35.530]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2708e07a356af9d7c800639c0593724582be7ebce284aefaad10b41a4254d3c5","nullifier":"0x0ca8b54ce68ff1790d43364ae78c3499b8881cf0c2a9eb47dc4edb221a8bc8c8"}
15:19:35 [15:19:35.532]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:35 [15:19:35.533]
VERBOSE:
sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1)
{"chainTipArchive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","blockNumber":5,"slot":6}
15:19:35 [15:19:35.544]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:35 [15:19:35.746]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:35 [15:19:35.848]
VERBOSE:
simulator:private_execution Executing private function Test:set_tx_max_block_number
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:35 [15:19:35.859]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:35 [15:19:35.860]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:35 [15:19:35.974]
INFO:
archiver Updated proven chain to block 4
{"provenBlockNumber":4}
15:19:35 [15:19:35.981]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:35 [15:19:35.983]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:36 [15:19:36.138]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:36 [15:19:36.323]
VERBOSE:
sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1)
{"chainTipArchive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","blockNumber":5,"slot":6}
15:19:36 [15:19:36.602]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:36 [15:19:36.966]
INFO:
pxe:service Proving completed in 1611.281962000001ms
{"timings":{"total":1611.281962000001,"sync":5.759931000000506,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":277.28099400000065,"oracles":{"debugLog":{"times":[0.45892499999899883,0.399438000000373,0.3875239999979385,0.4573240000026999,0.39074800000162213,0.3193520000022545,0.3127180000010412,0.4169270000020333]},"fetchTaggedLogs":{"times":[44.69317000000228]},"loadCapsule":{"times":[0.33489100000224425,0.1652799999974377,0.3511949999992794,0.22857099999964703,0.19666700000016135,0.4796840000017255]},"getSharedSecret":{"times":[2.5640959999982442]},"aes128Decrypt":{"times":[0.22413400000004913,0.8414730000004056]},"getPublicKeysAndPartialAddress":{"times":[1.219001000001299]},"getKeyValidationRequest":{"times":[4.9086889999998675]},"deliverNote":{"times":[9.342823999999382]},"deleteCapsule":{"times":[0.7668750000011642]},"storeCapsule":{"times":[0.21141400000124122]},"getNotes":{"times":[1.0251969999990251]},"getAuthWitness":{"times":[0.17323899999973946]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05361300000004121]},"callPrivateFunction":{"times":[443.38102699999945]}}},{"functionName":"private_kernel_init","time":56.35860400000092},{"functionName":"Test:set_tx_max_block_number","time":337.60062599999947,"oracles":{"debugLog":{"times":[0.40481200000067474,0.4000160000032338]},"fetchTaggedLogs":{"times":[122.66875400000208]},"loadCapsule":{"times":[0.2004319999978179,0.17405499999949825,0.20193900000231224]}}},{"functionName":"private_kernel_inner","time":109.21958200000154},{"functionName":"private_kernel_reset","time":170.41280499999993},{"functionName":"private_kernel_tail","time":67.87694499999998}],"unaccounted":586.7724749999979}}
15:19:36 [15:19:36.968]
INFO:
e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with no enqueued public calls invalidates the transaction
15:19:36 [15:19:36.970]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:37 [15:19:37.001]
VERBOSE:
sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1)
{"chainTipArchive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","blockNumber":5,"slot":6}
15:19:37 [15:19:37.061]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:37 [15:19:37.096]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:37 [15:19:37.096]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:37 [15:19:37.131]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:37 [15:19:37.135]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x094b8c6a3fa03f37b11a60b104d1e7c2e3a80cc95a38af0aee9024457bd98184
15:19:37 [15:19:37.144]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
15:19:37 [15:19:37.145]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
15:19:37 [15:19:37.149]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
15:19:37 [15:19:37.150]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
15:19:37 [15:19:37.152]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2708e07a356af9d7c800639c0593724582be7ebce284aefaad10b41a4254d3c5","nullifier":"0x0ca8b54ce68ff1790d43364ae78c3499b8881cf0c2a9eb47dc4edb221a8bc8c8"}
15:19:37 [15:19:37.154]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:37 [15:19:37.165]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:37 [15:19:37.264]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:37 [15:19:37.468]
VERBOSE:
simulator:private_execution Executing private function Test:set_tx_max_block_number
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:37 [15:19:37.482]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:37 [15:19:37.482]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:37 [15:19:37.588]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:37 [15:19:37.611]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:37 [15:19:37.612]
VERBOSE:
sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1)
{"chainTipArchive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","blockNumber":5,"slot":6}
15:19:37 [15:19:37.614]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:37 [15:19:37.857]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:38 [15:19:38.229]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:38 [15:19:38.372]
VERBOSE:
sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1)
{"chainTipArchive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","blockNumber":5,"slot":6}
15:19:38 [15:19:38.605]
INFO:
pxe:service Proving completed in 1607.081721999999ms
{"timings":{"total":1607.081721999999,"sync":4.088637999997445,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":255.85296200000084,"oracles":{"debugLog":{"times":[0.4336440000006405,0.39010500000222237,0.38122800000201096,0.4787860000033106,0.42096399999718415,0.32684599999993225,0.3365320000011707,0.40607100000124774]},"fetchTaggedLogs":{"times":[37.27771399999983]},"loadCapsule":{"times":[0.18241299999863259,0.17480300000170246,0.3173319999987143,0.2023509999999078,0.15366999999969266,0.1844920000003185]},"getSharedSecret":{"times":[2.470603999998275]},"aes128Decrypt":{"times":[0.19579199999861885,0.8197860000000219]},"getPublicKeysAndPartialAddress":{"times":[1.14846899999975]},"getKeyValidationRequest":{"times":[1.1718030000010913]},"deliverNote":{"times":[2.4297499999993306]},"deleteCapsule":{"times":[0.21243399999730173]},"storeCapsule":{"times":[0.19576100000267616]},"getNotes":{"times":[1.0649310000007972]},"getAuthWitness":{"times":[0.20151299999997718]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05549299999984214]},"callPrivateFunction":{"times":[452.6764609999991]}}},{"functionName":"private_kernel_init","time":55.52406300000075},{"functionName":"Test:set_tx_max_block_number","time":348.7004620000007,"oracles":{"debugLog":{"times":[0.412082000002556,0.29492799999934505]},"fetchTaggedLogs":{"times":[130.98661300000094]},"loadCapsule":{"times":[0.17771200000061071,0.15707400000246707,0.18390899999940302]}}},{"functionName":"private_kernel_inner","time":109.20284000000174},{"functionName":"private_kernel_reset","time":172.63560199999847},{"functionName":"private_kernel_tail","time":67.87340400000176}],"unaccounted":593.2037509999973}}
15:19:38 [15:19:38.619]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:38 [15:19:38.634]
VERBOSE:
p2p:tx_validator:tx_metadata Rejecting tx 0x1afda82b2f1532e28e59b68ef4f107be90487458ad193784aa039a5818e8bb4a for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005.
15:19:38 [15:19:38.637]
WARN:
node Received invalid tx 0x1afda82b2f1532e28e59b68ef4f107be90487458ad193784aa039a5818e8bb4a: Invalid max block number
{"txHash":"0x1afda82b2f1532e28e59b68ef4f107be90487458ad193784aa039a5818e8bb4a"}
15:19:38 [15:19:38.641]
ERROR:
pxe:service Error: Error: Invalid tx: Invalid max block number
15:19:38 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19)
15:19:38 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33
15:19:38 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17)
15:19:38 [15:19:38.643]
INFO:
e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with an enqueued public call sets the max block number
15:19:38 [15:19:38.715]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:38 [15:19:38.750]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:38 [15:19:38.751]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:38 [15:19:38.784]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:38 [15:19:38.788]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x094b8c6a3fa03f37b11a60b104d1e7c2e3a80cc95a38af0aee9024457bd98184
15:19:38 [15:19:38.796]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
15:19:38 [15:19:38.797]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
15:19:38 [15:19:38.801]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
15:19:38 [15:19:38.802]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
15:19:38 [15:19:38.804]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2708e07a356af9d7c800639c0593724582be7ebce284aefaad10b41a4254d3c5","nullifier":"0x0ca8b54ce68ff1790d43364ae78c3499b8881cf0c2a9eb47dc4edb221a8bc8c8"}
15:19:38 [15:19:38.805]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:38 [15:19:38.816]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:38 [15:19:38.919]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:39 [15:19:39.127]
VERBOSE:
simulator:private_execution Executing private function Test:set_tx_max_block_number
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:39 [15:19:39.140]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:39 [15:19:39.140]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:39 [15:19:39.251]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:39 [15:19:39.260]
VERBOSE:
sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1)
{"chainTipArchive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","blockNumber":5,"slot":6}
15:19:39 [15:19:39.265]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:39 [15:19:39.267]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:39 [15:19:39.510]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:39 [15:19:39.787]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:39 [15:19:39.926]
VERBOSE:
sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1)
{"chainTipArchive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","blockNumber":5,"slot":6}
15:19:40 [15:19:40.174]
INFO:
pxe:service Proving completed in 1520.466132999998ms
{"timings":{"total":1520.466132999998,"sync":2.5534179999995104,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":258.3148519999959,"oracles":{"debugLog":{"times":[0.457961999996769,0.33445600000050035,0.3639019999973243,0.45251899999857415,0.3938820000003034,0.29617900000084774,0.2903039999982866,0.45304699999906006]},"fetchTaggedLogs":{"times":[35.67054599999756]},"loadCapsule":{"times":[0.17004200000155834,0.13654699999824516,0.33838899999682326,0.16786000000138301,0.13693899999998393,0.16342100000110804]},"getSharedSecret":{"times":[2.37711599999966]},"aes128Decrypt":{"times":[0.17545199999949546,0.773750000000291]},"getPublicKeysAndPartialAddress":{"times":[1.1015770000012708]},"getKeyValidationRequest":{"times":[1.1167420000019774]},"deliverNote":{"times":[2.130151000001206]},"deleteCapsule":{"times":[0.19270699999833596]},"storeCapsule":{"times":[0.23748800000248593]},"getNotes":{"times":[1.4318639999983134]},"getAuthWitness":{"times":[0.21514999999999418]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.2188800000003539]},"callPrivateFunction":{"times":[454.7056119999979]}}},{"functionName":"private_kernel_init","time":55.66274500000145},{"functionName":"Test:set_tx_max_block_number","time":346.4041130000005,"oracles":{"debugLog":{"times":[0.4168709999976272,0.30833500000153435]},"fetchTaggedLogs":{"times":[126.24229600000035]},"loadCapsule":{"times":[0.14941499999986263,0.16517700000258628,0.16361099999994622]},"storeInExecutionCache":{"times":[0.045581999998830725]},"notifyEnqueuedPublicFunctionCall":{"times":[0.04738599999836879]}}},{"functionName":"private_kernel_inner","time":108.98290999999881},{"functionName":"private_kernel_reset","time":173.94113599999764},{"functionName":"private_kernel_tail","time":82.25002699999823}],"unaccounted":492.35693200000605}}
15:19:40 [15:19:40.177]
INFO:
e2e:e2e_max_block_number Running test: e2e_max_block_number when requesting max block numbers lower than the mined one with an enqueued public call invalidates the transaction
15:19:40 [15:19:40.179]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:40 [15:19:40.258]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:40 [15:19:40.293]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:40 [15:19:40.293]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:40 [15:19:40.337]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64"}
15:19:40 [15:19:40.342]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x094b8c6a3fa03f37b11a60b104d1e7c2e3a80cc95a38af0aee9024457bd98184
15:19:40 [15:19:40.353]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
15:19:40 [15:19:40.354]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
15:19:40 [15:19:40.360]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
15:19:40 [15:19:40.360]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
15:19:40 [15:19:40.376]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2708e07a356af9d7c800639c0593724582be7ebce284aefaad10b41a4254d3c5","nullifier":"0x0ca8b54ce68ff1790d43364ae78c3499b8881cf0c2a9eb47dc4edb221a8bc8c8"}
15:19:40 [15:19:40.378]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:40 [15:19:40.390]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x0f6ae99242e8594e60e7f8c3a3502caf1d238c2de78e489b6378b5c9bc463c64 as fee payer
15:19:40 [15:19:40.393]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:40 [15:19:40.661]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:40 [15:19:40.759]
VERBOSE:
simulator:private_execution Executing private function Test:set_tx_max_block_number
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:40 [15:19:40.771]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:19:40 [15:19:40.771]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:40 [15:19:40.781]
VERBOSE:
sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1)
{"chainTipArchive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","blockNumber":5,"slot":6}
15:19:40 [15:19:40.891]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x0c819d9babd00e81f5dba00c352fb0c0bb472f63dfde0cb38b766d38d09d5d7f"}
15:19:40 [15:19:40.893]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:19:41 [15:19:41.038]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:41 [15:19:41.413]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:41 [15:19:41.554]
VERBOSE:
sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1)
{"chainTipArchive":"0x2a0c6eb0eecb59e332d9d6f07970a85b9312ae0a49d89c0da149bbfec78f6e4e","blockNumber":5,"slot":6}
15:19:41 [15:19:41.804]
INFO:
pxe:service Proving completed in 1606.7696240000005ms
{"timings":{"total":1606.7696240000005,"sync":2.6001199999991513,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":284.81478199999765,"oracles":{"debugLog":{"times":[0.41224099999817554,0.4179519999997865,0.4221419999994396,0.5184709999994084,0.400510999999824,0.36762500000259024,0.3323290000007546,0.4536179999995511]},"fetchTaggedLogs":{"times":[47.2170729999998]},"loadCapsule":{"times":[0.18654599999717902,0.1930049999973562,0.3922870000023977,0.3032579999999143,0.1750899999969988,0.17514399999708985]},"getSharedSecret":{"times":[3.8580819999988307]},"aes128Decrypt":{"times":[0.40917199999967124,1.0381449999986216]},"getPublicKeysAndPartialAddress":{"times":[1.9049430000013672]},"getKeyValidationRequest":{"times":[1.550146000001405]},"deliverNote":{"times":[16.429368000000977]},"deleteCapsule":{"times":[0.4126429999996617]},"storeCapsule":{"times":[0.32256799999959185]},"getNotes":{"times":[1.408824000001914]},"getAuthWitness":{"times":[0.2079139999987092]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.08005899999989197]},"callPrivateFunction":{"times":[507.1316619999998]}}},{"functionName":"private_kernel_init","time":56.220907999999326},{"functionName":"Test:set_tx_max_block_number","time":402.2970310000019,"oracles":{"debugLog":{"times":[0.40297999999893364,0.373740999999427]},"fetchTaggedLogs":{"times":[121.17624100000103]},"loadCapsule":{"times":[0.1653839999999036,0.14255300000149873,0.17153699999835226]},"storeInExecutionCache":{"times":[0.04441700000097626]},"notifyEnqueuedPublicFunctionCall":{"times":[0.04876499999954831]}}},{"functionName":"private_kernel_inner","time":108.87316999999894},{"functionName":"private_kernel_reset","time":173.4986119999994},{"functionName":"private_kernel_tail","time":84.70980000000054}],"unaccounted":493.7552010000036}}
15:19:41 [15:19:41.831]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:19:41 [15:19:41.845]
VERBOSE:
p2p:tx_validator:tx_metadata Rejecting tx 0x26941c5384b0d1cae91f95cee05169bd2d79370a838430f06d3d30a4fa8f772e for low max block number. Tx max block number: 0x0000000000000000000000000000000000000000000000000000000000000004, current block number: 0x0000000000000000000000000000000000000000000000000000000000000005.
15:19:41 [15:19:41.848]
WARN:
node Received invalid tx 0x26941c5384b0d1cae91f95cee05169bd2d79370a838430f06d3d30a4fa8f772e: Invalid max block number
{"txHash":"0x26941c5384b0d1cae91f95cee05169bd2d79370a838430f06d3d30a4fa8f772e"}
15:19:41 [15:19:41.850]
ERROR:
pxe:service Error: Error: Invalid tx: Invalid max block number
15:19:41 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19)
15:19:41 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33
15:19:41 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17)
15:19:41 [15:19:41.852]
INFO:
node Stopping
15:19:41 [15:19:41.853]
INFO:
slasher Slasher client stopped.
15:19:41 [15:19:41.853]
INFO:
sequencer Stopped sequencer
15:19:41 [15:19:41.853]
INFO:
p2p P2P client stopped.
15:19:41 [15:19:41.855]
INFO:
world_state Stopped world state synchronizer
15:19:41 [15:19:41.855]
INFO:
archiver Stopped.
15:19:41 [15:19:41.855]
INFO:
node Stopped
15:19:41 [15:19:41.855]
VERBOSE:
e2e:e2e_max_block_number Cleaning up ACVM state
15:19:41 [15:19:41.855]
INFO:
e2e:e2e_max_block_number Cleaning up ACVM temp directory /tmp/4af658e9
15:19:41 [15:19:41.856]
VERBOSE:
e2e:e2e_max_block_number Cleaning up BB state
15:19:41 [15:19:41.864]
INFO:
blob-sink:server Stopping blob sink
15:19:41 [15:19:41.864]
INFO:
blob-sink:server Blob sink stopped
15:19:41 [15:19:41.864]
VERBOSE:
e2e:e2e_max_block_number Cleaning up data directory at /tmp/c4f2767af25d72b3
15:19:41
PASS src/e2e_max_block_number.test.ts
15:19:41 e2e_max_block_number
15:19:41 when requesting max block numbers higher than the mined one
15:19:41 with no enqueued public calls
15:19:41
✓ sets the max block number (1714 ms)
15:19:41
✓ does not invalidate the transaction (2688 ms)
15:19:41 with an enqueued public call
15:19:41
✓ sets the max block number (1673 ms)
15:19:41
✓ does not invalidate the transaction (2684 ms)
15:19:41 when requesting max block numbers lower than the mined one
15:19:41 with no enqueued public calls
15:19:41
✓ sets the max block number (1623 ms)
15:19:41
✓ invalidates the transaction (1674 ms)
15:19:41 with an enqueued public call
15:19:41
✓ sets the max block number (1534 ms)
15:19:41
✓ invalidates the transaction (1674 ms)
15:19:41
15:19:41
Test Suites: 1 passed, 1 total
15:19:41
Tests: 8 passed, 8 total
15:19:41
Snapshots: 0 total
15:19:41
Time: 31.807 s
15:19:41
Ran all test suites matching /src\/e2e_max_block_number.test.ts/i
.
15:19:41
Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?