Parent Log: http://ci.aztec-labs.com/d807837b95d20001 Command: 97e7ef9a556fd7e6:ISOLATE=1:NAME=e2e_private_voting_contract yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_private_voting_contract.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/f3a077dd83e56ee0ae0a1175f0dc39fdfa50e6d7 Env: REF_NAME=gh-readonly-queue/master/pr-14734-a229cf871638d3fd774f9272645cc1ac29b8196a CURRENT_VERSION=0.87.9 CI_FULL=1 Date: Tue Jun 24 10:37:40 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14734_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_f9aac7f8d569bb18_master 10:37:40 +++ id -u 10:37:40 +++ id -g 10:37:40 ++ docker run -d --name e2e_private_voting_contract --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_private_voting_contract.test.ts'\''' 10:37:41 + cid=e07d7894ff839f3bdc12abd5137548445062ecf49e330553f5bc97130ead598f 10:37:41 + set +x 10:37:41 {"level":25,"time":1750761461906,"pid":12,"hostname":"e07d7894ff83","module":"logger","msg":"Logger initialized with level verbose"} 10:37:48 [10:37:48.416] INFO: e2e:e2e_private_voting_contract Logging metrics to log/unknown.jsonl 10:37:48 [10:37:48.703] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-aXNqId/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 10:37:48 [10:37:48.744] INFO: e2e:e2e_private_voting_contract Calling anvil_setBlockTimestampInterval with params: [12] 10:37:48 [10:37:48.767] WARN: e2e:e2e_private_voting_contract Set block interval to 12 10:37:48 [10:37:48.767] VERBOSE: e2e:e2e_private_voting_contract Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 10:37:48 [10:37:48.810] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 10:37:48 [10:37:48.820] VERBOSE: e2e:e2e_private_voting_contract Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 10:37:48 [10:37:48.841] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 10:37:48 [10:37:48.847] VERBOSE: e2e:e2e_private_voting_contract Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 10:37:48 [10:37:48.865] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169 {"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 10:37:48 [10:37:48.870] VERBOSE: e2e:e2e_private_voting_contract Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 10:37:48 [10:37:48.886] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c {"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 10:37:48 [10:37:48.892] VERBOSE: e2e:e2e_private_voting_contract Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 10:37:48 [10:37:48.915] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e {"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"} 10:37:48 [10:37:48.922] VERBOSE: e2e:e2e_private_voting_contract Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 10:37:48 [10:37:48.944] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64 {"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"} 10:37:48 [10:37:48.952] VERBOSE: e2e:e2e_private_voting_contract Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 10:37:48 [10:37:48.953] VERBOSE: e2e:e2e_private_voting_contract Waiting for deployments to complete 10:37:48 [10:37:48.954] INFO: e2e:e2e_private_voting_contract Waiting for 6 transactions to be mined... 10:37:48 [10:37:48.983] INFO: e2e:e2e_private_voting_contract All transactions mined successfully 10:37:49 [10:37:49.003] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 {"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"} 10:37:49 [10:37:49.004] VERBOSE: e2e:e2e_private_voting_contract Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8 10:37:49 [10:37:49.015] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63 {"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"} 10:37:49 [10:37:49.046] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf {"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"} 10:37:49 [10:37:49.053] VERBOSE: e2e:e2e_private_voting_contract Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 10:37:49 [10:37:49.088] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 {"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"} 10:37:49 [10:37:49.089] VERBOSE: e2e:e2e_private_voting_contract Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2 10:37:49 [10:37:49.115] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1 {"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"} 10:37:49 [10:37:49.120] VERBOSE: e2e:e2e_private_voting_contract Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 10:37:49 [10:37:49.135] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a {"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"} 10:37:49 [10:37:49.135] VERBOSE: e2e:e2e_private_voting_contract Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a 10:37:49 [10:37:49.136] VERBOSE: e2e:e2e_private_voting_contract Waiting for deployments to complete 10:37:49 [10:37:49.136] INFO: e2e:e2e_private_voting_contract Waiting for 8 transactions to be mined... 10:37:49 [10:37:49.157] INFO: e2e:e2e_private_voting_contract All transactions mined successfully 10:37:49 [10:37:49.171] VERBOSE: e2e:e2e_private_voting_contract Deployed shared contracts 10:37:49 [10:37:49.212] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 {"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"} 10:37:49 [10:37:49.213] VERBOSE: e2e:e2e_private_voting_contract Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034 10:37:49 [10:37:49.217] VERBOSE: e2e:e2e_private_voting_contract Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 10:37:49 [10:37:49.233] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c {"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"} 10:37:49 [10:37:49.277] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a {"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"} 10:37:49 [10:37:49.284] VERBOSE: e2e:e2e_private_voting_contract Awaiting for linked libraries to be deployed 10:37:49 [10:37:49.326] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0xa93814a6b67c08e1cdae60c6519c000753604443e1addec221346d3dc098369b {"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"} 10:37:49 [10:37:49.333] VERBOSE: e2e:e2e_private_voting_contract Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016 {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100} 10:37:49 [10:37:49.333] INFO: e2e:e2e_private_voting_contract Waiting for 9 transactions to be mined... 10:37:49 [10:37:49.357] INFO: e2e:e2e_private_voting_contract All transactions mined successfully 10:37:49 [10:37:49.357] VERBOSE: e2e:e2e_private_voting_contract All core contracts have been deployed 10:37:49 [10:37:49.375] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a {"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"} 10:37:49 [10:37:49.375] VERBOSE: e2e:e2e_private_voting_contract Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments) 10:37:49 [10:37:49.389] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab {"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"} 10:37:49 [10:37:49.394] VERBOSE: e2e:e2e_private_voting_contract Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae 10:37:49 [10:37:49.423] VERBOSE: e2e:e2e_private_voting_contract Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d {"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"} 10:37:49 [10:37:49.423] VERBOSE: e2e:e2e_private_voting_contract Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d 10:37:49 [10:37:49.423] INFO: e2e:e2e_private_voting_contract Waiting for 10 transactions to be mined... 10:37:49 [10:37:49.442] INFO: e2e:e2e_private_voting_contract All transactions mined successfully 10:37:49 [10:37:49.450] VERBOSE: e2e:e2e_private_voting_contract Rollup deployed 10:37:49 [10:37:49.450] VERBOSE: e2e:e2e_private_voting_contract Waiting for rollup and slash factory to be deployed 10:37:49 [10:37:49.451] INFO: e2e:e2e_private_voting_contract Waiting for 10 transactions to be mined... 10:37:49 [10:37:49.477] INFO: e2e:e2e_private_voting_contract All transactions mined successfully 10:37:49 [10:37:49.477] VERBOSE: e2e:e2e_private_voting_contract All transactions for L1 deployment have been mined 10:37:49 [10:37:49.504] INFO: e2e:e2e_private_voting_contract Aztec L1 contracts initialized {"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 10:37:49 [10:37:49.509] INFO: ethereum:cheat_codes Calling anvil_getAutomine with params: [] on http://127.0.0.1:8545 10:37:49 [10:37:49.512] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:49 [10:37:49.513] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016 10:37:49 [10:37:49.514] INFO: telemetry:client Using NoopTelemetryClient 10:37:49 [10:37:49.519] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/4143f614b8986beb/blob-sink with map size 134217728 KB (LMDB v2) 10:37:49 [10:37:49.519] WARN: aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks 10:37:49 [10:37:49.520] INFO: ethereum:cheat_codes Calling evm_mine with params: [] on http://127.0.0.1:8545 10:37:49 [10:37:49.522] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:49 [10:37:49.524] INFO: aztecjs:utils:watcher New L1 time: 1750761708 10:37:49 [10:37:49.525] WARN: foundation:test-date-provider Time set to 2025-06-24T10:41:48.000Z {"offset":238475,"timeMs":1750761708000} 10:37:49 [10:37:49.526] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 10:37:49 [10:37:49.533] INFO: blob-sink:server Server is running on http://localhost:42185 10:37:49 [10:37:49.533] VERBOSE: e2e:e2e_private_voting_contract Creating and synching an aztec node... 10:37:49 [10:37:49.534] VERBOSE: e2e:e2e_private_voting_contract Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/81e9585c/acvm 10:37:49 [10:37:49.534] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:42185"} 10:37:49 [10:37:49.549] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 10:37:49 [10:37:49.550] INFO: archiver:lmdb Creating archiver data store at directory /tmp/4143f614b8986beb/archiver with map size 134217728 KB (LMDB v2) 10:37:49 [10:37:49.551] INFO: archiver:lmdb Starting data store with maxReaders 16 10:37:49 [10:37:49.749] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:49 [10:37:49.852] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:42185"} 10:37:49 [10:37:49.873] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:42185"} 10:37:49 [10:37:49.873] WARN: node:blob-sink:client No L1 consensus host urls configured 10:37:49 [10:37:49.874] WARN: node:blob-sink:client No archive client configured 10:37:49 [10:37:49.877] INFO: archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x4bb85ab5b5cd67243b66b198076caece3b0e1185089a96d0cbd1f6e554ae9a4f {"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x99407427af9aad4cbd825516bc20f8f7c6669e49da97fdb4a6ea59dabae6a6e8","l1BlockNumber":16}} 10:37:49 [10:37:49.894] INFO: archiver Initial archiver sync to L1 block 20 complete. {"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 10:37:49 [10:37:49.903] INFO: world-state:database Creating world state data store at directory /tmp/4143f614b8986beb/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 10:37:49 [10:37:49.928] INFO: world_state Created world state synchroniser with block history of 64 10:37:49 [10:37:49.929] WARN: node Aztec node is accepting fake proofs 10:37:49 [10:37:49.941] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/4143f614b8986beb/p2p with map size 134217728 KB (LMDB v2) 10:37:49 [10:37:49.943] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 10:37:49 [10:37:49.944] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/4143f614b8986beb/p2p-archive with map size 134217728 KB (LMDB v2) 10:37:49 [10:37:49.945] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 10:37:49 [10:37:49.946] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/4143f614b8986beb/p2p-peers with map size 134217728 KB (LMDB v2) 10:37:49 [10:37:49.948] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 10:37:49 [10:37:49.949] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 10:37:49 [10:37:49.950] VERBOSE: p2p P2P is disabled. Using dummy P2P service 10:37:49 [10:37:49.954] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 10:37:49 [10:37:49.955] INFO: world_state Started world state synchronizer from block 1 10:37:49 [10:37:49.956] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:49 [10:37:49.959] VERBOSE: world-state:block_stream Starting sync from block number 0 10:37:49 [10:37:49.962] INFO: p2p Starting p2p client from block 0 with empty mempools 10:37:49 [10:37:49.962] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 10:37:49 [10:37:49.963] INFO: slasher Slasher client initialized 10:37:49 [10:37:49.964] INFO: slasher Starting Slasher client... 10:37:49 [10:37:49.999] VERBOSE: validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:37:49 [10:37:49.999] VERBOSE: node All Aztec Node subsystems synced 10:37:50 [10:37:50.012] INFO: sequencer-client Deploying forwarder contract 10:37:50 [10:37:50.019] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 10:37:50 [10:37:50.035] VERBOSE: sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918 {"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"} 10:37:50 [10:37:50.035] VERBOSE: sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918. 10:37:50 [10:37:50.040] INFO: sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 10:37:50 [10:37:50.075] INFO: validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:37:50 [10:37:50.075] VERBOSE: validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0 10:37:50 [10:37:50.076] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/81e9585c/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 10:37:50 [10:37:50.331] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 10:37:50 [10:37:50.334] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 10:37:50 [10:37:50.336] INFO: node Aztec Node version: 0.87.9 10:37:50 [10:37:50.337] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","governanceAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","governanceProposerAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","rollupAddress":"0x0b306bf915c4d645ff596e518faf3f9669b97016","inboxAddress":"0xde46dabb403afc77dd67b6c69bbd44b9347ae0e7","outboxAddress":"0xd1c0400e4d1cb3e9b5b8b7571814729e72bf7061","feeJuicePortalAddress":"0x828357aaec783ab8368becae52781ff73a6dbb35","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0x75537828f2ce51be7289709686a69cbfdbb714f1","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae","feeAssetHandlerAddress":"0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6","stakingAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788"} 10:37:50 [10:37:50.338] VERBOSE: e2e:e2e_private_voting_contract Creating a pxe... 10:37:50 [10:37:50.344] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:50 [10:37:50.361] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/8ed47ae496fecd7f/pxe_data with map size 134217728 KB (LMDB v2) 10:37:50 [10:37:50.375] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1057064ca8f2da9d944c996dfcc60297c2aa6f29a54e3d0efb38d3b8b44cd068","blockNumber":1,"slot":2} 10:37:50 [10:37:50.377] INFO: pxe:data:lmdb Starting data store with maxReaders 16 10:37:50 [10:37:50.549] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:50 [10:37:50.641] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 10:37:50 [10:37:50.642] INFO: pxe:service Started PXE connected to chain 31337 version 1974575722 10:37:50 [10:37:50.690] INFO: pxe:service Registered account 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 10:37:50 [10:37:50.764] WARN: pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata 10:37:50 [10:37:50.765] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 10:37:50 [10:37:50.793] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:50 [10:37:50.982] INFO: pxe:service Added contract SchnorrAccount at 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 10:37:50 [10:37:50.995] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1057064ca8f2da9d944c996dfcc60297c2aa6f29a54e3d0efb38d3b8b44cd068","blockNumber":1,"slot":2} 10:37:51 [10:37:51.041] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:51 [10:37:51.052] VERBOSE: pxe:block_stream Starting sync from block number 0 10:37:51 [10:37:51.092] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 10:37:51 [10:37:51.212] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:37:51 [10:37:51.952] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 10:37:52 [10:37:52.094] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 10:37:52 [10:37:52.205] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:52 [10:37:52.245] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1057064ca8f2da9d944c996dfcc60297c2aa6f29a54e3d0efb38d3b8b44cd068","blockNumber":1,"slot":2} 10:37:52 [10:37:52.353] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:37:52 [10:37:52.396] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:52 [10:37:52.397] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:37:52 [10:37:52.439] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:52 [10:37:52.473] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:37:52 [10:37:52.477] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:52 [10:37:52.660] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:52 [10:37:52.683] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:37:52 [10:37:52.720] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:52 [10:37:52.721] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:37:52 [10:37:52.784] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1057064ca8f2da9d944c996dfcc60297c2aa6f29a54e3d0efb38d3b8b44cd068","blockNumber":1,"slot":2} 10:37:52 [10:37:52.791] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:37:52 [10:37:52.795] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:52 [10:37:52.813] VERBOSE: simulator:client_execution_context:debug_log Setting 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 as fee payer 10:37:53 [10:37:53.005] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:53 [10:37:53.313] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:53 [10:37:53.447] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1057064ca8f2da9d944c996dfcc60297c2aa6f29a54e3d0efb38d3b8b44cd068","blockNumber":1,"slot":2} 10:37:53 [10:37:53.576] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:54 [10:37:54.006] INFO: pxe:service Proving completed in 2966.6018700000004ms {"timings":{"total":2966.6018700000004,"sync":13.290624999999636,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":190.0661089999976,"oracles":{"callPrivateFunction":{"times":[1035.8148520000013,416.0152199999993,226.2395149999993]}}},{"functionName":"private_kernel_init","time":120.25144800000089},{"functionName":"ContractClassRegisterer:register","time":1009.3829040000019,"oracles":{"loadCapsule":{"times":[76.45150599999943]},"notifyCreatedNullifier":{"times":[19.05596499999956]},"debugLog":{"times":[1.2232750000002852]},"notifyCreatedContractClassLog":{"times":[17.38550100000066]}}},{"functionName":"private_kernel_inner","time":185.7394089999998},{"functionName":"SchnorrAccount:constructor","time":385.4136830000007,"oracles":{"getContractInstance":{"times":[1.4241899999997258]},"debugLog":{"times":[0.5215910000006261,0.3860180000010587]},"fetchTaggedLogs":{"times":[79.17191099999945]},"loadCapsule":{"times":[0.38413799999943876,0.2768259999993461,0.29871599999933096]},"notifyCreatedNullifier":{"times":[0.2246450000002369,0.356777000000875]},"notifyCreatedNote":{"times":[0.48613099999965925]},"getRandomField":{"times":[0.7035039999991568,0.28904599999987113,0.1788539999997738,0.13101199999982782,0.0985419999997248,0.10409200000140117,0.0712920000005397,0.06821099999979197,0.07226099999934377,0.08645199999955366,0.07913099999859696]},"getIndexedTaggingSecretAsSender":{"times":[48.33432999999968]},"incrementAppTaggingSecretIndexAsSender":{"times":[39.11324500000046]}}},{"functionName":"private_kernel_inner","time":119.51742299999933},{"functionName":"SchnorrAccount:entrypoint","time":189.32288700000026,"oracles":{"debugLog":{"times":[0.6474939999989147,0.6047730000009324,0.9089089999997668]},"fetchTaggedLogs":{"times":[72.66490299999896]},"loadCapsule":{"times":[0.3131469999989349,0.34705700000085926,0.3590379999986908]},"getNotes":{"times":[2.428561999999147]},"getAuthWitness":{"times":[0.4420689999988099]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.0298210000000836]}}},{"functionName":"private_kernel_inner","time":106.38607599999887},{"functionName":"private_kernel_reset","time":154.2779960000007},{"functionName":"private_kernel_tail","time":67.88941300000079}],"unaccounted":425.063897}} 10:37:54 [10:37:54.021] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:54 [10:37:54.096] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1057064ca8f2da9d944c996dfcc60297c2aa6f29a54e3d0efb38d3b8b44cd068","blockNumber":1,"slot":2} 10:37:54 [10:37:54.153] VERBOSE: p2p:tx_pool Adding tx 0x1bc8e9d13e3467d5d2a5a89aa508ffc79bcbb9b5d7eead45fae88d4a061c150d to pool {"eventName":"tx-added-to-pool","txHash":"0x1bc8e9d13e3467d5d2a5a89aa508ffc79bcbb9b5d7eead45fae88d4a061c150d","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"} 10:37:54 [10:37:54.169] INFO: node Received tx 0x1bc8e9d13e3467d5d2a5a89aa508ffc79bcbb9b5d7eead45fae88d4a061c150d {"txHash":"0x1bc8e9d13e3467d5d2a5a89aa508ffc79bcbb9b5d7eead45fae88d4a061c150d"} 10:37:54 [10:37:54.169] INFO: pxe:service Sent transaction 0x1bc8e9d13e3467d5d2a5a89aa508ffc79bcbb9b5d7eead45fae88d4a061c150d 10:37:54 [10:37:54.229] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:54 [10:37:54.434] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:54 [10:37:54.620] VERBOSE: sequencer Building block 1 for slot 2 {"slot":2,"blockNumber":1,"msgCount":0} 10:37:54 [10:37:54.633] VERBOSE: sequencer Processing pending txs {"slot":2,"slotStart":"2025-06-24T10:42:12.000Z","now":"2025-06-24T10:41:53.108Z"} 10:37:54 [10:37:54.646] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:54 [10:37:54.917] VERBOSE: simulator:public-processor Processed tx 0x1bc8e9d13e3467d5d2a5a89aa508ffc79bcbb9b5d7eead45fae88d4a061c150d with no public calls in 200.86507899999924ms {"txHash":"0x1bc8e9d13e3467d5d2a5a89aa508ffc79bcbb9b5d7eead45fae88d4a061c150d","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":200.86507899999924} 10:37:54 [10:37:54.920] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:54 [10:37:54.926] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.29110516300000017s {"duration":0.29110516300000017,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344} 10:37:55 [10:37:55.077] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2294323278f6d9d6774113a7711c28a54bea42b9ff97a0b87749466794b7b166","globalVariables":{"chainId":31337,"version":1974575722,"blockNumber":1,"slotNumber":2,"timestamp":1750761732,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x1bc8e9d13e3467d5d2a5a89aa508ffc79bcbb9b5d7eead45fae88d4a061c150d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":457.0258850000009,"publicProcessDuration":292.52746300000035,"rollupCircuitsDuration":444.3483670000005,"txCount":1,"blockNumber":1,"blockTimestamp":1750761732,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:37:55 [10:37:55.080] VERBOSE: sequencer Attesting committee is empty 10:37:55 [10:37:55.158] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:55 [10:37:55.391] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:55 [10:37:55.398] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x879004edfc0d98e574b9989ee5ef1950d44272815fe5b74a5e76eb3d08f4fe4b 10:37:55 [10:37:55.398] VERBOSE: sequencer-client Sent L1 transaction 0x879004edfc0d98e574b9989ee5ef1950d44272815fe5b74a5e76eb3d08f4fe4b {"gasLimit":514585,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"} 10:37:55 [10:37:55.412] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:37:55 [10:37:55.413] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x879004edfc0d98e574b9989ee5ef1950d44272815fe5b74a5e76eb3d08f4fe4b","calldataSize":1444,"calldataGas":12292,"txCount":1,"blockNumber":1,"blockTimestamp":1750761732,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:37:55 [10:37:55.414] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:37:55 [10:37:55.433] INFO: blob-sink:server Received blob sidecar for block 0x8bb49a35592d8e11c8f98e074c653274616a4bdb04e18f3759cf4a01f3e31da8 10:37:55 [10:37:55.439] INFO: blob-sink:server Blob sidecar stored successfully for block 0x8bb49a35592d8e11c8f98e074c653274616a4bdb04e18f3759cf4a01f3e31da8 10:37:55 [10:37:55.597] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:55 [10:37:55.602] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on http://127.0.0.1:8545 10:37:55 [10:37:55.607] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 10:37:55 [10:37:55.621] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 10:37:55 [10:37:55.756] VERBOSE: archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a 10:37:55 [10:37:55.766] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750761768] on http://127.0.0.1:8545 10:37:55 [10:37:55.769] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:37:55 [10:37:55.771] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x2294323278f6d9d6774113a7711c28a54bea42b9ff97a0b87749466794b7b166","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":1974575722,"blockNumber":1,"slotNumber":2,"timestamp":1750761732,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x14e4e41e8b54a3ba90c5a1c3aa10f730bcee4207e0b0014a31d74c9742d1402e","archiveNextLeafIndex":2} 10:37:55 [10:37:55.773] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750761768 10:37:55 [10:37:55.774] WARN: foundation:test-date-provider Time set to 2025-06-24T10:42:48.000Z {"offset":292226,"timeMs":1750761768000} 10:37:55 [10:37:55.774] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 10:37:55 [10:37:55.806] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:55 [10:37:55.891] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":39.699587999999494,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750761732,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 10:37:55 [10:37:55.892] WARN: pxe:service No artifact found for contract class 0x0fbfc518e8ee4971622de3f275570a7a200e07a53c8d4dd82acd6d50f2c32d82 when looking for its metadata 10:37:55 [10:37:55.893] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0fbfc518e8ee4971622de3f275570a7a200e07a53c8d4dd82acd6d50f2c32d82 as part of deployment for 0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd 10:37:55 [10:37:55.983] VERBOSE: p2p Synched to latest block 1 10:37:56 [10:37:56.022] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:56 [10:37:56.045] INFO: pxe:service Added contract EasyPrivateVoting at 0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd with class 0x0fbfc518e8ee4971622de3f275570a7a200e07a53c8d4dd82acd6d50f2c32d82 10:37:56 [10:37:56.066] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x14e4e41e8b54a3ba90c5a1c3aa10f730bcee4207e0b0014a31d74c9742d1402e","header":{"contentCommitment":{"blobsHash":"0x0016c6a3d7fc3394671f4c98bd3e2351fbfca6b32dc343c50e9d072b0619c1df","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750761732,"version":1974575722},"lastArchive":"0x1057064ca8f2da9d944c996dfcc60297c2aa6f29a54e3d0efb38d3b8b44cd068","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x11f5aebf8a976392b746f11cc90f691d348f31f6fa9eb6ad436ed7897a36228f","nullifierTree":"0x13d1e519d900d0f3c0e7679632aae0698180f0fb5561baec791b751b7628155a","publicDataTree":"0x0df5c3d0e4ed2c2b8d7b78db3b8156b2620c45262ddcf32bee89e2483814a621"},"totalFees":6794052480,"totalManaUsed":20448}} 10:37:56 [10:37:56.161] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:37:56 [10:37:56.200] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:37:56 [10:37:56.200] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:37:56 [10:37:56.246] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:56 [10:37:56.265] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:37:56 [10:37:56.270] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1a1e2a53d755a165e498590fd85b1cd7d7dfe272e3de81b17563aa269e544360 10:37:56 [10:37:56.283] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:37:56 [10:37:56.284] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:37:56 [10:37:56.299] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:37:56 [10:37:56.300] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:37:56 [10:37:56.315] VERBOSE: pxe:service Added note {"index":0,"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x261891464d85b916c1e62eec36f1edf9237e11cee19bb167a74fb838bc8fc05b","nullifier":"0x22784239a0d9e9ec0b27c81de76d48df36ddc1966ebca5f487637fd2d27d51e5"} 10:37:56 [10:37:56.316] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 10:37:56 [10:37:56.322] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:37:56 [10:37:56.338] VERBOSE: simulator:client_execution_context:debug_log Setting 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 as fee payer 10:37:56 [10:37:56.372] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:37:56 [10:37:56.813] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0fbfc518e8ee4971622de3f275570a7a200e07a53c8d4dd82acd6d50f2c32d82,0x07c93431ee7228896e85ee48c35d1e5b5d747283021244321f1e2360b29dc702,0x0eb6620c85cc03233af00abd8ad2f32e9528f320b09af98beb73808818ac20da,0x13d6566bc501e38ffb391e30ee7b29055b9227558ad14198c97fbf4c5f80e599 10:37:56 [10:37:56.954] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 10:37:57 [10:37:57.021] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:57 [10:37:57.095] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 10:37:57 [10:37:57.119] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x14e4e41e8b54a3ba90c5a1c3aa10f730bcee4207e0b0014a31d74c9742d1402e","blockNumber":2,"slot":3} 10:37:57 [10:37:57.156] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 10:37:57 [10:37:57.172] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd,0x0000000000000000000000000000000000000000000000000000000000000001,0x2b3b008f87dcf6073c64446e6a68bf9400c20c65673902d89973c53d4d3e4590,0x0fbfc518e8ee4971622de3f275570a7a200e07a53c8d4dd82acd6d50f2c32d82,0x1058ff5c5db1dfdddfce975190cf63db221cb0d0156609ad579742b53c3643cd,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 10:37:57 [10:37:57.229] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:57 [10:37:57.443] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:57 [10:37:57.651] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:57 [10:37:57.901] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:57 [10:37:57.903] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x14e4e41e8b54a3ba90c5a1c3aa10f730bcee4207e0b0014a31d74c9742d1402e","blockNumber":2,"slot":3} 10:37:58 [10:37:58.336] INFO: pxe:service Proving completed in 2277.682198999999ms {"timings":{"total":2277.682198999999,"sync":8.479299000000537,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":276.7023909999989,"oracles":{"debugLog":{"times":[0.7140750000016851,0.5079609999993409,0.5250710000000254,0.7068949999993492,0.38336900000103924,0.3660280000003695,0.3625269999993179,0.5795020000005024]},"fetchTaggedLogs":{"times":[67.05089500000031]},"loadCapsule":{"times":[0.29044599999906495,0.4452190000010887,0.6137330000001384,0.7984770000002754,0.2997670000004291,1.0274509999999282]},"getSharedSecret":{"times":[4.267100000000937]},"aes128Decrypt":{"times":[1.3134380000010424,0.8506780000006984]},"getPublicKeysAndPartialAddress":{"times":[5.087816999999632]},"getKeyValidationRequest":{"times":[6.047676999998657]},"deliverNote":{"times":[15.853624999999738]},"deleteCapsule":{"times":[1.1432650000006106]},"storeCapsule":{"times":[3.8376010000010865]},"getNotes":{"times":[2.172606000000087]},"getAuthWitness":{"times":[0.2986170000003767]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06930099999954109]},"callPrivateFunction":{"times":[675.2223699999995,162.82247599999937]},"notifyEnqueuedPublicFunctionCall":{"times":[0.3161370000016177]}}},{"functionName":"private_kernel_init","time":69.34214400000019},{"functionName":"ContractClassRegisterer:register","time":661.2780550000025,"oracles":{"loadCapsule":{"times":[7.570550000000367]},"notifyCreatedNullifier":{"times":[0.6237229999987903]},"debugLog":{"times":[0.6947039999995468]},"notifyCreatedContractClassLog":{"times":[21.342900999999983]}}},{"functionName":"private_kernel_inner","time":117.44452900000033},{"functionName":"ContractInstanceDeployer:deploy","time":104.18466799999987,"oracles":{"storeInExecutionCache":{"times":[0.4557999999997264]},"callPrivateFunction":{"times":[58.62724699999853]},"notifyCreatedNullifier":{"times":[0.3824980000008509]},"debugLog":{"times":[0.6964239999997517]}}},{"functionName":"private_kernel_inner","time":156.97876299999916},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":45.13784299999861,"oracles":{}},{"functionName":"private_kernel_inner","time":120.37929099999747},{"functionName":"private_kernel_reset","time":206.49899799999912},{"functionName":"private_kernel_tail","time":89.24926400000186}],"unaccounted":422.0069540000004}} 10:37:58 [10:37:58.365] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:58 [10:37:58.550] VERBOSE: p2p:tx_pool Adding tx 0x18a47d57d0f848ac29639b364312fa8c92e3524fd560363dc6313425f206b78d to pool {"eventName":"tx-added-to-pool","txHash":"0x18a47d57d0f848ac29639b364312fa8c92e3524fd560363dc6313425f206b78d","noteHashCount":0,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":151,"proofSize":172052,"size":322921,"feePaymentMethod":"fee_juice"} 10:37:58 [10:37:58.579] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:58 [10:37:58.580] INFO: node Received tx 0x18a47d57d0f848ac29639b364312fa8c92e3524fd560363dc6313425f206b78d {"txHash":"0x18a47d57d0f848ac29639b364312fa8c92e3524fd560363dc6313425f206b78d"} 10:37:58 [10:37:58.580] INFO: pxe:service Sent transaction 0x18a47d57d0f848ac29639b364312fa8c92e3524fd560363dc6313425f206b78d 10:37:58 [10:37:58.601] VERBOSE: sequencer Building block 2 for slot 3 {"slot":3,"blockNumber":2,"msgCount":0} 10:37:58 [10:37:58.619] VERBOSE: sequencer Processing pending txs {"slot":3,"slotStart":"2025-06-24T10:42:48.000Z","now":"2025-06-24T10:42:50.845Z"} 10:37:58 [10:37:58.797] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:58 [10:37:58.959] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x35393df3 completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x35393df3","duration":98.42473799999789} 10:37:59 [10:37:59.178] VERBOSE: simulator:public-processor Processed tx 0x18a47d57d0f848ac29639b364312fa8c92e3524fd560363dc6313425f206b78d with 1 public calls in 442.72389300000214ms {"txHash":"0x18a47d57d0f848ac29639b364312fa8c92e3524fd560363dc6313425f206b78d","txFee":32675777440,"revertCode":0,"gasUsed":{"totalGas":{"daGas":91648,"l2Gas":98344},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":3072,"l2Gas":61800},"billedGas":{"daGas":91648,"l2Gas":98344}},"publicDataWriteCount":6,"nullifierCount":4,"noteHashCount":0,"contractClassLogCount":1,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":442.72389300000214} 10:37:59 [10:37:59.184] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:59 [10:37:59.188] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.5685428079999983s {"duration":0.5685428079999983,"rate":108698.93899000861,"totalPublicGas":{"daGas":3072,"l2Gas":61800},"totalBlockGas":{"daGas":91648,"l2Gas":98344},"totalSizeInBytes":6144} 10:37:59 [10:37:59.363] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 81062.09721426027 mana/s {"blockHash":"0x08c4c156297ea4a066b3a4dbf9ef2dfc3a80392d7592b4052e0e78ca0446fc28","globalVariables":{"chainId":31337,"version":1974575722,"blockNumber":2,"slotNumber":3,"timestamp":1750761768,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x18a47d57d0f848ac29639b364312fa8c92e3524fd560363dc6313425f206b78d"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":761.7662660000024,"publicProcessDuration":569.0950809999995,"rollupCircuitsDuration":745.1788759999981,"txCount":1,"blockNumber":2,"blockTimestamp":1750761768,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":151} 10:37:59 [10:37:59.365] VERBOSE: sequencer Attesting committee is empty 10:37:59 [10:37:59.450] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:59 [10:37:59.648] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x34d51010f75f6a83eea7f4568b0f5819b0ab04427aff54781eb9a324b48d8ff0 10:37:59 [10:37:59.648] VERBOSE: sequencer-client Sent L1 transaction 0x34d51010f75f6a83eea7f4568b0f5819b0ab04427aff54781eb9a324b48d8ff0 {"gasLimit":470217,"maxFeePerGas":"1.303611208","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:37:59 [10:37:59.661] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:59 [10:37:59.664] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000002"] on http://127.0.0.1:8545 10:37:59 [10:37:59.667] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 10:37:59 [10:37:59.670] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:37:59 [10:37:59.670] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x34d51010f75f6a83eea7f4568b0f5819b0ab04427aff54781eb9a324b48d8ff0","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":2,"blockTimestamp":1750761768,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":151} 10:37:59 [10:37:59.670] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:37:59 [10:37:59.673] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 10:37:59 [10:37:59.680] INFO: blob-sink:server Received blob sidecar for block 0x0c467dd52cc627f3c1d1eaf44aa7c7c06b262e674c601839806c30de61438c6e 10:37:59 [10:37:59.686] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750761804] on http://127.0.0.1:8545 10:37:59 [10:37:59.688] INFO: blob-sink:server Blob sidecar stored successfully for block 0x0c467dd52cc627f3c1d1eaf44aa7c7c06b262e674c601839806c30de61438c6e 10:37:59 [10:37:59.689] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:37:59 [10:37:59.691] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750761804 10:37:59 [10:37:59.691] WARN: foundation:test-date-provider Time set to 2025-06-24T10:43:24.000Z {"offset":324309,"timeMs":1750761804000} 10:37:59 [10:37:59.691] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 10:37:59 [10:37:59.858] VERBOSE: archiver:block-helper Store contract instance at 0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd 10:37:59 [10:37:59.865] VERBOSE: archiver:block-helper Store contract class 0x0fbfc518e8ee4971622de3f275570a7a200e07a53c8d4dd82acd6d50f2c32d82 10:37:59 [10:37:59.870] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:37:59 [10:37:59.881] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x08c4c156297ea4a066b3a4dbf9ef2dfc3a80392d7592b4052e0e78ca0446fc28","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":1974575722,"blockNumber":2,"slotNumber":3,"timestamp":1750761768,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x0333f1ab877ff064c9d98e9f89a528a36ab350bcae9a87d43fab9341013bacd2","archiveNextLeafIndex":3} 10:37:59 [10:37:59.885] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 10:37:59 [10:37:59.968] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":7.836315000000468,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750761768,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":151} 10:37:59 [10:37:59.996] VERBOSE: p2p Synched to latest block 2 10:38:00 [10:38:00.080] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:00 [10:38:00.192] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x0333f1ab877ff064c9d98e9f89a528a36ab350bcae9a87d43fab9341013bacd2","blockNumber":3,"slot":4} 10:38:00 [10:38:00.283] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:00 [10:38:00.487] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:00 [10:38:00.668] INFO: aztecjs:deploy_sent_tx Contract 0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd successfully deployed. 10:38:00 [10:38:00.669] INFO: e2e:e2e_private_voting_contract Counter contract deployed at 0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd 10:38:00 [10:38:00.672] INFO: e2e:e2e_private_voting_contract Running test: e2e_voting_contract votes votes, then tries to vote again 10:38:00 [10:38:00.690] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:00 [10:38:00.714] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x0333f1ab877ff064c9d98e9f89a528a36ab350bcae9a87d43fab9341013bacd2","header":{"contentCommitment":{"blobsHash":"0x008a02d8e0dbbf12fcc8ad82b58292ec5cea6fd81294c3a4f75782d9d270e839","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750761768,"version":1974575722},"lastArchive":"0x14e4e41e8b54a3ba90c5a1c3aa10f730bcee4207e0b0014a31d74c9742d1402e","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x11f5aebf8a976392b746f11cc90f691d348f31f6fa9eb6ad436ed7897a36228f","nullifierTree":"0x1319c7065797bba5ba4c9e87f4f7a129141da89975256ad6bf461cb7dfa85f29","publicDataTree":"0x1d2ecb0d517278825967f1ef9b7939d0e93c580552d9d20a3f5f1d05a6964aa6"},"totalFees":32675777440,"totalManaUsed":98344}} 10:38:00 [10:38:00.721] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x0333f1ab877ff064c9d98e9f89a528a36ab350bcae9a87d43fab9341013bacd2","blockNumber":3,"slot":4} 10:38:00 [10:38:00.817] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:38:00 [10:38:00.872] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:38:00 [10:38:00.873] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:38:00 [10:38:00.901] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:00 [10:38:00.928] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:38:00 [10:38:00.946] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1a1e2a53d755a165e498590fd85b1cd7d7dfe272e3de81b17563aa269e544360 10:38:00 [10:38:00.960] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:38:00 [10:38:00.961] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:38:00 [10:38:00.969] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:38:00 [10:38:00.970] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:38:00 [10:38:00.974] VERBOSE: pxe:service Added note {"index":0,"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x261891464d85b916c1e62eec36f1edf9237e11cee19bb167a74fb838bc8fc05b","nullifier":"0x22784239a0d9e9ec0b27c81de76d48df36ddc1966ebca5f487637fd2d27d51e5"} 10:38:00 [10:38:00.976] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:38:00 [10:38:00.997] VERBOSE: simulator:client_execution_context:debug_log Setting 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 as fee payer 10:38:01 [10:38:01.032] VERBOSE: simulator:private_execution Executing private function EasyPrivateVoting:cast_vote {"contract":"0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd"} 10:38:01 [10:38:01.106] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:01 [10:38:01.316] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:01 [10:38:01.363] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x0333f1ab877ff064c9d98e9f89a528a36ab350bcae9a87d43fab9341013bacd2","blockNumber":3,"slot":4} 10:38:01 [10:38:01.877] INFO: pxe:service Proving completed in 1190.8455619999986ms {"timings":{"total":1190.8455619999986,"sync":33.617490000000544,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":276.4215649999969,"oracles":{"debugLog":{"times":[0.7644959999997809,0.5021909999995842,0.5519920000006096,0.6445740000017395,0.5899830000016664,0.5029610000019602,0.42001899999741,0.8372469999994792]},"fetchTaggedLogs":{"times":[71.07534000000305]},"loadCapsule":{"times":[0.27357499999925494,0.2236750000010943,0.505089999998745,0.23096500000247033,0.2438849999998638,0.2225639999996929]},"getSharedSecret":{"times":[4.496014999996987]},"aes128Decrypt":{"times":[0.3555469999992056,1.0708119999981136]},"getPublicKeysAndPartialAddress":{"times":[2.1945960000011837]},"getKeyValidationRequest":{"times":[2.232126999999309]},"deliverNote":{"times":[3.837821000000986]},"deleteCapsule":{"times":[0.33165699999881326]},"storeCapsule":{"times":[0.2809559999986959]},"getNotes":{"times":[4.417003000002296]},"getAuthWitness":{"times":[0.2631150000015623]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.13371200000256067]},"callPrivateFunction":{"times":[60.73166100000162]}}},{"functionName":"private_kernel_init","time":86.89016400000037},{"functionName":"EasyPrivateVoting:cast_vote","time":47.01596200000131,"oracles":{"getPublicKeysAndPartialAddress":{"times":[5.237940000002709]},"getKeyValidationRequest":{"times":[1.8356889999995474]},"notifyCreatedNullifier":{"times":[0.24477499999920838]},"storeInExecutionCache":{"times":[0.05856100000164588]},"notifyEnqueuedPublicFunctionCall":{"times":[0.08229100000244216]}}},{"functionName":"private_kernel_inner","time":163.02995100000044},{"functionName":"private_kernel_reset","time":249.73798100000204},{"functionName":"private_kernel_tail","time":95.83116299999892}],"unaccounted":238.30128599999807}} 10:38:01 [10:38:01.906] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:01 [10:38:01.955] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x0333f1ab877ff064c9d98e9f89a528a36ab350bcae9a87d43fab9341013bacd2","blockNumber":3,"slot":4} 10:38:01 [10:38:01.960] VERBOSE: p2p:tx_pool Adding tx 0x27a611dbb88681ad78a042770c47e49a70de50b264b1321811fa4a1d594a5bd6 to pool {"eventName":"tx-added-to-pool","txHash":"0x27a611dbb88681ad78a042770c47e49a70de50b264b1321811fa4a1d594a5bd6","noteHashCount":0,"nullifierCount":2,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226313,"feePaymentMethod":"fee_juice"} 10:38:01 [10:38:01.967] INFO: node Received tx 0x27a611dbb88681ad78a042770c47e49a70de50b264b1321811fa4a1d594a5bd6 {"txHash":"0x27a611dbb88681ad78a042770c47e49a70de50b264b1321811fa4a1d594a5bd6"} 10:38:01 [10:38:01.967] INFO: pxe:service Sent transaction 0x27a611dbb88681ad78a042770c47e49a70de50b264b1321811fa4a1d594a5bd6 10:38:02 [10:38:02.114] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:02 [10:38:02.319] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:02 [10:38:02.499] VERBOSE: sequencer Building block 3 for slot 4 {"slot":4,"blockNumber":3,"msgCount":0} 10:38:02 [10:38:02.517] VERBOSE: sequencer Processing pending txs {"slot":4,"slotStart":"2025-06-24T10:43:24.000Z","now":"2025-06-24T10:43:26.826Z"} 10:38:02 [10:38:02.525] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:02 [10:38:02.578] VERBOSE: simulator:public_tx_simulator Simulation of enqueued public call 0x5f06617e completed successfully. {"eventName":"avm-simulation","appCircuitName":"0x5f06617e","duration":34.834945000002335} 10:38:02 [10:38:02.596] VERBOSE: simulator:public-processor Processed tx 0x27a611dbb88681ad78a042770c47e49a70de50b264b1321811fa4a1d594a5bd6 with 1 public calls in 62.25354399999924ms {"txHash":"0x27a611dbb88681ad78a042770c47e49a70de50b264b1321811fa4a1d594a5bd6","txFee":18258351520,"revertCode":0,"gasUsed":{"totalGas":{"daGas":2048,"l2Gas":54952},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":512,"l2Gas":23752},"billedGas":{"daGas":2048,"l2Gas":54952}},"publicDataWriteCount":2,"nullifierCount":2,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":62.25354399999924} 10:38:02 [10:38:02.597] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.07924980300000244s {"duration":0.07924980300000244,"rate":299710.5242015462,"totalPublicGas":{"daGas":512,"l2Gas":23752},"totalBlockGas":{"daGas":2048,"l2Gas":54952},"totalSizeInBytes":352} 10:38:02 [10:38:02.704] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 115788.94878429783 mana/s {"blockHash":"0x1fe8f2e864022bbb92950b21a4c59c00085b2ddc3c32a30202885cbcb8f75a1e","globalVariables":{"chainId":31337,"version":1974575722,"blockNumber":3,"slotNumber":4,"timestamp":1750761804,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x27a611dbb88681ad78a042770c47e49a70de50b264b1321811fa4a1d594a5bd6"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":204.42842400000154,"publicProcessDuration":79.53564900000129,"rollupCircuitsDuration":187.92930699999852,"txCount":1,"blockNumber":3,"blockTimestamp":1750761804,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:38:02 [10:38:02.706] VERBOSE: sequencer Attesting committee is empty 10:38:02 [10:38:02.782] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:02 [10:38:02.962] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x26e573b6912629a4a29849a7ce57e0a92df8683ef99a1720db2f9bf4877d4a5f 10:38:02 [10:38:02.962] VERBOSE: sequencer-client Sent L1 transaction 0x26e573b6912629a4a29849a7ce57e0a92df8683ef99a1720db2f9bf4877d4a5f {"gasLimit":470298,"maxFeePerGas":"1.279583398","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 10:38:02 [10:38:02.973] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 10:38:02 [10:38:02.974] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x26e573b6912629a4a29849a7ce57e0a92df8683ef99a1720db2f9bf4877d4a5f","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":3,"blockTimestamp":1750761804,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:38:02 [10:38:02.974] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 10:38:02 [10:38:02.982] INFO: blob-sink:server Received blob sidecar for block 0x39add48034dd4de4c9720fff891a85acca7bfc7c6cb5b950d7fb3c2621771813 10:38:02 [10:38:02.988] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:02 [10:38:02.990] INFO: blob-sink:server Blob sidecar stored successfully for block 0x39add48034dd4de4c9720fff891a85acca7bfc7c6cb5b950d7fb3c2621771813 10:38:02 [10:38:02.999] INFO: ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on http://127.0.0.1:8545 10:38:03 [10:38:03.002] WARN: ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 10:38:03 [10:38:03.006] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 10:38:03 [10:38:03.013] INFO: ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750761840] on http://127.0.0.1:8545 10:38:03 [10:38:03.015] INFO: ethereum:cheat_codes Calling hardhat_mine with params: [1] on http://127.0.0.1:8545 10:38:03 [10:38:03.017] WARN: ethereum:cheat_codes Warped L1 timestamp to 1750761840 10:38:03 [10:38:03.017] WARN: foundation:test-date-provider Time set to 2025-06-24T10:44:00.000Z {"offset":356983,"timeMs":1750761840000} 10:38:03 [10:38:03.018] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 10:38:03 [10:38:03.142] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x1fe8f2e864022bbb92950b21a4c59c00085b2ddc3c32a30202885cbcb8f75a1e","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":1974575722,"blockNumber":3,"slotNumber":4,"timestamp":1750761804,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x2e8ce61887b89a4973478670d9b2d8fc568640518a3ea0002f02371914db0de0","archiveNextLeafIndex":4} 10:38:03 [10:38:03.144] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 10:38:03 [10:38:03.196] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:03 [10:38:03.255] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":8.754585000002407,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750761804,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 10:38:03 [10:38:03.260] VERBOSE: p2p Synched to latest block 3 10:38:03 [10:38:03.400] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:03 [10:38:03.497] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2e8ce61887b89a4973478670d9b2d8fc568640518a3ea0002f02371914db0de0","blockNumber":4,"slot":5} 10:38:03 [10:38:03.603] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:03 [10:38:03.806] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:03 [10:38:03.988] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x2e8ce61887b89a4973478670d9b2d8fc568640518a3ea0002f02371914db0de0","header":{"contentCommitment":{"blobsHash":"0x00afd12540d73143b8bd6b64993de6ca607e5fa41964cd9402ddc20351dad3ce","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750761804,"version":1974575722},"lastArchive":"0x0333f1ab877ff064c9d98e9f89a528a36ab350bcae9a87d43fab9341013bacd2","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x11f5aebf8a976392b746f11cc90f691d348f31f6fa9eb6ad436ed7897a36228f","nullifierTree":"0x0a4a673da360befea44d2beb4cc5da0d302bc3485db5c46da04d67d283b5df78","publicDataTree":"0x0f0e4f86783a0607f0f495418745e3a929f36147c5798d84259743530db5d457"},"totalFees":18258351520,"totalManaUsed":54952}} 10:38:04 [10:38:04.015] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:04 [10:38:04.042] VERBOSE: simulator Executing utility function get_vote {"contract":"0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd","selector":"0x50ac913f"} 10:38:04 [10:38:04.049] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2e8ce61887b89a4973478670d9b2d8fc568640518a3ea0002f02371914db0de0","blockNumber":4,"slot":5} 10:38:04 [10:38:04.051] VERBOSE: simulator Utility simulation for 0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd.0x50ac913f completed 10:38:04 [10:38:04.076] INFO: pxe:service Simulating transaction execution request to 0x27e740b2 at 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 {"origin":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4","functionSelector":"0x27e740b2","simulatePublic":true,"chainId":"0x0000000000000000000000000000000000000000000000000000000000007a69","version":"0x0000000000000000000000000000000000000000000000000000000075b1a26a","authWitnesses":["0x1df2b4454958addf4facaffdfa5dfbedb81573c13e434384a89fc2fd1f14370c"]} 10:38:04 [10:38:04.174] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:38:04 [10:38:04.216] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:38:04 [10:38:04.216] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:38:04 [10:38:04.223] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:04 [10:38:04.270] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:38:04 [10:38:04.275] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1a1e2a53d755a165e498590fd85b1cd7d7dfe272e3de81b17563aa269e544360 10:38:04 [10:38:04.284] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:38:04 [10:38:04.285] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:38:04 [10:38:04.297] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:38:04 [10:38:04.297] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:38:04 [10:38:04.300] VERBOSE: pxe:service Added note {"index":0,"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x261891464d85b916c1e62eec36f1edf9237e11cee19bb167a74fb838bc8fc05b","nullifier":"0x22784239a0d9e9ec0b27c81de76d48df36ddc1966ebca5f487637fd2d27d51e5"} 10:38:04 [10:38:04.302] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:38:04 [10:38:04.314] VERBOSE: simulator:client_execution_context:debug_log Setting 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 as fee payer 10:38:04 [10:38:04.335] VERBOSE: simulator:private_execution Executing private function EasyPrivateVoting:cast_vote {"contract":"0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd"} 10:38:04 [10:38:04.448] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:04 [10:38:04.608] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2e8ce61887b89a4973478670d9b2d8fc568640518a3ea0002f02371914db0de0","blockNumber":4,"slot":5} 10:38:04 [10:38:04.768] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:05 [10:38:05.086] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:05 [10:38:05.135] VERBOSE: node Simulating public calls for tx 0x043cbe2297c79b33bf749593f78ff62d68338e13c58c0862da01c6cc6de3b859 {"globalVariables":{"chainId":31337,"version":1974575722,"blockNumber":4,"slotNumber":5,"timestamp":1750761840,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":50700},"txHash":"0x043cbe2297c79b33bf749593f78ff62d68338e13c58c0862da01c6cc6de3b859","blockNumber":4} 10:38:05 [10:38:05.147] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2e8ce61887b89a4973478670d9b2d8fc568640518a3ea0002f02371914db0de0","blockNumber":4,"slot":5} 10:38:05 [10:38:05.155] VERBOSE: simulator:state_manager Siloed nullifier 0x12668b91fb2e09bcb5adfbf13a89f67ada767cdaf926a5e59e0cb0f75e496922 already present in tree! 10:38:05 [10:38:05.159] WARN: simulator:public_tx_context APP_LOGIC phase reverted! insertRevertiblesFromPrivate failed with reason: Nullifier collision encountered when inserting revertible nullifiers from private 10:38:05 Details: Siloed nullifier 0x12668b91fb2e09bcb5adfbf13a89f67ada767cdaf926a5e59e0cb0f75e496922 already exists in parent cache or host. 10:38:05 Error stack: NullifierCollisionError: Siloed nullifier 0x12668b91fb2e09bcb5adfbf13a89f67ada767cdaf926a5e59e0cb0f75e496922 already exists in parent cache or host. 10:38:05 at PublicPersistableStateManager.writeSiloedNullifier (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/state_manager/state_manager.js:190:23) 10:38:05 at TelemetryPublicTxSimulator.insertRevertiblesFromPrivate (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_tx_simulator/public_tx_simulator.js:263:17) 10:38:05 at TelemetryPublicTxSimulator.insertRevertiblesFromPrivate (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_tx_simulator/measured_public_tx_simulator.js:33:24) 10:38:05 at TelemetryPublicTxSimulator.simulate (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_tx_simulator/public_tx_simulator.js:54:29) 10:38:05 at TelemetryPublicTxSimulator.simulate (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_tx_simulator/measured_public_tx_simulator.js:14:25) 10:38:05 at PublicProcessor.processTxWithPublicCalls (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_processor/public_processor.js:321:91) 10:38:05 at PublicProcessor.processTxWithinDeadline (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_processor/public_processor.js:268:20) 10:38:05 at elapsed (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/timer/elapsed.js:8:20) 10:38:05 at PublicProcessor.processTx (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_processor/public_processor.js:221:53) 10:38:05 at PublicProcessor.process (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_processor/public_processor.js:159:53) 10:38:05 at AztecNodeService.simulatePublicCalls (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:718:66) 10:38:05 at PXEService.#simulatePublicCalls (/home/aztec-dev/aztec-packages/yarn-project/pxe/dest/pxe_service/pxe_service.js:241:28) 10:38:05 at /home/aztec-dev/aztec-packages/yarn-project/pxe/dest/pxe_service/pxe_service.js:580:36 10:38:05 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 10:38:05 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 10:38:05 [10:38:05.180] VERBOSE: simulator:public-processor Processed tx 0x043cbe2297c79b33bf749593f78ff62d68338e13c58c0862da01c6cc6de3b859 with 1 public calls in 33.6377599999978ms {"txHash":"0x043cbe2297c79b33bf749593f78ff62d68338e13c58c0862da01c6cc6de3b859","txFee":1581840000,"revertCode":1,"revertReason":{"originalMessage":"Nullifier collision encountered when inserting revertible nullifiers from private\nDetails: Siloed nullifier 0x12668b91fb2e09bcb5adfbf13a89f67ada767cdaf926a5e59e0cb0f75e496922 already exists in parent cache or host.\nError stack: NullifierCollisionError: Siloed nullifier 0x12668b91fb2e09bcb5adfbf13a89f67ada767cdaf926a5e59e0cb0f75e496922 already exists in parent cache or host.\n at PublicPersistableStateManager.writeSiloedNullifier (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/state_manager/state_manager.js:190:23)\n at TelemetryPublicTxSimulator.insertRevertiblesFromPrivate (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_tx_simulator/public_tx_simulator.js:263:17)\n at TelemetryPublicTxSimulator.insertRevertiblesFromPrivate (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_tx_simulator/measured_public_tx_simulator.js:33:24)\n at TelemetryPublicTxSimulator.simulate (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_tx_simulator/public_tx_simulator.js:54:29)\n at TelemetryPublicTxSimulator.simulate (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_tx_simulator/measured_public_tx_simulator.js:14:25)\n at PublicProcessor.processTxWithPublicCalls (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_processor/public_processor.js:321:91)\n at PublicProcessor.processTxWithinDeadline (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_processor/public_processor.js:268:20)\n at elapsed (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/timer/elapsed.js:8:20)\n at PublicProcessor.processTx (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_processor/public_processor.js:221:53)\n at PublicProcessor.process (/home/aztec-dev/aztec-packages/yarn-project/simulator/dest/public/public_processor/public_processor.js:159:53)\n at AztecNodeService.simulatePublicCalls (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:718:66)\n at PXEService.#simulatePublicCalls (/home/aztec-dev/aztec-packages/yarn-project/pxe/dest/pxe_service/pxe_service.js:241:28)\n at /home/aztec-dev/aztec-packages/yarn-project/pxe/dest/pxe_service/pxe_service.js:580:36\n at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33\n at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17)","functionErrorStack":[],"revertData":[]},"gasUsed":{"totalGas":{"daGas":1536,"l2Gas":31200},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0},"billedGas":{"daGas":1536,"l2Gas":31200}},"publicDataWriteCount":1,"nullifierCount":1,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":33.6377599999978} 10:38:05 [10:38:05.181] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.04134967300000062s {"duration":0.04134967300000062,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1536,"l2Gas":31200},"totalSizeInBytes":256} 10:38:05 [10:38:05.183] ERROR: pxe:service Failed to enrich public simulation error: TypeError: Cannot read properties of undefined (reading 'contractAddress') 10:38:05 [10:38:05.281] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:38:05 [10:38:05.319] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 10:38:05 [10:38:05.319] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:38:05 [10:38:05.320] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:05 [10:38:05.370] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4"} 10:38:05 [10:38:05.376] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x1a1e2a53d755a165e498590fd85b1cd7d7dfe272e3de81b17563aa269e544360 10:38:05 [10:38:05.385] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 10:38:05 [10:38:05.386] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 10:38:05 [10:38:05.392] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 10:38:05 [10:38:05.393] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 10:38:05 [10:38:05.396] VERBOSE: pxe:service Added note {"index":0,"contract":"0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x261891464d85b916c1e62eec36f1edf9237e11cee19bb167a74fb838bc8fc05b","nullifier":"0x22784239a0d9e9ec0b27c81de76d48df36ddc1966ebca5f487637fd2d27d51e5"} 10:38:05 [10:38:05.398] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 10:38:05 [10:38:05.416] VERBOSE: simulator:client_execution_context:debug_log Setting 0x2c1d991fee22b9114ac36d481c3c4dfc5470f63a9cca2611e3d9d9504a3e8de4 as fee payer 10:38:05 [10:38:05.446] VERBOSE: simulator:private_execution Executing private function EasyPrivateVoting:cast_vote {"contract":"0x052018fe959048e6c1c75e97e036a6261051df3356f84d890e7e18579f43dcbd"} 10:38:05 [10:38:05.522] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:05 [10:38:05.906] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:05 [10:38:05.911] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x2e8ce61887b89a4973478670d9b2d8fc568640518a3ea0002f02371914db0de0","blockNumber":4,"slot":5} 10:38:06 [10:38:06.196] INFO: pxe:service Proving completed in 1001.0402759999997ms {"timings":{"total":1001.0402759999997,"sync":7.718682999999146,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":215.53322800000024,"oracles":{"debugLog":{"times":[0.6155829999988782,0.3888279999991937,0.5016400000022259,0.4573899999995774,0.478770000001532,0.31410699999833014,0.33588699999745586,0.700635000001057]},"fetchTaggedLogs":{"times":[54.67216399999961]},"loadCapsule":{"times":[0.24387500000011642,0.18901399999958812,0.30676600000151666,0.20756400000027497,0.2024840000012773,0.1805129999993369]},"getSharedSecret":{"times":[2.8111190000017814]},"aes128Decrypt":{"times":[0.3602570000002743,0.7312249999995402]},"getPublicKeysAndPartialAddress":{"times":[1.7374969999982568]},"getKeyValidationRequest":{"times":[1.9170000000012806]},"deliverNote":{"times":[3.4903640000011364]},"deleteCapsule":{"times":[0.3362360000028275]},"storeCapsule":{"times":[0.2931259999968461]},"getNotes":{"times":[1.4477609999994456]},"getAuthWitness":{"times":[0.2250050000002375]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.062261999999464024]},"callPrivateFunction":{"times":[59.251609999999346]}}},{"functionName":"private_kernel_init","time":65.53103199999896},{"functionName":"EasyPrivateVoting:cast_vote","time":46.69534600000043,"oracles":{"getPublicKeysAndPartialAddress":{"times":[8.856347000000824]},"getKeyValidationRequest":{"times":[1.8829489999989164]},"notifyCreatedNullifier":{"times":[0.2676559999999881]},"storeInExecutionCache":{"times":[0.035200000002078013]},"notifyEnqueuedPublicFunctionCall":{"times":[0.05394200000228011]}}},{"functionName":"private_kernel_inner","time":163.9281090000004},{"functionName":"private_kernel_reset","time":197.38334499999837},{"functionName":"private_kernel_tail","time":91.52503199999774}],"unaccounted":212.72550100000444}} 10:38:06 [10:38:06.245] INFO: ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on http://127.0.0.1:8545 10:38:06 [10:38:06.271] VERBOSE: p2p:tx_validator:tx_double_spend Rejecting tx 0x26c2be2ccc2a7d6f712c7655d00fad30635d67098ce7be89b03febe3d9b31e41 for repeating a nullifier 10:38:06 [10:38:06.275] WARN: node Received invalid tx 0x26c2be2ccc2a7d6f712c7655d00fad30635d67098ce7be89b03febe3d9b31e41: Existing nullifier {"txHash":"0x26c2be2ccc2a7d6f712c7655d00fad30635d67098ce7be89b03febe3d9b31e41"} 10:38:06 [10:38:06.279] ERROR: pxe:service Error: Error: Invalid tx: Existing nullifier 10:38:06 at AztecNodeService.#sendTx (/home/aztec-dev/aztec-packages/yarn-project/aztec-node/dest/aztec-node/server.js:339:19) 10:38:06 at /home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/serial_queue.js:58:33 10:38:06 at FifoMemoryQueue.process (/home/aztec-dev/aztec-packages/yarn-project/foundation/dest/queue/base_memory_queue.js:110:17) 10:38:06 [10:38:06.285] INFO: node Stopping 10:38:06 [10:38:06.286] INFO: slasher Slasher client stopped. 10:38:06 [10:38:06.286] INFO: sequencer Stopped sequencer 10:38:06 [10:38:06.287] INFO: p2p P2P client stopped. 10:38:06 [10:38:06.290] INFO: world_state Stopped world state synchronizer 10:38:06 [10:38:06.290] INFO: archiver Stopped. 10:38:06 [10:38:06.290] INFO: node Stopped 10:38:06 [10:38:06.291] VERBOSE: e2e:e2e_private_voting_contract Cleaning up ACVM state 10:38:06 [10:38:06.291] INFO: e2e:e2e_private_voting_contract Cleaning up ACVM temp directory /tmp/81e9585c 10:38:06 [10:38:06.292] VERBOSE: e2e:e2e_private_voting_contract Cleaning up BB state 10:38:06 [10:38:06.303] INFO: blob-sink:server Stopping blob sink 10:38:06 [10:38:06.304] INFO: blob-sink:server Blob sink stopped 10:38:06 [10:38:06.304] VERBOSE: e2e:e2e_private_voting_contract Cleaning up data directory at /tmp/4143f614b8986beb 10:38:06 PASS src/e2e_private_voting_contract.test.ts 10:38:06 e2e_voting_contract 10:38:06 votes 10:38:06 ✓ votes, then tries to vote again (5611 ms) 10:38:06 10:38:06 Test Suites: 1 passed, 1 total 10:38:06 Tests: 1 passed, 1 total 10:38:06 Snapshots: 0 total 10:38:06 Time: 24.587 s 10:38:06 Ran all test suites matching /src\/e2e_private_voting_contract.test.ts/i. 10:38:06 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?