Parent Log:
http://ci.aztec-labs.com/90a1e84efe306bbd
Command: 97e7ef9a556fd7e6:ISOLATE=1:NAME=e2e_cheat_codes yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_cheat_codes.test.ts
Commit:
https://github.com/AztecProtocol/aztec-packages/commit/a229cf871638d3fd774f9272645cc1ac29b8196a
Env: REF_NAME=gh-readonly-queue/master/pr-14912-015cb0cadef6a41094c3649f32a735392e1b9693 CURRENT_VERSION=0.87.9 CI_FULL=1
Date: Wed Jun 18 15:59:45 UTC 2025
System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14912_amd64_x1-full
Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s
History:
http://ci.aztec-labs.com/list/history_39ce75ad687a474b_master
15:59:45 +++ id -u
15:59:45 +++ id -g
15:59:45 ++ docker run -d --name e2e_cheat_codes --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_cheat_codes.test.ts'\'''
15:59:45 + cid=7a4017cf28cb0ef74e0d16cc85028d9e24354a2d07a4ccb67f970a847347766d
15:59:45 + set +x
15:59:46 {"level":25,"time":1750262386325,"pid":11,"hostname":"7a4017cf28cb","module":"logger","msg":"Logger initialized with level verbose"}
15:59:52 [15:59:52.290]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes mine mine block
15:59:52 [15:59:52.530]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.553]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
15:59:52 [15:59:52.565]
WARN:
ethereum:cheat_codes Mined 1 L1 blocks
15:59:52 [15:59:52.565]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.578]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes mine mine 10 blocks
15:59:52 [15:59:52.670]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.674]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [10] on
http://127.0.0.1:8545
15:59:52 [15:59:52.678]
WARN:
ethereum:cheat_codes Mined 10 L1 blocks
15:59:52 [15:59:52.678]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.684]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes mine mine 42 blocks
15:59:52 [15:59:52.765]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.769]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [42] on
http://127.0.0.1:8545
15:59:52 [15:59:52.774]
WARN:
ethereum:cheat_codes Mined 42 L1 blocks
15:59:52 [15:59:52.774]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.782]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes mine mine 99 blocks
15:59:52 [15:59:52.874]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.878]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [99] on
http://127.0.0.1:8545
15:59:52 [15:59:52.884]
WARN:
ethereum:cheat_codes Mined 99 L1 blocks
15:59:52 [15:59:52.885]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.890]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes setNextBlockTimestamp by 100
15:59:52 [15:59:52.971]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.978]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:52 [15:59:52.980]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262492] on
http://127.0.0.1:8545
15:59:52 [15:59:52.982]
WARN:
ethereum:cheat_codes Set L1 next block timestamp to 1750262492
15:59:52 [15:59:52.982]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:52 [15:59:52.983]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
15:59:52 [15:59:52.985]
WARN:
ethereum:cheat_codes Mined 1 L1 blocks
15:59:52 [15:59:52.985]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:52 [15:59:52.986]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:52 [15:59:52.990]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes setNextBlockTimestamp by 42
15:59:53 [15:59:53.068]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:53 [15:59:53.072]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:53 [15:59:53.074]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262435] on
http://127.0.0.1:8545
15:59:53 [15:59:53.075]
WARN:
ethereum:cheat_codes Set L1 next block timestamp to 1750262435
15:59:53 [15:59:53.076]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:53 [15:59:53.077]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
15:59:53 [15:59:53.078]
WARN:
ethereum:cheat_codes Mined 1 L1 blocks
15:59:53 [15:59:53.078]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:53 [15:59:53.080]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:53 [15:59:53.084]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes setNextBlockTimestamp by 99
15:59:53 [15:59:53.160]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:53 [15:59:53.164]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:53 [15:59:53.166]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262492] on
http://127.0.0.1:8545
15:59:53 [15:59:53.167]
WARN:
ethereum:cheat_codes Set L1 next block timestamp to 1750262492
15:59:53 [15:59:53.167]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:53 [15:59:53.169]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
15:59:53 [15:59:53.170]
WARN:
ethereum:cheat_codes Mined 1 L1 blocks
15:59:53 [15:59:53.170]
INFO:
ethereum:cheat_codes Calling eth_blockNumber with params: [] on
http://127.0.0.1:8545
15:59:53 [15:59:53.172]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:53 [15:59:53.175]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes setNextBlockTimestamp to a past timestamp throws
15:59:53 [15:59:53.252]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:53 [15:59:53.255]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750261393] on
http://127.0.0.1:8545
15:59:53 [15:59:53.269]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes load a value at a particular storage slot
15:59:53 [15:59:53.380]
INFO:
ethereum:cheat_codes Calling eth_getStorageAt with params: ["0x0000000000000000000000000000000000000000","0x00","latest"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.389]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes store a value at a given slot and its keccak value of the slot (if it were in a map)
15:59:53 [15:59:53.488]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","0x01","0x0000000000000000000000000000000000000000000000000000000000000005"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.491]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 at 1 to 5
15:59:53 [15:59:53.492]
INFO:
ethereum:cheat_codes Calling eth_getStorageAt with params: ["0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","0x01","latest"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.495]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","0xada5013122d395ba3c54772283fb069b10426056ef8ca54750cb9bb552a59e7d","0x0000000000000000000000000000000000000000000000000000000000000005"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.496]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 at 78541660797044910968829902406342334108369226379826116161446442989268089806461 to 5
15:59:53 [15:59:53.497]
INFO:
ethereum:cheat_codes Calling eth_getStorageAt with params: ["0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","0xada5013122d395ba3c54772283fb069b10426056ef8ca54750cb9bb552a59e7d","latest"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.500]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes store a value at a given slot and its keccak value of the slot (if it were in a map)
15:59:53 [15:59:53.579]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","0xbc40fbf4394cd00f78fae9763b0c2c71b21ea442c42fdadc5b720537240ebac1","0x0000000000000000000000000000000000000000000000000000000000000005"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.584]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 at 85149632668394328379478047665368132835280330034293399760893490333839055043265 to 5
15:59:53 [15:59:53.584]
INFO:
ethereum:cheat_codes Calling eth_getStorageAt with params: ["0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","0xbc40fbf4394cd00f78fae9763b0c2c71b21ea442c42fdadc5b720537240ebac1","latest"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.586]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","0x8066494f08af53d6edb5c6df49048a3b4ae59e20df6d59c86bf8c8650304747e","0x0000000000000000000000000000000000000000000000000000000000000005"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.588]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 at 58076768977499219212177354345858258922703203414073725940090140522697466344574 to 5
15:59:53 [15:59:53.588]
INFO:
ethereum:cheat_codes Calling eth_getStorageAt with params: ["0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","0x8066494f08af53d6edb5c6df49048a3b4ae59e20df6d59c86bf8c8650304747e","latest"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.591]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes set bytecode correctly
15:59:53 [15:59:53.669]
INFO:
ethereum:cheat_codes Calling hardhat_setCode with params: ["0x70997970c51812dc3a010c7d01b50e0d17dc79c8","0x1234"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.672]
WARN:
ethereum:cheat_codes Set bytecode for 0x70997970c51812dc3a010c7d01b50e0d17dc79c8 to 0x1234
15:59:53 [15:59:53.672]
INFO:
ethereum:cheat_codes Calling eth_getCode with params: ["0x70997970c51812dc3a010c7d01b50e0d17dc79c8","latest"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.675]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L1 cheatcodes impersonate
15:59:53 [15:59:53.785]
INFO:
ethereum:cheat_codes Calling hardhat_impersonateAccount with params: ["0xe117944c4cf378894481c7676afcbfaf5ebbf3d7"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.786]
WARN:
ethereum:cheat_codes Impersonating 0xe117944c4cf378894481c7676afcbfaf5ebbf3d7
15:59:53 [15:59:53.793]
INFO:
ethereum:cheat_codes Calling hardhat_stopImpersonatingAccount with params: ["0xe117944c4cf378894481c7676afcbfaf5ebbf3d7"] on
http://127.0.0.1:8545
15:59:53 [15:59:53.794]
WARN:
ethereum:cheat_codes Stopped impersonating 0xe117944c4cf378894481c7676afcbfaf5ebbf3d7
15:59:53 [15:59:53.882]
INFO:
e2e:e2e_cheat_codes Logging metrics to log/e2e_cheat_codes.jsonl
15:59:54 [15:59:54.019]
INFO:
world-state:database Creating world state data store at directory /tmp/aztec-world-state-EpAzgF/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads.
15:59:54 [15:59:54.059]
INFO:
e2e:e2e_cheat_codes Calling anvil_setBlockTimestampInterval with params: [12]
15:59:54 [15:59:54.062]
WARN:
e2e:e2e_cheat_codes Set block interval to 12
15:59:54 [15:59:54.062]
VERBOSE:
e2e:e2e_cheat_codes Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266
15:59:54 [15:59:54.088]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x277e4793f6e2cd181f412eadc8be0fb7610674abaaf8a99d92d51aa23eb36c84
{"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"}
15:59:54 [15:59:54.096]
VERBOSE:
e2e:e2e_cheat_codes Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3
15:59:54 [15:59:54.118]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0xbbbc74469f1265d31106bb407903fbfedd04f24c46b16acf881c31ce633e2ccb
{"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"}
15:59:54 [15:59:54.124]
VERBOSE:
e2e:e2e_cheat_codes Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512
15:59:54 [15:59:54.139]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x994bc32fd6a609f011a10ac8bc6574d2fdfd26878b3b63e28e02f1494c2c9169
{"gasLimit":1014492,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"}
15:59:54 [15:59:54.147]
VERBOSE:
e2e:e2e_cheat_codes Deployed Registry at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0
15:59:54 [15:59:54.170]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x60ebac4bd4e7add1c487878a45aee7180d0271a7181adcda1fb8aca89f4c701c
{"gasLimit":767650,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"}
15:59:54 [15:59:54.175]
VERBOSE:
e2e:e2e_cheat_codes Deployed GovernanceProposer at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9
15:59:54 [15:59:54.198]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x1fb91a334e8f826b09df475377c78a68728a14cc881cb74236c9d8bc4340f75e
{"gasLimit":2851994,"maxFeePerGas":"3.584762973","maxPriorityFeePerGas":"2.48832"}
15:59:54 [15:59:54.207]
VERBOSE:
e2e:e2e_cheat_codes Deployed Governance at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9
15:59:54 [15:59:54.220]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0xd5c8cc40f7a77f0dce3c4e4734f239a2e090a9349db5ef6c8c8dadf318cc8e64
{"gasLimit":391705,"maxFeePerGas":"3.951216637","maxPriorityFeePerGas":"2.985984"}
15:59:54 [15:59:54.224]
VERBOSE:
e2e:e2e_cheat_codes Deployed CoinIssuer at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707
15:59:54 [15:59:54.226]
VERBOSE:
e2e:e2e_cheat_codes Waiting for deployments to complete
15:59:54 [15:59:54.226]
INFO:
e2e:e2e_cheat_codes Waiting for 6 transactions to be mined...
15:59:54 [15:59:54.243]
INFO:
e2e:e2e_cheat_codes All transactions mined successfully
15:59:54 [15:59:54.267]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8
{"gasLimit":56804,"maxFeePerGas":"4.446876289","maxPriorityFeePerGas":"3.5831808"}
15:59:54 [15:59:54.268]
VERBOSE:
e2e:e2e_cheat_codes Added coin issuer 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 as minter on fee asset in 0x947e11059041976e5e859daa9c835bfce901b80599fe54edbf5d419b9934e4a8
15:59:54 [15:59:54.279]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0xfda1306c79d34e7ae73d79bc52f140c9e61e3eacfd89cda21ed1a93f5bb66a63
{"gasLimit":56647,"maxFeePerGas":"5.057899916","maxPriorityFeePerGas":"4.29981696"}
15:59:54 [15:59:54.305]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x91b758c2f235d4663ef7939c8c84edb0be532e83a816d3b03efda2f5bd9b3abf
{"gasLimit":339691,"maxFeePerGas":"5.823401983","maxPriorityFeePerGas":"5.159780352"}
15:59:54 [15:59:54.314]
VERBOSE:
e2e:e2e_cheat_codes Deployed FeeAssetHandler at 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6
15:59:54 [15:59:54.342]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2
{"gasLimit":56804,"maxFeePerGas":"6.772666409","maxPriorityFeePerGas":"6.191736422"}
15:59:54 [15:59:54.342]
VERBOSE:
e2e:e2e_cheat_codes Added fee asset handler 0x2279b7a0a67db372996a5fab50d91eaa73d2ebe6 as minter on fee asset in 0x2097f635dea4f43e7c1e68fc38da10d242d42c5446d56e0f5b207d619a900fa2
15:59:54 [15:59:54.367]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x5d2a2997fcf062ce1a4301140243773d0dbf42f8ab0309a56838a672cda47af1
{"gasLimit":1115222,"maxFeePerGas":"7.93976784","maxPriorityFeePerGas":"7.430083706"}
15:59:54 [15:59:54.372]
VERBOSE:
e2e:e2e_cheat_codes Deployed StakingAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788
15:59:54 [15:59:54.384]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a
{"gasLimit":56804,"maxFeePerGas":"9.362275123","maxPriorityFeePerGas":"8.916100447"}
15:59:54 [15:59:54.385]
VERBOSE:
e2e:e2e_cheat_codes Added staking asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on staking asset in 0xf186a1655129667b8d1dee1def30ffc0151a4963115adb1019bdc9485dd5454a
15:59:54 [15:59:54.385]
VERBOSE:
e2e:e2e_cheat_codes Waiting for deployments to complete
15:59:54 [15:59:54.385]
INFO:
e2e:e2e_cheat_codes Waiting for 8 transactions to be mined...
15:59:54 [15:59:54.405]
INFO:
e2e:e2e_cheat_codes All transactions mined successfully
15:59:54 [15:59:54.416]
VERBOSE:
e2e:e2e_cheat_codes Deployed shared contracts
15:59:54 [15:59:54.443]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034
{"gasLimit":84837,"maxFeePerGas":"11.093178824","maxPriorityFeePerGas":"10.699320536"}
15:59:54 [15:59:54.444]
VERBOSE:
e2e:e2e_cheat_codes Funded reward distributor with 10000000000000000000000000 fee asset in 0xdd080c6c58752ae31c39d6d0f1ca1a681d9e82caa8bae1d7dec322e872ca3034
15:59:54 [15:59:54.453]
VERBOSE:
e2e:e2e_cheat_codes Rollup config args
{"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100}
15:59:54 [15:59:54.476]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0xf8c00080a63c2a550ff510ae9f17cb81c0369449b7596ae7ecee321c9d8dfa1c
{"gasLimit":86397,"maxFeePerGas":"13.183966014","maxPriorityFeePerGas":"12.839184643"}
15:59:54 [15:59:54.508]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0xe94c52fea20b31aa94745e2f80aab275814e7f1afb67fe06fd0910437db7a19a
{"gasLimit":4909800,"maxFeePerGas":"15.708908398","maxPriorityFeePerGas":"15.407021571"}
15:59:54 [15:59:54.514]
VERBOSE:
e2e:e2e_cheat_codes Awaiting for linked libraries to be deployed
15:59:54 [15:59:54.563]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x0ed4f56a5fbf975e275a27ad0b5dda98fea5231fcb1a760611d1ff0c127031b7
{"gasLimit":10407409,"maxFeePerGas":"18.752757986","maxPriorityFeePerGas":"18.488425885"}
15:59:54 [15:59:54.571]
VERBOSE:
e2e:e2e_cheat_codes Deployed Rollup at 0x0b306bf915c4d645ff596e518faf3f9669b97016
{"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"minimumStake":100000000000000000000,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100}
15:59:54 [15:59:54.571]
INFO:
e2e:e2e_cheat_codes Waiting for 9 transactions to be mined...
15:59:54 [15:59:54.592]
INFO:
e2e:e2e_cheat_codes All transactions mined successfully
15:59:54 [15:59:54.592]
VERBOSE:
e2e:e2e_cheat_codes All core contracts have been deployed
15:59:54 [15:59:54.621]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a
{"gasLimit":64317,"maxFeePerGas":"22.426414275","maxPriorityFeePerGas":"22.186111062"}
15:59:54 [15:59:54.621]
VERBOSE:
e2e:e2e_cheat_codes Funding fee juice portal with 10000000000000000000000 fee juice in 0xb50c2fdf6f888692e398188f4a5bc4b4790eacbc395d9bef962e6a188cb7cb7a (accelerated test deployments)
15:59:54 [15:59:54.649]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x3b438661f5338150c9883ff175549487ede9538bbe0ebd33b95928611e2884ab
{"gasLimit":716194,"maxFeePerGas":"26.850966182","maxPriorityFeePerGas":"26.623333274"}
15:59:54 [15:59:54.666]
VERBOSE:
e2e:e2e_cheat_codes Deployed SlashFactory at 0x9a9f2ccfde556a7e9ff0848998aa4a0cfd8863ae
15:59:54 [15:59:54.701]
VERBOSE:
e2e:e2e_cheat_codes Sent L1 transaction 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d
{"gasLimit":116953,"maxFeePerGas":"32.147280396","maxPriorityFeePerGas":"31.947999928"}
15:59:54 [15:59:54.702]
VERBOSE:
e2e:e2e_cheat_codes Adding rollup 0x0b306bf915c4d645ff596e518faf3f9669b97016 to registry 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x51a9e8244dbb479b288e781a8e685e0dd26d60c71a4efdd3f3ce617b2ea95e2d
15:59:54 [15:59:54.702]
INFO:
e2e:e2e_cheat_codes Waiting for 10 transactions to be mined...
15:59:54 [15:59:54.725]
INFO:
e2e:e2e_cheat_codes All transactions mined successfully
15:59:54 [15:59:54.731]
VERBOSE:
e2e:e2e_cheat_codes Rollup deployed
15:59:54 [15:59:54.732]
VERBOSE:
e2e:e2e_cheat_codes Waiting for rollup and slash factory to be deployed
15:59:54 [15:59:54.732]
INFO:
e2e:e2e_cheat_codes Waiting for 10 transactions to be mined...
15:59:54 [15:59:54.773]
INFO:
e2e:e2e_cheat_codes All transactions mined successfully
15:59:54 [15:59:54.774]
VERBOSE:
e2e:e2e_cheat_codes All transactions for L1 deployment have been mined
15:59:54 [15:59:54.797]
INFO:
e2e:e2e_cheat_codes 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"}
15:59:54 [15:59:54.800]
INFO:
ethereum:cheat_codes Calling anvil_getAutomine with params: [] on
http://127.0.0.1:8545
15:59:54 [15:59:54.802]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:54 [15:59:54.803]
INFO:
aztecjs:utils:watcher Watcher started for rollup at 0x0B306BF915C4d645ff596e518fAf3F9669b97016
15:59:54 [15:59:54.803]
INFO:
telemetry:client Using NoopTelemetryClient
15:59:54 [15:59:54.808]
INFO:
kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/4509bd8e575d55f6/blob-sink with map size 134217728 KB (LMDB v2)
15:59:54 [15:59:54.809]
WARN:
aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks
15:59:54 [15:59:54.809]
INFO:
ethereum:cheat_codes Calling evm_mine with params: [] on
http://127.0.0.1:8545
15:59:54 [15:59:54.811]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:54 [15:59:54.814]
INFO:
aztecjs:utils:watcher New L1 time: 1750262633
15:59:54 [15:59:54.814]
WARN:
foundation:test-date-provider Time set to 2025-06-18T16:03:53.000Z
{"offset":238186,"timeMs":1750262633000}
15:59:54 [15:59:54.816]
INFO:
kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16
15:59:54 [15:59:54.824]
INFO:
blob-sink:server Server is running on
http://localhost:36781
15:59:54 [15:59:54.824]
VERBOSE:
e2e:e2e_cheat_codes Creating and synching an aztec node...
15:59:54 [15:59:54.825]
VERBOSE:
e2e:e2e_cheat_codes Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/e3efcd9e/acvm
15:59:54 [15:59:54.826]
INFO:
node:blob-sink:client Creating HTTP blob sink client.
{"blobSinkUrl":"http://localhost:36781"}
15:59:54 [15:59:54.866]
VERBOSE:
node Snapshot sync is disabled. No snapshots URL provided.
15:59:54 [15:59:54.867]
INFO:
archiver:lmdb Creating archiver data store at directory /tmp/4509bd8e575d55f6/archiver with map size 134217728 KB (LMDB v2)
15:59:54 [15:59:54.868]
INFO:
archiver:lmdb Starting data store with maxReaders 16
15:59:55 [15:59:55.081]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:55 [15:59:55.174]
INFO:
node:blob-sink:client Testing configured blob sources
{"blobSinkUrl":"http://localhost:36781"}
15:59:55 [15:59:55.190]
INFO:
node:blob-sink:client Blob sink is reachable
{"blobSinkUrl":"http://localhost:36781"}
15:59:55 [15:59:55.191]
WARN:
node:blob-sink:client No L1 consensus host urls configured
15:59:55 [15:59:55.191]
WARN:
node:blob-sink:client No archive client configured
15:59:55 [15:59:55.194]
INFO:
archiver Starting archiver sync to rollup contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 from L1 block 16 to current L1 block 20 with hash 0x554e2ebcc821f66b3b805a589afac0390533836ad1c2bebaba334549290e9be0
{"blocksSynchedTo":16,"messagesSynchedTo":{"l1BlockHash":"0x010087673991ff41c873e5c6172d8383adee84ae13a2cee4393df202aa096d15","l1BlockNumber":16}}
15:59:55 [15:59:55.209]
INFO:
archiver Initial archiver sync to L1 block 20 complete.
{"l1BlockNumber":20,"syncPoint":{"blocksSynchedTo":20},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}}
15:59:55 [15:59:55.234]
INFO:
world-state:database Creating world state data store at directory /tmp/4509bd8e575d55f6/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads.
15:59:55 [15:59:55.266]
INFO:
world_state Created world state synchroniser with block history of 64
15:59:55 [15:59:55.266]
WARN:
node Aztec node is accepting fake proofs
15:59:55 [15:59:55.279]
INFO:
p2p:lmdb-v2 Creating p2p data store at directory /tmp/4509bd8e575d55f6/p2p with map size 134217728 KB (LMDB v2)
15:59:55 [15:59:55.281]
INFO:
p2p:lmdb-v2 Starting data store with maxReaders 16
15:59:55 [15:59:55.283]
INFO:
p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/4509bd8e575d55f6/p2p-archive with map size 134217728 KB (LMDB v2)
15:59:55 [15:59:55.286]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:55 [15:59:55.290]
INFO:
p2p-archive:lmdb-v2 Starting data store with maxReaders 16
15:59:55 [15:59:55.291]
INFO:
p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/4509bd8e575d55f6/p2p-peers with map size 134217728 KB (LMDB v2)
15:59:55 [15:59:55.292]
INFO:
p2p-peer:lmdb-v2 Starting data store with maxReaders 16
15:59:55 [15:59:55.294]
INFO:
p2p:tx_pool Setting maximum tx mempool size
{"maxTxPoolSize":100000000}
15:59:55 [15:59:55.295]
VERBOSE:
p2p P2P is disabled. Using dummy P2P service
15:59:55 [15:59:55.298]
VERBOSE:
world-state:block_stream Starting L2 block stream
{"proven":false,"pollIntervalMS":100}
15:59:55 [15:59:55.299]
INFO:
world_state Started world state synchronizer from block 1
15:59:55 [15:59:55.301]
VERBOSE:
world-state:block_stream Starting sync from block number 0
15:59:55 [15:59:55.302]
INFO:
p2p Starting p2p client from block 0 with empty mempools
15:59:55 [15:59:55.302]
VERBOSE:
p2p:l2-block-stream Starting L2 block stream
{"batchSize":20,"pollIntervalMS":100,"startingBlock":0}
15:59:55 [15:59:55.303]
INFO:
slasher Slasher client initialized
15:59:55 [15:59:55.304]
INFO:
slasher Starting Slasher client...
15:59:55 [15:59:55.334]
VERBOSE:
validator Initialized validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
15:59:55 [15:59:55.335]
VERBOSE:
node All Aztec Node subsystems synced
15:59:55 [15:59:55.344]
INFO:
sequencer-client Deploying forwarder contract
15:59:55 [15:59:55.351]
VERBOSE:
p2p:l2-block-stream Starting sync from block number 0
15:59:55 [15:59:55.366]
VERBOSE:
sequencer-client Sent L1 transaction 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918
{"gasLimit":436981,"maxFeePerGas":"1.353583777","maxPriorityFeePerGas":"1.2"}
15:59:55 [15:59:55.366]
VERBOSE:
sequencer-client Deployed contract with salt 0x000000000000000000000000f39Fd6e51aad88F6F4ce6aB8827279cffFb92266 to address 0x5075Ec8e7b5B34c80CE1041bd7988a537d858E4A in tx 0xa852e9420ee3d2b2162627e15a554cf55020e4cf63a143914c2fe05d25120918.
15:59:55 [15:59:55.371]
INFO:
sequencer-client Forwarder contract deployed at 0x5075ec8e7b5b34c80ce1041bd7988a537d858e4a with owner 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266
15:59:55 [15:59:55.387]
INFO:
validator Started validator with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
15:59:55 [15:59:55.388]
VERBOSE:
validator Validator 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 not on the validator committee for epoch 0
15:59:55 [15:59:55.388]
INFO:
sequencer Sequencer config set
{"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/e3efcd9e/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false}
15:59:55 [15:59:55.586]
VERBOSE:
sequencer Sequencer timetable updated
{"enforceTimeTable":false}
15:59:55 [15:59:55.589]
INFO:
sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266
15:59:55 [15:59:55.591]
INFO:
node Aztec Node version: 0.87.9
15:59:55 [15:59:55.591]
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"}
15:59:55 [15:59:55.593]
VERBOSE:
e2e:e2e_cheat_codes Creating a pxe...
15:59:55 [15:59:55.598]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:55 [15:59:55.605]
INFO:
pxe:data:lmdb Creating pxe_data data store at directory /tmp/b3b7549b5eaaeb26/pxe_data with map size 134217728 KB (LMDB v2)
15:59:55 [15:59:55.616]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x214bc88ac01262bc2fbf8eb2cd2d94569e08aee2c4eacf0bbb573d42ea5461c6","blockNumber":1,"slot":2}
15:59:55 [15:59:55.617]
INFO:
pxe:data:lmdb Starting data store with maxReaders 16
15:59:55 [15:59:55.828]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:55 [15:59:55.866]
VERBOSE:
pxe:service Registered protocol contracts in pxe
{"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"}
15:59:55 [15:59:55.867]
INFO:
pxe:service Started PXE connected to chain 31337 version 2521052666
15:59:55 [15:59:55.927]
INFO:
pxe:service Registered account 0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f
15:59:55 [15:59:55.998]
WARN:
pxe:service No artifact found for contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a when looking for its metadata
15:59:55 [15:59:55.999]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a as part of deployment for 0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f
15:59:56 [15:59:56.089]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:56 [15:59:56.135]
INFO:
pxe:service Added contract SchnorrAccount at 0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f with class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a
15:59:56 [15:59:56.182]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x214bc88ac01262bc2fbf8eb2cd2d94569e08aee2c4eacf0bbb573d42ea5461c6","blockNumber":1,"slot":2}
15:59:56 [15:59:56.215]
VERBOSE:
pxe:block_stream Starting sync from block number 0
15:59:56 [15:59:56.239]
VERBOSE:
simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"}
15:59:56 [15:59:56.349]
VERBOSE:
simulator:private_execution Executing private function ContractClassRegisterer:register
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"}
15:59:57 [15:59:57.045]
VERBOSE:
simulator:client_execution_context:debug_log ContractClassRegistered: 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a,0x09c9af7c05966ce886fc47ad78b12b911fae2aa99b888dc6175a3c214b956f0e,0x0c05463206ae0d0995c03525c6103b2ea543e6d7bbdbf5b3197928455d75a9e5,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c
15:59:57 [15:59:57.196]
VERBOSE:
simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..."
15:59:57 [15:59:57.281]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:57 [15:59:57.320]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x214bc88ac01262bc2fbf8eb2cd2d94569e08aee2c4eacf0bbb573d42ea5461c6","blockNumber":1,"slot":2}
15:59:57 [15:59:57.462]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:constructor
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
15:59:57 [15:59:57.503]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:57 [15:59:57.513]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:59:57 [15:59:57.515]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
15:59:57 [15:59:57.585]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
15:59:57 [15:59:57.592]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:59:57 [15:59:57.718]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:57 [15:59:57.764]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
15:59:57 [15:59:57.798]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
15:59:57 [15:59:57.798]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
15:59:57 [15:59:57.842]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x214bc88ac01262bc2fbf8eb2cd2d94569e08aee2c4eacf0bbb573d42ea5461c6","blockNumber":1,"slot":2}
15:59:57 [15:59:57.857]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
15:59:57 [15:59:57.862]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
15:59:57 [15:59:57.883]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f as fee payer
15:59:58 [15:59:58.080]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:58 [15:59:58.324]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:58 [15:59:58.447]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x214bc88ac01262bc2fbf8eb2cd2d94569e08aee2c4eacf0bbb573d42ea5461c6","blockNumber":1,"slot":2}
15:59:58 [15:59:58.572]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:59 [15:59:59.079]
INFO:
pxe:service Proving completed in 2866.2706480000015ms
{"timings":{"total":2866.2706480000015,"sync":3.287852000001294,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":178.22244600000158,"oracles":{"callPrivateFunction":{"times":[981.9247730000006,411.9052700000011,210.22137999999904]}}},{"functionName":"private_kernel_init","time":113.2093420000001},{"functionName":"ContractClassRegisterer:register","time":954.3255719999997,"oracles":{"loadCapsule":{"times":[18.011536999998498]},"notifyCreatedNullifier":{"times":[14.880497000000105]},"debugLog":{"times":[1.0741550000002462]},"notifyCreatedContractClassLog":{"times":[13.055260999999518]}}},{"functionName":"private_kernel_inner","time":127.33491800000047},{"functionName":"SchnorrAccount:constructor","time":365.18468199999916,"oracles":{"getContractInstance":{"times":[4.210896000000503]},"debugLog":{"times":[0.974528000000646,0.7355180000013206]},"fetchTaggedLogs":{"times":[74.14348499999869]},"loadCapsule":{"times":[0.8138350000008359,1.4334139999991748,0.2939430000005814]},"notifyCreatedNullifier":{"times":[0.2908029999998689,0.27629200000046694]},"notifyCreatedNote":{"times":[0.5055809999994381]},"getRandomField":{"times":[0.658623000001171,0.26505100000031234,0.09722700000020268,0.0736959999994724,0.042312999999921885,0.05105399999956717,0.07553499999994528,0.13339099999939208,0.07012600000052771,0.09103699999832315,0.07800599999973201]},"getIndexedTaggingSecretAsSender":{"times":[34.44397800000115]},"incrementAppTaggingSecretIndexAsSender":{"times":[27.58907100000033]}}},{"functionName":"private_kernel_inner","time":115.2606250000008},{"functionName":"SchnorrAccount:entrypoint","time":183.90059099999962,"oracles":{"debugLog":{"times":[0.6777940000010858,0.5880570000008447,0.7502399999993941]},"fetchTaggedLogs":{"times":[62.06036099999983]},"loadCapsule":{"times":[0.3125550000004296,0.2891729999992094,0.2459499999986292]},"getNotes":{"times":[2.108888000000661]},"getAuthWitness":{"times":[0.4775079999999434]},"notifySetMinRevertibleSideEffectCounter":{"times":[1.0070300000006682]}}},{"functionName":"private_kernel_inner","time":114.59819199999947},{"functionName":"private_kernel_reset","time":196.94292099999984},{"functionName":"private_kernel_tail","time":92.63859000000048}],"unaccounted":421.36491699999897}}
15:59:59 [15:59:59.103]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:59 [15:59:59.197]
VERBOSE:
sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1)
{"chainTipArchive":"0x214bc88ac01262bc2fbf8eb2cd2d94569e08aee2c4eacf0bbb573d42ea5461c6","blockNumber":1,"slot":2}
15:59:59 [15:59:59.261]
VERBOSE:
p2p:tx_pool Adding tx 0x21be82b2da49dbf597af18b33ca8f62d2bc565b87f39b9e7ba788dd3f95088ee to pool
{"eventName":"tx-added-to-pool","txHash":"0x21be82b2da49dbf597af18b33ca8f62d2bc565b87f39b9e7ba788dd3f95088ee","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":293088,"feePaymentMethod":"fee_juice"}
15:59:59 [15:59:59.279]
INFO:
node Received tx 0x21be82b2da49dbf597af18b33ca8f62d2bc565b87f39b9e7ba788dd3f95088ee
{"txHash":"0x21be82b2da49dbf597af18b33ca8f62d2bc565b87f39b9e7ba788dd3f95088ee"}
15:59:59 [15:59:59.279]
INFO:
pxe:service Sent transaction 0x21be82b2da49dbf597af18b33ca8f62d2bc565b87f39b9e7ba788dd3f95088ee
15:59:59 [15:59:59.313]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:59 [15:59:59.517]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:59 [15:59:59.718]
VERBOSE:
sequencer Building block 1 for slot 2
{"slot":2,"blockNumber":1,"msgCount":0}
15:59:59 [15:59:59.720]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:59 [15:59:59.733]
VERBOSE:
sequencer Processing pending txs
{"slot":2,"slotStart":"2025-06-18T16:04:17.000Z","now":"2025-06-18T16:03:57.919Z"}
15:59:59 [15:59:59.970]
VERBOSE:
simulator:public-processor Processed tx 0x21be82b2da49dbf597af18b33ca8f62d2bc565b87f39b9e7ba788dd3f95088ee with no public calls in 173.47779000000082ms
{"txHash":"0x21be82b2da49dbf597af18b33ca8f62d2bc565b87f39b9e7ba788dd3f95088ee","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":173.47779000000082}
15:59:59 [15:59:59.972]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
15:59:59 [15:59:59.975]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.23991344899999967s
{"duration":0.23991344899999967,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":20448},"totalSizeInBytes":1344}
16:00:00 [16:00:00.099]
INFO:
sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s
{"blockHash":"0x24119fffb557dfcd983723511db4287805749846f9a0cffbdabe97e16df31d0e","globalVariables":{"chainId":31337,"version":2521052666,"blockNumber":1,"slotNumber":2,"timestamp":1750262657,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x21be82b2da49dbf597af18b33ca8f62d2bc565b87f39b9e7ba788dd3f95088ee"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":382.44854000000123,"publicProcessDuration":241.08064299999933,"rollupCircuitsDuration":367.42299099999946,"txCount":1,"blockNumber":1,"blockTimestamp":1750262657,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
16:00:00 [16:00:00.107]
VERBOSE:
sequencer Attesting committee is empty
16:00:00 [16:00:00.214]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:00 [16:00:00.419]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:00 [16:00:00.549]
VERBOSE:
ethereum:tx_delayer Sent tx immediately 0xb0610af433b33e08a0a7562ce8d9409ffce56bb1ff4c00d7c2cb61de04654f86
16:00:00 [16:00:00.549]
VERBOSE:
sequencer-client Sent L1 transaction 0xb0610af433b33e08a0a7562ce8d9409ffce56bb1ff4c00d7c2cb61de04654f86
{"gasLimit":514536,"maxFeePerGas":"1.574385805","maxPriorityFeePerGas":"1.44","maxFeePerBlobGas":"0.000000001"}
16:00:00 [16:00:00.563]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
16:00:00 [16:00:00.564]
VERBOSE:
sequencer:publisher Published L2 block to L1 rollup contract
{"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xb0610af433b33e08a0a7562ce8d9409ffce56bb1ff4c00d7c2cb61de04654f86","calldataSize":1444,"calldataGas":12280,"txCount":1,"blockNumber":1,"blockTimestamp":1750262657,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
16:00:00 [16:00:00.564]
VERBOSE:
node:blob-sink:client Sending 1 blobs to blob sink
16:00:00 [16:00:00.583]
INFO:
blob-sink:server Received blob sidecar for block 0xae4a554bd325fb48800cfda18b8bd78ef5533a04d4a6433f59821915197fdc17
16:00:00 [16:00:00.589]
INFO:
blob-sink:server Blob sidecar stored successfully for block 0xae4a554bd325fb48800cfda18b8bd78ef5533a04d4a6433f59821915197fdc17
16:00:00 [16:00:00.621]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:00 [16:00:00.742]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000001"] on
http://127.0.0.1:8545
16:00:00 [16:00:00.744]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1
16:00:00 [16:00:00.747]
INFO:
aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1.
16:00:00 [16:00:00.786]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262693] on
http://127.0.0.1:8545
16:00:00 [16:00:00.791]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
16:00:00 [16:00:00.888]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:00 [16:00:00.890]
WARN:
aztecjs:utils:watcher Wall time is more than 24 seconds away from L1 time, mining a block and syncing clocks
16:00:00 [16:00:00.891]
INFO:
ethereum:cheat_codes Calling evm_mine with params: [] on
http://127.0.0.1:8545
16:00:00 [16:00:00.892]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1750262693
16:00:00 [16:00:00.892]
WARN:
foundation:test-date-provider Time set to 2025-06-18T16:04:53.000Z
{"offset":292108,"timeMs":1750262693000}
16:00:00 [16:00:00.893]
INFO:
aztecjs:utils:watcher Slot 2 was filled, jumped to next slot
16:00:00 [16:00:00.904]
VERBOSE:
archiver:block-helper Store contract class 0x0729afe6a2795df3ffa50d1ebbb6f439dc372d317f0aa72e2df96bb66218286a
16:00:00 [16:00:00.906]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:00 [16:00:00.916]
INFO:
aztecjs:utils:watcher New L1 time: 1750262705
16:00:00 [16:00:00.917]
WARN:
foundation:test-date-provider Time set to 2025-06-18T16:05:05.000Z
{"offset":304083,"timeMs":1750262705000}
16:00:00 [16:00:00.918]
INFO:
archiver Downloaded L2 block 1
{"blockHash":"0x24119fffb557dfcd983723511db4287805749846f9a0cffbdabe97e16df31d0e","blockNumber":1,"txCount":1,"globalVariables":{"chainId":31337,"version":2521052666,"blockNumber":1,"slotNumber":2,"timestamp":1750262657,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x240bab94dfc03172707d0837ac8a9290afd03766a4676e4ab3f7873097bc1761","archiveNextLeafIndex":2}
16:00:00 [16:00:00.924]
INFO:
archiver Updated proven chain to block 1
{"provenBlockNumber":1}
16:00:01 [16:00:01.482]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:01 [16:00:01.485]
INFO:
world_state World state updated with L2 block 1
{"eventName":"l2-block-handled","duration":478.91170500000044,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1750262657,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7}
16:00:01 [16:00:01.486]
WARN:
pxe:service No artifact found for contract class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb when looking for its metadata
16:00:01 [16:00:01.492]
INFO:
aztecjs:contract_interaction Creating request for registering contract class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb as part of deployment for 0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2
16:00:01 [16:00:01.716]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:01 [16:00:01.811]
VERBOSE:
p2p Synched to latest block 1
16:00:02 [16:00:02.136]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:02 [16:00:02.152]
INFO:
pxe:service Added contract Token at 0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2 with class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb
16:00:02 [16:00:02.177]
VERBOSE:
pxe:service Updated pxe last block to 1
{"blockHash":{},"archive":"0x240bab94dfc03172707d0837ac8a9290afd03766a4676e4ab3f7873097bc1761","header":{"contentCommitment":{"blobsHash":"0x00c1c9ff4acfbfb24f174be031fe0e6a67cbe8432bcf0272d294e23d41e5357a","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1750262657,"version":2521052666},"lastArchive":"0x214bc88ac01262bc2fbf8eb2cd2d94569e08aee2c4eacf0bbb573d42ea5461c6","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2eac6026472a59b7f7f3a0b74de768de08de3c01abe62570e127787646e4041e","nullifierTree":"0x291b2e256b6c9d39275a6aab678623b450357c8b6f293315a34f1aafe1bc1971","publicDataTree":"0x1463283a7c145e8585a881d8da2ab1b17e3d6032569b1d1a0b4502b8af073e96"},"totalFees":6794052480,"totalManaUsed":20448}}
16:00:02 [16:00:02.319]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
16:00:02 [16:00:02.370]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
16:00:02 [16:00:02.370]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
16:00:02 [16:00:02.371]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:02 [16:00:02.460]
VERBOSE:
sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1)
{"chainTipArchive":"0x240bab94dfc03172707d0837ac8a9290afd03766a4676e4ab3f7873097bc1761","blockNumber":2,"slot":3}
16:00:02 [16:00:02.498]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
16:00:02 [16:00:02.505]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x0787256decb3a32e463e2253c3db08b4052f412a6c9dc2e60439f85f857edddd
16:00:02 [16:00:02.522]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
16:00:02 [16:00:02.523]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
16:00:02 [16:00:02.535]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
16:00:02 [16:00:02.535]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
16:00:02 [16:00:02.556]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x0a5d48c716734cbcbb01465988464467efee889338d6e96f7c7baa14cd5a170a","nullifier":"0x1a8910e201c4abeee875e4b841cedf60e93a19f1a9b975abe2489b1acf21f1d7"}
16:00:02 [16:00:02.560]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
16:00:02 [16:00:02.583]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f as fee payer
16:00:02 [16:00:02.585]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:02 [16:00:02.666]
VERBOSE:
simulator:private_execution Executing private function ContractClassRegisterer:register
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"}
16:00:03 [16:00:03.218]
VERBOSE:
simulator:client_execution_context:debug_log ContractClassRegistered: 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb,0x15aae2e0e2a7081ca06113c682f5373e67df2c3553eda599696574961c502fb4,0x0d6c93288fa1ed94413e630fd9c2f5ded8cd010325d6df84f5b9c8ac12bcac0c,0x146e9c4b5411f0c1e61cf995f05f86929ea24d4cd1b7e2f76bf817490af90f2f
16:00:03 [16:00:03.345]
VERBOSE:
simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..."
16:00:03 [16:00:03.418]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:03 [16:00:03.546]
VERBOSE:
simulator:private_execution Executing private function ContractInstanceDeployer:deploy
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"}
16:00:03 [16:00:03.566]
VERBOSE:
sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1)
{"chainTipArchive":"0x240bab94dfc03172707d0837ac8a9290afd03766a4676e4ab3f7873097bc1761","blockNumber":2,"slot":3}
16:00:03 [16:00:03.611]
VERBOSE:
simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered
{"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"}
16:00:03 [16:00:03.635]
VERBOSE:
simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2,0x0000000000000000000000000000000000000000000000000000000000000001,0x2ab00c099250ea98b786a4fee78672721704a0c9a2a8a02e6a362949435fbc3b,0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb,0x1ba7b70f03314b7be0740e078c32784328d3d294e58a7237633e74d07c1bd990,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f
16:00:03 [16:00:03.673]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:03 [16:00:03.964]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:04 [16:00:04.209]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:04 [16:00:04.348]
VERBOSE:
sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1)
{"chainTipArchive":"0x240bab94dfc03172707d0837ac8a9290afd03766a4676e4ab3f7873097bc1761","blockNumber":2,"slot":3}
16:00:04 [16:00:04.534]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:04 [16:00:04.830]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:05 [16:00:05.144]
INFO:
pxe:service Proving completed in 2975.2195089999987ms
{"timings":{"total":2975.2195089999987,"sync":10.692332999999053,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":435.13441200000307,"oracles":{"debugLog":{"times":[0.665962999999465,0.7558700000008685,0.6618330000019341,0.8672690000021248,0.6993149999980233,0.6503819999998086,0.5317319999994652,0.7274380000017118]},"fetchTaggedLogs":{"times":[130.65203300000212]},"loadCapsule":{"times":[0.5039200000028359,0.4319649999997637,0.779951999997138,0.6403410000020813,0.2983940000012808,4.198454999997921]},"getSharedSecret":{"times":[5.988797999998496]},"aes128Decrypt":{"times":[1.545813000000635,1.4936189999971248]},"getPublicKeysAndPartialAddress":{"times":[2.5562639999989187]},"getKeyValidationRequest":{"times":[4.151230999999825]},"deliverNote":{"times":[21.091292999997677]},"deleteCapsule":{"times":[0.6397610000021814]},"storeCapsule":{"times":[0.5950870000015129]},"getNotes":{"times":[3.2100360000004002]},"getAuthWitness":{"times":[0.35498899999947753]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.0613149999990128]},"callPrivateFunction":{"times":[827.9432889999989,234.4855860000025]},"notifyEnqueuedPublicFunctionCall":{"times":[0.4766380000000936]}}},{"functionName":"private_kernel_init","time":92.10039700000198},{"functionName":"ContractClassRegisterer:register","time":804.3063239999974,"oracles":{"loadCapsule":{"times":[3.335997000001953]},"notifyCreatedNullifier":{"times":[0.6959260000003269]},"debugLog":{"times":[0.7773019999985991]},"notifyCreatedContractClassLog":{"times":[6.302133000001049]}}},{"functionName":"private_kernel_inner","time":231.24145800000042},{"functionName":"ContractInstanceDeployer:deploy","time":139.48326799999631,"oracles":{"storeInExecutionCache":{"times":[0.2562900000011723]},"callPrivateFunction":{"times":[71.97595200000069]},"notifyCreatedNullifier":{"times":[0.35426899999947636]},"debugLog":{"times":[0.6961650000012014]}}},{"functionName":"private_kernel_inner","time":179.71352699999989},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":52.07943400000295,"oracles":{}},{"functionName":"private_kernel_inner","time":166.2179099999994},{"functionName":"private_kernel_reset","time":211.34361999999965},{"functionName":"private_kernel_tail","time":101.17878199999905}],"unaccounted":551.7280439999995}}
16:00:05 [16:00:05.448]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:05 [16:00:05.477]
VERBOSE:
sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1)
{"chainTipArchive":"0x240bab94dfc03172707d0837ac8a9290afd03766a4676e4ab3f7873097bc1761","blockNumber":2,"slot":3}
16:00:05 [16:00:05.480]
VERBOSE:
p2p:tx_pool Adding tx 0x2f3f14dff3ff85c02441f0ba1eb42df80d925fdb72222b40f98980a505637595 to pool
{"eventName":"tx-added-to-pool","txHash":"0x2f3f14dff3ff85c02441f0ba1eb42df80d925fdb72222b40f98980a505637595","noteHashCount":0,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":709,"proofSize":172052,"size":324937,"feePaymentMethod":"fee_juice"}
16:00:05 [16:00:05.506]
INFO:
node Received tx 0x2f3f14dff3ff85c02441f0ba1eb42df80d925fdb72222b40f98980a505637595
{"txHash":"0x2f3f14dff3ff85c02441f0ba1eb42df80d925fdb72222b40f98980a505637595"}
16:00:05 [16:00:05.506]
INFO:
pxe:service Sent transaction 0x2f3f14dff3ff85c02441f0ba1eb42df80d925fdb72222b40f98980a505637595
16:00:05 [16:00:05.659]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:05 [16:00:05.864]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:06 [16:00:06.036]
VERBOSE:
sequencer Building block 2 for slot 3
{"slot":3,"blockNumber":2,"msgCount":0}
16:00:06 [16:00:06.059]
VERBOSE:
sequencer Processing pending txs
{"slot":3,"slotStart":"2025-06-18T16:04:53.000Z","now":"2025-06-18T16:05:10.142Z"}
16:00:06 [16:00:06.076]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:06 [16:00:06.477]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:06 [16:00:06.661]
VERBOSE:
simulator:public_tx_simulator Simulation of enqueued public call 0x5e134abf completed successfully.
{"eventName":"avm-simulation","appCircuitName":"0x5e134abf","duration":281.99611599999844}
16:00:06 [16:00:06.851]
VERBOSE:
simulator:public-processor Processed tx 0x2f3f14dff3ff85c02441f0ba1eb42df80d925fdb72222b40f98980a505637595 with 1 public calls in 638.348103999997ms
{"txHash":"0x2f3f14dff3ff85c02441f0ba1eb42df80d925fdb72222b40f98980a505637595","txFee":139009277500,"revertCode":0,"gasUsed":{"totalGas":{"daGas":380416,"l2Gas":418375},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":6144,"l2Gas":381831},"billedGas":{"daGas":380416,"l2Gas":418375}},"publicDataWriteCount":12,"nullifierCount":4,"noteHashCount":0,"contractClassLogCount":1,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":638.348103999997}
16:00:06 [16:00:06.853]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:06 [16:00:06.856]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.7965662759999977s
{"duration":0.7965662759999977,"rate":479346.1780950429,"totalPublicGas":{"daGas":6144,"l2Gas":381831},"totalBlockGas":{"daGas":380416,"l2Gas":418375},"totalSizeInBytes":24384}
16:00:07 [16:00:07.053]
INFO:
sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 374883.9417023121 mana/s
{"blockHash":"0x0fe924319c939f794e296d3320c042741128319d08d3f1d50464c550d819d293","globalVariables":{"chainId":31337,"version":2521052666,"blockNumber":2,"slotNumber":3,"timestamp":1750262693,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x2f3f14dff3ff85c02441f0ba1eb42df80d925fdb72222b40f98980a505637595"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":1017.9215049999984,"publicProcessDuration":796.9048430000003,"rollupCircuitsDuration":994.3691359999975,"txCount":1,"blockNumber":2,"blockTimestamp":1750262693,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":709}
16:00:07 [16:00:07.055]
VERBOSE:
sequencer Attesting committee is empty
16:00:07 [16:00:07.160]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:07 [16:00:07.374]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:07 [16:00:07.379]
VERBOSE:
ethereum:tx_delayer Sent tx immediately 0x993284ee6b16e180ed2b2390a88b3be29fc0b5404807c7e0e6490c9fe5943c79
16:00:07 [16:00:07.380]
VERBOSE:
sequencer-client Sent L1 transaction 0x993284ee6b16e180ed2b2390a88b3be29fc0b5404807c7e0e6490c9fe5943c79
{"gasLimit":470280,"maxFeePerGas":"1.290659797","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"}
16:00:07 [16:00:07.397]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
16:00:07 [16:00:07.398]
VERBOSE:
sequencer:publisher Published L2 block to L1 rollup contract
{"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x993284ee6b16e180ed2b2390a88b3be29fc0b5404807c7e0e6490c9fe5943c79","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":2,"blockTimestamp":1750262693,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":709}
16:00:07 [16:00:07.398]
VERBOSE:
node:blob-sink:client Sending 1 blobs to blob sink
16:00:07 [16:00:07.402]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262729] on
http://127.0.0.1:8545
16:00:07 [16:00:07.404]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
16:00:07 [16:00:07.406]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1750262729
16:00:07 [16:00:07.406]
WARN:
foundation:test-date-provider Time set to 2025-06-18T16:05:29.000Z
{"offset":321594,"timeMs":1750262729000}
16:00:07 [16:00:07.406]
INFO:
aztecjs:utils:watcher Slot 3 was filled, jumped to next slot
16:00:07 [16:00:07.409]
INFO:
blob-sink:server Received blob sidecar for block 0x36f396434d2ef55183fc99abe84043f4ab4320bd5e313b1c26d6af226c1b3fcb
16:00:07 [16:00:07.415]
INFO:
blob-sink:server Blob sidecar stored successfully for block 0x36f396434d2ef55183fc99abe84043f4ab4320bd5e313b1c26d6af226c1b3fcb
16:00:07 [16:00:07.579]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:07 [16:00:07.977]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:08 [16:00:08.044]
VERBOSE:
archiver:block-helper Store contract instance at 0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2
16:00:08 [16:00:08.085]
VERBOSE:
archiver:block-helper Store contract class 0x04bc49f0673daeaf90a2800f1977e7a6926baa21f8f7788eeadb74f9e27424fb
16:00:08 [16:00:08.133]
INFO:
archiver Downloaded L2 block 2
{"blockHash":"0x0fe924319c939f794e296d3320c042741128319d08d3f1d50464c550d819d293","blockNumber":2,"txCount":1,"globalVariables":{"chainId":31337,"version":2521052666,"blockNumber":2,"slotNumber":3,"timestamp":1750262693,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x0bffb172f5d09c7ddfb45dbc868704e608e2ab6c6a51db8af1f1d55d173ca0e4","archiveNextLeafIndex":3}
16:00:08 [16:00:08.201]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:08 [16:00:08.253]
INFO:
world_state World state updated with L2 block 2
{"eventName":"l2-block-handled","duration":13.04917099999875,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1750262693,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":709}
16:00:08 [16:00:08.261]
VERBOSE:
p2p Synched to latest block 2
16:00:08 [16:00:08.403]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:08 [16:00:08.722]
INFO:
aztecjs:deploy_sent_tx Contract 0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2 successfully deployed.
16:00:08 [16:00:08.723]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L2 cheatcodes load public
16:00:08 [16:00:08.726]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:08 [16:00:08.749]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L2 cheatcodes load public returns 0 for non existent value
16:00:08 [16:00:08.761]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L2 cheatcodes load private works as expected for no notes
16:00:08 [16:00:08.773]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L2 cheatcodes load private
16:00:08 [16:00:08.791]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x0bffb172f5d09c7ddfb45dbc868704e608e2ab6c6a51db8af1f1d55d173ca0e4","blockNumber":3,"slot":4}
16:00:09 [16:00:09.044]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:09 [16:00:09.107]
VERBOSE:
pxe:service Updated pxe last block to 2
{"blockHash":{},"archive":"0x0bffb172f5d09c7ddfb45dbc868704e608e2ab6c6a51db8af1f1d55d173ca0e4","header":{"contentCommitment":{"blobsHash":"0x000d56aa9476dc31166d354a9fef291fa86d0f768ff01d547c9447574f983466","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1750262693,"version":2521052666},"lastArchive":"0x240bab94dfc03172707d0837ac8a9290afd03766a4676e4ab3f7873097bc1761","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2eac6026472a59b7f7f3a0b74de768de08de3c01abe62570e127787646e4041e","nullifierTree":"0x291be57d2aaa9c20163e69710b90df166bbce705e42f857a03a9bd537851ced8","publicDataTree":"0x1f189f41520fc4d8d7ff86c37099a2aebe8bbb86f497b1ec6bf482f9497d3cd2"},"totalFees":139009277500,"totalManaUsed":418375}}
16:00:09 [16:00:09.210]
VERBOSE:
simulator:private_execution Executing private function SchnorrAccount:entrypoint
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
16:00:09 [16:00:09.259]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
16:00:09 [16:00:09.260]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
16:00:09 [16:00:09.263]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:09 [16:00:09.346]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x0bffb172f5d09c7ddfb45dbc868704e608e2ab6c6a51db8af1f1d55d173ca0e4","blockNumber":3,"slot":4}
16:00:09 [16:00:09.352]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f"}
16:00:09 [16:00:09.358]
VERBOSE:
simulator:client_execution_context:debug_log Processing log with tag 0x0787256decb3a32e463e2253c3db08b4052f412a6c9dc2e60439f85f857edddd
16:00:09 [16:00:09.371]
VERBOSE:
simulator:client_execution_context:debug_log Processing private note msg
16:00:09 [16:00:09.372]
VERBOSE:
simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001
16:00:09 [16:00:09.380]
VERBOSE:
simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
16:00:09 [16:00:09.381]
VERBOSE:
simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message
16:00:09 [16:00:09.387]
VERBOSE:
pxe:service Added note
{"index":0,"contract":"0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x0a5d48c716734cbcbb01465988464467efee889338d6e96f7c7baa14cd5a170a","nullifier":"0x1a8910e201c4abeee875e4b841cedf60e93a19f1a9b975abe2489b1acf21f1d7"}
16:00:09 [16:00:09.390]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
16:00:09 [16:00:09.409]
VERBOSE:
simulator:client_execution_context:debug_log Setting 0x08ccb87ddf71a76822b4de3262b2d075bbfc5a84aebf7ee72df9c6494fc8c37f as fee payer
16:00:09 [16:00:09.760]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:10 [16:00:10.133]
VERBOSE:
simulator:private_execution Executing private function Token:mint_to_private
{"contract":"0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2"}
16:00:10 [16:00:10.176]
VERBOSE:
simulator:client_execution_context:debug_log Performing message discovery
16:00:10 [16:00:10.176]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2"}
16:00:10 [16:00:10.185]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:10 [16:00:10.389]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x0bffb172f5d09c7ddfb45dbc868704e608e2ab6c6a51db8af1f1d55d173ca0e4","blockNumber":3,"slot":4}
16:00:10 [16:00:10.397]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:10 [16:00:10.409]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2"}
16:00:10 [16:00:10.413]
VERBOSE:
simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes
16:00:10 [16:00:10.740]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:11 [16:00:11.018]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:11 [16:00:11.198]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x0bffb172f5d09c7ddfb45dbc868704e608e2ab6c6a51db8af1f1d55d173ca0e4","blockNumber":3,"slot":4}
16:00:11 [16:00:11.371]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:12 [16:00:12.347]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:12 [16:00:12.352]
VERBOSE:
sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1)
{"chainTipArchive":"0x0bffb172f5d09c7ddfb45dbc868704e608e2ab6c6a51db8af1f1d55d173ca0e4","blockNumber":3,"slot":4}
16:00:12 [16:00:12.723]
INFO:
pxe:service Proving completed in 3634.2908690000004ms
{"timings":{"total":3634.2908690000004,"sync":20.25852600000144,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":439.434925000005,"oracles":{"debugLog":{"times":[0.7828929999996035,0.5233319999970263,0.5134410000027856,0.5249020000010205,0.5209519999989425,0.34699800000089454,0.45511599999736063,0.6529319999972358]},"fetchTaggedLogs":{"times":[96.30839000000196]},"loadCapsule":{"times":[0.23518799999874318,0.18664500000159023,0.4873489999990852,0.4989000000023225,0.21409700000003795,0.477867999998125]},"getSharedSecret":{"times":[4.2180869999974675]},"aes128Decrypt":{"times":[0.3623200000001816,1.065005000000383]},"getPublicKeysAndPartialAddress":{"times":[1.9567060000008496]},"getKeyValidationRequest":{"times":[2.1880139999993844]},"deliverNote":{"times":[6.488808000001882]},"deleteCapsule":{"times":[0.40278200000102515]},"storeCapsule":{"times":[0.40699300000051153]},"getNotes":{"times":[4.397991000001639]},"getAuthWitness":{"times":[0.31394499999805703]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.09902800000054413]},"callPrivateFunction":{"times":[1349.0129880000022]}}},{"functionName":"private_kernel_init","time":89.69593199999872},{"functionName":"Token:mint_to_private","time":1189.8462849999996,"oracles":{"debugLog":{"times":[0.6594819999991159,0.3919309999982943]},"fetchTaggedLogs":{"times":[235.24795000000086]},"loadCapsule":{"times":[0.39691099999981816,0.22938799999974435,0.1652429999994638]},"getRandomField":{"times":[0.1086879999966186,0.19812600000295788,0.22573799999736366,0.09549799999876996,0.05495399999927031,0.052503999999316875,0.03672300000107498,0.04921299999841722,0.0374630000005709,0.10650900000109687,0.06771500000104425,0.0934080000006361]},"getIndexedTaggingSecretAsSender":{"times":[150.66415499999857]},"incrementAppTaggingSecretIndexAsSender":{"times":[163.3094830000009]},"notifyCreatedNullifier":{"times":[0.22857800000201678]},"storeInExecutionCache":{"times":[0.03210199999739416]},"notifyEnqueuedPublicFunctionCall":{"times":[0.04819299999871873]}}},{"functionName":"private_kernel_inner","time":192.35957000000053},{"functionName":"private_kernel_reset","time":260.6686269999991},{"functionName":"private_kernel_tail","time":114.35205900000074}],"unaccounted":1327.6749449999952}}
16:00:12 [16:00:12.753]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:12 [16:00:12.802]
VERBOSE:
p2p:tx_pool Adding tx 0x13f3bb529ecc1c43209e68c205ae5b50800b946af30cf20a37d0359d915ac169 to pool
{"eventName":"tx-added-to-pool","txHash":"0x13f3bb529ecc1c43209e68c205ae5b50800b946af30cf20a37d0359d915ac169","noteHashCount":0,"nullifierCount":2,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":226377,"feePaymentMethod":"fee_juice"}
16:00:12 [16:00:12.817]
INFO:
node Received tx 0x13f3bb529ecc1c43209e68c205ae5b50800b946af30cf20a37d0359d915ac169
{"txHash":"0x13f3bb529ecc1c43209e68c205ae5b50800b946af30cf20a37d0359d915ac169"}
16:00:12 [16:00:12.818]
INFO:
pxe:service Sent transaction 0x13f3bb529ecc1c43209e68c205ae5b50800b946af30cf20a37d0359d915ac169
16:00:12 [16:00:12.908]
VERBOSE:
sequencer Building block 3 for slot 4
{"slot":4,"blockNumber":3,"msgCount":0}
16:00:12 [16:00:12.961]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:12 [16:00:12.976]
VERBOSE:
sequencer Processing pending txs
{"slot":4,"slotStart":"2025-06-18T16:05:29.000Z","now":"2025-06-18T16:05:34.570Z"}
16:00:13 [16:00:13.066]
VERBOSE:
simulator:public_tx_simulator Simulation of enqueued public call 0xf1e7e92a completed successfully.
{"eventName":"avm-simulation","appCircuitName":"0xf1e7e92a","duration":39.06830600000103}
16:00:13 [16:00:13.091]
VERBOSE:
simulator:public-processor Processed tx 0x13f3bb529ecc1c43209e68c205ae5b50800b946af30cf20a37d0359d915ac169 with 1 public calls in 81.6688929999982ms
{"txHash":"0x13f3bb529ecc1c43209e68c205ae5b50800b946af30cf20a37d0359d915ac169","txFee":24485900700,"revertCode":0,"gasUsed":{"totalGas":{"daGas":13312,"l2Gas":73695},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":2048,"l2Gas":42495},"billedGas":{"daGas":13312,"l2Gas":73695}},"publicDataWriteCount":2,"nullifierCount":2,"noteHashCount":1,"contractClassLogCount":0,"publicLogCount":1,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":81.6688929999982}
16:00:13 [16:00:13.094]
INFO:
simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.11739566099999865s
{"duration":0.11739566099999865,"rate":361981.0105247458,"totalPublicGas":{"daGas":2048,"l2Gas":42495},"totalBlockGas":{"daGas":13312,"l2Gas":73695},"totalSizeInBytes":1216}
16:00:13 [16:00:13.264]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:13 [16:00:13.311]
INFO:
sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 103929.57312444822 mana/s
{"blockHash":"0x27563ec608884707dc67787ce9958ed647cd0a2e90e3f1f87d5239fd5ce0d59f","globalVariables":{"chainId":31337,"version":2521052666,"blockNumber":3,"slotNumber":4,"timestamp":1750262729,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"txHashes":["0x13f3bb529ecc1c43209e68c205ae5b50800b946af30cf20a37d0359d915ac169"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":408.090784,"publicProcessDuration":117.92295500000182,"rollupCircuitsDuration":352.38474100000167,"txCount":1,"blockNumber":3,"blockTimestamp":1750262729,"privateLogCount":1,"publicLogCount":1,"contractClassLogCount":0,"contractClassLogSize":0}
16:00:13 [16:00:13.316]
VERBOSE:
sequencer Attesting committee is empty
16:00:13 [16:00:13.485]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:13 [16:00:13.679]
VERBOSE:
ethereum:tx_delayer Sent tx immediately 0x261b1cd2fc80c7471dcd6ecebc8cf6f1aac89cfd9e6f845e316501b2df279e54
16:00:13 [16:00:13.679]
VERBOSE:
sequencer-client Sent L1 transaction 0x261b1cd2fc80c7471dcd6ecebc8cf6f1aac89cfd9e6f845e316501b2df279e54
{"gasLimit":478954,"maxFeePerGas":"1.269635484","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"}
16:00:13 [16:00:13.691]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:13 [16:00:13.697]
INFO:
sequencer:publisher Bundled [propose] transaction [succeeded]
16:00:13 [16:00:13.698]
VERBOSE:
sequencer:publisher Published L2 block to L1 rollup contract
{"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x261b1cd2fc80c7471dcd6ecebc8cf6f1aac89cfd9e6f845e316501b2df279e54","calldataSize":1444,"calldataGas":12304,"txCount":1,"blockNumber":3,"blockTimestamp":1750262729,"privateLogCount":1,"publicLogCount":1,"contractClassLogCount":0,"contractClassLogSize":0}
16:00:13 [16:00:13.698]
VERBOSE:
node:blob-sink:client Sending 1 blobs to blob sink
16:00:13 [16:00:13.712]
INFO:
blob-sink:server Received blob sidecar for block 0x20822c79bea44b49c13f3cef7d5cfcf1a3dfcc8119f3368030378100c80f794e
16:00:13 [16:00:13.718]
INFO:
blob-sink:server Blob sidecar stored successfully for block 0x20822c79bea44b49c13f3cef7d5cfcf1a3dfcc8119f3368030378100c80f794e
16:00:13 [16:00:13.747]
INFO:
ethereum:cheat_codes Calling evm_setNextBlockTimestamp with params: [1750262765] on
http://127.0.0.1:8545
16:00:13 [16:00:13.748]
INFO:
ethereum:cheat_codes Calling hardhat_mine with params: [1] on
http://127.0.0.1:8545
16:00:13 [16:00:13.750]
WARN:
ethereum:cheat_codes Warped L1 timestamp to 1750262765
16:00:13 [16:00:13.750]
WARN:
foundation:test-date-provider Time set to 2025-06-18T16:06:05.000Z
{"offset":351250,"timeMs":1750262765000}
16:00:13 [16:00:13.750]
INFO:
aztecjs:utils:watcher Slot 4 was filled, jumped to next slot
16:00:13 [16:00:13.908]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:14 [16:00:14.166]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:14 [16:00:14.202]
INFO:
archiver Downloaded L2 block 3
{"blockHash":"0x27563ec608884707dc67787ce9958ed647cd0a2e90e3f1f87d5239fd5ce0d59f","blockNumber":3,"txCount":1,"globalVariables":{"chainId":31337,"version":2521052666,"blockNumber":3,"slotNumber":4,"timestamp":1750262729,"coinbase":"0x0000000000000000000000000000000000000000","feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260},"archiveRoot":"0x080c5731b09b98650226243f4931296f057929b9ba357d27cb5758322ed20235","archiveNextLeafIndex":4}
16:00:14 [16:00:14.293]
INFO:
world_state World state updated with L2 block 3
{"eventName":"l2-block-handled","duration":6.996877999998105,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1750262729,"privateLogCount":1,"publicLogCount":1,"contractClassLogCount":0,"contractClassLogSize":0}
16:00:14 [16:00:14.319]
VERBOSE:
p2p Synched to latest block 3
16:00:14 [16:00:14.371]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:14 [16:00:14.575]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:14 [16:00:14.745]
VERBOSE:
sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1)
{"chainTipArchive":"0x080c5731b09b98650226243f4931296f057929b9ba357d27cb5758322ed20235","blockNumber":4,"slot":5}
16:00:14 [16:00:14.778]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:14 [16:00:14.836]
VERBOSE:
pxe:service Updated pxe last block to 3
{"blockHash":{},"archive":"0x080c5731b09b98650226243f4931296f057929b9ba357d27cb5758322ed20235","header":{"contentCommitment":{"blobsHash":"0x00ff1273abc380b7845c16e4149a47b0bd029cd0de575f526cce1cd00f512ef3","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","numTxs":1,"outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0x0000000000000000000000000000000000000000","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1750262729,"version":2521052666},"lastArchive":"0x0bffb172f5d09c7ddfb45dbc868704e608e2ab6c6a51db8af1f1d55d173ca0e4","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x19280f0491290b0fed1562b0a55f72e350746504722f61d250f7731798e7610e","nullifierTree":"0x14b92c4759ce721d5800e340f72cc6fd5c37d327e381ec7165ccc260d8ea5a5d","publicDataTree":"0x24b11c14dc8a089315e1700e9896bc65caa4d3ee7601223db420087fd948a545"},"totalFees":24485900700,"totalManaUsed":73695}}
16:00:15 [16:00:15.098]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:15 [16:00:15.226]
VERBOSE:
simulator Executing utility function sync_private_state
{"contract":"0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2","selector":"0xc6cedc33"}
16:00:15 [16:00:15.242]
VERBOSE:
simulator:client_view_context:debug_log Performing message discovery
16:00:15 [16:00:15.243]
VERBOSE:
pxe:service Searching for tagged logs
{"contract":"0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2"}
16:00:15 [16:00:15.388]
INFO:
ethereum:cheat_codes Calling eth_getBlockByNumber with params: ["latest",true] on
http://127.0.0.1:8545
16:00:15 [16:00:15.410]
VERBOSE:
sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1)
{"chainTipArchive":"0x080c5731b09b98650226243f4931296f057929b9ba357d27cb5758322ed20235","blockNumber":4,"slot":5}
16:00:15 [16:00:15.436]
VERBOSE:
pxe:service Searching for nullifiers of known notes
{"contract":"0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2"}
16:00:15 [16:00:15.442]
VERBOSE:
simulator:client_view_context:debug_log Processing log with tag 0x18312342510df79da8fce9008a7be4193f143b3f14b1efe40dabd653df7e3df3
16:00:15 [16:00:15.460]
VERBOSE:
simulator:client_view_context:debug_log Processing partial note private msg
16:00:15 [16:00:15.475]
VERBOSE:
simulator:client_view_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000001 pending partial notes
16:00:15 [16:00:15.481]
VERBOSE:
simulator:client_view_context:debug_log Completion log found for partial note with tag 0x1b7266405444c1c91980d71a85d259fdb085109b0a61b2c7e7988516bb6d1f53
16:00:15 [16:00:15.482]
VERBOSE:
simulator:client_view_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2 for storage slot 0x0b73dd1de75f997e8ef4afa473ccb3b54b2ba49d03767faa0315b14eefd5dac8
16:00:15 [16:00:15.491]
VERBOSE:
simulator:client_view_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes
16:00:15 [16:00:15.491]
VERBOSE:
simulator:client_view_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes for partial note with tag 0x1b7266405444c1c91980d71a85d259fdb085109b0a61b2c7e7988516bb6d1f53
16:00:15 [16:00:15.497]
VERBOSE:
pxe:service Added note
{"index":128,"contract":"0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2","slot":"0x0b73dd1de75f997e8ef4afa473ccb3b54b2ba49d03767faa0315b14eefd5dac8","noteHash":"0x2f7c61bb0acc7a950de98bd8ee2097594b1968863001b585e16fd81776da75a3","nullifier":"0x2b0b39524a822b8847cdd3acc32f1174bd9708fa4d5b5bbc241247ad7e75c8dc"}
16:00:15 [16:00:15.502]
VERBOSE:
simulator Utility simulation for 0x002271e87a956493de611d19f68b871b5bae30f3d1f14835863d795eff396ec2.0xc6cedc33 completed
16:00:15 [16:00:15.507]
INFO:
e2e:e2e_cheat_codes Running test: e2e_cheat_codes L2 cheatcodes markAsProven
16:00:15 [16:00:15.518]
INFO:
ethereum:cheat_codes Calling hardhat_setStorageAt with params: ["0x0b306bf915c4d645ff596e518faf3f9669b97016","0x0958201b72d64259285941dffd868dac55267471fcc73e8a06e1fd9cf8706362","0x0000000000000000000000000000000000000000000000000000000000000003"] on
http://127.0.0.1:8545
16:00:15 [16:00:15.522]
WARN:
ethereum:cheat_codes Set L1 storage for contract 0x0b306bf915c4d645ff596e518faf3f9669b97016 at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3
16:00:15 [16:00:15.524]
INFO:
aztecjs:cheat_codes Proven tip moved: 1 -> 3. Pending tip: 3.
16:00:15 [16:00:15.535]
INFO:
node Stopping
16:00:15 [16:00:15.536]
INFO:
slasher Slasher client stopped.
16:00:15 [16:00:15.536]
INFO:
sequencer Stopped sequencer
16:00:15 [16:00:15.537]
INFO:
p2p P2P client stopped.
16:00:15 [16:00:15.541]
INFO:
world_state Stopped world state synchronizer
16:00:15 [16:00:15.541]
INFO:
archiver Stopped.
16:00:15 [16:00:15.541]
INFO:
node Stopped
16:00:15 [16:00:15.541]
VERBOSE:
e2e:e2e_cheat_codes Cleaning up ACVM state
16:00:15 [16:00:15.541]
INFO:
e2e:e2e_cheat_codes Cleaning up ACVM temp directory /tmp/e3efcd9e
16:00:15 [16:00:15.558]
VERBOSE:
e2e:e2e_cheat_codes Cleaning up BB state
16:00:15 [16:00:15.562]
INFO:
blob-sink:server Stopping blob sink
16:00:15 [16:00:15.563]
INFO:
blob-sink:server Blob sink stopped
16:00:15 [16:00:15.563]
VERBOSE:
e2e:e2e_cheat_codes Cleaning up data directory at /tmp/4509bd8e575d55f6
16:00:15
PASS src/e2e_cheat_codes.test.ts
16:00:15 e2e_cheat_codes
16:00:15 L1 cheatcodes
16:00:15
✓ setNextBlockTimestamp by 100 (100 ms)
16:00:15
✓ setNextBlockTimestamp by 42 (93 ms)
16:00:15
✓ setNextBlockTimestamp by 99 (91 ms)
16:00:15
✓ setNextBlockTimestamp to a past timestamp throws (93 ms)
16:00:15
✓ load a value at a particular storage slot (120 ms)
16:00:15
✓ store a value at a given slot and its keccak value of the slot (if it were in a map) (111 ms)
16:00:15
✓ store a value at a given slot and its keccak value of the slot (if it were in a map) (91 ms)
16:00:15
✓ set bytecode correctly (84 ms)
16:00:15
✓ impersonate (125 ms)
16:00:15 mine
16:00:15
✓ mine block (290 ms)
16:00:15
✓ mine 10 blocks (106 ms)
16:00:15
✓ mine 42 blocks (98 ms)
16:00:15
✓ mine 99 blocks (107 ms)
16:00:15 L2 cheatcodes
16:00:15
✓ load public (26 ms)
16:00:15
✓ load public returns 0 for non existent value (13 ms)
16:00:15
✓ load private works as expected for no notes (11 ms)
16:00:15
✓ load private (6733 ms)
16:00:15
✓ markAsProven (20 ms)
16:00:15
16:00:15
Test Suites: 1 passed, 1 total
16:00:15
Tests: 18 passed, 18 total
16:00:15
Snapshots: 0 total
16:00:15
Time: 29.409 s
16:00:15
Ran all test suites matching /src\/e2e_cheat_codes.test.ts/i
.
16:00:15
Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?