Project

General

Profile

Bug #19247 » case8.txt

Igor Fedotov, 03/09/2017 11:46 AM

 
ObjectStore/StoreTest.SyntheticMatrixCsumVsCompression/2
---------------------- 11 / 16 ----------------------
max_write = 131072
max_size = 262144
alignment = 512
bluestore_min_alloc_size = 16384
bluestore_compression_mode = force
bluestore_compression_algorithm = snappy
bluestore_csum_type = crc32c
bluestore_default_buffered_read = false
bluestore_default_buffered_write = true
bluestore_sync_submit_transaction = false
seeding object 0
seeding object 500
Op 0
available_objects: 995 in_flight_objects: 5 total objects: 1000 in_flight 5
Op 1000
available_objects: 995 in_flight_objects: 1 total objects: 996 in_flight 1
Op 2000
available_objects: 1008 in_flight_objects: 3 total objects: 1011 in_flight 3
Op 3000
available_objects: 1018 in_flight_objects: 2 total objects: 1020 in_flight 2
Op 4000
available_objects: 1032 in_flight_objects: 2 total objects: 1034 in_flight 2
--- buffer mismatch between offset 0x1a800 and 0x1d000, total 0x1fc00
--- expected:
00014600 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
*
0001a800 38 36 34 39 30 34 38 34 32 30 35 39 39 35 36 33 |8649048420599563|
0001a810 31 31 31 30 37 38 38 39 38 38 32 34 31 35 32 31 |1110788988241521|

---actual
00014600 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
*
0001d000 37 31 32 38 34 37 30 30 36 34 32 32 39 31 34 34 |7128470064229144|


************ log snippet ****************

