2019/03/12-15:23:21.918899 7f0c974f4700 RocksDB version: 5.7.3
2019/03/12-15:23:21.918955 7f0c974f4700 Git sha rocksdb_build_git_sha:
2019/03/12-15:23:21.918960 7f0c974f4700 Compile date Sep  1 2017
2019/03/12-15:23:21.918963 7f0c974f4700 DB SUMMARY
2019/03/12-15:23:21.919019 7f0c974f4700 CURRENT file:  CURRENT
2019/03/12-15:23:21.919023 7f0c974f4700 IDENTITY file:  IDENTITY
2019/03/12-15:23:21.919030 7f0c974f4700 MANIFEST file:  MANIFEST-000007 size: 87 Bytes
2019/03/12-15:23:21.919035 7f0c974f4700 SST files in /home/svenkata/samza-release/samza-release/samza-test/state/mystore/Partition_0 dir, Total Num: 1, files: 000006.sst 
2019/03/12-15:23:21.919040 7f0c974f4700 Write Ahead Log file in /home/svenkata/samza-release/samza-release/samza-test/state/mystore/Partition_0: 000003.log size: 0 ; 
2019/03/12-15:23:21.919045 7f0c974f4700                         Options.error_if_exists: 0
2019/03/12-15:23:21.919047 7f0c974f4700                       Options.create_if_missing: 1
2019/03/12-15:23:21.919049 7f0c974f4700                         Options.paranoid_checks: 1
2019/03/12-15:23:21.919052 7f0c974f4700                                     Options.env: 0x7f0debff8880
2019/03/12-15:23:21.919055 7f0c974f4700                                Options.info_log: 0x7f0c98034d50
2019/03/12-15:23:21.919057 7f0c974f4700                Options.max_file_opening_threads: 16
2019/03/12-15:23:21.919060 7f0c974f4700                               Options.use_fsync: 0
2019/03/12-15:23:21.919062 7f0c974f4700                       Options.max_log_file_size: 67108864
2019/03/12-15:23:21.919065 7f0c974f4700                  Options.max_manifest_file_size: 18446744073709551615
2019/03/12-15:23:21.919067 7f0c974f4700                   Options.log_file_time_to_roll: 0
2019/03/12-15:23:21.919070 7f0c974f4700                       Options.keep_log_file_num: 2
2019/03/12-15:23:21.919072 7f0c974f4700                    Options.recycle_log_file_num: 0
2019/03/12-15:23:21.919074 7f0c974f4700                         Options.allow_fallocate: 1
2019/03/12-15:23:21.919076 7f0c974f4700                        Options.allow_mmap_reads: 0
2019/03/12-15:23:21.919078 7f0c974f4700                       Options.allow_mmap_writes: 0
2019/03/12-15:23:21.919081 7f0c974f4700                        Options.use_direct_reads: 0
2019/03/12-15:23:21.919083 7f0c974f4700                        Options.use_direct_io_for_flush_and_compaction: 0
2019/03/12-15:23:21.919086 7f0c974f4700          Options.create_missing_column_families: 0
2019/03/12-15:23:21.919088 7f0c974f4700                              Options.db_log_dir: 
2019/03/12-15:23:21.919091 7f0c974f4700                                 Options.wal_dir: /home/svenkata/samza-release/samza-release/samza-test/state/mystore/Partition_0
2019/03/12-15:23:21.919093 7f0c974f4700                Options.table_cache_numshardbits: 6
2019/03/12-15:23:21.919096 7f0c974f4700                      Options.max_subcompactions: 1
2019/03/12-15:23:21.919098 7f0c974f4700                  Options.max_background_flushes: -1
2019/03/12-15:23:21.919100 7f0c974f4700                         Options.WAL_ttl_seconds: 0
2019/03/12-15:23:21.919103 7f0c974f4700                       Options.WAL_size_limit_MB: 0
2019/03/12-15:23:21.919105 7f0c974f4700             Options.manifest_preallocation_size: 4194304
2019/03/12-15:23:21.919107 7f0c974f4700                     Options.is_fd_close_on_exec: 1
2019/03/12-15:23:21.919110 7f0c974f4700                   Options.advise_random_on_open: 1
2019/03/12-15:23:21.919112 7f0c974f4700                    Options.db_write_buffer_size: 0
2019/03/12-15:23:21.919114 7f0c974f4700         Options.access_hint_on_compaction_start: 1
2019/03/12-15:23:21.919116 7f0c974f4700  Options.new_table_reader_for_compaction_inputs: 0
2019/03/12-15:23:21.919119 7f0c974f4700               Options.compaction_readahead_size: 0
2019/03/12-15:23:21.919121 7f0c974f4700           Options.random_access_max_buffer_size: 1048576
2019/03/12-15:23:21.919123 7f0c974f4700           Options.writable_file_max_buffer_size: 1048576
2019/03/12-15:23:21.919126 7f0c974f4700                      Options.use_adaptive_mutex: 0
2019/03/12-15:23:21.919128 7f0c974f4700                            Options.rate_limiter: (nil)
2019/03/12-15:23:21.919146 7f0c974f4700     Options.sst_file_manager.rate_bytes_per_sec: 0
2019/03/12-15:23:21.919149 7f0c974f4700                          Options.bytes_per_sync: 0
2019/03/12-15:23:21.919151 7f0c974f4700                      Options.wal_bytes_per_sync: 0
2019/03/12-15:23:21.919153 7f0c974f4700                       Options.wal_recovery_mode: 2
2019/03/12-15:23:21.919155 7f0c974f4700                  Options.enable_thread_tracking: 0
2019/03/12-15:23:21.919157 7f0c974f4700                  Options.enable_pipelined_write: 0
2019/03/12-15:23:21.919160 7f0c974f4700         Options.allow_concurrent_memtable_write: 1
2019/03/12-15:23:21.919162 7f0c974f4700      Options.enable_write_thread_adaptive_yield: 1
2019/03/12-15:23:21.919164 7f0c974f4700             Options.write_thread_max_yield_usec: 100
2019/03/12-15:23:21.919167 7f0c974f4700            Options.write_thread_slow_yield_usec: 3
2019/03/12-15:23:21.919169 7f0c974f4700                               Options.row_cache: None
2019/03/12-15:23:21.919172 7f0c974f4700                              Options.wal_filter: None
2019/03/12-15:23:21.919174 7f0c974f4700             Options.avoid_flush_during_recovery: 0
2019/03/12-15:23:21.919176 7f0c974f4700             Options.allow_ingest_behind: 0
2019/03/12-15:23:21.919179 7f0c974f4700             Options.concurrent_prepare: 0
2019/03/12-15:23:21.919181 7f0c974f4700             Options.manual_wal_flush: 0
2019/03/12-15:23:21.919184 7f0c974f4700             Options.max_background_jobs: 2
2019/03/12-15:23:21.919186 7f0c974f4700             Options.max_background_compactions: -1
2019/03/12-15:23:21.919189 7f0c974f4700             Options.avoid_flush_during_shutdown: 0
2019/03/12-15:23:21.919191 7f0c974f4700             Options.delayed_write_rate : 16777216
2019/03/12-15:23:21.919193 7f0c974f4700             Options.max_total_wal_size: 0
2019/03/12-15:23:21.919196 7f0c974f4700             Options.delete_obsolete_files_period_micros: 21600000000
2019/03/12-15:23:21.919199 7f0c974f4700                   Options.stats_dump_period_sec: 600
2019/03/12-15:23:21.919201 7f0c974f4700                          Options.max_open_files: -1
2019/03/12-15:23:21.919204 7f0c974f4700 Compression algorithms supported:
2019/03/12-15:23:21.919206 7f0c974f4700 	Snappy supported: 1
2019/03/12-15:23:21.919209 7f0c974f4700 	Zlib supported: 1
2019/03/12-15:23:21.919211 7f0c974f4700 	Bzip supported: 1
2019/03/12-15:23:21.919213 7f0c974f4700 	LZ4 supported: 1
2019/03/12-15:23:21.919216 7f0c974f4700 	ZSTD supported: 1
2019/03/12-15:23:21.919218 7f0c974f4700 Fast CRC32 supported: 0
2019/03/12-15:23:21.919428 7f0c974f4700 [db/version_set.cc:2712] Recovering from manifest file: MANIFEST-000007
2019/03/12-15:23:21.919545 7f0c974f4700 [db/column_family.cc:413] --------------- Options for column family [default]:
2019/03/12-15:23:21.919550 7f0c974f4700               Options.comparator: leveldb.BytewiseComparator
2019/03/12-15:23:21.919553 7f0c974f4700           Options.merge_operator: None
2019/03/12-15:23:21.919555 7f0c974f4700        Options.compaction_filter: None
2019/03/12-15:23:21.919557 7f0c974f4700        Options.compaction_filter_factory: None
2019/03/12-15:23:21.919560 7f0c974f4700         Options.memtable_factory: SkipListFactory
2019/03/12-15:23:21.919563 7f0c974f4700            Options.table_factory: BlockBasedTable
2019/03/12-15:23:21.919595 7f0c974f4700            table_factory options:   flush_block_policy_factory: FlushBlockBySizePolicyFactory (0x7f0c98031f70)
  cache_index_and_filter_blocks: 0
  cache_index_and_filter_blocks_with_high_priority: 0
  pin_l0_filter_and_index_blocks_in_cache: 0
  index_type: 0
  hash_index_allow_collision: 1
  checksum: 1
  no_block_cache: 0
  block_cache: 0x7f0c980241f8
  block_cache_name: LRUCache
  block_cache_options:
    capacity : 104857600
    num_shard_bits : 6
    strict_capacity_limit : 0
    high_pri_pool_ratio: 0.000
  block_cache_compressed: (nil)
  persistent_cache: (nil)
  block_size: 4096
  block_size_deviation: 10
  block_restart_interval: 16
  index_block_restart_interval: 1
  filter_policy: nullptr
  whole_key_filtering: 1
  format_version: 0
