Parent Log: http://ci.aztec-labs.com/cfa3a08d8c538c28 Command: 1905308686f8cbc3:ISOLATE=1:NAME=e2e_offchain_message yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_offchain_message.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/30660269b33bab8cca354c41659533acf4d48e07 Env: REF_NAME=gh-readonly-queue/next/pr-15026-d96baf1c44329e8b2e3a432ad803f702f5184a62 CURRENT_VERSION=0.87.6 CI_FULL=0 Date: Fri Jun 13 09:33:39 UTC 2025 System: ARCH=arm64 CPUS=64 MEM=247Gi HOSTNAME=pr-15026_arm64_a1-fast Resources: CPU_LIST=0-63 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_9323dbf3b3f7fe91_next 09:33:40 +++ id -u 09:33:40 +++ id -g 09:33:40 ++ docker run -d --name e2e_offchain_message --net=none --cpuset-cpus=0-63 --cpus=2 --memory=8g --user 1000:1000 -v/home/aztec-dev:/home/aztec-dev --mount type=tmpfs,target=/tmp,tmpfs-size=1g --workdir /home/aztec-dev/aztec-packages -e HOME -e VERBOSE -e GIT_CONFIG_GLOBAL=/home/aztec-dev/aztec-packages/build-images/src/home/.gitconfig -e FORCE_COLOR=true -e CPUS -e MEM aztecprotocol/build:3.0 /bin/bash -c 'timeout -v 600s bash -c '\''yarn-project/end-to-end/scripts/run_test.sh simple src/e2e_offchain_message.test.ts'\''' 09:33:40 + cid=661b2d0c6046a88038b93984f55af9e2c840c9129ef6991cb97b08bc92217ff2 09:33:40 + set +x 09:33:40 {"level":25,"time":1749807220728,"pid":12,"hostname":"661b2d0c6046","module":"logger","msg":"Logger initialized with level verbose"} 09:33:44 [09:33:44.882] INFO: e2e:e2e_offchain_message Logging metrics to log/unknown.jsonl 09:33:45 [09:33:45.349] INFO: world-state:database Creating world state data store at directory /tmp/aztec-world-state-nAMY0r/world_state with map sizes {"archiveTreeMapSizeKb":10485760,"nullifierTreeMapSizeKb":10485760,"noteHashTreeMapSizeKb":10485760,"messageTreeMapSizeKb":10485760,"publicDataTreeMapSizeKb":10485760} and 2 threads. 09:33:45 [09:33:45.384] INFO: e2e:e2e_offchain_message Calling anvil_setBlockTimestampInterval with params: [12] 09:33:45 [09:33:45.405] WARN: e2e:e2e_offchain_message Set block interval to 12 09:33:45 [09:33:45.405] VERBOSE: e2e:e2e_offchain_message Deploying contracts from 0xf39Fd6e51aad88F6F4ce6aB8827279cffFb92266 09:33:45 [09:33:45.451] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x5f6007147b276ec3270f42c9cc4c2453661a1882dd03ebb0a7663e0351326302 {"gasLimit":854274,"maxFeePerGas":"2.80180664","maxPriorityFeePerGas":"1.2"} 09:33:45 [09:33:45.458] VERBOSE: e2e:e2e_offchain_message Deployed Fee Asset at 0x5fbdb2315678afecb367f032d93f642f64180aa3 09:33:45 [09:33:45.473] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xaf47cdb235bd1f13781af7e220955c2cdc0e084c2ebf03be2e261fd9e4cd20e4 {"gasLimit":854259,"maxFeePerGas":"3.04180664","maxPriorityFeePerGas":"1.44"} 09:33:45 [09:33:45.478] VERBOSE: e2e:e2e_offchain_message Deployed Staking Asset at 0xe7f1725e7734ce288f8367e1bb143e90bb3f0512 09:33:45 [09:33:45.495] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x127d08cafb8afc3d7c988fa49d05483d6d2b44a017de0544d9d53ed8e954f028 {"gasLimit":3068916,"maxFeePerGas":"3.139083461","maxPriorityFeePerGas":"1.728"} 09:33:45 [09:33:45.500] VERBOSE: e2e:e2e_offchain_message Deployed GSE at 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 09:33:45 [09:33:45.513] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xdb9612b2166822d6497afb75ab3fe675145b118f9d3ef58bc23bdb9816e5d0f5 {"gasLimit":1014492,"maxFeePerGas":"3.316669081","maxPriorityFeePerGas":"2.0736"} 09:33:45 [09:33:45.517] VERBOSE: e2e:e2e_offchain_message Deployed Registry at 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 09:33:45 [09:33:45.534] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x32ed4059fca555e8be9e05117cb7e979fafb635b31d08729d246c0704eaa1138 {"gasLimit":1966612,"maxFeePerGas":"3.602497631","maxPriorityFeePerGas":"2.48832"} 09:33:45 [09:33:45.539] VERBOSE: e2e:e2e_offchain_message Deployed GovernanceProposer at 0xdc64a140aa3e981100a9beca4e685f962f0cf6c9 09:33:45 [09:33:45.554] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xf29078d6fdb1fd1334c85ea0a8f17d2b4c4766e863a0822c2fb665091b723499 {"gasLimit":3042380,"maxFeePerGas":"3.968738902","maxPriorityFeePerGas":"2.985984"} 09:33:45 [09:33:45.558] VERBOSE: e2e:e2e_offchain_message Deployed Governance at 0x5fc8d32690cc91d4c39d9d3abcbd16989f875707 09:33:45 [09:33:45.568] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xb1ee83a7dc467ba966679060fa6683b6cb0ae294ddd576326f77273f9fd15167 {"gasLimit":100000,"maxFeePerGas":"4.456512857","maxPriorityFeePerGas":"3.5831808"} 09:33:45 [09:33:45.568] VERBOSE: e2e:e2e_offchain_message Set governance on GSE in 0xb1ee83a7dc467ba966679060fa6683b6cb0ae294ddd576326f77273f9fd15167 09:33:45 [09:33:45.579] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xd7c909c2f426dbb387bba51539e35cd537bbd9383154eb2618179b00ef0008b2 {"gasLimit":391705,"maxFeePerGas":"5.082433956","maxPriorityFeePerGas":"4.29981696"} 09:33:45 [09:33:45.583] VERBOSE: e2e:e2e_offchain_message Deployed CoinIssuer at 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 09:33:45 [09:33:45.583] VERBOSE: e2e:e2e_offchain_message Waiting for deployments to complete 09:33:45 [09:33:45.584] INFO: e2e:e2e_offchain_message Waiting for 7 transactions to be mined... 09:33:45 [09:33:45.605] INFO: e2e:e2e_offchain_message All transactions mined successfully 09:33:45 [09:33:45.616] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x50623955002e1e57158f557090a59024971ab40a20dee71f36c8bea1ae79dcca {"gasLimit":100000,"maxFeePerGas":"5.844872118","maxPriorityFeePerGas":"5.159780352"} 09:33:45 [09:33:45.617] VERBOSE: e2e:e2e_offchain_message Added coin issuer 0xa513e6e4b8f2a923d98304ec87f64353c4d5c853 as minter on fee asset in 0x50623955002e1e57158f557090a59024971ab40a20dee71f36c8bea1ae79dcca 09:33:45 [09:33:45.629] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x7ef4acce2d29fb2e046cebc7a8a26e7c7540e156376f7b1c5bb5b863cbe564c3 {"gasLimit":56661,"maxFeePerGas":"6.793055288","maxPriorityFeePerGas":"6.191736422"} 09:33:45 [09:33:45.640] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xb25c5ab4294e8fbc0c9624e56aa31ec351526cb6af6350c11417f282dd0f9bd3 {"gasLimit":339705,"maxFeePerGas":"7.956474918","maxPriorityFeePerGas":"7.430083706"} 09:33:45 [09:33:45.645] VERBOSE: e2e:e2e_offchain_message Deployed FeeAssetHandler at 0x610178da211fef7d417bc0e6fed39f05609ad788 09:33:45 [09:33:45.656] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x08a4b0382909196399efe606a9ab1253db3a33421ff5f1c3d76a1d9d2ca06484 {"gasLimit":56804,"maxFeePerGas":"9.376899884","maxPriorityFeePerGas":"8.916100447"} 09:33:45 [09:33:45.657] VERBOSE: e2e:e2e_offchain_message Added fee asset handler 0x610178da211fef7d417bc0e6fed39f05609ad788 as minter on fee asset in 0x08a4b0382909196399efe606a9ab1253db3a33421ff5f1c3d76a1d9d2ca06484 09:33:45 [09:33:45.672] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xf9401b33d177baeffffb49aad9037387d78c09beb9e9ec7ba1d3f2570522b753 {"gasLimit":2474287,"maxFeePerGas":"11.103607102","maxPriorityFeePerGas":"10.699320536"} 09:33:45 [09:33:45.677] VERBOSE: e2e:e2e_offchain_message Deployed StakingAssetHandler at 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 09:33:45 [09:33:45.689] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x28e1ecce4a1baaa32ca177534485a033b735c7dcb2864165719cbade0787fac3 {"gasLimit":56804,"maxFeePerGas":"13.193094869","maxPriorityFeePerGas":"12.839184643"} 09:33:45 [09:33:45.689] VERBOSE: e2e:e2e_offchain_message Added staking asset handler 0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0 as minter on staking asset in 0x28e1ecce4a1baaa32ca177534485a033b735c7dcb2864165719cbade0787fac3 09:33:45 [09:33:45.689] VERBOSE: e2e:e2e_offchain_message Waiting for deployments to complete 09:33:45 [09:33:45.689] INFO: e2e:e2e_offchain_message Waiting for 9 transactions to be mined... 09:33:45 [09:33:45.711] INFO: e2e:e2e_offchain_message All transactions mined successfully 09:33:45 [09:33:45.723] VERBOSE: e2e:e2e_offchain_message Deployed shared contracts 09:33:45 [09:33:45.738] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x32fc7527e2716a4d58ec1bdeb099b80d5244cd705c9519555b6b565b7ba2a111 {"gasLimit":84837,"maxFeePerGas":"15.722774098","maxPriorityFeePerGas":"15.407021571"} 09:33:45 [09:33:45.739] VERBOSE: e2e:e2e_offchain_message Funded reward distributor with 10000000000000000000000000 fee asset in 0x32fc7527e2716a4d58ec1bdeb099b80d5244cd705c9519555b6b565b7ba2a111 09:33:45 [09:33:45.752] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xdef66310c9d0b7dd201d14d81f0afe9cb0a7a1bcdfb3985c400aaac3112b99a8 {"gasLimit":152965,"maxFeePerGas":"18.764833904","maxPriorityFeePerGas":"18.488425885"} 09:33:45 [09:33:45.756] VERBOSE: e2e:e2e_offchain_message Rollup will use the mock verifier at 0x0b306bf915c4d645ff596e518faf3f9669b97016 09:33:45 [09:33:45.757] VERBOSE: e2e:e2e_offchain_message Rollup config args {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100,"rewardConfig":{"sequencerBps":5000,"increment":200000,"maxScore":5000000,"a":5000,"k":1000000,"minimum":100000}} 09:33:45 [09:33:45.767] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xa30936656c85bd30a140fccee2cc68506bbb2794b4d16485d9d2cef61cfec969 {"gasLimit":86383,"maxFeePerGas":"22.428130924","maxPriorityFeePerGas":"22.186111062"} 09:33:45 [09:33:45.789] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xbe78ed180cb67f2324e0f55f5bcf89cc6f9feb261fdc97fb4688481193c42049 {"gasLimit":5075920,"maxFeePerGas":"26.835357743","maxPriorityFeePerGas":"26.623333274"} 09:33:45 [09:33:45.810] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xadefaa698fbe2ccad8b7262edf757f1bbec31f893ad6df9dc23634f426ba2e27 {"gasLimit":2439043,"maxFeePerGas":"32.133648528","maxPriorityFeePerGas":"31.947999928"} 09:33:45 [09:33:45.815] VERBOSE: e2e:e2e_offchain_message Awaiting for linked libraries to be deployed 09:33:45 [09:33:45.850] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0xd960aeafeaf82fb378388dcd27d365444c0efaadb01339b3912ef5643eb70ef4 {"gasLimit":10498896,"maxFeePerGas":"38.506586449","maxPriorityFeePerGas":"38.337599913"} 09:33:45 [09:33:45.856] VERBOSE: e2e:e2e_offchain_message Deployed Rollup at 0x3aa5ebb10dc797cac828524e59a333d0a371443c {"aztecSlotDuration":36,"aztecEpochDuration":32,"targetCommitteeSize":48,"aztecProofSubmissionWindow":64,"slashingQuorum":6,"slashingRoundSize":10,"manaTarget":10000000000,"provingCostPerMana":100,"rewardConfig":{"sequencerBps":5000,"increment":200000,"maxScore":5000000,"a":5000,"k":1000000,"minimum":100000}} 09:33:45 [09:33:45.856] INFO: e2e:e2e_offchain_message Waiting for 11 transactions to be mined... 09:33:45 [09:33:45.882] INFO: e2e:e2e_offchain_message All transactions mined successfully 09:33:45 [09:33:45.882] VERBOSE: e2e:e2e_offchain_message All core contracts have been deployed 09:33:45 [09:33:45.895] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x0a0174e8d0005aa9a9551eee71e39afebdb2de9976dcbd47f8af4f474230fcb2 {"gasLimit":64317,"maxFeePerGas":"46.155845375","maxPriorityFeePerGas":"46.005119895"} 09:33:45 [09:33:45.896] VERBOSE: e2e:e2e_offchain_message Funding fee juice portal with 10000000000000000000000 fee juice in 0x0a0174e8d0005aa9a9551eee71e39afebdb2de9976dcbd47f8af4f474230fcb2 (accelerated test deployments) 09:33:45 [09:33:45.907] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x6a926be81b73dd91dae704702483da1c551ca87b055c30c4ea85773cbb8c17f2 {"gasLimit":1040131,"maxFeePerGas":"55.349017914","maxPriorityFeePerGas":"55.206143874"} 09:33:45 [09:33:45.911] VERBOSE: e2e:e2e_offchain_message Deployed SlashFactory at 0x59b670e9fa9d0a427751af201d676719a970857b 09:33:45 [09:33:45.930] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x0acdf75a39c0fe97fc7d072ffe5aed05f55df6cc237b6d433d4708e019236c9a {"gasLimit":116846,"maxFeePerGas":"66.372451249","maxPriorityFeePerGas":"66.247372648"} 09:33:45 [09:33:45.930] VERBOSE: e2e:e2e_offchain_message Adding rollup 0x3aa5ebb10dc797cac828524e59a333d0a371443c to registry 0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9 in tx 0x0acdf75a39c0fe97fc7d072ffe5aed05f55df6cc237b6d433d4708e019236c9a 09:33:45 [09:33:45.944] VERBOSE: e2e:e2e_offchain_message Sent L1 transaction 0x1019a21ee117e4e7e203f44054937179d452ee7dc01f25842f0b0ed5d9ffb02e {"gasLimit":109478,"maxFeePerGas":"79.607194411","maxPriorityFeePerGas":"79.496847177"} 09:33:45 [09:33:45.944] VERBOSE: e2e:e2e_offchain_message Adding rollup 0x3aa5ebb10dc797cac828524e59a333d0a371443c to GSE 0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0 in tx 0x1019a21ee117e4e7e203f44054937179d452ee7dc01f25842f0b0ed5d9ffb02e 09:33:45 [09:33:45.948] INFO: e2e:e2e_offchain_message Waiting for 12 transactions to be mined... 09:33:45 [09:33:45.972] INFO: e2e:e2e_offchain_message All transactions mined successfully 09:33:45 [09:33:45.978] VERBOSE: e2e:e2e_offchain_message Rollup deployed 09:33:45 [09:33:45.978] VERBOSE: e2e:e2e_offchain_message Waiting for rollup and slash factory to be deployed 09:33:45 [09:33:45.979] INFO: e2e:e2e_offchain_message Waiting for 12 transactions to be mined... 09:33:46 [09:33:46.001] INFO: e2e:e2e_offchain_message All transactions mined successfully 09:33:46 [09:33:46.001] VERBOSE: e2e:e2e_offchain_message All transactions for L1 deployment have been mined 09:33:46 [09:33:46.017] INFO: e2e:e2e_offchain_message Aztec L1 contracts initialized {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x3aa5ebb10dc797cac828524e59a333d0a371443c","inboxAddress":"0x4374eecaad0dcaa149cffc160d5a0552b1d092b0","outboxAddress":"0x44bf2a9217a2970a1bcc7529bf1d40828c594320","feeJuicePortalAddress":"0xa0719ec6accbac2301f88bc71e2f5ddc8c29149b","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266"} 09:33:46 [09:33:46.017] INFO: e2e:e2e_offchain_message Handing over to governance 09:33:46 [09:33:46.025] INFO: aztecjs:utils:watcher Watcher started for rollup at 0x3Aa5ebB10DC797CAC828524e59A333d0A371443c 09:33:46 [09:33:46.026] INFO: telemetry:client Using NoopTelemetryClient 09:33:46 [09:33:46.030] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:33:46 [09:33:46.031] WARN: foundation:test-date-provider Time set to 2025-06-13T09:38:32.000Z {"offset":285970,"timeMs":1749807512000} 09:33:46 [09:33:46.031] INFO: kv-store:lmdb-v2:blob-sink Creating blob-sink data store at directory /tmp/d97a4bca7c073bf8/blob-sink with map size 134217728 KB (LMDB v2) 09:33:46 [09:33:46.035] INFO: kv-store:lmdb-v2:blob-sink Starting data store with maxReaders 16 09:33:46 [09:33:46.040] INFO: blob-sink:server Server is running on http://localhost:45087 09:33:46 [09:33:46.040] VERBOSE: e2e:e2e_offchain_message Creating and synching an aztec node... 09:33:46 [09:33:46.041] VERBOSE: e2e:e2e_offchain_message Using native ACVM binary at ../../noir/noir-repo/target/release/acvm with working directory /tmp/9b5efcaf/acvm 09:33:46 [09:33:46.041] INFO: node:blob-sink:client Creating HTTP blob sink client. {"blobSinkUrl":"http://localhost:45087"} 09:33:46 [09:33:46.061] VERBOSE: node Snapshot sync is disabled. No snapshots URL provided. 09:33:46 [09:33:46.061] INFO: archiver:lmdb Creating archiver data store at directory /tmp/d97a4bca7c073bf8/archiver with map size 134217728 KB (LMDB v2) 09:33:46 [09:33:46.062] INFO: archiver:lmdb Starting data store with maxReaders 16 09:33:46 [09:33:46.347] INFO: node:blob-sink:client Testing configured blob sources {"blobSinkUrl":"http://localhost:45087"} 09:33:46 [09:33:46.361] INFO: node:blob-sink:client Blob sink is reachable {"blobSinkUrl":"http://localhost:45087"} 09:33:46 [09:33:46.361] WARN: node:blob-sink:client No L1 consensus host urls configured 09:33:46 [09:33:46.362] WARN: node:blob-sink:client No archive client configured 09:33:46 [09:33:46.364] INFO: archiver Starting archiver sync to rollup contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c from L1 block 20 to current L1 block 24 with hash 0xaac96cfe5a6878ec4039c1d1ccfd3f8a141d1b54eea056b164ed19c3ec539278 {"blocksSynchedTo":20,"messagesSynchedTo":{"l1BlockHash":"0x1c4a36e95612aa70e5cec26a8946b8fdc8cb3216d85e75d4deecfe7f861c6d5b","l1BlockNumber":20}} 09:33:46 [09:33:46.377] INFO: archiver Initial archiver sync to L1 block 24 complete. {"l1BlockNumber":24,"syncPoint":{"blocksSynchedTo":24,"messagesSynchedTo":{"l1BlockHash":"0xaac96cfe5a6878ec4039c1d1ccfd3f8a141d1b54eea056b164ed19c3ec539278","l1BlockNumber":24}},"latest":{"number":0},"proven":{"number":0},"finalized":{"number":0}} 09:33:46 [09:33:46.381] INFO: world-state:database Creating world state data store at directory /tmp/d97a4bca7c073bf8/world_state with map sizes {"archiveTreeMapSizeKb":134217728,"nullifierTreeMapSizeKb":134217728,"noteHashTreeMapSizeKb":134217728,"messageTreeMapSizeKb":134217728,"publicDataTreeMapSizeKb":134217728} and 2 threads. 09:33:46 [09:33:46.403] INFO: world_state Created world state synchroniser with block history of 64 09:33:46 [09:33:46.403] WARN: node Aztec node is accepting fake proofs 09:33:46 [09:33:46.412] INFO: p2p:lmdb-v2 Creating p2p data store at directory /tmp/d97a4bca7c073bf8/p2p with map size 134217728 KB (LMDB v2) 09:33:46 [09:33:46.413] INFO: p2p:lmdb-v2 Starting data store with maxReaders 16 09:33:46 [09:33:46.414] INFO: p2p-archive:lmdb-v2 Creating p2p-archive data store at directory /tmp/d97a4bca7c073bf8/p2p-archive with map size 134217728 KB (LMDB v2) 09:33:46 [09:33:46.415] INFO: p2p-archive:lmdb-v2 Starting data store with maxReaders 16 09:33:46 [09:33:46.416] INFO: p2p-peer:lmdb-v2 Creating p2p-peers data store at directory /tmp/d97a4bca7c073bf8/p2p-peers with map size 134217728 KB (LMDB v2) 09:33:46 [09:33:46.417] INFO: p2p-peer:lmdb-v2 Starting data store with maxReaders 16 09:33:46 [09:33:46.418] INFO: p2p:tx_pool Setting maximum tx mempool size {"maxTxPoolSize":100000000} 09:33:46 [09:33:46.418] VERBOSE: p2p P2P is disabled. Using dummy P2P service 09:33:46 [09:33:46.421] VERBOSE: world-state:block_stream Starting L2 block stream {"proven":false,"pollIntervalMS":100} 09:33:46 [09:33:46.421] INFO: world_state Started world state synchronizer from block 1 09:33:46 [09:33:46.423] VERBOSE: world-state:block_stream Starting sync from block number 0 09:33:46 [09:33:46.425] INFO: p2p Starting p2p client from block 0 with empty mempools 09:33:46 [09:33:46.425] VERBOSE: p2p:l2-block-stream Starting L2 block stream {"batchSize":20,"pollIntervalMS":100,"startingBlock":0} 09:33:46 [09:33:46.653] INFO: epoch-prune-watcher EpochPruneWatcher initialized 09:33:46 [09:33:46.686] VERBOSE: validator Initialized validator with addresses: 0xf1fbb6342b996d9fcbc89a9f2670ea6f6b4a0d96 09:33:46 [09:33:46.686] VERBOSE: node All Aztec Node subsystems synced 09:33:46 [09:33:46.698] INFO: slasher Starting Slasher client... 09:33:46 [09:33:46.707] INFO: sequencer-client Deploying forwarder contract 09:33:46 [09:33:46.709] VERBOSE: p2p:l2-block-stream Starting sync from block number 0 09:33:46 [09:33:46.725] VERBOSE: sequencer-client Sent L1 transaction 0x864850f8ffc128a17e70ea6cef66d26c8919aaaae5fe5c53ab1c47d0444d3ffb {"gasLimit":323704,"maxFeePerGas":"95.492859983","maxPriorityFeePerGas":"95.396216612"} 09:33:46 [09:33:46.725] VERBOSE: sequencer-client Deployed contract with salt 0x0000000000000000000000000000000000000000000000000000000000000042 to address 0x0fe4d493871B0C51332De8A330B02BA8172Da4aC in tx 0x864850f8ffc128a17e70ea6cef66d26c8919aaaae5fe5c53ab1c47d0444d3ffb. 09:33:46 [09:33:46.729] INFO: sequencer-client Forwarder contract deployed at 0x0fe4d493871b0c51332de8a330b02ba8172da4ac 09:33:46 [09:33:46.735] INFO: validator Started validator with addresses: 0xf1fbb6342b996d9fcbc89a9f2670ea6f6b4a0d96 09:33:46 [09:33:46.736] VERBOSE: validator Validators 0xf1fbb6342b996d9fcbc89a9f2670ea6f6b4a0d96 are not on the validator committee for epoch 0 09:33:46 [09:33:46.736] INFO: sequencer Sequencer config set {"transactionPollingIntervalMS":500,"maxTxsPerBlock":32,"minTxsPerBlock":1,"maxL2BlockGas":10000000000,"publishTxsWithProposals":false,"maxDABlockGas":10000000000,"acvmWorkingDirectory":"/tmp/9b5efcaf/acvm","acvmBinaryPath":"../../noir/noir-repo/target/release/acvm","maxBlockSizeInBytes":1048576,"governanceProposerPayload":"0x0000000000000000000000000000000000000000","maxL1TxInclusionTimeIntoSlot":12,"enforceTimeTable":false} 09:33:46 [09:33:46.737] VERBOSE: sequencer Sequencer timetable updated {"enforceTimeTable":false} 09:33:46 [09:33:46.738] INFO: sequencer Sequencer started with address 0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266 09:33:46 [09:33:46.739] INFO: node Aztec Node version: 0.87.6 09:33:46 [09:33:46.739] INFO: node Aztec Node started on chain 0x7a69 {"registryAddress":"0xcf7ed3acca5a467e9e704c703e8d87f634fb0fc9","governanceAddress":"0x5fc8d32690cc91d4c39d9d3abcbd16989f875707","governanceProposerAddress":"0xdc64a140aa3e981100a9beca4e685f962f0cf6c9","rollupAddress":"0x3aa5ebb10dc797cac828524e59a333d0a371443c","inboxAddress":"0x4374eecaad0dcaa149cffc160d5a0552b1d092b0","outboxAddress":"0x44bf2a9217a2970a1bcc7529bf1d40828c594320","feeJuicePortalAddress":"0xa0719ec6accbac2301f88bc71e2f5ddc8c29149b","feeJuiceAddress":"0x5fbdb2315678afecb367f032d93f642f64180aa3","stakingAssetAddress":"0xe7f1725e7734ce288f8367e1bb143e90bb3f0512","rewardDistributorAddress":"0xd8058efe0198ae9dd7d563e1b4938dcbc86a1f81","gseAddress":"0x9fe46736679d2d9a65f0992f2272de9f3c7fa6e0","coinIssuerAddress":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","slashFactoryAddress":"0x59b670e9fa9d0a427751af201d676719a970857b","feeAssetHandlerAddress":"0x610178da211fef7d417bc0e6fed39f05609ad788","stakingAssetHandlerAddress":"0xa51c1fc2f0d1a1b8494ed1fe312d7c3a78ed91c0","zkPassportVerifierAddress":"0xee9f10f38319eae2730dba28fb09081db806c5e5"} 09:33:46 [09:33:46.740] VERBOSE: e2e:e2e_offchain_message Creating a pxe... 09:33:46 [09:33:46.746] INFO: pxe:data:lmdb Creating pxe_data data store at directory /tmp/5cae5ecb79ba0eb2/pxe_data with map size 134217728 KB (LMDB v2) 09:33:46 [09:33:46.749] WARN: sequencer Cannot propose block due to slot mismatch with rollup contract (this can be caused by a clock out of sync). Expected slot 1 but got 2. {"rollup":[2,1],"newBlockNumber":1,"expectedSlot":1} 09:33:46 [09:33:46.750] INFO: pxe:data:lmdb Starting data store with maxReaders 16 09:33:46 [09:33:46.931] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:33:46 [09:33:46.931] WARN: foundation:test-date-provider Time set to 2025-06-13T09:38:44.000Z {"offset":297069,"timeMs":1749807524000} 09:33:47 [09:33:47.032] VERBOSE: pxe:service Registered protocol contracts in pxe {"AuthRegistry":"0x0000000000000000000000000000000000000000000000000000000000000001","ContractInstanceDeployer":"0x0000000000000000000000000000000000000000000000000000000000000002","ContractClassRegisterer":"0x0000000000000000000000000000000000000000000000000000000000000003","MultiCallEntrypoint":"0x0000000000000000000000000000000000000000000000000000000000000004","FeeJuice":"0x0000000000000000000000000000000000000000000000000000000000000005","Router":"0x0000000000000000000000000000000000000000000000000000000000000006"} 09:33:47 [09:33:47.032] INFO: pxe:service Started PXE connected to chain 31337 version 2713600433 09:33:47 [09:33:47.081] INFO: pxe:service Registered account 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f 09:33:47 [09:33:47.135] WARN: pxe:service No artifact found for contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 when looking for its metadata 09:33:47 [09:33:47.137] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 as part of deployment for 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f 09:33:47 [09:33:47.270] INFO: pxe:service Added contract SchnorrAccount at 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f with class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 09:33:47 [09:33:47.290] VERBOSE: sequencer Preparing proposal for block 1 at slot 2 {"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807536,"version":2713600433},"chainTipArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","blockNumber":1,"slot":2} 09:33:47 [09:33:47.291] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","blockNumber":1,"slot":2} 09:33:47 [09:33:47.316] VERBOSE: pxe:block_stream Starting sync from block number 0 09:33:47 [09:33:47.354] VERBOSE: simulator:private_execution Executing private function MultiCallEntrypoint:entrypoint {"contract":"0x0000000000000000000000000000000000000000000000000000000000000004"} 09:33:47 [09:33:47.455] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 09:33:47 [09:33:47.854] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097,0x1957d1ce818946c748798e559ad49f7f8cde3f3354114d1283b33b6a89100d84,0x25001159661e67f498b5874e31e434e254a328f93390888fe41be70dca9d8f41,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 09:33:47 [09:33:47.927] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 09:33:48 [09:33:48.010] VERBOSE: sequencer Preparing proposal for block 1 at slot 2 {"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807536,"version":2713600433},"chainTipArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","blockNumber":1,"slot":2} 09:33:48 [09:33:48.011] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","blockNumber":1,"slot":2} 09:33:48 [09:33:48.086] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:constructor {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:48 [09:33:48.125] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:33:48 [09:33:48.126] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:48 [09:33:48.163] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:48 [09:33:48.167] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:33:48 [09:33:48.345] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:48 [09:33:48.380] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:33:48 [09:33:48.380] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:48 [09:33:48.417] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:48 [09:33:48.420] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:33:48 [09:33:48.436] VERBOSE: simulator:client_execution_context:debug_log Setting 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f as fee payer 09:33:48 [09:33:48.634] VERBOSE: sequencer Preparing proposal for block 1 at slot 2 {"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807536,"version":2713600433},"chainTipArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","blockNumber":1,"slot":2} 09:33:48 [09:33:48.636] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","blockNumber":1,"slot":2} 09:33:49 [09:33:49.162] VERBOSE: sequencer Preparing proposal for block 1 at slot 2 {"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807536,"version":2713600433},"chainTipArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","blockNumber":1,"slot":2} 09:33:49 [09:33:49.164] VERBOSE: sequencer Not enough txs to build block 1 at slot 2 (got 0 txs, need 1) {"chainTipArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","blockNumber":1,"slot":2} 09:33:49 [09:33:49.525] INFO: pxe:service Proving completed in 2211.2177550000006ms {"timings":{"total":2211.2177550000006,"sync":2.2122819999995045,"perFunction":[{"functionName":"MultiCallEntrypoint:entrypoint","time":141.31364600000052,"oracles":{"callPrivateFunction":{"times":[586.1145029999998,286.7457089999998,178.2995150000006]}}},{"functionName":"private_kernel_init","time":90.29126600000018},{"functionName":"ContractClassRegisterer:register","time":567.9522580000003,"oracles":{"loadCapsule":{"times":[9.490555999999742]},"notifyCreatedNullifier":{"times":[0.5963089999995645]},"debugLog":{"times":[0.7523240000000442]},"notifyCreatedContractClassLog":{"times":[7.68847900000037]}}},{"functionName":"private_kernel_inner","time":120.17661500000031},{"functionName":"SchnorrAccount:constructor","time":260.847119,"oracles":{"getContractInstance":{"times":[3.35518299999967]},"debugLog":{"times":[0.48098299999946903,0.4124469999997018]},"fetchTaggedLogs":{"times":[39.81106399999953]},"loadCapsule":{"times":[0.3475480000006428,0.26250399999935325,0.26430100000015955]},"validateEnqueuedNotesAndEvents":{"times":[1.7707049999999072]},"notifyCreatedNullifier":{"times":[0.2117149999994581,0.24747500000012224]},"notifyCreatedNote":{"times":[0.5411270000004151]},"getRandomField":{"times":[0.2158319999998639,0.15468799999962357,0.10849899999993795,0.08656000000064523,0.08647800000017014,0.0835109999998167,0.08735800000067684,0.07304500000009284,0.07919700000002194,0.08755900000051042,0.08726899999965099]},"getIndexedTaggingSecretAsSender":{"times":[42.21621999999934]},"incrementAppTaggingSecretIndexAsSender":{"times":[24.566436000000067]}}},{"functionName":"private_kernel_inner","time":106.36058400000002},{"functionName":"SchnorrAccount:entrypoint","time":151.71820799999932,"oracles":{"debugLog":{"times":[0.46196300000156043,0.4022340000010445,0.5581020000008721]},"fetchTaggedLogs":{"times":[38.51292700000158]},"loadCapsule":{"times":[0.2292280000001483,0.22120499999982712,0.23720099999991362]},"validateEnqueuedNotesAndEvents":{"times":[1.2835199999990436]},"getNotes":{"times":[2.6211700000003475]},"getAuthWitness":{"times":[0.35318499999993946]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.8141919999998208]}}},{"functionName":"private_kernel_inner","time":105.3274079999992},{"functionName":"private_kernel_reset","time":178.2654470000016},{"functionName":"private_kernel_tail","time":71.7353179999991}],"unaccounted":415.0176040000006}} 09:33:49 [09:33:49.605] VERBOSE: p2p:tx_pool Adding tx 0x021c2ce2a4598a63594dffa4bb32ba0f1909a0e50644bd89169bc65573185e83 to pool {"eventName":"tx-added-to-pool","txHash":"0x021c2ce2a4598a63594dffa4bb32ba0f1909a0e50644bd89169bc65573185e83","noteHashCount":1,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":292848,"feePaymentMethod":"fee_juice"} 09:33:49 [09:33:49.624] INFO: node Received tx 0x021c2ce2a4598a63594dffa4bb32ba0f1909a0e50644bd89169bc65573185e83 {"txHash":"0x021c2ce2a4598a63594dffa4bb32ba0f1909a0e50644bd89169bc65573185e83"} 09:33:49 [09:33:49.624] INFO: pxe:service Sent transaction 0x021c2ce2a4598a63594dffa4bb32ba0f1909a0e50644bd89169bc65573185e83 09:33:49 [09:33:49.672] VERBOSE: sequencer Preparing proposal for block 1 at slot 2 {"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807536,"version":2713600433},"chainTipArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","blockNumber":1,"slot":2} 09:33:49 [09:33:49.683] VERBOSE: block-builder Building block 1 for slot 2 {"slot":2,"slotStart":"2025-06-13T09:38:56.000Z","now":"2025-06-13T09:38:46.752Z","blockNumber":1,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:33:49 [09:33:49.724] VERBOSE: simulator:public-processor Processed tx 0x021c2ce2a4598a63594dffa4bb32ba0f1909a0e50644bd89169bc65573185e83 with no public calls in 20.22037300000011ms {"txHash":"0x021c2ce2a4598a63594dffa4bb32ba0f1909a0e50644bd89169bc65573185e83","txFee":2101212240,"revertCode":0,"gasUsed":{"totalGas":{"daGas":16896,"l2Gas":6324},"billedGas":{"daGas":16896,"l2Gas":6324},"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":20.22037300000011} 09:33:49 [09:33:49.726] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.040119814000001496s {"duration":0.040119814000001496,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":16896,"l2Gas":6324},"totalSizeInBytes":1344} 09:33:49 [09:33:49.752] INFO: sequencer Built block 1 for slot 2 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2099c6d66624bf94751069d7cbc10372324c48d241a3f25ff508db13890cefbc","globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807536,"version":2713600433},"txHashes":["0x021c2ce2a4598a63594dffa4bb32ba0f1909a0e50644bd89169bc65573185e83"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":75.64649199999985,"publicProcessDuration":40.84519199999886,"rollupCircuitsDuration":69.36563699999897,"txCount":1,"blockNumber":1,"blockTimestamp":1749807536,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:33:49 [09:33:49.756] VERBOSE: sequencer Attesting committee is empty 09:33:52 [09:33:52.457] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x791085ad151451d202744c8ec888210bc9ea3bf13c9ee96543256a1518d36b55 09:33:52 [09:33:52.457] VERBOSE: node Sent L1 transaction 0x791085ad151451d202744c8ec888210bc9ea3bf13c9ee96543256a1518d36b55 {"gasLimit":487012,"maxFeePerGas":"114.56009636","maxPriorityFeePerGas":"114.475459934","maxFeePerBlobGas":"0.000000001"} 09:33:52 [09:33:52.468] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:33:52 [09:33:52.469] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x791085ad151451d202744c8ec888210bc9ea3bf13c9ee96543256a1518d36b55","calldataSize":1252,"calldataGas":9964,"txCount":1,"blockNumber":1,"blockTimestamp":1749807536,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:33:52 [09:33:52.469] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:33:52 [09:33:52.490] INFO: blob-sink:server Received blob sidecar for block 0x80c419902c8f5196803607d2ce526a65b36b3aaeb289a363fa17db2e7fa2be49 09:33:52 [09:33:52.496] INFO: blob-sink:server Blob sidecar stored successfully for block 0x80c419902c8f5196803607d2ce526a65b36b3aaeb289a363fa17db2e7fa2be49 09:33:52 [09:33:52.500] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749807572 09:33:52 [09:33:52.500] WARN: foundation:test-date-provider Time set to 2025-06-13T09:39:32.000Z {"offset":339500,"timeMs":1749807572000} 09:33:52 [09:33:52.500] INFO: aztecjs:utils:watcher Slot 2 was filled, jumped to next slot 09:33:52 [09:33:52.589] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 1 09:33:52 [09:33:52.590] INFO: aztecjs:cheat_codes Proven tip moved: 0 -> 1. Pending tip: 1. 09:33:52 [09:33:52.729] VERBOSE: archiver:block-helper Store contract class 0x0b89e6f8d5ceea693e15799442ccf7b89aac377f557925144092ccd99080e097 09:33:52 [09:33:52.738] INFO: archiver Downloaded L2 block 1 {"blockHash":"0x2099c6d66624bf94751069d7cbc10372324c48d241a3f25ff508db13890cefbc","blockNumber":1,"txCount":1,"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807536,"version":2713600433},"archiveRoot":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","archiveNextLeafIndex":2} 09:33:52 [09:33:52.744] INFO: archiver Updated proven chain to block 1 {"provenBlockNumber":1} 09:33:52 [09:33:52.828] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":63.97370799999953,"unfinalisedBlockNumber":1,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":1,"blockTimestamp":1749807536,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:33:52 [09:33:52.829] WARN: pxe:service No artifact found for contract class 0x1c08e94427135be33826fb4eb4ed8c670719158296c30e4f491c082fd10e2af5 when looking for its metadata 09:33:52 [09:33:52.831] INFO: aztecjs:contract_interaction Creating request for registering contract class 0x1c08e94427135be33826fb4eb4ed8c670719158296c30e4f491c082fd10e2af5 as part of deployment for 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26 09:33:52 [09:33:52.926] VERBOSE: p2p Synched to latest block 1 09:33:53 [09:33:53.004] INFO: pxe:service Added contract OffchainMessage at 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26 with class 0x1c08e94427135be33826fb4eb4ed8c670719158296c30e4f491c082fd10e2af5 09:33:53 [09:33:53.023] VERBOSE: sequencer Preparing proposal for block 2 at slot 3 {"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807572,"version":2713600433},"chainTipArchive":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","blockNumber":2,"slot":3} 09:33:53 [09:33:53.034] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","blockNumber":2,"slot":3} 09:33:53 [09:33:53.043] VERBOSE: pxe:service Updated pxe last block to 1 {"blockHash":{},"archive":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","header":{"contentCommitment":{"blobsHash":"0x00b4d9338f54f9015486baa68fc8e4323ce9f3ae6b66ee967e9dfb17d131b214","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":1,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":2,"timestamp":1749807536,"version":2713600433},"lastArchive":"0x1c76ef5f4b9758cf8ada3e1796113be6803a073ce8e610904b44f8e80d87feb1","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x03a8ad632581e7ecbb51ade47d8249fd1720588ef06f47e298a9cb1cba3b5565","nullifierTree":"0x05d4fe652f76b8a001f86210b48c4c6fa9a2c18613c20e9407996b738b4edd33","publicDataTree":"0x0916b5c07f197b0e18cbeb208f93dadd055d2d3d3d3cced082af9d7ac63bc421"},"totalFees":2101212240,"totalManaUsed":6324}} 09:33:53 [09:33:53.133] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:53 [09:33:53.167] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:33:53 [09:33:53.167] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:53 [09:33:53.215] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:53 [09:33:53.225] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2310064e62b0c2a63e49fd1d6524a7e497920151cd3259d71fbe4721a13647b0 09:33:53 [09:33:53.237] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 09:33:53 [09:33:53.238] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 09:33:53 [09:33:53.245] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 09:33:53 [09:33:53.245] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 09:33:53 [09:33:53.251] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:33:53 [09:33:53.268] VERBOSE: pxe:service Added note {"index":0,"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1b6d6b8b17196f9cb898faedb26667bd9f4c431a4a5853f874cf5c6e86b03a54","nullifier":"0x0f9d9fad5f7a42621ac855693bf200de561eb53bb37518aad8da7c4961356297"} 09:33:53 [09:33:53.282] VERBOSE: simulator:client_execution_context:debug_log Setting 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f as fee payer 09:33:53 [09:33:53.325] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:register {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 09:33:53 [09:33:53.640] VERBOSE: simulator:client_execution_context:debug_log ContractClassRegistered: 0x1c08e94427135be33826fb4eb4ed8c670719158296c30e4f491c082fd10e2af5,0x2f592dbe7e087ce36f4eb433cf3894d708f912cd24736847661984e4cdbc9e1d,0x03e247bc05b2cb615b364b2859d5c43f51e70ed747edc4a6dab18dbb557c1b0c,0x136df56b2c789090458197f8589ee9a9215579138440b4fe7cd9d33e8d88151c 09:33:53 [09:33:53.710] VERBOSE: simulator:client_execution_context Emitted log from ContractClassRegisterer: "0000000000000000000000000000000000000000000000000000000000000003000000006999d1e02b08a447a463563453cb..." 09:33:53 [09:33:53.828] VERBOSE: sequencer Preparing proposal for block 2 at slot 3 {"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807572,"version":2713600433},"chainTipArchive":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","blockNumber":2,"slot":3} 09:33:53 [09:33:53.841] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 09:33:53 [09:33:53.854] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","blockNumber":2,"slot":3} 09:33:53 [09:33:53.893] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 09:33:53 [09:33:53.904] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26,0x0000000000000000000000000000000000000000000000000000000000000001,0x072b261470b25631069e005b80dd4b09e065cb0ded7e50f64f3be66ca5fe2c53,0x1c08e94427135be33826fb4eb4ed8c670719158296c30e4f491c082fd10e2af5,0x0000000000000000000000000000000000000000000000000000000000000000,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f 09:33:54 [09:33:54.539] VERBOSE: sequencer Preparing proposal for block 2 at slot 3 {"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807572,"version":2713600433},"chainTipArchive":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","blockNumber":2,"slot":3} 09:33:54 [09:33:54.654] VERBOSE: sequencer Not enough txs to build block 2 at slot 3 (got 0 txs, need 1) {"chainTipArchive":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","blockNumber":2,"slot":3} 09:33:54 [09:33:54.901] INFO: pxe:service Proving completed in 1869.2901770000008ms {"timings":{"total":1869.2901770000008,"sync":12.806325999999899,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":258.2199500000006,"oracles":{"debugLog":{"times":[0.4602969999996276,0.39054899999973713,0.42270599999937986,0.5597689999995055,0.4044750000011845,0.3410569999996369,0.3608569999996689,0.4415100000005623]},"fetchTaggedLogs":{"times":[52.14389300000039]},"loadCapsule":{"times":[1.8965800000005402,0.40734599999996135,2.030929999998989,0.2496790000004694,0.1882690000002185,0.27863199999956123,1.093708999998853]},"getSharedSecret":{"times":[4.236261000000013]},"aes128Decrypt":{"times":[0.9063509999996313,0.9736339999999473]},"getPublicKeysAndPartialAddress":{"times":[3.130381999999372]},"getKeyValidationRequest":{"times":[1.8706310000015947]},"storeCapsule":{"times":[0.5365540000002511,1.0831559999987803,1.2823459999999614]},"deleteCapsule":{"times":[1.1684780000014143]},"validateEnqueuedNotesAndEvents":{"times":[16.15947100000085]},"getNotes":{"times":[3.020908000000418]},"getAuthWitness":{"times":[0.2159190000002127]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.08448800000041956]},"callPrivateFunction":{"times":[479.0421940000015,145.19071299999996]}}},{"functionName":"private_kernel_init","time":58.49132800000007},{"functionName":"ContractClassRegisterer:register","time":461.22997099999884,"oracles":{"loadCapsule":{"times":[3.6483619999999064]},"notifyCreatedNullifier":{"times":[0.309332999999242]},"debugLog":{"times":[0.45034599999962666]},"notifyCreatedContractClassLog":{"times":[6.767737000000125]}}},{"functionName":"private_kernel_inner","time":110.52534000000014},{"functionName":"ContractInstanceDeployer:deploy","time":91.03135800000018,"oracles":{"storeInExecutionCache":{"times":[0.16527700000006007]},"callPrivateFunction":{"times":[53.68294600000081]},"notifyCreatedNullifier":{"times":[0.25771700000041164]},"debugLog":{"times":[0.45212500000161526]}}},{"functionName":"private_kernel_inner","time":104.35483900000145},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":36.70910499999991,"oracles":{}},{"functionName":"private_kernel_inner","time":106.87016099999892},{"functionName":"private_kernel_reset","time":178.7273640000003},{"functionName":"private_kernel_tail","time":67.65259500000138}],"unaccounted":382.67183999999907}} 09:33:54 [09:33:54.984] VERBOSE: p2p:tx_pool Adding tx 0x2838920acc1e8d118121df08997cf674f5d6a2d4a9de9376fc09b0aca4721369 to pool {"eventName":"tx-added-to-pool","txHash":"0x2838920acc1e8d118121df08997cf674f5d6a2d4a9de9376fc09b0aca4721369","noteHashCount":0,"nullifierCount":3,"privateLogCount":1,"classRegisteredCount":1,"contractClassLogSize":7,"proofSize":172052,"size":292848,"feePaymentMethod":"fee_juice"} 09:33:54 [09:33:54.998] INFO: node Received tx 0x2838920acc1e8d118121df08997cf674f5d6a2d4a9de9376fc09b0aca4721369 {"txHash":"0x2838920acc1e8d118121df08997cf674f5d6a2d4a9de9376fc09b0aca4721369"} 09:33:54 [09:33:54.999] INFO: pxe:service Sent transaction 0x2838920acc1e8d118121df08997cf674f5d6a2d4a9de9376fc09b0aca4721369 09:33:55 [09:33:55.176] VERBOSE: sequencer Preparing proposal for block 2 at slot 3 {"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807572,"version":2713600433},"chainTipArchive":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","blockNumber":2,"slot":3} 09:33:55 [09:33:55.194] VERBOSE: block-builder Building block 2 for slot 3 {"slot":3,"slotStart":"2025-06-13T09:39:32.000Z","now":"2025-06-13T09:39:34.694Z","blockNumber":2,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:33:55 [09:33:55.229] VERBOSE: simulator:public-processor Processed tx 0x2838920acc1e8d118121df08997cf674f5d6a2d4a9de9376fc09b0aca4721369 with no public calls in 18.116807000000335ms {"txHash":"0x2838920acc1e8d118121df08997cf674f5d6a2d4a9de9376fc09b0aca4721369","txFee":1675254920,"revertCode":0,"gasUsed":{"totalGas":{"daGas":14848,"l2Gas":5042},"billedGas":{"daGas":14848,"l2Gas":5042},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":3,"noteHashCount":0,"contractClassLogCount":1,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":18.116807000000335} 09:33:55 [09:33:55.230] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.03392700299999887s {"duration":0.03392700299999887,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":14848,"l2Gas":5042},"totalSizeInBytes":1184} 09:33:55 [09:33:55.249] INFO: sequencer Built block 2 for slot 3 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1f2b0dea5bc71d8563ff0383051a87f3568d3fe3dfe69731420c742a32e80326","globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807572,"version":2713600433},"txHashes":["0x2838920acc1e8d118121df08997cf674f5d6a2d4a9de9376fc09b0aca4721369"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":59.77482600000076,"publicProcessDuration":34.24926100000084,"rollupCircuitsDuration":55.2514880000017,"txCount":1,"blockNumber":2,"blockTimestamp":1749807572,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:33:55 [09:33:55.251] VERBOSE: sequencer Attesting committee is empty 09:33:55 [09:33:55.414] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xe9212e9bd5e1e265d5f2d43b6ebb006b126a28e07ed649a830a024eabacca58c 09:33:55 [09:33:55.414] VERBOSE: node Sent L1 transaction 0xe9212e9bd5e1e265d5f2d43b6ebb006b126a28e07ed649a830a024eabacca58c {"gasLimit":407311,"maxFeePerGas":"1.265176207","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 09:33:55 [09:33:55.430] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:33:55 [09:33:55.430] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xe9212e9bd5e1e265d5f2d43b6ebb006b126a28e07ed649a830a024eabacca58c","calldataSize":1252,"calldataGas":9964,"txCount":1,"blockNumber":2,"blockTimestamp":1749807572,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:33:55 [09:33:55.430] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:33:55 [09:33:55.433] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749807608 09:33:55 [09:33:55.433] WARN: foundation:test-date-provider Time set to 2025-06-13T09:40:08.000Z {"offset":372567,"timeMs":1749807608000} 09:33:55 [09:33:55.433] INFO: aztecjs:utils:watcher Slot 3 was filled, jumped to next slot 09:33:55 [09:33:55.434] INFO: blob-sink:server Received blob sidecar for block 0xa63090ee70f48c6b4851f58791c23a3641cde28746db8b8f7e055621dcff033d 09:33:55 [09:33:55.438] INFO: blob-sink:server Blob sidecar stored successfully for block 0xa63090ee70f48c6b4851f58791c23a3641cde28746db8b8f7e055621dcff033d 09:33:55 [09:33:55.615] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 2 09:33:55 [09:33:55.618] INFO: aztecjs:cheat_codes Proven tip moved: 1 -> 2. Pending tip: 2. 09:33:55 [09:33:55.746] VERBOSE: archiver:block-helper Store contract instance at 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26 09:33:55 [09:33:55.747] VERBOSE: archiver:block-helper Store contract class 0x1c08e94427135be33826fb4eb4ed8c670719158296c30e4f491c082fd10e2af5 09:33:55 [09:33:55.753] INFO: archiver Downloaded L2 block 2 {"blockHash":"0x1f2b0dea5bc71d8563ff0383051a87f3568d3fe3dfe69731420c742a32e80326","blockNumber":2,"txCount":1,"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807572,"version":2713600433},"archiveRoot":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","archiveNextLeafIndex":3} 09:33:55 [09:33:55.759] INFO: archiver Updated proven chain to block 2 {"provenBlockNumber":2} 09:33:55 [09:33:55.856] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":5.892659999999523,"unfinalisedBlockNumber":2,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":2,"blockTimestamp":1749807572,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":1,"contractClassLogSize":7} 09:33:55 [09:33:55.874] VERBOSE: p2p Synched to latest block 2 09:33:55 [09:33:55.948] VERBOSE: sequencer Preparing proposal for block 3 at slot 4 {"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807608,"version":2713600433},"chainTipArchive":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","blockNumber":3,"slot":4} 09:33:55 [09:33:55.949] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","blockNumber":3,"slot":4} 09:33:56 [09:33:56.028] INFO: aztecjs:deploy_sent_tx Contract 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26 successfully deployed. 09:33:56 [09:33:56.220] INFO: pxe:service Added contract OffchainMessage at 0x243de5f6469106e032d680427a97dce099188077fdc73081193b17c87a9adaa5 with class 0x1c08e94427135be33826fb4eb4ed8c670719158296c30e4f491c082fd10e2af5 09:33:56 [09:33:56.240] VERBOSE: pxe:service Updated pxe last block to 2 {"blockHash":{},"archive":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","header":{"contentCommitment":{"blobsHash":"0x001de325f5a90174d134a5e4e67db7a8f78cbc4a033970ccdce8a9a79a30eb87","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":2,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":3,"timestamp":1749807572,"version":2713600433},"lastArchive":"0x20a4a73d22f2bec4c7f1d232d8cf9d4402cb40fc1c5f57ae63b1b79648582318","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x03a8ad632581e7ecbb51ade47d8249fd1720588ef06f47e298a9cb1cba3b5565","nullifierTree":"0x11988dd8d5ffea559bfe4c95fd079619330c5d5f80b35842cb9b8b1c76ccc22b","publicDataTree":"0x190c08ffbe04c03b608a4262f2f890c109b268ab928310ae3ce2f2dd90ae41c2"},"totalFees":1675254920,"totalManaUsed":5042}} 09:33:56 [09:33:56.317] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:56 [09:33:56.350] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:33:56 [09:33:56.351] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:56 [09:33:56.410] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:56 [09:33:56.415] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2310064e62b0c2a63e49fd1d6524a7e497920151cd3259d71fbe4721a13647b0 09:33:56 [09:33:56.424] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 09:33:56 [09:33:56.425] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 09:33:56 [09:33:56.430] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 09:33:56 [09:33:56.431] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 09:33:56 [09:33:56.433] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:33:56 [09:33:56.436] VERBOSE: pxe:service Added note {"index":0,"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1b6d6b8b17196f9cb898faedb26667bd9f4c431a4a5853f874cf5c6e86b03a54","nullifier":"0x0f9d9fad5f7a42621ac855693bf200de561eb53bb37518aad8da7c4961356297"} 09:33:56 [09:33:56.448] VERBOSE: simulator:client_execution_context:debug_log Setting 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f as fee payer 09:33:56 [09:33:56.503] VERBOSE: simulator:private_execution Executing private function ContractInstanceDeployer:deploy {"contract":"0x0000000000000000000000000000000000000000000000000000000000000002"} 09:33:56 [09:33:56.518] VERBOSE: sequencer Preparing proposal for block 3 at slot 4 {"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807608,"version":2713600433},"chainTipArchive":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","blockNumber":3,"slot":4} 09:33:56 [09:33:56.519] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","blockNumber":3,"slot":4} 09:33:56 [09:33:56.556] VERBOSE: simulator:private_execution Executing private function ContractClassRegisterer:assert_class_id_is_registered {"contract":"0x0000000000000000000000000000000000000000000000000000000000000003"} 09:33:56 [09:33:56.567] VERBOSE: simulator:client_execution_context:debug_log ContractInstanceDeployed: 0x0000000085864497636cf755ae7bde03f267ce01a520981c21c3682aaf82a631,0x243de5f6469106e032d680427a97dce099188077fdc73081193b17c87a9adaa5,0x0000000000000000000000000000000000000000000000000000000000000001,0x0f326ab62245c79e170d8b82e84204012aca0768d7773927641ce71423d2f2d7,0x1c08e94427135be33826fb4eb4ed8c670719158296c30e4f491c082fd10e2af5,0x0000000000000000000000000000000000000000000000000000000000000000,0x01498945581e0eb9f8427ad6021184c700ef091d570892c437d12c7d90364bbd,0x170ae506787c5c43d6ca9255d571c10fa9ffa9d141666e290c347c5c9ab7e344,0x00c044b05b6ca83b9c2dbae79cc1135155956a64e136819136e9947fe5e5866c,0x1c1f0ca244c7cd46b682552bff8ae77dea40b966a71de076ec3b7678f2bdb151,0x1b00316144359e9a3ec8e49c1cdb7eeb0cedd190dfd9dc90eea5115aa779e287,0x080ffc74d7a8b0bccb88ac11f45874172f3847eb8b92654aaa58a3d2b8dc7833,0x019c111f36ad3fc1d9b7a7a14344314d2864b94f030594cd67f753ef774a1efb,0x2039907fe37f08d10739255141bb066c506a12f7d1e8dfec21abc58494705b6f,0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f 09:33:57 [09:33:57.357] INFO: pxe:service Proving completed in 1124.542617000001ms {"timings":{"total":1124.542617000001,"sync":10.163431000000855,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":205.56877600000007,"oracles":{"debugLog":{"times":[0.4404770000001008,0.38378199999897333,0.42395399999986694,0.49198299999989104,0.3987269999997807,0.34409599999889906,0.3035060000001977,0.45201100000122096]},"fetchTaggedLogs":{"times":[62.35025499999938]},"loadCapsule":{"times":[0.18855099999927916,0.1513169999998354,0.3576020000000426,0.1926629999998113,0.14026099999864527,0.1412369999998191,0.17555499999980384]},"getSharedSecret":{"times":[2.7436369999995804]},"aes128Decrypt":{"times":[0.21839800000088871,0.8536540000004607]},"getPublicKeysAndPartialAddress":{"times":[1.2406350000001112]},"getKeyValidationRequest":{"times":[1.5167879999989964]},"storeCapsule":{"times":[0.27341399999932037,0.22680200000104378,0.19057400000019697]},"deleteCapsule":{"times":[0.18193000000064785]},"validateEnqueuedNotesAndEvents":{"times":[3.584302999999636]},"getNotes":{"times":[1.2101069999989704]},"getAuthWitness":{"times":[0.19464800000059768]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.06346699999994598]},"callPrivateFunction":{"times":[122.76339600000028]}}},{"functionName":"private_kernel_init","time":57.55427000000054},{"functionName":"ContractInstanceDeployer:deploy","time":67.15204099999937,"oracles":{"storeInExecutionCache":{"times":[0.07387000000016997]},"callPrivateFunction":{"times":[55.18136600000071]},"notifyCreatedNullifier":{"times":[0.2956530000010389]},"debugLog":{"times":[0.45944800000143005]}}},{"functionName":"private_kernel_inner","time":106.26022400000147},{"functionName":"ContractClassRegisterer:assert_class_id_is_registered","time":37.78818499999943,"oracles":{}},{"functionName":"private_kernel_inner","time":108.93584600000031},{"functionName":"private_kernel_reset","time":189.78717299999698},{"functionName":"private_kernel_tail","time":69.6053209999991}],"unaccounted":271.72735000000284}} 09:33:57 [09:33:57.388] VERBOSE: sequencer Preparing proposal for block 3 at slot 4 {"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807608,"version":2713600433},"chainTipArchive":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","blockNumber":3,"slot":4} 09:33:57 [09:33:57.391] VERBOSE: sequencer Not enough txs to build block 3 at slot 4 (got 0 txs, need 1) {"chainTipArchive":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","blockNumber":3,"slot":4} 09:33:57 [09:33:57.391] VERBOSE: p2p:tx_pool Adding tx 0x0df301bda5993de2bcd65cce443800a41ba9449119261633021dbe5e906326bc to pool {"eventName":"tx-added-to-pool","txHash":"0x0df301bda5993de2bcd65cce443800a41ba9449119261633021dbe5e906326bc","noteHashCount":0,"nullifierCount":2,"privateLogCount":1,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196240,"feePaymentMethod":"fee_juice"} 09:33:57 [09:33:57.395] INFO: node Received tx 0x0df301bda5993de2bcd65cce443800a41ba9449119261633021dbe5e906326bc {"txHash":"0x0df301bda5993de2bcd65cce443800a41ba9449119261633021dbe5e906326bc"} 09:33:57 [09:33:57.396] INFO: pxe:service Sent transaction 0x0df301bda5993de2bcd65cce443800a41ba9449119261633021dbe5e906326bc 09:33:57 [09:33:57.906] VERBOSE: sequencer Preparing proposal for block 3 at slot 4 {"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807608,"version":2713600433},"chainTipArchive":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","blockNumber":3,"slot":4} 09:33:57 [09:33:57.916] VERBOSE: block-builder Building block 3 for slot 4 {"slot":4,"slotStart":"2025-06-13T09:40:08.000Z","now":"2025-06-13T09:40:10.483Z","blockNumber":3,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:33:57 [09:33:57.936] VERBOSE: simulator:public-processor Processed tx 0x0df301bda5993de2bcd65cce443800a41ba9449119261633021dbe5e906326bc with no public calls in 12.509819000002608ms {"txHash":"0x0df301bda5993de2bcd65cce443800a41ba9449119261633021dbe5e906326bc","txFee":1173542320,"revertCode":0,"gasUsed":{"totalGas":{"daGas":9728,"l2Gas":3532},"billedGas":{"daGas":9728,"l2Gas":3532},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":2,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":1,"l2ToL1MessageCount":0,"durationMs":12.509819000002608} 09:33:57 [09:33:57.937] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.019010457000000314s {"duration":0.019010457000000314,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":9728,"l2Gas":3532},"totalSizeInBytes":832} 09:33:57 [09:33:57.956] INFO: sequencer Built block 3 for slot 4 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x1ac2bb2d3320426960933d15965a77c2ec0ae3dc273d181da401edf1d878166d","globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807608,"version":2713600433},"txHashes":["0x0df301bda5993de2bcd65cce443800a41ba9449119261633021dbe5e906326bc"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":44.53625000000102,"publicProcessDuration":19.293102000003273,"rollupCircuitsDuration":39.995317000000796,"txCount":1,"blockNumber":3,"blockTimestamp":1749807608,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:33:57 [09:33:57.958] VERBOSE: sequencer Attesting committee is empty 09:33:58 [09:33:58.119] VERBOSE: ethereum:tx_delayer Sent tx immediately 0x5d354edc44a192a67973b4b6da6521547263740974d6426eeac5544980b29888 09:33:58 [09:33:58.119] VERBOSE: node Sent L1 transaction 0x5d354edc44a192a67973b4b6da6521547263740974d6426eeac5544980b29888 {"gasLimit":407311,"maxFeePerGas":"1.250031713","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 09:33:58 [09:33:58.128] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:33:58 [09:33:58.129] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0x5d354edc44a192a67973b4b6da6521547263740974d6426eeac5544980b29888","calldataSize":1252,"calldataGas":9964,"txCount":1,"blockNumber":3,"blockTimestamp":1749807608,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:33:58 [09:33:58.129] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:33:58 [09:33:58.134] INFO: blob-sink:server Received blob sidecar for block 0x4b7ddbd301ec038db17d47cabb0531fc23a8ccf5bba73de1c55a30cf704a4d83 09:33:58 [09:33:58.138] INFO: blob-sink:server Blob sidecar stored successfully for block 0x4b7ddbd301ec038db17d47cabb0531fc23a8ccf5bba73de1c55a30cf704a4d83 09:33:58 [09:33:58.197] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:33:58 [09:33:58.198] WARN: foundation:test-date-provider Time set to 2025-06-13T09:40:20.000Z {"offset":381802,"timeMs":1749807620000} 09:33:58 [09:33:58.201] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 3 09:33:58 [09:33:58.203] INFO: aztecjs:cheat_codes Proven tip moved: 2 -> 3. Pending tip: 3. 09:33:58 [09:33:58.230] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749807644 09:33:58 [09:33:58.231] WARN: foundation:test-date-provider Time set to 2025-06-13T09:40:44.000Z {"offset":405769,"timeMs":1749807644000} 09:33:58 [09:33:58.231] INFO: aztecjs:utils:watcher Slot 4 was filled, jumped to next slot 09:33:58 [09:33:58.651] VERBOSE: sequencer Cannot propose block 3 at next L2 slot 5 due to pending sync from L1 {"syncedToL1Ts":1749807608,"syncedToL2Slot":4,"nextSlot":5,"nextSlotTs":1749807644,"l1SlotDuration":12} 09:33:58 [09:33:58.702] VERBOSE: archiver:block-helper Store contract instance at 0x243de5f6469106e032d680427a97dce099188077fdc73081193b17c87a9adaa5 09:33:58 [09:33:58.703] INFO: archiver Downloaded L2 block 3 {"blockHash":"0x1ac2bb2d3320426960933d15965a77c2ec0ae3dc273d181da401edf1d878166d","blockNumber":3,"txCount":1,"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807608,"version":2713600433},"archiveRoot":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","archiveNextLeafIndex":4} 09:33:58 [09:33:58.704] INFO: archiver Updated proven chain to block 3 {"provenBlockNumber":3} 09:33:58 [09:33:58.739] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":5.744736000000557,"unfinalisedBlockNumber":3,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":3,"blockTimestamp":1749807608,"privateLogCount":1,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:33:58 [09:33:58.743] VERBOSE: p2p Synched to latest block 3 09:33:59 [09:33:59.163] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:33:59 [09:33:59.164] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:33:59 [09:33:59.424] INFO: aztecjs:deploy_sent_tx Contract 0x243de5f6469106e032d680427a97dce099188077fdc73081193b17c87a9adaa5 successfully deployed. 09:33:59 [09:33:59.426] INFO: e2e:e2e_offchain_message Running test: e2e_offchain_message should emit offchain message 09:33:59 [09:33:59.451] VERBOSE: pxe:service Updated pxe last block to 3 {"blockHash":{},"archive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","header":{"contentCommitment":{"blobsHash":"0x00257b11d3826dd87a0c511e7e54d3633117e9c8aad40a102d02df393dcd2e07","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":3,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":332260,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":4,"timestamp":1749807608,"version":2713600433},"lastArchive":"0x2b51d93a9a8d9c70af4341a054ad9134df9fd27eafe270506270204910afd5aa","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x03a8ad632581e7ecbb51ade47d8249fd1720588ef06f47e298a9cb1cba3b5565","nullifierTree":"0x1368d9627f87cd438e5f4e81407e3b4e13df43b3e32c86d495248c396f69207f","publicDataTree":"0x01b42f05b00bb0f0fb56540382f169bb94de26e1098baa294f193f87d341f55d"},"totalFees":1173542320,"totalManaUsed":3532}} 09:33:59 [09:33:59.527] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:59 [09:33:59.560] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:33:59 [09:33:59.561] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:59 [09:33:59.603] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:33:59 [09:33:59.607] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2310064e62b0c2a63e49fd1d6524a7e497920151cd3259d71fbe4721a13647b0 09:33:59 [09:33:59.616] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 09:33:59 [09:33:59.616] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 09:33:59 [09:33:59.623] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 09:33:59 [09:33:59.623] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 09:33:59 [09:33:59.625] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:33:59 [09:33:59.628] VERBOSE: pxe:service Added note {"index":0,"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1b6d6b8b17196f9cb898faedb26667bd9f4c431a4a5853f874cf5c6e86b03a54","nullifier":"0x0f9d9fad5f7a42621ac855693bf200de561eb53bb37518aad8da7c4961356297"} 09:33:59 [09:33:59.642] VERBOSE: simulator:client_execution_context:debug_log Setting 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f as fee payer 09:33:59 [09:33:59.719] VERBOSE: simulator:private_execution Executing private function OffchainMessage:emit_offchain_message_for_recipient {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:33:59 [09:33:59.755] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:33:59 [09:33:59.756] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:33:59 [09:33:59.800] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:33:59 [09:33:59.805] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:33:59 [09:33:59.812] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:33:59 [09:33:59.815] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:33:59 [09:33:59.961] VERBOSE: simulator:private_execution Executing private function OffchainMessage:emit_offchain_message_for_recipient {"contract":"0x243de5f6469106e032d680427a97dce099188077fdc73081193b17c87a9adaa5"} 09:34:00 [09:34:00.015] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:34:00 [09:34:00.015] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x243de5f6469106e032d680427a97dce099188077fdc73081193b17c87a9adaa5"} 09:34:00 [09:34:00.057] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x243de5f6469106e032d680427a97dce099188077fdc73081193b17c87a9adaa5"} 09:34:00 [09:34:00.060] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:00 [09:34:00.144] VERBOSE: simulator:private_execution Executing private function OffchainMessage:emit_offchain_message_for_recipient {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:00 [09:34:00.178] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:34:00 [09:34:00.179] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:00 [09:34:00.214] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:00 [09:34:00.217] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:00 [09:34:00.447] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:00 [09:34:00.482] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:01 [09:34:01.282] INFO: pxe:service Proving completed in 1835.3134559999999ms {"timings":{"total":1835.3134559999999,"sync":5.793212000000494,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":202.15927700000248,"oracles":{"debugLog":{"times":[0.42687800000203424,0.35869800000000396,0.366479000000254,0.48990000000048894,0.3792570000005071,0.31652799999937997,0.33641399999760324,0.4245329999976093]},"fetchTaggedLogs":{"times":[44.24570799999856]},"loadCapsule":{"times":[0.1844679999994696,0.145355999997264,0.3253440000007686,0.19927699999971082,0.17580099999759113,0.15079400000104215,0.1734550000001036]},"getSharedSecret":{"times":[2.6083639999997104]},"aes128Decrypt":{"times":[0.2146740000025602,0.812554000000091]},"getPublicKeysAndPartialAddress":{"times":[1.2095919999992475]},"getKeyValidationRequest":{"times":[2.749492000002647]},"storeCapsule":{"times":[0.25762400000166963,0.1979460000002291,0.34824700000172015]},"deleteCapsule":{"times":[0.18819799999982934]},"validateEnqueuedNotesAndEvents":{"times":[3.5233600000028673]},"getNotes":{"times":[2.7858719999967434]},"getAuthWitness":{"times":[0.2056090000005497]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.055001000000629574]},"callPrivateFunction":{"times":[611.929830000001]}}},{"functionName":"private_kernel_init","time":52.81415299999935},{"functionName":"OffchainMessage:emit_offchain_message_for_recipient","time":192.2940529999978,"oracles":{"debugLog":{"times":[0.4621530000003986,0.37575599999763654]},"fetchTaggedLogs":{"times":[58.458552000000054]},"loadCapsule":{"times":[0.209538999999495,0.14947400000164635,0.18980500000179745]},"validateEnqueuedNotesAndEvents":{"times":[0.9646670000001905]},"emitOffchainMessage":{"times":[0.19563799999741605]},"storeInExecutionCache":{"times":[0.11939999999958673]},"callPrivateFunction":{"times":[419.09418899999946]}}},{"functionName":"private_kernel_inner","time":102.92233399999895},{"functionName":"OffchainMessage:emit_offchain_message_for_recipient","time":253.26863500000036,"oracles":{"debugLog":{"times":[0.4778890000015963,0.3523280000008526]},"fetchTaggedLogs":{"times":[43.73511800000051]},"loadCapsule":{"times":[0.17676600000049802,0.14740200000233017,0.1881150000008347]},"validateEnqueuedNotesAndEvents":{"times":[1.0314930000022287]},"emitOffchainMessage":{"times":[0.049651000001176726]},"storeInExecutionCache":{"times":[0.11504799999966053]},"callPrivateFunction":{"times":[165.43293500000073]}}},{"functionName":"private_kernel_inner","time":103.18442499999946},{"functionName":"OffchainMessage:emit_offchain_message_for_recipient","time":137.52222600000096,"oracles":{"debugLog":{"times":[0.434320000000298,0.3495970000003581]},"fetchTaggedLogs":{"times":[37.383804999997665]},"loadCapsule":{"times":[0.16392700000142213,0.14364299999942887,0.19939399999930174]},"validateEnqueuedNotesAndEvents":{"times":[1.0577440000015486]},"emitOffchainMessage":{"times":[0.05878800000209594]}}},{"functionName":"private_kernel_inner","time":102.86514700000043},{"functionName":"private_kernel_reset","time":179.77949100000114},{"functionName":"private_kernel_tail","time":66.80914399999892}],"unaccounted":435.9013589999995}} 09:34:01 [09:34:01.286] INFO: e2e:e2e_offchain_message Running test: e2e_offchain_message should not emit any offchain messages 09:34:01 [09:34:01.316] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:01 [09:34:01.319] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:01 [09:34:01.393] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:34:01 [09:34:01.427] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:34:01 [09:34:01.427] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:34:01 [09:34:01.463] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:34:01 [09:34:01.467] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2310064e62b0c2a63e49fd1d6524a7e497920151cd3259d71fbe4721a13647b0 09:34:01 [09:34:01.476] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 09:34:01 [09:34:01.476] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 09:34:01 [09:34:01.481] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 09:34:01 [09:34:01.482] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 09:34:01 [09:34:01.484] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:01 [09:34:01.487] VERBOSE: pxe:service Added note {"index":0,"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1b6d6b8b17196f9cb898faedb26667bd9f4c431a4a5853f874cf5c6e86b03a54","nullifier":"0x0f9d9fad5f7a42621ac855693bf200de561eb53bb37518aad8da7c4961356297"} 09:34:01 [09:34:01.498] VERBOSE: simulator:client_execution_context:debug_log Setting 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f as fee payer 09:34:01 [09:34:01.579] VERBOSE: simulator:private_execution Executing private function OffchainMessage:emit_offchain_message_for_recipient {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:01 [09:34:01.613] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:34:01 [09:34:01.613] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:01 [09:34:01.651] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:01 [09:34:01.653] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:01 [09:34:01.923] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:02 [09:34:02.035] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:02 [09:34:02.280] INFO: pxe:service Proving completed in 977.395539000001ms {"timings":{"total":977.395539000001,"sync":15.568736000001081,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":194.9582730000002,"oracles":{"debugLog":{"times":[0.4092710000004445,0.382355000001553,0.38946999999825493,0.5258690000009665,0.40766699999949196,0.3427479999991192,0.31304000000091037,0.4458940000004077]},"fetchTaggedLogs":{"times":[38.182629999999335]},"loadCapsule":{"times":[0.20380199999999604,0.1459450000002107,0.3580309999997553,0.212336000000505,0.1317750000016531,0.16609199999948032,0.17399500000101398]},"getSharedSecret":{"times":[2.5560310000000754]},"aes128Decrypt":{"times":[0.2040759999981674,0.8499850000007427]},"getPublicKeysAndPartialAddress":{"times":[1.2298549999977695]},"getKeyValidationRequest":{"times":[1.220686000000569]},"storeCapsule":{"times":[0.3005169999996724,0.19458900000245194,0.20130300000164425]},"deleteCapsule":{"times":[0.19587699999829056]},"validateEnqueuedNotesAndEvents":{"times":[3.619623999999021]},"getNotes":{"times":[1.0175280000003113]},"getAuthWitness":{"times":[0.19771699999910197]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.050994000001082895]},"callPrivateFunction":{"times":[170.69577299999946]}}},{"functionName":"private_kernel_init","time":52.455808000002435},{"functionName":"OffchainMessage:emit_offchain_message_for_recipient","time":141.92982499999925,"oracles":{"debugLog":{"times":[0.4053060000005644,0.3901640000003681]},"fetchTaggedLogs":{"times":[39.29888900000151]},"loadCapsule":{"times":[0.19230400000014924,0.15031200000157696,0.1821799999997893]},"validateEnqueuedNotesAndEvents":{"times":[1.1170300000012503]}}},{"functionName":"private_kernel_inner","time":102.73961100000088},{"functionName":"private_kernel_reset","time":183.07386699999915},{"functionName":"private_kernel_tail","time":66.919206999999}],"unaccounted":219.750211999999}} 09:34:02 [09:34:02.281] INFO: e2e:e2e_offchain_message Running test: e2e_offchain_message should revert when emitting offchain message from utility function 09:34:02 [09:34:02.374] VERBOSE: simulator Executing utility function emitting_offchain_message_from_utility_reverts {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26","selector":"0xaa12a861"} 09:34:02 [09:34:02.382] VERBOSE: simulator:client_view_context:debug_log Performing message discovery 09:34:02 [09:34:02.382] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:02 [09:34:02.416] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:02 [09:34:02.418] VERBOSE: simulator:client_view_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:02 [09:34:02.427] INFO: e2e:e2e_offchain_message Running test: e2e_offchain_message should emit event as offchain message and process it 09:34:02 [09:34:02.509] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:34:02 [09:34:02.542] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:34:02 [09:34:02.543] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:34:02 [09:34:02.590] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:02 [09:34:02.592] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:02 [09:34:02.598] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:34:02 [09:34:02.602] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2310064e62b0c2a63e49fd1d6524a7e497920151cd3259d71fbe4721a13647b0 09:34:02 [09:34:02.611] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 09:34:02 [09:34:02.612] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 09:34:02 [09:34:02.616] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 09:34:02 [09:34:02.617] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 09:34:02 [09:34:02.620] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:02 [09:34:02.624] VERBOSE: pxe:service Added note {"index":0,"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1b6d6b8b17196f9cb898faedb26667bd9f4c431a4a5853f874cf5c6e86b03a54","nullifier":"0x0f9d9fad5f7a42621ac855693bf200de561eb53bb37518aad8da7c4961356297"} 09:34:02 [09:34:02.635] VERBOSE: simulator:client_execution_context:debug_log Setting 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f as fee payer 09:34:02 [09:34:02.712] VERBOSE: simulator:private_execution Executing private function OffchainMessage:emit_event_as_offchain_message_for_msg_sender {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:02 [09:34:02.729] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:34:02 [09:34:02.729] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:02 [09:34:02.763] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:02 [09:34:02.765] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:03 [09:34:03.376] INFO: pxe:service Proving completed in 943.862047999999ms {"timings":{"total":943.862047999999,"sync":2.5439360000018496,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":213.10560700000133,"oracles":{"debugLog":{"times":[0.447359999998298,0.36500400000295485,0.3737379999984114,0.4859440000000177,0.3870509999978822,0.31642700000156765,0.34167900000102236,0.4278779999986]},"fetchTaggedLogs":{"times":[57.409803000002285]},"loadCapsule":{"times":[0.17281800000273506,0.14083899999968708,0.31187300000237883,0.188136000000668,0.15141900000162423,0.1819869999999355,0.18094199999904959]},"getSharedSecret":{"times":[2.456802999997308]},"aes128Decrypt":{"times":[0.34580300000015995,1.015105999998923]},"getPublicKeysAndPartialAddress":{"times":[1.122467000001052]},"getKeyValidationRequest":{"times":[1.1761719999994966]},"storeCapsule":{"times":[0.2689490000011574,0.190772999998444,0.23951400000078138]},"deleteCapsule":{"times":[1.553223999999318]},"validateEnqueuedNotesAndEvents":{"times":[3.4445290000003297]},"getNotes":{"times":[1.0760660000014468]},"getAuthWitness":{"times":[0.1964269999989483]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.059454999998706626]},"callPrivateFunction":{"times":[139.71015799999805]}}},{"functionName":"private_kernel_init","time":52.035218999997596},{"functionName":"OffchainMessage:emit_event_as_offchain_message_for_msg_sender","time":112.16269400000238,"oracles":{"debugLog":{"times":[0.4375450000006822,0.35476199999902747]},"fetchTaggedLogs":{"times":[35.21850999999879]},"loadCapsule":{"times":[0.157932999998593,0.15058300000237068,0.17666000000099302]},"validateEnqueuedNotesAndEvents":{"times":[0.8974249999992026]},"getRandomField":{"times":[0.08894700000018929,0.06884400000126334,0.0743320000001404,0.060248999998293584,0.03697699999975157,0.032761000002210494,0.02892499999870779,0.028416000001016073,0.04514999999810243,0.03317900000183727,0.028169000001071254,0.027310000001307344]},"notifyCreatedNullifier":{"times":[0.2371370000000752]},"emitOffchainMessage":{"times":[0.066912999998749]}}},{"functionName":"private_kernel_inner","time":103.07864900000277},{"functionName":"private_kernel_reset","time":179.34022499999992},{"functionName":"private_kernel_tail","time":69.49248100000113}],"unaccounted":212.10323699999208}} 09:34:03 [09:34:03.410] VERBOSE: p2p:tx_pool Adding tx 0x2455cc8689e708b5b470d7f098cdd817bd531dc89538ebce96d01a6c1e0fe67e to pool {"eventName":"tx-added-to-pool","txHash":"0x2455cc8689e708b5b470d7f098cdd817bd531dc89538ebce96d01a6c1e0fe67e","noteHashCount":0,"nullifierCount":2,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196240,"feePaymentMethod":"fee_juice"} 09:34:03 [09:34:03.412] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:03 [09:34:03.415] INFO: node Received tx 0x2455cc8689e708b5b470d7f098cdd817bd531dc89538ebce96d01a6c1e0fe67e {"txHash":"0x2455cc8689e708b5b470d7f098cdd817bd531dc89538ebce96d01a6c1e0fe67e"} 09:34:03 [09:34:03.416] INFO: pxe:service Sent transaction 0x2455cc8689e708b5b470d7f098cdd817bd531dc89538ebce96d01a6c1e0fe67e 09:34:03 [09:34:03.416] VERBOSE: sequencer Not enough txs to build block 4 at slot 5 (got 0 txs, need 1) {"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:03 [09:34:03.928] VERBOSE: sequencer Preparing proposal for block 4 at slot 5 {"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"chainTipArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","blockNumber":4,"slot":5} 09:34:03 [09:34:03.937] VERBOSE: block-builder Building block 4 for slot 5 {"slot":5,"slotStart":"2025-06-13T09:40:44.000Z","now":"2025-06-13T09:40:49.706Z","blockNumber":4,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:34:03 [09:34:03.956] VERBOSE: simulator:public-processor Processed tx 0x2455cc8689e708b5b470d7f098cdd817bd531dc89538ebce96d01a6c1e0fe67e with no public calls in 12.024000999997952ms {"txHash":"0x2455cc8689e708b5b470d7f098cdd817bd531dc89538ebce96d01a6c1e0fe67e","txFee":113659760,"revertCode":0,"gasUsed":{"totalGas":{"daGas":1536,"l2Gas":3532},"billedGas":{"daGas":1536,"l2Gas":3532},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":2,"noteHashCount":0,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":12.024000999997952} 09:34:03 [09:34:03.957] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.018393512999999074s {"duration":0.018393512999999074,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1536,"l2Gas":3532},"totalSizeInBytes":288} 09:34:03 [09:34:03.974] INFO: sequencer Built block 4 for slot 5 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x217573c4db43becb45b21efc75c5067ac39e3f0c2f0b54a16e00b7d0174ff897","globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"txHashes":["0x2455cc8689e708b5b470d7f098cdd817bd531dc89538ebce96d01a6c1e0fe67e"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":42.64632899999924,"publicProcessDuration":18.73591700000179,"rollupCircuitsDuration":37.80196100000103,"txCount":1,"blockNumber":4,"blockTimestamp":1749807644,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:34:03 [09:34:03.976] VERBOSE: sequencer Attesting committee is empty 09:34:04 [09:34:04.134] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xb2d2740602ffb172652964e98f1fd767f448600924a7969070874f59f6ab2b62 09:34:04 [09:34:04.134] VERBOSE: node Sent L1 transaction 0xb2d2740602ffb172652964e98f1fd767f448600924a7969070874f59f6ab2b62 {"gasLimit":407323,"maxFeePerGas":"1.238406227","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 09:34:04 [09:34:04.142] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:34:04 [09:34:04.143] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xb2d2740602ffb172652964e98f1fd767f448600924a7969070874f59f6ab2b62","calldataSize":1252,"calldataGas":9964,"txCount":1,"blockNumber":4,"blockTimestamp":1749807644,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:34:04 [09:34:04.143] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:34:04 [09:34:04.147] INFO: blob-sink:server Received blob sidecar for block 0x3018c371dea6af487d4e1abb28a1a36bf2cde909223ff9b2c4071e324742e54c 09:34:04 [09:34:04.150] INFO: blob-sink:server Blob sidecar stored successfully for block 0x3018c371dea6af487d4e1abb28a1a36bf2cde909223ff9b2c4071e324742e54c 09:34:04 [09:34:04.212] WARN: aztecjs:utils:watcher L1 is ahead of wall time. Syncing wall time to L1 time 09:34:04 [09:34:04.212] WARN: foundation:test-date-provider Time set to 2025-06-13T09:40:56.000Z {"offset":411788,"timeMs":1749807656000} 09:34:04 [09:34:04.216] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 4 09:34:04 [09:34:04.217] INFO: aztecjs:cheat_codes Proven tip moved: 3 -> 4. Pending tip: 4. 09:34:04 [09:34:04.241] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749807680 09:34:04 [09:34:04.241] WARN: foundation:test-date-provider Time set to 2025-06-13T09:41:20.000Z {"offset":435759,"timeMs":1749807680000} 09:34:04 [09:34:04.242] INFO: aztecjs:utils:watcher Slot 5 was filled, jumped to next slot 09:34:04 [09:34:04.660] VERBOSE: sequencer Cannot propose block 4 at next L2 slot 6 due to pending sync from L1 {"syncedToL1Ts":1749807644,"syncedToL2Slot":5,"nextSlot":6,"nextSlotTs":1749807680,"l1SlotDuration":12} 09:34:04 [09:34:04.692] INFO: archiver Downloaded L2 block 4 {"blockHash":"0x217573c4db43becb45b21efc75c5067ac39e3f0c2f0b54a16e00b7d0174ff897","blockNumber":4,"txCount":1,"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"archiveRoot":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","archiveNextLeafIndex":5} 09:34:04 [09:34:04.694] INFO: archiver Updated proven chain to block 4 {"provenBlockNumber":4} 09:34:04 [09:34:04.757] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":5.7855840000011085,"unfinalisedBlockNumber":4,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":4,"blockTimestamp":1749807644,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:34:04 [09:34:04.798] VERBOSE: p2p Synched to latest block 4 09:34:05 [09:34:05.172] VERBOSE: sequencer Preparing proposal for block 5 at slot 6 {"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807680,"version":2713600433},"chainTipArchive":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","blockNumber":5,"slot":6} 09:34:05 [09:34:05.173] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","blockNumber":5,"slot":6} 09:34:05 [09:34:05.425] VERBOSE: pxe:service Updated pxe last block to 4 {"blockHash":{},"archive":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","header":{"contentCommitment":{"blobsHash":"0x00fa5b0b821de74e288f9f9dedcea8e515f29b98e2104aeab8bd878b51b2fdba","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":4,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":5,"timestamp":1749807644,"version":2713600433},"lastArchive":"0x0f3548a20c5a1a7778db40ab8b8dbfa0696a89dc2b5bb275a07dc019ddf727c0","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x03a8ad632581e7ecbb51ade47d8249fd1720588ef06f47e298a9cb1cba3b5565","nullifierTree":"0x1113097d8c01abeac13153674fdd4319eb384eb9bda0f1e919432a351286511c","publicDataTree":"0x21e95d979fe0991d7926fd65f7de0e085d8e9b1dfbb4b133bcfff14917e68c19"},"totalFees":113659760,"totalManaUsed":3532}} 09:34:05 [09:34:05.506] VERBOSE: simulator Executing utility function process_message {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26","selector":"0x988dd306"} 09:34:05 [09:34:05.517] VERBOSE: simulator:client_view_context:debug_log Performing message discovery 09:34:05 [09:34:05.517] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:05 [09:34:05.554] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:05 [09:34:05.556] VERBOSE: simulator:client_view_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:05 [09:34:05.566] VERBOSE: simulator:client_view_context:debug_log Processing private event msg 09:34:05 [09:34:05.567] VERBOSE: simulator Utility simulation for 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26.0x988dd306 completed 09:34:05 [09:34:05.568] VERBOSE: pxe:service Getting private events for 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26 from 4 to 5 09:34:05 [09:34:05.647] VERBOSE: simulator Executing utility function sync_private_state {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26","selector":"0xc6cedc33"} 09:34:05 [09:34:05.658] VERBOSE: simulator:client_view_context:debug_log Performing message discovery 09:34:05 [09:34:05.658] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:05 [09:34:05.701] VERBOSE: sequencer Preparing proposal for block 5 at slot 6 {"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807680,"version":2713600433},"chainTipArchive":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","blockNumber":5,"slot":6} 09:34:05 [09:34:05.701] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:05 [09:34:05.702] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","blockNumber":5,"slot":6} 09:34:05 [09:34:05.705] VERBOSE: simulator:client_view_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:05 [09:34:05.712] VERBOSE: private_event_data_provider storing private event log {"contractAddress":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26","recipient":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f","msgContent":["0x0000000000000000000000000000000000000000000000000000000000000001","0x0000000000000000000000000000000000000000000000000000000000000002","0x0000000000000000000000000000000000000000000000000000000000000003"],"blockNumber":4} 09:34:05 [09:34:05.714] VERBOSE: simulator Utility simulation for 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26.0xc6cedc33 completed 09:34:05 [09:34:05.716] INFO: e2e:e2e_offchain_message Running test: e2e_offchain_message should emit note as offchain message and process it 09:34:05 [09:34:05.809] VERBOSE: simulator:private_execution Executing private function SchnorrAccount:entrypoint {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:34:05 [09:34:05.842] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:34:05 [09:34:05.842] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:34:05 [09:34:05.879] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f"} 09:34:05 [09:34:05.883] VERBOSE: simulator:client_execution_context:debug_log Processing log with tag 0x2310064e62b0c2a63e49fd1d6524a7e497920151cd3259d71fbe4721a13647b0 09:34:05 [09:34:05.892] VERBOSE: simulator:client_execution_context:debug_log Processing private note msg 09:34:05 [09:34:05.893] VERBOSE: simulator:client_execution_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f for storage slot 0x0000000000000000000000000000000000000000000000000000000000000001 09:34:05 [09:34:05.898] VERBOSE: simulator:client_execution_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 09:34:05 [09:34:05.899] VERBOSE: simulator:client_execution_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 09:34:05 [09:34:05.901] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:05 [09:34:05.904] VERBOSE: pxe:service Added note {"index":0,"contract":"0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f","slot":"0x0000000000000000000000000000000000000000000000000000000000000001","noteHash":"0x1b6d6b8b17196f9cb898faedb26667bd9f4c431a4a5853f874cf5c6e86b03a54","nullifier":"0x0f9d9fad5f7a42621ac855693bf200de561eb53bb37518aad8da7c4961356297"} 09:34:05 [09:34:05.916] VERBOSE: simulator:client_execution_context:debug_log Setting 0x211b2dcd0232cd7199cdbe9e426bf687aa2347e3d835950d20b4f81fee0a1b1f as fee payer 09:34:05 [09:34:05.994] VERBOSE: simulator:private_execution Executing private function OffchainMessage:emit_note_as_offchain_message {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:06 [09:34:06.011] VERBOSE: simulator:client_execution_context:debug_log Performing message discovery 09:34:06 [09:34:06.011] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:06 [09:34:06.047] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:06 [09:34:06.049] VERBOSE: simulator:client_execution_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:06 [09:34:06.279] VERBOSE: sequencer Preparing proposal for block 5 at slot 6 {"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807680,"version":2713600433},"chainTipArchive":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","blockNumber":5,"slot":6} 09:34:06 [09:34:06.312] VERBOSE: sequencer Not enough txs to build block 5 at slot 6 (got 0 txs, need 1) {"chainTipArchive":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","blockNumber":5,"slot":6} 09:34:06 [09:34:06.722] INFO: pxe:service Proving completed in 989.140585000001ms {"timings":{"total":989.140585000001,"sync":4.680512000002636,"perFunction":[{"functionName":"SchnorrAccount:entrypoint","time":192.45424200000707,"oracles":{"debugLog":{"times":[0.43561400000180583,0.37408399999912945,0.38147200000094017,0.4742050000022573,0.3712149999992107,0.3148070000024745,0.2959919999993872,0.4355059999979858]},"fetchTaggedLogs":{"times":[38.81082500000048]},"loadCapsule":{"times":[0.1633110000002489,0.12742900000012014,0.3280250000025262,0.19696699999985867,0.12800200000128825,0.1582940000007511,0.18072600000232342]},"getSharedSecret":{"times":[2.856685999999172]},"aes128Decrypt":{"times":[0.1889259999989008,0.8358169999992242]},"getPublicKeysAndPartialAddress":{"times":[2.3430539999972098]},"getKeyValidationRequest":{"times":[1.1583950000022014]},"storeCapsule":{"times":[0.2567479999997886,0.17853900000045542,0.2067910000005213]},"deleteCapsule":{"times":[0.20139600000038627]},"validateEnqueuedNotesAndEvents":{"times":[4.447844999998779]},"getNotes":{"times":[0.9981650000008813]},"getAuthWitness":{"times":[0.1901030000008177]},"notifySetMinRevertibleSideEffectCounter":{"times":[0.054035000001022127]},"callPrivateFunction":{"times":[200.9180149999993]}}},{"functionName":"private_kernel_init","time":53.70442699999694},{"functionName":"OffchainMessage:emit_note_as_offchain_message","time":172.367583999996,"oracles":{"debugLog":{"times":[0.4027810000006866,0.37656300000162446]},"fetchTaggedLogs":{"times":[37.22611700000198]},"loadCapsule":{"times":[0.16796100000283332,0.14150299999892013,0.16221299999961047]},"validateEnqueuedNotesAndEvents":{"times":[0.9049159999995027]},"getRandomField":{"times":[0.09230100000058883,0.08801799999855575,0.0693859999992128,0.05274000000281376,0.03938500000003842,0.034451999999873806,0.031517000003077555,0.02914799999780371,0.025902000001224224,0.027041000001190696,0.04613999999855878,0.038499999998748535]},"notifyCreatedNote":{"times":[0.11856999999872642]},"getIndexedTaggingSecretAsSender":{"times":[32.85690800000157]},"incrementAppTaggingSecretIndexAsSender":{"times":[24.8721489999989]},"emitOffchainMessage":{"times":[0.07607999999891035]}}},{"functionName":"private_kernel_inner","time":103.01559899999847},{"functionName":"private_kernel_reset","time":174.72853100000066},{"functionName":"private_kernel_tail","time":69.37813299999834}],"unaccounted":218.8115570000009}} 09:34:06 [09:34:06.750] VERBOSE: p2p:tx_pool Adding tx 0x14af95f9f535659b602ebb0d55a698a8c9a507838775e10cd33ddd74e5b79056 to pool {"eventName":"tx-added-to-pool","txHash":"0x14af95f9f535659b602ebb0d55a698a8c9a507838775e10cd33ddd74e5b79056","noteHashCount":1,"nullifierCount":1,"privateLogCount":0,"classRegisteredCount":0,"contractClassLogSize":0,"proofSize":172052,"size":196240,"feePaymentMethod":"fee_juice"} 09:34:06 [09:34:06.754] INFO: node Received tx 0x14af95f9f535659b602ebb0d55a698a8c9a507838775e10cd33ddd74e5b79056 {"txHash":"0x14af95f9f535659b602ebb0d55a698a8c9a507838775e10cd33ddd74e5b79056"} 09:34:06 [09:34:06.754] INFO: pxe:service Sent transaction 0x14af95f9f535659b602ebb0d55a698a8c9a507838775e10cd33ddd74e5b79056 09:34:06 [09:34:06.822] VERBOSE: sequencer Preparing proposal for block 5 at slot 6 {"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807680,"version":2713600433},"chainTipArchive":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","blockNumber":5,"slot":6} 09:34:06 [09:34:06.831] VERBOSE: block-builder Building block 5 for slot 6 {"slot":6,"slotStart":"2025-06-13T09:41:20.000Z","now":"2025-06-13T09:41:22.590Z","blockNumber":5,"msgCount":0,"opts":{"maxTransactions":32,"maxBlockSize":1048576,"maxBlockGas":{"daGas":10000000000,"l2Gas":10000000000}}} 09:34:06 [09:34:06.855] VERBOSE: simulator:public-processor Processed tx 0x14af95f9f535659b602ebb0d55a698a8c9a507838775e10cd33ddd74e5b79056 with no public calls in 13.19455299999754ms {"txHash":"0x14af95f9f535659b602ebb0d55a698a8c9a507838775e10cd33ddd74e5b79056","txFee":106322720,"revertCode":0,"gasUsed":{"totalGas":{"daGas":1536,"l2Gas":3304},"billedGas":{"daGas":1536,"l2Gas":3304},"teardownGas":{"daGas":0,"l2Gas":0},"publicGas":{"daGas":0,"l2Gas":0}},"publicDataWriteCount":1,"nullifierCount":1,"noteHashCount":1,"contractClassLogCount":0,"publicLogCount":0,"privateLogCount":0,"l2ToL1MessageCount":0,"durationMs":13.19455299999754} 09:34:06 [09:34:06.856] INFO: simulator:public-processor Processed 1 successful txs and 0 failed txs in 0.023350467000000208s {"duration":0.023350467000000208,"rate":0,"totalPublicGas":{"daGas":0,"l2Gas":0},"totalBlockGas":{"daGas":1536,"l2Gas":3304},"totalSizeInBytes":320} 09:34:06 [09:34:06.875] INFO: sequencer Built block 5 for slot 6 with 1 txs and 0 messages. 0 mana/s {"blockHash":"0x2cdee108f824e0c409d3fc6971c1e712b001b9442bc65be2ad45631059f5bd05","globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807680,"version":2713600433},"txHashes":["0x14af95f9f535659b602ebb0d55a698a8c9a507838775e10cd33ddd74e5b79056"],"eventName":"l2-block-built","creator":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","duration":48.73865500000102,"publicProcessDuration":23.954517000001943,"rollupCircuitsDuration":44.06527500000084,"txCount":1,"blockNumber":5,"blockTimestamp":1749807680,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:34:06 [09:34:06.877] VERBOSE: sequencer Attesting committee is empty 09:34:07 [09:34:07.030] VERBOSE: ethereum:tx_delayer Sent tx immediately 0xb15b247d9a9d728057a127b27225bf79c78ae10fd1b40eadeded3c91ae3e82df 09:34:07 [09:34:07.030] VERBOSE: node Sent L1 transaction 0xb15b247d9a9d728057a127b27225bf79c78ae10fd1b40eadeded3c91ae3e82df {"gasLimit":407323,"maxFeePerGas":"1.229482071","maxPriorityFeePerGas":"1.2","maxFeePerBlobGas":"0.000000001"} 09:34:07 [09:34:07.045] INFO: sequencer:publisher Bundled [propose] transaction [succeeded] 09:34:07 [09:34:07.045] VERBOSE: sequencer:publisher Published L2 block to L1 rollup contract {"sender":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","transactionHash":"0xb15b247d9a9d728057a127b27225bf79c78ae10fd1b40eadeded3c91ae3e82df","calldataSize":1252,"calldataGas":9964,"txCount":1,"blockNumber":5,"blockTimestamp":1749807680,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:34:07 [09:34:07.046] VERBOSE: node:blob-sink:client Sending 1 blobs to blob sink 09:34:07 [09:34:07.048] WARN: ethereum:cheat_codes Warped L1 timestamp to 1749807716 09:34:07 [09:34:07.048] WARN: foundation:test-date-provider Time set to 2025-06-13T09:41:56.000Z {"offset":468952,"timeMs":1749807716000} 09:34:07 [09:34:07.048] INFO: aztecjs:utils:watcher Slot 6 was filled, jumped to next slot 09:34:07 [09:34:07.049] INFO: blob-sink:server Received blob sidecar for block 0x008caa7b63308eac7f9ae2feaa04fab4dd2b611759a418c09f5a32627acfc2cb 09:34:07 [09:34:07.052] INFO: blob-sink:server Blob sidecar stored successfully for block 0x008caa7b63308eac7f9ae2feaa04fab4dd2b611759a418c09f5a32627acfc2cb 09:34:07 [09:34:07.231] WARN: ethereum:cheat_codes Set L1 storage for contract 0x3aa5ebb10dc797cac828524e59a333d0a371443c at 4226519774845294989066245638914502055781420935494253450203740607592046551906 to 5 09:34:07 [09:34:07.233] INFO: aztecjs:cheat_codes Proven tip moved: 4 -> 5. Pending tip: 5. 09:34:07 [09:34:07.476] INFO: archiver Downloaded L2 block 5 {"blockHash":"0x2cdee108f824e0c409d3fc6971c1e712b001b9442bc65be2ad45631059f5bd05","blockNumber":5,"txCount":1,"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807680,"version":2713600433},"archiveRoot":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","archiveNextLeafIndex":6} 09:34:07 [09:34:07.480] INFO: archiver Updated proven chain to block 5 {"provenBlockNumber":5} 09:34:07 [09:34:07.556] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":5.680010000000038,"unfinalisedBlockNumber":5,"finalisedBlockNumber":0,"oldestHistoricBlock":1,"txCount":1,"blockNumber":5,"blockTimestamp":1749807680,"privateLogCount":0,"publicLogCount":0,"contractClassLogCount":0,"contractClassLogSize":0} 09:34:07 [09:34:07.587] VERBOSE: p2p Synched to latest block 5 09:34:07 [09:34:07.762] VERBOSE: pxe:service Updated pxe last block to 5 {"blockHash":{},"archive":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","header":{"contentCommitment":{"blobsHash":"0x00c1de37c5bf7e4f5a7d3b4e69a376aff8e01d77721782ad36acb4743d0d83e8","inHash":"0x00089a9d421a82c4a25f7acbebe69e638d5b064fa8a60e018793dcb0be53752c","outHash":"0x0000000000000000000000000000000000000000000000000000000000000000"},"globalVariables":{"blockNumber":5,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":6,"timestamp":1749807680,"version":2713600433},"lastArchive":"0x2786dc4c34ebd2215e70b8862a4944a0e76caf6ce6be74940b379c6b90548c74","state":{"l1ToL2MessageTree":"0x2e33ee2008411c04b99c24b313513d097a0d21a5040b6193d1f978b8226892d6","noteHashTree":"0x2149439f838c5131a0a0080675f052c29c6aa53858484ea97ff5b7fc0d2580f9","nullifierTree":"0x04c999aaa07e62aeb089af0a3c7d40e95148e521ab639039f80206fe9a0b58df","publicDataTree":"0x17f34534245b97e5ec73f197a58073939b6d82dbfa638bbe4300f17129f021c8"},"totalFees":106322720,"totalManaUsed":3304}} 09:34:07 [09:34:07.842] VERBOSE: simulator Executing utility function process_message {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26","selector":"0x988dd306"} 09:34:07 [09:34:07.852] VERBOSE: simulator:client_view_context:debug_log Performing message discovery 09:34:07 [09:34:07.853] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:07 [09:34:07.886] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:07 [09:34:07.893] VERBOSE: simulator:client_view_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:07 [09:34:07.902] VERBOSE: simulator:client_view_context:debug_log Processing private note msg 09:34:07 [09:34:07.903] VERBOSE: simulator:client_view_context:debug_log Attempting nonce discovery on 0x0000000000000000000000000000000000000000000000000000000000000001 potential notes on contract 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26 for storage slot 0x2281680b24aab0110f04d51a98d3dbe6437e30af8a59a50da7e90d242a24b7aa 09:34:07 [09:34:07.908] VERBOSE: simulator:client_view_context:debug_log Discovered a total of 0x0000000000000000000000000000000000000000000000000000000000000001 notes 09:34:07 [09:34:07.908] VERBOSE: simulator:client_view_context:debug_log Discovered 0x0000000000000000000000000000000000000000000000000000000000000001 notes from a private message 09:34:07 [09:34:07.910] VERBOSE: simulator Utility simulation for 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26.0x988dd306 completed 09:34:07 [09:34:07.990] VERBOSE: simulator Executing utility function get_note_value {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26","selector":"0x5e6122e2"} 09:34:08 [09:34:08.000] VERBOSE: simulator:client_view_context:debug_log Performing message discovery 09:34:08 [09:34:08.000] VERBOSE: pxe:service Searching for tagged logs {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:08 [09:34:08.038] VERBOSE: pxe:service Searching for nullifiers of known notes {"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26"} 09:34:08 [09:34:08.040] VERBOSE: simulator:client_view_context:debug_log 0x0000000000000000000000000000000000000000000000000000000000000000 pending partial notes 09:34:08 [09:34:08.043] VERBOSE: pxe:service Added note {"index":256,"contract":"0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26","slot":"0x2281680b24aab0110f04d51a98d3dbe6437e30af8a59a50da7e90d242a24b7aa","noteHash":"0x04a829144a0b82e47df446ba64d30027668660429d992af48cd855f7eb6ea784","nullifier":"0x1726173ac4c76c89083bda55ae5bed7692d2f430ac6ded70918558f355300a9c"} 09:34:08 [09:34:08.046] VERBOSE: simulator Utility simulation for 0x2649896a8366677150656d5c00413699b96d07ae508f68310e95bbf57dd89a26.0x5e6122e2 completed 09:34:08 [09:34:08.048] INFO: node Stopping Aztec Node 09:34:08 [09:34:08.068] VERBOSE: sequencer Preparing proposal for block 6 at slot 7 {"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749807716,"version":2713600433},"chainTipArchive":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","blockNumber":6,"slot":7} 09:34:08 [09:34:08.068] VERBOSE: sequencer Not enough txs to build block 6 at slot 7 (got 0 txs, need 1) {"chainTipArchive":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","blockNumber":6,"slot":7} 09:34:08 [09:34:08.578] VERBOSE: sequencer Preparing proposal for block 6 at slot 7 {"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749807716,"version":2713600433},"chainTipArchive":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","blockNumber":6,"slot":7} 09:34:08 [09:34:08.579] VERBOSE: sequencer Not enough txs to build block 6 at slot 7 (got 0 txs, need 1) {"chainTipArchive":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","blockNumber":6,"slot":7} 09:34:09 [09:34:09.090] VERBOSE: sequencer Preparing proposal for block 6 at slot 7 {"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749807716,"version":2713600433},"chainTipArchive":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","blockNumber":6,"slot":7} 09:34:09 [09:34:09.091] VERBOSE: sequencer Not enough txs to build block 6 at slot 7 (got 0 txs, need 1) {"chainTipArchive":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","blockNumber":6,"slot":7} 09:34:09 [09:34:09.600] VERBOSE: sequencer Preparing proposal for block 6 at slot 7 {"globalVariables":{"blockNumber":6,"chainId":31337,"coinbase":"0xf39fd6e51aad88f6f4ce6ab8827279cfffb92266","feePerDaGas":0,"feePerL2Gas":32180,"feeRecipient":"0x0000000000000000000000000000000000000000000000000000000000000000","slotNumber":7,"timestamp":1749807716,"version":2713600433},"chainTipArchive":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","blockNumber":6,"slot":7} 09:34:09 [09:34:09.601] VERBOSE: sequencer Not enough txs to build block 6 at slot 7 (got 0 txs, need 1) {"chainTipArchive":"0x13c8e0435f1cc5ce01acbb49ca0f32f177419f7d73951f5146b3cdc1cb78928a","blockNumber":6,"slot":7} 09:34:10 [09:34:10.049] INFO: slasher Slasher client stopped. 09:34:10 [09:34:10.050] INFO: sequencer Stopped sequencer 09:34:10 [09:34:10.050] INFO: p2p P2P client stopped 09:34:10 [09:34:10.051] INFO: world_state Stopped world state synchronizer 09:34:10 [09:34:10.051] INFO: archiver Stopped. 09:34:10 [09:34:10.052] INFO: node Stopped Aztec Node 09:34:10 [09:34:10.061] INFO: blob-sink:server Stopping blob sink 09:34:10 [09:34:10.062] INFO: blob-sink:server Blob sink stopped 09:34:10 PASS src/e2e_offchain_message.test.ts 09:34:10 e2e_offchain_message 09:34:10 ✓ should emit offchain message (1860 ms) 09:34:10 ✓ should not emit any offchain messages (995 ms) 09:34:10 ✓ should revert when emitting offchain message from utility function (145 ms) 09:34:10 ✓ should emit event as offchain message and process it (3289 ms) 09:34:10 ✓ should emit note as offchain message and process it (2331 ms) 09:34:10 09:34:10 Test Suites: 1 passed, 1 total 09:34:10 Tests: 5 passed, 5 total 09:34:10 Snapshots: 0 total 09:34:10 Time: 29.494 s 09:34:10 Ran all test suites matching /src\/e2e_offchain_message.test.ts/i. 09:34:10 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?