cg-data.log

2021-07-09 16:51:21.949 [info] <0.19554.0>@miner_util:mark:81 starting miner_hbbft_handler mark at init
2021-07-09 16:53:16.648 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval init to next_round was 114699ms
2021-07-09 16:53:16.727 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 79ms
2021-07-09 16:53:57.032 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 40305ms
2021-07-09 16:53:57.120 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 88ms
2021-07-09 16:54:14.105 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 16984ms
2021-07-09 16:54:14.430 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 325ms
2021-07-09 16:54:49.688 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 35259ms
2021-07-09 16:54:59.950 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10262ms
2021-07-09 16:55:15.436 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15486ms
2021-07-09 16:55:17.657 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2221ms
2021-07-09 16:55:17.657 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913445 took: 2221 ms
2021-07-09 16:55:18.312 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 654ms
2021-07-09 16:55:18.412 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 100ms
2021-07-09 16:55:31.613 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13202ms
2021-07-09 16:55:32.244 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 631ms
2021-07-09 16:55:49.859 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 17615ms
2021-07-09 16:56:05.226 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15367ms
2021-07-09 16:56:06.511 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1285ms
2021-07-09 16:56:06.511 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913446 took: 1285 ms
2021-07-09 16:56:06.773 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 261ms
2021-07-09 16:56:07.375 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 603ms
2021-07-09 16:56:23.461 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16086ms
2021-07-09 16:56:24.016 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 555ms
2021-07-09 16:56:39.608 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15592ms
2021-07-09 16:56:56.014 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16405ms
2021-07-09 16:56:57.195 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1182ms
2021-07-09 16:56:57.195 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913447 took: 1181 ms
2021-07-09 16:56:57.397 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 202ms
2021-07-09 16:56:57.916 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 519ms
2021-07-09 16:57:14.223 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16307ms
2021-07-09 16:57:14.763 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 540ms
2021-07-09 16:57:29.290 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14526ms
2021-07-09 16:57:45.914 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16625ms
2021-07-09 16:57:46.782 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 868ms
2021-07-09 16:57:46.782 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913448 took: 868 ms
2021-07-09 16:57:46.911 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 129ms
2021-07-09 16:57:48.063 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 1152ms
2021-07-09 16:58:01.933 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13870ms
2021-07-09 16:58:02.489 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 555ms
2021-07-09 16:58:20.286 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 17798ms
2021-07-09 16:58:36.496 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16209ms
2021-07-09 16:58:37.646 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1151ms
2021-07-09 16:58:37.646 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913449 took: 1151 ms
2021-07-09 16:58:37.817 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 171ms
2021-07-09 16:58:38.397 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 580ms
2021-07-09 16:58:54.102 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15704ms
2021-07-09 16:58:54.633 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 531ms
2021-07-09 16:59:09.153 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14521ms
2021-07-09 16:59:27.093 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17939ms
2021-07-09 16:59:28.286 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1193ms
2021-07-09 16:59:28.286 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913450 took: 1193 ms
2021-07-09 16:59:28.526 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 241ms
2021-07-09 16:59:29.169 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 643ms
2021-07-09 16:59:44.163 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14994ms
2021-07-09 16:59:44.718 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 555ms
2021-07-09 16:59:59.212 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14494ms
2021-07-09 17:00:16.485 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17273ms
2021-07-09 17:00:18.040 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1555ms
2021-07-09 17:00:18.040 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913451 took: 1555 ms
2021-07-09 17:00:18.438 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 397ms
2021-07-09 17:00:18.720 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 283ms
2021-07-09 17:00:33.494 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14773ms
2021-07-09 17:00:34.103 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 610ms
2021-07-09 17:00:49.570 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15466ms
2021-07-09 17:01:07.267 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17698ms
2021-07-09 17:01:09.474 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2207ms
2021-07-09 17:01:09.474 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913452 took: 2207 ms
2021-07-09 17:01:09.928 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 454ms
2021-07-09 17:01:10.473 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 545ms
2021-07-09 17:01:32.611 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 22138ms
2021-07-09 17:01:33.636 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1025ms
2021-07-09 17:01:39.698 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 6062ms
2021-07-09 17:01:56.179 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16480ms
2021-07-09 17:01:58.434 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2256ms
2021-07-09 17:01:58.434 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913453 took: 2256 ms
2021-07-09 17:01:58.954 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 520ms
2021-07-09 17:01:59.698 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 743ms
2021-07-09 17:02:14.466 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14769ms
2021-07-09 17:02:15.243 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 777ms
2021-07-09 17:02:29.519 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14275ms
2021-07-09 17:02:47.073 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17555ms
2021-07-09 17:02:49.444 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2371ms
2021-07-09 17:02:49.444 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913454 took: 2371 ms
2021-07-09 17:02:50.322 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 878ms
2021-07-09 17:02:50.739 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 417ms
2021-07-09 17:03:04.748 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14008ms
2021-07-09 17:03:05.827 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1079ms
2021-07-09 17:03:19.889 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14063ms
2021-07-09 17:03:36.702 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16813ms
2021-07-09 17:03:40.361 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3659ms
2021-07-09 17:03:40.361 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913455 took: 3659 ms
2021-07-09 17:03:42.233 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1872ms
2021-07-09 17:03:42.550 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 317ms
2021-07-09 17:03:55.783 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13233ms
2021-07-09 17:03:57.035 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1252ms
2021-07-09 17:04:09.982 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12947ms
2021-07-09 17:04:27.873 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17891ms
2021-07-09 17:04:31.129 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3255ms
2021-07-09 17:04:31.129 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913456 took: 3256 ms
2021-07-09 17:04:32.489 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1361ms
2021-07-09 17:04:33.162 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 673ms
2021-07-09 17:04:47.497 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14335ms
2021-07-09 17:04:48.823 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1325ms
2021-07-09 17:04:59.520 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10698ms
2021-07-09 17:05:17.085 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17565ms
2021-07-09 17:05:21.185 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4100ms
2021-07-09 17:05:21.185 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913457 took: 4100 ms
2021-07-09 17:05:23.520 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2335ms
2021-07-09 17:05:23.985 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 465ms
2021-07-09 17:05:36.248 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12263ms
2021-07-09 17:05:37.382 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1134ms
2021-07-09 17:05:49.265 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 11883ms
2021-07-09 17:06:07.273 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18008ms
2021-07-09 17:06:10.829 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3556ms
2021-07-09 17:06:10.829 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913458 took: 3556 ms
2021-07-09 17:06:12.157 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1328ms
2021-07-09 17:06:12.624 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 467ms
2021-07-09 17:06:25.573 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12948ms
2021-07-09 17:06:26.889 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1317ms
2021-07-09 17:06:39.784 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12894ms
2021-07-09 17:06:59.755 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19971ms
2021-07-09 17:07:04.722 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4968ms
2021-07-09 17:07:04.722 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913459 took: 4968 ms
2021-07-09 17:07:07.027 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2303ms
2021-07-09 17:07:07.882 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 857ms
2021-07-09 17:07:18.198 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10315ms
2021-07-09 17:07:19.328 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1131ms
2021-07-09 17:07:29.217 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 9889ms
2021-07-09 17:07:48.144 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18926ms
2021-07-09 17:07:52.112 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3969ms
2021-07-09 17:07:52.112 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913460 took: 3969 ms
2021-07-09 17:07:53.673 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1561ms
2021-07-09 17:07:54.039 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 366ms
2021-07-09 17:08:06.666 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12627ms
2021-07-09 17:08:08.071 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1404ms
2021-07-09 17:08:19.762 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 11692ms
2021-07-09 17:08:38.948 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19186ms
2021-07-09 17:08:42.965 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4017ms
2021-07-09 17:08:42.965 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913461 took: 4017 ms
2021-07-09 17:08:45.833 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2868ms
2021-07-09 17:08:46.477 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 644ms
2021-07-09 17:09:00.292 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13815ms
2021-07-09 17:09:01.512 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1220ms
2021-07-09 17:09:09.345 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 7833ms
2021-07-09 17:09:27.098 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17753ms
2021-07-09 17:09:31.050 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3952ms
2021-07-09 17:09:31.051 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913462 took: 3952 ms
2021-07-09 17:09:32.922 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1872ms
2021-07-09 17:09:33.404 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 482ms
2021-07-09 17:09:47.471 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14067ms
2021-07-09 17:09:49.056 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1585ms
2021-07-09 17:09:59.619 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10563ms
2021-07-09 17:10:18.454 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18835ms
2021-07-09 17:10:22.586 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4132ms
2021-07-09 17:10:22.586 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913463 took: 4132 ms
2021-07-09 17:10:24.312 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1726ms
2021-07-09 17:10:24.831 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 519ms
2021-07-09 17:10:35.126 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10295ms
2021-07-09 17:10:36.049 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 923ms
2021-07-09 17:10:49.146 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13097ms
2021-07-09 17:11:07.707 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18560ms
2021-07-09 17:11:10.987 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3281ms
2021-07-09 17:11:10.987 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913464 took: 3281 ms
2021-07-09 17:11:11.996 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1009ms
2021-07-09 17:11:12.401 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 405ms
2021-07-09 17:11:23.406 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 11004ms
2021-07-09 17:11:24.453 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1048ms
2021-07-09 17:11:39.519 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15066ms
2021-07-09 17:11:57.652 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18133ms
2021-07-09 17:12:01.503 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3850ms
2021-07-09 17:12:01.503 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913465 took: 3851 ms
2021-07-09 17:12:03.334 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1832ms
2021-07-09 17:12:03.768 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 434ms
2021-07-09 17:12:13.219 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 9451ms
2021-07-09 17:12:14.296 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1077ms
2021-07-09 17:12:29.225 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14928ms
2021-07-09 17:12:49.008 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19784ms
2021-07-09 17:12:51.520 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2512ms
2021-07-09 17:12:51.520 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913466 took: 2512 ms
2021-07-09 17:12:52.171 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 651ms
2021-07-09 17:12:52.644 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 473ms
2021-07-09 17:13:01.818 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 9174ms
2021-07-09 17:13:02.842 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1024ms
2021-07-09 17:13:20.087 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 17244ms
2021-07-09 17:13:37.788 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17702ms
2021-07-09 17:13:41.232 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3444ms
2021-07-09 17:13:41.232 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913467 took: 3444 ms
2021-07-09 17:13:42.288 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1056ms
2021-07-09 17:13:42.894 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 606ms
2021-07-09 17:13:51.689 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 8795ms
2021-07-09 17:13:52.825 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1136ms
2021-07-09 17:14:09.809 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 16983ms
2021-07-09 17:14:26.868 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17060ms
2021-07-09 17:14:29.295 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2427ms
2021-07-09 17:14:29.295 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913468 took: 2427 ms
2021-07-09 17:14:29.972 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 677ms
2021-07-09 17:14:30.688 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 716ms
2021-07-09 17:14:41.289 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10601ms
2021-07-09 17:14:42.321 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1032ms
2021-07-09 17:14:59.346 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 17025ms
2021-07-09 17:15:17.471 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18124ms
2021-07-09 17:15:20.429 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2959ms
2021-07-09 17:15:20.429 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913469 took: 2959 ms
2021-07-09 17:15:21.415 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 986ms
2021-07-09 17:15:21.774 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 358ms
2021-07-09 17:15:31.439 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 9666ms
2021-07-09 17:15:32.452 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1013ms
2021-07-09 17:15:49.529 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 17076ms
2021-07-09 17:16:07.586 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18057ms
2021-07-09 17:16:10.030 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2445ms
2021-07-09 17:16:10.030 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913470 took: 2445 ms
2021-07-09 17:16:10.813 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 782ms
2021-07-09 17:16:11.017 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 205ms
2021-07-09 17:16:21.997 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10980ms
2021-07-09 17:16:23.016 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1019ms
2021-07-09 17:16:40.310 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 17294ms
2021-07-09 17:16:57.642 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17332ms
2021-07-09 17:17:00.953 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3311ms
2021-07-09 17:17:00.953 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913471 took: 3311 ms
2021-07-09 17:17:02.394 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1441ms
2021-07-09 17:17:02.841 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 447ms
2021-07-09 17:17:13.574 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10733ms
2021-07-09 17:17:14.702 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1128ms
2021-07-09 17:17:29.690 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14988ms
2021-07-09 17:17:46.574 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16884ms
2021-07-09 17:17:49.027 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2453ms
2021-07-09 17:17:49.027 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913472 took: 2453 ms
2021-07-09 17:17:49.852 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 825ms
2021-07-09 17:17:50.102 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 250ms
2021-07-09 17:18:04.312 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14210ms
2021-07-09 17:18:05.346 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1033ms
2021-07-09 17:18:07.493 [info] <0.15959.1>@miner_util:mark:81 starting miner_hbbft_handler mark at init
2021-07-09 17:18:19.409 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14064ms
2021-07-09 17:18:36.204 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16794ms
2021-07-09 17:18:39.149 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2946ms
2021-07-09 17:18:39.149 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913473 took: 2946 ms
2021-07-09 17:18:40.521 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1372ms
2021-07-09 17:18:40.924 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 402ms
2021-07-09 17:18:55.062 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14138ms
2021-07-09 17:18:56.426 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1365ms
2021-07-09 17:19:09.087 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12661ms
2021-07-09 17:19:27.374 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18287ms
2021-07-09 17:20:23.429 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 56055ms
2021-07-09 17:20:23.429 [info] <0.19554.0>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913474 took: 56055 ms
2021-07-09 17:20:25.609 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2180ms
2021-07-09 17:20:27.380 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 1771ms
2021-07-09 17:20:36.409 [info] <0.19554.0>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 9029ms
2021-07-09 17:20:38.177 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval init to next_round was 150684ms
2021-07-09 17:20:40.493 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 2316ms
2021-07-09 17:20:46.065 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 5572ms
2021-07-09 17:20:47.902 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1837ms
2021-07-09 17:20:47.902 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913475 took: 1837 ms
2021-07-09 17:20:48.631 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 729ms
2021-07-09 17:20:48.817 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 186ms
2021-07-09 17:21:04.564 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15746ms
2021-07-09 17:21:07.811 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 3248ms
2021-07-09 17:21:09.688 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1877ms
2021-07-09 17:21:38.319 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 28630ms
2021-07-09 17:21:48.492 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10174ms
2021-07-09 17:22:04.722 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16230ms
2021-07-09 17:22:07.880 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3158ms
2021-07-09 17:22:07.880 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913477 took: 3158 ms
2021-07-09 17:22:09.305 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1424ms
2021-07-09 17:22:09.452 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 148ms
2021-07-09 17:22:23.492 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14040ms
2021-07-09 17:22:24.308 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 816ms
2021-07-09 17:22:38.556 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14248ms
2021-07-09 17:22:54.332 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15776ms
2021-07-09 17:22:56.166 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1833ms
2021-07-09 17:22:56.166 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913478 took: 1833 ms
2021-07-09 17:22:56.642 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 477ms
2021-07-09 17:22:56.833 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 191ms
2021-07-09 17:23:12.567 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15734ms
2021-07-09 17:23:13.180 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 612ms
2021-07-09 17:23:28.747 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15568ms
2021-07-09 17:23:44.394 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15647ms
2021-07-09 17:23:45.246 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 852ms
2021-07-09 17:23:45.246 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913479 took: 852 ms
2021-07-09 17:23:45.450 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 204ms
2021-07-09 17:23:45.943 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 493ms
2021-07-09 17:24:00.115 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14171ms
2021-07-09 17:24:00.669 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 555ms
2021-07-09 17:24:18.123 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 17453ms
2021-07-09 17:24:34.694 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16571ms
2021-07-09 17:24:35.935 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1242ms
2021-07-09 17:24:35.935 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913480 took: 1242 ms
2021-07-09 17:24:36.164 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 229ms
2021-07-09 17:24:36.392 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 228ms
2021-07-09 17:24:53.252 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16860ms
2021-07-09 17:24:53.827 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 575ms
2021-07-09 17:25:08.306 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14479ms
2021-07-09 17:25:25.349 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17043ms
2021-07-09 17:25:26.270 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 921ms
2021-07-09 17:25:26.270 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913481 took: 921 ms
2021-07-09 17:25:26.472 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 202ms
2021-07-09 17:25:27.110 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 637ms
2021-07-09 17:25:42.693 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15584ms
2021-07-09 17:25:43.233 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 540ms
2021-07-09 17:25:58.982 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15749ms
2021-07-09 17:26:15.692 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16710ms
2021-07-09 17:26:17.322 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1630ms
2021-07-09 17:26:17.323 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913482 took: 1630 ms
2021-07-09 17:26:17.697 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 375ms
2021-07-09 17:26:17.850 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 153ms
2021-07-09 17:26:32.930 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15080ms
2021-07-09 17:26:33.553 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 622ms
2021-07-09 17:26:49.205 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15652ms
2021-07-09 17:27:06.184 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16980ms
2021-07-09 17:27:07.569 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1384ms
2021-07-09 17:27:07.569 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913483 took: 1384 ms
2021-07-09 17:27:08.005 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 437ms
2021-07-09 17:27:08.132 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 126ms
2021-07-09 17:27:21.407 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13276ms
2021-07-09 17:27:22.101 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 694ms
2021-07-09 17:27:38.498 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 16397ms
2021-07-09 17:27:57.743 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19245ms
2021-07-09 17:28:00.006 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2263ms
2021-07-09 17:28:00.006 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913484 took: 2263 ms
2021-07-09 17:28:00.623 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 617ms
2021-07-09 17:28:00.968 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 344ms
2021-07-09 17:28:15.556 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14589ms
2021-07-09 17:28:16.344 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 788ms
2021-07-09 17:28:28.660 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12315ms
2021-07-09 17:28:48.168 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19508ms
2021-07-09 17:28:50.673 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2505ms
2021-07-09 17:28:50.673 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913485 took: 2505 ms
2021-07-09 17:28:51.445 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 773ms
2021-07-09 17:28:51.893 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 448ms
2021-07-09 17:29:04.716 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12823ms
2021-07-09 17:29:05.569 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 853ms
2021-07-09 17:29:18.850 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13281ms
2021-07-09 17:29:40.068 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 21218ms
2021-07-09 17:29:42.802 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2733ms
2021-07-09 17:29:42.802 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913486 took: 2733 ms
2021-07-09 17:29:43.548 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 747ms
2021-07-09 17:29:44.192 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 644ms
2021-07-09 17:30:01.515 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17323ms
2021-07-09 17:30:02.900 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1384ms
2021-07-09 17:30:08.642 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 5743ms
2021-07-09 17:30:27.256 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18614ms
2021-07-09 17:30:30.327 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3071ms
2021-07-09 17:30:30.327 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913487 took: 3071 ms
2021-07-09 17:30:31.155 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 828ms
2021-07-09 17:30:31.654 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 499ms
2021-07-09 17:30:46.870 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15215ms
2021-07-09 17:30:48.274 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1404ms
2021-07-09 17:30:59.095 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10822ms
2021-07-09 17:31:17.428 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18333ms
2021-07-09 17:31:21.333 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3905ms
2021-07-09 17:31:21.333 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913488 took: 3905 ms
2021-07-09 17:31:22.495 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1162ms
2021-07-09 17:31:23.030 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 535ms
2021-07-09 17:31:37.921 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14890ms
2021-07-09 17:31:39.425 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1505ms
2021-07-09 17:31:49.096 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 9671ms
2021-07-09 17:32:07.165 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18069ms
2021-07-09 17:32:10.582 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3417ms
2021-07-09 17:32:10.582 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913489 took: 3417 ms
2021-07-09 17:32:11.914 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1332ms
2021-07-09 17:32:12.718 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 804ms
2021-07-09 17:32:25.901 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13183ms
2021-07-09 17:32:27.346 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1445ms
2021-07-09 17:32:39.215 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 11869ms
2021-07-09 17:32:59.396 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 20180ms
2021-07-09 17:33:04.383 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4988ms
2021-07-09 17:33:04.383 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913490 took: 4988 ms
2021-07-09 17:33:06.850 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2467ms
2021-07-09 17:33:07.888 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 1038ms
2021-07-09 17:33:21.583 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13695ms
2021-07-09 17:33:23.719 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 2136ms
2021-07-09 17:33:28.678 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 4959ms
2021-07-09 17:33:51.618 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 22940ms
2021-07-09 17:33:56.114 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4495ms
2021-07-09 17:33:56.114 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913491 took: 4495 ms
2021-07-09 17:33:58.109 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1996ms
2021-07-09 17:33:59.103 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 994ms
2021-07-09 17:34:11.482 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12379ms
2021-07-09 17:34:12.745 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1263ms
2021-07-09 17:34:18.655 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 5910ms
2021-07-09 17:34:38.766 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 20111ms
2021-07-09 17:34:43.141 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4375ms
2021-07-09 17:34:43.141 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913492 took: 4375 ms
2021-07-09 17:34:44.904 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1763ms
2021-07-09 17:34:45.765 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 861ms
2021-07-09 17:34:59.387 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13622ms
2021-07-09 17:35:01.175 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1788ms
2021-07-09 17:35:08.494 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 7319ms
2021-07-09 17:35:29.608 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 21114ms
2021-07-09 17:35:33.899 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4291ms
2021-07-09 17:35:33.899 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913493 took: 4291 ms
2021-07-09 17:35:35.536 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1637ms
2021-07-09 17:35:36.944 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 1407ms
2021-07-09 17:35:49.511 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12568ms
2021-07-09 17:35:50.875 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1363ms
2021-07-09 17:35:58.656 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 7782ms
2021-07-09 17:36:21.752 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 23096ms
2021-07-09 17:36:25.154 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3402ms
2021-07-09 17:36:25.154 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913494 took: 3402 ms
2021-07-09 17:36:26.221 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1067ms
2021-07-09 17:36:27.104 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 882ms
2021-07-09 17:36:41.507 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14404ms
2021-07-09 17:36:43.463 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1956ms
2021-07-09 17:36:48.699 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 5236ms
2021-07-09 17:37:08.496 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19796ms
2021-07-09 17:37:12.771 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4275ms
2021-07-09 17:37:12.771 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913495 took: 4275 ms
2021-07-09 17:37:14.618 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1847ms
2021-07-09 17:37:15.775 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 1158ms
2021-07-09 17:37:28.202 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12427ms
2021-07-09 17:37:30.053 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1851ms
2021-07-09 17:37:38.208 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 8155ms
2021-07-09 17:38:14.007 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 35799ms
2021-07-09 17:38:19.421 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 5414ms
2021-07-09 17:38:19.421 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913496 took: 5414 ms
2021-07-09 17:38:21.795 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2374ms
2021-07-09 17:38:22.784 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 989ms
2021-07-09 17:38:36.351 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13567ms
2021-07-09 17:38:39.653 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 3302ms
2021-07-09 17:38:41.454 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1801ms
2021-07-09 17:38:48.711 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 7257ms
2021-07-09 17:38:52.890 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4179ms
2021-07-09 17:38:52.890 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913497 took: 4179 ms
2021-07-09 17:38:54.800 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1910ms
2021-07-09 17:38:55.183 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 383ms
2021-07-09 17:39:08.946 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13763ms
2021-07-09 17:39:10.847 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1901ms
2021-07-09 17:39:22.215 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 11368ms
2021-07-09 17:39:41.127 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18912ms
2021-07-09 17:39:45.046 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3919ms
2021-07-09 17:39:45.046 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913498 took: 3919 ms
2021-07-09 17:39:46.206 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1159ms
2021-07-09 17:39:46.697 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 492ms
2021-07-09 17:39:58.988 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12291ms
2021-07-09 17:40:00.265 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1277ms
2021-07-09 17:40:12.302 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12037ms
2021-07-09 17:40:32.035 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19733ms
2021-07-09 17:40:36.406 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4371ms
2021-07-09 17:40:36.406 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913499 took: 4371 ms
2021-07-09 17:40:37.975 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1569ms
2021-07-09 17:40:38.494 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 519ms
2021-07-09 17:40:50.817 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12323ms
2021-07-09 17:40:52.300 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1482ms
2021-07-09 17:41:02.165 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 9866ms
2021-07-09 17:41:21.726 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19561ms
2021-07-09 17:41:25.969 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4243ms
2021-07-09 17:41:25.969 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913500 took: 4243 ms
2021-07-09 17:41:27.468 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1499ms
2021-07-09 17:41:28.231 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 763ms
2021-07-09 17:41:38.356 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10125ms
2021-07-09 17:41:39.481 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1125ms
2021-07-09 17:41:51.462 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 11981ms
2021-07-09 17:42:11.969 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 20506ms
2021-07-09 17:42:16.186 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4218ms
2021-07-09 17:42:16.186 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913501 took: 4218 ms
2021-07-09 17:42:17.797 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1611ms
2021-07-09 17:42:18.216 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 418ms
2021-07-09 17:42:30.024 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 11809ms
2021-07-09 17:42:31.206 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1182ms
2021-07-09 17:42:41.037 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 9831ms
2021-07-09 17:43:00.525 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19487ms
2021-07-09 17:43:04.764 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4240ms
2021-07-09 17:43:04.764 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913502 took: 4240 ms
2021-07-09 17:43:06.435 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1671ms
2021-07-09 17:43:07.217 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 781ms
2021-07-09 17:43:20.616 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13400ms
2021-07-09 17:43:22.077 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1460ms
2021-07-09 17:43:31.829 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 9753ms
2021-07-09 17:43:53.136 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 21307ms
2021-07-09 17:43:57.878 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4741ms
2021-07-09 17:43:57.878 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913503 took: 4741 ms
2021-07-09 17:44:00.141 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2264ms
2021-07-09 17:44:00.586 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 445ms
2021-07-09 17:44:17.392 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16806ms
2021-07-09 17:44:20.418 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 3025ms
2021-07-09 17:44:21.906 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1489ms
2021-07-09 17:44:41.203 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19297ms
2021-07-09 17:44:44.962 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3759ms
2021-07-09 17:44:44.962 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913504 took: 3759 ms
2021-07-09 17:44:46.568 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1606ms
2021-07-09 17:44:47.128 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 560ms
2021-07-09 17:45:08.183 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 21055ms
2021-07-09 17:45:10.481 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 2297ms
2021-07-09 17:45:12.145 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1665ms
2021-07-09 17:45:29.982 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17837ms
2021-07-09 17:45:32.394 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2412ms
2021-07-09 17:45:32.394 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913505 took: 2412 ms
2021-07-09 17:45:32.988 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 593ms
2021-07-09 17:45:33.347 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 360ms
2021-07-09 17:45:47.528 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14181ms
2021-07-09 17:45:48.591 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1063ms
2021-07-09 17:46:01.726 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13135ms
2021-07-09 17:46:21.403 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19676ms
2021-07-09 17:46:24.248 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2846ms
2021-07-09 17:46:24.248 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913506 took: 2846 ms
2021-07-09 17:46:24.963 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 715ms
2021-07-09 17:46:25.662 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 699ms
2021-07-09 17:46:42.319 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16657ms
2021-07-09 17:46:43.544 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1225ms
2021-07-09 17:46:51.421 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 7877ms
2021-07-09 17:47:04.333 [info] <0.22838.2>@miner_util:mark:81 starting miner_hbbft_handler mark at init
2021-07-09 17:47:09.951 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18528ms
2021-07-09 17:47:12.834 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2885ms
2021-07-09 17:47:12.834 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913507 took: 2884 ms
2021-07-09 17:47:13.829 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 995ms
2021-07-09 17:47:14.288 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 459ms
2021-07-09 17:47:27.254 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12965ms
2021-07-09 17:47:28.513 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1259ms
2021-07-09 17:47:41.293 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12781ms
2021-07-09 17:47:59.871 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18578ms
2021-07-09 17:50:46.977 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 167106ms
2021-07-09 17:50:46.977 [info] <0.15959.1>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913508 took: 167106 ms
2021-07-09 17:50:49.535 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2558ms
2021-07-09 17:50:51.537 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 2002ms
2021-07-09 17:51:04.248 [info] <0.15959.1>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12711ms
2021-07-09 17:51:09.824 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval init to next_round was 245491ms
2021-07-09 17:51:13.449 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 3625ms
2021-07-09 17:52:03.261 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 49812ms
2021-07-09 17:52:05.861 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 2600ms
2021-07-09 17:52:21.945 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 16084ms
2021-07-09 17:52:24.182 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 2237ms
2021-07-09 17:52:40.070 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 15888ms
2021-07-09 17:52:41.640 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1570ms
2021-07-09 17:52:56.011 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 14371ms
2021-07-09 17:52:57.431 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1419ms
2021-07-09 17:53:41.460 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 44030ms
2021-07-09 17:53:42.210 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 750ms
2021-07-09 17:53:56.322 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 14112ms
2021-07-09 17:53:57.192 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 870ms
2021-07-09 17:54:28.072 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 30880ms
2021-07-09 17:54:43.085 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15012ms
2021-07-09 17:54:59.464 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16380ms
2021-07-09 17:55:00.817 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1353ms
2021-07-09 17:55:00.818 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913516 took: 1353 ms
2021-07-09 17:55:01.024 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 207ms
2021-07-09 17:55:01.592 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 568ms
2021-07-09 17:55:50.124 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 48532ms
2021-07-09 17:55:51.435 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1311ms
2021-07-09 17:55:51.994 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 559ms
2021-07-09 17:56:11.383 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 19389ms
2021-07-09 17:56:23.617 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12234ms
2021-07-09 17:56:48.764 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 25147ms
2021-07-09 17:56:50.441 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1677ms
2021-07-09 17:56:50.441 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913518 took: 1677 ms
2021-07-09 17:56:50.841 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 400ms
2021-07-09 17:56:51.330 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 489ms
2021-07-09 17:57:07.113 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15783ms
2021-07-09 17:57:07.905 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 792ms
2021-07-09 17:57:13.135 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 5230ms
2021-07-09 17:57:30.216 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17081ms
2021-07-09 17:57:32.294 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2078ms
2021-07-09 17:57:32.294 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913519 took: 2078 ms
2021-07-09 17:57:32.638 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 344ms
2021-07-09 17:57:33.003 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 365ms
2021-07-09 17:57:50.341 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17338ms
2021-07-09 17:57:51.083 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 742ms
2021-07-09 17:58:03.420 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12336ms
2021-07-09 17:58:28.562 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 25143ms
2021-07-09 17:58:31.805 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3243ms
2021-07-09 17:58:31.805 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913520 took: 3243 ms
2021-07-09 17:58:32.819 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1013ms
2021-07-09 17:58:33.339 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 521ms
2021-07-09 17:58:53.881 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 20542ms
2021-07-09 17:58:55.000 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1119ms
2021-07-09 17:58:55.325 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 324ms
2021-07-09 17:59:11.150 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15826ms
2021-07-09 17:59:14.318 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3167ms
2021-07-09 17:59:14.318 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913521 took: 3168 ms
2021-07-09 17:59:15.282 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 964ms
2021-07-09 17:59:15.477 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 196ms
2021-07-09 17:59:30.785 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15307ms
2021-07-09 17:59:31.477 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 692ms
2021-07-09 17:59:44.091 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12615ms
2021-07-09 18:00:00.640 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16549ms
2021-07-09 18:00:03.656 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3015ms
2021-07-09 18:00:03.656 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913522 took: 3015 ms
2021-07-09 18:00:04.287 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 632ms
2021-07-09 18:00:05.056 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 769ms
2021-07-09 18:00:19.236 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14180ms
2021-07-09 18:00:20.145 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 909ms
2021-07-09 18:00:33.272 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13127ms
2021-07-09 18:00:50.970 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17697ms
2021-07-09 18:00:54.277 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3308ms
2021-07-09 18:00:54.277 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913523 took: 3308 ms
2021-07-09 18:00:54.952 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 675ms
2021-07-09 18:00:55.422 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 469ms
2021-07-09 18:01:09.239 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13818ms
2021-07-09 18:01:10.198 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 959ms
2021-07-09 18:01:23.275 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13077ms
2021-07-09 18:01:41.943 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18668ms
2021-07-09 18:01:45.526 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3582ms
2021-07-09 18:01:45.526 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913524 took: 3583 ms
2021-07-09 18:01:46.544 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1019ms
2021-07-09 18:01:46.969 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 425ms
2021-07-09 18:01:58.196 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 11226ms
2021-07-09 18:01:58.942 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 747ms
2021-07-09 18:02:13.227 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14285ms
2021-07-09 18:02:31.734 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18507ms
2021-07-09 18:02:36.103 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4369ms
2021-07-09 18:02:36.103 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913525 took: 4369 ms
2021-07-09 18:02:37.524 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1421ms
2021-07-09 18:02:37.928 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 404ms
2021-07-09 18:02:51.399 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13471ms
2021-07-09 18:02:52.546 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1147ms
2021-07-09 18:03:03.522 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10976ms
2021-07-09 18:03:21.489 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17967ms
2021-07-09 18:03:25.449 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3959ms
2021-07-09 18:03:25.449 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913526 took: 3959 ms
2021-07-09 18:03:26.840 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1392ms
2021-07-09 18:03:27.171 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 331ms
2021-07-09 18:03:41.325 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14154ms
2021-07-09 18:03:42.385 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1060ms
2021-07-09 18:03:53.397 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 11012ms
2021-07-09 18:04:11.442 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18045ms
2021-07-09 18:04:15.679 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4237ms
2021-07-09 18:04:15.679 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913527 took: 4237 ms
2021-07-09 18:04:17.054 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1375ms
2021-07-09 18:04:17.863 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 809ms
2021-07-09 18:04:33.503 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15640ms
2021-07-09 18:04:34.617 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1114ms
2021-07-09 18:04:43.625 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 9008ms
2021-07-09 18:05:01.004 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17379ms
2021-07-09 18:05:04.458 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3454ms
2021-07-09 18:05:04.458 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913528 took: 3454 ms
2021-07-09 18:05:05.641 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1183ms
2021-07-09 18:05:06.315 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 673ms
2021-07-09 18:05:20.206 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13892ms
2021-07-09 18:05:21.666 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1460ms
2021-07-09 18:05:33.232 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 11566ms
2021-07-09 18:05:51.946 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18713ms
2021-07-09 18:05:57.067 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 5122ms
2021-07-09 18:05:57.067 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913529 took: 5122 ms
2021-07-09 18:05:59.100 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2033ms
2021-07-09 18:05:59.669 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 568ms
2021-07-09 18:06:09.912 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10244ms
2021-07-09 18:06:11.140 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1228ms
2021-07-09 18:06:24.251 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13110ms
2021-07-09 18:06:40.754 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16504ms
2021-07-09 18:06:43.858 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3104ms
2021-07-09 18:06:43.858 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913530 took: 3104 ms
2021-07-09 18:06:44.787 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 929ms
2021-07-09 18:06:45.210 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 423ms
2021-07-09 18:06:56.024 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10814ms
2021-07-09 18:06:56.992 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 968ms
2021-07-09 18:07:13.052 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 16060ms
2021-07-09 18:07:30.560 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17508ms
2021-07-09 18:07:35.376 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4815ms
2021-07-09 18:07:35.376 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913531 took: 4815 ms
2021-07-09 18:07:38.026 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2651ms
2021-07-09 18:07:38.734 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 707ms
2021-07-09 18:07:52.380 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13647ms
2021-07-09 18:07:53.550 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1169ms
2021-07-09 18:08:03.478 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 9929ms
2021-07-09 18:08:19.561 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16083ms
2021-07-09 18:08:23.270 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3709ms
2021-07-09 18:08:23.270 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913532 took: 3709 ms
2021-07-09 18:08:24.329 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1059ms
2021-07-09 18:08:24.810 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 481ms
2021-07-09 18:08:35.368 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10558ms
2021-07-09 18:08:36.240 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 872ms
2021-07-09 18:08:53.400 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 17160ms
2021-07-09 18:09:11.038 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17638ms
2021-07-09 18:09:15.153 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4114ms
2021-07-09 18:09:15.153 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913533 took: 4115 ms
2021-07-09 18:09:16.634 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1482ms
2021-07-09 18:09:17.320 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 686ms
2021-07-09 18:09:28.227 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 10906ms
2021-07-09 18:09:29.351 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1125ms
2021-07-09 18:09:43.246 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13895ms
2021-07-09 18:10:01.756 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18510ms
2021-07-09 18:10:05.820 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4064ms
2021-07-09 18:10:05.820 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913534 took: 4064 ms
2021-07-09 18:10:07.598 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1778ms
2021-07-09 18:10:08.104 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 506ms
2021-07-09 18:10:20.721 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12617ms
2021-07-09 18:10:21.808 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1086ms
2021-07-09 18:10:33.961 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12154ms
2021-07-09 18:10:50.311 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16350ms
2021-07-09 18:10:53.755 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3443ms
2021-07-09 18:10:53.755 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913535 took: 3443 ms
2021-07-09 18:10:54.929 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1175ms
2021-07-09 18:10:55.468 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 539ms
2021-07-09 18:11:08.084 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12616ms
2021-07-09 18:11:09.118 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1034ms
2021-07-09 18:11:23.106 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13988ms
2021-07-09 18:11:40.395 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17289ms
2021-07-09 18:11:42.817 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2422ms
2021-07-09 18:11:42.817 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913536 took: 2422 ms
2021-07-09 18:11:43.454 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 636ms
2021-07-09 18:11:43.929 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 476ms
2021-07-09 18:11:59.672 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15742ms
2021-07-09 18:12:01.019 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1348ms
2021-07-09 18:12:13.851 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12832ms
2021-07-09 18:12:30.458 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16606ms
2021-07-09 18:12:33.297 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2840ms
2021-07-09 18:12:33.297 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913537 took: 2840 ms
2021-07-09 18:12:34.176 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 879ms
2021-07-09 18:12:34.511 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 335ms
2021-07-09 18:12:51.170 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16659ms
2021-07-09 18:12:52.307 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1136ms
2021-07-09 18:13:03.189 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10883ms
2021-07-09 18:13:20.722 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17533ms
2021-07-09 18:13:23.064 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2342ms
2021-07-09 18:13:23.064 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913538 took: 2342 ms
2021-07-09 18:13:24.284 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1220ms
2021-07-09 18:13:24.936 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 652ms
2021-07-09 18:13:48.792 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 23856ms
2021-07-09 18:13:50.299 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1507ms
2021-07-09 18:13:54.111 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 3812ms
2021-07-09 18:14:12.935 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18824ms
2021-07-09 18:14:15.622 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2687ms
2021-07-09 18:14:15.623 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913539 took: 2687 ms
2021-07-09 18:14:16.476 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 854ms
2021-07-09 18:14:16.998 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 521ms
2021-07-09 18:14:33.260 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16263ms
2021-07-09 18:14:34.736 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1476ms
2021-07-09 18:14:43.364 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 8628ms
2021-07-09 18:15:00.117 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16752ms
2021-07-09 18:15:03.038 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2922ms
2021-07-09 18:15:03.038 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913540 took: 2922 ms
2021-07-09 18:15:04.049 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1010ms
2021-07-09 18:15:04.434 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 386ms
2021-07-09 18:15:21.239 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16805ms
2021-07-09 18:15:22.359 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1119ms
2021-07-09 18:15:33.305 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10947ms
2021-07-09 18:15:42.382 [info] <0.24240.3>@miner_util:mark:81 starting miner_hbbft_handler mark at init
2021-07-09 18:15:51.251 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17946ms
2021-07-09 18:15:54.172 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2921ms
2021-07-09 18:15:54.172 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913541 took: 2921 ms
2021-07-09 18:15:55.178 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1006ms
2021-07-09 18:15:55.777 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 599ms
2021-07-09 18:16:12.977 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17200ms
2021-07-09 18:16:14.960 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1983ms
2021-07-09 18:16:24.384 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 9424ms
2021-07-09 18:16:40.813 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16429ms
2021-07-09 18:17:20.786 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 39972ms
2021-07-09 18:17:20.786 [info] <0.22838.2>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913542 took: 39972 ms
2021-07-09 18:17:23.131 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2346ms
2021-07-09 18:17:25.066 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 1935ms
2021-07-09 18:17:44.231 [info] <0.22838.2>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 19165ms
2021-07-09 18:17:45.927 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval init to next_round was 123545ms
2021-07-09 18:17:48.870 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 2943ms
2021-07-09 18:18:10.265 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 21394ms
2021-07-09 18:18:12.753 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2489ms
2021-07-09 18:18:12.753 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913543 took: 2489 ms
2021-07-09 18:18:14.112 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1359ms
2021-07-09 18:18:14.391 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 279ms
2021-07-09 18:18:31.407 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17016ms
2021-07-09 18:18:34.850 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 3443ms
2021-07-09 18:18:37.509 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 2659ms
2021-07-09 18:19:02.348 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 24839ms
2021-07-09 18:19:14.411 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12063ms
2021-07-09 18:19:31.043 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16631ms
2021-07-09 18:19:33.394 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2352ms
2021-07-09 18:19:33.394 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913545 took: 2352 ms
2021-07-09 18:19:34.113 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 719ms
2021-07-09 18:19:34.415 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 302ms
2021-07-09 18:19:50.375 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15959ms
2021-07-09 18:19:51.417 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1043ms
2021-07-09 18:20:04.475 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13058ms
2021-07-09 18:20:20.884 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16409ms
2021-07-09 18:20:23.174 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2290ms
2021-07-09 18:20:23.174 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913546 took: 2290 ms
2021-07-09 18:20:23.789 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 615ms
2021-07-09 18:20:23.943 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 154ms
2021-07-09 18:20:45.075 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 21132ms
2021-07-09 18:20:46.259 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1184ms
2021-07-09 18:20:54.100 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 7841ms
2021-07-09 18:21:09.612 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15512ms
2021-07-09 18:21:11.920 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2308ms
2021-07-09 18:21:11.920 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913547 took: 2308 ms
2021-07-09 18:21:12.510 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 590ms
2021-07-09 18:21:12.781 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 271ms
2021-07-09 18:21:27.813 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15032ms
2021-07-09 18:21:28.422 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 608ms
2021-07-09 18:21:45.090 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 16669ms
2021-07-09 18:22:00.076 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 14986ms
2021-07-09 18:22:01.650 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1574ms
2021-07-09 18:22:01.650 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913548 took: 1574 ms
2021-07-09 18:22:02.039 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 389ms
2021-07-09 18:22:02.373 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 334ms
2021-07-09 18:22:19.628 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17255ms
2021-07-09 18:22:20.285 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 657ms
2021-07-09 18:22:34.863 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14578ms
2021-07-09 18:22:49.405 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 14542ms
2021-07-09 18:22:50.808 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1403ms
2021-07-09 18:22:50.808 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913549 took: 1403 ms
2021-07-09 18:22:51.255 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 447ms
2021-07-09 18:22:51.484 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 228ms
2021-07-09 18:23:08.982 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17499ms
2021-07-09 18:23:09.675 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 693ms
2021-07-09 18:23:26.179 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 16504ms
2021-07-09 18:24:47.069 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 80890ms
2021-07-09 18:24:50.227 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3158ms
2021-07-09 18:24:50.227 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913550 took: 3158 ms
2021-07-09 18:24:51.157 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 930ms
2021-07-09 18:24:56.974 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 5817ms
2021-07-09 18:25:15.289 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 18315ms
2021-07-09 18:25:20.525 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 5235ms
2021-07-09 18:25:21.603 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1079ms
2021-07-09 18:25:31.055 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 9452ms
2021-07-09 18:25:33.066 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2011ms
2021-07-09 18:25:33.066 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913551 took: 2011 ms
2021-07-09 18:25:33.484 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 418ms
2021-07-09 18:25:34.559 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 1074ms
2021-07-09 18:25:50.314 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15756ms
2021-07-09 18:25:53.670 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 3356ms
2021-07-09 18:25:59.390 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 5720ms
2021-07-09 18:26:21.826 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 22436ms
2021-07-09 18:26:25.832 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4006ms
2021-07-09 18:26:25.832 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913552 took: 4006 ms
2021-07-09 18:26:27.563 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1731ms
2021-07-09 18:26:28.425 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 862ms
2021-07-09 18:26:45.877 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17452ms
2021-07-09 18:26:47.548 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1671ms
2021-07-09 18:26:50.184 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 2636ms
2021-07-09 18:27:06.058 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15874ms
2021-07-09 18:27:09.149 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3091ms
2021-07-09 18:27:09.149 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913553 took: 3091 ms
2021-07-09 18:27:10.211 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1062ms
2021-07-09 18:27:10.953 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 742ms
2021-07-09 18:27:28.091 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17138ms
2021-07-09 18:27:29.502 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1411ms
2021-07-09 18:27:39.107 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 9605ms
2021-07-09 18:27:55.889 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16782ms
2021-07-09 18:27:59.319 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3430ms
2021-07-09 18:27:59.319 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913554 took: 3430 ms
2021-07-09 18:28:00.566 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1247ms
2021-07-09 18:28:01.067 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 501ms
2021-07-09 18:28:18.811 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17744ms
2021-07-09 18:28:20.095 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1284ms
2021-07-09 18:28:30.257 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10162ms
2021-07-09 18:28:48.000 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17743ms
2021-07-09 18:28:50.977 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2976ms
2021-07-09 18:28:50.977 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913555 took: 2976 ms
2021-07-09 18:28:51.923 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 947ms
2021-07-09 18:28:52.377 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 454ms
2021-07-09 18:29:07.583 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15206ms
2021-07-09 18:29:09.109 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1526ms
2021-07-09 18:29:19.707 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10598ms
2021-07-09 18:29:36.332 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16625ms
2021-07-09 18:29:39.698 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3366ms
2021-07-09 18:29:39.698 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913556 took: 3366 ms
2021-07-09 18:29:40.841 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1143ms
2021-07-09 18:29:41.637 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 796ms
2021-07-09 18:29:56.613 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14976ms
2021-07-09 18:29:57.826 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1213ms
2021-07-09 18:30:09.903 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12077ms
2021-07-09 18:30:27.533 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17630ms
2021-07-09 18:30:30.656 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3123ms
2021-07-09 18:30:30.656 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913557 took: 3123 ms
2021-07-09 18:30:31.553 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 897ms
2021-07-09 18:30:31.897 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 344ms
2021-07-09 18:30:46.001 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 14104ms
2021-07-09 18:30:47.228 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1227ms
2021-07-09 18:31:00.410 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13182ms
2021-07-09 18:31:18.089 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17679ms
2021-07-09 18:31:22.135 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4046ms
2021-07-09 18:31:22.135 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913558 took: 4046 ms
2021-07-09 18:31:24.176 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2041ms
2021-07-09 18:31:24.728 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 551ms
2021-07-09 18:31:37.637 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12910ms
2021-07-09 18:31:39.308 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1670ms
2021-07-09 18:31:50.015 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 10708ms
2021-07-09 18:32:07.417 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17402ms
2021-07-09 18:32:12.569 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 5152ms
2021-07-09 18:32:12.569 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913559 took: 5152 ms
2021-07-09 18:32:15.211 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2641ms
2021-07-09 18:32:15.827 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 617ms
2021-07-09 18:32:29.767 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13940ms
2021-07-09 18:32:31.832 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 2065ms
2021-07-09 18:32:40.037 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 8205ms
2021-07-09 18:32:55.211 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15174ms
2021-07-09 18:32:58.355 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3144ms
2021-07-09 18:32:58.356 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913560 took: 3144 ms
2021-07-09 18:32:59.407 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1051ms
2021-07-09 18:32:59.634 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 228ms
2021-07-09 18:33:15.592 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15956ms
2021-07-09 18:33:17.056 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1466ms
2021-07-09 18:33:29.696 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12640ms
2021-07-09 18:33:45.990 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16294ms
2021-07-09 18:33:49.244 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3254ms
2021-07-09 18:33:49.244 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913561 took: 3254 ms
2021-07-09 18:33:50.383 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1139ms
2021-07-09 18:33:50.934 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 551ms
2021-07-09 18:34:03.400 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12466ms
2021-07-09 18:34:04.596 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1196ms
2021-07-09 18:34:19.478 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14882ms
2021-07-09 18:34:37.223 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17745ms
2021-07-09 18:34:40.354 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3131ms
2021-07-09 18:34:40.354 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913562 took: 3131 ms
2021-07-09 18:34:41.264 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 910ms
2021-07-09 18:34:41.815 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 551ms
2021-07-09 18:34:54.445 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12630ms
2021-07-09 18:34:55.791 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1346ms
2021-07-09 18:35:09.594 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13803ms
2021-07-09 18:35:27.685 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18091ms
2021-07-09 18:35:31.225 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3540ms
2021-07-09 18:35:31.225 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913563 took: 3540 ms
2021-07-09 18:35:32.560 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1335ms
2021-07-09 18:35:33.271 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 711ms
2021-07-09 18:35:46.096 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 12825ms
2021-07-09 18:35:47.662 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1566ms
2021-07-09 18:35:59.122 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 11459ms
2021-07-09 18:36:17.465 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18343ms
2021-07-09 18:36:20.550 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3086ms
2021-07-09 18:36:20.550 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913564 took: 3086 ms
2021-07-09 18:36:21.593 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1043ms
2021-07-09 18:36:21.834 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 241ms
2021-07-09 18:36:33.563 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 11729ms
2021-07-09 18:36:34.857 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1294ms
2021-07-09 18:36:49.702 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14845ms
2021-07-09 18:37:07.882 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18179ms
2021-07-09 18:37:11.045 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3164ms
2021-07-09 18:37:11.045 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913565 took: 3164 ms
2021-07-09 18:37:11.905 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 860ms
2021-07-09 18:37:12.725 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 819ms
2021-07-09 18:37:22.450 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 9725ms
2021-07-09 18:37:23.663 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1214ms
2021-07-09 18:37:39.586 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15923ms
2021-07-09 18:37:56.667 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17081ms
2021-07-09 18:38:00.395 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3728ms
2021-07-09 18:38:00.395 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913566 took: 3728 ms
2021-07-09 18:38:02.094 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1699ms
2021-07-09 18:38:02.702 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 608ms
2021-07-09 18:38:14.160 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 11458ms
2021-07-09 18:38:15.907 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1747ms
2021-07-09 18:38:29.208 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13301ms
2021-07-09 18:38:46.341 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17133ms
2021-07-09 18:38:51.030 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4689ms
2021-07-09 18:38:51.030 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913567 took: 4689 ms
2021-07-09 18:38:53.652 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2622ms
2021-07-09 18:38:54.497 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 845ms
2021-07-09 18:39:05.867 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 11369ms
2021-07-09 18:39:07.176 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1310ms
2021-07-09 18:39:20.228 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13051ms
2021-07-09 18:39:35.582 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15354ms
2021-07-09 18:39:38.888 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3307ms
2021-07-09 18:39:38.888 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913568 took: 3307 ms
2021-07-09 18:39:40.363 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1475ms
2021-07-09 18:39:40.691 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 328ms
2021-07-09 18:39:52.014 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 11323ms
2021-07-09 18:39:53.026 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1012ms
2021-07-09 18:40:10.368 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 17342ms
2021-07-09 18:40:25.920 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15552ms
2021-07-09 18:40:34.602 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 8682ms
2021-07-09 18:40:34.602 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913569 took: 8682 ms
2021-07-09 18:40:41.196 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 6594ms
2021-07-09 18:40:41.625 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 429ms
2021-07-09 18:40:59.562 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17937ms
2021-07-09 18:41:01.192 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1630ms
2021-07-09 18:41:02.602 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1410ms
2021-07-09 18:41:15.660 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 13058ms
2021-07-09 18:41:18.754 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3094ms
2021-07-09 18:41:18.754 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913570 took: 3094 ms
2021-07-09 18:41:19.843 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1089ms
2021-07-09 18:41:20.224 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 381ms
2021-07-09 18:41:35.292 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15068ms
2021-07-09 18:41:36.206 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 913ms
2021-07-09 18:41:49.306 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13101ms
2021-07-09 18:42:06.172 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16866ms
2021-07-09 18:42:09.953 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3781ms
2021-07-09 18:42:09.953 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913571 took: 3781 ms
2021-07-09 18:42:11.454 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1501ms
2021-07-09 18:42:11.959 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 504ms
2021-07-09 18:42:25.474 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13516ms
2021-07-09 18:42:26.652 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1178ms
2021-07-09 18:42:39.557 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12905ms
2021-07-09 18:42:56.182 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16624ms
2021-07-09 18:42:59.297 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3116ms
2021-07-09 18:42:59.297 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913572 took: 3116 ms
2021-07-09 18:43:00.172 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 875ms
2021-07-09 18:43:00.595 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 423ms
2021-07-09 18:43:14.440 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13845ms
2021-07-09 18:43:15.709 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1269ms
2021-07-09 18:43:29.601 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13892ms
2021-07-09 18:43:47.011 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17410ms
2021-07-09 18:43:50.223 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3212ms
2021-07-09 18:43:50.223 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913573 took: 3212 ms
2021-07-09 18:43:51.148 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 924ms
2021-07-09 18:43:51.380 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 233ms
2021-07-09 18:44:07.994 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16614ms
2021-07-09 18:44:09.100 [info] <0.4402.5>@miner_util:mark:81 starting miner_hbbft_handler mark at init
2021-07-09 18:44:09.410 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 1416ms
2021-07-09 18:44:20.474 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 11064ms
2021-07-09 18:44:38.655 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 18180ms
2021-07-09 18:47:20.979 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 162324ms
2021-07-09 18:47:20.979 [info] <0.24240.3>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913574 took: 162324 ms
2021-07-09 18:47:23.235 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 2257ms
2021-07-09 18:47:25.631 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 2396ms
2021-07-09 18:47:40.974 [info] <0.24240.3>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 15342ms
2021-07-09 18:47:47.300 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval init to next_round was 218200ms
2021-07-09 18:47:49.481 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 2181ms
2021-07-09 18:48:02.621 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 13140ms
2021-07-09 18:48:04.915 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 2294ms
2021-07-09 18:48:24.022 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 19107ms
2021-07-09 18:48:26.107 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 2085ms
2021-07-09 18:48:44.813 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 18706ms
2021-07-09 18:48:46.799 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1985ms
2021-07-09 18:49:29.126 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 42328ms
2021-07-09 18:49:30.489 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 1363ms
2021-07-09 18:49:47.133 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 16643ms
2021-07-09 18:49:48.094 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 962ms
2021-07-09 18:50:14.825 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to next_round was 26731ms
2021-07-09 18:50:30.171 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15346ms
2021-07-09 18:50:45.376 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 15205ms
2021-07-09 18:50:46.545 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1169ms
2021-07-09 18:50:46.545 [info] <0.4402.5>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913581 took: 1169 ms
2021-07-09 18:50:46.834 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 289ms
2021-07-09 18:50:47.067 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 232ms
2021-07-09 18:51:03.542 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16476ms
2021-07-09 18:51:04.101 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 559ms
2021-07-09 18:51:19.706 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15605ms
2021-07-09 18:51:35.890 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16184ms
2021-07-09 18:51:37.616 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1726ms
2021-07-09 18:51:37.616 [info] <0.4402.5>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913582 took: 1726 ms
2021-07-09 18:51:38.114 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 498ms
2021-07-09 18:51:38.329 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 215ms
2021-07-09 18:51:54.906 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16577ms
2021-07-09 18:51:55.467 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 561ms
2021-07-09 18:52:10.220 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14753ms
2021-07-09 18:52:24.998 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 14778ms
2021-07-09 18:52:26.772 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1774ms
2021-07-09 18:52:26.772 [info] <0.4402.5>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913583 took: 1774 ms
2021-07-09 18:52:27.417 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 645ms
2021-07-09 18:52:27.575 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 158ms
2021-07-09 18:52:44.379 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16804ms
2021-07-09 18:52:44.923 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 544ms
2021-07-09 18:52:59.438 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14515ms
2021-07-09 18:53:16.792 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17353ms
2021-07-09 18:53:17.906 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1115ms
2021-07-09 18:53:17.906 [info] <0.4402.5>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913584 took: 1115 ms
2021-07-09 18:53:18.460 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 554ms
2021-07-09 18:53:18.742 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 282ms
2021-07-09 18:53:36.424 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 17681ms
2021-07-09 18:53:37.059 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 635ms
2021-07-09 18:53:49.481 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 12423ms
2021-07-09 18:54:06.496 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17015ms
2021-07-09 18:54:08.248 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 1751ms
2021-07-09 18:54:08.248 [info] <0.4402.5>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913585 took: 1752 ms
2021-07-09 18:54:08.566 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 318ms
2021-07-09 18:54:09.065 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 500ms
2021-07-09 18:54:25.697 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 16632ms
2021-07-09 18:54:26.400 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 703ms
2021-07-09 18:54:39.883 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 13482ms
2021-07-09 18:54:57.027 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 17145ms
2021-07-09 18:54:59.795 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 2768ms
2021-07-09 18:54:59.795 [info] <0.4402.5>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913586 took: 2768 ms
2021-07-09 18:55:00.353 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 558ms
2021-07-09 18:55:00.614 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 261ms
2021-07-09 18:55:14.027 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13413ms
2021-07-09 18:55:14.658 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 631ms
2021-07-09 18:55:29.041 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 14383ms
2021-07-09 18:55:45.317 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 16276ms
2021-07-09 18:55:48.631 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 3314ms
2021-07-09 18:55:48.631 [info] <0.4402.5>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913587 took: 3314 ms
2021-07-09 18:55:49.506 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 875ms
2021-07-09 18:55:49.897 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 390ms
2021-07-09 18:56:03.242 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval gossip_sigs to done_sigs was 13346ms
2021-07-09 18:56:03.861 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_sigs to next_round was 619ms
2021-07-09 18:56:19.299 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval next_round to start_acs_new was 15438ms
2021-07-09 18:56:38.333 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval start_acs_new to done_protocol was 19034ms
2021-07-09 18:56:42.408 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval done_protocol to block_success was 4075ms
2021-07-09 18:56:42.408 [info] <0.4402.5>@miner_hbbft_handler:handle_msg_hbbft:351 block creation for round 913588 took: 4075 ms
2021-07-09 18:56:43.987 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval block_success to finalize_round was 1579ms
2021-07-09 18:56:44.251 [info] <0.4402.5>@miner_util:mark:88 miner_hbbft_handler interval finalize_round to gossip_sigs was 263ms