2019/03/12-15:23:21.919619 7f0c974f4700        Options.write_buffer_size: 33554432
2019/03/12-15:23:21.919622 7f0c974f4700  Options.max_write_buffer_number: 3
2019/03/12-15:23:21.919625 7f0c974f4700          Options.compression: Snappy
2019/03/12-15:23:21.919627 7f0c974f4700                  Options.bottommost_compression: Disabled
2019/03/12-15:23:21.919630 7f0c974f4700       Options.prefix_extractor: nullptr
2019/03/12-15:23:21.919632 7f0c974f4700   Options.memtable_insert_with_hint_prefix_extractor: nullptr
2019/03/12-15:23:21.919635 7f0c974f4700             Options.num_levels: 7
2019/03/12-15:23:21.919637 7f0c974f4700        Options.min_write_buffer_number_to_merge: 1
2019/03/12-15:23:21.919640 7f0c974f4700     Options.max_write_buffer_number_to_maintain: 0
2019/03/12-15:23:21.919642 7f0c974f4700            Options.compression_opts.window_bits: -14
2019/03/12-15:23:21.919645 7f0c974f4700                  Options.compression_opts.level: -1
2019/03/12-15:23:21.919647 7f0c974f4700               Options.compression_opts.strategy: 0
2019/03/12-15:23:21.919649 7f0c974f4700         Options.compression_opts.max_dict_bytes: 0
2019/03/12-15:23:21.919652 7f0c974f4700      Options.level0_file_num_compaction_trigger: 4
2019/03/12-15:23:21.919654 7f0c974f4700          Options.level0_slowdown_writes_trigger: 20
2019/03/12-15:23:21.919656 7f0c974f4700              Options.level0_stop_writes_trigger: 36
2019/03/12-15:23:21.919659 7f0c974f4700                   Options.target_file_size_base: 67108864
2019/03/12-15:23:21.919661 7f0c974f4700             Options.target_file_size_multiplier: 1
2019/03/12-15:23:21.919663 7f0c974f4700                Options.max_bytes_for_level_base: 268435456
2019/03/12-15:23:21.919666 7f0c974f4700 Options.level_compaction_dynamic_level_bytes: 0
2019/03/12-15:23:21.919671 7f0c974f4700          Options.max_bytes_for_level_multiplier: 10.000000
2019/03/12-15:23:21.919674 7f0c974f4700 Options.max_bytes_for_level_multiplier_addtl[0]: 1
2019/03/12-15:23:21.919677 7f0c974f4700 Options.max_bytes_for_level_multiplier_addtl[1]: 1
2019/03/12-15:23:21.919679 7f0c974f4700 Options.max_bytes_for_level_multiplier_addtl[2]: 1
2019/03/12-15:23:21.919682 7f0c974f4700 Options.max_bytes_for_level_multiplier_addtl[3]: 1
2019/03/12-15:23:21.919684 7f0c974f4700 Options.max_bytes_for_level_multiplier_addtl[4]: 1
2019/03/12-15:23:21.919686 7f0c974f4700 Options.max_bytes_for_level_multiplier_addtl[5]: 1
2019/03/12-15:23:21.919689 7f0c974f4700 Options.max_bytes_for_level_multiplier_addtl[6]: 1
2019/03/12-15:23:21.919691 7f0c974f4700       Options.max_sequential_skip_in_iterations: 8
2019/03/12-15:23:21.919694 7f0c974f4700                    Options.max_compaction_bytes: 1677721600
2019/03/12-15:23:21.919696 7f0c974f4700                        Options.arena_block_size: 4194304
2019/03/12-15:23:21.919699 7f0c974f4700   Options.soft_pending_compaction_bytes_limit: 68719476736
2019/03/12-15:23:21.919701 7f0c974f4700   Options.hard_pending_compaction_bytes_limit: 274877906944
2019/03/12-15:23:21.919704 7f0c974f4700       Options.rate_limit_delay_max_milliseconds: 100
2019/03/12-15:23:21.919706 7f0c974f4700                Options.disable_auto_compactions: 0
2019/03/12-15:23:21.919709 7f0c974f4700                        Options.compaction_style: kCompactionStyleUniversal
2019/03/12-15:23:21.919713 7f0c974f4700                          Options.compaction_pri: kByCompensatedSize
2019/03/12-15:23:21.919715 7f0c974f4700 Options.compaction_options_universal.size_ratio: 1
2019/03/12-15:23:21.919717 7f0c974f4700 Options.compaction_options_universal.min_merge_width: 2
2019/03/12-15:23:21.919720 7f0c974f4700 Options.compaction_options_universal.max_merge_width: 4294967295
2019/03/12-15:23:21.919722 7f0c974f4700 Options.compaction_options_universal.max_size_amplification_percent: 200
2019/03/12-15:23:21.919725 7f0c974f4700 Options.compaction_options_universal.compression_size_percent: -1
2019/03/12-15:23:21.919728 7f0c974f4700 Options.compaction_options_universal.stop_style: kCompactionStopStyleTotalSize
2019/03/12-15:23:21.919752 7f0c974f4700 Options.compaction_options_fifo.max_table_files_size: 1073741824
2019/03/12-15:23:21.919755 7f0c974f4700 Options.compaction_options_fifo.allow_compaction: 0
2019/03/12-15:23:21.919757 7f0c974f4700 Options.compaction_options_fifo.ttl: 0
2019/03/12-15:23:21.919760 7f0c974f4700                   Options.table_properties_collectors: 
2019/03/12-15:23:21.919763 7f0c974f4700                   Options.inplace_update_support: 0
2019/03/12-15:23:21.919765 7f0c974f4700                 Options.inplace_update_num_locks: 10000
2019/03/12-15:23:21.919768 7f0c974f4700               Options.memtable_prefix_bloom_size_ratio: 0.000000
2019/03/12-15:23:21.919771 7f0c974f4700   Options.memtable_huge_page_size: 0
2019/03/12-15:23:21.919774 7f0c974f4700                           Options.bloom_locality: 0
2019/03/12-15:23:21.919776 7f0c974f4700                    Options.max_successive_merges: 0
2019/03/12-15:23:21.919778 7f0c974f4700                Options.optimize_filters_for_hits: 0
2019/03/12-15:23:21.919781 7f0c974f4700                Options.paranoid_file_checks: 0
2019/03/12-15:23:21.919783 7f0c974f4700                Options.force_consistency_checks: 0
2019/03/12-15:23:21.919786 7f0c974f4700                Options.report_bg_io_stats: 0
2019/03/12-15:23:21.920505 7f0c974f4700 [db/version_set.cc:2969] Recovered from manifest file:/home/svenkata/samza-release/samza-release/samza-test/state/mystore/Partition_0/MANIFEST-000007 succeeded,manifest_file_number is 7, next_file_number is 9, last_sequence is 6, log_number is 0,prev_log_number is 0,max_column_family is 0
2019/03/12-15:23:21.920512 7f0c974f4700 [db/version_set.cc:2977] Column family [default] (ID 0), log number is 3
2019/03/12-15:23:21.920611 7f0c974f4700 EVENT_LOG_v1 {"time_micros": 1552429401920600, "job": 1, "event": "recovery_started", "log_files": [3]}
2019/03/12-15:23:21.920619 7f0c974f4700 [db/db_impl_open.cc:506] Recovering log #3 mode 2
2019/03/12-15:23:21.920787 7f0c974f4700 [db/version_set.cc:2498] Creating manifest 9
2019/03/12-15:23:21.930844 7f0c974f4700 EVENT_LOG_v1 {"time_micros": 1552429401930837, "job": 1, "event": "recovery_finished"}
2019/03/12-15:23:21.939008 7f0c974f4700 [db/db_impl_open.cc:1111] DB pointer 0x7f0c98034120
2019/03/12-15:23:21.967221 7f0c974f4700 [db/db_impl_write.cc:1127] [default] New memtable created with log file: #10. Immutable memtables: 0.
2019/03/12-15:23:21.967304 7f0c817d2700 (Original Log Time 2019/03/12-15:23:21.967285) [db/db_impl_compaction_flush.cc:1216] Calling FlushMemTableToOutputFile with column family [default], flush slots available 1, compaction slots available 1, flush slots scheduled 1, compaction slots scheduled 0
2019/03/12-15:23:21.967312 7f0c817d2700 [db/flush_job.cc:264] [default] [JOB 3] Flushing memtable with next log file: 10
2019/03/12-15:23:21.967338 7f0c817d2700 EVENT_LOG_v1 {"time_micros": 1552429401967326, "job": 3, "event": "flush_started", "num_memtables": 1, "num_entries": 1, "num_deletes": 0, "memory_usage": 816}
2019/03/12-15:23:21.967343 7f0c817d2700 [db/flush_job.cc:293] [default] [JOB 3] Level-0 flush table #13: started
2019/03/12-15:23:21.971224 7f0c817d2700 EVENT_LOG_v1 {"time_micros": 1552429401971203, "cf_name": "default", "job": 3, "event": "table_file_creation", "file_number": 13, "file_size": 914, "table_properties": {"data_size": 26, "index_size": 27, "filter_size": 0, "raw_key_size": 9, "raw_average_key_size": 9, "raw_value_size": 1, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 1, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:21.971252 7f0c817d2700 [db/flush_job.cc:324] [default] [JOB 3] Level-0 flush table #13: 914 bytes OK
2019/03/12-15:23:21.978656 7f0c817d2700 (Original Log Time 2019/03/12-15:23:21.975128) [db/memtable_list.cc:372] [default] Level-0 commit table #13 started
2019/03/12-15:23:21.978662 7f0c817d2700 (Original Log Time 2019/03/12-15:23:21.978590) [db/memtable_list.cc:395] [default] Level-0 commit table #13: memtable #1 done
2019/03/12-15:23:21.978665 7f0c817d2700 (Original Log Time 2019/03/12-15:23:21.978614) EVENT_LOG_v1 {"time_micros": 1552429401978604, "job": 3, "event": "flush_finished", "lsm_state": [2, 0, 0, 0, 0, 0, 0], "immutable_memtables": 0}
2019/03/12-15:23:21.978668 7f0c817d2700 (Original Log Time 2019/03/12-15:23:21.978642) [db/db_impl_compaction_flush.cc:140] [default] Level summary: files[2 0 0 0 0 0 0] max score 0.50
2019/03/12-15:23:21.988117 7f0c974f4700 [db/db_impl_write.cc:1127] [default] New memtable created with log file: #10. Immutable memtables: 0.
2019/03/12-15:23:21.988196 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:21.988176) [db/db_impl_compaction_flush.cc:1216] Calling FlushMemTableToOutputFile with column family [default], flush slots available 1, compaction slots available 1, flush slots scheduled 1, compaction slots scheduled 0
2019/03/12-15:23:21.988207 7f0c80fd1700 [db/flush_job.cc:264] [default] [JOB 4] Flushing memtable with next log file: 10
2019/03/12-15:23:21.988241 7f0c80fd1700 EVENT_LOG_v1 {"time_micros": 1552429401988226, "job": 4, "event": "flush_started", "num_memtables": 1, "num_entries": 1, "num_deletes": 0, "memory_usage": 816}
2019/03/12-15:23:21.988249 7f0c80fd1700 [db/flush_job.cc:293] [default] [JOB 4] Level-0 flush table #14: started
2019/03/12-15:23:21.992264 7f0c80fd1700 EVENT_LOG_v1 {"time_micros": 1552429401992244, "cf_name": "default", "job": 4, "event": "table_file_creation", "file_number": 14, "file_size": 914, "table_properties": {"data_size": 26, "index_size": 27, "filter_size": 0, "raw_key_size": 9, "raw_average_key_size": 9, "raw_value_size": 1, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 1, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:21.992291 7f0c80fd1700 [db/flush_job.cc:324] [default] [JOB 4] Level-0 flush table #14: 914 bytes OK
2019/03/12-15:23:21.998785 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:21.995509) [db/memtable_list.cc:372] [default] Level-0 commit table #14 started
2019/03/12-15:23:21.998790 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:21.998722) [db/memtable_list.cc:395] [default] Level-0 commit table #14: memtable #1 done
2019/03/12-15:23:21.998793 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:21.998752) EVENT_LOG_v1 {"time_micros": 1552429401998734, "job": 4, "event": "flush_finished", "lsm_state": [3, 0, 0, 0, 0, 0, 0], "immutable_memtables": 0}
2019/03/12-15:23:21.998796 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:21.998772) [db/db_impl_compaction_flush.cc:140] [default] Level summary: files[3 0 0 0 0 0 0] max score 0.75
2019/03/12-15:23:22.001427 7f0c974f4700 [db/db_impl_write.cc:1127] [default] New memtable created with log file: #10. Immutable memtables: 0.
2019/03/12-15:23:22.001519 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.001504) [db/db_impl_compaction_flush.cc:1216] Calling FlushMemTableToOutputFile with column family [default], flush slots available 1, compaction slots available 1, flush slots scheduled 1, compaction slots scheduled 0
2019/03/12-15:23:22.001526 7f0de3f8a700 [db/flush_job.cc:264] [default] [JOB 5] Flushing memtable with next log file: 10
2019/03/12-15:23:22.001550 7f0de3f8a700 EVENT_LOG_v1 {"time_micros": 1552429402001540, "job": 5, "event": "flush_started", "num_memtables": 1, "num_entries": 1, "num_deletes": 0, "memory_usage": 816}
2019/03/12-15:23:22.001555 7f0de3f8a700 [db/flush_job.cc:293] [default] [JOB 5] Level-0 flush table #15: started
2019/03/12-15:23:22.007176 7f0de3f8a700 EVENT_LOG_v1 {"time_micros": 1552429402007157, "cf_name": "default", "job": 5, "event": "table_file_creation", "file_number": 15, "file_size": 914, "table_properties": {"data_size": 26, "index_size": 27, "filter_size": 0, "raw_key_size": 9, "raw_average_key_size": 9, "raw_value_size": 1, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 1, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:22.007201 7f0de3f8a700 [db/flush_job.cc:324] [default] [JOB 5] Level-0 flush table #15: 914 bytes OK
2019/03/12-15:23:22.013477 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.010479) [db/memtable_list.cc:372] [default] Level-0 commit table #15 started
2019/03/12-15:23:22.013482 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.013411) [db/memtable_list.cc:395] [default] Level-0 commit table #15: memtable #1 done
2019/03/12-15:23:22.013485 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.013433) EVENT_LOG_v1 {"time_micros": 1552429402013424, "job": 5, "event": "flush_finished", "lsm_state": [4, 0, 0, 0, 0, 0, 0], "immutable_memtables": 0}
2019/03/12-15:23:22.013488 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.013461) [db/db_impl_compaction_flush.cc:140] [default] Level summary: files[4 0 0 0 0 0 0] max score 1.00
2019/03/12-15:23:22.013685 7f0c85ff3700 [WARN] [db/db_impl.cc:424] ------- DUMPING STATS -------
2019/03/12-15:23:22.013725 7f0c85ff3700 [WARN] [db/db_impl.cc:425] 
** DB Stats **
Uptime(secs): 0.1 total, 0.1 interval
Cumulative writes: 4 writes, 3 keys, 4 commit groups, 0.8 writes per commit group, ingest: 0.00 GB, 0.00 MB/s
Cumulative WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s
Cumulative stall: 00:00:0.000 H:M:S, 0.0 percent
Interval writes: 4 writes, 3 keys, 4 commit groups, 0.8 writes per commit group, ingest: 0.00 MB, 0.00 MB/s
Interval WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 MB, 0.00 MB/s
Interval stall: 00:00:0.000 H:M:S, 0.0 percent

