chore(bench): sort items in text bench resutls (#466)

This commit is contained in:
Valery Antopol 2023-02-13 19:55:03 +03:00 committed by GitHub
parent 5e509223d6
commit 0358854a5c
No known key found for this signature in database
GPG Key ID: 4AEE18F83AFDEB23
3 changed files with 200 additions and 197 deletions

View File

@ -6,115 +6,115 @@
"stats": { "stats": {
"air::runner::execute_air": { "air::runner::execute_air": {
"common_prefix": "air", "common_prefix": "air",
"duration": "1.26s", "duration": "1.19s",
"nested": { "nested": {
"farewell_step::outcome::from_success_result": { "farewell_step::outcome::from_success_result": {
"common_prefix": "air::farewell_step::outcome", "common_prefix": "air::farewell_step::outcome",
"duration": "113.80ms", "duration": "108.00ms",
"nested": { "nested": {
"populate_outcome_from_contexts": { "populate_outcome_from_contexts": {
"common_prefix": "air::farewell_step::outcome::serde_json", "common_prefix": "air::farewell_step::outcome::serde_json",
"duration": "111.40ms", "duration": "105.00ms",
"nested": { "nested": {
"to_vec(call_results)": "217.00µs", "to_vec(call_results)": "207.00µs",
"to_vec(data)": "105.27ms" "to_vec(data)": "99.50ms"
} }
} }
} }
}, },
"preparation_step::preparation::prepare": { "preparation_step::preparation::prepare": {
"common_prefix": "", "common_prefix": "",
"duration": "1.14s", "duration": "1.07s",
"nested": { "nested": {
"air::preparation_step::preparation::make_exec_ctx": "2.93ms", "air::preparation_step::preparation::make_exec_ctx": "2.83ms",
"air_interpreter_data::interpreter_data::serde_json::from_slice": "1.12s", "air_interpreter_data::interpreter_data::serde_json::from_slice": "1.06s",
"air_parser::parser::air_parser::parse": "4.04ms" "air_parser::parser::air_parser::parse": "3.90ms"
} }
}, },
"runner::execute": "261.00µs" "runner::execute": "253.00µs"
} }
} }
}, },
"total_time": "1.26s" "total_time": "1.19s"
}, },
"dashboard": { "dashboard": {
"comment": "big dashboard test", "comment": "big dashboard test",
"stats": { "stats": {
"air::runner::execute_air": { "air::runner::execute_air": {
"common_prefix": "air", "common_prefix": "air",
"duration": "195.20ms", "duration": "192.00ms",
"nested": { "nested": {
"farewell_step::outcome::from_success_result": { "farewell_step::outcome::from_success_result": {
"common_prefix": "air::farewell_step::outcome", "common_prefix": "air::farewell_step::outcome",
"duration": "27.59ms", "duration": "28.70ms",
"nested": { "nested": {
"populate_outcome_from_contexts": { "populate_outcome_from_contexts": {
"common_prefix": "air::farewell_step::outcome::serde_json", "common_prefix": "air::farewell_step::outcome::serde_json",
"duration": "26.20ms", "duration": "27.40ms",
"nested": { "nested": {
"to_vec(call_results)": "287.00µs", "to_vec(call_results)": "282.00µs",
"to_vec(data)": "22.07ms" "to_vec(data)": "23.60ms"
} }
} }
} }
}, },
"preparation_step::preparation::prepare": { "preparation_step::preparation::prepare": {
"common_prefix": "", "common_prefix": "",
"duration": "51.72ms", "duration": "47.90ms",
"nested": { "nested": {
"air::preparation_step::preparation::make_exec_ctx": "3.38ms", "air::preparation_step::preparation::make_exec_ctx": "3.24ms",
"air_interpreter_data::interpreter_data::serde_json::from_slice": "31.07ms", "air_interpreter_data::interpreter_data::serde_json::from_slice": "28.80ms",
"air_parser::parser::air_parser::parse": "12.64ms" "air_parser::parser::air_parser::parse": "12.10ms"
} }
}, },
"runner::execute": { "runner::execute": {
"common_prefix": "air::execution_step::instructions::call", "common_prefix": "air::execution_step::instructions::call",
"duration": "109.30ms", "duration": "109.00ms",
"nested": { "nested": {
"execute": { "execute": {
"common_prefix": "air::execution_step::instructions::call::resolved_call", "common_prefix": "air::execution_step::instructions::call::resolved_call",
"duration": "90.94ms", "duration": "90.67ms",
"nested": { "nested": {
"execute": { "execute": {
"common_prefix": "air::execution_step", "common_prefix": "air::execution_step",
"duration": "14.24ms", "duration": "13.72ms",
"nested": { "nested": {
"instructions::call::resolved_call::prepare_request_params": { "instructions::call::resolved_call::prepare_request_params": {
"common_prefix": "air::execution_step", "common_prefix": "air::execution_step",
"duration": "2.47ms", "duration": "2.25ms",
"nested": { "nested": {
"instructions::call::resolved_call::serde_json::to_string(tetraplets)": "582.00µs", "instructions::call::resolved_call::serde_json::to_string(tetraplets)": "559.00µs",
"resolver::resolve::resolve_ast_variable": { "resolver::resolve::resolve_ast_variable": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "469.00µs", "duration": "419.00µs",
"nested": { "nested": {
"resolve_variable": "176.00µs" "resolve_variable": "135.00µs"
} }
} }
} }
}, },
"resolver::resolve::resolve_ast_variable": { "resolver::resolve::resolve_ast_variable": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "1.23ms", "duration": "1.22ms",
"nested": { "nested": {
"resolve_variable": "360.00µs" "resolve_variable": "355.00µs"
} }
} }
} }
}, },
"new": { "new": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "55.18ms", "duration": "54.91ms",
"nested": { "nested": {
"resolve_ast_scalar": { "resolve_ast_scalar": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "40.32ms", "duration": "40.27ms",
"nested": { "nested": {
"resolve_ast_variable": { "resolve_ast_variable": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "25.35ms", "duration": "25.33ms",
"nested": { "nested": {
"resolve_variable": "8.25ms" "resolve_variable": "8.27ms"
} }
} }
} }
@ -128,108 +128,108 @@
} }
} }
}, },
"total_time": "195.20ms" "total_time": "192.00ms"
}, },
"long_data": { "long_data": {
"comment": "Long data trace", "comment": "Long data trace",
"stats": { "stats": {
"air::runner::execute_air": { "air::runner::execute_air": {
"common_prefix": "air", "common_prefix": "air",
"duration": "343.70ms", "duration": "345.00ms",
"nested": { "nested": {
"farewell_step::outcome::from_success_result": { "farewell_step::outcome::from_success_result": {
"common_prefix": "air::farewell_step::outcome", "common_prefix": "air::farewell_step::outcome",
"duration": "29.28ms", "duration": "29.30ms",
"nested": { "nested": {
"populate_outcome_from_contexts": { "populate_outcome_from_contexts": {
"common_prefix": "air::farewell_step::outcome::serde_json", "common_prefix": "air::farewell_step::outcome::serde_json",
"duration": "26.79ms", "duration": "26.80ms",
"nested": { "nested": {
"to_vec(call_results)": "211.00µs", "to_vec(call_results)": "207.00µs",
"to_vec(data)": "21.45ms" "to_vec(data)": "21.70ms"
} }
} }
} }
}, },
"preparation_step::preparation::prepare": { "preparation_step::preparation::prepare": {
"common_prefix": "", "common_prefix": "",
"duration": "307.70ms", "duration": "310.00ms",
"nested": { "nested": {
"air::preparation_step::preparation::make_exec_ctx": "3.43ms", "air::preparation_step::preparation::make_exec_ctx": "3.37ms",
"air_interpreter_data::interpreter_data::serde_json::from_slice": "295.30ms", "air_interpreter_data::interpreter_data::serde_json::from_slice": "298.00ms",
"air_parser::parser::air_parser::parse": "3.98ms" "air_parser::parser::air_parser::parse": "3.90ms"
} }
}, },
"runner::execute": "255.00µs" "runner::execute": "253.00µs"
} }
} }
}, },
"total_time": "343.70ms" "total_time": "345.00ms"
}, },
"network_explore": { "network_explore": {
"comment": "5 peers of network are discovered", "comment": "5 peers of network are discovered",
"stats": { "stats": {
"air::runner::execute_air": { "air::runner::execute_air": {
"common_prefix": "air", "common_prefix": "air",
"duration": "81.30ms", "duration": "79.40ms",
"nested": { "nested": {
"farewell_step::outcome::from_success_result": { "farewell_step::outcome::from_success_result": {
"common_prefix": "air::farewell_step::outcome", "common_prefix": "air::farewell_step::outcome",
"duration": "10.51ms", "duration": "10.20ms",
"nested": { "nested": {
"populate_outcome_from_contexts": { "populate_outcome_from_contexts": {
"common_prefix": "air::farewell_step::outcome::serde_json", "common_prefix": "air::farewell_step::outcome::serde_json",
"duration": "9.17ms", "duration": "8.94ms",
"nested": { "nested": {
"to_vec(call_results)": "210.00µs", "to_vec(call_results)": "212.00µs",
"to_vec(data)": "4.69ms" "to_vec(data)": "4.56ms"
} }
} }
} }
}, },
"preparation_step::preparation::prepare": { "preparation_step::preparation::prepare": {
"common_prefix": "", "common_prefix": "",
"duration": "34.91ms", "duration": "34.00ms",
"nested": { "nested": {
"air::preparation_step::preparation::make_exec_ctx": "4.28ms", "air::preparation_step::preparation::make_exec_ctx": "4.18ms",
"air_interpreter_data::interpreter_data::serde_json::from_slice": "13.20ms", "air_interpreter_data::interpreter_data::serde_json::from_slice": "12.86ms",
"air_parser::parser::air_parser::parse": "13.44ms" "air_parser::parser::air_parser::parse": "13.10ms"
} }
}, },
"runner::execute": { "runner::execute": {
"common_prefix": "air::execution_step::instructions::call", "common_prefix": "air::execution_step::instructions::call",
"duration": "29.25ms", "duration": "28.90ms",
"nested": { "nested": {
"execute": { "execute": {
"common_prefix": "air::execution_step::instructions::call::resolved_call", "common_prefix": "air::execution_step::instructions::call::resolved_call",
"duration": "15.63ms", "duration": "15.85ms",
"nested": { "nested": {
"execute": { "execute": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "5.01ms", "duration": "4.90ms",
"nested": { "nested": {
"resolve_ast_variable": { "resolve_ast_variable": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "392.00µs", "duration": "380.00µs",
"nested": { "nested": {
"resolve_variable": "128.00µs" "resolve_variable": "125.00µs"
} }
} }
} }
}, },
"new": { "new": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "6.51ms", "duration": "6.39ms",
"nested": { "nested": {
"resolve_ast_scalar": { "resolve_ast_scalar": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "3.92ms", "duration": "3.86ms",
"nested": { "nested": {
"resolve_ast_variable": { "resolve_ast_variable": {
"common_prefix": "air::execution_step::resolver::resolve", "common_prefix": "air::execution_step::resolver::resolve",
"duration": "2.78ms", "duration": "2.74ms",
"nested": { "nested": {
"resolve_variable": "866.00µs" "resolve_variable": "849.00µs"
} }
} }
} }
@ -243,25 +243,25 @@
} }
} }
}, },
"total_time": "81.30ms" "total_time": "79.40ms"
}, },
"parser_10000_100": { "parser_10000_100": {
"comment": "Running very long AIR script with lot of variables and assignments", "comment": "Running very long AIR script with lot of variables and assignments",
"stats": { "stats": {
"air::runner::execute_air": { "air::runner::execute_air": {
"common_prefix": "air", "common_prefix": "air",
"duration": "1.35s", "duration": "1.36s",
"nested": { "nested": {
"farewell_step::outcome::from_success_result": { "farewell_step::outcome::from_success_result": {
"common_prefix": "air::farewell_step::outcome", "common_prefix": "air::farewell_step::outcome",
"duration": "7.81ms", "duration": "9.49ms",
"nested": { "nested": {
"populate_outcome_from_contexts": { "populate_outcome_from_contexts": {
"common_prefix": "air::farewell_step::outcome::serde_json", "common_prefix": "air::farewell_step::outcome::serde_json",
"duration": "6.51ms", "duration": "7.18ms",
"nested": { "nested": {
"to_vec(call_results)": "212.00µs", "to_vec(call_results)": "226.00µs",
"to_vec(data)": "2.88ms" "to_vec(data)": "3.37ms"
} }
} }
} }
@ -270,20 +270,20 @@
"common_prefix": "", "common_prefix": "",
"duration": "1.33s", "duration": "1.33s",
"nested": { "nested": {
"air::preparation_step::preparation::make_exec_ctx": "2.94ms", "air::preparation_step::preparation::make_exec_ctx": "2.86ms",
"air_parser::parser::air_parser::parse": "1.32s" "air_parser::parser::air_parser::parse": "1.32s"
} }
}, },
"runner::execute": { "runner::execute": {
"common_prefix": "air::execution_step::instructions::call", "common_prefix": "air::execution_step::instructions::call",
"duration": "7.95ms", "duration": "7.38ms",
"nested": { "nested": {
"execute": { "execute": {
"common_prefix": "air::execution_step::instructions::call::resolved_call", "common_prefix": "air::execution_step::instructions::call::resolved_call",
"duration": "4.78ms", "duration": "4.63ms",
"nested": { "nested": {
"execute": "907.00µs", "execute": "953.00µs",
"new": "1.25ms" "new": "1.26ms"
} }
} }
} }
@ -291,10 +291,10 @@
} }
} }
}, },
"total_time": "1.35s" "total_time": "1.36s"
} }
}, },
"datetime": "2023-02-13 12:26:56.868568+00:00", "datetime": "2023-02-13 16:42:08.810990+00:00",
"platform": "macOS-12.3.1-arm64-arm-64bit", "platform": "macOS-12.3.1-arm64-arm-64bit",
"version": "0.35.1" "version": "0.35.1"
}, },

View File

@ -1,85 +1,85 @@
Machine 5fce753d17dde8b439ef04cdbce135789eb44646c753548ad79a37b88cf17f0a: Machine 5fce753d17dde8b439ef04cdbce135789eb44646c753548ad79a37b88cf17f0a:
Platform: macOS-12.3.1-arm64-arm-64bit Platform: macOS-12.3.1-arm64-arm-64bit
Timestamp: 2023-02-13 12:26:56.868568+00:00 Timestamp: 2023-02-13 16:42:08.810990+00:00
AquaVM version: 0.35.1 AquaVM version: 0.35.1
Benches: Benches:
big_values_data (1.26s): Loading a trace with huge values big_values_data (1.19s): Loading a trace with huge values
air::runner::execute_air: 1.26s air::runner::execute_air: 1.19s
preparation_step::preparation::prepare: 1.14s farewell_step::outcome::from_success_result: 108.00ms
air_interpreter_data::interpreter_data::serde_json::from_slice: 1.12s populate_outcome_from_contexts: 105.00ms
air_parser::parser::air_parser::parse: 4.04ms to_vec(call_results): 207.00µs
air::preparation_step::preparation::make_exec_ctx: 2.93ms to_vec(data): 99.50ms
runner::execute: 261.00µs preparation_step::preparation::prepare: 1.07s
farewell_step::outcome::from_success_result: 113.80ms air::preparation_step::preparation::make_exec_ctx: 2.83ms
populate_outcome_from_contexts: 111.40ms air_interpreter_data::interpreter_data::serde_json::from_slice: 1.06s
to_vec(data): 105.27ms air_parser::parser::air_parser::parse: 3.90ms
to_vec(call_results): 217.00µs runner::execute: 253.00µs
dashboard (195.20ms): big dashboard test dashboard (192.00ms): big dashboard test
air::runner::execute_air: 195.20ms air::runner::execute_air: 192.00ms
preparation_step::preparation::prepare: 51.72ms farewell_step::outcome::from_success_result: 28.70ms
air_interpreter_data::interpreter_data::serde_json::from_slice: 31.07ms populate_outcome_from_contexts: 27.40ms
air_parser::parser::air_parser::parse: 12.64ms to_vec(call_results): 282.00µs
air::preparation_step::preparation::make_exec_ctx: 3.38ms to_vec(data): 23.60ms
runner::execute: 109.30ms preparation_step::preparation::prepare: 47.90ms
execute: 90.94ms air::preparation_step::preparation::make_exec_ctx: 3.24ms
new: 55.18ms air_interpreter_data::interpreter_data::serde_json::from_slice: 28.80ms
resolve_ast_scalar: 40.32ms air_parser::parser::air_parser::parse: 12.10ms
resolve_ast_variable: 25.35ms runner::execute: 109.00ms
resolve_variable: 8.25ms execute: 90.67ms
execute: 14.24ms execute: 13.72ms
resolver::resolve::resolve_ast_variable: 1.23ms instructions::call::resolved_call::prepare_request_params: 2.25ms
resolve_variable: 360.00µs instructions::call::resolved_call::serde_json::to_string(tetraplets): 559.00µs
instructions::call::resolved_call::prepare_request_params: 2.47ms resolver::resolve::resolve_ast_variable: 419.00µs
resolver::resolve::resolve_ast_variable: 469.00µs resolve_variable: 135.00µs
resolve_variable: 176.00µs resolver::resolve::resolve_ast_variable: 1.22ms
instructions::call::resolved_call::serde_json::to_string(tetraplets): 582.00µs resolve_variable: 355.00µs
farewell_step::outcome::from_success_result: 27.59ms new: 54.91ms
populate_outcome_from_contexts: 26.20ms resolve_ast_scalar: 40.27ms
to_vec(data): 22.07ms resolve_ast_variable: 25.33ms
to_vec(call_results): 287.00µs resolve_variable: 8.27ms
long_data (343.70ms): Long data trace long_data (345.00ms): Long data trace
air::runner::execute_air: 343.70ms air::runner::execute_air: 345.00ms
preparation_step::preparation::prepare: 307.70ms farewell_step::outcome::from_success_result: 29.30ms
air_interpreter_data::interpreter_data::serde_json::from_slice: 295.30ms populate_outcome_from_contexts: 26.80ms
air_parser::parser::air_parser::parse: 3.98ms to_vec(call_results): 207.00µs
air::preparation_step::preparation::make_exec_ctx: 3.43ms to_vec(data): 21.70ms
runner::execute: 255.00µs preparation_step::preparation::prepare: 310.00ms
farewell_step::outcome::from_success_result: 29.28ms air::preparation_step::preparation::make_exec_ctx: 3.37ms
populate_outcome_from_contexts: 26.79ms air_interpreter_data::interpreter_data::serde_json::from_slice: 298.00ms
to_vec(data): 21.45ms air_parser::parser::air_parser::parse: 3.90ms
to_vec(call_results): 211.00µs runner::execute: 253.00µs
network_explore (81.30ms): 5 peers of network are discovered network_explore (79.40ms): 5 peers of network are discovered
air::runner::execute_air: 81.30ms air::runner::execute_air: 79.40ms
preparation_step::preparation::prepare: 34.91ms farewell_step::outcome::from_success_result: 10.20ms
air_interpreter_data::interpreter_data::serde_json::from_slice: 13.20ms populate_outcome_from_contexts: 8.94ms
air_parser::parser::air_parser::parse: 13.44ms
air::preparation_step::preparation::make_exec_ctx: 4.28ms
runner::execute: 29.25ms
execute: 15.63ms
new: 6.51ms
resolve_ast_scalar: 3.92ms
resolve_ast_variable: 2.78ms
resolve_variable: 866.00µs
execute: 5.01ms
resolve_ast_variable: 392.00µs
resolve_variable: 128.00µs
farewell_step::outcome::from_success_result: 10.51ms
populate_outcome_from_contexts: 9.17ms
to_vec(data): 4.69ms
to_vec(call_results): 210.00µs
parser_10000_100 (1.35s): Running very long AIR script with lot of variables and assignments
air::runner::execute_air: 1.35s
preparation_step::preparation::prepare: 1.33s
air_parser::parser::air_parser::parse: 1.32s
air::preparation_step::preparation::make_exec_ctx: 2.94ms
runner::execute: 7.95ms
execute: 4.78ms
new: 1.25ms
execute: 907.00µs
farewell_step::outcome::from_success_result: 7.81ms
populate_outcome_from_contexts: 6.51ms
to_vec(data): 2.88ms
to_vec(call_results): 212.00µs to_vec(call_results): 212.00µs
to_vec(data): 4.56ms
preparation_step::preparation::prepare: 34.00ms
air::preparation_step::preparation::make_exec_ctx: 4.18ms
air_interpreter_data::interpreter_data::serde_json::from_slice: 12.86ms
air_parser::parser::air_parser::parse: 13.10ms
runner::execute: 28.90ms
execute: 15.85ms
execute: 4.90ms
resolve_ast_variable: 380.00µs
resolve_variable: 125.00µs
new: 6.39ms
resolve_ast_scalar: 3.86ms
resolve_ast_variable: 2.74ms
resolve_variable: 849.00µs
parser_10000_100 (1.36s): Running very long AIR script with lot of variables and assignments
air::runner::execute_air: 1.36s
farewell_step::outcome::from_success_result: 9.49ms
populate_outcome_from_contexts: 7.18ms
to_vec(call_results): 226.00µs
to_vec(data): 3.37ms
preparation_step::preparation::prepare: 1.33s
air::preparation_step::preparation::make_exec_ctx: 2.86ms
air_parser::parser::air_parser::parse: 1.32s
runner::execute: 7.38ms
execute: 4.63ms
execute: 953.00µs
new: 1.26ms
Machine c1f3ea5950db0a10b44da931c25774d64ab25084f47d504f72f311e694550ff1: Machine c1f3ea5950db0a10b44da931c25774d64ab25084f47d504f72f311e694550ff1:
Platform: macOS-12.0.1-x86_64-i386-64bit Platform: macOS-12.0.1-x86_64-i386-64bit
Timestamp: 2023-02-13 12:24:06.147422+00:00 Timestamp: 2023-02-13 12:24:06.147422+00:00
@ -87,81 +87,81 @@ Machine c1f3ea5950db0a10b44da931c25774d64ab25084f47d504f72f311e694550ff1:
Benches: Benches:
big_values_data (2.04s): Loading a trace with huge values big_values_data (2.04s): Loading a trace with huge values
air::runner::execute_air: 2.04s air::runner::execute_air: 2.04s
preparation_step::preparation::prepare: 1.87s
air_interpreter_data::interpreter_data::serde_json::from_slice: 1.87s
air_parser::parser::air_parser::parse: 98.00µs
air::preparation_step::preparation::make_exec_ctx: 73.00µs
runner::execute: 24.00µs
farewell_step::outcome::from_success_result: 172.30ms farewell_step::outcome::from_success_result: 172.30ms
populate_outcome_from_contexts: 172.20ms populate_outcome_from_contexts: 172.20ms
to_vec(data): 170.30ms
to_vec(call_results): 37.00µs to_vec(call_results): 37.00µs
to_vec(data): 170.30ms
preparation_step::preparation::prepare: 1.87s
air::preparation_step::preparation::make_exec_ctx: 73.00µs
air_interpreter_data::interpreter_data::serde_json::from_slice: 1.87s
air_parser::parser::air_parser::parse: 98.00µs
runner::execute: 24.00µs
dashboard (188.20ms): big dashboard test dashboard (188.20ms): big dashboard test
air::runner::execute_air: 188.20ms air::runner::execute_air: 188.20ms
farewell_step::outcome::from_success_result: 29.39ms
populate_outcome_from_contexts: 29.33ms
to_vec(call_results): 49.00µs
to_vec(data): 28.96ms
preparation_step::preparation::prepare: 31.34ms preparation_step::preparation::prepare: 31.34ms
air::preparation_step::preparation::make_exec_ctx: 102.00µs
air_interpreter_data::interpreter_data::serde_json::from_slice: 30.46ms air_interpreter_data::interpreter_data::serde_json::from_slice: 30.46ms
air_parser::parser::air_parser::parse: 520.00µs air_parser::parser::air_parser::parse: 520.00µs
air::preparation_step::preparation::make_exec_ctx: 102.00µs
runner::execute: 127.20ms runner::execute: 127.20ms
execute: 113.82ms execute: 113.82ms
execute: 11.77ms
instructions::call::resolved_call::prepare_request_params: 1.04ms
instructions::call::resolved_call::serde_json::to_string(tetraplets): 60.00µs
resolver::resolve::resolve_ast_variable: 628.00µs
resolve_variable: 204.00µs
resolver::resolve::resolve_ast_variable: 1.55ms
resolve_variable: 496.00µs
new: 74.44ms new: 74.44ms
resolve_ast_scalar: 55.54ms resolve_ast_scalar: 55.54ms
resolve_ast_variable: 34.35ms resolve_ast_variable: 34.35ms
resolve_variable: 11.08ms resolve_variable: 11.08ms
execute: 11.77ms
resolver::resolve::resolve_ast_variable: 1.55ms
resolve_variable: 496.00µs
instructions::call::resolved_call::prepare_request_params: 1.04ms
resolver::resolve::resolve_ast_variable: 628.00µs
resolve_variable: 204.00µs
instructions::call::resolved_call::serde_json::to_string(tetraplets): 60.00µs
farewell_step::outcome::from_success_result: 29.39ms
populate_outcome_from_contexts: 29.33ms
to_vec(data): 28.96ms
to_vec(call_results): 49.00µs
long_data (530.20ms): Long data trace long_data (530.20ms): Long data trace
air::runner::execute_air: 530.20ms air::runner::execute_air: 530.20ms
preparation_step::preparation::prepare: 497.40ms
air_interpreter_data::interpreter_data::serde_json::from_slice: 497.10ms
air_parser::parser::air_parser::parse: 116.00µs
air::preparation_step::preparation::make_exec_ctx: 124.00µs
runner::execute: 27.00µs
farewell_step::outcome::from_success_result: 32.42ms farewell_step::outcome::from_success_result: 32.42ms
populate_outcome_from_contexts: 32.30ms populate_outcome_from_contexts: 32.30ms
to_vec(data): 31.36ms
to_vec(call_results): 43.00µs to_vec(call_results): 43.00µs
to_vec(data): 31.36ms
preparation_step::preparation::prepare: 497.40ms
air::preparation_step::preparation::make_exec_ctx: 124.00µs
air_interpreter_data::interpreter_data::serde_json::from_slice: 497.10ms
air_parser::parser::air_parser::parse: 116.00µs
runner::execute: 27.00µs
network_explore (12.58ms): 5 peers of network are discovered network_explore (12.58ms): 5 peers of network are discovered
air::runner::execute_air: 12.58ms air::runner::execute_air: 12.58ms
farewell_step::outcome::from_success_result: 437.00µs
populate_outcome_from_contexts: 347.00µs
to_vec(call_results): 31.00µs
to_vec(data): 128.00µs
preparation_step::preparation::prepare: 1.23ms preparation_step::preparation::prepare: 1.23ms
air::preparation_step::preparation::make_exec_ctx: 113.00µs
air_interpreter_data::interpreter_data::serde_json::from_slice: 458.00µs air_interpreter_data::interpreter_data::serde_json::from_slice: 458.00µs
air_parser::parser::air_parser::parse: 388.00µs air_parser::parser::air_parser::parse: 388.00µs
air::preparation_step::preparation::make_exec_ctx: 113.00µs
runner::execute: 10.61ms runner::execute: 10.61ms
execute: 9.26ms execute: 9.26ms
execute: 1.67ms
resolve_ast_variable: 640.00µs
resolve_variable: 223.00µs
new: 5.36ms new: 5.36ms
resolve_ast_scalar: 3.96ms resolve_ast_scalar: 3.96ms
resolve_ast_variable: 2.43ms resolve_ast_variable: 2.43ms
resolve_variable: 786.00µs resolve_variable: 786.00µs
execute: 1.67ms
resolve_ast_variable: 640.00µs
resolve_variable: 223.00µs
farewell_step::outcome::from_success_result: 437.00µs
populate_outcome_from_contexts: 347.00µs
to_vec(data): 128.00µs
to_vec(call_results): 31.00µs
parser_10000_100 (2.30s): Running very long AIR script with lot of variables and assignments parser_10000_100 (2.30s): Running very long AIR script with lot of variables and assignments
air::runner::execute_air: 2.30s air::runner::execute_air: 2.30s
preparation_step::preparation::prepare: 2.30s
air_parser::parser::air_parser::parse: 2.30s
air::preparation_step::preparation::make_exec_ctx: 82.00µs
runner::execute: 407.00µs
execute: 274.00µs
new: 62.00µs
execute: 50.00µs
farewell_step::outcome::from_success_result: 367.00µs farewell_step::outcome::from_success_result: 367.00µs
populate_outcome_from_contexts: 286.00µs populate_outcome_from_contexts: 286.00µs
to_vec(data): 88.00µs
to_vec(call_results): 29.00µs to_vec(call_results): 29.00µs
to_vec(data): 88.00µs
preparation_step::preparation::prepare: 2.30s
air::preparation_step::preparation::make_exec_ctx: 82.00µs
air_parser::parser::air_parser::parse: 2.30s
runner::execute: 407.00µs
execute: 274.00µs
execute: 50.00µs
new: 62.00µs
Machine d77ebe8481884bc3b2778c8083f1bf459e548e929edd87041beb14f6b868d35f: Machine d77ebe8481884bc3b2778c8083f1bf459e548e929edd87041beb14f6b868d35f:
Platform: macOS-13.1-arm64-arm-64bit Platform: macOS-13.1-arm64-arm-64bit
Timestamp: 2023-02-03 12:08:43.454860+00:00 Timestamp: 2023-02-03 12:08:43.454860+00:00

View File

@ -27,7 +27,7 @@ class TextReporter:
def save_text_report(self, file): def save_text_report(self, file):
"""Save report to the file.""" """Save report to the file."""
for machine_id, machine in self.data.items(): for machine_id, machine in _sorted_items(self.data):
_print_indent("Machine {}:".format(machine_id), _print_indent("Machine {}:".format(machine_id),
indent=0, file=file) indent=0, file=file)
self._save_machine(machine, file=file) self._save_machine(machine, file=file)
@ -43,7 +43,7 @@ class TextReporter:
_print_indent("Benches:", indent=indent, file=file) _print_indent("Benches:", indent=indent, file=file)
nested_indent = indent + self.indent_step nested_indent = indent + self.indent_step
for bench_name, bench in machine["benches"].items(): for bench_name, bench in _sorted_items(machine["benches"]):
self._save_bench( self._save_bench(
bench_name, bench, indent=nested_indent, file=file) bench_name, bench, indent=nested_indent, file=file)
@ -52,7 +52,7 @@ class TextReporter:
"{} ({}): {}".format( "{} ({}): {}".format(
bench_name, bench["total_time"], bench["comment"]), bench_name, bench["total_time"], bench["comment"]),
indent=indent, file=file) indent=indent, file=file)
for fname, stats in bench["stats"].items(): for fname, stats in _sorted_items(bench["stats"]):
self._save_stats(fname, stats, indent + self.indent_step, file) self._save_stats(fname, stats, indent + self.indent_step, file)
def _save_stats(self, fname, stats, indent, file): def _save_stats(self, fname, stats, indent, file):
@ -63,7 +63,7 @@ class TextReporter:
"{}: {}".format(fname, duration), "{}: {}".format(fname, duration),
indent=indent, indent=indent,
file=file) file=file)
for nested_fname, nested_stats in stats["nested"].items(): for nested_fname, nested_stats in _sorted_items(stats["nested"]):
self._save_stats(nested_fname, nested_stats, self._save_stats(nested_fname, nested_stats,
indent=(indent + self.indent_step), file=file) indent=(indent + self.indent_step), file=file)
else: else:
@ -74,3 +74,6 @@ class TextReporter:
def _print_indent(line, indent, file): def _print_indent(line, indent, file):
print("{:<{indent}}{}".format("", line, indent=indent), file=file) print("{:<{indent}}{}".format("", line, indent=indent), file=file)
def _sorted_items(d):
return sorted(d.items(), key=lambda pair: pair[0])