feat: op count timers

[ludamad]

This expands on the previous #4437 PR with the ability to automatically microbenchmark pieces.

• If you want to measure timing , uses the preset op-count-time
• If you want to measure call counts, uses the preset op-count-track

Example of google benchmark running with preset op-count-time

------------------------------------------------------------------------------------
Benchmark                          Time             CPU   Iterations UserCounters...
------------------------------------------------------------------------------------
GoblinBench/GoblinFull/1       10609 ms         9065 ms            1 batch_mul_with_endomorphism=48 batch_mul_with_endomorphism(t)=646.575M
^C

-----------------------------------------------------------------------------------
Benchmark                         Time             CPU   Iterations UserCounters...
-----------------------------------------------------------------------------------
GoblinBench/GoblinFull/2      15225 ms        13342 ms            1 batch_mul_with_endomorphism=52 batch_mul_with_endomorphism(t)=1.02146G

Note with nanosecond units, one M(illion) is one millisecond, one G(iga) is one second

[AztecBot]

Benchmark results

No metrics with a significant change found.

Detailed results

All benchmarks are run on txs on the Benchmarking contract on the repository. Each tx consists of a batch call to create_note and increment_balance, which guarantees that each tx has a private call, a nested private call, a public call, and a nested public call, as well as an emitted private note, an unencrypted log, and public storage read and write.

This benchmark source data is available in JSON format on S3 here.

Values are compared against data from master at commit ed6d5218 and shown if the difference exceeds 1%.

L2 block published to L1

Each column represents the number of txs on an L2 block published to L1.

Metric	8 txs	32 txs	128 txs
l1_rollup_calldata_size_in_bytes	45,700	180,580	720,100
l1_rollup_calldata_gas	222,844	871,372	3,465,988
l1_rollup_execution_gas	316,035	989,820	3,686,366
l2_block_processing_time_in_ms	1,158	4,368 (+1%)	17,324 (+1%)
note_successful_decrypting_time_in_ms	314 (-2%)	1,001 (+2%)	3,738 (+2%)
note_trial_decrypting_time_in_ms	26.1 (-23%)	33.3 (-70%)	147
l2_block_building_time_in_ms	18,110	72,609 (+2%)	292,369 (+2%)
l2_block_rollup_simulation_time_in_ms	13,267	53,125 (+2%)	214,416 (+2%)
l2_block_public_tx_process_time_in_ms	4,815 (+1%)	19,401 (+2%)	77,690 (+2%)

L2 chain processing

Each column represents the number of blocks on the L2 chain where each block has 16 txs.

Metric	5 blocks	10 blocks
node_history_sync_time_in_ms	14,244 (+1%)	28,232 (+4%)
note_history_successful_decrypting_time_in_ms	2,342	4,598
note_history_trial_decrypting_time_in_ms	84.1 (-1%)	232 (+13%)
node_database_size_in_bytes	17,375,312	32,976,976
pxe_database_size_in_bytes	29,923	59,478

Circuits stats

Stats on running time and I/O sizes collected for every circuit run across all benchmarks.

Circuit	circuit_simulation_time_in_ms	circuit_input_size_in_bytes	circuit_output_size_in_bytes
private-kernel-init	307	44,528	25,825
private-kernel-ordering	194 (+1%)	44,145	16,193
base-rollup	1,436 (+1%)	130,286	933
root-rollup	82.4	4,192	729
private-kernel-inner	434 (+1%)	71,331	25,825
public-kernel-private-input	249 (+1%)	34,687	27,457
public-kernel-non-first-iteration	247 (+1%)	34,729	27,457
merge-rollup	7.84	2,712	933

Tree insertion stats

The duration to insert a fixed batch of leaves into each tree type.

Metric	1 leaves	2 leaves	8 leaves	16 leaves	32 leaves	128 leaves	64 leaves	512 leaves	1024 leaves	2048 leaves	8192 leaves
batch_insert_into_append_only_tree_16_depth_ms	10.2 (+1%)	11.3 (+7%)	12.6 (+1%)	16.6 (-2%)	23.3 (+2%)	64.4 (+1%)	N/A	N/A	N/A	N/A	N/A
batch_insert_into_append_only_tree_16_depth_hash_count	16.9	17.5	23.0	31.6	47.0	143	N/A	N/A	N/A	N/A	N/A
batch_insert_into_append_only_tree_16_depth_hash_ms	0.595 (+1%)	0.630 (+7%)	0.537 (+1%)	0.515 (-2%)	0.486 (+1%)	0.444 (+1%)	N/A	N/A	N/A	N/A	N/A
batch_insert_into_append_only_tree_32_depth_ms	N/A	N/A	N/A	N/A	N/A	75.9 (+2%)	47.8 (+1%)	239	460 (-1%)	900	3,572 (+1%)
batch_insert_into_append_only_tree_32_depth_hash_count	N/A	N/A	N/A	N/A	N/A	159	96.0	543	1,055	2,079	8,223
batch_insert_into_append_only_tree_32_depth_hash_ms	N/A	N/A	N/A	N/A	N/A	0.469 (+2%)	0.490 (+1%)	0.436	0.431 (-1%)	0.428	0.429 (+1%)
batch_insert_into_indexed_tree_20_depth_ms	N/A	N/A	N/A	N/A	N/A	103	56.7	350	685	1,372 (+1%)	5,431 (+1%)
batch_insert_into_indexed_tree_20_depth_hash_count	N/A	N/A	N/A	N/A	N/A	197	104	691	1,363	2,707	10,771
batch_insert_into_indexed_tree_20_depth_hash_ms	N/A	N/A	N/A	N/A	N/A	0.492	0.497	0.477 (+1%)	0.472	0.476 (+1%)	0.472
batch_insert_into_indexed_tree_40_depth_ms	N/A	N/A	N/A	56.5	N/A	N/A	N/A	N/A	N/A	N/A	N/A
batch_insert_into_indexed_tree_40_depth_hash_count	N/A	N/A	N/A	94.1	N/A	N/A	N/A	N/A	N/A	N/A	N/A
batch_insert_into_indexed_tree_40_depth_hash_ms	N/A	N/A	N/A	0.578	N/A	N/A	N/A	N/A	N/A	N/A	N/A

Miscellaneous

Transaction sizes based on how many contracts are deployed in the tx.

Metric	0 deployed contracts	1 deployed contracts
tx_size_in_bytes	16,827	40,279

Transaction processing duration by data writes.

Metric	0 new commitments	1 new commitments
tx_pxe_processing_time_ms	553 (+1%)	1,305 (+1%)

Metric	0 public data writes	1 public data writes
tx_sequencer_processing_time_ms	0.522 (+9%)	595 (+1%)