** Compaction Stats [default] **
Level    Files   Size     Score Read(GB)  Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop
----------------------------------------------------------------------------------------------------------------------------------------------------------
  L0      4/0    3.61 KB   0.0      0.0     0.0      0.0       0.0      0.0       0.0   1.0      0.0      0.1         0         3    0.008       0      0
 Sum      4/0    3.61 KB   0.0      0.0     0.0      0.0       0.0      0.0       0.0   1.0      0.0      0.1         0         3    0.008       0      0
 Int      0/0    0.00 KB   0.0      0.0     0.0      0.0       0.0      0.0       0.0   1.0      0.0      0.1         0         3    0.008       0      0
Uptime(secs): 0.1 total, 0.1 interval
Flush(GB): cumulative 0.000, interval 0.000
AddFile(GB): cumulative 0.000, interval 0.000
AddFile(Total Files): cumulative 0, interval 0
AddFile(L0 Files): cumulative 0, interval 0
AddFile(Keys): cumulative 0, interval 0
Cumulative compaction: 0.00 GB write, 0.03 MB/s write, 0.00 GB read, 0.00 MB/s read, 0.0 seconds
Interval compaction: 0.00 GB write, 0.03 MB/s write, 0.00 GB read, 0.00 MB/s read, 0.0 seconds
Stalls(count): 0 level0_slowdown, 0 level0_slowdown_with_compaction, 0 level0_numfiles, 0 level0_numfiles_with_compaction, 0 stop for pending_compaction_bytes, 0 slowdown for pending_compaction_bytes, 0 memtable_compaction, 0 memtable_slowdown, interval 0 total count