//clone
2017-03-08 17:39:20.994657 7f4a07027a80 15 bluestore(bluestore.test_temp_dir) _clone_range 555.0_head #555:2e000000:::OBJ_627aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# -> #555:99000000:::OBJ_1128:head# from 0xba00~11600 to offset 0xba00
2017-03-08 17:39:20.994668 7f4a07027a80 15 bluestore(bluestore.test_temp_dir) _do_zero 555.0_head #555:99000000:::OBJ_1128:head# 0xba00~11600
2017-03-08 17:39:20.994673 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_onode 0x7f49e9fd2100 #555:99000000:::OBJ_1128:head# nid 17658 size 0x1fc00 (130048) expected_object_size 0 expect
ed_write_size 0 in 0 shards, 0 spanning blobs
2017-03-08 17:39:20.994677 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x4000~c000: 0x4000~c000 Blob(0x7f49e5332e10 blob([0x44890000~10000] mutable+csum+has_unused crc32c/0
x1000 unused=0xf) use_tracker(0x10000 0xc000) SharedBlob(0x7f49e5332e80 sbid 0x0))
2017-03-08 17:39:20.994687 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [0,0,0,0,9ac8bdfb,a1d3d86f,2666028b,2c4bfe4f,1ea91b77,89180e5b,8e16a1a4,408f947d,b1eabeb1,1
5f6514f,6338bf8b,dd09f1b4]
2017-03-08 17:39:20.994690 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x10000~fc00: 0x0~fc00 Blob(0x7f49e5332ef0 blob([0x448a0000~10000] mutable+csum crc32c/0x1000) use_tr
acker(0x10000 0xfc00) SharedBlob(0x7f49e5332f60 sbid 0x0))
2017-03-08 17:39:20.994695 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [5ec3fe1,50254116,d7a282c1,f0c31bb9,2ff82f3c,e1d4d253,8362cfe5,a3116d9f,58f055ce,57193c4c,d
450ad44,a1bd199e,6eaee7c3,799068e6,82d852f9,9e0c5949]
2017-03-08 17:39:20.994702 7f4a07027a80 20 bluestore.onode(0x7f49e9fd2100) flush done
2017-03-08 17:39:20.994704 7f4a07027a80 30 bluestore.extentmap(0x7f49e9fd2250) fault_range 0xba00~11600
2017-03-08 17:39:20.994708 7f4a07027a80 30 bluestore.extentmap(0x7f49e9fd2250) dirty_range 0xba00~11600
2017-03-08 17:39:20.994711 7f4a07027a80 20 bluestore.extentmap(0x7f49e9fd2250) dirty_range mark inline shard dirty
2017-03-08 17:39:20.994714 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _wctx_finish lex_old 0xba00~4600: 0xba00~4600 Blob(0x7f49e5332e10 blob([0x44890000~10000] mutable+csum+has_unused crc32
c/0x1000 unused=0xf) use_tracker(0x10000 0xc000) SharedBlob(0x7f49e5332e80 sbid 0x0))
2017-03-08 17:39:20.994718 7f4a07027a80 20 bluestore.blob(0x7f49e5332e10) put_ref 0xba00~4600 Blob(0x7f49e5332e10 blob([0x44890000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xf) use_track
er(0x10000 0xc000) SharedBlob(0x7f49e5332e80 sbid 0x0))
2017-03-08 17:39:20.994723 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _wctx_finish lex_old 0x10000~d000: 0x0~d000 Blob(0x7f49e5332ef0 blob([0x448a0000~10000] mutable+csum crc32c/0x1000) use
_tracker(0x10000 0xfc00) SharedBlob(0x7f49e5332f60 sbid 0x0))
2017-03-08 17:39:20.994726 7f4a07027a80 20 bluestore.blob(0x7f49e5332ef0) put_ref 0x0~d000 Blob(0x7f49e5332ef0 blob([0x448a0000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0xfc00) Shared
Blob(0x7f49e5332f60 sbid 0x0))
2017-03-08 17:39:20.994731 7f4a07027a80 10 bluestore(bluestore.test_temp_dir) _do_zero 555.0_head #555:99000000:::OBJ_1128:head# 0xba00~11600 = 0
2017-03-08 17:39:20.994734 7f4a07027a80 15 bluestore(bluestore.test_temp_dir) _do_clone_range 555.0_head #555:2e000000:::OBJ_627aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# -> #555:99000000:::OBJ_1128:head# 0xba00~11600 -> 0xba00~11600
2017-03-08 17:39:20.994741 7f4a07027a80 30 bluestore.extentmap(0x7f49e099f750) fault_range 0xba00~11600
2017-03-08 17:39:20.994742 7f4a07027a80 30 bluestore.extentmap(0x7f49e9fd2250) fault_range 0xba00~11600
2017-03-08 17:39:20.994743 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_onode 0x7f49e099f600 #555:2e000000:::OBJ_627aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# nid 17110 size 0x32c00 (207872) expected_object_size 1048576 expected_write_size 65536 in 0 shards, 0 spanning blobs
2017-03-08 17:39:20.994749 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x5a00~a600: 0x5a00~a600 Blob(0x7f49de27ccc0 blob([0x44b60000~10000] mutable+csum+has_unused crc32c/0
x1000 unused=0x1f) use_tracker(0x10000 0xa600) SharedBlob(0x7f49de27c9b0 sbid 0x0))
2017-03-08 17:39:20.994754 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [0,0,0,0,0,67f65dd2,e10fcd7b,cf48aa29,7e4c0d46,3fc1b40d,2a5dbeef,55821618,957401a1,8a79720d
,44b8dade,de227f23]
2017-03-08 17:39:20.994758 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x10000~4600: 0x0~4600 Blob(0x7f49de27d820 blob([0x40b60000~10000] mutable+csum+has_unused crc32c/0x1
000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 sbid 0x0))
2017-03-08 17:39:20.994762 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,
1aeff427,a7ca9220]
2017-03-08 17:39:20.994766 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:20.994769 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x1a800~5800: 0xa800~5800 Blob(0x7f49de27d820 blob([0x40b60000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 sbid 0x0))
2017-03-08 17:39:20.994773 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:20.994777 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:20.994779 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x20000~10000: 0x0~10000 Blob(0x7f49de4ff780 blob([0x44d40000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x10000) SharedBlob(0x7f49de4ff8d0 sbid 0x0))
2017-03-08 17:39:20.994783 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [d2daa932,63c8d205,353aac97,2ab9a6e5,538c838a,d5e62e0,4b0f0dfb,8a3b7b39,6141a73b,6b766382,60096ecf,c77a4bb1,30b3e70e,4c232734,94cbc0e0,39eba923]
2017-03-08 17:39:20.994789 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x0~10000 buffer(0x7f49fa8a1a60 space 0x7f49de4ff8e8 0x0~10000 clean)
2017-03-08 17:39:20.994791 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x30000~2c00: 0x0~2c00 Blob(0x7f49de4ff1d0 blob([0x40b80000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xfff8) use_tracker(0x10000 0x2c00) SharedBlob(0x7f49de4ff320 sbid 0x0))
2017-03-08 17:39:20.994796 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [af7eecaa,8569ced1,67841d96,0,0,0,0,0,0,0,0,0,0,0,0,0]
2017-03-08 17:39:20.994798 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x0~3000 buffer(0x7f49fa8a1c40 space 0x7f49de4ff338 0x0~3000 clean)
2017-03-08 17:39:20.994801 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_onode 0x7f49e9fd2100 #555:99000000:::OBJ_1128:head# nid 17658 size 0x1fc00 (130048) expected_object_size 0 expected_write_size 0 in 0 shards, 0 spanning blobs
2017-03-08 17:39:20.994804 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x4000~7a00: 0x4000~7a00 Blob(0x7f49e5332e10 blob([0x44890000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xf) use_tracker(0x10000 0x7a00) SharedBlob(0x7f49e5332e80 sbid 0x0))
2017-03-08 17:39:20.994808 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [0,0,0,0,9ac8bdfb,a1d3d86f,2666028b,2c4bfe4f,1ea91b77,89180e5b,8e16a1a4,408f947d,b1eabeb1,15f6514f,6338bf8b,dd09f1b4]
2017-03-08 17:39:20.994811 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x1d000~2c00: 0xd000~2c00 Blob(0x7f49e5332ef0 blob([0x448a0000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x2c00) SharedBlob(0x7f49e5332f60 sbid 0x0))
2017-03-08 17:39:20.994815 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [5ec3fe1,50254116,d7a282c1,f0c31bb9,2ff82f3c,e1d4d253,8362cfe5,a3116d9f,58f055ce,57193c4c,d450ad44,a1bd199e,6eaee7c3,799068e6,82d852f9,9e0c5949]
2017-03-08 17:39:20.994819 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _do_clone_range src 0x5a00~a600: 0x5a00~a600 Blob(0x7f49de27ccc0 blob([0x44b60000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0xa600) SharedBlob(0x7f49de27c9b0 sbid 0x0))
2017-03-08 17:39:20.994827 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _assign_blobid 11085
2017-03-08 17:39:20.994829 7f4a07027a80 10 bluestore(bluestore.test_temp_dir).collection(555.0_head) make_blob_shared Blob(0x7f49de27ccc0 blob([0x44b60000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0xa600) SharedBlob(0x7f49de27c9b0 sbid 0x0))
2017-03-08 17:39:20.994834 7f4a07027a80 20 bluestore(bluestore.test_temp_dir).collection(555.0_head) make_blob_shared now Blob(0x7f49de27ccc0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0xa600) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=1))))
2017-03-08 17:39:20.994854 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _do_clone_range new Blob(0x7f49e5332fd0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x0 0x0) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2))))
2017-03-08 17:39:20.994864 7f4a07027a80 20 bluestore.blob(0x7f49e5332fd0) get_ref 0xba00~4600 Blob(0x7f49e5332fd0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x0 0x0) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2))))
2017-03-08 17:39:20.994870 7f4a07027a80 20 bluestore.blob(0x7f49e5332fd0) get_ref init 0x10000, 10000
2017-03-08 17:39:20.994872 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _do_clone_range dst 0xba00~4600: 0xba00~4600 Blob(0x7f49e5332fd0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0x4600) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2))))
2017-03-08 17:39:20.994878 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _do_clone_range src 0x10000~4600: 0x0~4600 Blob(0x7f49de27d820 blob([0x40b60000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 sbid 0x0))
2017-03-08 17:39:20.994882 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _assign_blobid 11086
2017-03-08 17:39:20.994883 7f4a07027a80 10 bluestore(bluestore.test_temp_dir).collection(555.0_head) make_blob_shared Blob(0x7f49de27d820 blob([0x40b60000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 sbid 0x0))
2017-03-08 17:39:20.994887 7f4a07027a80 20 bluestore(bluestore.test_temp_dir).collection(555.0_head) make_blob_shared now Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=1))))
2017-03-08 17:39:20.994892 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _do_clone_range new Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x0 0x0) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.994896 7f4a07027a80 20 bluestore.blob(0x7f49e5333040) get_ref 0x0~4600 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x0 0x0) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.994899 7f4a07027a80 20 bluestore.blob(0x7f49e5333040) get_ref init 0x10000, 10000
2017-03-08 17:39:20.994901 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _do_clone_range dst 0x10000~4600: 0x0~4600 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x4600) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.994904 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _do_clone_range src 0x1a800~5800: 0xa800~5800 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.994909 7f4a07027a80 20 bluestore.blob(0x7f49e5333040) get_ref 0xa800~2800 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x4600) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.994913 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _do_clone_range dst 0x1a800~2800: 0xa800~2800 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.994917 7f4a07027a80 30 bluestore.extentmap(0x7f49e099f750) dirty_range 0xba00~11600
2017-03-08 17:39:20.994918 7f4a07027a80 20 bluestore.extentmap(0x7f49e099f750) dirty_range mark inline shard dirty
2017-03-08 17:39:20.994921 7f4a07027a80 30 bluestore.extentmap(0x7f49e9fd2250) dirty_range 0xba00~11600
2017-03-08 17:39:20.994923 7f4a07027a80 20 bluestore.extentmap(0x7f49e9fd2250) dirty_range mark inline shard dirty
2017-03-08 17:39:20.994924 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_onode 0x7f49e099f600 #555:2e000000:::OBJ_627aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# nid 17110 size 0x32c00 (207872) expected_object_size 1048576 expected_write_size 65536 in 0 shards, 0 spanning blobs
2017-03-08 17:39:20.994931 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x5a00~a600: 0x5a00~a600 Blob(0x7f49de27ccc0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0xa600) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2))))
2017-03-08 17:39:20.994936 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [0,0,0,0,0,67f65dd2,e10fcd7b,cf48aa29,7e4c0d46,3fc1b40d,2a5dbeef,55821618,957401a1,8a79720d,44b8dade,de227f23]
2017-03-08 17:39:20.994939 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x10000~4600: 0x0~4600 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.994943 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:20.994947 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:20.994949 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x1a800~5800: 0xa800~5800 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.994953 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:20.994956 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:20.994958 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x20000~10000: 0x0~10000 Blob(0x7f49de4ff780 blob([0x44d40000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x10000) SharedBlob(0x7f49de4ff8d0 sbid 0x0))
2017-03-08 17:39:20.994962 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [d2daa932,63c8d205,353aac97,2ab9a6e5,538c838a,d5e62e0,4b0f0dfb,8a3b7b39,6141a73b,6b766382,60096ecf,c77a4bb1,30b3e70e,4c232734,94cbc0e0,39eba923]
2017-03-08 17:39:20.994965 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x0~10000 buffer(0x7f49fa8a1a60 space 0x7f49de4ff8e8 0x0~10000 clean)
2017-03-08 17:39:20.994967 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x30000~2c00: 0x0~2c00 Blob(0x7f49de4ff1d0 blob([0x40b80000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xfff8) use_tracker(0x10000 0x2c00) SharedBlob(0x7f49de4ff320 sbid 0x0))
2017-03-08 17:39:20.994973 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [af7eecaa,8569ced1,67841d96,0,0,0,0,0,0,0,0,0,0,0,0,0]
2017-03-08 17:39:20.994976 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x0~3000 buffer(0x7f49fa8a1c40 space 0x7f49de4ff338 0x0~3000 clean)
2017-03-08 17:39:20.994978 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_onode 0x7f49e9fd2100 #555:99000000:::OBJ_1128:head# nid 17658 size 0x1fc00 (130048) expected_object_size 0 expected_write_size 0 in 0 shards, 0 spanning blobs
2017-03-08 17:39:20.995004 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x4000~7a00: 0x4000~7a00 Blob(0x7f49e5332e10 blob([0x44890000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xf) use_tracker(0x10000 0x7a00) SharedBlob(0x7f49e5332e80 sbid 0x0))
2017-03-08 17:39:20.995009 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [0,0,0,0,9ac8bdfb,a1d3d86f,2666028b,2c4bfe4f,1ea91b77,89180e5b,8e16a1a4,408f947d,b1eabeb1,15f6514f,6338bf8b,dd09f1b4]
2017-03-08 17:39:20.995012 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xba00~4600: 0xba00~4600 Blob(0x7f49e5332fd0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0x4600) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2))))
2017-03-08 17:39:20.995017 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [0,0,0,0,0,67f65dd2,e10fcd7b,cf48aa29,7e4c0d46,3fc1b40d,2a5dbeef,55821618,957401a1,8a79720d,44b8dade,de227f23]
2017-03-08 17:39:20.995020 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x10000~4600: 0x0~4600 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.995024 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:20.995027 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:20.995030 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x1a800~2800: 0xa800~2800 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.995034 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:20.995037 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:20.995039 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x1d000~2c00: 0xd000~2c00 Blob(0x7f49e5332ef0 blob([0x448a0000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x2c00) SharedBlob(0x7f49e5332f60 sbid 0x0))
2017-03-08 17:39:20.995042 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [5ec3fe1,50254116,d7a282c1,f0c31bb9,2ff82f3c,e1d4d253,8362cfe5,a3116d9f,58f055ce,57193c4c,d450ad44,a1bd199e,6eaee7c3,799068e6,82d852f9,9e0c5949]
2017-03-08 17:39:20.995047 7f4a07027a80 10 bluestore(bluestore.test_temp_dir) _clone_range 555.0_head #555:2e000000:::OBJ_627aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# -> #555:99000000:::OBJ_1128:head# from 0xba00~11600 to offset 0xba00 = 0
2017-03-08 17:39:20.995055 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _txc_write_nodes txc 0x7f49e9fd1800 onodes 0x7f49e099f600,0x7f49e9fd2100 shared_blobs 0x7f49de27c9b0,0x7f49de27da50
2017-03-08 17:39:20.995058 7f4a07027a80 20 bluestore.extentmap(0x7f49e099f750) update #555:2e000000:::OBJ_627aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2#
2017-03-08 17:39:20.995066 7f4a07027a80 20 bluestore.extentmap(0x7f49e099f750) update inline shard 342 bytes from 5 extents
2017-03-08 17:39:20.995065 7f49d7bf3700 20 bluestore(bluestore.test_temp_dir) _kv_sync_thread committed 1 cleaned 0 in 0.000603
2017-03-08 17:39:20.995070 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) onode #555:2e000000:::OBJ_627aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# is 376 (28 bytes onode + 2 bytes spanning blobs + 346 bytes inline extents)

