reads_test: open dir="../data/social_media_data_10m" secs=3600 target_rps=100000 wal_only_recovery=false threads=32 configured_threads=32 max_threads=32 auto_safe_laptop_mode=false nodes=4 partitions=8 mix_profile=default alternating_schedule=60s_easy_then_60s_heavy_random_then_60s_heavy_random_queries warmup_mode=none warmup_secs=30 warmup_budget_mb=1024 max_ram_mb=8192 page_cache_mb=4915 index_cache_mb=2048 runtime_reserve_mb=1228 cpu_percent=100
reads_test: startup phase=db_open elapsed=171446ms process_start_to_engine_open_start_ms=4 configured_node_open_parallelism=4 configured_partition_open_parallelism=8 configured_total_open_budget=32 node_open_total_wall_ms=18334 node_open_sum_ms=71051 node_open_max_ms=18332 node_open_p50_ms=17662 node_open_p95_ms=17790 peak_parallel_node_opens=4 partition_open_total_wall_ms=70882 partition_open_sum_ms=516957 partition_open_max_ms=18313 partition_open_p50_ms=16243 partition_open_p95_ms=17570 peak_parallel_partition_opens=8 peak_parallel_partition_opens_per_node=8 peak_parallel_partition_opens_global=32 active_partition_open_workers=0 bytes_read_during_open=6868664503 files_opened_during_open=192 dbms_open_ms=153108
reads_test: startup node node_id=0 partition_id=all open_start_offset_ms=4 metadata_open_ms=15 manifest_load_ms=0 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=1 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=7 wal_scan_ms=0 wal_replay_ms=108675 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=18332 partition_open_total_wall_ms=18317 partition_open_sum_ms=130694 partition_open_max_ms=18313 partition_open_p50_ms=16246 partition_open_p95_ms=16339 configured_partition_open_parallelism=8 active_partition_open_workers=0 peak_parallel_partition_opens=8 peak_parallel_partition_opens_global=32 bytes_read_during_open=1716316446 files_opened_during_open=48
reads_test: startup partition node_id=0 partition_id=0 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=18273 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=1 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=14703 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=18313 bytes_read_during_open=214415208 files_opened_during_open=6
reads_test: startup partition node_id=0 partition_id=1 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14327 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=0 wal_scan_ms=0 wal_replay_ms=13214 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=15527 bytes_read_during_open=215343476 files_opened_during_open=6
reads_test: startup partition node_id=0 partition_id=2 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14191 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13246 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16108 bytes_read_during_open=214284415 files_opened_during_open=6
reads_test: startup partition node_id=0 partition_id=3 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=15930 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13759 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16246 bytes_read_during_open=214303181 files_opened_during_open=6
reads_test: startup partition node_id=0 partition_id=4 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16186 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13325 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16261 bytes_read_during_open=214800680 files_opened_during_open=6
reads_test: startup partition node_id=0 partition_id=5 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16228 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=12890 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16296 bytes_read_during_open=214513160 files_opened_during_open=6
reads_test: startup partition node_id=0 partition_id=6 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16210 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=14198 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16339 bytes_read_during_open=214420057 files_opened_during_open=6
reads_test: startup partition node_id=0 partition_id=7 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14360 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13340 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=15604 bytes_read_during_open=214236269 files_opened_during_open=6
reads_test: startup node node_id=1 partition_id=all open_start_offset_ms=4 metadata_open_ms=45 manifest_load_ms=0 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=3 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=2 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=10 wal_scan_ms=3 wal_replay_ms=109671 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=17790 partition_open_total_wall_ms=17745 partition_open_sum_ms=127720 partition_open_max_ms=17742 partition_open_p50_ms=16111 partition_open_p95_ms=16343 configured_partition_open_parallelism=8 active_partition_open_workers=0 peak_parallel_partition_opens=8 peak_parallel_partition_opens_global=32 bytes_read_during_open=1717531766 files_opened_during_open=48
reads_test: startup partition node_id=1 partition_id=0 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=13857 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13563 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=14390 bytes_read_during_open=214569030 files_opened_during_open=6
reads_test: startup partition node_id=1 partition_id=1 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=13822 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=3 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=2 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=3 wal_replay_ms=12694 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=14391 bytes_read_during_open=214876404 files_opened_during_open=6
reads_test: startup partition node_id=1 partition_id=2 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14402 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=2 wal_scan_ms=0 wal_replay_ms=13037 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16294 bytes_read_during_open=214672055 files_opened_during_open=6
reads_test: startup partition node_id=1 partition_id=3 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16231 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13623 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16343 bytes_read_during_open=214816130 files_opened_during_open=6
reads_test: startup partition node_id=1 partition_id=4 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16271 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=14242 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16342 bytes_read_during_open=214794733 files_opened_during_open=6
reads_test: startup partition node_id=1 partition_id=5 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14318 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=2 wal_scan_ms=0 wal_replay_ms=13332 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16107 bytes_read_during_open=214303231 files_opened_during_open=6
reads_test: startup partition node_id=1 partition_id=6 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=17726 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=15981 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=17742 bytes_read_during_open=214661217 files_opened_during_open=6
reads_test: startup partition node_id=1 partition_id=7 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14181 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13199 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16111 bytes_read_during_open=214838966 files_opened_during_open=6
reads_test: startup node node_id=2 partition_id=all open_start_offset_ms=4 metadata_open_ms=20 manifest_load_ms=0 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=2 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=7 wal_scan_ms=2 wal_replay_ms=109615 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=17267 partition_open_total_wall_ms=17247 partition_open_sum_ms=128991 partition_open_max_ms=17243 partition_open_p50_ms=16226 partition_open_p95_ms=16292 configured_partition_open_parallelism=8 active_partition_open_workers=0 peak_parallel_partition_opens=8 peak_parallel_partition_opens_global=32 bytes_read_during_open=1716719772 files_opened_during_open=48
reads_test: startup partition node_id=2 partition_id=0 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14336 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13104 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=14423 bytes_read_during_open=214899876 files_opened_during_open=6
reads_test: startup partition node_id=2 partition_id=1 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14331 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13550 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16107 bytes_read_during_open=214106743 files_opened_during_open=6
reads_test: startup partition node_id=2 partition_id=2 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16212 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=0 wal_scan_ms=0 wal_replay_ms=13417 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16243 bytes_read_during_open=214470292 files_opened_during_open=6
reads_test: startup partition node_id=2 partition_id=3 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16211 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=1 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13619 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16292 bytes_read_during_open=214601971 files_opened_during_open=6
reads_test: startup partition node_id=2 partition_id=4 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14189 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=2 wal_replay_ms=13077 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16226 bytes_read_during_open=214634875 files_opened_during_open=6
reads_test: startup partition node_id=2 partition_id=5 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=17203 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=15807 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=17243 bytes_read_during_open=214651325 files_opened_during_open=6
reads_test: startup partition node_id=2 partition_id=6 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14094 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13083 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16233 bytes_read_during_open=214405995 files_opened_during_open=6
reads_test: startup partition node_id=2 partition_id=7 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=15565 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=1 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13958 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16224 bytes_read_during_open=214948695 files_opened_during_open=6
reads_test: startup node node_id=3 partition_id=all open_start_offset_ms=4 metadata_open_ms=89 manifest_load_ms=0 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=1 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=9 wal_scan_ms=1 wal_replay_ms=109831 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=17662 partition_open_total_wall_ms=17573 partition_open_sum_ms=129552 partition_open_max_ms=17570 partition_open_p50_ms=16289 partition_open_p95_ms=16340 configured_partition_open_parallelism=8 active_partition_open_workers=0 peak_parallel_partition_opens=8 peak_parallel_partition_opens_global=32 bytes_read_during_open=1718096519 files_opened_during_open=48
reads_test: startup partition node_id=3 partition_id=0 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=17546 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=15867 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=17570 bytes_read_during_open=214292685 files_opened_during_open=6
reads_test: startup partition node_id=3 partition_id=1 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=14403 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=12978 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=15604 bytes_read_during_open=214634124 files_opened_during_open=6
reads_test: startup partition node_id=3 partition_id=2 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=15538 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=1 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=12761 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16109 bytes_read_during_open=214954811 files_opened_during_open=6
reads_test: startup partition node_id=3 partition_id=3 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16215 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13219 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16293 bytes_read_during_open=215096268 files_opened_during_open=6
reads_test: startup partition node_id=3 partition_id=4 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16093 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13000 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16291 bytes_read_during_open=214746383 files_opened_during_open=6
reads_test: startup partition node_id=3 partition_id=5 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=13879 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13298 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=15056 bytes_read_during_open=214405673 files_opened_during_open=6
reads_test: startup partition node_id=3 partition_id=6 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16085 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=2 wal_scan_ms=1 wal_replay_ms=15018 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16289 bytes_read_during_open=214891658 files_opened_during_open=6
reads_test: startup partition node_id=3 partition_id=7 open_start_offset_ms=6 metadata_open_ms=0 manifest_load_ms=16207 manifest_open_ms=0 manifest_decode_ms=0 lock_acquire_ms=0 checkpoint_load_ms=0 free_list_recovery_ms=0 worker_spawn_ms=0 pager_open_ms=0 page_file_open_ms=0 wal_open_ms=1 wal_scan_ms=0 wal_replay_ms=13690 table_registry_ms=0 table_discovery_ms=0 background_recovery_wait_ms=0 total_open_ms=16340 bytes_read_during_open=215074917 files_opened_during_open=6
reads_test: startup phase=metadata_loading elapsed=16ms checkpoint_lsn=0 replayed_lsn=39072
reads_test: startup phase=catalog elapsed=15ms
reads_test: startup phase=index_recovery elapsed=153073ms
reads_test: live_read_ready=true finalized_ready=false state=READ_READY mutation_log_unsynced_bytes=0 checkpoint_durable_for_reopen=false catalog_ready=true primary_ready=true wal_replay_complete=true checkpoint_lsn=0 durable_lsn=0 replayed_lsn=39072 wal_replay_ms=12440 metadata_load_ms=185 pending_index_mutations=0 secondary_pending_mutations=0 text_pending_mutations=0 mutation_apply_rate=60461 estimated_seconds_remaining=0 index_overlay_queryable=true index_segments_queryable=true secondary_ready=true text_ready=true reason=none
reads_test: checkpoint_reopen checkpoint_reopen_candidate_count=0 checkpoint_selected_lsn=0 checkpoint_rejected_reason=checkpoint_lsn_zero wal_bytes_skipped_by_checkpoint=0 index_log_bytes_skipped_by_checkpoint=6421332168
reads_test: readiness_milestones primary_recovered_at_ms=231726 catalog_ready_at_ms=231726 primary_logical_view_ready_at_ms=231726 manifest_valid_at_ms=231726 wal_replay_complete_at_ms=231726 index_overlay_queryable_at_ms=231726 index_segments_queryable_at_ms=231726 secondary_ready_at_ms=231726 text_ready_at_ms=231726 read_ready_at_ms=231726 write_ready_at_ms=0
reads_test: readiness_transition state=OPENING at_ms=0 blocking_reasons=none
reads_test: readiness_transition state=READ_READY at_ms=231726 blocking_reasons=immutable_memtables=391|dirty_page_bytes=4800413696|checkpoint_not_durable latest_lsn=39072 durable_lsn=0 page_durable_lsn=0 manifest_durable_lsn=0 truncate_lsn=0 doc_store_durable_lsn=0|pending_mutations=11969408|secondary_pending_mutations=4721920|text_pending_mutations=7247488|secondary_index_lag_ms=840967|text_index_lag_ms=897325
reads_test: recovery_complete database_open_ms=153104 dbms_open_ms=153108 metadata_loading_ms=185 wal_replay_ms=12440 catalog_recovery_ms=15 lsm_index_open_ms=153073 readiness_wait_ms=170353 recovery_complete_ms=581216
reads_test: lsm_startup directory_scan_ms=0 manifest_read_ms=0 manifest_parse_ms=0 manifest_deserialize_ms=0 manifest_validation_ms=0 table_discovery_ms=0 table_open_wall_ms=108 table_open_sum_ms=217 index_open_parallelism=4 peak_parallel_index_opens=4 lsm_open_worker_count=4 index_open_queue_wait_ms=881 table_open_result_lock_wait_ms=0 segment_file_discovery_ms=0 segment_file_open_ms=8 segment_header_read_ms=8 segment_metadata_read_ms=170 segment_metadata_deserialize_ms=84 segment_validation_ms=0 segment_index_load_ms=0 segment_checksum_ms=0 mutation_log_open_ms=0 mutation_log_scan_ms=136113 mutation_log_decode_ms=1593 mutation_log_filter_ms=12 mutation_log_replay_ms=137718 mutation_dispatch_ms=6577 mutation_dispatch_backpressure_ms=881 mutation_apply_wall_ms=150082 mutation_apply_cpu_ms=5624 mutation_enqueue_ms=1763 checkpoint_manifest_ms=0 pending_queue_reconstruction_ms=1763 pending_queue_snapshot_ms=0 final_index_registration_ms=3 lag_metric_refresh_ms=621 compaction_metric_refresh_ms=2 worker_spawn_ms=8 aggregate_index_open_ms=217 index_open_wall_ms=108 total_measured_substage_ms=140493 total_index_open_wall_ms=153071 unaccounted_open_ms=12578 index_tables=4 secondary_tables=4 text_tables=0 segment_count=6 secondary_segment_count=6 text_segment_count=0 pending_queue_items=20000000 segment_metadata_bytes_read=15672457 segment_data_bytes_read=0 mutation_log_bytes=6421332168 mutation_log_first_lsn=1 mutation_log_last_lsn=20000000 mutation_records=20000000 mutation_records_decoded=20000000 mutation_records_scanned=20000000 mutation_records_below_floor=0 mutation_records_replayed=20000000 replayed_mutations=20000000 skipped_durable_mutations=0 files_opened=12 filesystem_metadata_calls=12 manifest_replay_floor_lsn=0 mutation_replay_floor_lsn=0 mutation_reclaimable_through_lsn=0 mutation_reclaimed_lsn=0 mutation_reclaimed_through_lsn=0 replay_floor_rejected_reason=legacy_manifest_missing_replay_floor
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].segment_count value=1
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].segment_file_discovery_ms value=0
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].segment_file_open_ms value=1
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].segment_header_read_ms value=1
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].segment_metadata_read_ms value=45
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].segment_metadata_deserialize_ms value=16
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].segment_validation_ms value=0
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].segment_metadata_bytes_read value=2097466
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].segment_data_bytes_read value=0
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].files_opened value=2
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].filesystem_metadata_calls value=2
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].total_open_ms value=49
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].segment_count value=3
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].segment_file_discovery_ms value=0
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].segment_file_open_ms value=0
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].segment_header_read_ms value=0
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].segment_metadata_read_ms value=68
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].segment_metadata_deserialize_ms value=29
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].segment_validation_ms value=0
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].segment_metadata_bytes_read value=8389548
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].segment_data_bytes_read value=0
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].files_opened value=6
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].filesystem_metadata_calls value=6
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].total_open_ms value=94
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].segment_count value=2
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].segment_file_discovery_ms value=0
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].segment_file_open_ms value=7
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].segment_header_read_ms value=7
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].segment_metadata_read_ms value=57
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].segment_metadata_deserialize_ms value=39
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].segment_validation_ms value=0
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].segment_metadata_bytes_read value=5185443
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].segment_data_bytes_read value=0
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].files_opened value=4
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].filesystem_metadata_calls value=4
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=secondary].total_open_ms value=73
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].segment_count value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].segment_file_discovery_ms value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].segment_file_open_ms value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].segment_header_read_ms value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].segment_metadata_read_ms value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].segment_metadata_deserialize_ms value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].segment_validation_ms value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].segment_metadata_bytes_read value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].segment_data_bytes_read value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].files_opened value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].filesystem_metadata_calls value=0
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=secondary].total_open_ms value=1
reads_test: lsm_startup_index metric=index_open[table=9439953132510313430,kind=secondary].pending_queue_items value=174592
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].pending_queue_items value=1306880
reads_test: lsm_startup_index metric=index_open[table=10259209326019780659,kind=secondary].pending_queue_items value=369408
reads_test: lsm_startup_index metric=index_open[table=10985365512571037218,kind=text].pending_queue_items value=369664
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].pending_queue_items value=1633024
reads_test: lsm_startup_index metric=index_open[table=11541567270475786649,kind=text].pending_queue_items value=455936
reads_test: lsm_startup_index metric=index_open[table=11550115665377471388,kind=secondary].pending_queue_items value=182528
reads_test: lsm_startup_index metric=index_open[table=11624666970863252393,kind=text].pending_queue_items value=269824
reads_test: lsm_startup_index metric=index_open[table=12012921583409832537,kind=secondary].pending_queue_items value=890368
reads_test: lsm_startup_index metric=index_open[table=12148048740072246362,kind=secondary].pending_queue_items value=274688
reads_test: lsm_startup_index metric=index_open[table=12186598357780717984,kind=secondary].pending_queue_items value=1088256
reads_test: lsm_startup_index metric=index_open[table=12264642885236927177,kind=secondary].pending_queue_items value=909056
reads_test: lsm_startup_index metric=index_open[table=12394143223024549877,kind=text].pending_queue_items value=698880
reads_test: lsm_startup_index metric=index_open[table=13385553692679020687,kind=text].pending_queue_items value=274688
reads_test: lsm_startup_index metric=index_open[table=13396455952142151395,kind=text].pending_queue_items value=909056
reads_test: lsm_startup_index metric=index_open[table=13592647167583057657,kind=text].pending_queue_items value=180352
reads_test: lsm_startup_index metric=index_open[table=14536805287048444545,kind=text].pending_queue_items value=369408
reads_test: lsm_startup_index metric=index_open[table=14723968795259990006,kind=secondary].pending_queue_items value=180352
reads_test: lsm_startup_index metric=index_open[table=14781325458063692334,kind=secondary].pending_queue_items value=698880
reads_test: lsm_startup_index metric=index_open[table=14847371417175498627,kind=text].pending_queue_items value=460288
reads_test: lsm_startup_index metric=index_open[table=15099530047237732152,kind=text].pending_queue_items value=1088256
reads_test: lsm_startup_index metric=index_open[table=15136280116978783466,kind=secondary].pending_queue_items value=269824
reads_test: lsm_startup_index metric=index_open[table=15171684167501876840,kind=secondary].pending_queue_items value=369664
reads_test: lsm_startup_index metric=index_open[table=15732122262421685094,kind=text].pending_queue_items value=736256
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=text].pending_queue_items value=1633024
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=text].pending_queue_items value=1306880
reads_test: lsm_startup_index metric=index_open[table=16981693102598120030,kind=text].pending_queue_items value=890368
reads_test: lsm_startup_index metric=index_open[table=17058852451635836613,kind=secondary].pending_queue_items value=460288
reads_test: lsm_startup_index metric=index_open[table=17071434096260697490,kind=secondary].pending_queue_items value=455936
reads_test: lsm_startup_index metric=index_open[table=17433252396585107584,kind=text].pending_queue_items value=174592
reads_test: lsm_startup_index metric=index_open[table=17849424355206749180,kind=text].pending_queue_items value=182528
reads_test: lsm_startup_index metric=index_open[table=18304033360603962195,kind=secondary].pending_queue_items value=736256
reads_test: lsm_startup_index metric=index_open[table=9439953132510313430,kind=secondary].records_replayed value=174592
reads_test: lsm_startup_index metric=index_open[table=10119546700238933226,kind=secondary].records_replayed value=1306880
reads_test: lsm_startup_index metric=index_open[table=10259209326019780659,kind=secondary].records_replayed value=369408
reads_test: lsm_startup_index metric=index_open[table=10985365512571037218,kind=text].records_replayed value=369664
reads_test: lsm_startup_index metric=index_open[table=11012616926180838204,kind=secondary].records_replayed value=1633024
reads_test: lsm_startup_index metric=index_open[table=11541567270475786649,kind=text].records_replayed value=455936
reads_test: lsm_startup_index metric=index_open[table=11550115665377471388,kind=secondary].records_replayed value=182528
reads_test: lsm_startup_index metric=index_open[table=11624666970863252393,kind=text].records_replayed value=269824
reads_test: lsm_startup_index metric=index_open[table=12012921583409832537,kind=secondary].records_replayed value=890368
reads_test: lsm_startup_index metric=index_open[table=12148048740072246362,kind=secondary].records_replayed value=274688
reads_test: lsm_startup_index metric=index_open[table=12186598357780717984,kind=secondary].records_replayed value=1088256
reads_test: lsm_startup_index metric=index_open[table=12264642885236927177,kind=secondary].records_replayed value=909056
reads_test: lsm_startup_index metric=index_open[table=12394143223024549877,kind=text].records_replayed value=698880
reads_test: lsm_startup_index metric=index_open[table=13385553692679020687,kind=text].records_replayed value=274688
reads_test: lsm_startup_index metric=index_open[table=13396455952142151395,kind=text].records_replayed value=909056
reads_test: lsm_startup_index metric=index_open[table=13592647167583057657,kind=text].records_replayed value=180352
reads_test: lsm_startup_index metric=index_open[table=14536805287048444545,kind=text].records_replayed value=369408
reads_test: lsm_startup_index metric=index_open[table=14723968795259990006,kind=secondary].records_replayed value=180352
reads_test: lsm_startup_index metric=index_open[table=14781325458063692334,kind=secondary].records_replayed value=698880
reads_test: lsm_startup_index metric=index_open[table=14847371417175498627,kind=text].records_replayed value=460288
reads_test: lsm_startup_index metric=index_open[table=15099530047237732152,kind=text].records_replayed value=1088256
reads_test: lsm_startup_index metric=index_open[table=15136280116978783466,kind=secondary].records_replayed value=269824
reads_test: lsm_startup_index metric=index_open[table=15171684167501876840,kind=secondary].records_replayed value=369664
reads_test: lsm_startup_index metric=index_open[table=15732122262421685094,kind=text].records_replayed value=736256
reads_test: lsm_startup_index metric=index_open[table=16226151855454274167,kind=text].records_replayed value=1633024
reads_test: lsm_startup_index metric=index_open[table=16686173826401613498,kind=text].records_replayed value=1306880
reads_test: lsm_startup_index metric=index_open[table=16981693102598120030,kind=text].records_replayed value=890368
reads_test: lsm_startup_index metric=index_open[table=17058852451635836613,kind=secondary].records_replayed value=460288
reads_test: lsm_startup_index metric=index_open[table=17071434096260697490,kind=secondary].records_replayed value=455936
reads_test: lsm_startup_index metric=index_open[table=17433252396585107584,kind=text].records_replayed value=174592
reads_test: lsm_startup_index metric=index_open[table=17849424355206749180,kind=text].records_replayed value=182528
reads_test: lsm_startup_index metric=index_open[table=18304033360603962195,kind=secondary].records_replayed value=736256
reads_test: post_recovery phase=first_successful_query elapsed=10ms time_since_open=581268ms total_to_first_query_ms=581273
reads_test: post_recovery phase=startup_smoke_validation elapsed=27874ms
reads_test: post_recovery phase=full_validation elapsed=67892ms
reads_test: recovery summary process_start_to_engine_open_start_ms=4 node_open_total_wall_ms=18334 node_open_sum_ms=71051 node_open_max_ms=18332 partition_open_total_wall_ms=70882 partition_open_sum_ms=516957 partition_open_max_ms=18313 database_open_ms=153104 dbms_open_ms=153108 lsm_index_open_ms=153073 catalog_restore_ms=15 readiness_wait_ms=170353 metadata_loading_ms=185 wal_replay_ms=12440 recovery_complete_ms=581216 time_to_first_successful_query_ms=581268 first_successful_query_ms=10 startup_smoke_validation_ms=27874 full_validation_ms=67892
reads_test: startup_smoke known_docs_checked=3 random_docs_checked=4 queries_executed=13 index_candidates=6 documents_hydrated=6 primary_lookup_ok=true secondary_eq_ok=true secondary_range_ok=true text_query_ok=true
reads_test: validation recovered_document_count=10000000 expected_document_count=10000000 known_docs_checked=3 random_docs_checked=4 primary_lookup_ok=true secondary_eq_ok=true secondary_range_ok=true text_query_ok=true not_found=0 corrupted=0 duplicates=0
reads_test: validation_metrics full_scans=16 documents_decoded=10000000 bytes_read=6433079757 partitions_scanned=512 queries_executed=13 index_candidates=6 documents_hydrated=6 duplicate_tracking_memory=144 document_count_ms=58223 known_document_lookup_ms=0 random_document_lookup_ms=0 primary_correctness_ms=1 secondary_eq_ms=5 secondary_range_ms=0 text_query_ms=0 duplicate_detection_ms=58223 corruption_detection_ms=58223
reads_test: checkpoint_state writer_checkpoint_lsn=0 reopened_checkpoint_lsn=0 match=true
reads_test: post_recovery phase=samples elapsed=131ms
reads_test: startup_timeline process_start_to_engine_open_start_ms=4 node_open_total_wall_ms=18334 node_open_sum_ms=71051 node_open_max_ms=18332 partition_open_total_wall_ms=70882 partition_open_sum_ms=516957 partition_open_max_ms=18313 dbms_open_ms=153108 lsm_index_open_ms=153073 catalog_restore_ms=15 readiness_wait_ms=170353 first_query_ms=10 startup_smoke_validation_ms=27874 full_validation_ms=67892 samples_load_ms=131 warmup_ms=0 total_to_first_query_ms=341809 time_to_benchmark_ready_ms=677165 total_to_benchmark_start_ms=677199 read_ready_at_ms=231726 write_ready_at_ms=0
reads_test: benchmark_warmup elapsed=0ms mode=none completed=true primary_meta_bytes=0 index_meta_bytes=0 primary_ops=0 index_ops=0 est_bytes=0 stop_reason=warmup_disabled
reads_test: steady_state begin elapsed_since_open=677241ms recovery_complete_ms=581216 warmup_ms=0 warmup_mode=none
reads_test: find_many_counters queries=5486 candidates=137150 hydrated=137150 predicate_matches=137150 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=5486 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=11497 candidates=287425 hydrated=287425 predicate_matches=287425 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=11497 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=17549 candidates=438725 hydrated=438725 predicate_matches=438725 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=17549 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=24062 candidates=601550 hydrated=601550 predicate_matches=601550 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=24062 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=30030 candidates=750750 hydrated=750750 predicate_matches=750750 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=30030 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=36250 candidates=906250 hydrated=906250 predicate_matches=906250 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=36250 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=42897 candidates=1072425 hydrated=1072425 predicate_matches=1072425 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=42897 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=49439 candidates=1235975 hydrated=1235975 predicate_matches=1235975 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=49439 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=55704 candidates=1392600 hydrated=1392600 predicate_matches=1392600 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=55704 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=61510 candidates=1537750 hydrated=1537750 predicate_matches=1537750 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=61510 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=68155 candidates=1703875 hydrated=1703875 predicate_matches=1703875 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=68155 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=75333 candidates=1883325 hydrated=1883325 predicate_matches=1883325 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=75333 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=83985 candidates=2099625 hydrated=2099625 predicate_matches=2099625 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=83985 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=93210 candidates=2330250 hydrated=2330250 predicate_matches=2330250 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=93210 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=102750 candidates=2568750 hydrated=2568750 predicate_matches=2568750 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=102750 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=112492 candidates=2812300 hydrated=2812300 predicate_matches=2812300 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=112492 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=121964 candidates=3049100 hydrated=3049100 predicate_matches=3049100 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=121964 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=131794 candidates=3294850 hydrated=3294850 predicate_matches=3294850 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=131794 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=141777 candidates=3544425 hydrated=3544425 predicate_matches=3544425 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=141777 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=151628 candidates=3790700 hydrated=3790700 predicate_matches=3790700 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=151628 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=161619 candidates=4040475 hydrated=4040475 predicate_matches=4040475 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=161619 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=171375 candidates=4284375 hydrated=4284375 predicate_matches=4284375 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=171375 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=181333 candidates=4533325 hydrated=4533325 predicate_matches=4533325 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=181333 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=0 empty_query_result=0
reads_test: find_many_counters queries=187227 candidates=4680675 hydrated=4680675 predicate_matches=4680675 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187227 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=68 empty_query_result=68
reads_test: find_many_counters queries=187240 candidates=4681000 hydrated=4681000 predicate_matches=4681000 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187240 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=102 empty_query_result=102
reads_test: find_many_counters queries=187253 candidates=4681325 hydrated=4681325 predicate_matches=4681325 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187253 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=177 empty_query_result=177
reads_test: find_many_counters queries=187280 candidates=4682000 hydrated=4682000 predicate_matches=4682000 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187280 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=312 empty_query_result=312
reads_test: find_many_counters queries=187304 candidates=4682600 hydrated=4682600 predicate_matches=4682600 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187304 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=435 empty_query_result=435
reads_test: find_many_counters queries=187329 candidates=4683225 hydrated=4683225 predicate_matches=4683225 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187329 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=554 empty_query_result=554
reads_test: find_many_counters queries=187349 candidates=4683725 hydrated=4683725 predicate_matches=4683725 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187349 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=630 empty_query_result=630
reads_test: find_many_counters queries=187359 candidates=4683975 hydrated=4683975 predicate_matches=4683975 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187359 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=657 empty_query_result=657
reads_test: find_many_counters queries=187363 candidates=4684075 hydrated=4684075 predicate_matches=4684075 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187363 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=687 empty_query_result=687
reads_test: find_many_counters queries=187378 candidates=4684450 hydrated=4684450 predicate_matches=4684450 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187378 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=773 empty_query_result=773
reads_test: find_many_counters queries=187400 candidates=4685000 hydrated=4685000 predicate_matches=4685000 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187400 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=863 empty_query_result=863
reads_test: find_many_counters queries=187417 candidates=4685425 hydrated=4685425 predicate_matches=4685425 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=187417 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=935 empty_query_result=935
reads_test: find_many_counters queries=188455 candidates=4711375 hydrated=4711375 predicate_matches=4711375 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=188455 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=190876 candidates=4771900 hydrated=4771900 predicate_matches=4771900 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=190876 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=193502 candidates=4837550 hydrated=4837550 predicate_matches=4837550 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=193502 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=196173 candidates=4904325 hydrated=4904325 predicate_matches=4904325 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=196173 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=199099 candidates=4977475 hydrated=4977475 predicate_matches=4977475 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=199099 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=202169 candidates=5054225 hydrated=5054225 predicate_matches=5054225 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=202169 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=205343 candidates=5133575 hydrated=5133575 predicate_matches=5133575 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=205343 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=208802 candidates=5220050 hydrated=5220050 predicate_matches=5220050 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=208802 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=212336 candidates=5308400 hydrated=5308400 predicate_matches=5308400 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=212336 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=216166 candidates=5404150 hydrated=5404150 predicate_matches=5404150 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=216166 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=220097 candidates=5502425 hydrated=5502425 predicate_matches=5502425 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=220097 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=224280 candidates=5607000 hydrated=5607000 predicate_matches=5607000 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=224280 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=231102 candidates=5777550 hydrated=5777550 predicate_matches=5777550 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=231102 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=239510 candidates=5987750 hydrated=5987750 predicate_matches=5987750 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=239510 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=248134 candidates=6203350 hydrated=6203350 predicate_matches=6203350 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=248134 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=256941 candidates=6423525 hydrated=6423525 predicate_matches=6423525 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=256941 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=265632 candidates=6640800 hydrated=6640800 predicate_matches=6640800 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=265632 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=274488 candidates=6862200 hydrated=6862200 predicate_matches=6862200 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=274488 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=282732 candidates=7068300 hydrated=7068300 predicate_matches=7068300 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=282732 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=291448 candidates=7286200 hydrated=7286200 predicate_matches=7286200 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=291448 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=300305 candidates=7507625 hydrated=7507625 predicate_matches=7507625 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=300305 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=309487 candidates=7737175 hydrated=7737175 predicate_matches=7737175 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=309487 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=318849 candidates=7971225 hydrated=7971225 predicate_matches=7971225 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=318849 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=328448 candidates=8211200 hydrated=8211200 predicate_matches=8211200 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=328448 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1009 empty_query_result=1009
reads_test: find_many_counters queries=329772 candidates=8244300 hydrated=8244300 predicate_matches=8244300 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329772 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1114 empty_query_result=1114
reads_test: find_many_counters queries=329792 candidates=8244800 hydrated=8244800 predicate_matches=8244800 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329792 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1223 empty_query_result=1223
reads_test: find_many_counters queries=329812 candidates=8245300 hydrated=8245300 predicate_matches=8245300 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329812 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1312 empty_query_result=1312
reads_test: find_many_counters queries=329825 candidates=8245625 hydrated=8245625 predicate_matches=8245625 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329825 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1367 empty_query_result=1367
reads_test: find_many_counters queries=329831 candidates=8245775 hydrated=8245775 predicate_matches=8245775 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329831 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1390 empty_query_result=1390
reads_test: find_many_counters queries=329834 candidates=8245850 hydrated=8245850 predicate_matches=8245850 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329834 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1431 empty_query_result=1431
reads_test: find_many_counters queries=329845 candidates=8246125 hydrated=8246125 predicate_matches=8246125 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329845 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1473 empty_query_result=1473
reads_test: find_many_counters queries=329856 candidates=8246400 hydrated=8246400 predicate_matches=8246400 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329856 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1520 empty_query_result=1520
reads_test: find_many_counters queries=329871 candidates=8246775 hydrated=8246775 predicate_matches=8246775 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329871 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1607 empty_query_result=1607
reads_test: find_many_counters queries=329899 candidates=8247475 hydrated=8247475 predicate_matches=8247475 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329899 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1719 empty_query_result=1719
reads_test: find_many_counters queries=329931 candidates=8248275 hydrated=8248275 predicate_matches=8248275 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329931 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1842 empty_query_result=1842
reads_test: find_many_counters queries=329968 candidates=8249200 hydrated=8249200 predicate_matches=8249200 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=329968 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1969 empty_query_result=1969
reads_test: find_many_counters queries=331969 candidates=8299225 hydrated=8299225 predicate_matches=8299225 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=331969 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1969 empty_query_result=1969
reads_test: find_many_counters queries=334377 candidates=8359425 hydrated=8359425 predicate_matches=8359425 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=334377 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1969 empty_query_result=1969
reads_test: find_many_counters queries=334941 candidates=8373525 hydrated=8373525 predicate_matches=8373525 empty_results=0 expected_empty=0 unexpected_empty=0 query_value_exists=334941 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1969 empty_query_result=1969
reads_test: done elapsed=375s elapsed_hms=0:06:15 reads=1903998 avg_reads/s=5077 errors=0 not_found=0 primary_document_not_found=0 seed_document_missing=0 query_empty_expected=0 query_empty_unexpected=1969 empty_query_result=1969 find_many_unexpected_empty=0 log=../data/social_media_data_1m\reads_log.txt other_log=../data/social_media_data_1m\reads_other_log.txt