ACI ·

Parent Log: http://ci.aztec-labs.com/a3bb03fa16e317bc Command: 00c2c16648004337: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/015cb0cadef6a41094c3649f32a735392e1b9693 Env: REF_NAME=gh-readonly-queue/master/pr-15027-e9a2301883801edbe36ea8bba05dcca7e714f30a CURRENT_VERSION=0.87.8 CI_FULL=0 Date: Wed Jun 18 14:25:05 UTC 2025 System: ARCH=arm64 CPUS=64 MEM=247Gi HOSTNAME=pr-15027_arm64_a1-fast Resources: CPU_LIST=0-63 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c393731fea71056e_master 14:25:05 +++ id -u 14:25:05 +++ id -g 14:25:05 ++ docker run -d --name e2e_keys --net=none --cpuset-cpus=0-63 --cpus=2 --memory=8g --user 1000:1000 -v/home/aztec-dev:/home/aztec-dev --mount type=tmpfs,target=/tmp,tmpfs-size=1g --workdir /home/aztec-dev/aztec-packages -e HOME -e VERBOSE -e GIT_CONFIG_GLOBAL=/home/aztec-dev/aztec-packages/build-images/src/home/.gitconfig -e FORCE_COLOR=true -e CPUS -e MEM aztecprotocol/build:3.0 /bin/bash -c 'timeout -v 600s bash -c '\''yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_keys.test.ts'\''' 14:25:05 + cid=6ec7b67d36b8b3a00927a47a5e8c200b9fa4d1dbb68b487db9db8ef26054e69a 14:25:05 + set +x 14:25:06 {"level":25,"time":1750256706224,"pid":13,"hostname":"6ec7b67d36b8","module":"logger","msg":"Logger initialized with level verbose"} 14:25:10 [14:25:10.437] INFO: e2e:e2e_keys Logging metrics to log/unknown.jsonl 14:25:10 [14:25:10.634] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-IW0ZLd/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 14:25:10 [14:25:10.664] INFO: e2e:e2e_keys Calling anvil_setBlockTimestampInterval with params: [12] 14:25:10 [14:25:10.685] WARN: e2e:e2e_keys Set block interval to 12 14:25:10 [14:25:10.686] VERBOSE: e2e:e2e_keys Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 14:25:10 [14:25:10.733] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 14:25:10 [14:25:10.741] VERBOSE: e2e:e2e_keys Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 14:25:10 [14:25:10.757] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 14:25:10 [14:25:10.763] VERBOSE: e2e:e2e_keys Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 14:25:10 [14:25:10.778] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 14:25:10 [14:25:10.783] VERBOSE: e2e:e2e_keys Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 14:25:10 [14:25:10.800] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 14:25:10 [14:25:10.805] VERBOSE: e2e:e2e_keys Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 14:25:10 [14:25:10.820] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 14:25:10 [14:25:10.825] VERBOSE: e2e:e2e_keys Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 14:25:10 [14:25:10.836] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 14:25:10 [14:25:10.840] VERBOSE: e2e:e2e_keys Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 14:25:10 [14:25:10.840] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 14:25:10 [14:25:10.841] INFO: e2e:e2e_keys Waiting for 6 transactions to be mined... 14:25:10 [14:25:10.861] INFO: e2e:e2e_keys All transactions mined successfully 14:25:10 [14:25:10.875] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 14:25:10 [14:25:10.875] VERBOSE: e2e:e2e_keys Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 14:25:10 [14:25:10.886] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 14:25:10 [14:25:10.901] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 14:25:10 [14:25:10.906] VERBOSE: e2e:e2e_keys Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 14:25:10 [14:25:10.918] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 14:25:10 [14:25:10.918] VERBOSE: e2e:e2e_keys Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 14:25:10 [14:25:10.933] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 14:25:10 [14:25:10.937] VERBOSE: e2e:e2e_keys Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 14:25:10 [14:25:10.949] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 14:25:10 [14:25:10.949] VERBOSE: e2e:e2e_keys Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 14:25:10 [14:25:10.949] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 14:25:10 [14:25:10.949] INFO: e2e:e2e_keys Waiting for 8 transactions to be mined... 14:25:10 [14:25:10.967] INFO: e2e:e2e_keys All transactions mined successfully 14:25:10 [14:25:10.976] VERBOSE: e2e:e2e_keys Deployed shared contracts 14:25:10 [14:25:10.991] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 14:25:10 [14:25:10.991] VERBOSE: e2e:e2e_keys Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 14:25:10 [14:25:10.994] 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} 14:25:11 [14:25:11.008] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 14:25:11 [14:25:11.030] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 14:25:11 [14:25:11.035] VERBOSE: e2e:e2e_keys Awaiting for linked libraries to be deployed 14:25:11 [14:25:11.068] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x4429448e4e372f8e432d24e31127e73e2b33eddab0df5d8e1c970e12b2cdf5ff {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 14:25:11 [14:25:11.074] 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} 14:25:11 [14:25:11.074] INFO: e2e:e2e_keys Waiting for 9 transactions to be mined... 14:25:11 [14:25:11.096] INFO: e2e:e2e_keys All transactions mined successfully 14:25:11 [14:25:11.096] VERBOSE: e2e:e2e_keys All core contracts have been deployed 14:25:11 [14:25:11.109] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 14:25:11 [14:25:11.109] VERBOSE: e2e:e2e_keys Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 14:25:11 [14:25:11.121] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 14:25:11 [14:25:11.125] VERBOSE: e2e:e2e_keys Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 14:25:11 [14:25:11.144] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 14:25:11 [14:25:11.144] VERBOSE: e2e:e2e_keys Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 14:25:11 [14:25:11.144] INFO: e2e:e2e_keys Waiting for 10 transactions to be mined... 14:25:11 [14:25:11.163] INFO: e2e:e2e_keys All transactions mined successfully 14:25:11 [14:25:11.169] VERBOSE: e2e:e2e_keys Rollup deployed 14:25:11 [14:25:11.169] VERBOSE: e2e:e2e_keys Waiting for rollup and slash factory to be deployed 14:25:11 [14:25:11.169] INFO: e2e:e2e_keys Waiting for 10 transactions to be mined... 14:25:11 [14:25:11.190] INFO: e2e:e2e_keys All transactions mined successfully 14:25:11 [14:25:11.190] VERBOSE: e2e:e2e_keys All transactions for L1 deployment have been mined 14:25:11 [14:25:11.206] 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"} 14:25:11 [14:25:11.209] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 14:25:11 [14:25:11.211] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:11 [14:25:11.212] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 14:25:11 [14:25:11.213] INFO: telemetry:client Using NoopTelemetryClient 14:25:11 [14:25:11.218] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 14:25:11 [14:25:11.218] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 14:25:11 [14:25:11.219] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/53345cc4715b421e/blob-sink with map size 134217728 KB (LMDB v2) 14:25:11 [14:25:11.221] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:11 [14:25:11.224] INFO: aztecjs:utils:watcher New L1 time: 1750256950 14:25:11 [14:25:11.224] WARN: foundation:test-date-provider Time set to 2025-06-18T14:29:10.000Z {"offset":238776,"timeMs":1750256950000} 14:25:11 [14:25:11.226] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 14:25:11 [14:25:11.231] INFO: blob-sink:server Server is running on http://localhost:33763 14:25:11 [14:25:11.231] VERBOSE: e2e:e2e_keys Creating and synching an aztec node... 14:25:11 [14:25:11.232] VERBOSE: e2e:e2e_keys Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/8f7e220d/acvm 14:25:11 [14:25:11.233] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:33763"} 14:25:11 [14:25:11.247] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 14:25:11 [14:25:11.248] INFO: archiver:lmdb Creating archiver data store at directory /tmp/53345cc4715b421e/archiver with map size 134217728 KB (LMDB v2) 14:25:11 [14:25:11.249] INFO: archiver:lmdb Starting data store with maxReaders 16 14:25:11 [14:25:11.434] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:11 [14:25:11.509] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:33763"} 14:25:11 [14:25:11.524] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:33763"} 14:25:11 [14:25:11.524] WARN: node:blob-sink:client No L1 consensus host urls configured 14:25:11 [14:25:11.524] WARN: node:blob-sink:client No archive client configured 14:25:11 [14:25:11.526] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0xb27e28459a377ab14b4beafa4040fef775362e0002e169368da0a4537f86e809 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x2fee8c60400fdaaa84a8d7df5e972b21f1baea3dbb66c1ce5512418597fd3379","l1BlockNumber":16}} 14:25:11 [14:25:11.539] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 14:25:11 [14:25:11.546] INFO: world-state:database Creating world state data store at directory /tmp/53345cc4715b421e/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 14:25:11 [14:25:11.568] INFO: world_state Created world state synchroniser with block history of 64 14:25:11 [14:25:11.568] WARN: node Aztec node is accepting fake proofs 14:25:11 [14:25:11.577] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/53345cc4715b421e/p2p with map size 134217728 KB (LMDB v2) 14:25:11 [14:25:11.578] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 14:25:11 [14:25:11.579] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/53345cc4715b421e/p2p-archive with map size 134217728 KB (LMDB v2) 14:25:11 [14:25:11.580] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 14:25:11 [14:25:11.581] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/53345cc4715b421e/p2p-peers with map size 134217728 KB (LMDB v2) 14:25:11 [14:25:11.582] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 14:25:11 [14:25:11.583] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 14:25:11 [14:25:11.583] VERBOSE: p2p P2P is disabled. Using dummy P2P service 14:25:11 [14:25:11.585] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 14:25:11 [14:25:11.586] INFO: world_state Started world state synchronizer from block 1 14:25:11 [14:25:11.588] VERBOSE: world-state:block_stream Starting sync from block number 0 14:25:11 [14:25:11.590] INFO: p2p Starting p2p client from block 0 with empty mempools 14:25:11 [14:25:11.590] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 14:25:11 [14:25:11.591] INFO: slasher Slasher client initialized 14:25:11 [14:25:11.591] INFO: slasher Starting Slasher client... 14:25:11 [14:25:11.620] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 14:25:11 [14:25:11.620] VERBOSE: node All Aztec Node subsystems synced 14:25:11 [14:25:11.627] INFO: sequencer-client Deploying forwarder contract 14:25:11 [14:25:11.633] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 14:25:11 [14:25:11.637] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:11 [14:25:11.645] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 14:25:11 [14:25:11.646] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 14:25:11 [14:25:11.651] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 14:25:11 [14:25:11.663] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 14:25:11 [14:25:11.664] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 14:25:11 [14:25:11.664] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/8f7e220d/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 14:25:11 [14:25:11.849] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 14:25:11 [14:25:11.851] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 14:25:11 [14:25:11.852] INFO: node Aztec Node version: 0.87.8 14:25:11 [14:25:11.852] 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"} 14:25:11 [14:25:11.853] VERBOSE: e2e:e2e_keys Creating a pxe... 14:25:11 [14:25:11.856] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:11 [14:25:11.865] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/b60203f2aeb44b6d/pxe_data with map size 134217728 KB (LMDB v2) 14:25:11 [14:25:11.875] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x054802ccc2635c95de2d51c819a985f2b361bbec62da0bf8421cbeb1dcaf96c9","blockNumber":1,"slot":2} 14:25:11 [14:25:11.876] INFO: pxe:data:lmdb Starting data store with maxReaders 16 14:25:12 [14:25:12.072] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:12 [14:25:12.102] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 14:25:12 [14:25:12.103] INFO: pxe:service Started PXE connected to chain 31337 version 1964831506 14:25:12 [14:25:12.150] INFO: pxe:service Registered account 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e 14:25:12 [14:25:12.203] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 14:25:12 [14:25:12.203] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e 14:25:12 [14:25:12.319] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:12 [14:25:12.322] INFO: pxe:service Added contract SchnorrAccount at 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 14:25:12 [14:25:12.361] VERBOSE: pxe:block_stream Starting sync from block number 0 14:25:12 [14:25:12.379] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 14:25:12 [14:25:12.440] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x054802ccc2635c95de2d51c819a985f2b361bbec62da0bf8421cbeb1dcaf96c9","blockNumber":1,"slot":2} 14:25:12 [14:25:12.465] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 14:25:12 [14:25:12.854] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 14:25:12 [14:25:12.927] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 14:25:12 [14:25:12.986] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:13 [14:25:13.014] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x054802ccc2635c95de2d51c819a985f2b361bbec62da0bf8421cbeb1dcaf96c9","blockNumber":1,"slot":2} 14:25:13 [14:25:13.082] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:13 [14:25:13.118] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:13 [14:25:13.119] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:13 [14:25:13.153] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:13 [14:25:13.157] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:13 [14:25:13.207] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:13 [14:25:13.313] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:13 [14:25:13.347] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:13 [14:25:13.348] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:13 [14:25:13.376] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:13 [14:25:13.379] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:13 [14:25:13.392] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e as fee payer 14:25:13 [14:25:13.413] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:13 [14:25:13.626] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:13 [14:25:13.635] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x054802ccc2635c95de2d51c819a985f2b361bbec62da0bf8421cbeb1dcaf96c9","blockNumber":1,"slot":2} 14:25:13 [14:25:13.839] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:14 [14:25:14.078] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:14 [14:25:14.477] INFO: pxe:service Proving completed in 2123.710724999999ms {"timings":{"total":2123.710724999999,"sync":8.46169800000007,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":119.91609499999959,"oracles":{"callPrivateFunction":{"times":[561.0360119999996,268.77669799999967,152.15322099999958]}}},{"functionName":"private_kernel_init","time":92.8008660000005},{"functionName":"ContractClassRegisterer:register","time":547.7544770000004,"oracles":{"loadCapsule":{"times":[4.452127999999902]},"notifyCreatedNullifier":{"times":[0.5715790000003835]},"debugLog":{"times":[0.812818000000334]},"notifyCreatedContractClassLog":{"times":[7.959149000000252]}}},{"functionName":"private_kernel_inner","time":195.81367799999953},{"functionName":"SchnorrAccount:constructor","time":243.94066999999995,"oracles":{"getContractInstance":{"times":[1.2608630000004268]},"debugLog":{"times":[0.4876960000001418,0.4241180000008171]},"fetchTaggedLogs":{"times":[37.00574299999971]},"loadCapsule":{"times":[0.34942299999966053,0.22627100000045175,0.26286700000036944]},"notifyCreatedNullifier":{"times":[0.2079539999995177,0.238734999999906]},"notifyCreatedNote":{"times":[0.3668680000000677]},"getRandomField":{"times":[0.3684669999993275,0.16571900000053574,0.09914200000002893,0.08549799999946117,0.0762910000003103,0.0887910000001284,0.06923699999970268,0.08272000000033586,0.07055399999990186,0.10332100000050559,0.06653199999982462]},"getIndexedTaggingSecretAsSender":{"times":[35.04258899999968]},"incrementAppTaggingSecretIndexAsSender":{"times":[26.11747099999957]}}},{"functionName":"private_kernel_inner","time":109.96706400000039},{"functionName":"SchnorrAccount:entrypoint","time":130.18829600000026,"oracles":{"debugLog":{"times":[0.4804759999997259,0.4087129999998069,0.5674639999997453]},"fetchTaggedLogs":{"times":[29.569542999999612]},"loadCapsule":{"times":[0.21584200000052078,0.19942400000036287,0.22874999999930878]},"getNotes":{"times":[1.4353259999998045]},"getAuthWitness":{"times":[0.3555100000003222]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.7480380000006335]}}},{"functionName":"private_kernel_inner","time":113.16375300000072},{"functionName":"private_kernel_reset","time":164.02120599999944},{"functionName":"private_kernel_tail","time":69.4998599999999}],"unaccounted":328.18306199999824}} 14:25:14 [14:25:14.495] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:14 [14:25:14.564] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x054802ccc2635c95de2d51c819a985f2b361bbec62da0bf8421cbeb1dcaf96c9","blockNumber":1,"slot":2} 14:25:14 [14:25:14.626] VERBOSE: p2p:tx_pool Adding tx 0x133141f3827e7a76f6ee763e9832e6c30c6279765ac66737abebafb93f5c4832 to pool {"eventName":"tx-added-to-pool","txHash":"0x133141f3827e7a76f6ee763e9832e6c30c6279765ac66737abebafb93f5c4832","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 14:25:14 [14:25:14.644] INFO: node Received tx 0x133141f3827e7a76f6ee763e9832e6c30c6279765ac66737abebafb93f5c4832 {"txHash":"0x133141f3827e7a76f6ee763e9832e6c30c6279765ac66737abebafb93f5c4832"} 14:25:14 [14:25:14.645] INFO: pxe:service Sent transaction 0x133141f3827e7a76f6ee763e9832e6c30c6279765ac66737abebafb93f5c4832 14:25:14 [14:25:14.702] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:14 [14:25:14.904] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:15 [14:25:15.078] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 14:25:15 [14:25:15.090] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-18T14:29:34.000Z","now":"2025-06-18T14:29:13.866Z"} 14:25:15 [14:25:15.152] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:15 [14:25:15.321] VERBOSE: simulator:public-processor Processed tx 0x133141f3827e7a76f6ee763e9832e6c30c6279765ac66737abebafb93f5c4832 with no public calls in 159.7393060000013ms {"txHash":"0x133141f3827e7a76f6ee763e9832e6c30c6279765ac66737abebafb93f5c4832","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":159.7393060000013} 14:25:15 [14:25:15.323] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.23202039499999955s {"duration":0.23202039499999955,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 14:25:15 [14:25:15.437] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:15 [14:25:15.450] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x00b0145de43ca7cb9d283f573b85f8e3f6e1008f3236a39b64e125f2674ec6a8","globalVariables":{"chainId":31337,"version":1964831506,"blockNumber":1,"slotNumber":2,"timestamp":1750256974,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x133141f3827e7a76f6ee763e9832e6c30c6279765ac66737abebafb93f5c4832"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":372.54895099999885,"publicProcessDuration":232.71208099999967,"rollupCircuitsDuration":361.1516530000008,"txCount":1,"blockNumber":1,"blockTimestamp":1750256974,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 14:25:15 [14:25:15.453] VERBOSE: sequencer Attesting committee is empty 14:25:15 [14:25:15.643] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:15 [14:25:15.815] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xae4eccc8bc243491334967aaa2117beda679f2612675aec6b588ec7433f34424 14:25:15 [14:25:15.815] VERBOSE: sequencer-client Sent L1 transaction 0xae4eccc8bc243491334967aaa2117beda679f2612675aec6b588ec7433f34424 {"gasLimit":514585,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 14:25:15 [14:25:15.825] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 14:25:15 [14:25:15.826] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xae4eccc8bc243491334967aaa2117beda679f2612675aec6b588ec7433f34424","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":1,"blockTimestamp":1750256974,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 14:25:15 [14:25:15.827] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 14:25:15 [14:25:15.840] INFO: blob-sink:server Received blob sidecar for block 0x6ca4af17cef90747b127cf18a68dfdb5c87569172ccc33e475b37fd38c81d5b0 14:25:15 [14:25:15.846] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:15 [14:25:15.849] INFO: blob-sink:server Blob sidecar stored successfully for block 0x6ca4af17cef90747b127cf18a68dfdb5c87569172ccc33e475b37fd38c81d5b0 14:25:15 [14:25:15.852] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 14:25:15 [14:25:15.854] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 14:25:15 [14:25:15.855] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 14:25:15 [14:25:15.863] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750257010] on http://127.0.0.1:8545 14:25:15 [14:25:15.865] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 14:25:15 [14:25:15.866] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750257010 14:25:15 [14:25:15.866] WARN: foundation:test-date-provider Time set to 2025-06-18T14:30:10.000Z {"offset":294134,"timeMs":1750257010000} 14:25:15 [14:25:15.866] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 14:25:16 [14:25:16.051] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:16 [14:25:16.201] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 14:25:16 [14:25:16.206] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x00b0145de43ca7cb9d283f573b85f8e3f6e1008f3236a39b64e125f2674ec6a8","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":1964831506,"blockNumber":1,"slotNumber":2,"timestamp":1750256974,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x1db917ac2b20efa88df3f2eea177ced9f84e26442c7427c395351cc85ecb51cb","archiveNextLeafIndex":2} 14:25:16 [14:25:16.212] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 14:25:16 [14:25:16.253] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:16 [14:25:16.617] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:16 [14:25:16.621] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":321.98960500000067,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750256974,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 14:25:16 [14:25:16.622] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 14:25:16 [14:25:16.624] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae 14:25:16 [14:25:16.836] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:16 [14:25:16.844] VERBOSE: p2p Synched to latest block 1 14:25:17 [14:25:17.078] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:17 [14:25:17.087] INFO: pxe:service Added contract Test at 0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 14:25:17 [14:25:17.113] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x1db917ac2b20efa88df3f2eea177ced9f84e26442c7427c395351cc85ecb51cb","header":{"contentCommitment":{"blobsHash":"0x00822ab43bdeffc9a459ed4b940137e4f4decfc51cd7c3398c6b0683663b7b1f","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750256974,"version":1964831506},"lastArchive":"0x054802ccc2635c95de2d51c819a985f2b361bbec62da0bf8421cbeb1dcaf96c9","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x16d1d234427184da563180994b3e421e8bf0dd1a00d0af8e0f09c5cf3310db59","nullifierTree":"0x2680e4378ccb5072b42861a70df324a0ebc1bb751123e5e42d4f13701e48add5","publicDataTree":"0x27651856c0af3f64f9413cb6de8b054cb8da5a08a04f7a1ec7284d7fc8e9f2c7"},"totalFees":6794052480,"totalManaUsed":20448}} 14:25:17 [14:25:17.250] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:17 [14:25:17.285] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:17 [14:25:17.285] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:17 [14:25:17.291] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:17 [14:25:17.333] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1db917ac2b20efa88df3f2eea177ced9f84e26442c7427c395351cc85ecb51cb","blockNumber":2,"slot":3} 14:25:17 [14:25:17.351] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:17 [14:25:17.355] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2c8f8a8bafaf50594c411ec5e9b4d88aef22b7f85b5e0ae7c126d92b6e8bc0a5 14:25:17 [14:25:17.365] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:25:17 [14:25:17.366] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:25:17 [14:25:17.371] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:25:17 [14:25:17.372] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:25:17 [14:25:17.376] VERBOSE: pxe:service Added note {"index":0,"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2d426b63f8fce18e1af5582725446483f32d5610cbec469098e49f8f7fcd21db","nullifier":"0x278779a71a9bd48340ce55898b631e23d960017219b56ab67a427da0d2e724eb"} 14:25:17 [14:25:17.377] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:17 [14:25:17.389] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e as fee payer 14:25:17 [14:25:17.415] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 14:25:17 [14:25:17.735] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 14:25:17 [14:25:17.807] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 14:25:17 [14:25:17.858] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:17 [14:25:17.917] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 14:25:17 [14:25:17.931] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1db917ac2b20efa88df3f2eea177ced9f84e26442c7427c395351cc85ecb51cb","blockNumber":2,"slot":3} 14:25:17 [14:25:17.955] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 14:25:17 [14:25:17.966] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae,0x0000000000000000000000000000000000000000000000000000000000000001,0x03defe069a6f09840345ba240192208394392a39cfc397136ffa909e21245ced,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e 14:25:18 [14:25:18.087] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:18 [14:25:18.404] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:18 [14:25:18.420] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:18 [14:25:18.421] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:18 [14:25:18.531] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:18 [14:25:18.568] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:18 [14:25:18.573] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1db917ac2b20efa88df3f2eea177ced9f84e26442c7427c395351cc85ecb51cb","blockNumber":2,"slot":3} 14:25:18 [14:25:18.577] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:18 [14:25:18.742] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:19 [14:25:19.079] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:19 [14:25:19.372] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:19 [14:25:19.373] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1db917ac2b20efa88df3f2eea177ced9f84e26442c7427c395351cc85ecb51cb","blockNumber":2,"slot":3} 14:25:19 [14:25:19.915] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:20 [14:25:20.280] INFO: pxe:service Proving completed in 3174.8019299999996ms {"timings":{"total":3174.8019299999996,"sync":10.503124000000753,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":396.59280699999726,"oracles":{"debugLog":{"times":[0.5888620000005176,0.40373299999919254,0.4196719999999914,0.5225209999989602,0.42542499999944994,0.35888400000112597,0.3372399999989284,0.4313449999990553]},"fetchTaggedLogs":{"times":[67.37585500000023]},"loadCapsule":{"times":[0.19316800000160583,0.38532500000110304,0.41422199999942677,0.22138600000107544,0.18476899999950547,0.20823100000052364]},"getSharedSecret":{"times":[3.1192510000000766]},"aes128Decrypt":{"times":[0.9648379999998724,0.949730999998792]},"getPublicKeysAndPartialAddress":{"times":[1.3720749999993131]},"getKeyValidationRequest":{"times":[1.6192140000002837]},"deliverNote":{"times":[3.6589330000006157]},"deleteCapsule":{"times":[0.35365599999931874]},"storeCapsule":{"times":[0.3165289999997185]},"getNotes":{"times":[1.2535100000004604]},"getAuthWitness":{"times":[0.19874199999867415]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06199999999989814]},"callPrivateFunction":{"times":[465.3490200000015,113.639588,611.1328580000009]}}},{"functionName":"private_kernel_init","time":62.58431399999972},{"functionName":"ContractClassRegisterer:register","time":455.6941739999984,"oracles":{"loadCapsule":{"times":[3.7054100000004837]},"notifyCreatedNullifier":{"times":[0.2775440000004892]},"debugLog":{"times":[0.43217700000059267]},"notifyCreatedContractClassLog":{"times":[7.189622999998392]}}},{"functionName":"private_kernel_inner","time":107.07979599999999},{"functionName":"ContractInstanceDeployer:deploy","time":69.89461200000005,"oracles":{"storeInExecutionCache":{"times":[0.16559300000153598]},"callPrivateFunction":{"times":[40.2334809999993]},"notifyCreatedNullifier":{"times":[0.2434290000001056]},"debugLog":{"times":[0.43328199999996286]}}},{"functionName":"private_kernel_inner","time":107.30610699999852},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":31.60273100000086,"oracles":{}},{"functionName":"private_kernel_inner","time":111.89423100000022},{"functionName":"Test:initialize","time":496.81718400000136,"oracles":{"getContractInstance":{"times":[2.766841000000568]},"debugLog":{"times":[0.4465959999997722,0.3722590000015771]},"fetchTaggedLogs":{"times":[155.30217300000004]},"loadCapsule":{"times":[0.18247499999961292,0.16147599999931117,0.20613099999900442]},"notifyCreatedNullifier":{"times":[0.21381399999881978]}}},{"functionName":"private_kernel_inner","time":111.27096600000004},{"functionName":"private_kernel_reset","time":163.25412700000015},{"functionName":"private_kernel_tail","time":67.81009199999971}],"unaccounted":982.4976650000026}} 14:25:20 [14:25:20.295] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:20 [14:25:20.301] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x1db917ac2b20efa88df3f2eea177ced9f84e26442c7427c395351cc85ecb51cb","blockNumber":2,"slot":3} 14:25:20 [14:25:20.438] VERBOSE: p2p:tx_pool Adding tx 0x1200a4b60a6cf98509db53f386533b7b8585750724c8fa8343fa32bab4142282 to pool {"eventName":"tx-added-to-pool","txHash":"0x1200a4b60a6cf98509db53f386533b7b8585750724c8fa8343fa32bab4142282","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 14:25:20 [14:25:20.456] INFO: node Received tx 0x1200a4b60a6cf98509db53f386533b7b8585750724c8fa8343fa32bab4142282 {"txHash":"0x1200a4b60a6cf98509db53f386533b7b8585750724c8fa8343fa32bab4142282"} 14:25:20 [14:25:20.456] INFO: pxe:service Sent transaction 0x1200a4b60a6cf98509db53f386533b7b8585750724c8fa8343fa32bab4142282 14:25:20 [14:25:20.499] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:20 [14:25:20.701] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:20 [14:25:20.817] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 14:25:20 [14:25:20.831] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-18T14:30:10.000Z","now":"2025-06-18T14:30:14.965Z"} 14:25:20 [14:25:20.914] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:21 [14:25:21.104] VERBOSE: simulator:public-processor Processed tx 0x1200a4b60a6cf98509db53f386533b7b8585750724c8fa8343fa32bab4142282 with no public calls in 181.23842499999955ms {"txHash":"0x1200a4b60a6cf98509db53f386533b7b8585750724c8fa8343fa32bab4142282","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":181.23842499999955} 14:25:21 [14:25:21.108] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.2761946410000001s {"duration":0.2761946410000001,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 14:25:21 [14:25:21.118] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:21 [14:25:21.273] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x08fb51d1e222444f1a4ead260b775b2bd3466b8f2a21e16a271ea0f239b22ed5","globalVariables":{"chainId":31337,"version":1964831506,"blockNumber":2,"slotNumber":3,"timestamp":1750257010,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1200a4b60a6cf98509db53f386533b7b8585750724c8fa8343fa32bab4142282"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":455.8937620000015,"publicProcessDuration":276.58381499999996,"rollupCircuitsDuration":443.14288600000145,"txCount":1,"blockNumber":2,"blockTimestamp":1750257010,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 14:25:21 [14:25:21.276] VERBOSE: sequencer Attesting committee is empty 14:25:21 [14:25:21.383] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:21 [14:25:21.579] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xaf0da86dea65ac386561f036a2b57f608a235291fc6c28dc66511aa87639b8d4 14:25:21 [14:25:21.579] VERBOSE: sequencer-client Sent L1 transaction 0xaf0da86dea65ac386561f036a2b57f608a235291fc6c28dc66511aa87639b8d4 {"gasLimit":470269,"maxFeePerGas":"1.303611208","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 14:25:21 [14:25:21.587] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:21 [14:25:21.595] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 14:25:21 [14:25:21.595] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xaf0da86dea65ac386561f036a2b57f608a235291fc6c28dc66511aa87639b8d4","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":2,"blockTimestamp":1750257010,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 14:25:21 [14:25:21.595] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 14:25:21 [14:25:21.598] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 14:25:21 [14:25:21.601] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 14:25:21 [14:25:21.602] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 14:25:21 [14:25:21.604] INFO: blob-sink:server Received blob sidecar for block 0xbf8e9d937182d27a96ba4f534376b178f8abb3c5fa1b9aae5ca5601782cd2b66 14:25:21 [14:25:21.608] INFO: blob-sink:server Blob sidecar stored successfully for block 0xbf8e9d937182d27a96ba4f534376b178f8abb3c5fa1b9aae5ca5601782cd2b66 14:25:21 [14:25:21.775] VERBOSE: archiver:block-helper Store contract instance at 0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae 14:25:21 [14:25:21.797] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 14:25:21 [14:25:21.821] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:21 [14:25:21.830] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x08fb51d1e222444f1a4ead260b775b2bd3466b8f2a21e16a271ea0f239b22ed5","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":1964831506,"blockNumber":2,"slotNumber":3,"timestamp":1750257010,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x0cafb9c215eb6dee4c6aa9d6431162aaa4693828e9a6c37facb405087c285433","archiveNextLeafIndex":3} 14:25:21 [14:25:21.838] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750257046] on http://127.0.0.1:8545 14:25:21 [14:25:21.839] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 14:25:21 [14:25:21.841] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 14:25:21 [14:25:21.842] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750257046 14:25:21 [14:25:21.843] WARN: foundation:test-date-provider Time set to 2025-06-18T14:30:46.000Z {"offset":324157,"timeMs":1750257046000} 14:25:21 [14:25:21.843] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 14:25:21 [14:25:21.880] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":6.5140470000005735,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750257010,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 14:25:21 [14:25:21.952] VERBOSE: p2p Synched to latest block 2 14:25:22 [14:25:22.030] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:22 [14:25:22.113] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x0cafb9c215eb6dee4c6aa9d6431162aaa4693828e9a6c37facb405087c285433","blockNumber":3,"slot":4} 14:25:22 [14:25:22.234] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:22 [14:25:22.437] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:22 [14:25:22.623] INFO: aztecjs:deploy_sent_tx Contract 0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae successfully deployed. 14:25:22 [14:25:22.624] INFO: e2e:e2e_keys Running test: Keys using nsk_app to detect nullification nsk_app and contract address are enough to detect note nullification 14:25:22 [14:25:22.641] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:22 [14:25:22.658] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x0cafb9c215eb6dee4c6aa9d6431162aaa4693828e9a6c37facb405087c285433","blockNumber":3,"slot":4} 14:25:22 [14:25:22.667] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x0cafb9c215eb6dee4c6aa9d6431162aaa4693828e9a6c37facb405087c285433","header":{"contentCommitment":{"blobsHash":"0x002795e97180dfb0015a9bc17dfb130f9e4eca07706522eb3635244ad476e024","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750257010,"version":1964831506},"lastArchive":"0x1db917ac2b20efa88df3f2eea177ced9f84e26442c7427c395351cc85ecb51cb","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x16d1d234427184da563180994b3e421e8bf0dd1a00d0af8e0f09c5cf3310db59","nullifierTree":"0x27cd5b4e0879fc2e477b84b5ec0a65642171bed9f302ae0e98cfaf12049951e8","publicDataTree":"0x2af2eaae5ceb1f0efa0db761debba90a55f4c50b878f1a9f54e705fe7b6cdec8"},"totalFees":7272506880,"totalManaUsed":21888}} 14:25:22 [14:25:22.731] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:22 [14:25:22.766] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:22 [14:25:22.766] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:22 [14:25:22.801] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:22 [14:25:22.805] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2c8f8a8bafaf50594c411ec5e9b4d88aef22b7f85b5e0ae7c126d92b6e8bc0a5 14:25:22 [14:25:22.815] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:25:22 [14:25:22.815] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:25:22 [14:25:22.820] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:25:22 [14:25:22.821] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:25:22 [14:25:22.823] VERBOSE: pxe:service Added note {"index":0,"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2d426b63f8fce18e1af5582725446483f32d5610cbec469098e49f8f7fcd21db","nullifier":"0x278779a71a9bd48340ce55898b631e23d960017219b56ab67a427da0d2e724eb"} 14:25:22 [14:25:22.824] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:22 [14:25:22.836] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e as fee payer 14:25:22 [14:25:22.936] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:23 [14:25:23.139] VERBOSE: simulator:private_execution Executing private function Test:call_create_note {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:23 [14:25:23.173] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:23 [14:25:23.173] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:23 [14:25:23.293] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:23 [14:25:23.311] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x0cafb9c215eb6dee4c6aa9d6431162aaa4693828e9a6c37facb405087c285433","blockNumber":3,"slot":4} 14:25:23 [14:25:23.319] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:23 [14:25:23.322] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:23 [14:25:23.589] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:23 [14:25:23.800] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:24 [14:25:24.195] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:24 [14:25:24.327] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x0cafb9c215eb6dee4c6aa9d6431162aaa4693828e9a6c37facb405087c285433","blockNumber":3,"slot":4} 14:25:24 [14:25:24.573] INFO: pxe:service Proving completed in 1919.9957160000013ms {"timings":{"total":1919.9957160000013,"sync":17.01476300000286,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":270.0587599999999,"oracles":{"debugLog":{"times":[0.4942960000007588,0.4040999999997439,0.38668999999936204,0.503830999998172,0.3898669999980484,0.3521600000021863,0.3334949999989476,0.4446119999993243]},"fetchTaggedLogs":{"times":[37.60417799999777]},"loadCapsule":{"times":[0.24924099999770988,0.17628400000103284,0.4171669999996084,0.1846609999993234,0.17244399999981397,0.19433500000013737]},"getSharedSecret":{"times":[2.694844000001467]},"aes128Decrypt":{"times":[0.20644200000242563,0.8209990000032121]},"getPublicKeysAndPartialAddress":{"times":[1.102415999997902]},"getKeyValidationRequest":{"times":[1.4072330000017246]},"deliverNote":{"times":[2.316232000001037]},"deleteCapsule":{"times":[0.21375800000168965]},"storeCapsule":{"times":[0.2089689999993425]},"getNotes":{"times":[1.2987429999993765]},"getAuthWitness":{"times":[0.2036840000000666]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06771200000002864]},"callPrivateFunction":{"times":[780.5553619999991]}}},{"functionName":"private_kernel_init","time":56.30242899999939},{"functionName":"Test:call_create_note","time":664.6751700000023,"oracles":{"debugLog":{"times":[0.4548389999981737,0.35427100000015344]},"fetchTaggedLogs":{"times":[147.80436700000064]},"loadCapsule":{"times":[0.18146300000080373,0.16452600000047823,0.20887500000026193]},"getRandomField":{"times":[0.09994999999980791,0.1094390000034764,0.13140299999940908,0.08927200000107405,0.08055500000045868,0.07411400000273716,0.057654999996884726,0.06560900000113179,0.058364000000437954,0.06279700000231969,0.06066699999792036,0.07085399999778019]},"notifyCreatedNote":{"times":[0.128353000000061]},"getIndexedTaggingSecretAsSender":{"times":[115.59832499999902]},"incrementAppTaggingSecretIndexAsSender":{"times":[138.99199300000328]}}},{"functionName":"private_kernel_inner","time":106.84453599999688},{"functionName":"private_kernel_reset","time":178.02580600000147},{"functionName":"private_kernel_tail","time":67.6952560000027}],"unaccounted":559.3789959999958}} 14:25:24 [14:25:24.586] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:24 [14:25:24.607] VERBOSE: p2p:tx_pool Adding tx 0x2f4d7250dbb4505c70f8a7bec1d91fee52e0a98a2a69004e76eee054f166d318 to pool {"eventName":"tx-added-to-pool","txHash":"0x2f4d7250dbb4505c70f8a7bec1d91fee52e0a98a2a69004e76eee054f166d318","noteHashCount":1,"nullifierCount":1,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 14:25:24 [14:25:24.611] INFO: node Received tx 0x2f4d7250dbb4505c70f8a7bec1d91fee52e0a98a2a69004e76eee054f166d318 {"txHash":"0x2f4d7250dbb4505c70f8a7bec1d91fee52e0a98a2a69004e76eee054f166d318"} 14:25:24 [14:25:24.611] INFO: pxe:service Sent transaction 0x2f4d7250dbb4505c70f8a7bec1d91fee52e0a98a2a69004e76eee054f166d318 14:25:24 [14:25:24.790] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:24 [14:25:24.846] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 14:25:24 [14:25:24.859] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-18T14:30:46.000Z","now":"2025-06-18T14:30:49.016Z"} 14:25:24 [14:25:24.878] VERBOSE: simulator:public-processor Processed tx 0x2f4d7250dbb4505c70f8a7bec1d91fee52e0a98a2a69004e76eee054f166d318 with no public calls in 12.325605999998515ms {"txHash":"0x2f4d7250dbb4505c70f8a7bec1d91fee52e0a98a2a69004e76eee054f166d318","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":12.325605999998515} 14:25:24 [14:25:24.879] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.01878406300000279s {"duration":0.01878406300000279,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11264,"l2Gas":9760},"totalSizeInBytes":960} 14:25:24 [14:25:24.991] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x286b0a81fdf31767deceaeb74bbf5ea404829d7c70a11d397bd4c7dd333c0ef8","globalVariables":{"chainId":31337,"version":1964831506,"blockNumber":3,"slotNumber":4,"timestamp":1750257046,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x2f4d7250dbb4505c70f8a7bec1d91fee52e0a98a2a69004e76eee054f166d318"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":145.14891900000293,"publicProcessDuration":19.11786099999881,"rollupCircuitsDuration":132.4034619999984,"txCount":1,"blockNumber":3,"blockTimestamp":1750257046,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:25:24 [14:25:24.993] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:24 [14:25:24.995] VERBOSE: sequencer Attesting committee is empty 14:25:25 [14:25:25.256] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:25 [14:25:25.262] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x26ba827ba1880d8b9ce644ae7895cdcd49c71c1698e94c7ad91155c6612eeded 14:25:25 [14:25:25.263] VERBOSE: sequencer-client Sent L1 transaction 0x26ba827ba1880d8b9ce644ae7895cdcd49c71c1698e94c7ad91155c6612eeded {"gasLimit":470265,"maxFeePerGas":"1.279583398","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 14:25:25 [14:25:25.271] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 14:25:25 [14:25:25.271] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x26ba827ba1880d8b9ce644ae7895cdcd49c71c1698e94c7ad91155c6612eeded","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":3,"blockTimestamp":1750257046,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:25:25 [14:25:25.272] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 14:25:25 [14:25:25.276] INFO: blob-sink:server Received blob sidecar for block 0xe69bb3e95f0a54d90757b279b1dfdf99d201d9a9acfefdd6932bf63ef45fcba5 14:25:25 [14:25:25.279] INFO: blob-sink:server Blob sidecar stored successfully for block 0xe69bb3e95f0a54d90757b279b1dfdf99d201d9a9acfefdd6932bf63ef45fcba5 14:25:25 [14:25:25.289] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 14:25:25 [14:25:25.291] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 14:25:25 [14:25:25.292] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 14:25:25 [14:25:25.304] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750257082] on http://127.0.0.1:8545 14:25:25 [14:25:25.305] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 14:25:25 [14:25:25.306] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750257082 14:25:25 [14:25:25.306] WARN: foundation:test-date-provider Time set to 2025-06-18T14:31:22.000Z {"offset":356694,"timeMs":1750257082000} 14:25:25 [14:25:25.307] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 14:25:25 [14:25:25.462] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:25 [14:25:25.753] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:25 [14:25:25.772] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x286b0a81fdf31767deceaeb74bbf5ea404829d7c70a11d397bd4c7dd333c0ef8","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":1964831506,"blockNumber":3,"slotNumber":4,"timestamp":1750257046,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x13d91d57da2f311cde571beaccb12634f2dbd54cafb69715f36c13c511c7fb69","archiveNextLeafIndex":4} 14:25:25 [14:25:25.778] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 14:25:25 [14:25:25.871] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":6.209966000002169,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750257046,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:25:25 [14:25:25.883] VERBOSE: p2p Synched to latest block 3 14:25:25 [14:25:25.957] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:26 [14:25:26.160] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:26 [14:25:26.297] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x13d91d57da2f311cde571beaccb12634f2dbd54cafb69715f36c13c511c7fb69","blockNumber":4,"slot":5} 14:25:26 [14:25:26.363] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:26 [14:25:26.565] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:26 [14:25:26.639] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x13d91d57da2f311cde571beaccb12634f2dbd54cafb69715f36c13c511c7fb69","header":{"contentCommitment":{"blobsHash":"0x00da5ad2a8621dc59b8d649e5b072f64b1e28c9fd0aa6881bd724d844e3c689d","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750257046,"version":1964831506},"lastArchive":"0x0cafb9c215eb6dee4c6aa9d6431162aaa4693828e9a6c37facb405087c285433","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x0cfa016c937a4df962c1dfc299158d18f086fb46eddafef088b04c695574436e","nullifierTree":"0x18ff844c70d149ce1d0878ad7ebc6c31ad8a95a615f23b39348382a8e8aae82d","publicDataTree":"0x2b8f823a7c4e7086daf17153857504e91c26c22da9871f0285cd9e1153677dd3"},"totalFees":3242857600,"totalManaUsed":9760}} 14:25:26 [14:25:26.700] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:26 [14:25:26.734] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:26 [14:25:26.735] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:26 [14:25:26.769] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:26 [14:25:26.772] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:26 [14:25:26.777] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2c8f8a8bafaf50594c411ec5e9b4d88aef22b7f85b5e0ae7c126d92b6e8bc0a5 14:25:26 [14:25:26.785] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:25:26 [14:25:26.786] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:25:26 [14:25:26.791] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:25:26 [14:25:26.791] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:25:26 [14:25:26.793] VERBOSE: pxe:service Added note {"index":0,"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2d426b63f8fce18e1af5582725446483f32d5610cbec469098e49f8f7fcd21db","nullifier":"0x278779a71a9bd48340ce55898b631e23d960017219b56ab67a427da0d2e724eb"} 14:25:26 [14:25:26.795] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:26 [14:25:26.812] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e as fee payer 14:25:27 [14:25:27.024] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:27 [14:25:27.026] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x13d91d57da2f311cde571beaccb12634f2dbd54cafb69715f36c13c511c7fb69","blockNumber":4,"slot":5} 14:25:27 [14:25:27.125] VERBOSE: simulator:private_execution Executing private function Test:call_destroy_note {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:27 [14:25:27.159] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:27 [14:25:27.160] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:27 [14:25:27.286] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:27 [14:25:27.289] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1bfa9f3f1157cd0353d1201d6c85f5e197644f4cc31ac19f56af5c67c021a527 14:25:27 [14:25:27.297] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:25:27 [14:25:27.298] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 14:25:27 [14:25:27.303] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:25:27 [14:25:27.303] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:25:27 [14:25:27.306] VERBOSE: pxe:service Added note {"index":128,"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x20570e2f9871027b6964911a95555cbe03326916b088e4e555b31b35566735cd","nullifier":"0x0e364c297b7eb6e68f09c78425fb9ab9d730f95792883561703c4c550f83a877"} 14:25:27 [14:25:27.307] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:27 [14:25:27.370] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:27 [14:25:27.644] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:27 [14:25:27.933] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:27 [14:25:27.949] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x13d91d57da2f311cde571beaccb12634f2dbd54cafb69715f36c13c511c7fb69","blockNumber":4,"slot":5} 14:25:28 [14:25:28.306] INFO: pxe:service Proving completed in 1671.1250169999985ms {"timings":{"total":1671.1250169999985,"sync":6.405299000001833,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":265.5843760000025,"oracles":{"debugLog":{"times":[0.42723299999852316,0.3946830000022601,0.38824400000157766,0.4496060000019497,0.38573599999654107,0.31438499999785563,0.3149999999986903,0.44083600000158185]},"fetchTaggedLogs":{"times":[40.511623000002146]},"loadCapsule":{"times":[0.16769299999941722,0.15674800000124378,0.3084289999969769,0.17945899999904213,0.14410900000075344,0.16679600000134087]},"getSharedSecret":{"times":[2.474015000003419]},"aes128Decrypt":{"times":[0.21195100000113598,0.8195040000027802]},"getPublicKeysAndPartialAddress":{"times":[1.1087439999973867]},"getKeyValidationRequest":{"times":[1.168785999998363]},"deliverNote":{"times":[2.4614650000003166]},"deleteCapsule":{"times":[0.1997799999990093]},"storeCapsule":{"times":[0.20857999999861931]},"getNotes":{"times":[7.151473000001715]},"getAuthWitness":{"times":[0.20738400000118418]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.05563100000290433]},"callPrivateFunction":{"times":[517.5433570000023]}}},{"functionName":"private_kernel_init","time":87.55879400000049},{"functionName":"Test:call_destroy_note","time":412.7158159999999,"oracles":{"debugLog":{"times":[0.42959999999948195,0.38101300000198535,0.42070999999850756,0.4804949999997916,0.38563000000067404,0.3454469999996945,0.3207540000003064]},"fetchTaggedLogs":{"times":[127.57601999999679]},"loadCapsule":{"times":[0.14859400000204914,0.14624999999796273,0.3425749999987602,0.18118399999730173,0.17632899999807705,0.17794399999911548]},"getSharedSecret":{"times":[2.357587999998941]},"aes128Decrypt":{"times":[0.20267900000180816,0.8214160000025004]},"getPublicKeysAndPartialAddress":{"times":[1.1464450000021316,1.2483610000017507]},"getKeyValidationRequest":{"times":[1.1774159999986296,1.3055480000002717]},"deliverNote":{"times":[2.216373000002932]},"deleteCapsule":{"times":[0.20556299999952898]},"storeCapsule":{"times":[0.21927099999811617]},"getNotes":{"times":[1.379508000001806]},"notifyNullifiedNote":{"times":[0.4286790000005567]}}},{"functionName":"private_kernel_inner","time":106.2895869999993},{"functionName":"private_kernel_reset","time":178.45186399999875},{"functionName":"private_kernel_tail","time":67.63058499999897}],"unaccounted":546.4886959999967}} 14:25:28 [14:25:28.319] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:28 [14:25:28.340] VERBOSE: p2p:tx_pool Adding tx 0x2ed3080146f33674751c54bd1bac0f6121a2aa7c1082f9e7932810b307ae1fce to pool {"eventName":"tx-added-to-pool","txHash":"0x2ed3080146f33674751c54bd1bac0f6121a2aa7c1082f9e7932810b307ae1fce","noteHashCount":0,"nullifierCount":2,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 14:25:28 [14:25:28.346] INFO: node Received tx 0x2ed3080146f33674751c54bd1bac0f6121a2aa7c1082f9e7932810b307ae1fce {"txHash":"0x2ed3080146f33674751c54bd1bac0f6121a2aa7c1082f9e7932810b307ae1fce"} 14:25:28 [14:25:28.346] INFO: pxe:service Sent transaction 0x2ed3080146f33674751c54bd1bac0f6121a2aa7c1082f9e7932810b307ae1fce 14:25:28 [14:25:28.464] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 14:25:28 [14:25:28.475] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-18T14:31:22.000Z","now":"2025-06-18T14:31:25.169Z"} 14:25:28 [14:25:28.493] VERBOSE: simulator:public-processor Processed tx 0x2ed3080146f33674751c54bd1bac0f6121a2aa7c1082f9e7932810b307ae1fce with no public calls in 11.959068000000116ms {"txHash":"0x2ed3080146f33674751c54bd1bac0f6121a2aa7c1082f9e7932810b307ae1fce","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":11.959068000000116} 14:25:28 [14:25:28.494] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.01847826599999826s {"duration":0.01847826599999826,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1536,"l2Gas":11200},"totalSizeInBytes":288} 14:25:28 [14:25:28.597] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:28 [14:25:28.612] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x07881e0ccc29a3bc0fd795602443a8cd8f8882a382bab613a2e84f6a20ab3f9a","globalVariables":{"chainId":31337,"version":1964831506,"blockNumber":4,"slotNumber":5,"timestamp":1750257082,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x2ed3080146f33674751c54bd1bac0f6121a2aa7c1082f9e7932810b307ae1fce"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":148.60600300000078,"publicProcessDuration":18.813335999999254,"rollupCircuitsDuration":138.08570300000065,"txCount":1,"blockNumber":4,"blockTimestamp":1750257082,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:25:28 [14:25:28.616] VERBOSE: sequencer Attesting committee is empty 14:25:28 [14:25:28.869] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:28 [14:25:28.877] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x4eb9de162cd3f8427987eab47872c205733ccbd0470d8279cbeb5618d979ea31 14:25:28 [14:25:28.877] VERBOSE: sequencer-client Sent L1 transaction 0x4eb9de162cd3f8427987eab47872c205733ccbd0470d8279cbeb5618d979ea31 {"gasLimit":470277,"maxFeePerGas":"1.261127735","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 14:25:28 [14:25:28.885] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750257118] on http://127.0.0.1:8545 14:25:28 [14:25:28.887] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 14:25:28 [14:25:28.890] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 14:25:28 [14:25:28.890] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x4eb9de162cd3f8427987eab47872c205733ccbd0470d8279cbeb5618d979ea31","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":4,"blockTimestamp":1750257082,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:25:28 [14:25:28.890] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 14:25:28 [14:25:28.893] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750257118 14:25:28 [14:25:28.893] WARN: foundation:test-date-provider Time set to 2025-06-18T14:31:58.000Z {"offset":389107,"timeMs":1750257118000} 14:25:28 [14:25:28.893] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 14:25:28 [14:25:28.895] INFO: blob-sink:server Received blob sidecar for block 0xde3f03d8b59b196db839131eb7a1e09e5075713f02a40f6fe97cf13dadfcc513 14:25:28 [14:25:28.899] INFO: blob-sink:server Blob sidecar stored successfully for block 0xde3f03d8b59b196db839131eb7a1e09e5075713f02a40f6fe97cf13dadfcc513 14:25:29 [14:25:29.074] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:29 [14:25:29.077] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 14:25:29 [14:25:29.078] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 14:25:29 [14:25:29.082] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 14:25:29 [14:25:29.348] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:29 [14:25:29.363] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x07881e0ccc29a3bc0fd795602443a8cd8f8882a382bab613a2e84f6a20ab3f9a","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":1964831506,"blockNumber":4,"slotNumber":5,"timestamp":1750257082,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x088424e252c39e42dfc547707aad0e30060d4ee2ee55d35fb5ea939a75696c73","archiveNextLeafIndex":5} 14:25:29 [14:25:29.365] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 14:25:29 [14:25:29.466] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":6.2082529999970575,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1750257082,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 14:25:29 [14:25:29.473] VERBOSE: p2p Synched to latest block 4 14:25:29 [14:25:29.552] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:29 [14:25:29.754] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:29 [14:25:29.912] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x088424e252c39e42dfc547707aad0e30060d4ee2ee55d35fb5ea939a75696c73","blockNumber":5,"slot":6} 14:25:29 [14:25:29.956] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:30 [14:25:30.158] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:30 [14:25:30.360] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:30 [14:25:30.373] INFO: e2e:e2e_keys Running test: Keys ovsk_app gets ovsk_app 14:25:30 [14:25:30.386] INFO: pxe:service Simulating transaction execution request to 0x27e740b2 at 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e {"origin":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x00000000000000000000000000000000000000000000000000000000751cf312","authWitnesses":["0x02dfc8f67846b1dd40abfe95acb29d4cf994d4a3d7165142fc54aa189e4c1a14"]} 14:25:30 [14:25:30.406] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x088424e252c39e42dfc547707aad0e30060d4ee2ee55d35fb5ea939a75696c73","header":{"contentCommitment":{"blobsHash":"0x0049f10db011a617d3f6cd9ddcb1c39d90ffcfbeb12d8c95e4116437d602618d","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750257082,"version":1964831506},"lastArchive":"0x13d91d57da2f311cde571beaccb12634f2dbd54cafb69715f36c13c511c7fb69","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x0cfa016c937a4df962c1dfc299158d18f086fb46eddafef088b04c695574436e","nullifierTree":"0x27349c03131c954c87da163e9420362b9d631cb19c489fa22f261c02443d10d5","publicDataTree":"0x055a8d0b6631cb82cb26c827eb4246f032b67d824e9476205cb877c2f1e154ba"},"totalFees":567840000,"totalManaUsed":11200}} 14:25:30 [14:25:30.474] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:30 [14:25:30.508] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:30 [14:25:30.509] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:30 [14:25:30.538] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x088424e252c39e42dfc547707aad0e30060d4ee2ee55d35fb5ea939a75696c73","blockNumber":5,"slot":6} 14:25:30 [14:25:30.550] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e"} 14:25:30 [14:25:30.554] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2c8f8a8bafaf50594c411ec5e9b4d88aef22b7f85b5e0ae7c126d92b6e8bc0a5 14:25:30 [14:25:30.563] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:25:30 [14:25:30.564] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 14:25:30 [14:25:30.567] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:30 [14:25:30.574] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:25:30 [14:25:30.574] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:25:30 [14:25:30.577] VERBOSE: pxe:service Added note {"index":0,"contract":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2d426b63f8fce18e1af5582725446483f32d5610cbec469098e49f8f7fcd21db","nullifier":"0x278779a71a9bd48340ce55898b631e23d960017219b56ab67a427da0d2e724eb"} 14:25:30 [14:25:30.578] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:30 [14:25:30.589] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e as fee payer 14:25:30 [14:25:30.795] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:30 [14:25:30.896] VERBOSE: simulator:private_execution Executing private function Test:get_ovsk_app {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:30 [14:25:30.907] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 14:25:30 [14:25:30.908] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:31 [14:25:31.031] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae"} 14:25:31 [14:25:31.035] VERBOSE: pxe:service Removed note for contract 0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae at slot 0x000000000000000000000000000000000000000000000000000000000000000c {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","nullifier":"0x0e364c297b7eb6e68f09c78425fb9ab9d730f95792883561703c4c550f83a877"} 14:25:31 [14:25:31.037] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1bfa9f3f1157cd0353d1201d6c85f5e197644f4cc31ac19f56af5c67c021a527 14:25:31 [14:25:31.050] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 14:25:31 [14:25:31.051] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 14:25:31 [14:25:31.061] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 14:25:31 [14:25:31.061] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 14:25:31 [14:25:31.063] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x088424e252c39e42dfc547707aad0e30060d4ee2ee55d35fb5ea939a75696c73","blockNumber":5,"slot":6} 14:25:31 [14:25:31.064] VERBOSE: pxe:service Added note {"index":128,"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x20570e2f9871027b6964911a95555cbe03326916b088e4e555b31b35566735cd","nullifier":"0x0e364c297b7eb6e68f09c78425fb9ab9d730f95792883561703c4c550f83a877"} 14:25:31 [14:25:31.065] VERBOSE: pxe:service Removed just-added note {"contract":"0x0242fc2953ec175e8921b5996ab8335c7330898be8c175196f206727a65faaae","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x20570e2f9871027b6964911a95555cbe03326916b088e4e555b31b35566735cd","nullifier":"0x0e364c297b7eb6e68f09c78425fb9ab9d730f95792883561703c4c550f83a877"} 14:25:31 [14:25:31.067] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 14:25:31 [14:25:31.147] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:31 [14:25:31.383] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:31 [14:25:31.720] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:31 [14:25:31.862] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x088424e252c39e42dfc547707aad0e30060d4ee2ee55d35fb5ea939a75696c73","blockNumber":5,"slot":6} 14:25:32 [14:25:32.099] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 14:25:32 [14:25:32.128] INFO: pxe:service Simulation completed for 0x28862490539f9a39ac5cda30912a490da17607942fea8fdc1f0b3ec2fc13ec52 in 1741.5170340000004ms {"txHash":"0x28862490539f9a39ac5cda30912a490da17607942fea8fdc1f0b3ec2fc13ec52","origin":"0x0812a534139604a2c857b22ada27828161897ace203384f7e6d90127948d9e5e","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x00000000000000000000000000000000000000000000000000000000751cf312","authWitnesses":["0x02dfc8f67846b1dd40abfe95acb29d4cf994d4a3d7165142fc54aa189e4c1a14"]} 14:25:32 [14:25:32.130] INFO: node Stopping 14:25:32 [14:25:32.131] INFO: slasher Slasher client stopped. 14:25:32 [14:25:32.131] INFO: sequencer Stopped sequencer 14:25:32 [14:25:32.131] INFO: p2p P2P client stopped. 14:25:32 [14:25:32.133] INFO: world_state Stopped world state synchronizer 14:25:32 [14:25:32.133] INFO: archiver Stopped. 14:25:32 [14:25:32.133] INFO: node Stopped 14:25:32 [14:25:32.133] VERBOSE: e2e:e2e_keys Cleaning up ACVM state 14:25:32 [14:25:32.133] INFO: e2e:e2e_keys Cleaning up ACVM temp directory /tmp/8f7e220d 14:25:32 [14:25:32.134] VERBOSE: e2e:e2e_keys Cleaning up BB state 14:25:32 [14:25:32.142] INFO: blob-sink:server Stopping blob sink 14:25:32 [14:25:32.143] INFO: blob-sink:server Blob sink stopped 14:25:32 [14:25:32.143] VERBOSE: e2e:e2e_keys Cleaning up data directory at /tmp/53345cc4715b421e 14:25:32 PASS src/e2e_keys.test.ts 14:25:32 Keys 14:25:32 using nsk_app to detect nullification 14:25:32 ✓ nsk_app and contract address are enough to detect note nullification (7749 ms) 14:25:32 ovsk_app 14:25:32 ✓ gets ovsk_app (1756 ms) 14:25:32 14:25:32 Test Suites: 1 passed, 1 total 14:25:32 Tests: 2 passed, 2 total 14:25:32 Snapshots: 0 total 14:25:32 Time: 26.073 s 14:25:32 Ran all test suites matching /src\/e2e_keys.test.ts/i. 14:25:32 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?