...

//read OK
2017-03-08 17:39:20.996099 7f49d73f2700 15 bluestore(bluestore.test_temp_dir) read 555.0_head #555:99000000:::OBJ_1128:head# 0x0~1fc00
2017-03-08 17:39:20.996172 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x10000~4600: 0x0~4600 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.996179 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:20.996186 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:20.996190 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x1a800~2800: 0xa800~2800 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:20.996195 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:20.996198 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)

//zero on clone source
2017-03-08 17:39:21.017869 7f4a07027a80 15 bluestore(bluestore.test_temp_dir) _zero 555.0_head #555:2e000000:::OBJ_627aaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# 0x14400~12c00
2017-03-08 17:39:21.017881 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_onode 0x7f49e099f600 #555:2e000000:::OBJ_627aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# nid 17110 size 0x32c00 (207872) expected_object_size 1048576 expected_write_size 65536 in 0 shards, 0 spanning blobs
2017-03-08 17:39:21.017892 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x5a00~a600: 0x5a00~a600 Blob(0x7f49de27ccc0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0xa600) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2))))
2017-03-08 17:39:21.017906 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [0,0,0,0,0,67f65dd2,e10fcd7b,cf48aa29,7e4c0d46,3fc1b40d,2a5dbeef,55821618,957401a1,8a79720d,44b8dade,de227f23]
2017-03-08 17:39:21.017910 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x10000~4600: 0x0~4600 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:21.017920 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:21.017923 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:21.017927 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x1a800~5800: 0xa800~5800 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:21.017936 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:21.017940 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:21.017943 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x20000~10000: 0x0~10000 Blob(0x7f49de4ff780 blob([0x44d40000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x10000) SharedBlob(0x7f49de4ff8d0 sbid 0x0))
2017-03-08 17:39:21.017948 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [d2daa932,63c8d205,353aac97,2ab9a6e5,538c838a,d5e62e0,4b0f0dfb,8a3b7b39,6141a73b,6b766382,60096ecf,c77a4bb1,30b3e70e,4c232734,94cbc0e0,39eba923]
2017-03-08 17:39:21.017952 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x0~10000 buffer(0x7f49fa8a1a60 space 0x7f49de4ff8e8 0x0~10000 clean)
2017-03-08 17:39:21.017954 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x30000~2c00: 0x0~2c00 Blob(0x7f49de4ff1d0 blob([0x40b80000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xfff8) use_tracker(0x10000 0x2c00) SharedBlob(0x7f49de4ff320 sbid 0x0))
2017-03-08 17:39:21.017959 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [af7eecaa,8569ced1,67841d96,0,0,0,0,0,0,0,0,0,0,0,0,0]
2017-03-08 17:39:21.017962 7f4a07027a80 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x0~3000 buffer(0x7f49fa8a1c40 space 0x7f49de4ff338 0x0~3000 clean)
2017-03-08 17:39:21.017965 7f4a07027a80 20 bluestore.onode(0x7f49e099f600) flush done
2017-03-08 17:39:21.017966 7f4a07027a80 30 bluestore.extentmap(0x7f49e099f750) fault_range 0x14400~12c00
2017-03-08 17:39:21.017973 7f4a07027a80 30 bluestore.extentmap(0x7f49e099f750) dirty_range 0x14400~12c00
2017-03-08 17:39:21.017975 7f4a07027a80 20 bluestore.extentmap(0x7f49e099f750) dirty_range mark inline shard dirty
2017-03-08 17:39:21.017978 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _wctx_finish lex_old 0x14400~200: 0x4400~200 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:21.017984 7f4a07027a80 20 bluestore.blob(0x7f49de27d820) put_ref 0x4400~200 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:21.017990 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _wctx_finish lex_old 0x1a800~5800: 0xa800~5800 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9c00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:21.018000 7f4a07027a80 20 bluestore.blob(0x7f49de27d820) put_ref 0xa800~5800 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x9c00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:21.018005 7f4a07027a80 20 bluestore(bluestore.test_temp_dir) _wctx_finish lex_old 0x20000~7000: 0x0~7000 Blob(0x7f49de4ff780 blob([0x44d40000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x10000) SharedBlob(0x7f49de4ff8d0 sbid 0x0))
2017-03-08 17:39:21.018010 7f4a07027a80 20 bluestore.blob(0x7f49de4ff780) put_ref 0x0~7000 Blob(0x7f49de4ff780 blob([0x44d40000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x10000) SharedBlob(0x7f49de4ff8d0 sbid 0x0))
2017-03-08 17:39:21.018018 7f4a07027a80 10 bluestore(bluestore.test_temp_dir) _do_zero 555.0_head #555:2e000000:::OBJ_627aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# 0x14400~12c00 = 0

...
//read on clone source
2017-03-08 17:39:21.027828 7f49d73f2700 15 bluestore(bluestore.test_temp_dir) read 555.0_head #555:2e000000:::OBJ_627aaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa
aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:2bab91d2# 0x0~32c00
...
_dump_extent_map 0x10000~4400: 0x0~4400 Blob(0x7f49de27d820 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x4400) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
...
//bad read on clone target
2017-03-08 17:39:21.371571 7f49d73f2700 15 bluestore(bluestore.test_temp_dir) read 555.0_head #555:99000000:::OBJ_1128:head#0 0x0~1fc00
2017-03-08 17:39:21.371582 7f49d73f2700 30 bluestore.OnodeSpace(0x7f49ddef5378 in 0x7f49fa842720) lookup
2017-03-08 17:39:21.371584 7f49d73f2700 30 bluestore.OnodeSpace(0x7f49ddef5378 in 0x7f49fa842720) lookup #555:99000000:::OBJ_1128:head#0 hit 0x7f49e9fd2100
2017-03-08 17:39:21.371588 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read 0x0~1fc00 size 0x1fc00 (130048)
2017-03-08 17:39:21.371590 7f49d73f2700 20 bluestore.onode(0x7f49e9fd2100) flush done
2017-03-08 17:39:21.371592 7f49d73f2700 30 bluestore.extentmap(0x7f49e9fd2250) fault_range 0x0~1fc00
2017-03-08 17:39:21.371594 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_onode 0x7f49e9fd2100 #555:99000000:::OBJ_1128:head#0 nid 17658 size 0x1fc00 (130048) expected_object_size 0 expected_write_size 0 in 0 shards, 0 spanning blobs
2017-03-08 17:39:21.371598 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x4000~7a00: 0x4000~7a00 Blob(0x7f49e5332e10 blob([0x44890000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xf) use_tracker(0x10000 0x7a00) SharedBlob(0x7f49e5332e80 sbid 0x0))
2017-03-08 17:39:21.371613 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [0,0,0,0,9ac8bdfb,a1d3d86f,2666028b,2c4bfe4f,1ea91b77,89180e5b,8e16a1a4,408f947d,b1eabeb1,15f6514f,6338bf8b,dd09f1b4]
2017-03-08 17:39:21.371617 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xba00~4600: 0xba00~4600 Blob(0x7f49e5332fd0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0x4600) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2))))
2017-03-08 17:39:21.371627 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [0,0,0,0,0,67f65dd2,e10fcd7b,cf48aa29,7e4c0d46,3fc1b40d,2a5dbeef,55821618,957401a1,8a79720d,44b8dade,de227f23]
2017-03-08 17:39:21.371630 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x10000~4600: 0x0~4600 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:21.371635 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:21.371638 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:21.371641 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x1a800~2800: 0xa800~2800 Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2))))
2017-03-08 17:39:21.371649 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [90817c03,2a603d73,a0bacead,40fad88,c0de0549,0,0,0,0,0,44d1ed15,eb4dbbad,db15f001,65829526,1aeff427,a7ca9220]
2017-03-08 17:39:21.371652 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0xa000~6000 buffer(0x7f49e63b6b60 space 0x7f49de27da68 0xa000~6000 clean)
2017-03-08 17:39:21.371654 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map 0x1d000~2c00: 0xd000~2c00 Blob(0x7f49e5332ef0 blob([0x448a0000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x2c00) SharedBlob(0x7f49e5332f60 sbid 0x0))
2017-03-08 17:39:21.371658 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _dump_extent_map csum: [5ec3fe1,50254116,d7a282c1,f0c31bb9,2ff82f3c,e1d4d253,8362cfe5,a3116d9f,58f055ce,57193c4c,d450ad44,a1bd199e,6eaee7c3,799068e6,82d852f9,9e0c5949]
2017-03-08 17:39:21.371661 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read hole 0x0~4000
2017-03-08 17:39:21.371663 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5332e10 blob([0x44890000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xf) use_tracker(0x10000 0x7a00) SharedBlob(0x7f49e5332e80 sbid 0x0)) need 0x4000~7a00 cache has 0x[]
2017-03-08 17:39:21.371667 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read will read 0x4000: 0x4000~7a00
2017-03-08 17:39:21.371670 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5332fd0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0x4600) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2)))) need 0xba00~4600 cache has 0x[]
2017-03-08 17:39:21.371673 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read will read 0xba00: 0xba00~4600
2017-03-08 17:39:21.371676 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2)))) need 0x0~4600 cache has 0x[]
2017-03-08 17:39:21.371679 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read will read 0x10000: 0x0~4600
2017-03-08 17:39:21.371681 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read hole 0x14600~6200
2017-03-08 17:39:21.371684 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2)))) need 0xa800~2800 cache has 0x[a800~2800]
2017-03-08 17:39:21.371690 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read use cache 0x1a800: 0xa800~2800
2017-03-08 17:39:21.371692 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5332ef0 blob([0x448a0000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x2c00) SharedBlob(0x7f49e5332f60 sbid 0x0)) need 0xd000~2c00 cache has 0x[]
2017-03-08 17:39:21.371695 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read will read 0x1d000: 0xd000~2c00
2017-03-08 17:39:21.371697 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5332e10 blob([0x44890000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xf) use_tracker(0x10000 0x7a00) SharedBlob(0x7f49e5332e80 sbid 0x0)) need 0x0x4000:4000~7a00
2017-03-08 17:39:21.371701 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read region 0x4000: 0x4000~7a00 reading 0x4000~8000
2017-03-08 17:39:21.371706 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5332ef0 blob([0x448a0000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x2c00) SharedBlob(0x7f49e5332f60 sbid 0x0)) need 0x0x1d000:d000~2c00
2017-03-08 17:39:21.371709 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read region 0x1d000: 0xd000~2c00 reading 0xd000~3000
2017-03-08 17:39:21.371711 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5332fd0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0x4600) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2)))) need 0x0xba00:ba00~4600
2017-03-08 17:39:21.371715 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read region 0xba00: 0xba00~4600 reading 0xb000~5000
2017-03-08 17:39:21.371717 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2)))) need 0x0x10000:0~4600
2017-03-08 17:39:21.371721 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read region 0x10000: 0x0~4600 reading 0x0~5000
2017-03-08 17:39:21.371783 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read waiting for aio
2017-03-08 17:39:21.372390 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5332e10 blob([0x44890000~10000] mutable+csum+has_unused crc32c/0x1000 unused=0xf) use_tracker(0x10000 0x7a00) SharedBlob(0x7f49e5332e80 sbid 0x0)) need 0x0x4000:4000~7a00
2017-03-08 17:39:21.372416 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5332ef0 blob([0x448a0000~10000] mutable+csum crc32c/0x1000) use_tracker(0x10000 0x2c00) SharedBlob(0x7f49e5332f60 sbid 0x0)) need 0x0x1d000:d000~2c00
2017-03-08 17:39:21.372427 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5332fd0 blob([0x44b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x1f) use_tracker(0x10000 0x4600) SharedBlob(0x7f49de27c9b0 loaded sbid 0x2b4d ref_map(ref_map(0x44b60000~10000=2)))) need 0x0xba00:ba00~4600
2017-03-08 17:39:21.372441 7f49d73f2700 20 bluestore(bluestore.test_temp_dir) _do_read blob Blob(0x7f49e5333040 blob([0x40b60000~10000] csum+has_unused+shared crc32c/0x1000 unused=0x3e0) use_tracker(0x10000 0x6e00) SharedBlob(0x7f49de27da50 loaded sbid 0x2b4e ref_map(ref_map(0x40b60000~10000=2)))) need 0x0x10000:0~4600
2017-03-08 17:39:21.372454 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read assemble 0x0: zeros for 0x0~4000
2017-03-08 17:39:21.372464 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read assemble 0x4000: data from 0x4000~7a00
2017-03-08 17:39:21.372471 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read assemble 0xba00: data from 0xba00~4600
2017-03-08 17:39:21.372473 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read assemble 0x10000: data from 0x10000~4600
2017-03-08 17:39:21.372474 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read assemble 0x14600: zeros for 0x14600~6200
2017-03-08 17:39:21.372480 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read assemble 0x1a800: data from 0x1a800~2800
2017-03-08 17:39:21.372482 7f49d73f2700 30 bluestore(bluestore.test_temp_dir) _do_read assemble 0x1d000: data from 0x1d000~2c00
2017-03-08 17:39:21.372495 7f49d73f2700 30 bluestore(bluestore.test_temp_dir).collection(555.0_head) trim_cache total meta bytes 617138, total onodes 391, bytes_per_onode 1578.36
2017-03-08 17:39:21.372513 7f49d73f2700 10 trim shard target 3906 k ratio 0.9 (3515 k + 390 k), current 4479 k (602 k + 3877 k), need_to_free 573 k (0 + 573 k) -> max 391 onodes + 3382862 buffer
2017-03-08 17:39:21.372526 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim onodes 391 / 391 buffers 3970048 / 3382862
2017-03-08 17:39:21.372529 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e63b65c0 space 0x7f49de27d7c8 0x0~8c00 clean)
2017-03-08 17:39:21.372533 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e63b6c00 space 0x7f49de27db48 0xc000~3000 clean)
2017-03-08 17:39:21.372538 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e63b7920 space 0x7f49de4ffdb8 0x8c00~4e00 clean)
2017-03-08 17:39:21.372541 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e63b7e20 space 0x7f49de4fff08 0x9000~7000 clean)
2017-03-08 17:39:21.372544 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e63b7f60 space 0x7f49de4fffe8 0x0~4000 clean)
2017-03-08 17:39:21.372547 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e69e9560 space 0x7f49de6cc288 0x4600~8a00 clean)
2017-03-08 17:39:21.372550 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e69e9880 space 0x7f49de6cc598 0xe400~1c00 clean)
2017-03-08 17:39:21.372552 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e69e99c0 space 0x7f49de6cc678 0x0~10000 clean)
2017-03-08 17:39:21.372555 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e69e9920 space 0x7f49de6cc758 0x0~1200 clean)
2017-03-08 17:39:21.372557 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e7be9480 space 0x7f49ddddd098 0x0~3000 clean)
2017-03-08 17:39:21.372560 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e7be92a0 space 0x7f49ddddd3a8 0x0~10000 clean)
2017-03-08 17:39:21.372563 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e7be9200 space 0x7f49de6ccd78 0x0~10000 clean)
2017-03-08 17:39:21.372571 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e7be97a0 space 0x7f49ddddd4f8 0x0~b000 clean)
2017-03-08 17:39:21.372580 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e7be9660 space 0x7f49de6ccde8 0xa000~6000 clean)
2017-03-08 17:39:21.372587 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e7bea600 space 0x7f49dddde8a8 0xf000~1000 clean)
2017-03-08 17:39:21.372589 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e7bea920 space 0x7f49ddddea68 0x0~3000 clean)
2017-03-08 17:39:21.372592 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49e7bea740 space 0x7f49dddded08 0x0~10000 clean)
2017-03-08 17:39:21.372595 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49ec2af740 space 0x7f49e04c9338 0x6a00~e00 clean)
2017-03-08 17:39:21.372598 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49ec2afe20 space 0x7f49e04c9c68 0x0~6000 clean)
2017-03-08 17:39:21.372600 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49eca1f520 space 0x7f49e04ca288 0xe000~2000 clean)
2017-03-08 17:39:21.372604 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim buffer_warm_in -> out buffer(0x7f49eca1f5c0 space 0x7f49e04ca448 0x0~10000 clean)
2017-03-08 17:39:21.372606 7f49d73f2700 20 bluestore.2QCache(0x7f49fa842720) _trim evicted 608 kB from warm_in list, done evicting warm_in buffers
2017-03-08 17:39:21.372614 7f49d73f2700 10 bluestore(bluestore.test_temp_dir) read 555.0_head #555:99000000:::OBJ_1128:head#0 0x0~1fc00 = 130048
(1-1/2)