[2021/10/29 00:13:18.434] format.sh: job in /data/mci/d6ac5dbd6df83f80a130eee4729fd4e6/wiredtiger/test/format/RUNDIR.7 failed
[2021/10/29 00:13:18.435]     t: process 33599 running
[2021/10/29 00:13:18.435]     [1635466390:344171][33599:0x3ff8ecf6e40], t, file:F00009, WT_CURSOR.insert: __verify_row_key_order_check, 259: the 1 and 3 keys on page at [write-check] are incorrectly sorted: 0000000189.00/opqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuv, 0000000189.00/opqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuv

[2021/10/29 00:13:18.437]     ############################################
[2021/10/29 00:13:18.437]     #  RUN PARAMETERS: V3
[2021/10/29 00:13:18.437]     ############################################
[2021/10/29 00:13:18.437]     assert.read_timestamp=0
[2021/10/29 00:13:18.437]     assert.write_timestamp=0
[2021/10/29 00:13:18.437]     backup=0
[2021/10/29 00:13:18.437]     backup.incremental=off
[2021/10/29 00:13:18.437]     backup.incr_granularity=8659
[2021/10/29 00:13:18.437]     block_cache=1
[2021/10/29 00:13:18.437]     block_cache.cache_on_checkpoint=0
[2021/10/29 00:13:18.437]     block_cache.cache_on_writes=1
[2021/10/29 00:13:18.437]     block_cache.size=111
[2021/10/29 00:13:18.437]     btree.huffman_value=0
[2021/10/29 00:13:18.437]     btree.internal_page_max=9
[2021/10/29 00:13:18.437]     btree.key_min=256
[2021/10/29 00:13:18.437]     btree.leaf_page_max=9
[2021/10/29 00:13:18.437]     btree.value_min=256
[2021/10/29 00:13:18.437]     cache=552
[2021/10/29 00:13:18.437]     cache.evict_max=4
[2021/10/29 00:13:18.437]     cache.minimum=20
[2021/10/29 00:13:18.437]     checkpoint=on
[2021/10/29 00:13:18.437]     checkpoint.log_size=37
[2021/10/29 00:13:18.437]     checkpoint.wait=86
[2021/10/29 00:13:18.437]     disk.data_extend=0
[2021/10/29 00:13:18.437]     disk.direct_io=0
[2021/10/29 00:13:18.437]     disk.encryption=rotn-7
[2021/10/29 00:13:18.437]     disk.mmap=1
[2021/10/29 00:13:18.437]     disk.mmap_all=0
[2021/10/29 00:13:18.437]     format.abort=0
[2021/10/29 00:13:18.437]     format.independent_thread_rng=0
[2021/10/29 00:13:18.437]     format.major_timeout=0
[2021/10/29 00:13:18.437]     import=0
[2021/10/29 00:13:18.437]     logging=0
[2021/10/29 00:13:18.437]     logging.archive=0
[2021/10/29 00:13:18.437]     logging.compression=none
[2021/10/29 00:13:18.437]     logging.file_max=170574
[2021/10/29 00:13:18.437]     logging.prealloc=0
[2021/10/29 00:13:18.437]     ops.alter=0
[2021/10/29 00:13:18.437]     ops.compaction=0
[2021/10/29 00:13:18.437]     ops.hs_cursor=0
[2021/10/29 00:13:18.437]     ops.prepare=0
[2021/10/29 00:13:18.437]     ops.random_cursor=0
[2021/10/29 00:13:18.437]     ops.salvage=0
[2021/10/29 00:13:18.437]     ops.verify=1
[2021/10/29 00:13:18.437]     quiet=1
[2021/10/29 00:13:18.437]     runs.in_memory=0
[2021/10/29 00:13:18.437]     runs.ops=0
[2021/10/29 00:13:18.437]     runs.rows=100000
[2021/10/29 00:13:18.437]     runs.source=table
[2021/10/29 00:13:18.437]     runs.tables=10
[2021/10/29 00:13:18.437]     runs.threads=6
[2021/10/29 00:13:18.437]     runs.timer=4
[2021/10/29 00:13:18.437]     runs.type=row-store
[2021/10/29 00:13:18.437]     runs.verify_failure_dump=0
[2021/10/29 00:13:18.437]     statistics=0
[2021/10/29 00:13:18.437]     statistics.server=0
[2021/10/29 00:13:18.437]     stress.aggressive_sweep=0
[2021/10/29 00:13:18.437]     stress.checkpoint=0
[2021/10/29 00:13:18.437]     stress.checkpoint_reserved_txnid_delay=0
[2021/10/29 00:13:18.437]     stress.checkpoint_prepare=1
[2021/10/29 00:13:18.437]     stress.failpoint_hs_delete_key_from_ts=1
[2021/10/29 00:13:18.437]     stress.failpoint_hs_insert_1=1
[2021/10/29 00:13:18.437]     stress.failpoint_hs_insert_2=0
[2021/10/29 00:13:18.437]     stress.hs_checkpoint_delay=0
[2021/10/29 00:13:18.437]     stress.hs_search=0
[2021/10/29 00:13:18.437]     stress.hs_sweep=0
[2021/10/29 00:13:18.437]     stress.split_1=0
[2021/10/29 00:13:18.437]     stress.split_2=0
[2021/10/29 00:13:18.437]     stress.split_3=0
[2021/10/29 00:13:18.437]     stress.split_4=0
[2021/10/29 00:13:18.437]     stress.split_5=0
[2021/10/29 00:13:18.437]     stress.split_6=0
[2021/10/29 00:13:18.437]     stress.split_7=0
[2021/10/29 00:13:18.438]     transaction.implicit=0
[2021/10/29 00:13:18.438]     transaction.timestamps=1
[2021/10/29 00:13:18.438]     wiredtiger.config=
[2021/10/29 00:13:18.438]     wiredtiger.rwlock=1
[2021/10/29 00:13:18.438]     wiredtiger.leak_memory=0
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table1.btree.compression=none
[2021/10/29 00:13:18.438]     table1.btree.dictionary=0
[2021/10/29 00:13:18.438]     table1.btree.internal_key_truncation=1
[2021/10/29 00:13:18.438]     table1.btree.key_max=256
[2021/10/29 00:13:18.438]     table1.btree.memory_page_max=1
[2021/10/29 00:13:18.438]     table1.btree.prefix_len=0
[2021/10/29 00:13:18.438]     table1.btree.prefix_compression=1
[2021/10/29 00:13:18.438]     table1.btree.prefix_compression_min=3
[2021/10/29 00:13:18.438]     table1.btree.reverse=0
[2021/10/29 00:13:18.438]     table1.btree.split_pct=60
[2021/10/29 00:13:18.438]     table1.btree.value_max=1831
[2021/10/29 00:13:18.438]     table1.disk.checksum=unencrypted
[2021/10/29 00:13:18.438]     table1.disk.firstfit=0
[2021/10/29 00:13:18.438]     table1.ops.pct.delete=5
[2021/10/29 00:13:18.438]     table1.ops.pct.insert=22
[2021/10/29 00:13:18.438]     table1.ops.pct.modify=21
[2021/10/29 00:13:18.438]     table1.ops.pct.read=46
[2021/10/29 00:13:18.438]     table1.ops.pct.write=6
[2021/10/29 00:13:18.438]     table1.ops.truncate=1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 2
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table2.btree.compression=snappy
[2021/10/29 00:13:18.438]     table2.btree.dictionary=0
[2021/10/29 00:13:18.438]     table2.btree.internal_key_truncation=1
[2021/10/29 00:13:18.438]     table2.btree.key_max=256
[2021/10/29 00:13:18.438]     table2.btree.memory_page_max=3
[2021/10/29 00:13:18.438]     table2.btree.prefix_len=0
[2021/10/29 00:13:18.438]     table2.btree.prefix_compression=1
[2021/10/29 00:13:18.438]     table2.btree.prefix_compression_min=0
[2021/10/29 00:13:18.438]     table2.btree.reverse=0
[2021/10/29 00:13:18.438]     table2.btree.split_pct=64
[2021/10/29 00:13:18.438]     table2.btree.value_max=490
[2021/10/29 00:13:18.438]     table2.disk.checksum=uncompressed
[2021/10/29 00:13:18.438]     table2.disk.firstfit=0
[2021/10/29 00:13:18.438]     table2.ops.pct.delete=94
[2021/10/29 00:13:18.438]     table2.ops.pct.insert=0
[2021/10/29 00:13:18.438]     table2.ops.pct.modify=2
[2021/10/29 00:13:18.438]     table2.ops.pct.read=2
[2021/10/29 00:13:18.438]     table2.ops.pct.write=2
[2021/10/29 00:13:18.438]     table2.ops.truncate=1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 3
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table3.btree.compression=none
[2021/10/29 00:13:18.438]     table3.btree.dictionary=0
[2021/10/29 00:13:18.438]     table3.btree.internal_key_truncation=1
[2021/10/29 00:13:18.438]     table3.btree.key_max=256
[2021/10/29 00:13:18.438]     table3.btree.memory_page_max=9
[2021/10/29 00:13:18.438]     table3.btree.prefix_len=54
[2021/10/29 00:13:18.438]     table3.btree.prefix_compression=1
[2021/10/29 00:13:18.438]     table3.btree.prefix_compression_min=3
[2021/10/29 00:13:18.438]     table3.btree.reverse=0
[2021/10/29 00:13:18.438]     table3.btree.split_pct=89
[2021/10/29 00:13:18.438]     table3.btree.value_max=1308
[2021/10/29 00:13:18.438]     table3.disk.checksum=unencrypted
[2021/10/29 00:13:18.438]     table3.disk.firstfit=0
[2021/10/29 00:13:18.438]     table3.ops.pct.delete=1
[2021/10/29 00:13:18.438]     table3.ops.pct.insert=1
[2021/10/29 00:13:18.438]     table3.ops.pct.modify=52
[2021/10/29 00:13:18.438]     table3.ops.pct.read=43
[2021/10/29 00:13:18.438]     table3.ops.pct.write=3
[2021/10/29 00:13:18.438]     table3.ops.truncate=1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 4
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table4.btree.compression=none
[2021/10/29 00:13:18.438]     table4.btree.dictionary=0
[2021/10/29 00:13:18.438]     table4.btree.internal_key_truncation=0
[2021/10/29 00:13:18.438]     table4.btree.key_max=256
[2021/10/29 00:13:18.438]     table4.btree.memory_page_max=9
[2021/10/29 00:13:18.438]     table4.btree.prefix_len=0
[2021/10/29 00:13:18.438]     table4.btree.prefix_compression=1
[2021/10/29 00:13:18.438]     table4.btree.prefix_compression_min=6
[2021/10/29 00:13:18.438]     table4.btree.reverse=0
[2021/10/29 00:13:18.438]     table4.btree.split_pct=90
[2021/10/29 00:13:18.438]     table4.btree.value_max=1893
[2021/10/29 00:13:18.438]     table4.disk.checksum=uncompressed
[2021/10/29 00:13:18.438]     table4.disk.firstfit=0
[2021/10/29 00:13:18.438]     table4.ops.pct.delete=3
[2021/10/29 00:13:18.438]     table4.ops.pct.insert=19
[2021/10/29 00:13:18.438]     table4.ops.pct.modify=6
[2021/10/29 00:13:18.438]     table4.ops.pct.read=62
[2021/10/29 00:13:18.438]     table4.ops.pct.write=10
[2021/10/29 00:13:18.438]     table4.ops.truncate=1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 5
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table5.btree.compression=snappy
[2021/10/29 00:13:18.438]     table5.btree.dictionary=0
[2021/10/29 00:13:18.438]     table5.btree.internal_key_truncation=1
[2021/10/29 00:13:18.438]     table5.btree.key_max=256
[2021/10/29 00:13:18.438]     table5.btree.memory_page_max=7
[2021/10/29 00:13:18.438]     table5.btree.prefix_len=0
[2021/10/29 00:13:18.438]     table5.btree.prefix_compression=1
[2021/10/29 00:13:18.438]     table5.btree.prefix_compression_min=7
[2021/10/29 00:13:18.438]     table5.btree.reverse=1
[2021/10/29 00:13:18.438]     table5.btree.split_pct=96
[2021/10/29 00:13:18.438]     table5.btree.value_max=1728
[2021/10/29 00:13:18.438]     table5.disk.checksum=uncompressed
[2021/10/29 00:13:18.438]     table5.disk.firstfit=0
[2021/10/29 00:13:18.438]     table5.ops.pct.delete=20
[2021/10/29 00:13:18.438]     table5.ops.pct.insert=36
[2021/10/29 00:13:18.438]     table5.ops.pct.modify=36
[2021/10/29 00:13:18.438]     table5.ops.pct.read=1
[2021/10/29 00:13:18.438]     table5.ops.pct.write=7
[2021/10/29 00:13:18.438]     table5.ops.truncate=1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 6
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table6.btree.compression=none
[2021/10/29 00:13:18.438]     table6.btree.dictionary=0
[2021/10/29 00:13:18.438]     table6.btree.internal_key_truncation=1
[2021/10/29 00:13:18.438]     table6.btree.key_max=256
[2021/10/29 00:13:18.438]     table6.btree.memory_page_max=4
[2021/10/29 00:13:18.438]     table6.btree.prefix_len=35
[2021/10/29 00:13:18.438]     table6.btree.prefix_compression=1
[2021/10/29 00:13:18.438]     table6.btree.prefix_compression_min=2
[2021/10/29 00:13:18.438]     table6.btree.reverse=0
[2021/10/29 00:13:18.438]     table6.btree.split_pct=82
[2021/10/29 00:13:18.438]     table6.btree.value_max=1542
[2021/10/29 00:13:18.438]     table6.disk.checksum=on
[2021/10/29 00:13:18.438]     table6.disk.firstfit=0
[2021/10/29 00:13:18.438]     table6.ops.pct.delete=87
[2021/10/29 00:13:18.438]     table6.ops.pct.insert=0
[2021/10/29 00:13:18.438]     table6.ops.pct.modify=13
[2021/10/29 00:13:18.438]     table6.ops.pct.read=0
[2021/10/29 00:13:18.438]     table6.ops.pct.write=0
[2021/10/29 00:13:18.438]     table6.ops.truncate=1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 7
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table7.btree.compression=none
[2021/10/29 00:13:18.438]     table7.btree.dictionary=1
[2021/10/29 00:13:18.438]     table7.btree.internal_key_truncation=1
[2021/10/29 00:13:18.438]     table7.btree.key_max=256
[2021/10/29 00:13:18.438]     table7.btree.memory_page_max=1
[2021/10/29 00:13:18.438]     table7.btree.prefix_len=0
[2021/10/29 00:13:18.438]     table7.btree.prefix_compression=0
[2021/10/29 00:13:18.438]     table7.btree.prefix_compression_min=3
[2021/10/29 00:13:18.438]     table7.btree.reverse=0
[2021/10/29 00:13:18.438]     table7.btree.split_pct=88
[2021/10/29 00:13:18.438]     table7.btree.value_max=508
[2021/10/29 00:13:18.438]     table7.disk.checksum=on
[2021/10/29 00:13:18.438]     table7.disk.firstfit=0
[2021/10/29 00:13:18.438]     table7.ops.pct.delete=18
[2021/10/29 00:13:18.438]     table7.ops.pct.insert=3
[2021/10/29 00:13:18.438]     table7.ops.pct.modify=0
[2021/10/29 00:13:18.438]     table7.ops.pct.read=18
[2021/10/29 00:13:18.438]     table7.ops.pct.write=61
[2021/10/29 00:13:18.438]     table7.ops.truncate=1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 8
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table8.btree.compression=snappy
[2021/10/29 00:13:18.438]     table8.btree.dictionary=0
[2021/10/29 00:13:18.438]     table8.btree.internal_key_truncation=1
[2021/10/29 00:13:18.438]     table8.btree.key_max=256
[2021/10/29 00:13:18.438]     table8.btree.memory_page_max=10
[2021/10/29 00:13:18.438]     table8.btree.prefix_len=0
[2021/10/29 00:13:18.438]     table8.btree.prefix_compression=1
[2021/10/29 00:13:18.438]     table8.btree.prefix_compression_min=1
[2021/10/29 00:13:18.438]     table8.btree.reverse=0
[2021/10/29 00:13:18.438]     table8.btree.split_pct=73
[2021/10/29 00:13:18.438]     table8.btree.value_max=712
[2021/10/29 00:13:18.438]     table8.disk.checksum=unencrypted
[2021/10/29 00:13:18.438]     table8.disk.firstfit=0
[2021/10/29 00:13:18.438]     table8.ops.pct.delete=89
[2021/10/29 00:13:18.438]     table8.ops.pct.insert=11
[2021/10/29 00:13:18.438]     table8.ops.pct.modify=0
[2021/10/29 00:13:18.438]     table8.ops.pct.read=0
[2021/10/29 00:13:18.438]     table8.ops.pct.write=0
[2021/10/29 00:13:18.438]     table8.ops.truncate=1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 9
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table9.btree.compression=zlib
[2021/10/29 00:13:18.438]     table9.btree.dictionary=0
[2021/10/29 00:13:18.438]     table9.btree.internal_key_truncation=1
[2021/10/29 00:13:18.438]     table9.btree.key_max=256
[2021/10/29 00:13:18.438]     table9.btree.memory_page_max=1
[2021/10/29 00:13:18.438]     table9.btree.prefix_len=0
[2021/10/29 00:13:18.438]     table9.btree.prefix_compression=1
[2021/10/29 00:13:18.438]     table9.btree.prefix_compression_min=6
[2021/10/29 00:13:18.438]     table9.btree.reverse=0
[2021/10/29 00:13:18.438]     table9.btree.split_pct=50
[2021/10/29 00:13:18.438]     table9.btree.value_max=2110
[2021/10/29 00:13:18.438]     table9.disk.checksum=unencrypted
[2021/10/29 00:13:18.438]     table9.disk.firstfit=0
[2021/10/29 00:13:18.438]     table9.ops.pct.delete=5
[2021/10/29 00:13:18.438]     table9.ops.pct.insert=7
[2021/10/29 00:13:18.438]     table9.ops.pct.modify=71
[2021/10/29 00:13:18.438]     table9.ops.pct.read=17
[2021/10/29 00:13:18.438]     table9.ops.pct.write=0
[2021/10/29 00:13:18.438]     table9.ops.truncate=1
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     #  TABLE PARAMETERS: table 10
[2021/10/29 00:13:18.438]     ############################################
[2021/10/29 00:13:18.438]     table10.btree.compression=zlib
[2021/10/29 00:13:18.438]     table10.btree.dictionary=0
[2021/10/29 00:13:18.438]     table10.btree.internal_key_truncation=1
[2021/10/29 00:13:18.438]     table10.btree.key_max=256
[2021/10/29 00:13:18.438]     table10.btree.memory_page_max=1
[2021/10/29 00:13:18.438]     table10.btree.prefix_len=0
[2021/10/29 00:13:18.438]     table10.btree.prefix_compression=1
[2021/10/29 00:13:18.438]     table10.btree.prefix_compression_min=0
[2021/10/29 00:13:18.438]     table10.btree.reverse=0
[2021/10/29 00:13:18.438]     table10.btree.split_pct=77
[2021/10/29 00:13:18.438]     table10.btree.value_max=2562
[2021/10/29 00:13:18.438]     table10.disk.checksum=unencrypted
[2021/10/29 00:13:18.438]     table10.disk.firstfit=0
[2021/10/29 00:13:18.438]     table10.ops.pct.delete=15
[2021/10/29 00:13:18.438]     table10.ops.pct.insert=0
[2021/10/29 00:13:18.438]     table10.ops.pct.modify=4
[2021/10/29 00:13:18.438]     table10.ops.pct.read=73
[2021/10/29 00:13:18.438]     table10.ops.pct.write=8
[2021/10/29 00:13:18.438]     table10.ops.truncate=1