Parent Log: http://ci.aztec-labs.com/90a1e84efe306bbd Command: 97e7ef9a556fd7e6: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/a229cf871638d3fd774f9272645cc1ac29b8196a Env: REF_NAME=gh-readonly-queue/master/pr-14912-015cb0cadef6a41094c3649f32a735392e1b9693 CURRENT_VERSION=0.87.9 CI_FULL=1 Date: Wed Jun 18 16:00:00 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14912_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c393731fea71056e_master 16:00:00 +++ id -u 16:00:00 +++ id -g 16:00:00 ++ 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'\''' 16:00:01 + cid=e3b00f426d4337ff2052f2fd76cf4ac017bc4f2097554660216f8f98680bd307 16:00:01 + set +x 16:00:02 {"level":25,"time":1750262402096,"pid":12,"hostname":"e3b00f426d43","module":"logger","msg":"Logger initialized with level verbose"} 16:00:09 [16:00:09.056] INFO: e2e:e2e_keys Logging metrics to log/unknown.jsonl 16:00:09 [16:00:09.348] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-39Y9BH/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 16:00:09 [16:00:09.395] INFO: e2e:e2e_keys Calling anvil_setBlockTimestampInterval with params: [12] 16:00:09 [16:00:09.424] WARN: e2e:e2e_keys Set block interval to 12 16:00:09 [16:00:09.425] VERBOSE: e2e:e2e_keys Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 16:00:09 [16:00:09.471] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 16:00:09 [16:00:09.481] VERBOSE: e2e:e2e_keys Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 16:00:09 [16:00:09.509] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 16:00:09 [16:00:09.516] VERBOSE: e2e:e2e_keys Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 16:00:09 [16:00:09.532] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 16:00:09 [16:00:09.536] VERBOSE: e2e:e2e_keys Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 16:00:09 [16:00:09.549] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 16:00:09 [16:00:09.554] VERBOSE: e2e:e2e_keys Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 16:00:09 [16:00:09.578] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 16:00:09 [16:00:09.587] VERBOSE: e2e:e2e_keys Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 16:00:09 [16:00:09.608] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 16:00:09 [16:00:09.617] VERBOSE: e2e:e2e_keys Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 16:00:09 [16:00:09.619] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 16:00:09 [16:00:09.619] INFO: e2e:e2e_keys Waiting for 6 transactions to be mined... 16:00:09 [16:00:09.654] INFO: e2e:e2e_keys All transactions mined successfully 16:00:09 [16:00:09.670] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 16:00:09 [16:00:09.670] VERBOSE: e2e:e2e_keys Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 16:00:09 [16:00:09.685] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 16:00:09 [16:00:09.705] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 16:00:09 [16:00:09.719] VERBOSE: e2e:e2e_keys Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 16:00:09 [16:00:09.748] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 16:00:09 [16:00:09.748] VERBOSE: e2e:e2e_keys Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 16:00:09 [16:00:09.822] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 16:00:09 [16:00:09.836] VERBOSE: e2e:e2e_keys Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 16:00:09 [16:00:09.862] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 16:00:09 [16:00:09.862] VERBOSE: e2e:e2e_keys Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 16:00:09 [16:00:09.863] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 16:00:09 [16:00:09.863] INFO: e2e:e2e_keys Waiting for 8 transactions to be mined... 16:00:09 [16:00:09.895] INFO: e2e:e2e_keys All transactions mined successfully 16:00:09 [16:00:09.914] VERBOSE: e2e:e2e_keys Deployed shared contracts 16:00:09 [16:00:09.940] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 16:00:09 [16:00:09.941] VERBOSE: e2e:e2e_keys Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 16:00:09 [16:00:09.946] 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} 16:00:09 [16:00:09.964] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 16:00:09 [16:00:09.996] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 16:00:10 [16:00:10.002] VERBOSE: e2e:e2e_keys Awaiting for linked libraries to be deployed 16:00:10 [16:00:10.058] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x75b26323f77a77f5107cb7b127d6db3a0b86c5b9f03799b6875837ef5e9b1093 {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 16:00:10 [16:00:10.068] 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} 16:00:10 [16:00:10.070] INFO: e2e:e2e_keys Waiting for 9 transactions to be mined... 16:00:10 [16:00:10.096] INFO: e2e:e2e_keys All transactions mined successfully 16:00:10 [16:00:10.097] VERBOSE: e2e:e2e_keys All core contracts have been deployed 16:00:10 [16:00:10.137] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 16:00:10 [16:00:10.138] VERBOSE: e2e:e2e_keys Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 16:00:10 [16:00:10.179] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 16:00:10 [16:00:10.197] VERBOSE: e2e:e2e_keys Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 16:00:10 [16:00:10.236] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 16:00:10 [16:00:10.237] VERBOSE: e2e:e2e_keys Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 16:00:10 [16:00:10.237] INFO: e2e:e2e_keys Waiting for 10 transactions to be mined... 16:00:10 [16:00:10.275] INFO: e2e:e2e_keys All transactions mined successfully 16:00:10 [16:00:10.285] VERBOSE: e2e:e2e_keys Rollup deployed 16:00:10 [16:00:10.285] VERBOSE: e2e:e2e_keys Waiting for rollup and slash factory to be deployed 16:00:10 [16:00:10.286] INFO: e2e:e2e_keys Waiting for 10 transactions to be mined... 16:00:10 [16:00:10.316] INFO: e2e:e2e_keys All transactions mined successfully 16:00:10 [16:00:10.317] VERBOSE: e2e:e2e_keys All transactions for L1 deployment have been mined 16:00:10 [16:00:10.363] 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"} 16:00:10 [16:00:10.367] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 16:00:10 [16:00:10.370] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:10 [16:00:10.372] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 16:00:10 [16:00:10.373] INFO: telemetry:client Using NoopTelemetryClient 16:00:10 [16:00:10.382] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 16:00:10 [16:00:10.383] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 16:00:10 [16:00:10.385] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/91d16a1f324c838a/blob-sink with map size 134217728 KB (LMDB v2) 16:00:10 [16:00:10.388] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:10 [16:00:10.391] INFO: aztecjs:utils:watcher New L1 time: 1750262649 16:00:10 [16:00:10.392] WARN: foundation:test-date-provider Time set to 2025-06-18T16:04:09.000Z {"offset":238608,"timeMs":1750262649000} 16:00:10 [16:00:10.398] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 16:00:10 [16:00:10.408] INFO: blob-sink:server Server is running on http://localhost:41089 16:00:10 [16:00:10.408] VERBOSE: e2e:e2e_keys Creating and synching an aztec node... 16:00:10 [16:00:10.413] VERBOSE: e2e:e2e_keys Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/1f81d7fe/acvm 16:00:10 [16:00:10.415] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:41089"} 16:00:10 [16:00:10.435] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 16:00:10 [16:00:10.436] INFO: archiver:lmdb Creating archiver data store at directory /tmp/91d16a1f324c838a/archiver with map size 134217728 KB (LMDB v2) 16:00:10 [16:00:10.445] INFO: archiver:lmdb Starting data store with maxReaders 16 16:00:10 [16:00:10.594] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:10 [16:00:10.828] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:10 [16:00:10.848] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:41089"} 16:00:10 [16:00:10.865] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:41089"} 16:00:10 [16:00:10.866] WARN: node:blob-sink:client No L1 consensus host urls configured 16:00:10 [16:00:10.866] WARN: node:blob-sink:client No archive client configured 16:00:10 [16:00:10.869] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0xc848170123720adc57d6419875fbb124d82299fd8b97d1e342524d3f57ccc617 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0xe94b1f73c887835c1297787d0589acf0faf3fe725a4aa37ec57db2149298b4c7","l1BlockNumber":16}} 16:00:10 [16:00:10.919] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 16:00:10 [16:00:10.940] INFO: world-state:database Creating world state data store at directory /tmp/91d16a1f324c838a/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 16:00:10 [16:00:10.975] INFO: world_state Created world state synchroniser with block history of 64 16:00:10 [16:00:10.975] WARN: node Aztec node is accepting fake proofs 16:00:10 [16:00:10.987] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/91d16a1f324c838a/p2p with map size 134217728 KB (LMDB v2) 16:00:10 [16:00:10.990] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 16:00:10 [16:00:10.991] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/91d16a1f324c838a/p2p-archive with map size 134217728 KB (LMDB v2) 16:00:10 [16:00:10.992] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 16:00:10 [16:00:10.994] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/91d16a1f324c838a/p2p-peers with map size 134217728 KB (LMDB v2) 16:00:10 [16:00:10.995] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 16:00:11 [16:00:11.005] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 16:00:11 [16:00:11.007] VERBOSE: p2p P2P is disabled. Using dummy P2P service 16:00:11 [16:00:11.011] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 16:00:11 [16:00:11.012] INFO: world_state Started world state synchronizer from block 1 16:00:11 [16:00:11.014] VERBOSE: world-state:block_stream Starting sync from block number 0 16:00:11 [16:00:11.016] INFO: p2p Starting p2p client from block 0 with empty mempools 16:00:11 [16:00:11.017] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 16:00:11 [16:00:11.018] INFO: slasher Slasher client initialized 16:00:11 [16:00:11.018] INFO: slasher Starting Slasher client... 16:00:11 [16:00:11.075] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 16:00:11 [16:00:11.076] VERBOSE: node All Aztec Node subsystems synced 16:00:11 [16:00:11.108] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:11 [16:00:11.121] INFO: sequencer-client Deploying forwarder contract 16:00:11 [16:00:11.149] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 16:00:11 [16:00:11.174] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 16:00:11 [16:00:11.175] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 16:00:11 [16:00:11.195] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 16:00:11 [16:00:11.229] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 16:00:11 [16:00:11.231] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 16:00:11 [16:00:11.232] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/1f81d7fe/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 16:00:11 [16:00:11.521] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 16:00:11 [16:00:11.549] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 16:00:11 [16:00:11.555] INFO: node Aztec Node version: 0.87.9 16:00:11 [16:00:11.555] 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"} 16:00:11 [16:00:11.562] VERBOSE: e2e:e2e_keys Creating a pxe... 16:00:11 [16:00:11.572] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:11 [16:00:11.592] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/2c0ee33882bd089e/pxe_data with map size 134217728 KB (LMDB v2) 16:00:11 [16:00:11.612] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x0edc66b3d7fc0e690a89f920b074b2169355c9c4713856658575cb8246521433","blockNumber":1,"slot":2} 16:00:11 [16:00:11.618] INFO: pxe:data:lmdb Starting data store with maxReaders 16 16:00:11 [16:00:11.805] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:11 [16:00:11.952] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 16:00:11 [16:00:11.957] INFO: pxe:service Started PXE connected to chain 31337 version 4008603340 16:00:12 [16:00:12.062] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:12 [16:00:12.196] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x0edc66b3d7fc0e690a89f920b074b2169355c9c4713856658575cb8246521433","blockNumber":1,"slot":2} 16:00:12 [16:00:12.210] INFO: pxe:service Registered account 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb 16:00:12 [16:00:12.342] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:12 [16:00:12.350] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 16:00:12 [16:00:12.359] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb 16:00:12 [16:00:12.553] INFO: pxe:service Added contract SchnorrAccount at 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 16:00:12 [16:00:12.557] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:12 [16:00:12.627] VERBOSE: pxe:block_stream Starting sync from block number 0 16:00:12 [16:00:12.685] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 16:00:12 [16:00:12.778] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:12 [16:00:12.844] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x0edc66b3d7fc0e690a89f920b074b2169355c9c4713856658575cb8246521433","blockNumber":1,"slot":2} 16:00:12 [16:00:12.861] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 16:00:13 [16:00:13.762] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 16:00:13 [16:00:13.915] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 16:00:14 [16:00:14.056] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x0edc66b3d7fc0e690a89f920b074b2169355c9c4713856658575cb8246521433","blockNumber":1,"slot":2} 16:00:14 [16:00:14.184] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:14 [16:00:14.240] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:14 [16:00:14.249] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:14 [16:00:14.250] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:14 [16:00:14.319] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:14 [16:00:14.327] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:14 [16:00:14.465] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:14 [16:00:14.553] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:14 [16:00:14.595] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:14 [16:00:14.596] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:14 [16:00:14.637] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x0edc66b3d7fc0e690a89f920b074b2169355c9c4713856658575cb8246521433","blockNumber":1,"slot":2} 16:00:14 [16:00:14.646] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:14 [16:00:14.649] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:14 [16:00:14.664] VERBOSE: simulator:client_execution_context:debug_log Setting 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb as fee payer 16:00:14 [16:00:14.684] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:14 [16:00:14.921] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:15 [16:00:15.405] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:15 [16:00:15.628] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x0edc66b3d7fc0e690a89f920b074b2169355c9c4713856658575cb8246521433","blockNumber":1,"slot":2} 16:00:15 [16:00:15.824] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:16 [16:00:16.368] INFO: pxe:service Proving completed in 3755.9775439999994ms {"timings":{"total":3755.9775439999994,"sync":16.266217000000324,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":212.90335700000105,"oracles":{"callPrivateFunction":{"times":[1275.695651,444.31749200000013,210.2733879999996]}}},{"functionName":"private_kernel_init","time":140.67524800000137},{"functionName":"ContractClassRegisterer:register","time":1241.411927000001,"oracles":{"loadCapsule":{"times":[58.8179999999993]},"notifyCreatedNullifier":{"times":[1.1826750000000175]},"debugLog":{"times":[1.2930730000007316]},"notifyCreatedContractClassLog":{"times":[20.05004899999949]}}},{"functionName":"private_kernel_inner","time":330.09084299999995},{"functionName":"SchnorrAccount:constructor","time":398.95703599999797,"oracles":{"getContractInstance":{"times":[3.8849599999994098]},"debugLog":{"times":[0.6106090000012045,0.7545199999985925]},"fetchTaggedLogs":{"times":[74.94970699999976]},"loadCapsule":{"times":[0.5701159999989613,0.38922199999979057,0.31928599999992]},"notifyCreatedNullifier":{"times":[0.3371470000001864,0.3601679999992484]},"notifyCreatedNote":{"times":[0.7207369999996445]},"getRandomField":{"times":[0.4140929999994114,0.3173449999994773,0.10360800000125892,0.10700800000086019,0.07782700000097975,0.06946600000082981,0.08908700000029057,0.05553500000132772,0.08591700000033597,0.08405599999969127,0.10491799999908835]},"getIndexedTaggingSecretAsSender":{"times":[60.55044799999996]},"incrementAppTaggingSecretIndexAsSender":{"times":[32.64773299999979]}}},{"functionName":"private_kernel_inner","time":189.3013659999997},{"functionName":"SchnorrAccount:entrypoint","time":189.54575599999953,"oracles":{"debugLog":{"times":[0.6950660000002244,0.4857479999991483,0.7698709999986022]},"fetchTaggedLogs":{"times":[52.24081599999954]},"loadCapsule":{"times":[0.2566500000011729,0.22891800000070361,0.248330000000351]},"getNotes":{"times":[1.896190999999817]},"getAuthWitness":{"times":[0.293402999999671]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.9649269999990793]}}},{"functionName":"private_kernel_inner","time":156.72430900000109},{"functionName":"private_kernel_reset","time":207.34518500000013},{"functionName":"private_kernel_tail","time":70.27395400000023}],"unaccounted":602.482345999997}} 16:00:16 [16:00:16.381] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:16 [16:00:16.452] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x0edc66b3d7fc0e690a89f920b074b2169355c9c4713856658575cb8246521433","blockNumber":1,"slot":2} 16:00:16 [16:00:16.508] VERBOSE: p2p:tx_pool Adding tx 0x1599c451c430f8aa811af89b4c93f006e7549827d2a2b546fdeacd65f46ce708 to pool {"eventName":"tx-added-to-pool","txHash":"0x1599c451c430f8aa811af89b4c93f006e7549827d2a2b546fdeacd65f46ce708","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 16:00:16 [16:00:16.528] INFO: node Received tx 0x1599c451c430f8aa811af89b4c93f006e7549827d2a2b546fdeacd65f46ce708 {"txHash":"0x1599c451c430f8aa811af89b4c93f006e7549827d2a2b546fdeacd65f46ce708"} 16:00:16 [16:00:16.529] INFO: pxe:service Sent transaction 0x1599c451c430f8aa811af89b4c93f006e7549827d2a2b546fdeacd65f46ce708 16:00:16 [16:00:16.590] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:16 [16:00:16.794] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:16 [16:00:16.971] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 16:00:16 [16:00:16.983] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-18T16:04:33.000Z","now":"2025-06-18T16:04:15.591Z"} 16:00:17 [16:00:17.038] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:17 [16:00:17.219] VERBOSE: simulator:public-processor Processed tx 0x1599c451c430f8aa811af89b4c93f006e7549827d2a2b546fdeacd65f46ce708 with no public calls in 166.99799799999892ms {"txHash":"0x1599c451c430f8aa811af89b4c93f006e7549827d2a2b546fdeacd65f46ce708","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":166.99799799999892} 16:00:17 [16:00:17.222] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.237555894000001s {"duration":0.237555894000001,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 16:00:17 [16:00:17.251] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:17 [16:00:17.431] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x262ed485bbc3f9dfff17793beda1e35337cb867c8c58f8c79fd59cfd9706aa3c","globalVariables":{"chainId":31337,"version":4008603340,"blockNumber":1,"slotNumber":2,"timestamp":1750262673,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1599c451c430f8aa811af89b4c93f006e7549827d2a2b546fdeacd65f46ce708"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":460.1725869999991,"publicProcessDuration":238.71921699999984,"rollupCircuitsDuration":448.2734880000007,"txCount":1,"blockNumber":1,"blockTimestamp":1750262673,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 16:00:17 [16:00:17.436] VERBOSE: sequencer Attesting committee is empty 16:00:17 [16:00:17.581] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:17 [16:00:17.854] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:18 [16:00:18.155] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:18 [16:00:18.164] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x4650acc160c0b70cb9e1432b82c7513f1783ac6657f2e088c6cf6f4ff058225a 16:00:18 [16:00:18.165] VERBOSE: sequencer-client Sent L1 transaction 0x4650acc160c0b70cb9e1432b82c7513f1783ac6657f2e088c6cf6f4ff058225a {"gasLimit":514585,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 16:00:18 [16:00:18.183] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 16:00:18 [16:00:18.185] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x4650acc160c0b70cb9e1432b82c7513f1783ac6657f2e088c6cf6f4ff058225a","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":1,"blockTimestamp":1750262673,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 16:00:18 [16:00:18.186] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 16:00:18 [16:00:18.190] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262709] on http://127.0.0.1:8545 16:00:18 [16:00:18.193] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 16:00:18 [16:00:18.196] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750262709 16:00:18 [16:00:18.196] WARN: foundation:test-date-provider Time set to 2025-06-18T16:05:09.000Z {"offset":290804,"timeMs":1750262709000} 16:00:18 [16:00:18.196] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 16:00:18 [16:00:18.213] INFO: blob-sink:server Received blob sidecar for block 0x53267d09d5e39e9fa735a88dddbac0811652ec725d2736313bbd110144f5cc6f 16:00:18 [16:00:18.221] INFO: blob-sink:server Blob sidecar stored successfully for block 0x53267d09d5e39e9fa735a88dddbac0811652ec725d2736313bbd110144f5cc6f 16:00:18 [16:00:18.365] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:18 [16:00:18.371] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 16:00:18 [16:00:18.373] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 16:00:18 [16:00:18.375] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 16:00:18 [16:00:18.611] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:18 [16:00:18.633] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 16:00:18 [16:00:18.648] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x262ed485bbc3f9dfff17793beda1e35337cb867c8c58f8c79fd59cfd9706aa3c","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":4008603340,"blockNumber":1,"slotNumber":2,"timestamp":1750262673,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2b3ace6c59714c44fc582de30560bcb3c56514773dbacf45951617ec18402a41","archiveNextLeafIndex":2} 16:00:18 [16:00:18.655] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 16:00:19 [16:00:19.172] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:19 [16:00:19.179] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 16:00:19 [16:00:19.183] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703 16:00:19 [16:00:19.391] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:19 [16:00:19.394] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":217.19654000000082,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750262673,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 16:00:19 [16:00:19.500] VERBOSE: p2p Synched to latest block 1 16:00:19 [16:00:19.799] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:19 [16:00:19.818] INFO: pxe:service Added contract Test at 0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703 with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 16:00:19 [16:00:19.865] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x2b3ace6c59714c44fc582de30560bcb3c56514773dbacf45951617ec18402a41","header":{"contentCommitment":{"blobsHash":"0x0050ab17c680874c7d5d7c08776955f4bc77c304b4dd3f7f413eb76af97631fc","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750262673,"version":4008603340},"lastArchive":"0x0edc66b3d7fc0e690a89f920b074b2169355c9c4713856658575cb8246521433","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x0e30ffc76eb3dcf65247e09fa4b137e34e03825e35422e51db8c98d94f2f940a","nullifierTree":"0x0c3c4191eaadfae9fad7b00b67a305e6511f57a2fd4040dffa10ab620d6a0035","publicDataTree":"0x197eacccbb43449de2e90f05e364f54f4cf7f2d35709ddb4dccb0cde54a53bbf"},"totalFees":6794052480,"totalManaUsed":20448}} 16:00:20 [16:00:20.006] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:20 [16:00:20.049] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:20 [16:00:20.050] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:20 [16:00:20.053] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:20 [16:00:20.145] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2b3ace6c59714c44fc582de30560bcb3c56514773dbacf45951617ec18402a41","blockNumber":2,"slot":3} 16:00:20 [16:00:20.182] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:20 [16:00:20.187] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2796b31d268df9b1b11f5dd893521f339312d5f694247f695d3a654b4abe3a54 16:00:20 [16:00:20.204] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 16:00:20 [16:00:20.205] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 16:00:20 [16:00:20.219] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 16:00:20 [16:00:20.219] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 16:00:20 [16:00:20.225] VERBOSE: pxe:service Added note {"index":0,"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x04e2149dfb7ebeb9098f96c5337338d3830b4fb9b11180af85374bcb619898d4","nullifier":"0x203b9aa5bae0315800e05a14e77d3e416a6392c32e7d5cedc2afb93e8ffad19e"} 16:00:20 [16:00:20.228] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:20 [16:00:20.249] VERBOSE: simulator:client_execution_context:debug_log Setting 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb as fee payer 16:00:20 [16:00:20.267] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:20 [16:00:20.291] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 16:00:20 [16:00:20.777] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 16:00:20 [16:00:20.917] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 16:00:21 [16:00:21.004] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:21 [16:00:21.079] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 16:00:21 [16:00:21.093] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2b3ace6c59714c44fc582de30560bcb3c56514773dbacf45951617ec18402a41","blockNumber":2,"slot":3} 16:00:21 [16:00:21.118] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 16:00:21 [16:00:21.131] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703,0x0000000000000000000000000000000000000000000000000000000000000001,0x25717bea467bc49f46640cf07ad01485d0441ed8f9b649dd357274392000cdd1,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb 16:00:21 [16:00:21.306] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:21 [16:00:21.932] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:21 [16:00:21.971] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:21 [16:00:21.975] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:21 [16:00:21.976] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:22 [16:00:22.021] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2b3ace6c59714c44fc582de30560bcb3c56514773dbacf45951617ec18402a41","blockNumber":2,"slot":3} 16:00:22 [16:00:22.233] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:22 [16:00:22.270] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:22 [16:00:22.276] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:22 [16:00:22.448] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:22 [16:00:22.678] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:22 [16:00:22.718] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2b3ace6c59714c44fc582de30560bcb3c56514773dbacf45951617ec18402a41","blockNumber":2,"slot":3} 16:00:22 [16:00:22.915] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:23 [16:00:23.184] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:23 [16:00:23.440] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:23 [16:00:23.583] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2b3ace6c59714c44fc582de30560bcb3c56514773dbacf45951617ec18402a41","blockNumber":2,"slot":3} 16:00:23 [16:00:23.803] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:24 [16:00:24.430] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:24 [16:00:24.726] INFO: pxe:service Proving completed in 4887.9431ms {"timings":{"total":4887.9431,"sync":35.06153600000107,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":641.4915360000014,"oracles":{"debugLog":{"times":[0.8592580000004091,0.6698429999996733,0.6733940000012808,0.7283779999997932,0.6586320000023989,0.5066210000004503,0.5695159999995667,0.8120149999995192]},"fetchTaggedLogs":{"times":[134.6372169999995]},"loadCapsule":{"times":[0.26252000000022235,0.4477260000021488,0.5840769999995246,0.3871709999984887,0.3633490000029269,0.32925600000089617]},"getSharedSecret":{"times":[5.5307209999991755]},"aes128Decrypt":{"times":[1.2608499999987544,1.2128569999986212]},"getPublicKeysAndPartialAddress":{"times":[6.265790000001289]},"getKeyValidationRequest":{"times":[3.1470010000011825]},"deliverNote":{"times":[5.673911999998381]},"deleteCapsule":{"times":[0.6291609999971115]},"storeCapsule":{"times":[0.5591150000000198]},"getNotes":{"times":[2.4013210000011895]},"getAuthWitness":{"times":[0.2979039999991073]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.10095799999908195]},"callPrivateFunction":{"times":[744.2735529999991,142.91549700000178,1144.6225889999987]}}},{"functionName":"private_kernel_init","time":110.60799100000077},{"functionName":"ContractClassRegisterer:register","time":727.7064309999987,"oracles":{"loadCapsule":{"times":[4.442453999999998]},"notifyCreatedNullifier":{"times":[0.8076039999978093]},"debugLog":{"times":[0.8005129999983183]},"notifyCreatedContractClassLog":{"times":[11.970394999996643]}}},{"functionName":"private_kernel_inner","time":190.6812659999996},{"functionName":"ContractInstanceDeployer:deploy","time":92.192202000002,"oracles":{"storeInExecutionCache":{"times":[0.2722009999997681]},"callPrivateFunction":{"times":[39.5489439999983]},"notifyCreatedNullifier":{"times":[0.31049500000153785]},"debugLog":{"times":[0.5822469999984605]}}},{"functionName":"private_kernel_inner","time":140.43099799999982},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":30.250622999996267,"oracles":{}},{"functionName":"private_kernel_inner","time":161.35280799999964},{"functionName":"Test:initialize","time":882.5222070000018,"oracles":{"getContractInstance":{"times":[12.024599000000308]},"debugLog":{"times":[0.5278719999987516,0.880460000000312]},"fetchTaggedLogs":{"times":[298.45140999999785]},"loadCapsule":{"times":[0.2622410000003583,0.6950059999981022,0.6713930000005348]},"notifyCreatedNullifier":{"times":[0.3650789999992412]}}},{"functionName":"private_kernel_inner","time":168.8329240000021},{"functionName":"private_kernel_reset","time":228.34223900000143},{"functionName":"private_kernel_tail","time":70.00968099999955}],"unaccounted":1408.4606579999963}} 16:00:24 [16:00:24.745] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:24 [16:00:24.752] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x2b3ace6c59714c44fc582de30560bcb3c56514773dbacf45951617ec18402a41","blockNumber":2,"slot":3} 16:00:24 [16:00:24.883] VERBOSE: p2p:tx_pool Adding tx 0x217727d394951c8d6e1fcd6a69191fa71a888ff884750266cc326edf9cbc7ea1 to pool {"eventName":"tx-added-to-pool","txHash":"0x217727d394951c8d6e1fcd6a69191fa71a888ff884750266cc326edf9cbc7ea1","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 16:00:24 [16:00:24.901] INFO: node Received tx 0x217727d394951c8d6e1fcd6a69191fa71a888ff884750266cc326edf9cbc7ea1 {"txHash":"0x217727d394951c8d6e1fcd6a69191fa71a888ff884750266cc326edf9cbc7ea1"} 16:00:24 [16:00:24.901] INFO: pxe:service Sent transaction 0x217727d394951c8d6e1fcd6a69191fa71a888ff884750266cc326edf9cbc7ea1 16:00:24 [16:00:24.953] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:25 [16:00:25.155] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:25 [16:00:25.279] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 16:00:25 [16:00:25.302] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-18T16:05:09.000Z","now":"2025-06-18T16:05:16.106Z"} 16:00:25 [16:00:25.401] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:25 [16:00:25.614] VERBOSE: simulator:public-processor Processed tx 0x217727d394951c8d6e1fcd6a69191fa71a888ff884750266cc326edf9cbc7ea1 with no public calls in 199.11312700000053ms {"txHash":"0x217727d394951c8d6e1fcd6a69191fa71a888ff884750266cc326edf9cbc7ea1","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":199.11312700000053} 16:00:25 [16:00:25.617] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:25 [16:00:25.619] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.3155130960000024s {"duration":0.3155130960000024,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 16:00:25 [16:00:25.781] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x0857cb73173da81d53cdbcd8f3c8e9d4942f3a060c1776b4ad8759647bc016fb","globalVariables":{"chainId":31337,"version":4008603340,"blockNumber":2,"slotNumber":3,"timestamp":1750262709,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x217727d394951c8d6e1fcd6a69191fa71a888ff884750266cc326edf9cbc7ea1"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":506.4078039999986,"publicProcessDuration":316.0384379999996,"rollupCircuitsDuration":480.13351899999907,"txCount":1,"blockNumber":2,"blockTimestamp":1750262709,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 16:00:25 [16:00:25.785] VERBOSE: sequencer Attesting committee is empty 16:00:25 [16:00:25.898] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:26 [16:00:26.106] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:26 [16:00:26.110] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x887e4633af38648eed6c390ff46261d1e4f8067a6294deadf2d9a9090f0f4e47 16:00:26 [16:00:26.111] VERBOSE: sequencer-client Sent L1 transaction 0x887e4633af38648eed6c390ff46261d1e4f8067a6294deadf2d9a9090f0f4e47 {"gasLimit":470234,"maxFeePerGas":"1.303611208","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 16:00:26 [16:00:26.126] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 16:00:26 [16:00:26.126] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x887e4633af38648eed6c390ff46261d1e4f8067a6294deadf2d9a9090f0f4e47","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":2,"blockTimestamp":1750262709,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 16:00:26 [16:00:26.127] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 16:00:26 [16:00:26.141] INFO: blob-sink:server Received blob sidecar for block 0x4865206cae2e0157ef4e1a4f97b3ef8330b8c3961f6effd8f7cc1cd38bdf3730 16:00:26 [16:00:26.153] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262745] on http://127.0.0.1:8545 16:00:26 [16:00:26.154] INFO: blob-sink:server Blob sidecar stored successfully for block 0x4865206cae2e0157ef4e1a4f97b3ef8330b8c3961f6effd8f7cc1cd38bdf3730 16:00:26 [16:00:26.156] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 16:00:26 [16:00:26.158] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750262745 16:00:26 [16:00:26.158] WARN: foundation:test-date-provider Time set to 2025-06-18T16:05:45.000Z {"offset":318842,"timeMs":1750262745000} 16:00:26 [16:00:26.159] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 16:00:26 [16:00:26.311] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:26 [16:00:26.317] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 16:00:26 [16:00:26.323] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 16:00:26 [16:00:26.325] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 16:00:26 [16:00:26.619] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:26 [16:00:26.643] VERBOSE: archiver:block-helper Store contract instance at 0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703 16:00:26 [16:00:26.667] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 16:00:26 [16:00:26.709] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x0857cb73173da81d53cdbcd8f3c8e9d4942f3a060c1776b4ad8759647bc016fb","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":4008603340,"blockNumber":2,"slotNumber":3,"timestamp":1750262709,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x1d0b85d3f8bde9d284fab39664c24828eac17c74c97b22c554e314c8ff562bdd","archiveNextLeafIndex":3} 16:00:26 [16:00:26.730] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 16:00:26 [16:00:26.746] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":6.829107000001386,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750262709,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 16:00:26 [16:00:26.827] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:26 [16:00:26.833] VERBOSE: p2p Synched to latest block 2 16:00:27 [16:00:27.141] INFO: aztecjs:deploy_sent_tx Contract 0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703 successfully deployed. 16:00:27 [16:00:27.143] INFO: e2e:e2e_keys Running test: Keys using nsk_app to detect nullification nsk_app and contract address are enough to detect note nullification 16:00:27 [16:00:27.146] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:27 [16:00:27.172] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x1d0b85d3f8bde9d284fab39664c24828eac17c74c97b22c554e314c8ff562bdd","header":{"contentCommitment":{"blobsHash":"0x0019df91394c4779a1f8a768ad8eec3ad0459d61b04557633d49b7403e4b3f91","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750262709,"version":4008603340},"lastArchive":"0x2b3ace6c59714c44fc582de30560bcb3c56514773dbacf45951617ec18402a41","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x0e30ffc76eb3dcf65247e09fa4b137e34e03825e35422e51db8c98d94f2f940a","nullifierTree":"0x1304776dcb59e610919990f18538a861201854ff0e089f68bf842654764b1937","publicDataTree":"0x30530d473319b7792e960733846110d2aa7a6da7803c69d3a9b0215cf059801a"},"totalFees":7272506880,"totalManaUsed":21888}} 16:00:27 [16:00:27.265] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:27 [16:00:27.309] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:27 [16:00:27.310] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:27 [16:00:27.350] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:27 [16:00:27.360] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x1d0b85d3f8bde9d284fab39664c24828eac17c74c97b22c554e314c8ff562bdd","blockNumber":3,"slot":4} 16:00:27 [16:00:27.396] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:27 [16:00:27.408] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2796b31d268df9b1b11f5dd893521f339312d5f694247f695d3a654b4abe3a54 16:00:27 [16:00:27.421] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 16:00:27 [16:00:27.422] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 16:00:27 [16:00:27.433] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 16:00:27 [16:00:27.434] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 16:00:27 [16:00:27.438] VERBOSE: pxe:service Added note {"index":0,"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x04e2149dfb7ebeb9098f96c5337338d3830b4fb9b11180af85374bcb619898d4","nullifier":"0x203b9aa5bae0315800e05a14e77d3e416a6392c32e7d5cedc2afb93e8ffad19e"} 16:00:27 [16:00:27.440] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:27 [16:00:27.459] VERBOSE: simulator:client_execution_context:debug_log Setting 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb as fee payer 16:00:27 [16:00:27.660] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:28 [16:00:28.267] VERBOSE: simulator:private_execution Executing private function Test:call_create_note {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:28 [16:00:28.318] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:28 [16:00:28.319] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:28 [16:00:28.329] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:28 [16:00:28.534] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:28 [16:00:28.540] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x1d0b85d3f8bde9d284fab39664c24828eac17c74c97b22c554e314c8ff562bdd","blockNumber":3,"slot":4} 16:00:28 [16:00:28.566] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:28 [16:00:28.577] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:28 [16:00:28.798] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:29 [16:00:29.035] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:29 [16:00:29.189] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x1d0b85d3f8bde9d284fab39664c24828eac17c74c97b22c554e314c8ff562bdd","blockNumber":3,"slot":4} 16:00:29 [16:00:29.426] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:29 [16:00:29.848] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:30 [16:00:30.017] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x1d0b85d3f8bde9d284fab39664c24828eac17c74c97b22c554e314c8ff562bdd","blockNumber":3,"slot":4} 16:00:30 [16:00:30.306] INFO: pxe:service Proving completed in 3140.961144000001ms {"timings":{"total":3140.961144000001,"sync":9.748350999998365,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":498.9781980000007,"oracles":{"debugLog":{"times":[0.7982840000004217,0.6469519999991462,0.6886750000012398,0.7978150000017195,0.6583419999988109,0.543283999999403,0.5487640000028478,0.7619109999977809]},"fetchTaggedLogs":{"times":[95.46627100000114]},"loadCapsule":{"times":[0.3548780000019178,0.31825600000229315,0.6599630000018806,0.24041900000156602,0.29860399999961373,0.2325879999989411]},"getSharedSecret":{"times":[4.116340000000491]},"aes128Decrypt":{"times":[0.32701600000291364,1.2470900000007532]},"getPublicKeysAndPartialAddress":{"times":[2.2507499999992433]},"getKeyValidationRequest":{"times":[3.9813689999973576]},"deliverNote":{"times":[3.8445380000011937]},"deleteCapsule":{"times":[0.31134499999825493]},"storeCapsule":{"times":[0.5143310000021302]},"getNotes":{"times":[2.3427780000019993]},"getAuthWitness":{"times":[0.28761300000041956]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.1453509999992093]},"callPrivateFunction":{"times":[1553.659134999998]}}},{"functionName":"private_kernel_init","time":73.09153700000024},{"functionName":"Test:call_create_note","time":1324.7861630000007,"oracles":{"debugLog":{"times":[0.6673029999983555,0.6949360000035085]},"fetchTaggedLogs":{"times":[256.3456240000014]},"loadCapsule":{"times":[0.29731399999946007,0.2461989999974321,0.37888999999995576]},"getRandomField":{"times":[0.2492999999994936,0.25156000000060885,0.20137599999725353,0.06166499999744701,0.06461599999965983,0.08076599999913014,0.05522500000006403,0.10488900000200374,0.05277399999977206,0.057444000001851236,0.048004000000219094,0.055653999999776715]},"notifyCreatedNote":{"times":[0.26664200000232086]},"getIndexedTaggingSecretAsSender":{"times":[208.97144800000024]},"incrementAppTaggingSecretIndexAsSender":{"times":[186.67593200000192]}}},{"functionName":"private_kernel_inner","time":141.7440690000003},{"functionName":"private_kernel_reset","time":186.930511999999},{"functionName":"private_kernel_tail","time":102.08056099999885}],"unaccounted":803.6017530000026}} 16:00:30 [16:00:30.321] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:30 [16:00:30.371] VERBOSE: p2p:tx_pool Adding tx 0x1b128eee22258d699a8f53e85dc647eeff5054d87ae6ec4cf755eefe68736e6b to pool {"eventName":"tx-added-to-pool","txHash":"0x1b128eee22258d699a8f53e85dc647eeff5054d87ae6ec4cf755eefe68736e6b","noteHashCount":1,"nullifierCount":1,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 16:00:30 [16:00:30.377] INFO: node Received tx 0x1b128eee22258d699a8f53e85dc647eeff5054d87ae6ec4cf755eefe68736e6b {"txHash":"0x1b128eee22258d699a8f53e85dc647eeff5054d87ae6ec4cf755eefe68736e6b"} 16:00:30 [16:00:30.378] INFO: pxe:service Sent transaction 0x1b128eee22258d699a8f53e85dc647eeff5054d87ae6ec4cf755eefe68736e6b 16:00:30 [16:00:30.529] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:30 [16:00:30.534] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 16:00:30 [16:00:30.550] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-18T16:05:45.000Z","now":"2025-06-18T16:05:49.392Z"} 16:00:30 [16:00:30.584] VERBOSE: simulator:public-processor Processed tx 0x1b128eee22258d699a8f53e85dc647eeff5054d87ae6ec4cf755eefe68736e6b with no public calls in 16.706608999997115ms {"txHash":"0x1b128eee22258d699a8f53e85dc647eeff5054d87ae6ec4cf755eefe68736e6b","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.706608999997115} 16:00:30 [16:00:30.589] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03798731499999849s {"duration":0.03798731499999849,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11264,"l2Gas":9760},"totalSizeInBytes":960} 16:00:30 [16:00:30.726] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1594a4b504010aa6e4279a50e769586e83a16146b341c4ea31af2ea5afb9086b","globalVariables":{"chainId":31337,"version":4008603340,"blockNumber":3,"slotNumber":4,"timestamp":1750262745,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1b128eee22258d699a8f53e85dc647eeff5054d87ae6ec4cf755eefe68736e6b"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":192.08184400000027,"publicProcessDuration":38.744815000001836,"rollupCircuitsDuration":178.6000239999994,"txCount":1,"blockNumber":3,"blockTimestamp":1750262745,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 16:00:30 [16:00:30.728] VERBOSE: sequencer Attesting committee is empty 16:00:30 [16:00:30.837] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:31 [16:00:31.053] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:31 [16:00:31.061] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x0dcf80b1a4882ddd25a391a67db1ba6833a8863f92eadafda942d047e34ce7db 16:00:31 [16:00:31.061] VERBOSE: sequencer-client Sent L1 transaction 0x0dcf80b1a4882ddd25a391a67db1ba6833a8863f92eadafda942d047e34ce7db {"gasLimit":470280,"maxFeePerGas":"1.279583398","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 16:00:31 [16:00:31.073] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 16:00:31 [16:00:31.080] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 16:00:31 [16:00:31.086] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 16:00:31 [16:00:31.088] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262781] on http://127.0.0.1:8545 16:00:31 [16:00:31.092] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 16:00:31 [16:00:31.092] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x0dcf80b1a4882ddd25a391a67db1ba6833a8863f92eadafda942d047e34ce7db","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":3,"blockTimestamp":1750262745,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 16:00:31 [16:00:31.093] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 16:00:31 [16:00:31.096] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 16:00:31 [16:00:31.100] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750262781 16:00:31 [16:00:31.100] WARN: foundation:test-date-provider Time set to 2025-06-18T16:06:21.000Z {"offset":349900,"timeMs":1750262781000} 16:00:31 [16:00:31.101] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 16:00:31 [16:00:31.104] INFO: blob-sink:server Received blob sidecar for block 0xb9e5e2c0509b40327ee7366ebea33fad6f42591b76a52e443b7d7fc6278351ad 16:00:31 [16:00:31.111] INFO: blob-sink:server Blob sidecar stored successfully for block 0xb9e5e2c0509b40327ee7366ebea33fad6f42591b76a52e443b7d7fc6278351ad 16:00:31 [16:00:31.260] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:31 [16:00:31.548] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:31 [16:00:31.562] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x1594a4b504010aa6e4279a50e769586e83a16146b341c4ea31af2ea5afb9086b","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":4008603340,"blockNumber":3,"slotNumber":4,"timestamp":1750262745,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x23d8f582ea3a6c9d8cbaadc56b0a92346271ed200bee2757eca68c97caeb69d4","archiveNextLeafIndex":4} 16:00:31 [16:00:31.563] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 16:00:31 [16:00:31.671] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":11.278653999997914,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750262745,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 16:00:31 [16:00:31.678] VERBOSE: p2p Synched to latest block 3 16:00:31 [16:00:31.753] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:31 [16:00:31.956] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:32 [16:00:32.132] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x23d8f582ea3a6c9d8cbaadc56b0a92346271ed200bee2757eca68c97caeb69d4","blockNumber":4,"slot":5} 16:00:32 [16:00:32.160] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:32 [16:00:32.364] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:32 [16:00:32.432] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x23d8f582ea3a6c9d8cbaadc56b0a92346271ed200bee2757eca68c97caeb69d4","header":{"contentCommitment":{"blobsHash":"0x0089adb2608fa6213038eb5d0414c8a13d65a5c128cdc848ff03a646ed3247b3","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750262745,"version":4008603340},"lastArchive":"0x1d0b85d3f8bde9d284fab39664c24828eac17c74c97b22c554e314c8ff562bdd","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x151452985bbed0d8d77e053b4e647f13c0ed3991d31d4d13aff124b6eb93e1ff","nullifierTree":"0x05231c18acfa459653799b777fab2f727930fb85f1107347b7db644b14af4c94","publicDataTree":"0x195362b7a98f1b6d5c736ec6ffa76c126be9c3518f84f3daa09415a6b99a1c86"},"totalFees":3242857600,"totalManaUsed":9760}} 16:00:32 [16:00:32.556] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:32 [16:00:32.609] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:32 [16:00:32.610] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:32 [16:00:32.614] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:32 [16:00:32.707] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x23d8f582ea3a6c9d8cbaadc56b0a92346271ed200bee2757eca68c97caeb69d4","blockNumber":4,"slot":5} 16:00:32 [16:00:32.753] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:32 [16:00:32.766] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2796b31d268df9b1b11f5dd893521f339312d5f694247f695d3a654b4abe3a54 16:00:32 [16:00:32.774] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 16:00:32 [16:00:32.775] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 16:00:32 [16:00:32.781] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 16:00:32 [16:00:32.782] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 16:00:32 [16:00:32.786] VERBOSE: pxe:service Added note {"index":0,"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x04e2149dfb7ebeb9098f96c5337338d3830b4fb9b11180af85374bcb619898d4","nullifier":"0x203b9aa5bae0315800e05a14e77d3e416a6392c32e7d5cedc2afb93e8ffad19e"} 16:00:32 [16:00:32.851] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:32 [16:00:32.852] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:32 [16:00:32.896] VERBOSE: simulator:client_execution_context:debug_log Setting 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb as fee payer 16:00:33 [16:00:33.070] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:33 [16:00:33.655] VERBOSE: simulator:private_execution Executing private function Test:call_destroy_note {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:33 [16:00:33.711] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:33 [16:00:33.712] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:33 [16:00:33.717] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:33 [16:00:33.925] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:33 [16:00:33.940] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x23d8f582ea3a6c9d8cbaadc56b0a92346271ed200bee2757eca68c97caeb69d4","blockNumber":4,"slot":5} 16:00:33 [16:00:33.976] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:33 [16:00:33.994] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f251f2dc826cef4f6e425d24bb644689b6f77820617a08372bde33b6ade1aff 16:00:34 [16:00:34.016] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 16:00:34 [16:00:34.020] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703 for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 16:00:34 [16:00:34.033] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 16:00:34 [16:00:34.034] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 16:00:34 [16:00:34.039] VERBOSE: pxe:service Added note {"index":128,"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x091d6c8d44f05b5537bbe847b159e103755a5411036dad5a0f1c18597013e22b","nullifier":"0x041fd273ba0d284ec4b39fd3e44d779a0e0ede6eb74ca5644d5175ce6450e61d"} 16:00:34 [16:00:34.041] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:34 [16:00:34.174] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:34 [16:00:34.441] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:34 [16:00:34.822] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:35 [16:00:35.032] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x23d8f582ea3a6c9d8cbaadc56b0a92346271ed200bee2757eca68c97caeb69d4","blockNumber":4,"slot":5} 16:00:35 [16:00:35.036] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:35 [16:00:35.297] INFO: pxe:service Proving completed in 2871.9718459999967ms {"timings":{"total":2871.9718459999967,"sync":9.417985000000044,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":854.0580960000043,"oracles":{"debugLog":{"times":[0.6593130000001111,0.4380239999991318,0.3639489999986836,0.5596649999970396,0.3467079999973066,0.36185899999691173,62.63192999999956,0.4984599999988859]},"fetchTaggedLogs":{"times":[150.72611900000265]},"loadCapsule":{"times":[0.8491680000006454,0.6342710000026273,2.202495999998064,0.48974900000030175,0.350667999999132,3.0492949999970733]},"getSharedSecret":{"times":[3.1265999999995984]},"aes128Decrypt":{"times":[0.26091100000121514,0.7620710000010149]},"getPublicKeysAndPartialAddress":{"times":[1.5447739999981422]},"getKeyValidationRequest":{"times":[2.0876470000002882]},"deliverNote":{"times":[4.668615000002319]},"deleteCapsule":{"times":[0.4136929999986023]},"storeCapsule":{"times":[0.3689489999997022]},"getNotes":{"times":[29.618414000000485]},"getAuthWitness":{"times":[0.2045059999982186]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.10490800000115996]},"callPrivateFunction":{"times":[1188.3540870000033]}}},{"functionName":"private_kernel_init","time":78.25199099999736},{"functionName":"Test:call_destroy_note","time":770.8134649999993,"oracles":{"debugLog":{"times":[0.864119000001665,0.6175999999977648,3.088676999999734,0.8342969999976049,0.6908059999987017,0.35122900000351365,0.41279299999951036]},"fetchTaggedLogs":{"times":[279.86898900000233]},"loadCapsule":{"times":[0.2918740000022808,0.18628399999943213,0.4507159999993746,0.32925600000089617,0.21042599999782396,0.21171700000195415]},"getSharedSecret":{"times":[5.925285000001168]},"aes128Decrypt":{"times":[0.3755810000002384,1.3562090000013995]},"getPublicKeysAndPartialAddress":{"times":[1.733578999999736,4.775513000000501]},"getKeyValidationRequest":{"times":[4.338438000002498,2.556284999998752]},"deliverNote":{"times":[5.750750999999582]},"deleteCapsule":{"times":[0.3056639999995241]},"storeCapsule":{"times":[0.20820699999967474]},"getNotes":{"times":[2.0884169999990263]},"notifyNullifiedNote":{"times":[0.6563319999986561]}}},{"functionName":"private_kernel_inner","time":163.40472500000033},{"functionName":"private_kernel_reset","time":193.8502559999979},{"functionName":"private_kernel_tail","time":73.92912400000205}],"unaccounted":728.2462039999955}} 16:00:35 [16:00:35.315] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:35 [16:00:35.340] VERBOSE: p2p:tx_pool Adding tx 0x01c8b67f960bef2a83551a6d518661522e026347929184250d39a7bdc900f464 to pool {"eventName":"tx-added-to-pool","txHash":"0x01c8b67f960bef2a83551a6d518661522e026347929184250d39a7bdc900f464","noteHashCount":0,"nullifierCount":2,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 16:00:35 [16:00:35.346] INFO: node Received tx 0x01c8b67f960bef2a83551a6d518661522e026347929184250d39a7bdc900f464 {"txHash":"0x01c8b67f960bef2a83551a6d518661522e026347929184250d39a7bdc900f464"} 16:00:35 [16:00:35.346] INFO: pxe:service Sent transaction 0x01c8b67f960bef2a83551a6d518661522e026347929184250d39a7bdc900f464 16:00:35 [16:00:35.519] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:35 [16:00:35.565] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 16:00:35 [16:00:35.581] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-18T16:06:21.000Z","now":"2025-06-18T16:06:25.481Z"} 16:00:35 [16:00:35.627] VERBOSE: simulator:public-processor Processed tx 0x01c8b67f960bef2a83551a6d518661522e026347929184250d39a7bdc900f464 with no public calls in 23.235882000000856ms {"txHash":"0x01c8b67f960bef2a83551a6d518661522e026347929184250d39a7bdc900f464","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":23.235882000000856} 16:00:35 [16:00:35.629] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.046365175999999335s {"duration":0.046365175999999335,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1536,"l2Gas":11200},"totalSizeInBytes":288} 16:00:35 [16:00:35.764] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:35 [16:00:35.788] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x20691ed483c4a8d58b0c08fc026a07bd5da2abf1958a1b2a47311e52c1433e41","globalVariables":{"chainId":31337,"version":4008603340,"blockNumber":4,"slotNumber":5,"timestamp":1750262781,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x01c8b67f960bef2a83551a6d518661522e026347929184250d39a7bdc900f464"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":224.079608,"publicProcessDuration":46.88082700000086,"rollupCircuitsDuration":209.00804000000062,"txCount":1,"blockNumber":4,"blockTimestamp":1750262781,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 16:00:35 [16:00:35.793] VERBOSE: sequencer Attesting committee is empty 16:00:35 [16:00:35.969] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:36 [16:00:36.122] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x29008219bfcc84a3ea186a288fcfd739f1c5a533ae2c27b290d23d2b930e258f 16:00:36 [16:00:36.123] VERBOSE: sequencer-client Sent L1 transaction 0x29008219bfcc84a3ea186a288fcfd739f1c5a533ae2c27b290d23d2b930e258f {"gasLimit":470248,"maxFeePerGas":"1.26112774","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 16:00:36 [16:00:36.138] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 16:00:36 [16:00:36.139] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x29008219bfcc84a3ea186a288fcfd739f1c5a533ae2c27b290d23d2b930e258f","calldataSize":1444,"calldataGas":12268,"txCount":1,"blockNumber":4,"blockTimestamp":1750262781,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 16:00:36 [16:00:36.139] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 16:00:36 [16:00:36.147] INFO: blob-sink:server Received blob sidecar for block 0x42e7287807be80e5a9eb086017417be4d09dfab8d10fcaeb352a46613f791330 16:00:36 [16:00:36.154] INFO: blob-sink:server Blob sidecar stored successfully for block 0x42e7287807be80e5a9eb086017417be4d09dfab8d10fcaeb352a46613f791330 16:00:36 [16:00:36.172] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:36 [16:00:36.180] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 16:00:36 [16:00:36.181] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 16:00:36 [16:00:36.183] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 16:00:36 [16:00:36.204] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262817] on http://127.0.0.1:8545 16:00:36 [16:00:36.206] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 16:00:36 [16:00:36.207] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750262817 16:00:36 [16:00:36.207] WARN: foundation:test-date-provider Time set to 2025-06-18T16:06:57.000Z {"offset":380793,"timeMs":1750262817000} 16:00:36 [16:00:36.208] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 16:00:36 [16:00:36.378] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:36 [16:00:36.581] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:36 [16:00:36.811] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:36 [16:00:36.829] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x20691ed483c4a8d58b0c08fc026a07bd5da2abf1958a1b2a47311e52c1433e41","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":4008603340,"blockNumber":4,"slotNumber":5,"timestamp":1750262781,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x185af60ee59dd267ea68f378abb37827cc9b2451e6aefa727e39976893e84474","archiveNextLeafIndex":5} 16:00:36 [16:00:36.836] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 16:00:36 [16:00:36.933] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":7.237789999999222,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1750262781,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 16:00:36 [16:00:36.943] VERBOSE: p2p Synched to latest block 4 16:00:37 [16:00:37.022] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:37 [16:00:37.190] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x185af60ee59dd267ea68f378abb37827cc9b2451e6aefa727e39976893e84474","blockNumber":5,"slot":6} 16:00:37 [16:00:37.234] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:37 [16:00:37.362] INFO: e2e:e2e_keys Running test: Keys ovsk_app gets ovsk_app 16:00:37 [16:00:37.375] INFO: pxe:service Simulating transaction execution request to 0x27e740b2 at 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb {"origin":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x00000000000000000000000000000000000000000000000000000000eeee6ecc","authWitnesses":["0x0989be6423b50e5bf98acb006ea15c1e25737640f604e3f09cfeccadf1690bbc"]} 16:00:37 [16:00:37.386] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x185af60ee59dd267ea68f378abb37827cc9b2451e6aefa727e39976893e84474","header":{"contentCommitment":{"blobsHash":"0x00211ec0582a38c52621fa9196fe77aa73769dcf0bf57e5dd49885f84da34d1b","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750262781,"version":4008603340},"lastArchive":"0x23d8f582ea3a6c9d8cbaadc56b0a92346271ed200bee2757eca68c97caeb69d4","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x151452985bbed0d8d77e053b4e647f13c0ed3991d31d4d13aff124b6eb93e1ff","nullifierTree":"0x3052dfd3e54d134a55fd8ecd5a305613a9941b27cb006191f2f776a09491ee88","publicDataTree":"0x1e83dafb42e83fb6add990b295f52f4e30df926fc7393326ecbb478b23705a41"},"totalFees":567840000,"totalManaUsed":11200}} 16:00:37 [16:00:37.454] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:37 [16:00:37.489] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:37 [16:00:37.540] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:37 [16:00:37.541] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:37 [16:00:37.627] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb"} 16:00:37 [16:00:37.636] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2796b31d268df9b1b11f5dd893521f339312d5f694247f695d3a654b4abe3a54 16:00:37 [16:00:37.652] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 16:00:37 [16:00:37.653] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 16:00:37 [16:00:37.664] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 16:00:37 [16:00:37.665] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 16:00:37 [16:00:37.668] VERBOSE: pxe:service Added note {"index":0,"contract":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x04e2149dfb7ebeb9098f96c5337338d3830b4fb9b11180af85374bcb619898d4","nullifier":"0x203b9aa5bae0315800e05a14e77d3e416a6392c32e7d5cedc2afb93e8ffad19e"} 16:00:37 [16:00:37.669] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:37 [16:00:37.680] VERBOSE: simulator:client_execution_context:debug_log Setting 0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb as fee payer 16:00:37 [16:00:37.822] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:38 [16:00:38.098] VERBOSE: simulator:private_execution Executing private function Test:get_ovsk_app {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:38 [16:00:38.111] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 16:00:38 [16:00:38.112] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:38 [16:00:38.134] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x185af60ee59dd267ea68f378abb37827cc9b2451e6aefa727e39976893e84474","blockNumber":5,"slot":6} 16:00:38 [16:00:38.135] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:38 [16:00:38.471] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:38 [16:00:38.518] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703"} 16:00:38 [16:00:38.525] VERBOSE: pxe:service Removed note for contract 0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703 at slot 0x000000000000000000000000000000000000000000000000000000000000000c {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","nullifier":"0x041fd273ba0d284ec4b39fd3e44d779a0e0ede6eb74ca5644d5175ce6450e61d"} 16:00:38 [16:00:38.527] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x0f251f2dc826cef4f6e425d24bb644689b6f77820617a08372bde33b6ade1aff 16:00:38 [16:00:38.540] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 16:00:38 [16:00:38.540] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703 for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 16:00:38 [16:00:38.548] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 16:00:38 [16:00:38.549] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 16:00:38 [16:00:38.553] VERBOSE: pxe:service Added note {"index":128,"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x091d6c8d44f05b5537bbe847b159e103755a5411036dad5a0f1c18597013e22b","nullifier":"0x041fd273ba0d284ec4b39fd3e44d779a0e0ede6eb74ca5644d5175ce6450e61d"} 16:00:38 [16:00:38.555] VERBOSE: pxe:service Removed just-added note {"contract":"0x298aadd9f2fbe369ecb90dd52e51a58cc187b197890c445ff76635aafd662703","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x091d6c8d44f05b5537bbe847b159e103755a5411036dad5a0f1c18597013e22b","nullifier":"0x041fd273ba0d284ec4b39fd3e44d779a0e0ede6eb74ca5644d5175ce6450e61d"} 16:00:38 [16:00:38.557] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 16:00:38 [16:00:38.677] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:38 [16:00:38.986] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:39 [16:00:39.343] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:39 [16:00:39.345] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x185af60ee59dd267ea68f378abb37827cc9b2451e6aefa727e39976893e84474","blockNumber":5,"slot":6} 16:00:39 [16:00:39.743] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 16:00:39 [16:00:39.779] INFO: pxe:service Simulation completed for 0x29da26e8a6d97090d099eb364e6a47065783d1147aa0579ed9790e1e6815afde in 2404.000881ms {"txHash":"0x29da26e8a6d97090d099eb364e6a47065783d1147aa0579ed9790e1e6815afde","origin":"0x074915b3a2004075eccf916dfed8f1f8d5ffb0acd8ec2ac1ecb2d0cb6ac709bb","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x00000000000000000000000000000000000000000000000000000000eeee6ecc","authWitnesses":["0x0989be6423b50e5bf98acb006ea15c1e25737640f604e3f09cfeccadf1690bbc"]} 16:00:39 [16:00:39.784] INFO: node Stopping 16:00:39 [16:00:39.785] INFO: slasher Slasher client stopped. 16:00:39 [16:00:39.786] INFO: sequencer Stopped sequencer 16:00:39 [16:00:39.786] INFO: p2p P2P client stopped. 16:00:39 [16:00:39.789] INFO: world_state Stopped world state synchronizer 16:00:39 [16:00:39.789] INFO: archiver Stopped. 16:00:39 [16:00:39.789] INFO: node Stopped 16:00:39 [16:00:39.789] VERBOSE: e2e:e2e_keys Cleaning up ACVM state 16:00:39 [16:00:39.790] INFO: e2e:e2e_keys Cleaning up ACVM temp directory /tmp/1f81d7fe 16:00:39 [16:00:39.791] VERBOSE: e2e:e2e_keys Cleaning up BB state 16:00:39 [16:00:39.802] INFO: blob-sink:server Stopping blob sink 16:00:39 [16:00:39.803] INFO: blob-sink:server Blob sink stopped 16:00:39 [16:00:39.803] VERBOSE: e2e:e2e_keys Cleaning up data directory at /tmp/91d16a1f324c838a 16:00:39 PASS src/e2e_keys.test.ts 16:00:39 Keys 16:00:39 using nsk_app to detect nullification 16:00:39 ✓ nsk_app and contract address are enough to detect note nullification (10218 ms) 16:00:39 ovsk_app 16:00:39 ✓ gets ovsk_app (2420 ms) 16:00:39 16:00:39 Test Suites: 1 passed, 1 total 16:00:39 Tests: 2 passed, 2 total 16:00:39 Snapshots: 0 total 16:00:39 Time: 37.928 s 16:00:39 Ran all test suites matching /src\/e2e_keys.test.ts/i. 16:00:39 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?