Parent Log: http://ci.aztec-labs.com/08c315a53e9a1c87 Command: d2a1f586812a792c:ISOLATE=1:NAME=e2e_keys yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_keys.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/71fe6b444df8fafa5531226cf458dbd22d2f3ab7 Env: REF_NAME=gh-readonly-queue/master/pr-15117-c06f30929f18b85bcea93c0926a8d640a3819f00 CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Tue Jun 17 19:25:22 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15117_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c393731fea71056e_master 19:25:22 +++ id -u 19:25:22 +++ id -g 19:25:22 ++ docker run -d --name e2e_keys --net=none --cpuset-cpus=0-127 --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_keys.test.ts'\''' 19:25:22 + cid=5eee9fd008619f02eacde8426a6171b8e6ee1709f57a44b61d6e84162f51e369 19:25:22 + set +x 19:25:23 {"level":25,"time":1750188323336,"pid":12,"hostname":"5eee9fd00861","module":"logger","msg":"Logger initialized with level verbose"} 19:25:30 [19:25:30.553] INFO: e2e:e2e_keys Logging metrics to log/unknown.jsonl 19:25:30 [19:25:30.895] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-ZvluYj/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 19:25:30 [19:25:30.949] INFO: e2e:e2e_keys Calling anvil_setBlockTimestampInterval with params: [12] 19:25:30 [19:25:30.984] WARN: e2e:e2e_keys Set block interval to 12 19:25:30 [19:25:30.985] VERBOSE: e2e:e2e_keys Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 19:25:31 [19:25:31.046] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 19:25:31 [19:25:31.058] VERBOSE: e2e:e2e_keys Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 19:25:31 [19:25:31.085] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 19:25:31 [19:25:31.091] VERBOSE: e2e:e2e_keys Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 19:25:31 [19:25:31.113] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 19:25:31 [19:25:31.121] VERBOSE: e2e:e2e_keys Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 19:25:31 [19:25:31.159] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 19:25:31 [19:25:31.168] VERBOSE: e2e:e2e_keys Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 19:25:31 [19:25:31.203] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 19:25:31 [19:25:31.209] VERBOSE: e2e:e2e_keys Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 19:25:31 [19:25:31.225] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 19:25:31 [19:25:31.232] VERBOSE: e2e:e2e_keys Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 19:25:31 [19:25:31.233] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 19:25:31 [19:25:31.234] INFO: e2e:e2e_keys Waiting for 6 transactions to be mined... 19:25:31 [19:25:31.263] INFO: e2e:e2e_keys All transactions mined successfully 19:25:31 [19:25:31.282] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 19:25:31 [19:25:31.282] VERBOSE: e2e:e2e_keys Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 19:25:31 [19:25:31.300] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 19:25:31 [19:25:31.316] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 19:25:31 [19:25:31.326] VERBOSE: e2e:e2e_keys Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 19:25:31 [19:25:31.349] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 19:25:31 [19:25:31.350] VERBOSE: e2e:e2e_keys Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 19:25:31 [19:25:31.378] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 19:25:31 [19:25:31.384] VERBOSE: e2e:e2e_keys Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 19:25:31 [19:25:31.398] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 19:25:31 [19:25:31.398] VERBOSE: e2e:e2e_keys Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 19:25:31 [19:25:31.398] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 19:25:31 [19:25:31.399] INFO: e2e:e2e_keys Waiting for 8 transactions to be mined... 19:25:31 [19:25:31.420] INFO: e2e:e2e_keys All transactions mined successfully 19:25:31 [19:25:31.435] VERBOSE: e2e:e2e_keys Deployed shared contracts 19:25:31 [19:25:31.463] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 19:25:31 [19:25:31.464] VERBOSE: e2e:e2e_keys Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 19:25:31 [19:25:31.469] VERBOSE: e2e:e2e_keys Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 19:25:31 [19:25:31.498] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 19:25:31 [19:25:31.530] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 19:25:31 [19:25:31.538] VERBOSE: e2e:e2e_keys Awaiting for linked libraries to be deployed 19:25:31 [19:25:31.590] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xd932d58b0b89eb74900dca97e6f5c2a24aeab2b6c6cfe23a5e640d05e027afef {"gasLimit":10407394,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 19:25:31 [19:25:31.597] VERBOSE: e2e:e2e_keys Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016 {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 19:25:31 [19:25:31.598] INFO: e2e:e2e_keys Waiting for 9 transactions to be mined... 19:25:31 [19:25:31.630] INFO: e2e:e2e_keys All transactions mined successfully 19:25:31 [19:25:31.630] VERBOSE: e2e:e2e_keys All core contracts have been deployed 19:25:31 [19:25:31.654] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 19:25:31 [19:25:31.654] VERBOSE: e2e:e2e_keys Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 19:25:31 [19:25:31.675] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x3076e8ed3c707fd6d82a919b677cf7a63a9c4828413a41044e3272f6f71e410a {"gasLimit":716194,"maxFeePerGas":"26.850966158","maxPriorityFeePerGas":"26.623333274"} 19:25:31 [19:25:31.679] VERBOSE: e2e:e2e_keys Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 19:25:31 [19:25:31.706] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xcd2cca55b67399deb9b7db167c38dc1d22e6d4d796fedc3ba097570542992776 {"gasLimit":116953,"maxFeePerGas":"32.147280374","maxPriorityFeePerGas":"31.947999928"} 19:25:31 [19:25:31.706] VERBOSE: e2e:e2e_keys Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0xcd2cca55b67399deb9b7db167c38dc1d22e6d4d796fedc3ba097570542992776 19:25:31 [19:25:31.706] INFO: e2e:e2e_keys Waiting for 10 transactions to be mined... 19:25:31 [19:25:31.724] INFO: e2e:e2e_keys All transactions mined successfully 19:25:31 [19:25:31.742] VERBOSE: e2e:e2e_keys Rollup deployed 19:25:31 [19:25:31.742] VERBOSE: e2e:e2e_keys Waiting for rollup and slash factory to be deployed 19:25:31 [19:25:31.742] INFO: e2e:e2e_keys Waiting for 10 transactions to be mined... 19:25:31 [19:25:31.795] INFO: e2e:e2e_keys All transactions mined successfully 19:25:31 [19:25:31.796] VERBOSE: e2e:e2e_keys All transactions for L1 deployment have been mined 19:25:31 [19:25:31.839] INFO: e2e:e2e_keys 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"} 19:25:31 [19:25:31.843] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 19:25:31 [19:25:31.846] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:31 [19:25:31.847] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 19:25:31 [19:25:31.848] INFO: telemetry:client Using NoopTelemetryClient 19:25:31 [19:25:31.854] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 19:25:31 [19:25:31.855] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 19:25:31 [19:25:31.856] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/be673f9a6ae9f2c4/blob-sink with map size 134217728 KB (LMDB v2) 19:25:31 [19:25:31.858] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:31 [19:25:31.861] INFO: aztecjs:utils:watcher New L1 time: 1750188570 19:25:31 [19:25:31.861] WARN: foundation:test-date-provider Time set to 2025-06-17T19:29:30.000Z {"offset":238139,"timeMs":1750188570000} 19:25:31 [19:25:31.864] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 19:25:31 [19:25:31.873] INFO: blob-sink:server Server is running on http://localhost:37237 19:25:31 [19:25:31.873] VERBOSE: e2e:e2e_keys Creating and synching an aztec node... 19:25:31 [19:25:31.874] VERBOSE: e2e:e2e_keys Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/54832a63/acvm 19:25:31 [19:25:31.876] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:37237"} 19:25:31 [19:25:31.898] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 19:25:31 [19:25:31.899] INFO: archiver:lmdb Creating archiver data store at directory /tmp/be673f9a6ae9f2c4/archiver with map size 134217728 KB (LMDB v2) 19:25:31 [19:25:31.901] INFO: archiver:lmdb Starting data store with maxReaders 16 19:25:32 [19:25:32.140] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:32 [19:25:32.281] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:37237"} 19:25:32 [19:25:32.304] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:37237"} 19:25:32 [19:25:32.305] WARN: node:blob-sink:client No L1 consensus host urls configured 19:25:32 [19:25:32.305] WARN: node:blob-sink:client No archive client configured 19:25:32 [19:25:32.309] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x6e103bb09f29e21c9ba9c06d406eab395a59381ea0cabb3150eed74d0dcdc120 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x7df9c81d9f715afd3f90978779797754f408f51ba109b8d11f17eec805bf8115","l1BlockNumber":16}} 19:25:32 [19:25:32.327] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 19:25:32 [19:25:32.346] INFO: world-state:database Creating world state data store at directory /tmp/be673f9a6ae9f2c4/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 19:25:32 [19:25:32.378] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:32 [19:25:32.390] INFO: world_state Created world state synchroniser with block history of 64 19:25:32 [19:25:32.390] WARN: node Aztec node is accepting fake proofs 19:25:32 [19:25:32.403] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/be673f9a6ae9f2c4/p2p with map size 134217728 KB (LMDB v2) 19:25:32 [19:25:32.407] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 19:25:32 [19:25:32.408] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/be673f9a6ae9f2c4/p2p-archive with map size 134217728 KB (LMDB v2) 19:25:32 [19:25:32.409] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 19:25:32 [19:25:32.411] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/be673f9a6ae9f2c4/p2p-peers with map size 134217728 KB (LMDB v2) 19:25:32 [19:25:32.412] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 19:25:32 [19:25:32.413] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 19:25:32 [19:25:32.414] VERBOSE: p2p P2P is disabled. Using dummy P2P service 19:25:32 [19:25:32.417] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 19:25:32 [19:25:32.418] INFO: world_state Started world state synchronizer from block 1 19:25:32 [19:25:32.420] VERBOSE: world-state:block_stream Starting sync from block number 0 19:25:32 [19:25:32.422] INFO: p2p Starting p2p client from block 0 with empty mempools 19:25:32 [19:25:32.422] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 19:25:32 [19:25:32.423] INFO: slasher Slasher client initialized 19:25:32 [19:25:32.423] INFO: slasher Starting Slasher client... 19:25:32 [19:25:32.478] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 19:25:32 [19:25:32.480] VERBOSE: node All Aztec Node subsystems synced 19:25:32 [19:25:32.503] INFO: sequencer-client Deploying forwarder contract 19:25:32 [19:25:32.512] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 19:25:32 [19:25:32.533] VERBOSE: sequencer-client Sent L1 transaction 0xb1f3df2017e7ba07c13a74da934c53780ad6675aaaf877e947cca2001f9da968 {"gasLimit":436981,"maxFeePerGas":"1.353583762","maxPriorityFeePerGas":"1.2"} 19:25:32 [19:25:32.533] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xb1f3df2017e7ba07c13a74da934c53780ad6675aaaf877e947cca2001f9da968. 19:25:32 [19:25:32.541] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 19:25:32 [19:25:32.559] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 19:25:32 [19:25:32.559] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 19:25:32 [19:25:32.560] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/54832a63/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 19:25:32 [19:25:32.797] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 19:25:32 [19:25:32.799] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 19:25:32 [19:25:32.800] INFO: node Aztec Node version: 0.87.8 19:25:32 [19:25:32.800] 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"} 19:25:32 [19:25:32.801] VERBOSE: e2e:e2e_keys Creating a pxe... 19:25:32 [19:25:32.804] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:32 [19:25:32.813] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/46885749dcda4d57/pxe_data with map size 134217728 KB (LMDB v2) 19:25:32 [19:25:32.824] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x270308bd5e5a334df1b7ca32bae396124cd30ce583c2dabfeacda0001accea9e","blockNumber":1,"slot":2} 19:25:32 [19:25:32.825] INFO: pxe:data:lmdb Starting data store with maxReaders 16 19:25:33 [19:25:33.047] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:33 [19:25:33.218] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 19:25:33 [19:25:33.220] INFO: pxe:service Started PXE connected to chain 31337 version 3714316680 19:25:33 [19:25:33.304] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:33 [19:25:33.343] INFO: pxe:service Registered account 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc 19:25:33 [19:25:33.456] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 19:25:33 [19:25:33.460] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc 19:25:33 [19:25:33.494] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x270308bd5e5a334df1b7ca32bae396124cd30ce583c2dabfeacda0001accea9e","blockNumber":1,"slot":2} 19:25:33 [19:25:33.572] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:33 [19:25:33.646] INFO: pxe:service Added contract SchnorrAccount at 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 19:25:33 [19:25:33.701] VERBOSE: pxe:block_stream Starting sync from block number 0 19:25:33 [19:25:33.737] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 19:25:33 [19:25:33.831] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:33 [19:25:33.877] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 19:25:34 [19:25:34.693] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 19:25:34 [19:25:34.837] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 19:25:35 [19:25:35.000] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x270308bd5e5a334df1b7ca32bae396124cd30ce583c2dabfeacda0001accea9e","blockNumber":1,"slot":2} 19:25:35 [19:25:35.116] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:35 [19:25:35.166] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:35 [19:25:35.177] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:35 [19:25:35.179] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:35 [19:25:35.238] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:35 [19:25:35.246] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:35 [19:25:35.390] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:35 [19:25:35.452] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:35 [19:25:35.504] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:35 [19:25:35.504] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:35 [19:25:35.574] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x270308bd5e5a334df1b7ca32bae396124cd30ce583c2dabfeacda0001accea9e","blockNumber":1,"slot":2} 19:25:35 [19:25:35.591] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:35 [19:25:35.595] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:35 [19:25:35.615] VERBOSE: simulator:client_execution_context:debug_log Setting 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc as fee payer 19:25:35 [19:25:35.640] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:35 [19:25:35.854] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:36 [19:25:36.073] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:36 [19:25:36.302] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x270308bd5e5a334df1b7ca32bae396124cd30ce583c2dabfeacda0001accea9e","blockNumber":1,"slot":2} 19:25:36 [19:25:36.304] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:36 [19:25:36.655] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:37 [19:25:37.256] INFO: pxe:service Proving completed in 3558.0658779999994ms {"timings":{"total":3558.0658779999994,"sync":3.7185409999983676,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":206.87120200000209,"oracles":{"callPrivateFunction":{"times":[1128.2753709999997,433.1059920000007,270.8145450000011]}}},{"functionName":"private_kernel_init","time":144.40149399999973},{"functionName":"ContractClassRegisterer:register","time":1104.1347480000004,"oracles":{"loadCapsule":{"times":[77.90326199999981]},"notifyCreatedNullifier":{"times":[0.9012370000000374]},"debugLog":{"times":[2.378595000000132]},"notifyCreatedContractClassLog":{"times":[21.0928240000012]}}},{"functionName":"private_kernel_inner","time":175.13281300000017},{"functionName":"SchnorrAccount:constructor","time":376.6580819999999,"oracles":{"getContractInstance":{"times":[11.727815000000192]},"debugLog":{"times":[0.796425000000454,0.8939869999994698]},"fetchTaggedLogs":{"times":[63.987036000000444]},"loadCapsule":{"times":[1.0253290000000561,0.8288159999992786,0.44258799999988696]},"notifyCreatedNullifier":{"times":[0.3167360000006738,0.34908700000050885]},"notifyCreatedNote":{"times":[1.039119999999457]},"getRandomField":{"times":[0.7396540000008827,0.2278839999999036,0.07947199999944132,0.0472009999994043,0.0740109999987908,0.06895100000110688,0.07367100000010396,0.06849099999999453,0.07503100000030827,0.09782199999972363,0.06572100000084902]},"getIndexedTaggingSecretAsSender":{"times":[38.53510700000152]},"incrementAppTaggingSecretIndexAsSender":{"times":[29.186658999999054]}}},{"functionName":"private_kernel_inner","time":147.93564199999855},{"functionName":"SchnorrAccount:entrypoint","time":233.17502299999978,"oracles":{"debugLog":{"times":[0.6713820000004489,0.5158810000011727,0.8983269999989716]},"fetchTaggedLogs":{"times":[89.46837200000118]},"loadCapsule":{"times":[0.22403399999893736,0.23170500000014727,0.3004959999998391]},"getNotes":{"times":[2.246723000000202]},"getAuthWitness":{"times":[0.45417799999995623]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.0990209999999934]}}},{"functionName":"private_kernel_inner","time":139.64862299999913},{"functionName":"private_kernel_reset","time":231.2181070000006},{"functionName":"private_kernel_tail","time":115.22336600000017}],"unaccounted":679.9482370000005}} 19:25:37 [19:25:37.277] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:37 [19:25:37.418] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x270308bd5e5a334df1b7ca32bae396124cd30ce583c2dabfeacda0001accea9e","blockNumber":1,"slot":2} 19:25:37 [19:25:37.434] VERBOSE: p2p:tx_pool Adding tx 0x19482452f03f42da48305b3795f529f8e4b4ae76a548f488a3272411d45d6446 to pool {"eventName":"tx-added-to-pool","txHash":"0x19482452f03f42da48305b3795f529f8e4b4ae76a548f488a3272411d45d6446","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 19:25:37 [19:25:37.453] INFO: node Received tx 0x19482452f03f42da48305b3795f529f8e4b4ae76a548f488a3272411d45d6446 {"txHash":"0x19482452f03f42da48305b3795f529f8e4b4ae76a548f488a3272411d45d6446"} 19:25:37 [19:25:37.454] INFO: pxe:service Sent transaction 0x19482452f03f42da48305b3795f529f8e4b4ae76a548f488a3272411d45d6446 19:25:37 [19:25:37.485] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:37 [19:25:37.690] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:37 [19:25:37.896] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:37 [19:25:37.968] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 19:25:37 [19:25:37.996] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-17T19:29:54.000Z","now":"2025-06-17T19:29:36.135Z"} 19:25:38 [19:25:38.190] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:38 [19:25:38.274] VERBOSE: simulator:public-processor Processed tx 0x19482452f03f42da48305b3795f529f8e4b4ae76a548f488a3272411d45d6446 with no public calls in 201.49219699999958ms {"txHash":"0x19482452f03f42da48305b3795f529f8e4b4ae76a548f488a3272411d45d6446","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":201.49219699999958} 19:25:38 [19:25:38.279] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.28108633099999863s {"duration":0.28108633099999863,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 19:25:38 [19:25:38.467] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:38 [19:25:38.484] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x04bf49e12e6173f7ff0b8bce98b27b139490f68783bc1e9919680342e45277d0","globalVariables":{"chainId":31337,"version":3714316680,"blockNumber":1,"slotNumber":2,"timestamp":1750188594,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x19482452f03f42da48305b3795f529f8e4b4ae76a548f488a3272411d45d6446"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":516.2513330000002,"publicProcessDuration":282.38101599999936,"rollupCircuitsDuration":489.3077379999995,"txCount":1,"blockNumber":1,"blockTimestamp":1750188594,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 19:25:38 [19:25:38.492] VERBOSE: sequencer Attesting committee is empty 19:25:38 [19:25:38.678] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:38 [19:25:38.936] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:38 [19:25:38.948] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x8d66f4211700778707f6d63e711ee09b5e54fcd65c84c5c78328fe9b0f608ce7 19:25:38 [19:25:38.948] VERBOSE: sequencer-client Sent L1 transaction 0x8d66f4211700778707f6d63e711ee09b5e54fcd65c84c5c78328fe9b0f608ce7 {"gasLimit":514524,"maxFeePerGas":"1.574385791","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 19:25:38 [19:25:38.963] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 19:25:38 [19:25:38.967] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 19:25:38 [19:25:38.972] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 19:25:38 [19:25:38.976] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 19:25:38 [19:25:38.977] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x8d66f4211700778707f6d63e711ee09b5e54fcd65c84c5c78328fe9b0f608ce7","calldataSize":1444,"calldataGas":12256,"txCount":1,"blockNumber":1,"blockTimestamp":1750188594,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 19:25:38 [19:25:38.978] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 19:25:39 [19:25:39.001] INFO: blob-sink:server Received blob sidecar for block 0x04261bfd3d7ff3ae73df63f7d8cb289b44cf93ec9d7630ad352ce13d79dff719 19:25:39 [19:25:39.012] INFO: blob-sink:server Blob sidecar stored successfully for block 0x04261bfd3d7ff3ae73df63f7d8cb289b44cf93ec9d7630ad352ce13d79dff719 19:25:39 [19:25:39.175] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:39 [19:25:39.198] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 19:25:39 [19:25:39.214] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x04bf49e12e6173f7ff0b8bce98b27b139490f68783bc1e9919680342e45277d0","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":3714316680,"blockNumber":1,"slotNumber":2,"timestamp":1750188594,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x00ec11e146800b29de5f0eb9bb2f80a9c380a718f63c36394a440c90a39b2f5a","archiveNextLeafIndex":2} 19:25:39 [19:25:39.231] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750188630] on http://127.0.0.1:8545 19:25:39 [19:25:39.234] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 19:25:39 [19:25:39.235] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 19:25:39 [19:25:39.238] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750188630 19:25:39 [19:25:39.238] WARN: foundation:test-date-provider Time set to 2025-06-17T19:30:30.000Z {"offset":290762,"timeMs":1750188630000} 19:25:39 [19:25:39.238] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 19:25:39 [19:25:39.303] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":9.793206999998802,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750188594,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 19:25:39 [19:25:39.643] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:39 [19:25:39.647] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 19:25:39 [19:25:39.653] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc 19:25:39 [19:25:39.904] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:39 [19:25:39.916] VERBOSE: p2p Synched to latest block 1 19:25:40 [19:25:40.206] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:40 [19:25:40.218] INFO: pxe:service Added contract Test at 0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 19:25:40 [19:25:40.246] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x00ec11e146800b29de5f0eb9bb2f80a9c380a718f63c36394a440c90a39b2f5a","header":{"contentCommitment":{"blobsHash":"0x00be8905010e40dd6eda611f586d4e4699d38cfe98f390cc4476811fe7be7bbc","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750188594,"version":3714316680},"lastArchive":"0x270308bd5e5a334df1b7ca32bae396124cd30ce583c2dabfeacda0001accea9e","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x12b6ba8bd425a731b45624ca9433eb2a955eaa8b2fb3a6b16b15b44246552b11","nullifierTree":"0x10ea2180f636b319f13238d2204a0d3d6c57fd3db552c2c3347a2730112915b1","publicDataTree":"0x2835fd86049e3944a93240c8d0c409e2ce99bf5ed12e508b6dae007437c8f592"},"totalFees":6794052480,"totalManaUsed":20448}} 19:25:40 [19:25:40.444] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:40 [19:25:40.473] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:40 [19:25:40.514] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:40 [19:25:40.514] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:40 [19:25:40.583] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x00ec11e146800b29de5f0eb9bb2f80a9c380a718f63c36394a440c90a39b2f5a","blockNumber":2,"slot":3} 19:25:40 [19:25:40.629] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:40 [19:25:40.634] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x048110b4768940c24c16093f5f5c3ee6f02f13582ebdd5a22b376b59dd54eb6b 19:25:40 [19:25:40.649] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:25:40 [19:25:40.650] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 19:25:40 [19:25:40.666] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:25:40 [19:25:40.667] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:25:40 [19:25:40.673] VERBOSE: pxe:service Added note {"index":0,"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a0cbc17a834e3973f2fe2277b90edbbff644621b92a3eca3806e6df875a87bf","nullifier":"0x1709ecad348d960521ea0d8eb7565f10c8e2ff40deff7c5612814ce34dd102fe"} 19:25:40 [19:25:40.676] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:40 [19:25:40.695] VERBOSE: simulator:client_execution_context:debug_log Setting 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc as fee payer 19:25:40 [19:25:40.728] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:40 [19:25:40.739] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 19:25:41 [19:25:41.299] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 19:25:41 [19:25:41.399] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 19:25:41 [19:25:41.538] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 19:25:41 [19:25:41.561] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x00ec11e146800b29de5f0eb9bb2f80a9c380a718f63c36394a440c90a39b2f5a","blockNumber":2,"slot":3} 19:25:41 [19:25:41.609] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 19:25:41 [19:25:41.633] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc,0x0000000000000000000000000000000000000000000000000000000000000001,0x22e7727314f8943932f805c4664198ef47f00688a80e16e39d7e502edf6efc9b,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc 19:25:41 [19:25:41.653] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:42 [19:25:42.313] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:42 [19:25:42.344] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:42 [19:25:42.345] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:42 [19:25:42.363] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:42 [19:25:42.553] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x00ec11e146800b29de5f0eb9bb2f80a9c380a718f63c36394a440c90a39b2f5a","blockNumber":2,"slot":3} 19:25:42 [19:25:42.571] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:42 [19:25:42.585] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:42 [19:25:42.590] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:42 [19:25:42.778] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:43 [19:25:43.027] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:43 [19:25:43.278] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:43 [19:25:43.355] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x00ec11e146800b29de5f0eb9bb2f80a9c380a718f63c36394a440c90a39b2f5a","blockNumber":2,"slot":3} 19:25:43 [19:25:43.525] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:43 [19:25:43.912] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:44 [19:25:44.341] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:44 [19:25:44.667] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:44 [19:25:44.675] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x00ec11e146800b29de5f0eb9bb2f80a9c380a718f63c36394a440c90a39b2f5a","blockNumber":2,"slot":3} 19:25:44 [19:25:44.973] INFO: pxe:service Proving completed in 4737.3723059999975ms {"timings":{"total":4737.3723059999975,"sync":13.608079999998154,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":638.7269790000028,"oracles":{"debugLog":{"times":[0.7286339999991469,0.5116600000001199,0.5946210000001884,0.6149310000000696,0.6308019999996759,0.4920899999997346,0.449187999998685,0.7489439999990282]},"fetchTaggedLogs":{"times":[117.66186299999754]},"loadCapsule":{"times":[0.24608500000249478,0.49010899999848334,0.6319419999999809,0.393897999998444,0.35281700000268756,0.34897600000113016]},"getSharedSecret":{"times":[4.926863999997295]},"aes128Decrypt":{"times":[1.204823000000033,1.4135569999998552]},"getPublicKeysAndPartialAddress":{"times":[7.739488999999594]},"getKeyValidationRequest":{"times":[3.179721000000427]},"deliverNote":{"times":[6.528075999998691]},"deleteCapsule":{"times":[0.47943900000245776]},"storeCapsule":{"times":[0.6747430000032182]},"getNotes":{"times":[3.766931999998633]},"getAuthWitness":{"times":[0.31355599999733386]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.0876719999978377]},"callPrivateFunction":{"times":[753.6579889999994,189.5298479999983,954.6903770000026]}}},{"functionName":"private_kernel_init","time":72.89401500000167},{"functionName":"ContractClassRegisterer:register","time":737.7611250000009,"oracles":{"loadCapsule":{"times":[6.189139000001887]},"notifyCreatedNullifier":{"times":[0.5383399999991525]},"debugLog":{"times":[0.7735650000031455]},"notifyCreatedContractClassLog":{"times":[8.837369000000763]}}},{"functionName":"private_kernel_inner","time":163.00396999999793},{"functionName":"ContractInstanceDeployer:deploy","time":100.26736000000164,"oracles":{"storeInExecutionCache":{"times":[0.3009060000003956]},"callPrivateFunction":{"times":[75.66298800000004]},"notifyCreatedNullifier":{"times":[0.3891570000014326]},"debugLog":{"times":[0.8874770000002172]}}},{"functionName":"private_kernel_inner","time":139.05610199999865},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":59.366195999999036,"oracles":{}},{"functionName":"private_kernel_inner","time":156.12637900000118},{"functionName":"Test:initialize","time":792.6159539999971,"oracles":{"getContractInstance":{"times":[7.205288000001019]},"debugLog":{"times":[0.7904949999974633,0.49151999999958207]},"fetchTaggedLogs":{"times":[243.4915910000018]},"loadCapsule":{"times":[0.37662799999816343,0.2556350000013481,0.2480040000009467]},"notifyCreatedNullifier":{"times":[0.3376570000000356]}}},{"functionName":"private_kernel_inner","time":238.88663400000223},{"functionName":"private_kernel_reset","time":210.9878290000015},{"functionName":"private_kernel_tail","time":93.61722200000077}],"unaccounted":1320.454460999994}} 19:25:44 [19:25:44.995] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:45 [19:25:45.154] VERBOSE: p2p:tx_pool Adding tx 0x1493da3f719f956ef2d92393ea53d3681758c7c577736706d9f0d0d8ff4b9a0f to pool {"eventName":"tx-added-to-pool","txHash":"0x1493da3f719f956ef2d92393ea53d3681758c7c577736706d9f0d0d8ff4b9a0f","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 19:25:45 [19:25:45.174] INFO: node Received tx 0x1493da3f719f956ef2d92393ea53d3681758c7c577736706d9f0d0d8ff4b9a0f {"txHash":"0x1493da3f719f956ef2d92393ea53d3681758c7c577736706d9f0d0d8ff4b9a0f"} 19:25:45 [19:25:45.174] INFO: pxe:service Sent transaction 0x1493da3f719f956ef2d92393ea53d3681758c7c577736706d9f0d0d8ff4b9a0f 19:25:45 [19:25:45.201] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:45 [19:25:45.204] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 19:25:45 [19:25:45.222] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-17T19:30:30.000Z","now":"2025-06-17T19:30:35.984Z"} 19:25:45 [19:25:45.442] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:45 [19:25:45.524] VERBOSE: simulator:public-processor Processed tx 0x1493da3f719f956ef2d92393ea53d3681758c7c577736706d9f0d0d8ff4b9a0f with no public calls in 176.8180250000005ms {"txHash":"0x1493da3f719f956ef2d92393ea53d3681758c7c577736706d9f0d0d8ff4b9a0f","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":176.8180250000005} 19:25:45 [19:25:45.529] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.3060642699999989s {"duration":0.3060642699999989,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 19:25:45 [19:25:45.709] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:45 [19:25:45.732] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x01d6f544c24bb7eaa8d23eecce765e1d5dbf9bcf55a7970e199c97e523082621","globalVariables":{"chainId":31337,"version":3714316680,"blockNumber":2,"slotNumber":3,"timestamp":1750188630,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1493da3f719f956ef2d92393ea53d3681758c7c577736706d9f0d0d8ff4b9a0f"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":528.0742699999973,"publicProcessDuration":306.6158890000006,"rollupCircuitsDuration":511.33727,"txCount":1,"blockNumber":2,"blockTimestamp":1750188630,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 19:25:45 [19:25:45.735] VERBOSE: sequencer Attesting committee is empty 19:25:45 [19:25:45.922] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:46 [19:25:46.063] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xd7075018db8c8cc0a8f66653a96e13bdca66600c3a8b87d0c96ee084e6671749 19:25:46 [19:25:46.064] VERBOSE: sequencer-client Sent L1 transaction 0xd7075018db8c8cc0a8f66653a96e13bdca66600c3a8b87d0c96ee084e6671749 {"gasLimit":470283,"maxFeePerGas":"1.303611163","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 19:25:46 [19:25:46.079] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 19:25:46 [19:25:46.079] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xd7075018db8c8cc0a8f66653a96e13bdca66600c3a8b87d0c96ee084e6671749","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":2,"blockTimestamp":1750188630,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 19:25:46 [19:25:46.080] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 19:25:46 [19:25:46.084] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750188666] on http://127.0.0.1:8545 19:25:46 [19:25:46.086] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 19:25:46 [19:25:46.089] INFO: blob-sink:server Received blob sidecar for block 0xa7cd18a417ffd6287cb863eb1bafd97ab0b98da8b805ca8fb86b913aa3d5f30a 19:25:46 [19:25:46.094] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750188666 19:25:46 [19:25:46.094] WARN: foundation:test-date-provider Time set to 2025-06-17T19:31:06.000Z {"offset":319906,"timeMs":1750188666000} 19:25:46 [19:25:46.094] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 19:25:46 [19:25:46.097] INFO: blob-sink:server Blob sidecar stored successfully for block 0xa7cd18a417ffd6287cb863eb1bafd97ab0b98da8b805ca8fb86b913aa3d5f30a 19:25:46 [19:25:46.126] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:46 [19:25:46.139] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 19:25:46 [19:25:46.141] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 19:25:46 [19:25:46.145] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 19:25:46 [19:25:46.312] VERBOSE: archiver:block-helper Store contract instance at 0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc 19:25:46 [19:25:46.343] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 19:25:46 [19:25:46.380] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:46 [19:25:46.394] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x01d6f544c24bb7eaa8d23eecce765e1d5dbf9bcf55a7970e199c97e523082621","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":3714316680,"blockNumber":2,"slotNumber":3,"timestamp":1750188630,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2256c8ee3021dc23715eb51843adb08f55e9d0e8f283b66feae4d8a913aeb777","archiveNextLeafIndex":3} 19:25:46 [19:25:46.429] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":9.134075000001758,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750188630,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 19:25:46 [19:25:46.446] VERBOSE: p2p Synched to latest block 2 19:25:46 [19:25:46.589] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:46 [19:25:46.609] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2256c8ee3021dc23715eb51843adb08f55e9d0e8f283b66feae4d8a913aeb777","blockNumber":3,"slot":4} 19:25:46 [19:25:46.797] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:46 [19:25:46.940] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 19:25:46 [19:25:46.999] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:47 [19:25:47.127] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2256c8ee3021dc23715eb51843adb08f55e9d0e8f283b66feae4d8a913aeb777","blockNumber":3,"slot":4} 19:25:47 [19:25:47.205] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:47 [19:25:47.483] INFO: aztecjs:deploy_sent_tx Contract 0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc successfully deployed. 19:25:47 [19:25:47.487] INFO: e2e:e2e_keys Running test: Keys using nsk_app to detect nullification nsk_app and contract address are enough to detect note nullification 19:25:47 [19:25:47.494] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:47 [19:25:47.557] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x2256c8ee3021dc23715eb51843adb08f55e9d0e8f283b66feae4d8a913aeb777","header":{"contentCommitment":{"blobsHash":"0x00394f5157453dcde721a024dda27383d9de5a5d849e86ab5af0cdb9ad87b56c","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750188630,"version":3714316680},"lastArchive":"0x00ec11e146800b29de5f0eb9bb2f80a9c380a718f63c36394a440c90a39b2f5a","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x12b6ba8bd425a731b45624ca9433eb2a955eaa8b2fb3a6b16b15b44246552b11","nullifierTree":"0x1261fd3a7bdf442493a7e48aed7a1690d3b29e44d0751136b0972da54c2e08ef","publicDataTree":"0x18ed5fff272692f548a44aad1bc8087d28d385d45414cf44ddeddfd972d4e51e"},"totalFees":7272506880,"totalManaUsed":21888}} 19:25:47 [19:25:47.649] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:47 [19:25:47.702] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:47 [19:25:47.702] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:47 [19:25:47.708] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:47 [19:25:47.761] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2256c8ee3021dc23715eb51843adb08f55e9d0e8f283b66feae4d8a913aeb777","blockNumber":3,"slot":4} 19:25:47 [19:25:47.828] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:47 [19:25:47.840] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x048110b4768940c24c16093f5f5c3ee6f02f13582ebdd5a22b376b59dd54eb6b 19:25:47 [19:25:47.855] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:25:47 [19:25:47.856] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 19:25:47 [19:25:47.866] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:25:47 [19:25:47.867] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:25:47 [19:25:47.871] VERBOSE: pxe:service Added note {"index":0,"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a0cbc17a834e3973f2fe2277b90edbbff644621b92a3eca3806e6df875a87bf","nullifier":"0x1709ecad348d960521ea0d8eb7565f10c8e2ff40deff7c5612814ce34dd102fe"} 19:25:47 [19:25:47.873] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:47 [19:25:47.891] VERBOSE: simulator:client_execution_context:debug_log Setting 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc as fee payer 19:25:48 [19:25:48.071] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:48 [19:25:48.490] VERBOSE: simulator:private_execution Executing private function Test:call_create_note {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:48 [19:25:48.552] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:48 [19:25:48.552] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:48 [19:25:48.569] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:48 [19:25:48.777] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2256c8ee3021dc23715eb51843adb08f55e9d0e8f283b66feae4d8a913aeb777","blockNumber":3,"slot":4} 19:25:48 [19:25:48.793] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:48 [19:25:48.857] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:48 [19:25:48.895] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:49 [19:25:49.081] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:49 [19:25:49.301] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:49 [19:25:49.494] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2256c8ee3021dc23715eb51843adb08f55e9d0e8f283b66feae4d8a913aeb777","blockNumber":3,"slot":4} 19:25:49 [19:25:49.595] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:50 [19:25:50.424] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:50 [19:25:50.610] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2256c8ee3021dc23715eb51843adb08f55e9d0e8f283b66feae4d8a913aeb777","blockNumber":3,"slot":4} 19:25:50 [19:25:50.912] INFO: pxe:service Proving completed in 3387.899970000002ms {"timings":{"total":3387.899970000002,"sync":35.941947999999684,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":540.9741369999974,"oracles":{"debugLog":{"times":[0.6582419999976992,0.5623310000009951,0.750504000003275,0.6970039999978326,0.7348339999989548,0.5269200000002456,0.40178799999921466,0.8503960000016377]},"fetchTaggedLogs":{"times":[135.62674700000207]},"loadCapsule":{"times":[0.2811150000015914,0.18944399999963935,0.461208999997325,0.21247299999959068,0.2941759999994247,0.2214349999994738]},"getSharedSecret":{"times":[4.522286999999778]},"aes128Decrypt":{"times":[0.3865779999978258,1.2104330000001937]},"getPublicKeysAndPartialAddress":{"times":[3.074198999998771]},"getKeyValidationRequest":{"times":[2.62163000000146]},"deliverNote":{"times":[3.858784000000014]},"deleteCapsule":{"times":[0.3781079999971553]},"storeCapsule":{"times":[0.27320500000132597]},"getNotes":{"times":[1.9772369999991497]},"getAuthWitness":{"times":[0.24478499999895575]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07381099999838625]},"callPrivateFunction":{"times":[1438.3349370000033]}}},{"functionName":"private_kernel_init","time":91.23307700000078},{"functionName":"Test:call_create_note","time":1210.5296850000013,"oracles":{"debugLog":{"times":[0.8645660000001953,0.6036820000008447]},"fetchTaggedLogs":{"times":[338.3864880000001]},"loadCapsule":{"times":[0.3247560000017984,2.938925999998901,0.3403470000012021]},"getRandomField":{"times":[0.18555399999968358,0.12718200000017532,0.23625400000310037,0.06238200000007055,0.05683099999805563,0.05388200000015786,0.050111000000470085,0.04285100000197417,0.052931999998691026,0.046430999998847255,0.046951000000262866,0.04637100000036298]},"notifyCreatedNote":{"times":[0.19680399999924703]},"getIndexedTaggingSecretAsSender":{"times":[179.84448299999713]},"incrementAppTaggingSecretIndexAsSender":{"times":[212.72731199999907]}}},{"functionName":"private_kernel_inner","time":138.25134700000126},{"functionName":"private_kernel_reset","time":224.29001399999834},{"functionName":"private_kernel_tail","time":89.7854789999983}],"unaccounted":1056.894283000005}} 19:25:50 [19:25:50.932] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:50 [19:25:50.974] VERBOSE: p2p:tx_pool Adding tx 0x1ec2cd342c0678e0bad2fc1d30ede0dc9d5713f6928f393c3ae9d680a213cd4c to pool {"eventName":"tx-added-to-pool","txHash":"0x1ec2cd342c0678e0bad2fc1d30ede0dc9d5713f6928f393c3ae9d680a213cd4c","noteHashCount":1,"nullifierCount":1,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 19:25:50 [19:25:50.982] INFO: node Received tx 0x1ec2cd342c0678e0bad2fc1d30ede0dc9d5713f6928f393c3ae9d680a213cd4c {"txHash":"0x1ec2cd342c0678e0bad2fc1d30ede0dc9d5713f6928f393c3ae9d680a213cd4c"} 19:25:50 [19:25:50.982] INFO: pxe:service Sent transaction 0x1ec2cd342c0678e0bad2fc1d30ede0dc9d5713f6928f393c3ae9d680a213cd4c 19:25:51 [19:25:51.130] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 19:25:51 [19:25:51.139] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:51 [19:25:51.147] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-17T19:31:06.000Z","now":"2025-06-17T19:31:11.053Z"} 19:25:51 [19:25:51.185] VERBOSE: simulator:public-processor Processed tx 0x1ec2cd342c0678e0bad2fc1d30ede0dc9d5713f6928f393c3ae9d680a213cd4c with no public calls in 16.69166899999982ms {"txHash":"0x1ec2cd342c0678e0bad2fc1d30ede0dc9d5713f6928f393c3ae9d680a213cd4c","txFee":3242857600,"revertCode":0,"gasUsed":{"totalGas":{"daGas":11264,"l2Gas":9760},"billedGas":{"daGas":11264,"l2Gas":9760},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":1,"noteHashCount":1,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":16.69166899999982} 19:25:51 [19:25:51.187] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03829123299999992s {"duration":0.03829123299999992,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11264,"l2Gas":9760},"totalSizeInBytes":960} 19:25:51 [19:25:51.312] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2c8257097d41b0a7f5b581070f58384e8a223d0c346ffef16071812aada0b8df","globalVariables":{"chainId":31337,"version":3714316680,"blockNumber":3,"slotNumber":4,"timestamp":1750188666,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1ec2cd342c0678e0bad2fc1d30ede0dc9d5713f6928f393c3ae9d680a213cd4c"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":181.58440600000176,"publicProcessDuration":38.75138199999856,"rollupCircuitsDuration":166.79189300000144,"txCount":1,"blockNumber":3,"blockTimestamp":1750188666,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 19:25:51 [19:25:51.315] VERBOSE: sequencer Attesting committee is empty 19:25:51 [19:25:51.416] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:51 [19:25:51.683] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:51 [19:25:51.693] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xb6e0eac3354ebd79aba83f78c72f1ee662fc6d3a86e9f6bd8ebfd30c857db474 19:25:51 [19:25:51.694] VERBOSE: sequencer-client Sent L1 transaction 0xb6e0eac3354ebd79aba83f78c72f1ee662fc6d3a86e9f6bd8ebfd30c857db474 {"gasLimit":470298,"maxFeePerGas":"1.279583374","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 19:25:51 [19:25:51.704] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750188702] on http://127.0.0.1:8545 19:25:51 [19:25:51.707] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 19:25:51 [19:25:51.710] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 19:25:51 [19:25:51.711] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xb6e0eac3354ebd79aba83f78c72f1ee662fc6d3a86e9f6bd8ebfd30c857db474","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":3,"blockTimestamp":1750188666,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 19:25:51 [19:25:51.711] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 19:25:51 [19:25:51.714] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750188702 19:25:51 [19:25:51.714] WARN: foundation:test-date-provider Time set to 2025-06-17T19:31:42.000Z {"offset":350286,"timeMs":1750188702000} 19:25:51 [19:25:51.714] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 19:25:51 [19:25:51.718] INFO: blob-sink:server Received blob sidecar for block 0x614f1e2771257796d0a37d2279c8ea7fbc4a30dc137dca11e40f9afbd9b7cf12 19:25:51 [19:25:51.723] INFO: blob-sink:server Blob sidecar stored successfully for block 0x614f1e2771257796d0a37d2279c8ea7fbc4a30dc137dca11e40f9afbd9b7cf12 19:25:51 [19:25:51.886] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:51 [19:25:51.892] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 19:25:51 [19:25:51.893] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 19:25:51 [19:25:51.896] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 19:25:52 [19:25:52.090] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:52 [19:25:52.335] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:52 [19:25:52.351] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x2c8257097d41b0a7f5b581070f58384e8a223d0c346ffef16071812aada0b8df","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":3714316680,"blockNumber":3,"slotNumber":4,"timestamp":1750188666,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2cbd72f37cbf815f1c569364acec92ab5a2e1d17ffc16b7c833fe96fd0be6f19","archiveNextLeafIndex":4} 19:25:52 [19:25:52.357] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 19:25:52 [19:25:52.457] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":9.112393999999767,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750188666,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 19:25:52 [19:25:52.468] VERBOSE: p2p Synched to latest block 3 19:25:52 [19:25:52.540] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:52 [19:25:52.743] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:52 [19:25:52.766] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2cbd72f37cbf815f1c569364acec92ab5a2e1d17ffc16b7c833fe96fd0be6f19","blockNumber":4,"slot":5} 19:25:52 [19:25:52.946] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:53 [19:25:53.060] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x2cbd72f37cbf815f1c569364acec92ab5a2e1d17ffc16b7c833fe96fd0be6f19","header":{"contentCommitment":{"blobsHash":"0x008ccb74b909d4d4c6ed6b94ed2b860310a8ae6c786f118cd7fab4f3f17540f9","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750188666,"version":3714316680},"lastArchive":"0x2256c8ee3021dc23715eb51843adb08f55e9d0e8f283b66feae4d8a913aeb777","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x19a1cd82823a56997212e9022376d2dff4d4622e92f8a6d834db89f8ec5dbad5","nullifierTree":"0x0bff93d984838c5ed4f4d628b6d87dbe51741e666d791dd41f341947b8e63c1f","publicDataTree":"0x0956249abbf48470d168ea98b300f7571451505096bdfb9b722644b9edf8f7ec"},"totalFees":3242857600,"totalManaUsed":9760}} 19:25:53 [19:25:53.180] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:53 [19:25:53.237] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:53 [19:25:53.238] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:53 [19:25:53.245] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:53 [19:25:53.305] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2cbd72f37cbf815f1c569364acec92ab5a2e1d17ffc16b7c833fe96fd0be6f19","blockNumber":4,"slot":5} 19:25:53 [19:25:53.324] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:53 [19:25:53.343] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x048110b4768940c24c16093f5f5c3ee6f02f13582ebdd5a22b376b59dd54eb6b 19:25:53 [19:25:53.357] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:25:53 [19:25:53.358] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 19:25:53 [19:25:53.370] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:25:53 [19:25:53.371] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:25:53 [19:25:53.375] VERBOSE: pxe:service Added note {"index":0,"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a0cbc17a834e3973f2fe2277b90edbbff644621b92a3eca3806e6df875a87bf","nullifier":"0x1709ecad348d960521ea0d8eb7565f10c8e2ff40deff7c5612814ce34dd102fe"} 19:25:53 [19:25:53.377] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:53 [19:25:53.400] VERBOSE: simulator:client_execution_context:debug_log Setting 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc as fee payer 19:25:53 [19:25:53.595] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:53 [19:25:53.983] VERBOSE: simulator:private_execution Executing private function Test:call_destroy_note {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:54 [19:25:54.039] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:54 [19:25:54.040] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:54 [19:25:54.056] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:54 [19:25:54.253] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2cbd72f37cbf815f1c569364acec92ab5a2e1d17ffc16b7c833fe96fd0be6f19","blockNumber":4,"slot":5} 19:25:54 [19:25:54.267] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:54 [19:25:54.292] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:54 [19:25:54.298] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1525ffbf18ec8c6f2bc24b50d92ee488fd658024fc20c44082b73278a14d179c 19:25:54 [19:25:54.313] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:25:54 [19:25:54.315] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 19:25:54 [19:25:54.321] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:25:54 [19:25:54.322] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:25:54 [19:25:54.326] VERBOSE: pxe:service Added note {"index":128,"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x29e2bf356904a371a9816e061ec6be35e9d9b21dbdc9ea04b62c25a08bdfd227","nullifier":"0x0d04831d61522e7ed576fd033db42ebe177f4ba5e1e1b4b9736910ccf3a85778"} 19:25:54 [19:25:54.329] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:54 [19:25:54.495] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:54 [19:25:54.723] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:55 [19:25:55.341] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:55 [19:25:55.549] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2cbd72f37cbf815f1c569364acec92ab5a2e1d17ffc16b7c833fe96fd0be6f19","blockNumber":4,"slot":5} 19:25:55 [19:25:55.553] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:55 [19:25:55.831] INFO: pxe:service Proving completed in 2782.6275030000033ms {"timings":{"total":2782.6275030000033,"sync":16.38472399999955,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":524.7261150000013,"oracles":{"debugLog":{"times":[0.8276959999966493,0.5324810000020079,0.5960209999975632,0.5755610000014713,0.5853209999986575,0.4232980000015232,0.38647800000035204,0.7306029999999737]},"fetchTaggedLogs":{"times":[101.81852900000013]},"loadCapsule":{"times":[0.3767280000029132,0.8447960000012245,0.5009989999998652,0.2607849999985774,0.22405399999843212,0.25023499999952037]},"getSharedSecret":{"times":[4.852611999998771]},"aes128Decrypt":{"times":[0.5749619999987772,1.2503939999987779]},"getPublicKeysAndPartialAddress":{"times":[5.776221000000078]},"getKeyValidationRequest":{"times":[1.7856639999990875]},"deliverNote":{"times":[3.963576000001922]},"deleteCapsule":{"times":[0.3526770000025863]},"storeCapsule":{"times":[0.4540290000004461]},"getNotes":{"times":[4.408414000001358]},"getAuthWitness":{"times":[0.3243959999999788]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.09692200000063167]},"callPrivateFunction":{"times":[969.9791489999989]}}},{"functionName":"private_kernel_init","time":71.80214500000147},{"functionName":"Test:call_destroy_note","time":748.5857719999985,"oracles":{"debugLog":{"times":[0.8356960000019171,0.6631720000004862,0.695942999998806,0.7586240000018734,0.4564879999998084,0.5679709999967599,0.41212799999993877]},"fetchTaggedLogs":{"times":[255.6892549999975]},"loadCapsule":{"times":[0.3361960000002,0.2633049999967625,0.4611189999995986,0.2411850000025879,0.2807260000008682,0.1873040000027686]},"getSharedSecret":{"times":[5.867782000001171]},"aes128Decrypt":{"times":[0.3966779999973369,1.3769460000003164]},"getPublicKeysAndPartialAddress":{"times":[1.5176690000007511,2.0745500000011816]},"getKeyValidationRequest":{"times":[1.8182050000032177,4.124589000002743]},"deliverNote":{"times":[4.581397999998444]},"deleteCapsule":{"times":[0.4838689999996859]},"storeCapsule":{"times":[0.37641699999949196]},"getNotes":{"times":[2.1274410000005446]},"notifyNullifiedNote":{"times":[0.9026669999984733]}}},{"functionName":"private_kernel_inner","time":157.6417580000052},{"functionName":"private_kernel_reset","time":210.07871200000227},{"functionName":"private_kernel_tail","time":75.53130599999713}],"unaccounted":977.8769709999979}} 19:25:55 [19:25:55.847] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:55 [19:25:55.868] VERBOSE: p2p:tx_pool Adding tx 0x037e09a2ad89763dea3414a49bee37f2bfe0eaaad0a6c743eb0f0eb96dae91b7 to pool {"eventName":"tx-added-to-pool","txHash":"0x037e09a2ad89763dea3414a49bee37f2bfe0eaaad0a6c743eb0f0eb96dae91b7","noteHashCount":0,"nullifierCount":2,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 19:25:55 [19:25:55.873] INFO: node Received tx 0x037e09a2ad89763dea3414a49bee37f2bfe0eaaad0a6c743eb0f0eb96dae91b7 {"txHash":"0x037e09a2ad89763dea3414a49bee37f2bfe0eaaad0a6c743eb0f0eb96dae91b7"} 19:25:55 [19:25:55.874] INFO: pxe:service Sent transaction 0x037e09a2ad89763dea3414a49bee37f2bfe0eaaad0a6c743eb0f0eb96dae91b7 19:25:56 [19:25:56.054] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:56 [19:25:56.090] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 19:25:56 [19:25:56.105] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-17T19:31:42.000Z","now":"2025-06-17T19:31:46.391Z"} 19:25:56 [19:25:56.132] VERBOSE: simulator:public-processor Processed tx 0x037e09a2ad89763dea3414a49bee37f2bfe0eaaad0a6c743eb0f0eb96dae91b7 with no public calls in 16.17848999999842ms {"txHash":"0x037e09a2ad89763dea3414a49bee37f2bfe0eaaad0a6c743eb0f0eb96dae91b7","txFee":567840000,"revertCode":0,"gasUsed":{"totalGas":{"daGas":1536,"l2Gas":11200},"billedGas":{"daGas":1536,"l2Gas":11200},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":2,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":16.17848999999842} 19:25:56 [19:25:56.133] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.027189800000000104s {"duration":0.027189800000000104,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1536,"l2Gas":11200},"totalSizeInBytes":288} 19:25:56 [19:25:56.244] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1cd4db3ca83e4aba691310be7af6a979591aa8d1190100c2211db131abbbd25d","globalVariables":{"chainId":31337,"version":3714316680,"blockNumber":4,"slotNumber":5,"timestamp":1750188702,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x037e09a2ad89763dea3414a49bee37f2bfe0eaaad0a6c743eb0f0eb96dae91b7"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":153.9732279999953,"publicProcessDuration":27.62525900000037,"rollupCircuitsDuration":139.4245790000059,"txCount":1,"blockNumber":4,"blockTimestamp":1750188702,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 19:25:56 [19:25:56.247] VERBOSE: sequencer Attesting committee is empty 19:25:56 [19:25:56.348] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:56 [19:25:56.552] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xeef92f7e4019a318af61a91e24709376a1c9ee0414e604373dc84eda9933bea4 19:25:56 [19:25:56.552] VERBOSE: sequencer-client Sent L1 transaction 0xeef92f7e4019a318af61a91e24709376a1c9ee0414e604373dc84eda9933bea4 {"gasLimit":470277,"maxFeePerGas":"1.261127723","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 19:25:56 [19:25:56.559] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:56 [19:25:56.564] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 19:25:56 [19:25:56.567] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 19:25:56 [19:25:56.570] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 19:25:56 [19:25:56.570] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xeef92f7e4019a318af61a91e24709376a1c9ee0414e604373dc84eda9933bea4","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":4,"blockTimestamp":1750188702,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 19:25:56 [19:25:56.571] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 19:25:56 [19:25:56.574] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 19:25:56 [19:25:56.581] INFO: blob-sink:server Received blob sidecar for block 0x63b615d7fa9e2e6f6cb632131b383cab1863decfae3d8056da9679f732f56d22 19:25:56 [19:25:56.588] INFO: blob-sink:server Blob sidecar stored successfully for block 0x63b615d7fa9e2e6f6cb632131b383cab1863decfae3d8056da9679f732f56d22 19:25:56 [19:25:56.589] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750188738] on http://127.0.0.1:8545 19:25:56 [19:25:56.590] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 19:25:56 [19:25:56.592] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750188738 19:25:56 [19:25:56.592] WARN: foundation:test-date-provider Time set to 2025-06-17T19:32:18.000Z {"offset":381408,"timeMs":1750188738000} 19:25:56 [19:25:56.592] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 19:25:56 [19:25:56.742] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x1cd4db3ca83e4aba691310be7af6a979591aa8d1190100c2211db131abbbd25d","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":3714316680,"blockNumber":4,"slotNumber":5,"timestamp":1750188702,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x1d3cdcc8ba831e714352f88378acb6373cba23ea1d362d818ac6a71b9fe83e87","archiveNextLeafIndex":5} 19:25:56 [19:25:56.744] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 19:25:56 [19:25:56.765] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:56 [19:25:56.848] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":7.045055000002321,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1750188702,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 19:25:56 [19:25:56.853] VERBOSE: p2p Synched to latest block 4 19:25:56 [19:25:56.897] INFO: e2e:e2e_keys Running test: Keys ovsk_app gets ovsk_app 19:25:56 [19:25:56.914] INFO: pxe:service Simulating transaction execution request to 0x27e740b2 at 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc {"origin":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x00000000000000000000000000000000000000000000000000000000dd63f988","authWitnesses":["0x0524e101e8a7a6b31b77bb25f974681f2d6829bb260e21bc6332d9cd8768355d"]} 19:25:56 [19:25:56.923] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x1d3cdcc8ba831e714352f88378acb6373cba23ea1d362d818ac6a71b9fe83e87","header":{"contentCommitment":{"blobsHash":"0x000b025457fa7b07e9c60d69ac934a37b5e37f10ede3f64c20b6fe829574cc21","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750188702,"version":3714316680},"lastArchive":"0x2cbd72f37cbf815f1c569364acec92ab5a2e1d17ffc16b7c833fe96fd0be6f19","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x19a1cd82823a56997212e9022376d2dff4d4622e92f8a6d834db89f8ec5dbad5","nullifierTree":"0x084eb60d4a6664e2ecbffaa75bfea2ef0377c17fa09297984a712eb734fd4f03","publicDataTree":"0x2edc301a4cef8181c8f41331cf6c6de3d3aff72c81d4ddbdf758f01aafc28160"},"totalFees":567840000,"totalManaUsed":11200}} 19:25:56 [19:25:56.988] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:57 [19:25:57.022] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:57 [19:25:57.069] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:57 [19:25:57.070] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:57 [19:25:57.130] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1d3cdcc8ba831e714352f88378acb6373cba23ea1d362d818ac6a71b9fe83e87","blockNumber":5,"slot":6} 19:25:57 [19:25:57.145] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc"} 19:25:57 [19:25:57.152] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x048110b4768940c24c16093f5f5c3ee6f02f13582ebdd5a22b376b59dd54eb6b 19:25:57 [19:25:57.161] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:25:57 [19:25:57.162] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 19:25:57 [19:25:57.167] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:25:57 [19:25:57.168] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:25:57 [19:25:57.171] VERBOSE: pxe:service Added note {"index":0,"contract":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1a0cbc17a834e3973f2fe2277b90edbbff644621b92a3eca3806e6df875a87bf","nullifier":"0x1709ecad348d960521ea0d8eb7565f10c8e2ff40deff7c5612814ce34dd102fe"} 19:25:57 [19:25:57.172] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:57 [19:25:57.185] VERBOSE: simulator:client_execution_context:debug_log Setting 0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc as fee payer 19:25:57 [19:25:57.338] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:57 [19:25:57.676] VERBOSE: simulator:private_execution Executing private function Test:get_ovsk_app {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:57 [19:25:57.694] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 19:25:57 [19:25:57.694] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:57 [19:25:57.826] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:57 [19:25:57.839] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1d3cdcc8ba831e714352f88378acb6373cba23ea1d362d818ac6a71b9fe83e87","blockNumber":5,"slot":6} 19:25:57 [19:25:57.853] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc"} 19:25:57 [19:25:57.859] VERBOSE: pxe:service Removed note for contract 0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc at slot 0x000000000000000000000000000000000000000000000000000000000000000c {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","nullifier":"0x0d04831d61522e7ed576fd033db42ebe177f4ba5e1e1b4b9736910ccf3a85778"} 19:25:57 [19:25:57.861] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1525ffbf18ec8c6f2bc24b50d92ee488fd658024fc20c44082b73278a14d179c 19:25:57 [19:25:57.875] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 19:25:57 [19:25:57.876] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 19:25:57 [19:25:57.884] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 19:25:57 [19:25:57.885] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 19:25:57 [19:25:57.888] VERBOSE: pxe:service Added note {"index":128,"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x29e2bf356904a371a9816e061ec6be35e9d9b21dbdc9ea04b62c25a08bdfd227","nullifier":"0x0d04831d61522e7ed576fd033db42ebe177f4ba5e1e1b4b9736910ccf3a85778"} 19:25:57 [19:25:57.891] VERBOSE: pxe:service Removed just-added note {"contract":"0x0665ab3ff20433a86ff37502cf49ff3bfb897ecc0acd16970042f4a0a5e8b5dc","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x29e2bf356904a371a9816e061ec6be35e9d9b21dbdc9ea04b62c25a08bdfd227","nullifier":"0x0d04831d61522e7ed576fd033db42ebe177f4ba5e1e1b4b9736910ccf3a85778"} 19:25:57 [19:25:57.892] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 19:25:58 [19:25:58.102] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:58 [19:25:58.549] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:58 [19:25:58.705] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1d3cdcc8ba831e714352f88378acb6373cba23ea1d362d818ac6a71b9fe83e87","blockNumber":5,"slot":6} 19:25:58 [19:25:58.999] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 19:25:59 [19:25:59.031] INFO: pxe:service Simulation completed for 0x299b53279f0182845ed66b066f63882e1b05526c1b423e163fabc65bc42b994b in 2116.6529020000016ms {"txHash":"0x299b53279f0182845ed66b066f63882e1b05526c1b423e163fabc65bc42b994b","origin":"0x17f29d20e9861b4a6def36642f42b01b7b84f40d40ce8b318fb03c6d55aaeadc","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x00000000000000000000000000000000000000000000000000000000dd63f988","authWitnesses":["0x0524e101e8a7a6b31b77bb25f974681f2d6829bb260e21bc6332d9cd8768355d"]} 19:25:59 [19:25:59.034] INFO: node Stopping 19:25:59 [19:25:59.035] INFO: slasher Slasher client stopped. 19:25:59 [19:25:59.035] INFO: sequencer Stopped sequencer 19:25:59 [19:25:59.035] INFO: p2p P2P client stopped. 19:25:59 [19:25:59.037] INFO: world_state Stopped world state synchronizer 19:25:59 [19:25:59.037] INFO: archiver Stopped. 19:25:59 [19:25:59.037] INFO: node Stopped 19:25:59 [19:25:59.037] VERBOSE: e2e:e2e_keys Cleaning up ACVM state 19:25:59 [19:25:59.038] INFO: e2e:e2e_keys Cleaning up ACVM temp directory /tmp/54832a63 19:25:59 [19:25:59.038] VERBOSE: e2e:e2e_keys Cleaning up BB state 19:25:59 [19:25:59.049] INFO: blob-sink:server Stopping blob sink 19:25:59 [19:25:59.050] INFO: blob-sink:server Blob sink stopped 19:25:59 [19:25:59.050] VERBOSE: e2e:e2e_keys Cleaning up data directory at /tmp/be673f9a6ae9f2c4 19:25:59 PASS src/e2e_keys.test.ts 19:25:59 Keys 19:25:59 using nsk_app to detect nullification 19:25:59 ✓ nsk_app and contract address are enough to detect note nullification (9409 ms) 19:25:59 ovsk_app 19:25:59 ✓ gets ovsk_app (2136 ms) 19:25:59 19:25:59 Test Suites: 1 passed, 1 total 19:25:59 Tests: 2 passed, 2 total 19:25:59 Snapshots: 0 total 19:25:59 Time: 35.908 s 19:25:59 Ran all test suites matching /src\/e2e_keys.test.ts/i. 19:25:59 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?