** File Read Latency Histogram By Level [default] **

** Compaction Stats [default] **
Level    Files   Size     Score Read(GB)  Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop
----------------------------------------------------------------------------------------------------------------------------------------------------------
  L0      4/0    3.61 KB   0.0      0.0     0.0      0.0       0.0      0.0       0.0   1.0      0.0      0.1         0         3    0.008       0      0
 Sum      4/0    3.61 KB   0.0      0.0     0.0      0.0       0.0      0.0       0.0   1.0      0.0      0.1         0         3    0.008       0      0
 Int      0/0    0.00 KB   0.0      0.0     0.0      0.0       0.0      0.0       0.0   0.0      0.0      0.0         0         0    0.000       0      0
Uptime(secs): 0.1 total, 0.0 interval
Flush(GB): cumulative 0.000, interval 0.000
AddFile(GB): cumulative 0.000, interval 0.000
AddFile(Total Files): cumulative 0, interval 0
AddFile(L0 Files): cumulative 0, interval 0
AddFile(Keys): cumulative 0, interval 0
Cumulative compaction: 0.00 GB write, 0.03 MB/s write, 0.00 GB read, 0.00 MB/s read, 0.0 seconds
Interval compaction: 0.00 GB write, 0.00 MB/s write, 0.00 GB read, 0.00 MB/s read, 0.0 seconds
Stalls(count): 0 level0_slowdown, 0 level0_slowdown_with_compaction, 0 level0_numfiles, 0 level0_numfiles_with_compaction, 0 stop for pending_compaction_bytes, 0 slowdown for pending_compaction_bytes, 0 memtable_compaction, 0 memtable_slowdown, interval 0 total count

