Parent Log: http://ci.aztec-labs.com/d807837b95d20001 Command: 97e7ef9a556fd7e6:ISOLATE=1:NAME=e2e_keys yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_keys.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/f3a077dd83e56ee0ae0a1175f0dc39fdfa50e6d7 Env: REF_NAME=gh-readonly-queue/master/pr-14734-a229cf871638d3fd774f9272645cc1ac29b8196a CURRENT_VERSION=0.87.9 CI_FULL=1 Date: Tue Jun 24 10:37:12 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14734_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c393731fea71056e_master 10:37:12 +++ id -u 10:37:12 +++ id -g 10:37:12 ++ docker run -d --name e2e_keys --net=none --cpuset-cpus=0-127 --cpus=2 --memory=8g --user 1000:1000 -v/home/aztec-dev:/home/aztec-dev --mount type=tmpfs,target=/tmp,tmpfs-size=1g --workdir /home/aztec-dev/aztec-packages -e HOME -e VERBOSE -e GIT_CONFIG_GLOBAL=/home/aztec-dev/aztec-packages/build-images/src/home/.gitconfig -e FORCE_COLOR=true -e CPUS -e MEM aztecprotocol/build:3.0 /bin/bash -c 'timeout -v 600s bash -c '\''yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_keys.test.ts'\''' 10:37:12 + cid=9c918007cfac0509dbb2b9ddc95120d3aff3b6b17f884f9b4e6925bdeb41e62d 10:37:12 + set +x 10:37:13 {"level":25,"time":1750761433439,"pid":12,"hostname":"9c918007cfac","module":"logger","msg":"Logger initialized with level verbose"} 10:37:19 [10:37:19.890] INFO: e2e:e2e_keys Logging metrics to log/unknown.jsonl 10:37:20 [10:37:20.262] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-aQfDSV/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 10:37:20 [10:37:20.300] INFO: e2e:e2e_keys Calling anvil_setBlockTimestampInterval with params: [12] 10:37:20 [10:37:20.333] WARN: e2e:e2e_keys Set block interval to 12 10:37:20 [10:37:20.334] VERBOSE: e2e:e2e_keys Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 10:37:20 [10:37:20.382] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:37:20 [10:37:20.390] VERBOSE: e2e:e2e_keys Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 10:37:20 [10:37:20.411] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:37:20 [10:37:20.417] VERBOSE: e2e:e2e_keys Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 10:37:20 [10:37:20.438] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 10:37:20 [10:37:20.444] VERBOSE: e2e:e2e_keys Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 10:37:20 [10:37:20.460] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 10:37:20 [10:37:20.465] VERBOSE: e2e:e2e_keys Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 10:37:20 [10:37:20.481] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 10:37:20 [10:37:20.485] VERBOSE: e2e:e2e_keys Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 10:37:20 [10:37:20.495] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 10:37:20 [10:37:20.499] VERBOSE: e2e:e2e_keys Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 10:37:20 [10:37:20.500] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 10:37:20 [10:37:20.500] INFO: e2e:e2e_keys Waiting for 6 transactions to be mined... 10:37:20 [10:37:20.521] INFO: e2e:e2e_keys All transactions mined successfully 10:37:20 [10:37:20.540] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 10:37:20 [10:37:20.541] VERBOSE: e2e:e2e_keys Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 10:37:20 [10:37:20.553] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 10:37:20 [10:37:20.568] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 10:37:20 [10:37:20.575] VERBOSE: e2e:e2e_keys Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 10:37:20 [10:37:20.589] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 10:37:20 [10:37:20.590] VERBOSE: e2e:e2e_keys Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 10:37:20 [10:37:20.610] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 10:37:20 [10:37:20.615] VERBOSE: e2e:e2e_keys Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 10:37:20 [10:37:20.630] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 10:37:20 [10:37:20.631] VERBOSE: e2e:e2e_keys Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 10:37:20 [10:37:20.631] VERBOSE: e2e:e2e_keys Waiting for deployments to complete 10:37:20 [10:37:20.632] INFO: e2e:e2e_keys Waiting for 8 transactions to be mined... 10:37:20 [10:37:20.660] INFO: e2e:e2e_keys All transactions mined successfully 10:37:20 [10:37:20.686] VERBOSE: e2e:e2e_keys Deployed shared contracts 10:37:20 [10:37:20.703] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 10:37:20 [10:37:20.703] VERBOSE: e2e:e2e_keys Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 10:37:20 [10:37:20.706] 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} 10:37:20 [10:37:20.726] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 10:37:20 [10:37:20.754] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 10:37:20 [10:37:20.759] VERBOSE: e2e:e2e_keys Awaiting for linked libraries to be deployed 10:37:20 [10:37:20.807] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x4168ca5e72317c56bc836c415b4286681285963b6239e5d93b1c9813ce4f9935 {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 10:37:20 [10:37:20.818] 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} 10:37:20 [10:37:20.819] INFO: e2e:e2e_keys Waiting for 9 transactions to be mined... 10:37:20 [10:37:20.844] INFO: e2e:e2e_keys All transactions mined successfully 10:37:20 [10:37:20.845] VERBOSE: e2e:e2e_keys All core contracts have been deployed 10:37:20 [10:37:20.862] VERBOSE: e2e:e2e_keys Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 10:37:20 [10:37:20.863] VERBOSE: e2e:e2e_keys Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 10:37:20 [10:37:20.874] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 10:37:20 [10:37:20.878] VERBOSE: e2e:e2e_keys Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 10:37:20 [10:37:20.896] VERBOSE: e2e:e2e_keys Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 10:37:20 [10:37:20.896] VERBOSE: e2e:e2e_keys Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 10:37:20 [10:37:20.897] INFO: e2e:e2e_keys Waiting for 10 transactions to be mined... 10:37:20 [10:37:20.922] INFO: e2e:e2e_keys All transactions mined successfully 10:37:20 [10:37:20.929] VERBOSE: e2e:e2e_keys Rollup deployed 10:37:20 [10:37:20.929] VERBOSE: e2e:e2e_keys Waiting for rollup and slash factory to be deployed 10:37:20 [10:37:20.930] INFO: e2e:e2e_keys Waiting for 10 transactions to be mined... 10:37:20 [10:37:20.948] INFO: e2e:e2e_keys All transactions mined successfully 10:37:20 [10:37:20.948] VERBOSE: e2e:e2e_keys All transactions for L1 deployment have been mined 10:37:20 [10:37:20.968] 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"} 10:37:20 [10:37:20.972] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 10:37:20 [10:37:20.975] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:20 [10:37:20.976] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 10:37:20 [10:37:20.977] INFO: telemetry:client Using NoopTelemetryClient 10:37:20 [10:37:20.983] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/b5eb015a0a5e0986/blob-sink with map size 134217728 KB (LMDB v2) 10:37:20 [10:37:20.984] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 10:37:20 [10:37:20.984] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 10:37:20 [10:37:20.987] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:20 [10:37:20.989] INFO: aztecjs:utils:watcher New L1 time: 1750761679 10:37:20 [10:37:20.989] WARN: foundation:test-date-provider Time set to 2025-06-24T10:41:19.000Z {"offset":238011,"timeMs":1750761679000} 10:37:20 [10:37:20.991] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 10:37:21 [10:37:20.997] INFO: blob-sink:server Server is running on http://localhost:41627 10:37:21 [10:37:20.997] VERBOSE: e2e:e2e_keys Creating and synching an aztec node... 10:37:21 [10:37:20.999] VERBOSE: e2e:e2e_keys Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/398cb704/acvm 10:37:21 [10:37:21.006] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:41627"} 10:37:21 [10:37:21.078] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 10:37:21 [10:37:21.080] INFO: archiver:lmdb Creating archiver data store at directory /tmp/b5eb015a0a5e0986/archiver with map size 134217728 KB (LMDB v2) 10:37:21 [10:37:21.144] INFO: archiver:lmdb Starting data store with maxReaders 16 10:37:21 [10:37:21.249] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:21 [10:37:21.466] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:21 [10:37:21.559] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:41627"} 10:37:21 [10:37:21.574] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:41627"} 10:37:21 [10:37:21.574] WARN: node:blob-sink:client No L1 consensus host urls configured 10:37:21 [10:37:21.574] WARN: node:blob-sink:client No archive client configured 10:37:21 [10:37:21.577] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x2a62952b17facd908e5b7fd298260c5fcc10be6297246b70000e89557e66197e {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x20d0359d3161e347be6a8384530c73b3488a88f217f83c6b3887468104b5dec3","l1BlockNumber":16}} 10:37:21 [10:37:21.591] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 10:37:21 [10:37:21.599] INFO: world-state:database Creating world state data store at directory /tmp/b5eb015a0a5e0986/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 10:37:21 [10:37:21.625] INFO: world_state Created world state synchroniser with block history of 64 10:37:21 [10:37:21.625] WARN: node Aztec node is accepting fake proofs 10:37:21 [10:37:21.636] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/b5eb015a0a5e0986/p2p with map size 134217728 KB (LMDB v2) 10:37:21 [10:37:21.638] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 10:37:21 [10:37:21.639] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/b5eb015a0a5e0986/p2p-archive with map size 134217728 KB (LMDB v2) 10:37:21 [10:37:21.640] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 10:37:21 [10:37:21.641] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/b5eb015a0a5e0986/p2p-peers with map size 134217728 KB (LMDB v2) 10:37:21 [10:37:21.642] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 10:37:21 [10:37:21.644] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 10:37:21 [10:37:21.645] VERBOSE: p2p P2P is disabled. Using dummy P2P service 10:37:21 [10:37:21.647] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 10:37:21 [10:37:21.648] INFO: world_state Started world state synchronizer from block 1 10:37:21 [10:37:21.650] VERBOSE: world-state:block_stream Starting sync from block number 0 10:37:21 [10:37:21.652] INFO: p2p Starting p2p client from block 0 with empty mempools 10:37:21 [10:37:21.652] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 10:37:21 [10:37:21.653] INFO: slasher Slasher client initialized 10:37:21 [10:37:21.653] INFO: slasher Starting Slasher client... 10:37:21 [10:37:21.694] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:37:21 [10:37:21.694] VERBOSE: node All Aztec Node subsystems synced 10:37:21 [10:37:21.702] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:21 [10:37:21.705] INFO: sequencer-client Deploying forwarder contract 10:37:21 [10:37:21.711] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 10:37:21 [10:37:21.728] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 10:37:21 [10:37:21.728] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 10:37:21 [10:37:21.734] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 10:37:21 [10:37:21.747] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:37:21 [10:37:21.748] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 10:37:21 [10:37:21.748] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/398cb704/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 10:37:22 [10:37:22.012] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 10:37:22 [10:37:22.017] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:37:22 [10:37:22.020] INFO: node Aztec Node version: 0.87.9 10:37:22 [10:37:22.020] 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"} 10:37:22 [10:37:22.022] VERBOSE: e2e:e2e_keys Creating a pxe... 10:37:22 [10:37:22.034] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:22 [10:37:22.044] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/9df3030257295047/pxe_data with map size 134217728 KB (LMDB v2) 10:37:22 [10:37:22.058] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x18999a03c98f534182bc13056a558ec3435c97671d79bd1d588b80d24970b681","blockNumber":1,"slot":2} 10:37:22 [10:37:22.059] INFO: pxe:data:lmdb Starting data store with maxReaders 16 10:37:22 [10:37:22.258] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:22 [10:37:22.371] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 10:37:22 [10:37:22.373] INFO: pxe:service Started PXE connected to chain 31337 version 1188152374 10:37:22 [10:37:22.438] INFO: pxe:service Registered account 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 10:37:22 [10:37:22.526] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:22 [10:37:22.529] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 10:37:22 [10:37:22.533] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 10:37:22 [10:37:22.648] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x18999a03c98f534182bc13056a558ec3435c97671d79bd1d588b80d24970b681","blockNumber":1,"slot":2} 10:37:22 [10:37:22.711] INFO: pxe:service Added contract SchnorrAccount at 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 10:37:22 [10:37:22.755] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:22 [10:37:22.761] VERBOSE: pxe:block_stream Starting sync from block number 0 10:37:22 [10:37:22.779] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 10:37:22 [10:37:22.935] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:37:23 [10:37:23.641] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 10:37:23 [10:37:23.785] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 10:37:23 [10:37:23.866] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:23 [10:37:23.919] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x18999a03c98f534182bc13056a558ec3435c97671d79bd1d588b80d24970b681","blockNumber":1,"slot":2} 10:37:24 [10:37:24.036] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:24 [10:37:24.094] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:24 [10:37:24.104] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:24 [10:37:24.105] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:24 [10:37:24.190] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:24 [10:37:24.195] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:24 [10:37:24.340] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:24 [10:37:24.489] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:24 [10:37:24.546] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:24 [10:37:24.546] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:24 [10:37:24.556] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:24 [10:37:24.621] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x18999a03c98f534182bc13056a558ec3435c97671d79bd1d588b80d24970b681","blockNumber":1,"slot":2} 10:37:24 [10:37:24.633] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:24 [10:37:24.638] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:24 [10:37:24.658] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 as fee payer 10:37:24 [10:37:24.763] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:25 [10:37:25.315] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:25 [10:37:25.490] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x18999a03c98f534182bc13056a558ec3435c97671d79bd1d588b80d24970b681","blockNumber":1,"slot":2} 10:37:25 [10:37:25.674] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:26 [10:37:26.203] INFO: pxe:service Proving completed in 3448.2158560000007ms {"timings":{"total":3448.2158560000007,"sync":6.494356999999582,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":213.2437330000048,"oracles":{"callPrivateFunction":{"times":[1019.0468860000001,500.9789610000007,310.3364840000013]}}},{"functionName":"private_kernel_init","time":161.09818799999994},{"functionName":"ContractClassRegisterer:register","time":993.2814709999984,"oracles":{"loadCapsule":{"times":[52.29237599999942]},"notifyCreatedNullifier":{"times":[0.9131779999988794]},"debugLog":{"times":[1.2708849999999074]},"notifyCreatedContractClassLog":{"times":[7.876333999998678]}}},{"functionName":"private_kernel_inner","time":215.11122700000124},{"functionName":"SchnorrAccount:constructor","time":461.24313099999927,"oracles":{"getContractInstance":{"times":[13.627087000000756]},"debugLog":{"times":[0.6233919999995123,0.5449709999993502]},"fetchTaggedLogs":{"times":[89.16052800000034]},"loadCapsule":{"times":[0.4131780000006984,0.31528700000126264,0.26402500000040163]},"notifyCreatedNullifier":{"times":[0.2009940000007191,0.37544700000034936]},"notifyCreatedNote":{"times":[0.41463800000019546]},"getRandomField":{"times":[0.36796700000013516,0.25811500000054366,0.12002300000131072,0.08389199999874108,0.0918020000008255,0.076560999999856,0.07894099999975879,0.058460999998715124,0.047510999998849,0.0869910000001255,0.04542099999889615]},"getIndexedTaggingSecretAsSender":{"times":[72.5593319999989]},"incrementAppTaggingSecretIndexAsSender":{"times":[61.1784090000001]}}},{"functionName":"private_kernel_inner","time":172.38272899999902},{"functionName":"SchnorrAccount:entrypoint","time":245.40493999999853,"oracles":{"debugLog":{"times":[0.789665000000241,0.5521210000006249,0.8024859999986802]},"fetchTaggedLogs":{"times":[90.8474210000004]},"loadCapsule":{"times":[0.3379869999989751,0.33125699999982317,0.245295000000624]},"getNotes":{"times":[2.4383379999999306]},"getAuthWitness":{"times":[0.43459900000016205]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.005419000000984]}}},{"functionName":"private_kernel_inner","time":124.4185690000013},{"functionName":"private_kernel_reset","time":208.65386099999887},{"functionName":"private_kernel_tail","time":81.91598599999998}],"unaccounted":564.9676639999998}} 10:37:26 [10:37:26.216] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:26 [10:37:26.290] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x18999a03c98f534182bc13056a558ec3435c97671d79bd1d588b80d24970b681","blockNumber":1,"slot":2} 10:37:26 [10:37:26.409] VERBOSE: p2p:tx_pool Adding tx 0x2078752db18714fc79ecbaf504eb55147f90b7a15c3c90ca032e11956fbeb70c to pool {"eventName":"tx-added-to-pool","txHash":"0x2078752db18714fc79ecbaf504eb55147f90b7a15c3c90ca032e11956fbeb70c","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 10:37:26 [10:37:26.441] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:26 [10:37:26.447] INFO: node Received tx 0x2078752db18714fc79ecbaf504eb55147f90b7a15c3c90ca032e11956fbeb70c {"txHash":"0x2078752db18714fc79ecbaf504eb55147f90b7a15c3c90ca032e11956fbeb70c"} 10:37:26 [10:37:26.447] INFO: pxe:service Sent transaction 0x2078752db18714fc79ecbaf504eb55147f90b7a15c3c90ca032e11956fbeb70c 10:37:26 [10:37:26.650] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:26 [10:37:26.808] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 10:37:26 [10:37:26.823] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-24T10:41:43.000Z","now":"2025-06-24T10:41:24.834Z"} 10:37:26 [10:37:26.918] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:27 [10:37:27.152] VERBOSE: simulator:public-processor Processed tx 0x2078752db18714fc79ecbaf504eb55147f90b7a15c3c90ca032e11956fbeb70c with no public calls in 218.35008000000016ms {"txHash":"0x2078752db18714fc79ecbaf504eb55147f90b7a15c3c90ca032e11956fbeb70c","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":218.35008000000016} 10:37:27 [10:37:27.154] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:27 [10:37:27.157] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.33213474099999984s {"duration":0.33213474099999984,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 10:37:27 [10:37:27.296] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x15cdba25c6ac196e88b2b2ffe7358ef329d35035b465b7029ca40a44581cf710","globalVariables":{"chainId":31337,"version":1188152374,"blockNumber":1,"slotNumber":2,"timestamp":1750761703,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x2078752db18714fc79ecbaf504eb55147f90b7a15c3c90ca032e11956fbeb70c"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":489.0067760000002,"publicProcessDuration":333.236891999999,"rollupCircuitsDuration":474.99364199999945,"txCount":1,"blockNumber":1,"blockTimestamp":1750761703,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:37:27 [10:37:27.299] VERBOSE: sequencer Attesting committee is empty 10:37:27 [10:37:27.389] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:27 [10:37:27.710] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:27 [10:37:27.715] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xf4d7004ea000445a4d4d8e1f791bc3f0e8e58ca84168c0e25a367e70b9a3ec7c 10:37:27 [10:37:27.716] VERBOSE: sequencer-client Sent L1 transaction 0xf4d7004ea000445a4d4d8e1f791bc3f0e8e58ca84168c0e25a367e70b9a3ec7c {"gasLimit":514599,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 10:37:27 [10:37:27.739] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:37:27 [10:37:27.741] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xf4d7004ea000445a4d4d8e1f791bc3f0e8e58ca84168c0e25a367e70b9a3ec7c","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":1,"blockTimestamp":1750761703,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:37:27 [10:37:27.742] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:37:27 [10:37:27.747] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750761739] on http://127.0.0.1:8545 10:37:27 [10:37:27.750] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:37:27 [10:37:27.772] INFO: blob-sink:server Received blob sidecar for block 0x03e0f0d6c7e5bdb7569423602fc74b36e61f5723f148218b5cbf7720b2a52aa7 10:37:27 [10:37:27.776] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750761739 10:37:27 [10:37:27.776] WARN: foundation:test-date-provider Time set to 2025-06-24T10:42:19.000Z {"offset":291224,"timeMs":1750761739000} 10:37:27 [10:37:27.777] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 10:37:27 [10:37:27.781] INFO: blob-sink:server Blob sidecar stored successfully for block 0x03e0f0d6c7e5bdb7569423602fc74b36e61f5723f148218b5cbf7720b2a52aa7 10:37:27 [10:37:27.958] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:27 [10:37:27.966] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 10:37:27 [10:37:27.980] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 10:37:27 [10:37:27.982] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 10:37:27 [10:37:27.989] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 10:37:27 [10:37:27.993] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x15cdba25c6ac196e88b2b2ffe7358ef329d35035b465b7029ca40a44581cf710","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":1188152374,"blockNumber":1,"slotNumber":2,"timestamp":1750761703,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x0f65e5e906f7eee6ec07255ec8a2532b05e6d56f43ea853b1b51d8d8d3e3f1fd","archiveNextLeafIndex":2} 10:37:28 [10:37:28.466] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:28 [10:37:28.474] WARN: pxe:service No artifact found for contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f when looking for its metadata 10:37:28 [10:37:28.477] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f as part of deployment for 0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51 10:37:28 [10:37:28.655] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":183.95828599999913,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750761703,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:37:28 [10:37:28.680] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:28 [10:37:28.750] VERBOSE: p2p Synched to latest block 1 10:37:28 [10:37:28.752] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 10:37:29 [10:37:29.091] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:29 [10:37:29.106] INFO: pxe:service Added contract Test at 0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51 with class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 10:37:29 [10:37:29.139] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x0f65e5e906f7eee6ec07255ec8a2532b05e6d56f43ea853b1b51d8d8d3e3f1fd","header":{"contentCommitment":{"blobsHash":"0x008ca3347f0dfae4c7f43949df20e283a1579de4ac48ac7887bb1f775e9a392a","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750761703,"version":1188152374},"lastArchive":"0x18999a03c98f534182bc13056a558ec3435c97671d79bd1d588b80d24970b681","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x0aa8275d516a9f9d15ffd63e9321ec4ff7cd3f77ca9520a0c20867819a994782","nullifierTree":"0x1414619e90741cb60adfcd2f06f0dc86329da7a4ef66ca1613a71f678e770203","publicDataTree":"0x234c9f0471b071f07f0f0e5d47084c41ebe03d438f3d0246761b341cc42eccb9"},"totalFees":6794052480,"totalManaUsed":20448}} 10:37:29 [10:37:29.259] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:29 [10:37:29.320] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:29 [10:37:29.320] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:29 [10:37:29.330] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:29 [10:37:29.386] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0f65e5e906f7eee6ec07255ec8a2532b05e6d56f43ea853b1b51d8d8d3e3f1fd","blockNumber":2,"slot":3} 10:37:29 [10:37:29.452] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:29 [10:37:29.463] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x256343772546dc602060552b1628eafe95ebb587709ed24818c36bb8922d4d2d 10:37:29 [10:37:29.482] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:37:29 [10:37:29.483] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:37:29 [10:37:29.497] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:37:29 [10:37:29.497] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:37:29 [10:37:29.505] VERBOSE: pxe:service Added note {"index":0,"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x11cdf14090bc4dd68da9b99d20492691a1d55326ba3fa963d5be56e88a79770f","nullifier":"0x257ad9b96d02c12403753b3f0fb547d1feb87120d72983a5ba984e10145d0cac"} 10:37:29 [10:37:29.507] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:29 [10:37:29.525] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 as fee payer 10:37:29 [10:37:29.542] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:29 [10:37:29.566] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:37:30 [10:37:30.179] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0231f367ddb29264182678ec70e69bd06340efa7dc780f1c7522d13c96eed157,0x08fed0d02dac97b0ce237807685976eba973a7f517fc0723c067c7b7b6e121cc,0x11714ee3dfbdb30a8b2975f186d75eb58f17897f0b8ce770a03c05432642725e 10:37:30 [10:37:30.340] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 10:37:30 [10:37:30.496] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 10:37:30 [10:37:30.509] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0f65e5e906f7eee6ec07255ec8a2532b05e6d56f43ea853b1b51d8d8d3e3f1fd","blockNumber":2,"slot":3} 10:37:30 [10:37:30.546] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:37:30 [10:37:30.562] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51,0x0000000000000000000000000000000000000000000000000000000000000001,0x26bda15406a3e94752ad4e9e8637618ec5784c3fd04f3487d584a846d536f06f,0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f,0x0eea76e1163ccfa69030e0733759dce0ccf602f045e7b4a3d9ec9b0864e70308,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 10:37:30 [10:37:30.721] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:31 [10:37:31.257] VERBOSE: simulator:private_execution Executing private function Test:initialize {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:31 [10:37:31.298] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:31 [10:37:31.312] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:31 [10:37:31.313] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:31 [10:37:31.407] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0f65e5e906f7eee6ec07255ec8a2532b05e6d56f43ea853b1b51d8d8d3e3f1fd","blockNumber":2,"slot":3} 10:37:31 [10:37:31.572] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:31 [10:37:31.622] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:31 [10:37:31.628] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:31 [10:37:31.866] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:32 [10:37:32.112] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:32 [10:37:32.200] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0f65e5e906f7eee6ec07255ec8a2532b05e6d56f43ea853b1b51d8d8d3e3f1fd","blockNumber":2,"slot":3} 10:37:32 [10:37:32.461] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:32 [10:37:32.716] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:33 [10:37:33.101] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:33 [10:37:33.697] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0f65e5e906f7eee6ec07255ec8a2532b05e6d56f43ea853b1b51d8d8d3e3f1fd","blockNumber":2,"slot":3} 10:37:34 [10:37:34.021] INFO: pxe:service Proving completed in 4891.57732ms {"timings":{"total":4891.57732,"sync":13.256279999997787,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":797.1118139999999,"oracles":{"debugLog":{"times":[0.7027840000009746,0.6191819999985455,0.5671609999990324,0.8326860000015586,0.5870319999994535,0.5302999999985332,0.4570789999997942,0.8139960000007704]},"fetchTaggedLogs":{"times":[138.98379399999976]},"loadCapsule":{"times":[0.577871999998024,0.5632509999995818,0.6646419999997306,0.32325700000001234,0.28560599999764236,0.46307900000101654]},"getSharedSecret":{"times":[8.78128199999992]},"aes128Decrypt":{"times":[1.3128060000017285,1.231103999998595]},"getPublicKeysAndPartialAddress":{"times":[2.9805880000021716]},"getKeyValidationRequest":{"times":[5.85112499999741]},"deliverNote":{"times":[7.3077839999969]},"deleteCapsule":{"times":[0.5727009999973234]},"storeCapsule":{"times":[0.5148499999995693]},"getNotes":{"times":[2.251075000000128]},"getAuthWitness":{"times":[0.28862500000104774]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.13823300000149175]},"callPrivateFunction":{"times":[881.9578189999993,158.0565979999992,1064.2086420000014]}}},{"functionName":"private_kernel_init","time":103.24495399999796},{"functionName":"ContractClassRegisterer:register","time":866.1582699999999,"oracles":{"loadCapsule":{"times":[5.649351000000024]},"notifyCreatedNullifier":{"times":[1.4994399999995949]},"debugLog":{"times":[1.5177900000016962]},"notifyCreatedContractClassLog":{"times":[12.470024999998714]}}},{"functionName":"private_kernel_inner","time":131.18302199999744},{"functionName":"ContractInstanceDeployer:deploy","time":101.76887500000157,"oracles":{"storeInExecutionCache":{"times":[0.2952260000020033]},"callPrivateFunction":{"times":[54.86665499999799]},"notifyCreatedNullifier":{"times":[0.35573699999804376]},"debugLog":{"times":[0.6186720000005153]}}},{"functionName":"private_kernel_inner","time":226.55120199999874},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":40.412992000001395,"oracles":{}},{"functionName":"private_kernel_inner","time":154.39242699999886},{"functionName":"Test:initialize","time":660.7149430000027,"oracles":{"getContractInstance":{"times":[28.825824999999895]},"debugLog":{"times":[1.1170720000009169,0.4622289999970235]},"fetchTaggedLogs":{"times":[312.56011800000124]},"loadCapsule":{"times":[1.0163500000016938,0.24487500000032014,0.20362400000158232]},"notifyCreatedNullifier":{"times":[0.26837499999965075]}}},{"functionName":"private_kernel_inner","time":142.57229400000142},{"functionName":"private_kernel_reset","time":232.25281299999915},{"functionName":"private_kernel_tail","time":89.18621899999926}],"unaccounted":1332.7712150000043}} 10:37:34 [10:37:34.046] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:34 [10:37:34.263] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:34 [10:37:34.278] VERBOSE: p2p:tx_pool Adding tx 0x2714c9d5a5c799ebb0bdb6c47a4604811a2258dd2fe392e6a46795adff919ca3 to pool {"eventName":"tx-added-to-pool","txHash":"0x2714c9d5a5c799ebb0bdb6c47a4604811a2258dd2fe392e6a46795adff919ca3","noteHashCount":0,"nullifierCount":4,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":583,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 10:37:34 [10:37:34.309] INFO: node Received tx 0x2714c9d5a5c799ebb0bdb6c47a4604811a2258dd2fe392e6a46795adff919ca3 {"txHash":"0x2714c9d5a5c799ebb0bdb6c47a4604811a2258dd2fe392e6a46795adff919ca3"} 10:37:34 [10:37:34.310] INFO: pxe:service Sent transaction 0x2714c9d5a5c799ebb0bdb6c47a4604811a2258dd2fe392e6a46795adff919ca3 10:37:34 [10:37:34.330] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 10:37:34 [10:37:34.348] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-24T10:42:19.000Z","now":"2025-06-24T10:42:25.572Z"} 10:37:34 [10:37:34.596] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:34 [10:37:34.688] VERBOSE: simulator:public-processor Processed tx 0x2714c9d5a5c799ebb0bdb6c47a4604811a2258dd2fe392e6a46795adff919ca3 with no public calls in 236.64304899999843ms {"txHash":"0x2714c9d5a5c799ebb0bdb6c47a4604811a2258dd2fe392e6a46795adff919ca3","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":236.64304899999843} 10:37:34 [10:37:34.690] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.34184995199999685s {"duration":0.34184995199999685,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":310272,"l2Gas":21888},"totalSizeInBytes":19648} 10:37:34 [10:37:34.895] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:34 [10:37:34.912] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2dd866cb4a300576279da786cbd72ae0522b515760bed13f9a8dcdd43d86ab27","globalVariables":{"chainId":31337,"version":1188152374,"blockNumber":2,"slotNumber":3,"timestamp":1750761739,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x2714c9d5a5c799ebb0bdb6c47a4604811a2258dd2fe392e6a46795adff919ca3"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":582.328975999997,"publicProcessDuration":342.30150100000174,"rollupCircuitsDuration":565.6918389999992,"txCount":1,"blockNumber":2,"blockTimestamp":1750761739,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 10:37:34 [10:37:34.916] VERBOSE: sequencer Attesting committee is empty 10:37:35 [10:37:35.100] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:35 [10:37:35.251] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x2d81101a6945ead62cc22df08f2aa32379a75ec1e14b2af233b63ca194df9155 10:37:35 [10:37:35.252] VERBOSE: sequencer-client Sent L1 transaction 0x2d81101a6945ead62cc22df08f2aa32379a75ec1e14b2af233b63ca194df9155 {"gasLimit":470298,"maxFeePerGas":"1.303611222","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:37:35 [10:37:35.266] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750761775] on http://127.0.0.1:8545 10:37:35 [10:37:35.270] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:37:35 [10:37:35.275] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:37:35 [10:37:35.276] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x2d81101a6945ead62cc22df08f2aa32379a75ec1e14b2af233b63ca194df9155","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":2,"blockTimestamp":1750761739,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 10:37:35 [10:37:35.276] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:37:35 [10:37:35.279] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750761775 10:37:35 [10:37:35.279] WARN: foundation:test-date-provider Time set to 2025-06-24T10:42:55.000Z {"offset":319721,"timeMs":1750761775000} 10:37:35 [10:37:35.280] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 10:37:35 [10:37:35.285] INFO: blob-sink:server Received blob sidecar for block 0xbdf8ecbd55d8797af1ae80d3d42c2cc503845c790b01103a7bc65973b1ebd11d 10:37:35 [10:37:35.293] INFO: blob-sink:server Blob sidecar stored successfully for block 0xbdf8ecbd55d8797af1ae80d3d42c2cc503845c790b01103a7bc65973b1ebd11d 10:37:35 [10:37:35.303] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:35 [10:37:35.315] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 10:37:35 [10:37:35.316] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 10:37:35 [10:37:35.318] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 10:37:35 [10:37:35.506] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:35 [10:37:35.711] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:35 [10:37:35.970] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:36 [10:37:36.004] VERBOSE: archiver:block-helper Store contract instance at 0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51 10:37:36 [10:37:36.031] VERBOSE: archiver:block-helper Store contract class 0x0cd6e6e7b5b2a59ff6e9f185228de43b82c3ec609c26a6e21bb33fa5d38bc08f 10:37:36 [10:37:36.067] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x2dd866cb4a300576279da786cbd72ae0522b515760bed13f9a8dcdd43d86ab27","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":1188152374,"blockNumber":2,"slotNumber":3,"timestamp":1750761739,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x12b7b5bd450c8711731d2b7240770b56665bc1fa5b8e9d2cbd76de19167cab7d","archiveNextLeafIndex":3} 10:37:36 [10:37:36.076] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 10:37:36 [10:37:36.108] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":11.234459999999672,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750761739,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":583} 10:37:36 [10:37:36.184] VERBOSE: p2p Synched to latest block 2 10:37:36 [10:37:36.185] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:36 [10:37:36.335] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x12b7b5bd450c8711731d2b7240770b56665bc1fa5b8e9d2cbd76de19167cab7d","blockNumber":3,"slot":4} 10:37:36 [10:37:36.502] INFO: aztecjs:deploy_sent_tx Contract 0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51 successfully deployed. 10:37:36 [10:37:36.506] INFO: e2e:e2e_keys Running test: Keys using nsk_app to detect nullification nsk_app and contract address are enough to detect note nullification 10:37:36 [10:37:36.513] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:36 [10:37:36.544] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x12b7b5bd450c8711731d2b7240770b56665bc1fa5b8e9d2cbd76de19167cab7d","header":{"contentCommitment":{"blobsHash":"0x008bbf302aad96f1e0f1541ce24c744e1e01c0a20a8728c47cbbec4f83336d74","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750761739,"version":1188152374},"lastArchive":"0x0f65e5e906f7eee6ec07255ec8a2532b05e6d56f43ea853b1b51d8d8d3e3f1fd","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x0aa8275d516a9f9d15ffd63e9321ec4ff7cd3f77ca9520a0c20867819a994782","nullifierTree":"0x2832d27c806e2ab3c549b2ba33bb860b3859837679fabbf3a28a02e1f71f8566","publicDataTree":"0x032293f8e54f8ee446b67b715f8d6ddf23c0e60c0354a0b0dccb8bc8c1a879d0"},"totalFees":7272506880,"totalManaUsed":21888}} 10:37:36 [10:37:36.641] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:36 [10:37:36.685] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:36 [10:37:36.685] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:36 [10:37:36.721] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:36 [10:37:36.748] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:36 [10:37:36.756] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x256343772546dc602060552b1628eafe95ebb587709ed24818c36bb8922d4d2d 10:37:36 [10:37:36.769] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:37:36 [10:37:36.770] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:37:36 [10:37:36.778] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:37:36 [10:37:36.779] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:37:36 [10:37:36.782] VERBOSE: pxe:service Added note {"index":0,"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x11cdf14090bc4dd68da9b99d20492691a1d55326ba3fa963d5be56e88a79770f","nullifier":"0x257ad9b96d02c12403753b3f0fb547d1feb87120d72983a5ba984e10145d0cac"} 10:37:36 [10:37:36.784] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:36 [10:37:36.798] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 as fee payer 10:37:36 [10:37:36.951] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:37 [10:37:37.460] VERBOSE: simulator:private_execution Executing private function Test:call_create_note {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:37 [10:37:37.520] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:37 [10:37:37.520] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:37 [10:37:37.531] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:37 [10:37:37.561] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x12b7b5bd450c8711731d2b7240770b56665bc1fa5b8e9d2cbd76de19167cab7d","blockNumber":3,"slot":4} 10:37:37 [10:37:37.746] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:37 [10:37:37.754] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:37 [10:37:37.764] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:38 [10:37:38.085] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:38 [10:37:38.269] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x12b7b5bd450c8711731d2b7240770b56665bc1fa5b8e9d2cbd76de19167cab7d","blockNumber":3,"slot":4} 10:37:38 [10:37:38.337] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:38 [10:37:38.836] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:39 [10:37:39.019] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x12b7b5bd450c8711731d2b7240770b56665bc1fa5b8e9d2cbd76de19167cab7d","blockNumber":3,"slot":4} 10:37:39 [10:37:39.326] INFO: pxe:service Proving completed in 2790.680708ms {"timings":{"total":2790.680708,"sync":11.088130000000092,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":613.7991789999978,"oracles":{"debugLog":{"times":[0.5615819999984524,0.505010000000766,0.47194999999919673,0.6480130000018107,0.4840299999996205,0.4466790000005858,0.4204879999997502,0.47709000000031665]},"fetchTaggedLogs":{"times":[68.6013529999982]},"loadCapsule":{"times":[0.20927400000073249,0.17761400000017602,0.4511400000010326,0.20151400000031572,0.2559959999998682,0.19793399999980466]},"getSharedSecret":{"times":[3.9969029999992927]},"aes128Decrypt":{"times":[0.2583150000027672,1.1319839999996475]},"getPublicKeysAndPartialAddress":{"times":[1.7543960000002699]},"getKeyValidationRequest":{"times":[1.9810919999981706]},"deliverNote":{"times":[3.5807849999982864]},"deleteCapsule":{"times":[0.30504600000131177]},"storeCapsule":{"times":[0.3131169999978738]},"getNotes":{"times":[1.512101000000257]},"getAuthWitness":{"times":[0.2559849999997823]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.048541000000113854]},"callPrivateFunction":{"times":[1308.894386]}}},{"functionName":"private_kernel_init","time":63.67612999999983},{"functionName":"Test:call_create_note","time":933.0714020000014,"oracles":{"debugLog":{"times":[0.8779379999978119,0.4005079999988084]},"fetchTaggedLogs":{"times":[242.5997720000014]},"loadCapsule":{"times":[0.6321930000012799,0.23001400000066496,0.29700599999705446]},"getRandomField":{"times":[0.14286300000094343,0.11996200000066892,0.14519300000029034,0.0633510000006936,0.08192200000121375,0.07180200000220793,0.06626200000027893,0.04564099999697646,0.05578099999911501,0.06163100000048871,0.03766100000211736,0.05428100000062841]},"notifyCreatedNote":{"times":[0.12724299999899813]},"getIndexedTaggingSecretAsSender":{"times":[159.18394099999932]},"incrementAppTaggingSecretIndexAsSender":{"times":[153.42882200000167]}}},{"functionName":"private_kernel_inner","time":151.70053700000062},{"functionName":"private_kernel_reset","time":215.56300000000192},{"functionName":"private_kernel_tail","time":98.48321199999918}],"unaccounted":703.299117999999}} 10:37:39 [10:37:39.342] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:39 [10:37:39.378] VERBOSE: p2p:tx_pool Adding tx 0x2ac9050273feecc70840f5e012f7c2165013b596ac6aa28d8897d8f835aefc49 to pool {"eventName":"tx-added-to-pool","txHash":"0x2ac9050273feecc70840f5e012f7c2165013b596ac6aa28d8897d8f835aefc49","noteHashCount":1,"nullifierCount":1,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 10:37:39 [10:37:39.390] INFO: node Received tx 0x2ac9050273feecc70840f5e012f7c2165013b596ac6aa28d8897d8f835aefc49 {"txHash":"0x2ac9050273feecc70840f5e012f7c2165013b596ac6aa28d8897d8f835aefc49"} 10:37:39 [10:37:39.391] INFO: pxe:service Sent transaction 0x2ac9050273feecc70840f5e012f7c2165013b596ac6aa28d8897d8f835aefc49 10:37:39 [10:37:39.543] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 10:37:39 [10:37:39.549] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:39 [10:37:39.559] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-24T10:42:55.000Z","now":"2025-06-24T10:42:59.280Z"} 10:37:39 [10:37:39.588] VERBOSE: simulator:public-processor Processed tx 0x2ac9050273feecc70840f5e012f7c2165013b596ac6aa28d8897d8f835aefc49 with no public calls in 12.421868000001268ms {"txHash":"0x2ac9050273feecc70840f5e012f7c2165013b596ac6aa28d8897d8f835aefc49","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.421868000001268} 10:37:39 [10:37:39.589] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.029434270999998263s {"duration":0.029434270999998263,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11264,"l2Gas":9760},"totalSizeInBytes":960} 10:37:39 [10:37:39.721] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x07376cefa33d478b662206f19ad5e1a33a25a51fec227cefbcd966728737d11e","globalVariables":{"chainId":31337,"version":1188152374,"blockNumber":3,"slotNumber":4,"timestamp":1750761775,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x2ac9050273feecc70840f5e012f7c2165013b596ac6aa28d8897d8f835aefc49"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":178.20392600000196,"publicProcessDuration":29.8303190000006,"rollupCircuitsDuration":163.93053899999722,"txCount":1,"blockNumber":3,"blockTimestamp":1750761775,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:37:39 [10:37:39.724] VERBOSE: sequencer Attesting committee is empty 10:37:39 [10:37:39.844] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:40 [10:37:40.048] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:40 [10:37:40.055] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xa116e7e1fc4fd9d32fed81fb479f524691fe699e1e84f508f7b50730fe2a8bb7 10:37:40 [10:37:40.056] VERBOSE: sequencer-client Sent L1 transaction 0xa116e7e1fc4fd9d32fed81fb479f524691fe699e1e84f508f7b50730fe2a8bb7 {"gasLimit":470265,"maxFeePerGas":"1.279583427","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:37:40 [10:37:40.071] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:37:40 [10:37:40.071] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xa116e7e1fc4fd9d32fed81fb479f524691fe699e1e84f508f7b50730fe2a8bb7","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":3,"blockTimestamp":1750761775,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:37:40 [10:37:40.071] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:37:40 [10:37:40.076] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750761811] on http://127.0.0.1:8545 10:37:40 [10:37:40.078] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:37:40 [10:37:40.080] INFO: blob-sink:server Received blob sidecar for block 0x7630b46a0e413acf65be8b0868d85d1a0f873708de3c76eccd0c4e0152af1734 10:37:40 [10:37:40.082] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750761811 10:37:40 [10:37:40.083] WARN: foundation:test-date-provider Time set to 2025-06-24T10:43:31.000Z {"offset":350917,"timeMs":1750761811000} 10:37:40 [10:37:40.083] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 10:37:40 [10:37:40.085] INFO: blob-sink:server Blob sidecar stored successfully for block 0x7630b46a0e413acf65be8b0868d85d1a0f873708de3c76eccd0c4e0152af1734 10:37:40 [10:37:40.251] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:40 [10:37:40.261] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 10:37:40 [10:37:40.262] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 10:37:40 [10:37:40.264] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 10:37:40 [10:37:40.550] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:40 [10:37:40.564] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x07376cefa33d478b662206f19ad5e1a33a25a51fec227cefbcd966728737d11e","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":1188152374,"blockNumber":3,"slotNumber":4,"timestamp":1750761775,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x0d005d8f521650153e2c84442843c7f28a11632e37b48c0dec3be8e57ca6d09a","archiveNextLeafIndex":4} 10:37:40 [10:37:40.566] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 10:37:40 [10:37:40.671] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":7.454384000000573,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750761775,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:37:40 [10:37:40.681] VERBOSE: p2p Synched to latest block 3 10:37:40 [10:37:40.755] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:40 [10:37:40.960] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:41 [10:37:41.095] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0d005d8f521650153e2c84442843c7f28a11632e37b48c0dec3be8e57ca6d09a","blockNumber":4,"slot":5} 10:37:41 [10:37:41.165] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:41 [10:37:41.367] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:41 [10:37:41.436] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x0d005d8f521650153e2c84442843c7f28a11632e37b48c0dec3be8e57ca6d09a","header":{"contentCommitment":{"blobsHash":"0x001e18aa1cd2671ddad47b16a6365e3291dc2912d88e1004f4fe8fc0bdcae857","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750761775,"version":1188152374},"lastArchive":"0x12b7b5bd450c8711731d2b7240770b56665bc1fa5b8e9d2cbd76de19167cab7d","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x27ad94670835c4ade8d9640ec4dde3b16c6653d5f1a98ee52008df278bb96457","nullifierTree":"0x08d4e3780ea1a786f58eaa4a8d5fd12a5dbf8b74570c361d7e28f50c78a21898","publicDataTree":"0x2bbffdb5cc8d7020a2e13ddc6e6e4c7c7c788bf80ebfdf08965b0d3da8e3654a"},"totalFees":3242857600,"totalManaUsed":9760}} 10:37:41 [10:37:41.500] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:41 [10:37:41.543] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:41 [10:37:41.543] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:41 [10:37:41.570] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:41 [10:37:41.618] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:41 [10:37:41.627] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0d005d8f521650153e2c84442843c7f28a11632e37b48c0dec3be8e57ca6d09a","blockNumber":4,"slot":5} 10:37:41 [10:37:41.630] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x256343772546dc602060552b1628eafe95ebb587709ed24818c36bb8922d4d2d 10:37:41 [10:37:41.640] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:37:41 [10:37:41.641] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:37:41 [10:37:41.646] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:37:41 [10:37:41.647] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:37:41 [10:37:41.653] VERBOSE: pxe:service Added note {"index":0,"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x11cdf14090bc4dd68da9b99d20492691a1d55326ba3fa963d5be56e88a79770f","nullifier":"0x257ad9b96d02c12403753b3f0fb547d1feb87120d72983a5ba984e10145d0cac"} 10:37:41 [10:37:41.655] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:41 [10:37:41.669] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 as fee payer 10:37:41 [10:37:41.776] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:42 [10:37:42.238] VERBOSE: simulator:private_execution Executing private function Test:call_destroy_note {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:42 [10:37:42.296] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:42 [10:37:42.296] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:42 [10:37:42.300] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:42 [10:37:42.461] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0d005d8f521650153e2c84442843c7f28a11632e37b48c0dec3be8e57ca6d09a","blockNumber":4,"slot":5} 10:37:42 [10:37:42.484] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:42 [10:37:42.489] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1fa4c38c8571f975303331b7fa4806b4b285be822e63f3e6635d74133f563dbc 10:37:42 [10:37:42.501] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:37:42 [10:37:42.502] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51 for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 10:37:42 [10:37:42.510] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:37:42 [10:37:42.511] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:37:42 [10:37:42.511] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:42 [10:37:42.517] VERBOSE: pxe:service Added note {"index":128,"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x11decdafb2c2d6661b26961fff1d5f64900d86d4685cc3937a7f6b1e15104e94","nullifier":"0x24852bfebc70ea81833d0e0ecd4075f69e95f7c7c5ab7a880ac9fc6f1863ec00"} 10:37:42 [10:37:42.523] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:42 [10:37:42.777] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:43 [10:37:43.363] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:43 [10:37:43.576] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:43 [10:37:43.583] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0d005d8f521650153e2c84442843c7f28a11632e37b48c0dec3be8e57ca6d09a","blockNumber":4,"slot":5} 10:37:43 [10:37:43.841] INFO: pxe:service Proving completed in 2412.035154000001ms {"timings":{"total":2412.035154000001,"sync":10.711852000000363,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":390.9742880000049,"oracles":{"debugLog":{"times":[0.5200110000005225,0.3154370000011113,0.35150699999940116,0.4126890000006824,0.4651789999988978,0.4003080000002228,0.45272900000054506,0.45870900000227266]},"fetchTaggedLogs":{"times":[85.00545299999794]},"loadCapsule":{"times":[0.18886399999973946,0.23262500000055297,0.5131910000018252,0.21710499999971944,0.21055400000113877,0.2683660000002419]},"getSharedSecret":{"times":[3.469651999999769]},"aes128Decrypt":{"times":[0.19182399999772315,0.6722539999973378]},"getPublicKeysAndPartialAddress":{"times":[1.5879929999973683]},"getKeyValidationRequest":{"times":[1.6481240000030084]},"deliverNote":{"times":[6.492143999999826]},"deleteCapsule":{"times":[0.3316969999978028]},"storeCapsule":{"times":[0.2673060000015539]},"getNotes":{"times":[1.6556740000014543]},"getAuthWitness":{"times":[0.25515599999926053]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.04242999999769381]},"callPrivateFunction":{"times":[889.893806]}}},{"functionName":"private_kernel_init","time":97.44111100000009},{"functionName":"Test:call_destroy_note","time":724.1997699999956,"oracles":{"debugLog":{"times":[0.7603459999991173,0.4772689999990689,0.4041980000001786,0.572282000000996,0.44311999999990803,0.40999899999951595,0.34601699999984703]},"fetchTaggedLogs":{"times":[191.18830499999967]},"loadCapsule":{"times":[0.2244250000003376,0.2579150000019581,0.4114689999987604,1.4628599999996368,1.5239409999994677,0.27891599999929895]},"getSharedSecret":{"times":[3.6484049999999115]},"aes128Decrypt":{"times":[0.2361349999991944,1.1142130000007455]},"getPublicKeysAndPartialAddress":{"times":[2.6514250000000175,2.3171279999987746]},"getKeyValidationRequest":{"times":[2.2796569999991334,2.7922079999989364]},"deliverNote":{"times":[6.261019999998098]},"deleteCapsule":{"times":[0.5229999999974098]},"storeCapsule":{"times":[1.4280089999992924]},"getNotes":{"times":[3.2306160000007367]},"notifyNullifiedNote":{"times":[0.4575590000022203]}}},{"functionName":"private_kernel_inner","time":180.00669299999936},{"functionName":"private_kernel_reset","time":189.76485500000126},{"functionName":"private_kernel_tail","time":80.0455899999979}],"unaccounted":738.8909950000016}} 10:37:43 [10:37:43.853] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:43 [10:37:43.872] VERBOSE: p2p:tx_pool Adding tx 0x1cef7af58728c4f900ca8ccd8029d084579a033318f59a64192b79a7d0a88d72 to pool {"eventName":"tx-added-to-pool","txHash":"0x1cef7af58728c4f900ca8ccd8029d084579a033318f59a64192b79a7d0a88d72","noteHashCount":0,"nullifierCount":2,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 10:37:43 [10:37:43.877] INFO: node Received tx 0x1cef7af58728c4f900ca8ccd8029d084579a033318f59a64192b79a7d0a88d72 {"txHash":"0x1cef7af58728c4f900ca8ccd8029d084579a033318f59a64192b79a7d0a88d72"} 10:37:43 [10:37:43.877] INFO: pxe:service Sent transaction 0x1cef7af58728c4f900ca8ccd8029d084579a033318f59a64192b79a7d0a88d72 10:37:44 [10:37:44.058] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:44 [10:37:44.104] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 10:37:44 [10:37:44.117] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-24T10:43:31.000Z","now":"2025-06-24T10:43:35.034Z"} 10:37:44 [10:37:44.146] VERBOSE: simulator:public-processor Processed tx 0x1cef7af58728c4f900ca8ccd8029d084579a033318f59a64192b79a7d0a88d72 with no public calls in 15.403228999999556ms {"txHash":"0x1cef7af58728c4f900ca8ccd8029d084579a033318f59a64192b79a7d0a88d72","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":15.403228999999556} 10:37:44 [10:37:44.150] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.031860029999999824s {"duration":0.031860029999999824,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1536,"l2Gas":11200},"totalSizeInBytes":288} 10:37:44 [10:37:44.261] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x24297c7a991d365263b8104ff90bbd02692a163d2f3bc2d12530d82e69e5a01a","globalVariables":{"chainId":31337,"version":1188152374,"blockNumber":4,"slotNumber":5,"timestamp":1750761811,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x1cef7af58728c4f900ca8ccd8029d084579a033318f59a64192b79a7d0a88d72"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":156.5302069999998,"publicProcessDuration":32.41862200000105,"rollupCircuitsDuration":144.44538599999942,"txCount":1,"blockNumber":4,"blockTimestamp":1750761811,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:37:44 [10:37:44.262] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:44 [10:37:44.263] VERBOSE: sequencer Attesting committee is empty 10:37:44 [10:37:44.537] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xe9ab396e3f2d2c35afcee8b97c4eb523287e38c31233050af3b089678ce53896 10:37:44 [10:37:44.537] VERBOSE: sequencer-client Sent L1 transaction 0xe9ab396e3f2d2c35afcee8b97c4eb523287e38c31233050af3b089678ce53896 {"gasLimit":470234,"maxFeePerGas":"1.261127755","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:37:44 [10:37:44.546] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:44 [10:37:44.554] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:37:44 [10:37:44.554] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xe9ab396e3f2d2c35afcee8b97c4eb523287e38c31233050af3b089678ce53896","calldataSize":1444,"calldataGas":12256,"txCount":1,"blockNumber":4,"blockTimestamp":1750761811,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:37:44 [10:37:44.555] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:37:44 [10:37:44.564] INFO: blob-sink:server Received blob sidecar for block 0x9ac84e4574bedae7b8ea55b6bad590d91e1f64c9a4212ce972cc95094a0de1b9 10:37:44 [10:37:44.570] INFO: blob-sink:server Blob sidecar stored successfully for block 0x9ac84e4574bedae7b8ea55b6bad590d91e1f64c9a4212ce972cc95094a0de1b9 10:37:44 [10:37:44.572] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750761847] on http://127.0.0.1:8545 10:37:44 [10:37:44.573] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:37:44 [10:37:44.575] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750761847 10:37:44 [10:37:44.575] WARN: foundation:test-date-provider Time set to 2025-06-24T10:44:07.000Z {"offset":382425,"timeMs":1750761847000} 10:37:44 [10:37:44.576] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 10:37:44 [10:37:44.751] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:44 [10:37:44.755] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 10:37:44 [10:37:44.759] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x24297c7a991d365263b8104ff90bbd02692a163d2f3bc2d12530d82e69e5a01a","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":1188152374,"blockNumber":4,"slotNumber":5,"timestamp":1750761811,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x1a094cd0362170dde32743957a9a6dbef6a7555371e85652b05f43e24ad19a63","archiveNextLeafIndex":5} 10:37:44 [10:37:44.763] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 10:37:44 [10:37:44.766] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 10:37:44 [10:37:44.873] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":7.466534999999567,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1750761811,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:37:44 [10:37:44.880] VERBOSE: p2p Synched to latest block 4 10:37:44 [10:37:44.895] INFO: e2e:e2e_keys Running test: Keys ovsk_app gets ovsk_app 10:37:44 [10:37:44.910] INFO: pxe:service Simulating transaction execution request to 0x27e740b2 at 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 {"origin":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x0000000000000000000000000000000000000000000000000000000046d1c436","authWitnesses":["0x091a51718a08e35f2b3e23b887968b4cd2a55e99a0216881e01c1e1bc3558a61"]} 10:37:44 [10:37:44.920] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x1a094cd0362170dde32743957a9a6dbef6a7555371e85652b05f43e24ad19a63","header":{"contentCommitment":{"blobsHash":"0x00081f7603330e316ae78cec96a08517f285e3b6b88ee61cdf1028abcd28428d","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1750761811,"version":1188152374},"lastArchive":"0x0d005d8f521650153e2c84442843c7f28a11632e37b48c0dec3be8e57ca6d09a","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x27ad94670835c4ade8d9640ec4dde3b16c6653d5f1a98ee52008df278bb96457","nullifierTree":"0x1f44e1c1bf5d181f71db93331a52ac73169881f84898d51b6b9ce3fe7ac8013b","publicDataTree":"0x0043556d5219c9112ba8df672c8449b4066e8df7a65e85f47cc14c72098059cd"},"totalFees":567840000,"totalManaUsed":11200}} 10:37:44 [10:37:44.981] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:45 [10:37:45.000] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:45 [10:37:45.042] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:45 [10:37:45.043] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:45 [10:37:45.116] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1a094cd0362170dde32743957a9a6dbef6a7555371e85652b05f43e24ad19a63","blockNumber":5,"slot":6} 10:37:45 [10:37:45.130] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227"} 10:37:45 [10:37:45.136] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x256343772546dc602060552b1628eafe95ebb587709ed24818c36bb8922d4d2d 10:37:45 [10:37:45.152] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:37:45 [10:37:45.153] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:37:45 [10:37:45.166] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:37:45 [10:37:45.167] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:37:45 [10:37:45.174] VERBOSE: pxe:service Added note {"index":0,"contract":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x11cdf14090bc4dd68da9b99d20492691a1d55326ba3fa963d5be56e88a79770f","nullifier":"0x257ad9b96d02c12403753b3f0fb547d1feb87120d72983a5ba984e10145d0cac"} 10:37:45 [10:37:45.176] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:45 [10:37:45.194] VERBOSE: simulator:client_execution_context:debug_log Setting 0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227 as fee payer 10:37:45 [10:37:45.327] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:45 [10:37:45.857] VERBOSE: simulator:private_execution Executing private function Test:get_ovsk_app {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:45 [10:37:45.872] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:45 [10:37:45.872] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:45 [10:37:45.879] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:46 [10:37:46.049] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 10:37:46 [10:37:46.050] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1a094cd0362170dde32743957a9a6dbef6a7555371e85652b05f43e24ad19a63","blockNumber":5,"slot":6} 10:37:46 [10:37:46.064] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51"} 10:37:46 [10:37:46.070] VERBOSE: pxe:service Removed note for contract 0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51 at slot 0x000000000000000000000000000000000000000000000000000000000000000c {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","nullifier":"0x24852bfebc70ea81833d0e0ecd4075f69e95f7c7c5ab7a880ac9fc6f1863ec00"} 10:37:46 [10:37:46.073] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1fa4c38c8571f975303331b7fa4806b4b285be822e63f3e6635d74133f563dbc 10:37:46 [10:37:46.087] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:37:46 [10:37:46.088] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51 for storage slot 0x000000000000000000000000000000000000000000000000000000000000000c 10:37:46 [10:37:46.090] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:46 [10:37:46.099] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:37:46 [10:37:46.099] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:37:46 [10:37:46.106] VERBOSE: pxe:service Added note {"index":128,"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x11decdafb2c2d6661b26961fff1d5f64900d86d4685cc3937a7f6b1e15104e94","nullifier":"0x24852bfebc70ea81833d0e0ecd4075f69e95f7c7c5ab7a880ac9fc6f1863ec00"} 10:37:46 [10:37:46.107] VERBOSE: pxe:service Removed just-added note {"contract":"0x2c8ec6632a38f3a9303b4941b7af243bb8582cc5b5d1e3a585d2f98cda1f7d51","slot":"0x000000000000000000000000000000000000000000000000000000000000000c","noteHash":"0x11decdafb2c2d6661b26961fff1d5f64900d86d4685cc3937a7f6b1e15104e94","nullifier":"0x24852bfebc70ea81833d0e0ecd4075f69e95f7c7c5ab7a880ac9fc6f1863ec00"} 10:37:46 [10:37:46.109] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:46 [10:37:46.308] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:46 [10:37:46.797] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:46 [10:37:46.971] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1a094cd0362170dde32743957a9a6dbef6a7555371e85652b05f43e24ad19a63","blockNumber":5,"slot":6} 10:37:47 [10:37:47.278] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:47 [10:37:47.321] INFO: pxe:service Simulation completed for 0x17386c8091273fb06980e956fdcacea28d0613408c3c8c7edd9afb71d8fbc8ca in 2410.454311999998ms {"txHash":"0x17386c8091273fb06980e956fdcacea28d0613408c3c8c7edd9afb71d8fbc8ca","origin":"0x0102d251123f307e51c058670ecf5676ab4a362e434406cebfa6bd4d7bfac227","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x0000000000000000000000000000000000000000000000000000000046d1c436","authWitnesses":["0x091a51718a08e35f2b3e23b887968b4cd2a55e99a0216881e01c1e1bc3558a61"]} 10:37:47 [10:37:47.332] INFO: node Stopping 10:37:47 [10:37:47.334] INFO: slasher Slasher client stopped. 10:37:47 [10:37:47.334] INFO: sequencer Stopped sequencer 10:37:47 [10:37:47.334] INFO: p2p P2P client stopped. 10:37:47 [10:37:47.338] INFO: world_state Stopped world state synchronizer 10:37:47 [10:37:47.338] INFO: archiver Stopped. 10:37:47 [10:37:47.338] INFO: node Stopped 10:37:47 [10:37:47.338] VERBOSE: e2e:e2e_keys Cleaning up ACVM state 10:37:47 [10:37:47.339] INFO: e2e:e2e_keys Cleaning up ACVM temp directory /tmp/398cb704 10:37:47 [10:37:47.340] VERBOSE: e2e:e2e_keys Cleaning up BB state 10:37:47 [10:37:47.349] INFO: blob-sink:server Stopping blob sink 10:37:47 [10:37:47.350] INFO: blob-sink:server Blob sink stopped 10:37:47 [10:37:47.350] VERBOSE: e2e:e2e_keys Cleaning up data directory at /tmp/b5eb015a0a5e0986 10:37:47 PASS src/e2e_keys.test.ts 10:37:47 Keys 10:37:47 using nsk_app to detect nullification 10:37:47 ✓ nsk_app and contract address are enough to detect note nullification (8389 ms) 10:37:47 ovsk_app 10:37:47 ✓ gets ovsk_app (2428 ms) 10:37:47 10:37:47 Test Suites: 1 passed, 1 total 10:37:47 Tests: 2 passed, 2 total 10:37:47 Snapshots: 0 total 10:37:47 Time: 34.115 s 10:37:47 Ran all test suites matching /src\/e2e_keys.test.ts/i. 10:37:47 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?