Parent Log: http://ci.aztec-labs.com/f823d0a4ebbfe5c2 Command: 53e9b8d3e8ba3411:ISOLATE=1:NAME=e2e_partial_notes yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_partial_notes.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/2707ec99de02004f3e30e7af0810e0d741ab9794 Env: REF_NAME=gh-readonly-queue/master/pr-15049-f4ac84d7ac4041d505399edd8819840bb14d6c9d CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Sat Jun 14 10:47:33 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15049_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_6b4a73ce992ff502_master 10:47:33 +++ id -u 10:47:33 +++ id -g 10:47:33 ++ docker run -d --name e2e_partial_notes --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_partial_notes.test.ts'\''' 10:47:34 + cid=60732a8e39df5984a8ab21f3fe8ef22bc02e157b7621209895b1de2b12d1b0af 10:47:34 + set +x 10:47:34 {"level":25,"time":1749898054757,"pid":12,"hostname":"60732a8e39df","module":"logger","msg":"Logger initialized with level verbose"} 10:47:41 [10:47:41.211] INFO: e2e:e2e_partial_notes Logging metrics to log/unknown.jsonl 10:47:41 [10:47:41.546] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-1Eqafe/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 10:47:41 [10:47:41.584] INFO: e2e:e2e_partial_notes Calling anvil_setBlockTimestampInterval with params: [12] 10:47:41 [10:47:41.607] WARN: e2e:e2e_partial_notes Set block interval to 12 10:47:41 [10:47:41.608] VERBOSE: e2e:e2e_partial_notes Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 10:47:41 [10:47:41.662] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:47:41 [10:47:41.668] VERBOSE: e2e:e2e_partial_notes Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 10:47:41 [10:47:41.694] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:47:41 [10:47:41.700] VERBOSE: e2e:e2e_partial_notes Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 10:47:41 [10:47:41.719] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 10:47:41 [10:47:41.724] VERBOSE: e2e:e2e_partial_notes Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 10:47:41 [10:47:41.740] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 10:47:41 [10:47:41.746] VERBOSE: e2e:e2e_partial_notes Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 10:47:41 [10:47:41.769] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 10:47:41 [10:47:41.774] VERBOSE: e2e:e2e_partial_notes Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 10:47:41 [10:47:41.795] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 10:47:41 [10:47:41.801] VERBOSE: e2e:e2e_partial_notes Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 10:47:41 [10:47:41.802] VERBOSE: e2e:e2e_partial_notes Waiting for deployments to complete 10:47:41 [10:47:41.803] INFO: e2e:e2e_partial_notes Waiting for 6 transactions to be mined... 10:47:41 [10:47:41.825] INFO: e2e:e2e_partial_notes All transactions mined successfully 10:47:41 [10:47:41.851] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 10:47:41 [10:47:41.852] VERBOSE: e2e:e2e_partial_notes Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 10:47:41 [10:47:41.867] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 10:47:41 [10:47:41.882] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 10:47:41 [10:47:41.887] VERBOSE: e2e:e2e_partial_notes Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 10:47:41 [10:47:41.905] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 10:47:41 [10:47:41.906] VERBOSE: e2e:e2e_partial_notes Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 10:47:41 [10:47:41.926] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 10:47:41 [10:47:41.932] VERBOSE: e2e:e2e_partial_notes Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 10:47:41 [10:47:41.956] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 10:47:41 [10:47:41.957] VERBOSE: e2e:e2e_partial_notes Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 10:47:41 [10:47:41.957] VERBOSE: e2e:e2e_partial_notes Waiting for deployments to complete 10:47:41 [10:47:41.958] INFO: e2e:e2e_partial_notes Waiting for 8 transactions to be mined... 10:47:41 [10:47:41.992] INFO: e2e:e2e_partial_notes All transactions mined successfully 10:47:42 [10:47:42.012] VERBOSE: e2e:e2e_partial_notes Deployed shared contracts 10:47:42 [10:47:42.050] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 10:47:42 [10:47:42.050] VERBOSE: e2e:e2e_partial_notes Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 10:47:42 [10:47:42.054] VERBOSE: e2e:e2e_partial_notes Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 10:47:42 [10:47:42.066] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 10:47:42 [10:47:42.099] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 10:47:42 [10:47:42.108] VERBOSE: e2e:e2e_partial_notes Awaiting for linked libraries to be deployed 10:47:42 [10:47:42.164] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0xa1326a5f70cdfd5c3b6e0cd719c38e4a41a2db2f63758e7655a7411d1a68e0d6 {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 10:47:42 [10:47:42.174] VERBOSE: e2e:e2e_partial_notes Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016 {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 10:47:42 [10:47:42.174] INFO: e2e:e2e_partial_notes Waiting for 9 transactions to be mined... 10:47:42 [10:47:42.200] INFO: e2e:e2e_partial_notes All transactions mined successfully 10:47:42 [10:47:42.202] VERBOSE: e2e:e2e_partial_notes All core contracts have been deployed 10:47:42 [10:47:42.229] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x87752e6d1988a4ae568647f8d87c596d1b69935ee30a33c51605875f496d2e9b {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 10:47:42 [10:47:42.230] VERBOSE: e2e:e2e_partial_notes Funding fee juice portal with 20000000000000000000000 fee juice in 0x87752e6d1988a4ae568647f8d87c596d1b69935ee30a33c51605875f496d2e9b (accelerated test deployments) 10:47:42 [10:47:42.254] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 10:47:42 [10:47:42.260] VERBOSE: e2e:e2e_partial_notes Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 10:47:42 [10:47:42.295] VERBOSE: e2e:e2e_partial_notes Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 10:47:42 [10:47:42.296] VERBOSE: e2e:e2e_partial_notes Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 10:47:42 [10:47:42.297] INFO: e2e:e2e_partial_notes Waiting for 10 transactions to be mined... 10:47:42 [10:47:42.342] INFO: e2e:e2e_partial_notes All transactions mined successfully 10:47:42 [10:47:42.352] VERBOSE: e2e:e2e_partial_notes Rollup deployed 10:47:42 [10:47:42.352] VERBOSE: e2e:e2e_partial_notes Waiting for rollup and slash factory to be deployed 10:47:42 [10:47:42.353] INFO: e2e:e2e_partial_notes Waiting for 10 transactions to be mined... 10:47:42 [10:47:42.373] INFO: e2e:e2e_partial_notes All transactions mined successfully 10:47:42 [10:47:42.374] VERBOSE: e2e:e2e_partial_notes All transactions for L1 deployment have been mined 10:47:42 [10:47:42.395] INFO: e2e:e2e_partial_notes 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:47:42 [10:47:42.401] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 10:47:42 [10:47:42.406] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:42 [10:47:42.408] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 10:47:42 [10:47:42.410] INFO: telemetry:client Using NoopTelemetryClient 10:47:42 [10:47:42.418] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 10:47:42 [10:47:42.418] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 10:47:42 [10:47:42.419] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/d848bc181d2c57e4/blob-sink with map size 134217728 KB (LMDB v2) 10:47:42 [10:47:42.422] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:42 [10:47:42.425] INFO: aztecjs:utils:watcher New L1 time: 1749898301 10:47:42 [10:47:42.426] WARN: foundation:test-date-provider Time set to 2025-06-14T10:51:41.000Z {"offset":238574,"timeMs":1749898301000} 10:47:42 [10:47:42.428] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 10:47:42 [10:47:42.437] INFO: blob-sink:server Server is running on http://localhost:35035 10:47:42 [10:47:42.437] VERBOSE: e2e:e2e_partial_notes Creating and synching an aztec node... 10:47:42 [10:47:42.438] VERBOSE: e2e:e2e_partial_notes Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/9f446227/acvm 10:47:42 [10:47:42.440] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:35035"} 10:47:42 [10:47:42.481] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 10:47:42 [10:47:42.483] INFO: archiver:lmdb Creating archiver data store at directory /tmp/d848bc181d2c57e4/archiver with map size 134217728 KB (LMDB v2) 10:47:42 [10:47:42.487] INFO: archiver:lmdb Starting data store with maxReaders 16 10:47:42 [10:47:42.707] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:42 [10:47:42.838] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:35035"} 10:47:42 [10:47:42.856] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:35035"} 10:47:42 [10:47:42.856] WARN: node:blob-sink:client No L1 consensus host urls configured 10:47:42 [10:47:42.857] WARN: node:blob-sink:client No archive client configured 10:47:42 [10:47:42.861] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x51f0acd333e5b91a36a39928e89520ee4cf9df3352ecbca68eafb45350056d73 {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0xad746cce90fe8cb208a41ccfc1ee85625cf15f07068f2aa83f695d6f084526b5","l1BlockNumber":16}} 10:47:42 [10:47:42.881] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0x51f0acd333e5b91a36a39928e89520ee4cf9df3352ecbca68eafb45350056d73","l1BlockNumber":20}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 10:47:42 [10:47:42.894] INFO: world-state:database Creating world state data store at directory /tmp/d848bc181d2c57e4/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 10:47:42 [10:47:42.920] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:42 [10:47:42.927] INFO: world_state Created world state synchroniser with block history of 64 10:47:42 [10:47:42.927] WARN: node Aztec node is accepting fake proofs 10:47:42 [10:47:42.938] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/d848bc181d2c57e4/p2p with map size 134217728 KB (LMDB v2) 10:47:42 [10:47:42.941] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 10:47:42 [10:47:42.943] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/d848bc181d2c57e4/p2p-archive with map size 134217728 KB (LMDB v2) 10:47:42 [10:47:42.944] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 10:47:42 [10:47:42.946] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/d848bc181d2c57e4/p2p-peers with map size 134217728 KB (LMDB v2) 10:47:42 [10:47:42.947] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 10:47:42 [10:47:42.950] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 10:47:42 [10:47:42.951] VERBOSE: p2p P2P is disabled. Using dummy P2P service 10:47:42 [10:47:42.955] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 10:47:42 [10:47:42.956] INFO: world_state Started world state synchronizer from block 1 10:47:42 [10:47:42.958] VERBOSE: world-state:block_stream Starting sync from block number 0 10:47:42 [10:47:42.964] INFO: p2p Starting p2p client from block 0 with empty mempools 10:47:42 [10:47:42.965] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 10:47:42 [10:47:42.966] INFO: slasher Slasher client initialized 10:47:42 [10:47:42.966] INFO: slasher Starting Slasher client... 10:47:43 [10:47:43.004] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:47:43 [10:47:43.004] VERBOSE: node All Aztec Node subsystems synced 10:47:43 [10:47:43.015] INFO: sequencer-client Deploying forwarder contract 10:47:43 [10:47:43.024] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 10:47:43 [10:47:43.042] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 10:47:43 [10:47:43.042] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 10:47:43 [10:47:43.050] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 10:47:43 [10:47:43.081] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:47:43 [10:47:43.082] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 10:47:43 [10:47:43.082] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/9f446227/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 10:47:43 [10:47:43.340] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 10:47:43 [10:47:43.343] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:47:43 [10:47:43.345] INFO: node Aztec Node version: 0.87.8 10:47:43 [10:47:43.345] 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:47:43 [10:47:43.346] VERBOSE: e2e:e2e_partial_notes Creating a pxe... 10:47:43 [10:47:43.350] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:43 [10:47:43.369] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/1d45205e33da5c81/pxe_data with map size 134217728 KB (LMDB v2) 10:47:43 [10:47:43.385] INFO: pxe:data:lmdb Starting data store with maxReaders 16 10:47:43 [10:47:43.390] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2e96c2742688b1f72b5e698a0fc9b1b51305f1a74618a18a0580647627c5095f","blockNumber":1,"slot":2} 10:47:43 [10:47:43.615] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:43 [10:47:43.759] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 10:47:43 [10:47:43.760] INFO: pxe:service Started PXE connected to chain 31337 version 1224373321 10:47:43 [10:47:43.823] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:43 [10:47:43.830] INFO: pxe:service Registered account 0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9 10:47:43 [10:47:43.905] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 10:47:43 [10:47:43.907] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9 10:47:43 [10:47:43.952] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2e96c2742688b1f72b5e698a0fc9b1b51305f1a74618a18a0580647627c5095f","blockNumber":1,"slot":2} 10:47:44 [10:47:44.065] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:44 [10:47:44.072] INFO: pxe:service Added contract SchnorrAccount at 0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 10:47:44 [10:47:44.116] VERBOSE: pxe:block_stream Starting sync from block number 0 10:47:44 [10:47:44.147] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 10:47:44 [10:47:44.258] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:47:44 [10:47:44.960] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 10:47:45 [10:47:45.083] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 10:47:45 [10:47:45.169] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:45 [10:47:45.202] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2e96c2742688b1f72b5e698a0fc9b1b51305f1a74618a18a0580647627c5095f","blockNumber":1,"slot":2} 10:47:45 [10:47:45.302] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:45 [10:47:45.342] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:45 [10:47:45.343] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:45 [10:47:45.382] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:45 [10:47:45.401] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:45 [10:47:45.409] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:45 [10:47:45.575] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:45 [10:47:45.623] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:45 [10:47:45.624] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:45 [10:47:45.629] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:45 [10:47:45.664] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:45 [10:47:45.667] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:45 [10:47:45.682] VERBOSE: simulator:client_execution_context:debug_log Setting 0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9 as fee payer 10:47:45 [10:47:45.758] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2e96c2742688b1f72b5e698a0fc9b1b51305f1a74618a18a0580647627c5095f","blockNumber":1,"slot":2} 10:47:45 [10:47:45.873] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:46 [10:47:46.117] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:46 [10:47:46.385] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:46 [10:47:46.506] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x2e96c2742688b1f72b5e698a0fc9b1b51305f1a74618a18a0580647627c5095f","blockNumber":1,"slot":2} 10:47:46 [10:47:46.928] INFO: pxe:service Proving completed in 2814.8631750000004ms {"timings":{"total":2814.8631750000004,"sync":3.462188999999853,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":161.51289899999756,"oracles":{"callPrivateFunction":{"times":[956.0919200000008,344.7316329999994,196.88214700000026]}}},{"functionName":"private_kernel_init","time":106.09508099999948},{"functionName":"ContractClassRegisterer:register","time":933.2253610000025,"oracles":{"loadCapsule":{"times":[14.797921000001224]},"notifyCreatedNullifier":{"times":[25.271691000001738]},"debugLog":{"times":[1.757195000000138]},"notifyCreatedContractClassLog":{"times":[10.68958500000008]}}},{"functionName":"private_kernel_inner","time":139.28958499999862},{"functionName":"SchnorrAccount:constructor","time":315.31401600000027,"oracles":{"getContractInstance":{"times":[1.2028620000000956]},"debugLog":{"times":[0.5174729999998817,0.3888600000009319]},"fetchTaggedLogs":{"times":[64.80421899999965]},"loadCapsule":{"times":[0.437381000001551,0.2533869999988383,0.23613599999953294]},"notifyCreatedNullifier":{"times":[0.19570500000008906,0.17455400000108057]},"notifyCreatedNote":{"times":[0.4386109999995824]},"getRandomField":{"times":[0.3322389999993902,0.24999599999864586,0.09328300000015588,0.06244200000037381,0.056501000000935164,0.05868199999895296,0.05921200000011595,0.08261200000015378,0.05741100000159349,0.101502999999866,0.0479910000012751]},"getIndexedTaggingSecretAsSender":{"times":[29.850438000001304]},"incrementAppTaggingSecretIndexAsSender":{"times":[32.454875000001266]}}},{"functionName":"private_kernel_inner","time":157.6103860000003},{"functionName":"SchnorrAccount:entrypoint","time":170.41668600000048,"oracles":{"debugLog":{"times":[0.7021870000007766,0.3163480000002892,0.9612649999999121]},"fetchTaggedLogs":{"times":[42.4244319999998]},"loadCapsule":{"times":[0.19666500000130327,0.14488300000084564,0.1632339999996475]},"getNotes":{"times":[1.6711430000013934]},"getAuthWitness":{"times":[0.2853270000014163]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.1009389999999257]}}},{"functionName":"private_kernel_inner","time":156.14318900000035},{"functionName":"private_kernel_reset","time":181.38946899999974},{"functionName":"private_kernel_tail","time":74.96897899999931}],"unaccounted":415.43533500000194}} 10:47:46 [10:47:46.945] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:47 [10:47:47.081] VERBOSE: p2p:tx_pool Adding tx 0x214d52e1a013dd85f20fc07e3d41646944d9bf6b18261472841d636bbbe7d6aa to pool {"eventName":"tx-added-to-pool","txHash":"0x214d52e1a013dd85f20fc07e3d41646944d9bf6b18261472841d636bbbe7d6aa","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 10:47:47 [10:47:47.101] INFO: node Received tx 0x214d52e1a013dd85f20fc07e3d41646944d9bf6b18261472841d636bbbe7d6aa {"txHash":"0x214d52e1a013dd85f20fc07e3d41646944d9bf6b18261472841d636bbbe7d6aa"} 10:47:47 [10:47:47.102] INFO: pxe:service Sent transaction 0x214d52e1a013dd85f20fc07e3d41646944d9bf6b18261472841d636bbbe7d6aa 10:47:47 [10:47:47.112] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 10:47:47 [10:47:47.126] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-14T10:52:05.000Z","now":"2025-06-14T10:51:45.700Z"} 10:47:47 [10:47:47.205] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:47 [10:47:47.395] VERBOSE: simulator:public-processor Processed tx 0x214d52e1a013dd85f20fc07e3d41646944d9bf6b18261472841d636bbbe7d6aa with no public calls in 178.66777899999943ms {"txHash":"0x214d52e1a013dd85f20fc07e3d41646944d9bf6b18261472841d636bbbe7d6aa","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":178.66777899999943} 10:47:47 [10:47:47.397] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.26944073600000046s {"duration":0.26944073600000046,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 10:47:47 [10:47:47.432] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:47 [10:47:47.563] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2a847d8d0e4cd035fab82ad621e9b63f2216bf14ec36aea5765d88bebe3cc472","globalVariables":{"chainId":31337,"version":1224373321,"blockNumber":1,"slotNumber":2,"timestamp":1749898325,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x214d52e1a013dd85f20fc07e3d41646944d9bf6b18261472841d636bbbe7d6aa"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":451.350058,"publicProcessDuration":270.5059830000009,"rollupCircuitsDuration":437.9791839999998,"txCount":1,"blockNumber":1,"blockTimestamp":1749898325,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:47:47 [10:47:47.567] VERBOSE: sequencer Attesting committee is empty 10:47:47 [10:47:47.649] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:47 [10:47:47.937] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:47 [10:47:47.945] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x501d1b8232fbf30e2dcfa3004245aed325167e6ee4a16a42aa09cb66cc94e159 10:47:47 [10:47:47.946] VERBOSE: sequencer-client Sent L1 transaction 0x501d1b8232fbf30e2dcfa3004245aed325167e6ee4a16a42aa09cb66cc94e159 {"gasLimit":514552,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 10:47:47 [10:47:47.962] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:47:47 [10:47:47.963] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x501d1b8232fbf30e2dcfa3004245aed325167e6ee4a16a42aa09cb66cc94e159","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":1,"blockTimestamp":1749898325,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:47:47 [10:47:47.964] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:47:48 [10:47:48.122] INFO: blob-sink:server Received blob sidecar for block 0x89abcd62a0c5d8bb318f6fa863ff9af14166f0cda61d4f741b080806a486704e 10:47:48 [10:47:48.126] INFO: blob-sink:server Blob sidecar stored successfully for block 0x89abcd62a0c5d8bb318f6fa863ff9af14166f0cda61d4f741b080806a486704e 10:47:48 [10:47:48.143] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:48 [10:47:48.149] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 10:47:48 [10:47:48.152] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 10:47:48 [10:47:48.155] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 10:47:48 [10:47:48.161] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749898361] on http://127.0.0.1:8545 10:47:48 [10:47:48.165] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:47:48 [10:47:48.167] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749898361 10:47:48 [10:47:48.167] WARN: foundation:test-date-provider Time set to 2025-06-14T10:52:41.000Z {"offset":292833,"timeMs":1749898361000} 10:47:48 [10:47:48.167] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 10:47:48 [10:47:48.380] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:48 [10:47:48.397] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 10:47:48 [10:47:48.408] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x2a847d8d0e4cd035fab82ad621e9b63f2216bf14ec36aea5765d88bebe3cc472","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":1224373321,"blockNumber":1,"slotNumber":2,"timestamp":1749898325,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x0c3448a4dc1fa4ce20b1d73b4f6afd7884b3f72de2d1330a45123c599b537a12","archiveNextLeafIndex":2} 10:47:48 [10:47:48.500] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":8.553429999999935,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749898325,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:47:48 [10:47:48.569] INFO: pxe:service Registered account 0x1c073e8885a6b262da3141ccf6d60f84de6fa10149c65a637a4d744fb1017501 10:47:48 [10:47:48.570] VERBOSE: p2p Synched to latest block 1 10:47:48 [10:47:48.681] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:48 [10:47:48.690] INFO: pxe:service Added contract SchnorrAccount at 0x1c073e8885a6b262da3141ccf6d60f84de6fa10149c65a637a4d744fb1017501 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 10:47:48 [10:47:48.722] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x0c3448a4dc1fa4ce20b1d73b4f6afd7884b3f72de2d1330a45123c599b537a12","header":{"contentCommitment":{"blobsHash":"0x0067add45d545bfc79202c7525b1957b2d13e46397b99f60e5c355f1fd50b783","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749898325,"version":1224373321},"lastArchive":"0x2e96c2742688b1f72b5e698a0fc9b1b51305f1a74618a18a0580647627c5095f","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2e11d3bcd40d19c24c4573f2e0bc5efd554094afce4a28eb228fefe086944522","nullifierTree":"0x23c56dd81c8ac5ff80de097e77c845af002ee516f7b17c44c0b2fbab75affba5","publicDataTree":"0x1a483f912bd6e38456dd9b16133c14c048f230c813e162b9959bd345a8816a9f"},"totalFees":6794052480,"totalManaUsed":20448}} 10:47:48 [10:47:48.739] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 10:47:48 [10:47:48.823] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x1c073e8885a6b262da3141ccf6d60f84de6fa10149c65a637a4d744fb1017501"} 10:47:48 [10:47:48.859] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:48 [10:47:48.860] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1c073e8885a6b262da3141ccf6d60f84de6fa10149c65a637a4d744fb1017501"} 10:47:48 [10:47:48.887] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:48 [10:47:48.922] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1c073e8885a6b262da3141ccf6d60f84de6fa10149c65a637a4d744fb1017501"} 10:47:48 [10:47:48.937] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 10:47:48 [10:47:48.939] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:49 [10:47:49.048] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0c3448a4dc1fa4ce20b1d73b4f6afd7884b3f72de2d1330a45123c599b537a12","blockNumber":2,"slot":3} 10:47:49 [10:47:49.115] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:49 [10:47:49.140] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x1c073e8885a6b262da3141ccf6d60f84de6fa10149c65a637a4d744fb1017501"} 10:47:49 [10:47:49.174] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:49 [10:47:49.174] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1c073e8885a6b262da3141ccf6d60f84de6fa10149c65a637a4d744fb1017501"} 10:47:49 [10:47:49.243] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1c073e8885a6b262da3141ccf6d60f84de6fa10149c65a637a4d744fb1017501"} 10:47:49 [10:47:49.248] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:49 [10:47:49.264] VERBOSE: simulator:client_execution_context:debug_log Setting 0x1c073e8885a6b262da3141ccf6d60f84de6fa10149c65a637a4d744fb1017501 as fee payer 10:47:49 [10:47:49.392] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:49 [10:47:49.627] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:49 [10:47:49.981] INFO: pxe:service Proving completed in 1268.673205000001ms {"timings":{"total":1268.673205000001,"sync":11.197047999999995,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":82.67658899999878,"oracles":{"callPrivateFunction":{"times":[304.940188999999,215.9960200000005]}}},{"functionName":"private_kernel_init","time":57.11405999999988},{"functionName":"SchnorrAccount:constructor","time":284.5354129999996,"oracles":{"getContractInstance":{"times":[3.74812700000075]},"debugLog":{"times":[0.5559439999997267,0.3369990000010148]},"fetchTaggedLogs":{"times":[76.61244099999931]},"loadCapsule":{"times":[0.5187929999992775,0.9719150000000809,0.9112039999999979]},"notifyCreatedNullifier":{"times":[0.1242629999997007,0.27796699999998964]},"notifyCreatedNote":{"times":[0.11364299999877403]},"getRandomField":{"times":[0.0934930000003078,0.15503399999943213,0.0527910000000702,0.10761199999978999,0.06903200000124343,0.06562200000007579,0.06470099999933154,0.07100200000058976,0.06503200000042852,0.06246099999953003,0.050420999999914784]},"getIndexedTaggingSecretAsSender":{"times":[58.30272100000002]},"incrementAppTaggingSecretIndexAsSender":{"times":[35.17180600000029]}}},{"functionName":"private_kernel_inner","time":108.51964399999997},{"functionName":"SchnorrAccount:entrypoint","time":187.87142600000152,"oracles":{"debugLog":{"times":[0.7233589999996184,0.486482000000251,0.7017280000000028]},"fetchTaggedLogs":{"times":[72.19485799999893]},"loadCapsule":{"times":[0.21963600000162842,0.22331599999961327,0.20120499999939057]},"getNotes":{"times":[1.3686350000007224]},"getAuthWitness":{"times":[0.2741970000006404]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.6001650000016525]}}},{"functionName":"private_kernel_inner","time":102.34621400000105},{"functionName":"private_kernel_reset","time":161.32171299999936},{"functionName":"private_kernel_tail","time":66.39308900000105}],"unaccounted":206.69800899999973}} 10:47:49 [10:47:49.998] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:50 [10:47:50.001] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x0c3448a4dc1fa4ce20b1d73b4f6afd7884b3f72de2d1330a45123c599b537a12","blockNumber":2,"slot":3} 10:47:50 [10:47:50.024] VERBOSE: p2p:tx_pool Adding tx 0x13633ad9cc5b7e5293b1e1c27ec553101d11548aca753f74c691220e4293ce75 to pool {"eventName":"tx-added-to-pool","txHash":"0x13633ad9cc5b7e5293b1e1c27ec553101d11548aca753f74c691220e4293ce75","noteHashCount":1,"nullifierCount":2,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196480,"feePaymentMethod":"fee_juice"} 10:47:50 [10:47:50.032] INFO: node Received tx 0x13633ad9cc5b7e5293b1e1c27ec553101d11548aca753f74c691220e4293ce75 {"txHash":"0x13633ad9cc5b7e5293b1e1c27ec553101d11548aca753f74c691220e4293ce75"} 10:47:50 [10:47:50.032] INFO: pxe:service Sent transaction 0x13633ad9cc5b7e5293b1e1c27ec553101d11548aca753f74c691220e4293ce75 10:47:50 [10:47:50.203] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:50 [10:47:50.407] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:50 [10:47:50.530] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 10:47:50 [10:47:50.543] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-14T10:52:41.000Z","now":"2025-06-14T10:52:43.376Z"} 10:47:50 [10:47:50.562] VERBOSE: simulator:public-processor Processed tx 0x13633ad9cc5b7e5293b1e1c27ec553101d11548aca753f74c691220e4293ce75 with no public calls in 12.257245999999213ms {"txHash":"0x13633ad9cc5b7e5293b1e1c27ec553101d11548aca753f74c691220e4293ce75","txFee":5018455040,"revertCode":0,"gasUsed":{"totalGas":{"daGas":11776,"l2Gas":15104},"billedGas":{"daGas":11776,"l2Gas":15104},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":2,"noteHashCount":1,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":12.257245999999213} 10:47:50 [10:47:50.564] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.020020164999999906s {"duration":0.020020164999999906,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":11776,"l2Gas":15104},"totalSizeInBytes":992} 10:47:50 [10:47:50.664] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:50 [10:47:50.682] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1737fc8caa9956a0009d17fa5f516a91335d332be0a82d3961eebc30ab4e2b31","globalVariables":{"chainId":31337,"version":1224373321,"blockNumber":2,"slotNumber":3,"timestamp":1749898361,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x13633ad9cc5b7e5293b1e1c27ec553101d11548aca753f74c691220e4293ce75"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":151.40685699999995,"publicProcessDuration":20.25843200000054,"rollupCircuitsDuration":139.57322300000124,"txCount":1,"blockNumber":2,"blockTimestamp":1749898361,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:50 [10:47:50.684] VERBOSE: sequencer Attesting committee is empty 10:47:50 [10:47:50.945] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:50 [10:47:50.949] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x91aeccc83ed828c7ebe7d53be70b4cef0121a4e2315bfdc1d2b461fd9e4988d7 10:47:50 [10:47:50.949] VERBOSE: sequencer-client Sent L1 transaction 0x91aeccc83ed828c7ebe7d53be70b4cef0121a4e2315bfdc1d2b461fd9e4988d7 {"gasLimit":470269,"maxFeePerGas":"1.303611197","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:47:50 [10:47:50.959] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 10:47:50 [10:47:50.962] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 10:47:50 [10:47:50.966] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:47:50 [10:47:50.966] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x91aeccc83ed828c7ebe7d53be70b4cef0121a4e2315bfdc1d2b461fd9e4988d7","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":2,"blockTimestamp":1749898361,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:50 [10:47:50.967] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:47:50 [10:47:50.969] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 10:47:50 [10:47:50.970] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749898397] on http://127.0.0.1:8545 10:47:50 [10:47:50.971] INFO: blob-sink:server Received blob sidecar for block 0x3f7c4f21df286880cfec71d65a9d8b9230ffe1db8f380df3d77b112b5a426b64 10:47:50 [10:47:50.974] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:47:50 [10:47:50.976] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749898397 10:47:50 [10:47:50.977] WARN: foundation:test-date-provider Time set to 2025-06-14T10:53:17.000Z {"offset":326023,"timeMs":1749898397000} 10:47:50 [10:47:50.977] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 10:47:50 [10:47:50.977] INFO: blob-sink:server Blob sidecar stored successfully for block 0x3f7c4f21df286880cfec71d65a9d8b9230ffe1db8f380df3d77b112b5a426b64 10:47:51 [10:47:51.153] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:51 [10:47:51.162] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x1737fc8caa9956a0009d17fa5f516a91335d332be0a82d3961eebc30ab4e2b31","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":1224373321,"blockNumber":2,"slotNumber":3,"timestamp":1749898361,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x28073f44978c182c02009c6414b239d08fd19fd333ee592a9ed204085bca17d5","archiveNextLeafIndex":3} 10:47:51 [10:47:51.169] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 10:47:51 [10:47:51.179] INFO: e2e:e2e_partial_notes Deploying Token contract... 10:47:51 [10:47:51.588] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:51 [10:47:51.593] WARN: pxe:service No artifact found for contract class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb when looking for its metadata 10:47:51 [10:47:51.594] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":11.643440000003466,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749898361,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:51 [10:47:51.599] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb as part of deployment for 0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d 10:47:51 [10:47:51.838] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:51 [10:47:51.848] VERBOSE: p2p Synched to latest block 2 10:47:52 [10:47:52.112] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:52 [10:47:52.126] INFO: pxe:service Added contract Token at 0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d with class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb 10:47:52 [10:47:52.151] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x28073f44978c182c02009c6414b239d08fd19fd333ee592a9ed204085bca17d5","header":{"contentCommitment":{"blobsHash":"0x008d89dbb1e7769e8fa7e746ad6b3d99a2cbe584f0ac1b43dcbaa43a3910a579","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749898361,"version":1224373321},"lastArchive":"0x0c3448a4dc1fa4ce20b1d73b4f6afd7884b3f72de2d1330a45123c599b537a12","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x056344b4c678ccbd60b05507e51d32b07cb0134c921e3acf72965e9186267a63","nullifierTree":"0x12934a43a2c1dc51d061189b82dc90faec8ae592be6c59ace246ef0451ba4bd6","publicDataTree":"0x202711bdc805f5063ad5778c3b1e5566ef0fe9d4675511b21bae54b709f4cfa7"},"totalFees":5018455040,"totalManaUsed":15104}} 10:47:52 [10:47:52.233] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:52 [10:47:52.273] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:52 [10:47:52.274] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:52 [10:47:52.314] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x28073f44978c182c02009c6414b239d08fd19fd333ee592a9ed204085bca17d5","blockNumber":3,"slot":4} 10:47:52 [10:47:52.318] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:52 [10:47:52.409] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:52 [10:47:52.419] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1b26ef595599195da4f9531076a294b591520400e4aa3d5d88b259d4b2537ef0 10:47:52 [10:47:52.490] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:52 [10:47:52.491] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:52 [10:47:52.507] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:52 [10:47:52.507] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:52 [10:47:52.515] VERBOSE: pxe:service Added note {"index":0,"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2469cf111e8021fe674b955991751121ddffb55153705100862ca570de67b654","nullifier":"0x22a9f7a777ae20770143e4af1decbb8e8343d22ee9cbebaf8ab30af7c8603121"} 10:47:52 [10:47:52.518] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:52 [10:47:52.520] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:52 [10:47:52.591] VERBOSE: simulator:client_execution_context:debug_log Setting 0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9 as fee payer 10:47:52 [10:47:52.652] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:47:53 [10:47:53.096] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb,0x15aae2e0e2a7081ca06113c682f5373e67df2c3553eda599696574961c502fb4,0x0d6c93288fa1ed94413e630fd9c2f5ded8cd010325d6df84f5b9c8ac12bcac0c,0x146e9c4b5411f0c1e61cf995f05f86929ea24d4cd1b7e2f76bf817490af90f2f 10:47:53 [10:47:53.195] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 10:47:53 [10:47:53.255] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:53 [10:47:53.310] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 10:47:53 [10:47:53.321] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x28073f44978c182c02009c6414b239d08fd19fd333ee592a9ed204085bca17d5","blockNumber":3,"slot":4} 10:47:53 [10:47:53.349] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:47:53 [10:47:53.360] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d,0x0000000000000000000000000000000000000000000000000000000000000001,0x0f3be6e4f402fc4328ebe7ed2825d651c9828cc0d4fb9173d93f4934ac1125c0,0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb,0x148584ba6726534317fb935d794708e0464cbc6bcca1a5dd9ab5116f6a4f832a,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9 10:47:53 [10:47:53.460] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:53 [10:47:53.704] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:53 [10:47:53.917] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:53 [10:47:54.000] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x28073f44978c182c02009c6414b239d08fd19fd333ee592a9ed204085bca17d5","blockNumber":3,"slot":4} 10:47:54 [10:47:54.385] INFO: pxe:service Proving completed in 2241.1811479999997ms {"timings":{"total":2241.1811479999997,"sync":9.710899000001518,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":472.3573180000021,"oracles":{"debugLog":{"times":[0.5459740000005695,0.46547199999986333,0.44768199999816716,0.5390939999997499,0.49422299999787356,0.43210199999884935,0.35181899999952293,18.222208999999566]},"fetchTaggedLogs":{"times":[142.44620700000087]},"loadCapsule":{"times":[0.4514409999974305,0.3306090000005497,0.4534720000010566,0.33887899999899673,0.4228110000003653,0.2668370000028517]},"getSharedSecret":{"times":[62.141380000000936]},"aes128Decrypt":{"times":[1.8269970000001194,1.6080420000034792]},"getPublicKeysAndPartialAddress":{"times":[10.168652000000293]},"getKeyValidationRequest":{"times":[2.510584999999992]},"deliverNote":{"times":[7.941603999999643]},"deleteCapsule":{"times":[0.5621649999993679]},"storeCapsule":{"times":[0.5045230000032461]},"getNotes":{"times":[4.74825200000123]},"getAuthWitness":{"times":[0.30630800000290037]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.11103300000104355]},"callPrivateFunction":{"times":[642.2438309999998,114.58341999999902]},"notifyEnqueuedPublicFunctionCall":{"times":[0.36747900000045775]}}},{"functionName":"private_kernel_init","time":59.81218000000081},{"functionName":"ContractClassRegisterer:register","time":631.7102610000002,"oracles":{"loadCapsule":{"times":[3.9507010000015725]},"notifyCreatedNullifier":{"times":[0.6124060000001919]},"debugLog":{"times":[0.8837630000016361]},"notifyCreatedContractClassLog":{"times":[9.271348999998736]}}},{"functionName":"private_kernel_inner","time":104.7814870000002},{"functionName":"ContractInstanceDeployer:deploy","time":60.95976900000096,"oracles":{"storeInExecutionCache":{"times":[0.2461460000013176]},"callPrivateFunction":{"times":[40.63801599999715]},"notifyCreatedNullifier":{"times":[0.37076900000101887]},"debugLog":{"times":[0.5158739999969839]}}},{"functionName":"private_kernel_inner","time":121.59537000000273},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":30.95193599999766,"oracles":{}},{"functionName":"private_kernel_inner","time":147.56457899999805},{"functionName":"private_kernel_reset","time":152.7784619999984},{"functionName":"private_kernel_tail","time":80.90720299999884}],"unaccounted":368.0516839999982}} 10:47:54 [10:47:54.410] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:54 [10:47:54.592] VERBOSE: p2p:tx_pool Adding tx 0x18c5a66aa9e6ad4b05d36bfff5f6c629c4bb1dec3a20953f00a4af6fec5f7525 to pool {"eventName":"tx-added-to-pool","txHash":"0x18c5a66aa9e6ad4b05d36bfff5f6c629c4bb1dec3a20953f00a4af6fec5f7525","noteHashCount":0,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":709,"proofSize":172052,"size":324937,"feePaymentMethod":"fee_juice"} 10:47:54 [10:47:54.622] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:54 [10:47:54.625] INFO: node Received tx 0x18c5a66aa9e6ad4b05d36bfff5f6c629c4bb1dec3a20953f00a4af6fec5f7525 {"txHash":"0x18c5a66aa9e6ad4b05d36bfff5f6c629c4bb1dec3a20953f00a4af6fec5f7525"} 10:47:54 [10:47:54.625] INFO: pxe:service Sent transaction 0x18c5a66aa9e6ad4b05d36bfff5f6c629c4bb1dec3a20953f00a4af6fec5f7525 10:47:54 [10:47:54.636] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 10:47:54 [10:47:54.648] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-14T10:53:17.000Z","now":"2025-06-14T10:53:20.671Z"} 10:47:54 [10:47:54.850] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:55 [10:47:55.080] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:55 [10:47:55.241] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x5e134abf completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x5e134abf","duration":271.1588699999993} 10:47:55 [10:47:55.444] VERBOSE: simulator:public-processor Processed tx 0x18c5a66aa9e6ad4b05d36bfff5f6c629c4bb1dec3a20953f00a4af6fec5f7525 with 1 public calls in 645.6329790000018ms {"txHash":"0x18c5a66aa9e6ad4b05d36bfff5f6c629c4bb1dec3a20953f00a4af6fec5f7525","txFee":139009277500,"revertCode":0,"gasUsed":{"totalGas":{"daGas":380416,"l2Gas":418375},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":6144,"l2Gas":381831},"billedGas":{"daGas":380416,"l2Gas":418375}},"publicDataWriteCount":12,"nullifierCount":4,"noteHashCount":0,"contractClassLogCount":1,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":645.6329790000018} 10:47:55 [10:47:55.445] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:55 [10:47:55.450] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.8007478309999969s {"duration":0.8007478309999969,"rate":476843.0025257246,"totalPublicGas":{"daGas":6144,"l2Gas":381831},"totalBlockGas":{"daGas":380416,"l2Gas":418375},"totalSizeInBytes":24384} 10:47:55 [10:47:55.636] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 381646.32821141224 mana/s {"blockHash":"0x04d84719e789066a787633aafbfb9c75132d3740feb72598d82fdf61299c04c8","globalVariables":{"chainId":31337,"version":1224373321,"blockNumber":3,"slotNumber":4,"timestamp":1749898397,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x18c5a66aa9e6ad4b05d36bfff5f6c629c4bb1dec3a20953f00a4af6fec5f7525"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":999.7146920000014,"publicProcessDuration":801.2747149999996,"rollupCircuitsDuration":988.9129150000008,"txCount":1,"blockNumber":3,"blockTimestamp":1749898397,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":709} 10:47:55 [10:47:55.639] VERBOSE: sequencer Attesting committee is empty 10:47:55 [10:47:55.739] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:55 [10:47:55.931] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x2f278c456e1f0aa497b614c2091ba5da79e802590a6e0282a8b78e49d96ef3cb 10:47:55 [10:47:55.932] VERBOSE: sequencer-client Sent L1 transaction 0x2f278c456e1f0aa497b614c2091ba5da79e802590a6e0282a8b78e49d96ef3cb {"gasLimit":470234,"maxFeePerGas":"1.279583393","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:47:55 [10:47:55.941] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:55 [10:47:55.944] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:47:55 [10:47:55.945] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x2f278c456e1f0aa497b614c2091ba5da79e802590a6e0282a8b78e49d96ef3cb","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":3,"blockTimestamp":1749898397,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":709} 10:47:55 [10:47:55.945] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:47:55 [10:47:55.956] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 10:47:55 [10:47:55.959] INFO: blob-sink:server Received blob sidecar for block 0x9447d49c26a1f38b60d24721429ab996a0112697937c08ea7a6c56245d7617f4 10:47:55 [10:47:55.965] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 10:47:55 [10:47:55.969] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 10:47:55 [10:47:55.969] INFO: blob-sink:server Blob sidecar stored successfully for block 0x9447d49c26a1f38b60d24721429ab996a0112697937c08ea7a6c56245d7617f4 10:47:55 [10:47:55.970] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749898433] on http://127.0.0.1:8545 10:47:55 [10:47:55.972] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:47:55 [10:47:55.974] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749898433 10:47:55 [10:47:55.974] WARN: foundation:test-date-provider Time set to 2025-06-14T10:53:53.000Z {"offset":357026,"timeMs":1749898433000} 10:47:55 [10:47:55.974] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 10:47:56 [10:47:56.150] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:56 [10:47:56.163] VERBOSE: archiver:block-helper Store contract instance at 0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d 10:47:56 [10:47:56.199] VERBOSE: archiver:block-helper Store contract class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb 10:47:56 [10:47:56.245] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x04d84719e789066a787633aafbfb9c75132d3740feb72598d82fdf61299c04c8","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":1224373321,"blockNumber":3,"slotNumber":4,"timestamp":1749898397,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x259f380537e11ef9dde61c5f3245c185f1066e3f451c5ed4bbbe8395f2539e56","archiveNextLeafIndex":4} 10:47:56 [10:47:56.258] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 10:47:56 [10:47:56.279] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":9.889037000000826,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749898397,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":709} 10:47:56 [10:47:56.366] VERBOSE: p2p Synched to latest block 3 10:47:56 [10:47:56.442] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:56 [10:47:56.470] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x259f380537e11ef9dde61c5f3245c185f1066e3f451c5ed4bbbe8395f2539e56","blockNumber":4,"slot":5} 10:47:56 [10:47:56.814] INFO: aztecjs:deploy_sent_tx Contract 0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d successfully deployed. 10:47:56 [10:47:56.814] INFO: e2e:e2e_partial_notes L2 contract deployed 10:47:56 [10:47:56.817] INFO: e2e:e2e_partial_notes Running test: partial notes mint to private 10:47:56 [10:47:56.818] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:57 [10:47:57.024] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:57 [10:47:57.044] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x259f380537e11ef9dde61c5f3245c185f1066e3f451c5ed4bbbe8395f2539e56","blockNumber":4,"slot":5} 10:47:57 [10:47:57.053] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x259f380537e11ef9dde61c5f3245c185f1066e3f451c5ed4bbbe8395f2539e56","header":{"contentCommitment":{"blobsHash":"0x001b77e0417d8943149d68f5a60bff34caec45af05816e9918f7fb2d566f91b4","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749898397,"version":1224373321},"lastArchive":"0x28073f44978c182c02009c6414b239d08fd19fd333ee592a9ed204085bca17d5","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x056344b4c678ccbd60b05507e51d32b07cb0134c921e3acf72965e9186267a63","nullifierTree":"0x15eabf3fe3c74ead157c445b6bfdb431cd308263e4c38905ea7e22049f6a12d5","publicDataTree":"0x1cd0eb10d69b22b655dfdc3f1dcce199f49d6db266f72d20a5b563fb0a0f7cf6"},"totalFees":139009277500,"totalManaUsed":418375}} 10:47:57 [10:47:57.147] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:57 [10:47:57.191] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:57 [10:47:57.191] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:57 [10:47:57.226] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:57 [10:47:57.278] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9"} 10:47:57 [10:47:57.286] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1b26ef595599195da4f9531076a294b591520400e4aa3d5d88b259d4b2537ef0 10:47:57 [10:47:57.297] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:47:57 [10:47:57.298] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:47:57 [10:47:57.310] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:47:57 [10:47:57.310] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:47:57 [10:47:57.313] VERBOSE: pxe:service Added note {"index":0,"contract":"0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x2469cf111e8021fe674b955991751121ddffb55153705100862ca570de67b654","nullifier":"0x22a9f7a777ae20770143e4af1decbb8e8343d22ee9cbebaf8ab30af7c8603121"} 10:47:57 [10:47:57.315] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:57 [10:47:57.326] VERBOSE: simulator:client_execution_context:debug_log Setting 0x097e9ff227cd28bed0a4a8e1cfdc1eb059d5914fa2748af4be4d6ff79d2451c9 as fee payer 10:47:57 [10:47:57.442] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:57 [10:47:57.627] VERBOSE: simulator:private_execution Executing private function Token:mint_to_private {"contract":"0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d"} 10:47:57 [10:47:57.657] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:47:57 [10:47:57.658] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d"} 10:47:57 [10:47:57.687] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x259f380537e11ef9dde61c5f3245c185f1066e3f451c5ed4bbbe8395f2539e56","blockNumber":4,"slot":5} 10:47:57 [10:47:57.760] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:57 [10:47:57.825] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d"} 10:47:57 [10:47:57.832] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:47:57 [10:47:57.986] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:58 [10:47:58.216] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:58 [10:47:58.341] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x259f380537e11ef9dde61c5f3245c185f1066e3f451c5ed4bbbe8395f2539e56","blockNumber":4,"slot":5} 10:47:58 [10:47:58.485] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:58 [10:47:58.862] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:59 [10:47:59.026] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x259f380537e11ef9dde61c5f3245c185f1066e3f451c5ed4bbbe8395f2539e56","blockNumber":4,"slot":5} 10:47:59 [10:47:59.275] INFO: pxe:service Proving completed in 2243.139493999999ms {"timings":{"total":2243.139493999999,"sync":22.822564999998576,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":341.7468850000005,"oracles":{"debugLog":{"times":[0.7363599999989674,0.5303339999991294,0.5769550000004529,0.5941160000002128,0.44511100000090664,0.3018380000030447,0.2689970000028552,0.5542849999983446]},"fetchTaggedLogs":{"times":[92.39057999999932]},"loadCapsule":{"times":[0.19613500000195927,0.17735499999980675,0.507223000000522,0.15250399999786168,0.18020400000023074,0.16758399999889662]},"getSharedSecret":{"times":[3.5117820000014035]},"aes128Decrypt":{"times":[0.2869879999998375,0.8988730000019132]},"getPublicKeysAndPartialAddress":{"times":[6.294264000000112]},"getKeyValidationRequest":{"times":[1.8208279999998922]},"deliverNote":{"times":[3.055489000002126]},"deleteCapsule":{"times":[0.2629069999966305]},"storeCapsule":{"times":[0.20646599999963655]},"getNotes":{"times":[1.4267470000013418]},"getAuthWitness":{"times":[0.15190399999846704]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.07001200000013341]},"callPrivateFunction":{"times":[902.3004269999983]}}},{"functionName":"private_kernel_init","time":55.42738500000269},{"functionName":"Token:mint_to_private","time":811.1486990000012,"oracles":{"debugLog":{"times":[0.5755349999999453,0.40074099999765167]},"fetchTaggedLogs":{"times":[172.73015600000144]},"loadCapsule":{"times":[0.27985700000135694,0.4257809999980964,0.44345199999952456]},"getRandomField":{"times":[0.12247299999944516,0.10979299999962677,0.15098399999988033,0.06920199999876786,0.22035499999765307,0.06775200000265613,0.07139100000131293,0.049061000001529464,0.04571099999884609,0.06829199999992852,0.05322099999830243,0.04676099999778671]},"getIndexedTaggingSecretAsSender":{"times":[241.32077500000014]},"incrementAppTaggingSecretIndexAsSender":{"times":[90.9798129999981]},"notifyCreatedNullifier":{"times":[0.22637599999870872]},"storeInExecutionCache":{"times":[0.03544099999999162]},"notifyEnqueuedPublicFunctionCall":{"times":[0.05246100000294973]}}},{"functionName":"private_kernel_inner","time":116.52282999999807},{"functionName":"private_kernel_reset","time":183.2799410000007},{"functionName":"private_kernel_tail","time":75.72863599999982}],"unaccounted":636.4625529999976}} 10:47:59 [10:47:59.299] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:59 [10:47:59.323] VERBOSE: p2p:tx_pool Adding tx 0x2c7c8a04e510d3aa89fc78ed2743aac1ac59311ff6ef4a2d0d4e1ebadba53c1b to pool {"eventName":"tx-added-to-pool","txHash":"0x2c7c8a04e510d3aa89fc78ed2743aac1ac59311ff6ef4a2d0d4e1ebadba53c1b","noteHashCount":0,"nullifierCount":2,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226377,"feePaymentMethod":"fee_juice"} 10:47:59 [10:47:59.330] INFO: node Received tx 0x2c7c8a04e510d3aa89fc78ed2743aac1ac59311ff6ef4a2d0d4e1ebadba53c1b {"txHash":"0x2c7c8a04e510d3aa89fc78ed2743aac1ac59311ff6ef4a2d0d4e1ebadba53c1b"} 10:47:59 [10:47:59.331] INFO: pxe:service Sent transaction 0x2c7c8a04e510d3aa89fc78ed2743aac1ac59311ff6ef4a2d0d4e1ebadba53c1b 10:47:59 [10:47:59.504] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:59 [10:47:59.551] VERBOSE: sequencer Building block 4 for slot 5 {"slot":5,"blockNumber":4,"msgCount":0} 10:47:59 [10:47:59.566] VERBOSE: sequencer Processing pending txs {"slot":5,"slotStart":"2025-06-14T10:53:53.000Z","now":"2025-06-14T10:53:56.592Z"} 10:47:59 [10:47:59.623] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0xf1e7e92a completed successfully. {"eventName":"avm-simulation","appCircuitName":"0xf1e7e92a","duration":35.38784300000043} 10:47:59 [10:47:59.644] VERBOSE: simulator:public-processor Processed tx 0x2c7c8a04e510d3aa89fc78ed2743aac1ac59311ff6ef4a2d0d4e1ebadba53c1b with 1 public calls in 65.9435999999987ms {"txHash":"0x2c7c8a04e510d3aa89fc78ed2743aac1ac59311ff6ef4a2d0d4e1ebadba53c1b","txFee":3736336500,"revertCode":0,"gasUsed":{"totalGas":{"daGas":13312,"l2Gas":73695},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":2048,"l2Gas":42495},"billedGas":{"daGas":13312,"l2Gas":73695}},"publicDataWriteCount":2,"nullifierCount":2,"noteHashCount":1,"contractClassLogCount":0,"publicLogCount":1,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":65.9435999999987} 10:47:59 [10:47:59.646] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.078734543999999s {"duration":0.078734543999999,"rate":539724.9776413329,"totalPublicGas":{"daGas":2048,"l2Gas":42495},"totalBlockGas":{"daGas":13312,"l2Gas":73695},"totalSizeInBytes":1216} 10:47:59 [10:47:59.775] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:47:59 [10:47:59.788] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 179239.1095443204 mana/s {"blockHash":"0x09299ba0a9376cc20e20083d9b1accf052e6b3e4ceffc2fb83c9fa06960f2029","globalVariables":{"chainId":31337,"version":1224373321,"blockNumber":4,"slotNumber":5,"timestamp":1749898433,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHashes":["0x2c7c8a04e510d3aa89fc78ed2743aac1ac59311ff6ef4a2d0d4e1ebadba53c1b"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":236.65508300000147,"publicProcessDuration":79.01094100000046,"rollupCircuitsDuration":222.92135499999858,"txCount":1,"blockNumber":4,"blockTimestamp":1749898433,"privateLogCount":1,"publicLogCount":1,"contractClassLogCount":0,"contractClassLogSize":0} 10:47:59 [10:47:59.789] VERBOSE: sequencer Attesting committee is empty 10:48:00 [10:48:00.045] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:00 [10:48:00.050] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x15a2f14a5b7100ca02fbb999cf5039ada53d9bb5fe1e9654fd942adb818ceee8 10:48:00 [10:48:00.051] VERBOSE: sequencer-client Sent L1 transaction 0x15a2f14a5b7100ca02fbb999cf5039ada53d9bb5fe1e9654fd942adb818ceee8 {"gasLimit":470310,"maxFeePerGas":"1.261127721","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:48:00 [10:48:00.079] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:48:00 [10:48:00.079] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x15a2f14a5b7100ca02fbb999cf5039ada53d9bb5fe1e9654fd942adb818ceee8","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":4,"blockTimestamp":1749898433,"privateLogCount":1,"publicLogCount":1,"contractClassLogCount":0,"contractClassLogSize":0} 10:48:00 [10:48:00.079] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:48:00 [10:48:00.082] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1749898469] on http://127.0.0.1:8545 10:48:00 [10:48:00.085] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:48:00 [10:48:00.093] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749898469 10:48:00 [10:48:00.094] WARN: foundation:test-date-provider Time set to 2025-06-14T10:54:29.000Z {"offset":388906,"timeMs":1749898469000} 10:48:00 [10:48:00.094] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 10:48:00 [10:48:00.096] INFO: blob-sink:server Received blob sidecar for block 0xc3f50dd2fcf9b57a72f94b209cf1ff46db17de534867ff477948914abc611ef7 10:48:00 [10:48:00.101] INFO: blob-sink:server Blob sidecar stored successfully for block 0xc3f50dd2fcf9b57a72f94b209cf1ff46db17de534867ff477948914abc611ef7 10:48:00 [10:48:00.249] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:00 [10:48:00.254] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000004"] on http://127.0.0.1:8545 10:48:00 [10:48:00.255] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 10:48:00 [10:48:00.256] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 10:48:00 [10:48:00.452] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:00 [10:48:00.732] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:00 [10:48:00.746] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x09299ba0a9376cc20e20083d9b1accf052e6b3e4ceffc2fb83c9fa06960f2029","blockNumber":4,"txCount":1,"globalVariables":{"chainId":31337,"version":1224373321,"blockNumber":4,"slotNumber":5,"timestamp":1749898433,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"archiveRoot":"0x1bb1c7aa39887b1e215f4dec5bd948fe63cef0ea7af7c8dd4bec2d348eac9078","archiveNextLeafIndex":5} 10:48:00 [10:48:00.749] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 10:48:00 [10:48:00.851] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":7.572237000000314,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749898433,"privateLogCount":1,"publicLogCount":1,"contractClassLogCount":0,"contractClassLogSize":0} 10:48:00 [10:48:00.861] VERBOSE: p2p Synched to latest block 4 10:48:00 [10:48:00.937] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:01 [10:48:01.114] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1bb1c7aa39887b1e215f4dec5bd948fe63cef0ea7af7c8dd4bec2d348eac9078","blockNumber":5,"slot":6} 10:48:01 [10:48:01.141] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:01 [10:48:01.343] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x1bb1c7aa39887b1e215f4dec5bd948fe63cef0ea7af7c8dd4bec2d348eac9078","header":{"contentCommitment":{"blobsHash":"0x0030357875740967d525790164a0c5497f180d60e223b296b0f1495ae8fc8068","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749898433,"version":1224373321},"lastArchive":"0x259f380537e11ef9dde61c5f3245c185f1066e3f451c5ed4bbbe8395f2539e56","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2c1779bde7093af79b68aa67ccd57207e744a8dd987c6b8223ae7f6b4372e3e7","nullifierTree":"0x14153fb1f75ee2273a9b399088bd3a959bee744ed80600c098577541e87b9449","publicDataTree":"0x1ca61cbf17d53494f2cf8b5adc28f2c5e1e79464b2d00a5f4a456a55fed8973c"},"totalFees":3736336500,"totalManaUsed":73695}} 10:48:01 [10:48:01.345] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:01 [10:48:01.737] VERBOSE: simulator Executing utility function balance_of_private {"contract":"0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d","selector":"0x4375727c"} 10:48:01 [10:48:01.745] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:01 [10:48:01.784] VERBOSE: simulator:client_view_context:debug_log Performing message discovery 10:48:01 [10:48:01.785] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d"} 10:48:01 [10:48:01.815] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x1bb1c7aa39887b1e215f4dec5bd948fe63cef0ea7af7c8dd4bec2d348eac9078","blockNumber":5,"slot":6} 10:48:01 [10:48:01.991] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:48:02 [10:48:02.005] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d"} 10:48:02 [10:48:02.015] VERBOSE: simulator:client_view_context:debug_log Processing log with tag 0x141b2e010e2ff3511c65218ff8d42c5bfa0dff0308c0c2ef1921fb29a580b265 10:48:02 [10:48:02.031] VERBOSE: simulator:client_view_context:debug_log Processing partial note private msg 10:48:02 [10:48:02.035] VERBOSE: simulator:client_view_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000001 pending partial notes 10:48:02 [10:48:02.039] VERBOSE: simulator:client_view_context:debug_log Completion log found for partial note with tag 0x1ca41aac9dcfb6b1c63f4acd392a84bbef5d993379ab8b1e011df20fbc99849c 10:48:02 [10:48:02.040] VERBOSE: simulator:client_view_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d for storage slot 0x195723c16aaa0bd207a786e0c3ee35c31d83eb1e3a9adabddc838cbb05424fee 10:48:02 [10:48:02.046] VERBOSE: simulator:client_view_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:48:02 [10:48:02.047] VERBOSE: simulator:client_view_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes for partial note with tag 0x1ca41aac9dcfb6b1c63f4acd392a84bbef5d993379ab8b1e011df20fbc99849c 10:48:02 [10:48:02.050] VERBOSE: pxe:service Added note {"index":192,"contract":"0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d","slot":"0x195723c16aaa0bd207a786e0c3ee35c31d83eb1e3a9adabddc838cbb05424fee","noteHash":"0x15b27f31db771e468954bcbdd67efb2b3da47594a74bc43734b9e528d1628c81","nullifier":"0x2f382350d43888d0266e26de51c6121274e300481d06c4de884fc7a349159f1b"} 10:48:02 [10:48:02.058] VERBOSE: simulator Utility simulation for 0x1aad9aa62bcee8369e40d1d1b98ef7718d86ea0f3b3d139442ae7ebae39c146d.0x4375727c completed 10:48:02 [10:48:02.064] INFO: node Stopping 10:48:02 [10:48:02.065] INFO: slasher Slasher client stopped. 10:48:02 [10:48:02.065] INFO: sequencer Stopped sequencer 10:48:02 [10:48:02.065] INFO: p2p P2P client stopped. 10:48:02 [10:48:02.067] INFO: world_state Stopped world state synchronizer 10:48:02 [10:48:02.067] INFO: archiver Stopped. 10:48:02 [10:48:02.068] INFO: node Stopped 10:48:02 [10:48:02.068] VERBOSE: e2e:e2e_partial_notes Cleaning up ACVM state 10:48:02 [10:48:02.068] INFO: e2e:e2e_partial_notes Cleaning up ACVM temp directory /tmp/9f446227 10:48:02 [10:48:02.068] VERBOSE: e2e:e2e_partial_notes Cleaning up BB state 10:48:02 [10:48:02.077] INFO: blob-sink:server Stopping blob sink 10:48:02 [10:48:02.077] INFO: blob-sink:server Blob sink stopped 10:48:02 [10:48:02.078] VERBOSE: e2e:e2e_partial_notes Cleaning up data directory at /tmp/d848bc181d2c57e4 10:48:02 PASS src/e2e_partial_notes.test.ts 10:48:02 partial notes 10:48:02 ✓ mint to private (5246 ms) 10:48:02 10:48:02 Test Suites: 1 passed, 1 total 10:48:02 Tests: 1 passed, 1 total 10:48:02 Snapshots: 0 total 10:48:02 Time: 27.475 s 10:48:02 Ran all test suites matching /src\/e2e_partial_notes.test.ts/i. 10:48:02 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?