** File Read Latency Histogram By Level [default] **
2019/03/12-15:23:22.013792 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.013758) [db/compaction_picker_universal.cc:270] [default] Universal: sorted runs files(4): files[4 0 0 0 0 0 0] max score 1.00
2019/03/12-15:23:22.013796 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.013761) [db/compaction_picker_universal.cc:655] [default] Universal: First candidate file 15[0]  to reduce size amp.
2019/03/12-15:23:22.013824 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.013763) [db/compaction_picker_universal.cc:693] [default] Universal: size amp needed. newer-files-total-size 2742 earliest-file-size 953
2019/03/12-15:23:22.013827 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.013766) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 15[0] with size 914 (compensated size 914)
2019/03/12-15:23:22.013830 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.013768) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 14[1] with size 914 (compensated size 914)
2019/03/12-15:23:22.013833 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.013770) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 13[2] with size 914 (compensated size 914)
2019/03/12-15:23:22.013836 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.013771) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 6[3] with size 953 (compensated size 953)
2019/03/12-15:23:22.013839 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.013779) [db/compaction_picker_universal.cc:278] [default] Universal: compacting for size amp
2019/03/12-15:23:22.013844 7f0c85ff3700 [db/compaction_job.cc:1437] [default] [JOB 6] Compacting 4@0 files to L6, score 1.00
2019/03/12-15:23:22.013852 7f0c85ff3700 [db/compaction_job.cc:1441] [default] Compaction start summary: Base version 5 Base level 0, inputs: [15(914B) 14(914B) 13(914B) 6(953B)], [], [], [], [], [], []
2019/03/12-15:23:22.013883 7f0c85ff3700 EVENT_LOG_v1 {"time_micros": 1552429402013862, "job": 6, "event": "compaction_started", "files_L0": [15, 14, 13, 6], "files_L1": [], "files_L2": [], "files_L3": [], "files_L4": [], "files_L5": [], "files_L6": [], "score": 1, "input_data_size": 3695}
2019/03/12-15:23:22.016206 7f0c974f4700 [db/db_impl_write.cc:1127] [default] New memtable created with log file: #10. Immutable memtables: 0.
2019/03/12-15:23:22.016295 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.016283) [db/db_impl_compaction_flush.cc:1216] Calling FlushMemTableToOutputFile with column family [default], flush slots available 1, compaction slots available 1, flush slots scheduled 1, compaction slots scheduled 1
2019/03/12-15:23:22.016302 7f0c81fd3700 [db/flush_job.cc:264] [default] [JOB 7] Flushing memtable with next log file: 10
2019/03/12-15:23:22.016328 7f0c81fd3700 EVENT_LOG_v1 {"time_micros": 1552429402016316, "job": 7, "event": "flush_started", "num_memtables": 1, "num_entries": 1, "num_deletes": 0, "memory_usage": 832}
2019/03/12-15:23:22.016334 7f0c81fd3700 [db/flush_job.cc:293] [default] [JOB 7] Level-0 flush table #17: started
2019/03/12-15:23:22.017896 7f0c85ff3700 [db/compaction_job.cc:1132] [default] [JOB 6] Generated table #16: 3 keys, 929 bytes
2019/03/12-15:23:22.017931 7f0c85ff3700 EVENT_LOG_v1 {"time_micros": 1552429402017912, "cf_name": "default", "job": 6, "event": "table_file_creation", "file_number": 16, "file_size": 929, "table_properties": {"data_size": 41, "index_size": 27, "filter_size": 0, "raw_key_size": 27, "raw_average_key_size": 9, "raw_value_size": 3, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 3, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:22.023044 7f0c81fd3700 EVENT_LOG_v1 {"time_micros": 1552429402023024, "cf_name": "default", "job": 7, "event": "table_file_creation", "file_number": 17, "file_size": 914, "table_properties": {"data_size": 26, "index_size": 27, "filter_size": 0, "raw_key_size": 9, "raw_average_key_size": 9, "raw_value_size": 1, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 1, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:22.023088 7f0c81fd3700 [db/flush_job.cc:324] [default] [JOB 7] Level-0 flush table #17: 914 bytes OK
2019/03/12-15:23:22.031394 7f0c85ff3700 [db/compaction_job.cc:1199] [default] [JOB 6] Compacted 4@0 files to L6 => 929 bytes
2019/03/12-15:23:22.037937 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.037805) [db/compaction_job.cc:621] [default] compacted to: files[0 0 0 0 0 0 1] max score 0.25, MB/sec: 0.2 rd, 0.1 wr, level 6, files in(4, 0) out(1) MB in(0.0, 0.0) out(0.0), read-write-amplify(1.3) write-amplify(0.3) OK, records in: 7, records dropped: 4
2019/03/12-15:23:22.037945 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.037837) EVENT_LOG_v1 {"time_micros": 1552429402037822, "job": 6, "event": "compaction_finished", "compaction_time_micros": 17465, "output_level": 6, "num_output_files": 1, "total_output_size": 929, "num_input_records": 7, "num_output_records": 3, "num_subcompactions": 1, "num_single_delete_mismatches": 0, "num_single_delete_fallthrough": 0, "lsm_state": [0, 0, 0, 0, 0, 0, 1]}
2019/03/12-15:23:22.038061 7f0c85ff3700 EVENT_LOG_v1 {"time_micros": 1552429402038055, "job": 6, "event": "table_file_deletion", "file_number": 15}
2019/03/12-15:23:22.038189 7f0c85ff3700 EVENT_LOG_v1 {"time_micros": 1552429402038182, "job": 6, "event": "table_file_deletion", "file_number": 14}
2019/03/12-15:23:22.038250 7f0c85ff3700 EVENT_LOG_v1 {"time_micros": 1552429402038247, "job": 6, "event": "table_file_deletion", "file_number": 13}
2019/03/12-15:23:22.038281 7f0c85ff3700 EVENT_LOG_v1 {"time_micros": 1552429402038278, "job": 6, "event": "table_file_deletion", "file_number": 6}
2019/03/12-15:23:22.038323 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.038315) [db/compaction_picker_universal.cc:261] [default] Universal: nothing to do
2019/03/12-15:23:22.038328 7f0c85ff3700 (Original Log Time 2019/03/12-15:23:22.038316) [db/db_impl_compaction_flush.cc:1518] Compaction nothing to do
2019/03/12-15:23:22.041334 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.034631) [db/memtable_list.cc:372] [default] Level-0 commit table #17 started
2019/03/12-15:23:22.041339 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.041282) [db/memtable_list.cc:395] [default] Level-0 commit table #17: memtable #1 done
2019/03/12-15:23:22.041341 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.041303) EVENT_LOG_v1 {"time_micros": 1552429402041293, "job": 7, "event": "flush_finished", "lsm_state": [1, 0, 0, 0, 0, 0, 1], "immutable_memtables": 0}
2019/03/12-15:23:22.041344 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.041321) [db/db_impl_compaction_flush.cc:140] [default] Level summary: files[1 0 0 0 0 0 1] max score 0.50
2019/03/12-15:23:22.044526 7f0c974f4700 [db/db_impl_write.cc:1127] [default] New memtable created with log file: #10. Immutable memtables: 0.
2019/03/12-15:23:22.044576 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.044563) [db/db_impl_compaction_flush.cc:1216] Calling FlushMemTableToOutputFile with column family [default], flush slots available 1, compaction slots available 1, flush slots scheduled 1, compaction slots scheduled 0
2019/03/12-15:23:22.044582 7f0c817d2700 [db/flush_job.cc:264] [default] [JOB 9] Flushing memtable with next log file: 10
2019/03/12-15:23:22.044602 7f0c817d2700 EVENT_LOG_v1 {"time_micros": 1552429402044593, "job": 9, "event": "flush_started", "num_memtables": 1, "num_entries": 1, "num_deletes": 0, "memory_usage": 824}
2019/03/12-15:23:22.044606 7f0c817d2700 [db/flush_job.cc:293] [default] [JOB 9] Level-0 flush table #18: started
2019/03/12-15:23:22.048469 7f0c817d2700 EVENT_LOG_v1 {"time_micros": 1552429402048452, "cf_name": "default", "job": 9, "event": "table_file_creation", "file_number": 18, "file_size": 916, "table_properties": {"data_size": 28, "index_size": 27, "filter_size": 0, "raw_key_size": 10, "raw_average_key_size": 10, "raw_value_size": 2, "raw_average_value_size": 2, "num_data_blocks": 1, "num_entries": 1, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:22.048498 7f0c817d2700 [db/flush_job.cc:324] [default] [JOB 9] Level-0 flush table #18: 916 bytes OK
2019/03/12-15:23:22.054960 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.051659) [db/memtable_list.cc:372] [default] Level-0 commit table #18 started
2019/03/12-15:23:22.054967 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.054901) [db/memtable_list.cc:395] [default] Level-0 commit table #18: memtable #1 done
2019/03/12-15:23:22.054972 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.054920) EVENT_LOG_v1 {"time_micros": 1552429402054912, "job": 9, "event": "flush_finished", "lsm_state": [2, 0, 0, 0, 0, 0, 1], "immutable_memtables": 0}
2019/03/12-15:23:22.054974 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.054944) [db/db_impl_compaction_flush.cc:140] [default] Level summary: files[2 0 0 0 0 0 1] max score 0.75
2019/03/12-15:23:22.057102 7f0c974f4700 [db/db_impl_write.cc:1127] [default] New memtable created with log file: #10. Immutable memtables: 0.
2019/03/12-15:23:22.057150 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:22.057136) [db/db_impl_compaction_flush.cc:1216] Calling FlushMemTableToOutputFile with column family [default], flush slots available 1, compaction slots available 1, flush slots scheduled 1, compaction slots scheduled 0
2019/03/12-15:23:22.057155 7f0c80fd1700 [db/flush_job.cc:264] [default] [JOB 10] Flushing memtable with next log file: 10
2019/03/12-15:23:22.057172 7f0c80fd1700 EVENT_LOG_v1 {"time_micros": 1552429402057164, "job": 10, "event": "flush_started", "num_memtables": 1, "num_entries": 1, "num_deletes": 1, "memory_usage": 816}
2019/03/12-15:23:22.057175 7f0c80fd1700 [db/flush_job.cc:293] [default] [JOB 10] Level-0 flush table #19: started
2019/03/12-15:23:22.060891 7f0c80fd1700 EVENT_LOG_v1 {"time_micros": 1552429402060876, "cf_name": "default", "job": 10, "event": "table_file_creation", "file_number": 19, "file_size": 914, "table_properties": {"data_size": 26, "index_size": 27, "filter_size": 0, "raw_key_size": 10, "raw_average_key_size": 10, "raw_value_size": 0, "raw_average_value_size": 0, "num_data_blocks": 1, "num_entries": 1, "filter_policy_name": "", "kDeletedKeys": "1", "kMergeOperands": "0"}}
2019/03/12-15:23:22.060910 7f0c80fd1700 [db/flush_job.cc:324] [default] [JOB 10] Level-0 flush table #19: 914 bytes OK
2019/03/12-15:23:22.067594 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:22.064399) [db/memtable_list.cc:372] [default] Level-0 commit table #19 started
2019/03/12-15:23:22.067597 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:22.067548) [db/memtable_list.cc:395] [default] Level-0 commit table #19: memtable #1 done
2019/03/12-15:23:22.067600 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:22.067564) EVENT_LOG_v1 {"time_micros": 1552429402067557, "job": 10, "event": "flush_finished", "lsm_state": [3, 0, 0, 0, 0, 0, 1], "immutable_memtables": 0}
2019/03/12-15:23:22.067602 7f0c80fd1700 (Original Log Time 2019/03/12-15:23:22.067583) [db/db_impl_compaction_flush.cc:140] [default] Level summary: files[3 0 0 0 0 0 1] max score 1.00
2019/03/12-15:23:22.067686 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.067643) [db/compaction_picker_universal.cc:270] [default] Universal: sorted runs files(4): files[3 0 0 0 0 0 1] max score 1.00
2019/03/12-15:23:22.067691 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.067646) [db/compaction_picker_universal.cc:655] [default] Universal: First candidate file 19[0]  to reduce size amp.
2019/03/12-15:23:22.067694 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.067648) [db/compaction_picker_universal.cc:693] [default] Universal: size amp needed. newer-files-total-size 2854 earliest-file-size 929
2019/03/12-15:23:22.067697 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.067651) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 19[0] with size 914 (compensated size 1024)
2019/03/12-15:23:22.067699 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.067653) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 18[1] with size 916 (compensated size 916)
2019/03/12-15:23:22.067702 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.067660) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 17[2] with size 914 (compensated size 914)
2019/03/12-15:23:22.067704 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.067661) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking level 6[3] with size 929 (compensated size 929)
2019/03/12-15:23:22.067707 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.067669) [db/compaction_picker_universal.cc:278] [default] Universal: compacting for size amp
2019/03/12-15:23:22.067711 7f0c857f2700 [db/compaction_job.cc:1437] [default] [JOB 11] Compacting 3@0 + 1@6 files to L6, score 1.00
2019/03/12-15:23:22.067719 7f0c857f2700 [db/compaction_job.cc:1441] [default] Compaction start summary: Base version 9 Base level 0, inputs: [19(914B) 18(916B) 17(914B)], [], [], [], [], [], [16(929B)]
2019/03/12-15:23:22.067761 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402067728, "job": 11, "event": "compaction_started", "files_L0": [19, 18, 17], "files_L1": [], "files_L2": [], "files_L3": [], "files_L4": [], "files_L5": [], "files_L6": [16], "score": 1, "input_data_size": 3673}
2019/03/12-15:23:22.071542 7f0c857f2700 [db/compaction_job.cc:1132] [default] [JOB 11] Generated table #20: 3 keys, 929 bytes
2019/03/12-15:23:22.071567 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402071554, "cf_name": "default", "job": 11, "event": "table_file_creation", "file_number": 20, "file_size": 929, "table_properties": {"data_size": 41, "index_size": 27, "filter_size": 0, "raw_key_size": 27, "raw_average_key_size": 9, "raw_value_size": 3, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 3, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:22.074708 7f0c857f2700 [db/compaction_job.cc:1199] [default] [JOB 11] Compacted 3@0 + 1@6 files to L6 => 929 bytes
2019/03/12-15:23:22.077782 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.077689) [db/compaction_job.cc:621] [default] compacted to: files[0 0 0 0 0 0 1] max score 0.25, MB/sec: 0.5 rd, 0.1 wr, level 6, files in(3, 1) out(1) MB in(0.0, 0.0) out(0.0), read-write-amplify(1.7) write-amplify(0.3) OK, records in: 6, records dropped: 3
2019/03/12-15:23:22.077787 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.077712) EVENT_LOG_v1 {"time_micros": 1552429402077701, "job": 11, "event": "compaction_finished", "compaction_time_micros": 6914, "output_level": 6, "num_output_files": 1, "total_output_size": 929, "num_input_records": 6, "num_output_records": 3, "num_subcompactions": 1, "num_single_delete_mismatches": 0, "num_single_delete_fallthrough": 0, "lsm_state": [0, 0, 0, 0, 0, 0, 1]}
2019/03/12-15:23:22.077847 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402077842, "job": 11, "event": "table_file_deletion", "file_number": 19}
2019/03/12-15:23:22.077880 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402077878, "job": 11, "event": "table_file_deletion", "file_number": 18}
2019/03/12-15:23:22.077910 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402077907, "job": 11, "event": "table_file_deletion", "file_number": 17}
2019/03/12-15:23:22.077938 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402077936, "job": 11, "event": "table_file_deletion", "file_number": 16}
2019/03/12-15:23:22.077963 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.077960) [db/compaction_picker_universal.cc:261] [default] Universal: nothing to do
2019/03/12-15:23:22.077965 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.077961) [db/db_impl_compaction_flush.cc:1518] Compaction nothing to do
2019/03/12-15:23:22.558089 7f0c974f4700 [db/db_impl_write.cc:1127] [default] New memtable created with log file: #10. Immutable memtables: 0.
2019/03/12-15:23:22.558187 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.558161) [db/db_impl_compaction_flush.cc:1216] Calling FlushMemTableToOutputFile with column family [default], flush slots available 1, compaction slots available 1, flush slots scheduled 1, compaction slots scheduled 0
2019/03/12-15:23:22.558199 7f0de3f8a700 [db/flush_job.cc:264] [default] [JOB 13] Flushing memtable with next log file: 10
2019/03/12-15:23:22.558246 7f0de3f8a700 EVENT_LOG_v1 {"time_micros": 1552429402558227, "job": 13, "event": "flush_started", "num_memtables": 1, "num_entries": 1, "num_deletes": 0, "memory_usage": 816}
2019/03/12-15:23:22.558257 7f0de3f8a700 [db/flush_job.cc:293] [default] [JOB 13] Level-0 flush table #21: started
2019/03/12-15:23:22.564885 7f0de3f8a700 EVENT_LOG_v1 {"time_micros": 1552429402564860, "cf_name": "default", "job": 13, "event": "table_file_creation", "file_number": 21, "file_size": 914, "table_properties": {"data_size": 26, "index_size": 27, "filter_size": 0, "raw_key_size": 9, "raw_average_key_size": 9, "raw_value_size": 1, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 1, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:22.564917 7f0de3f8a700 [db/flush_job.cc:324] [default] [JOB 13] Level-0 flush table #21: 914 bytes OK
2019/03/12-15:23:22.573112 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.568111) [db/memtable_list.cc:372] [default] Level-0 commit table #21 started
2019/03/12-15:23:22.573119 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.573035) [db/memtable_list.cc:395] [default] Level-0 commit table #21: memtable #1 done
2019/03/12-15:23:22.573122 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.573062) EVENT_LOG_v1 {"time_micros": 1552429402573051, "job": 13, "event": "flush_finished", "lsm_state": [1, 0, 0, 0, 0, 0, 1], "immutable_memtables": 0}
2019/03/12-15:23:22.573126 7f0de3f8a700 (Original Log Time 2019/03/12-15:23:22.573094) [db/db_impl_compaction_flush.cc:140] [default] Level summary: files[1 0 0 0 0 0 1] max score 0.50
2019/03/12-15:23:22.576149 7f0c974f4700 [db/db_impl_write.cc:1127] [default] New memtable created with log file: #10. Immutable memtables: 0.
2019/03/12-15:23:22.576220 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.576201) [db/db_impl_compaction_flush.cc:1216] Calling FlushMemTableToOutputFile with column family [default], flush slots available 1, compaction slots available 1, flush slots scheduled 1, compaction slots scheduled 0
2019/03/12-15:23:22.576228 7f0c81fd3700 [db/flush_job.cc:264] [default] [JOB 14] Flushing memtable with next log file: 10
2019/03/12-15:23:22.576256 7f0c81fd3700 EVENT_LOG_v1 {"time_micros": 1552429402576244, "job": 14, "event": "flush_started", "num_memtables": 1, "num_entries": 1, "num_deletes": 0, "memory_usage": 816}
2019/03/12-15:23:22.576261 7f0c81fd3700 [db/flush_job.cc:293] [default] [JOB 14] Level-0 flush table #22: started
2019/03/12-15:23:22.580196 7f0c81fd3700 EVENT_LOG_v1 {"time_micros": 1552429402580175, "cf_name": "default", "job": 14, "event": "table_file_creation", "file_number": 22, "file_size": 914, "table_properties": {"data_size": 26, "index_size": 27, "filter_size": 0, "raw_key_size": 9, "raw_average_key_size": 9, "raw_value_size": 1, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 1, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:22.580224 7f0c81fd3700 [db/flush_job.cc:324] [default] [JOB 14] Level-0 flush table #22: 914 bytes OK
2019/03/12-15:23:22.586914 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.583527) [db/memtable_list.cc:372] [default] Level-0 commit table #22 started
2019/03/12-15:23:22.586920 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.586856) [db/memtable_list.cc:395] [default] Level-0 commit table #22: memtable #1 done
2019/03/12-15:23:22.586923 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.586880) EVENT_LOG_v1 {"time_micros": 1552429402586870, "job": 14, "event": "flush_finished", "lsm_state": [2, 0, 0, 0, 0, 0, 1], "immutable_memtables": 0}
2019/03/12-15:23:22.586926 7f0c81fd3700 (Original Log Time 2019/03/12-15:23:22.586900) [db/db_impl_compaction_flush.cc:140] [default] Level summary: files[2 0 0 0 0 0 1] max score 0.75
2019/03/12-15:23:22.590123 7f0c974f4700 [db/db_impl_write.cc:1127] [default] New memtable created with log file: #10. Immutable memtables: 0.
2019/03/12-15:23:22.590194 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.590178) [db/db_impl_compaction_flush.cc:1216] Calling FlushMemTableToOutputFile with column family [default], flush slots available 1, compaction slots available 1, flush slots scheduled 1, compaction slots scheduled 0
2019/03/12-15:23:22.590202 7f0c817d2700 [db/flush_job.cc:264] [default] [JOB 15] Flushing memtable with next log file: 10
2019/03/12-15:23:22.590228 7f0c817d2700 EVENT_LOG_v1 {"time_micros": 1552429402590216, "job": 15, "event": "flush_started", "num_memtables": 1, "num_entries": 1, "num_deletes": 0, "memory_usage": 816}
2019/03/12-15:23:22.590235 7f0c817d2700 [db/flush_job.cc:293] [default] [JOB 15] Level-0 flush table #23: started
2019/03/12-15:23:22.594004 7f0c817d2700 EVENT_LOG_v1 {"time_micros": 1552429402593984, "cf_name": "default", "job": 15, "event": "table_file_creation", "file_number": 23, "file_size": 914, "table_properties": {"data_size": 26, "index_size": 27, "filter_size": 0, "raw_key_size": 9, "raw_average_key_size": 9, "raw_value_size": 1, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 1, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:22.594029 7f0c817d2700 [db/flush_job.cc:324] [default] [JOB 15] Level-0 flush table #23: 914 bytes OK
2019/03/12-15:23:22.602202 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.597917) [db/memtable_list.cc:372] [default] Level-0 commit table #23 started
2019/03/12-15:23:22.602206 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.602126) [db/memtable_list.cc:395] [default] Level-0 commit table #23: memtable #1 done
2019/03/12-15:23:22.602208 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.602168) EVENT_LOG_v1 {"time_micros": 1552429402602141, "job": 15, "event": "flush_finished", "lsm_state": [3, 0, 0, 0, 0, 0, 1], "immutable_memtables": 0}
2019/03/12-15:23:22.602210 7f0c817d2700 (Original Log Time 2019/03/12-15:23:22.602191) [db/db_impl_compaction_flush.cc:140] [default] Level summary: files[3 0 0 0 0 0 1] max score 1.00
2019/03/12-15:23:22.602275 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.602226) [db/compaction_picker_universal.cc:270] [default] Universal: sorted runs files(4): files[3 0 0 0 0 0 1] max score 1.00
2019/03/12-15:23:22.602282 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.602230) [db/compaction_picker_universal.cc:655] [default] Universal: First candidate file 23[0]  to reduce size amp.
2019/03/12-15:23:22.602286 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.602231) [db/compaction_picker_universal.cc:693] [default] Universal: size amp needed. newer-files-total-size 2742 earliest-file-size 929
2019/03/12-15:23:22.602289 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.602235) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 23[0] with size 914 (compensated size 914)
2019/03/12-15:23:22.602293 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.602238) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 22[1] with size 914 (compensated size 914)
2019/03/12-15:23:22.602296 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.602240) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking file 21[2] with size 914 (compensated size 914)
2019/03/12-15:23:22.602299 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.602242) [db/compaction_picker_universal.cc:724] [default] Universal: size amp picking level 6[3] with size 929 (compensated size 929)
2019/03/12-15:23:22.602303 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.602253) [db/compaction_picker_universal.cc:278] [default] Universal: compacting for size amp
2019/03/12-15:23:22.602308 7f0c857f2700 [db/compaction_job.cc:1437] [default] [JOB 16] Compacting 3@0 + 1@6 files to L6, score 1.00
2019/03/12-15:23:22.602318 7f0c857f2700 [db/compaction_job.cc:1441] [default] Compaction start summary: Base version 13 Base level 0, inputs: [23(914B) 22(914B) 21(914B)], [], [], [], [], [], [20(929B)]
2019/03/12-15:23:22.602355 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402602330, "job": 16, "event": "compaction_started", "files_L0": [23, 22, 21], "files_L1": [], "files_L2": [], "files_L3": [], "files_L4": [], "files_L5": [], "files_L6": [20], "score": 1, "input_data_size": 3671}
2019/03/12-15:23:22.606457 7f0c857f2700 [db/compaction_job.cc:1132] [default] [JOB 16] Generated table #24: 5 keys, 950 bytes
2019/03/12-15:23:22.606499 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402606479, "cf_name": "default", "job": 16, "event": "table_file_creation", "file_number": 24, "file_size": 950, "table_properties": {"data_size": 62, "index_size": 27, "filter_size": 0, "raw_key_size": 45, "raw_average_key_size": 9, "raw_value_size": 5, "raw_average_value_size": 1, "num_data_blocks": 1, "num_entries": 5, "filter_policy_name": "", "kDeletedKeys": "0", "kMergeOperands": "0"}}
2019/03/12-15:23:22.609854 7f0c857f2700 [db/compaction_job.cc:1199] [default] [JOB 16] Compacted 3@0 + 1@6 files to L6 => 950 bytes
2019/03/12-15:23:22.613083 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.612957) [db/compaction_job.cc:621] [default] compacted to: files[0 0 0 0 0 0 1] max score 0.25, MB/sec: 0.5 rd, 0.1 wr, level 6, files in(3, 1) out(1) MB in(0.0, 0.0) out(0.0), read-write-amplify(1.7) write-amplify(0.3) OK, records in: 6, records dropped: 1
2019/03/12-15:23:22.613090 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.612990) EVENT_LOG_v1 {"time_micros": 1552429402612974, "job": 16, "event": "compaction_finished", "compaction_time_micros": 7451, "output_level": 6, "num_output_files": 1, "total_output_size": 950, "num_input_records": 6, "num_output_records": 5, "num_subcompactions": 1, "num_single_delete_mismatches": 0, "num_single_delete_fallthrough": 0, "lsm_state": [0, 0, 0, 0, 0, 0, 1]}
2019/03/12-15:23:22.613191 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402613185, "job": 16, "event": "table_file_deletion", "file_number": 23}
2019/03/12-15:23:22.613238 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402613235, "job": 16, "event": "table_file_deletion", "file_number": 22}
2019/03/12-15:23:22.613278 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402613275, "job": 16, "event": "table_file_deletion", "file_number": 21}
2019/03/12-15:23:22.613316 7f0c857f2700 EVENT_LOG_v1 {"time_micros": 1552429402613313, "job": 16, "event": "table_file_deletion", "file_number": 20}
2019/03/12-15:23:22.613350 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.613345) [db/compaction_picker_universal.cc:261] [default] Universal: nothing to do
2019/03/12-15:23:22.613354 7f0c857f2700 (Original Log Time 2019/03/12-15:23:22.613347) [db/db_impl_compaction_flush.cc:1518] Compaction nothing to do
2019/03/12-15:23:23.082350 7f0c974f4700 [db/db_impl.cc:222] Shutdown: canceling all background work
2019/03/12-15:23:23.082565 7f0c974f4700 [db/db_impl.cc:348] Shutdown complete
