Project

General

Profile

Bug #18153 ยป ceph-osd.0.log

crash log - zhiyuan wang, 12/21/2016 07:22 AM

 
-918> 2016-12-21 15:14:35.523061 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3059a1480 1 blobs
-917> 2016-12-21 15:14:35.523062 7fb2aee5b700 10 bitmapalloc:reserve instance 140406818704320 num_used 138562 total 140320
-916> 2016-12-21 15:14:35.523065 7fb2aee5b700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-915> 2016-12-21 15:14:35.523077 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb318ad1c20 blob([0x21d410000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb307f4ad00)) csum_type none csum_order 12 csum_length 0x10000
-914> 2016-12-21 15:14:35.523083 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-913> 2016-12-21 15:14:35.523084 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x310000
-912> 2016-12-21 15:14:35.523086 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x300000~10000 = 0
-911> 2016-12-21 15:14:35.523091 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-910> 2016-12-21 15:14:35.523098 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-909> 2016-12-21 15:14:35.523101 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3059a1480 onodes 0x7fb304bffc00 shared_blobs
-908> 2016-12-21 15:14:35.523109 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 395 bytes from 49 extents
-907> 2016-12-21 15:14:35.523112 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 779 (378 bytes onode + 2 bytes spanning blobs + 399 bytes inline extents)
-906> 2016-12-21 15:14:35.523120 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059a1480 prepare
-905> 2016-12-21 15:14:35.523122 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3059a1480
-904> 2016-12-21 15:14:35.523128 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-903> 2016-12-21 15:14:35.523135 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb3043f0800 seq 631
-902> 2016-12-21 15:14:35.523156 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-901> 2016-12-21 15:14:35.523162 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-900> 2016-12-21 15:14:35.523166 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-899> 2016-12-21 15:14:35.523169 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-898> 2016-12-21 15:14:35.523172 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x280000~10000
-897> 2016-12-21 15:14:35.523175 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x280000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-896> 2016-12-21 15:14:35.523179 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-895> 2016-12-21 15:14:35.523180 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x280000~10000 target_blob_size 0x80000 compress 0
-894> 2016-12-21 15:14:35.523182 7fb2afe5d700 20 bluestore.BufferSpace(0x7fb31680e458 in 0x7fb302807dc0) _discard 0x0~10000
-893> 2016-12-21 15:14:35.523187 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x280000~10000: 0x0~10000 Blob(0x7fb313299690 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb31680e400))
-892> 2016-12-21 15:14:35.523191 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3043f0800 1 blobs
-891> 2016-12-21 15:14:35.523192 7fb2afe5d700 10 bitmapalloc:reserve instance 140406818704320 num_used 138563 total 140320
-890> 2016-12-21 15:14:35.523195 7fb2afe5d700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-889> 2016-12-21 15:14:35.523206 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb313299690 blob([0x21d420000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb31680e400)) csum_type none csum_order 12 csum_length 0x10000
-888> 2016-12-21 15:14:35.523212 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-887> 2016-12-21 15:14:35.523214 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x280000~10000 = 0
-886> 2016-12-21 15:14:35.523219 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-885> 2016-12-21 15:14:35.523226 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-884> 2016-12-21 15:14:35.523228 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3043f0800 onodes 0x7fb30445af00 shared_blobs
-883> 2016-12-21 15:14:35.523236 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 373 bytes from 46 extents
-882> 2016-12-21 15:14:35.523239 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 757 (378 bytes onode + 2 bytes spanning blobs + 377 bytes inline extents)
-881> 2016-12-21 15:14:35.523242 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-880> 2016-12-21 15:14:35.523246 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3043f0800 prepare
-879> 2016-12-21 15:14:35.523248 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3043f0800
-878> 2016-12-21 15:14:35.523248 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb305972f00 seq 891
-877> 2016-12-21 15:14:35.523269 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-876> 2016-12-21 15:14:35.523275 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-875> 2016-12-21 15:14:35.523279 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x310000~10000
-874> 2016-12-21 15:14:35.523282 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x310000~10000 - have 0x310000 (3211264) bytes fadvise_flags 0x0
-873> 2016-12-21 15:14:35.523285 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-872> 2016-12-21 15:14:35.523287 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x310000~10000 target_blob_size 0x80000 compress 0
-871> 2016-12-21 15:14:35.523289 7fb2aee5b700 20 bluestore.BufferSpace(0x7fb307f4b058 in 0x7fb302807340) _discard 0x0~10000
-870> 2016-12-21 15:14:35.523294 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x310000~10000: 0x0~10000 Blob(0x7fb318ad17a0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb307f4b000))
-869> 2016-12-21 15:14:35.523298 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305972f00 1 blobs
-868> 2016-12-21 15:14:35.523300 7fb2aee5b700 10 bitmapalloc:reserve instance 140406818704320 num_used 138564 total 140320
-867> 2016-12-21 15:14:35.523303 7fb2aee5b700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-866> 2016-12-21 15:14:35.523314 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb318ad17a0 blob([0x21d430000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb307f4b000)) csum_type none csum_order 12 csum_length 0x10000
-865> 2016-12-21 15:14:35.523320 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-864> 2016-12-21 15:14:35.523322 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x320000
-863> 2016-12-21 15:14:35.523323 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x310000~10000 = 0
-862> 2016-12-21 15:14:35.523328 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-861> 2016-12-21 15:14:35.523335 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-860> 2016-12-21 15:14:35.523338 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305972f00 onodes 0x7fb304bffc00 shared_blobs
-859> 2016-12-21 15:14:35.523346 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 403 bytes from 50 extents
-858> 2016-12-21 15:14:35.523348 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 787 (378 bytes onode + 2 bytes spanning blobs + 407 bytes inline extents)
-857> 2016-12-21 15:14:35.523356 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305972f00 prepare
-856> 2016-12-21 15:14:35.523357 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305972f00
-855> 2016-12-21 15:14:35.523365 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-854> 2016-12-21 15:14:35.523370 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb3053d6a80 seq 632
-853> 2016-12-21 15:14:35.523392 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-852> 2016-12-21 15:14:35.523397 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-851> 2016-12-21 15:14:35.523401 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-850> 2016-12-21 15:14:35.523404 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-849> 2016-12-21 15:14:35.523408 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x290000~10000
-848> 2016-12-21 15:14:35.523411 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x290000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-847> 2016-12-21 15:14:35.523414 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-846> 2016-12-21 15:14:35.523415 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x290000~10000 target_blob_size 0x80000 compress 0
-845> 2016-12-21 15:14:35.523418 7fb2afe5d700 20 bluestore.BufferSpace(0x7fb31680f298 in 0x7fb302807dc0) _discard 0x0~10000
-844> 2016-12-21 15:14:35.523423 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x290000~10000: 0x0~10000 Blob(0x7fb313299570 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb31680f240))
-843> 2016-12-21 15:14:35.523427 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3053d6a80 1 blobs
-842> 2016-12-21 15:14:35.523428 7fb2afe5d700 10 bitmapalloc:reserve instance 140406818704320 num_used 138565 total 140320
-841> 2016-12-21 15:14:35.523430 7fb2afe5d700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-840> 2016-12-21 15:14:35.523442 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb313299570 blob([0x21d440000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb31680f240)) csum_type none csum_order 12 csum_length 0x10000
-839> 2016-12-21 15:14:35.523448 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-838> 2016-12-21 15:14:35.523449 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x290000~10000 = 0
-837> 2016-12-21 15:14:35.523454 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-836> 2016-12-21 15:14:35.523461 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-835> 2016-12-21 15:14:35.523464 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3053d6a80 onodes 0x7fb30445af00 shared_blobs
-834> 2016-12-21 15:14:35.523472 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 381 bytes from 47 extents
-833> 2016-12-21 15:14:35.523474 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 765 (378 bytes onode + 2 bytes spanning blobs + 385 bytes inline extents)
-832> 2016-12-21 15:14:35.523482 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3053d6a80 prepare
-831> 2016-12-21 15:14:35.523483 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3053d6a80
-830> 2016-12-21 15:14:35.523522 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-829> 2016-12-21 15:14:35.523529 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb3042bcf80 seq 892
-828> 2016-12-21 15:14:35.523550 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-827> 2016-12-21 15:14:35.523556 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-826> 2016-12-21 15:14:35.523577 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x320000~10000
-825> 2016-12-21 15:14:35.523581 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x320000~10000 - have 0x320000 (3276800) bytes fadvise_flags 0x0
-824> 2016-12-21 15:14:35.523584 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-823> 2016-12-21 15:14:35.523586 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x320000~10000 target_blob_size 0x80000 compress 0
-822> 2016-12-21 15:14:35.523588 7fb2aee5b700 20 bluestore.BufferSpace(0x7fb307f4b358 in 0x7fb302807340) _discard 0x0~10000
-821> 2016-12-21 15:14:35.523593 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x320000~10000: 0x0~10000 Blob(0x7fb318ad1320 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb307f4b300))
-820> 2016-12-21 15:14:35.523597 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3042bcf80 1 blobs
-819> 2016-12-21 15:14:35.523599 7fb2aee5b700 10 bitmapalloc:reserve instance 140406818704320 num_used 138566 total 140320
-818> 2016-12-21 15:14:35.523601 7fb2aee5b700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-817> 2016-12-21 15:14:35.523603 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-816> 2016-12-21 15:14:35.523609 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb3059c3400 seq 633
-815> 2016-12-21 15:14:35.523616 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb318ad1320 blob([0x21d450000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb307f4b300)) csum_type none csum_order 12 csum_length 0x10000
-814> 2016-12-21 15:14:35.523622 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-813> 2016-12-21 15:14:35.523624 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x330000
-812> 2016-12-21 15:14:35.523625 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x320000~10000 = 0
-811> 2016-12-21 15:14:35.523631 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-810> 2016-12-21 15:14:35.523629 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-809> 2016-12-21 15:14:35.523635 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-808> 2016-12-21 15:14:35.523639 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-807> 2016-12-21 15:14:35.523639 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-806> 2016-12-21 15:14:35.523642 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3042bcf80 onodes 0x7fb304bffc00 shared_blobs
-805> 2016-12-21 15:14:35.523642 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-804> 2016-12-21 15:14:35.523646 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2a0000~10000
-803> 2016-12-21 15:14:35.523650 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 411 bytes from 51 extents
-802> 2016-12-21 15:14:35.523649 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2a0000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-801> 2016-12-21 15:14:35.523653 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 795 (378 bytes onode + 2 bytes spanning blobs + 415 bytes inline extents)
-800> 2016-12-21 15:14:35.523655 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-799> 2016-12-21 15:14:35.523657 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x2a0000~10000 target_blob_size 0x80000 compress 0
-798> 2016-12-21 15:14:35.523660 7fb2afe5d700 20 bluestore.BufferSpace(0x7fb31680c7d8 in 0x7fb302807dc0) _discard 0x0~10000
-797> 2016-12-21 15:14:35.523661 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3042bcf80 prepare
-796> 2016-12-21 15:14:35.523663 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3042bcf80
-795> 2016-12-21 15:14:35.523665 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x2a0000~10000: 0x0~10000 Blob(0x7fb313299450 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb31680c780))
-794> 2016-12-21 15:14:35.523669 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3059c3400 1 blobs
-793> 2016-12-21 15:14:35.523670 7fb2afe5d700 10 bitmapalloc:reserve instance 140406818704320 num_used 138567 total 140320
-792> 2016-12-21 15:14:35.523673 7fb2afe5d700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-791> 2016-12-21 15:14:35.523687 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb313299450 blob([0x21d460000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb31680c780)) csum_type none csum_order 12 csum_length 0x10000
-790> 2016-12-21 15:14:35.523693 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-789> 2016-12-21 15:14:35.523695 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2a0000~10000 = 0
-788> 2016-12-21 15:14:35.523700 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-787> 2016-12-21 15:14:35.523707 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-786> 2016-12-21 15:14:35.523710 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3059c3400 onodes 0x7fb30445af00 shared_blobs
-785> 2016-12-21 15:14:35.523719 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 389 bytes from 48 extents
-784> 2016-12-21 15:14:35.523722 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 773 (378 bytes onode + 2 bytes spanning blobs + 393 bytes inline extents)
-783> 2016-12-21 15:14:35.523729 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059c3400 prepare
-782> 2016-12-21 15:14:35.523730 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3059c3400
-781> 2016-12-21 15:14:35.523844 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-780> 2016-12-21 15:14:35.523844 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-779> 2016-12-21 15:14:35.523850 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb3043dd980 seq 634
-778> 2016-12-21 15:14:35.523851 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb3084bb700 seq 893
-777> 2016-12-21 15:14:35.523874 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-776> 2016-12-21 15:14:35.523880 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-775> 2016-12-21 15:14:35.523884 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-774> 2016-12-21 15:14:35.523887 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-773> 2016-12-21 15:14:35.523890 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2b0000~10000
-772> 2016-12-21 15:14:35.523893 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2b0000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-771> 2016-12-21 15:14:35.523897 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-770> 2016-12-21 15:14:35.523898 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x2b0000~10000 target_blob_size 0x80000 compress 0
-769> 2016-12-21 15:14:35.523901 7fb2afe5d700 20 bluestore.BufferSpace(0x7fb3180da658 in 0x7fb302807dc0) _discard 0x0~10000
-768> 2016-12-21 15:14:35.523873 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-767> 2016-12-21 15:14:35.523905 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-766> 2016-12-21 15:14:35.523906 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x2b0000~10000: 0x0~10000 Blob(0x7fb313299330 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3180da600))
-765> 2016-12-21 15:14:35.523910 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3043dd980 1 blobs
-764> 2016-12-21 15:14:35.523909 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x330000~10000
-763> 2016-12-21 15:14:35.523911 7fb2afe5d700 10 bitmapalloc:reserve instance 140406818704320 num_used 138568 total 140320
-762> 2016-12-21 15:14:35.523912 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x330000~10000 - have 0x330000 (3342336) bytes fadvise_flags 0x0
-761> 2016-12-21 15:14:35.523914 7fb2afe5d700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-760> 2016-12-21 15:14:35.523916 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-759> 2016-12-21 15:14:35.523918 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x330000~10000 target_blob_size 0x80000 compress 0
-758> 2016-12-21 15:14:35.523921 7fb2aee5b700 20 bluestore.BufferSpace(0x7fb307f4b658 in 0x7fb302807340) _discard 0x0~10000
-757> 2016-12-21 15:14:35.523926 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x330000~10000: 0x0~10000 Blob(0x7fb318ad0ea0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb307f4b600))
-756> 2016-12-21 15:14:35.523930 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3084bb700 1 blobs
-755> 2016-12-21 15:14:35.523928 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb313299330 blob([0x21d470000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3180da600)) csum_type none csum_order 12 csum_length 0x10000
-754> 2016-12-21 15:14:35.523932 7fb2aee5b700 10 bitmapalloc:reserve instance 140406818704320 num_used 138569 total 140320
-753> 2016-12-21 15:14:35.523934 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-752> 2016-12-21 15:14:35.523934 7fb2aee5b700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-751> 2016-12-21 15:14:35.523937 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2b0000~10000 = 0
-750> 2016-12-21 15:14:35.523942 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-749> 2016-12-21 15:14:35.523948 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-748> 2016-12-21 15:14:35.523951 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3043dd980 onodes 0x7fb30445af00 shared_blobs
-747> 2016-12-21 15:14:35.523952 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb318ad0ea0 blob([0x21d480000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb307f4b600)) csum_type none csum_order 12 csum_length 0x10000
-746> 2016-12-21 15:14:35.523959 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-745> 2016-12-21 15:14:35.523959 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 397 bytes from 49 extents
-744> 2016-12-21 15:14:35.523961 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x340000
-743> 2016-12-21 15:14:35.523962 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x330000~10000 = 0
-742> 2016-12-21 15:14:35.523963 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 781 (378 bytes onode + 2 bytes spanning blobs + 401 bytes inline extents)
-741> 2016-12-21 15:14:35.523967 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-740> 2016-12-21 15:14:35.523970 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3043dd980 prepare
-739> 2016-12-21 15:14:35.523972 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3043dd980
-738> 2016-12-21 15:14:35.523975 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-737> 2016-12-21 15:14:35.523986 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3084bb700 onodes 0x7fb304bffc00 shared_blobs
-736> 2016-12-21 15:14:35.523994 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 419 bytes from 52 extents
-735> 2016-12-21 15:14:35.523997 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 803 (378 bytes onode + 2 bytes spanning blobs + 423 bytes inline extents)
-734> 2016-12-21 15:14:35.524009 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3084bb700 prepare
-733> 2016-12-21 15:14:35.524010 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3084bb700
-732> 2016-12-21 15:14:35.524088 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-731> 2016-12-21 15:14:35.524094 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb305749180 seq 635
-730> 2016-12-21 15:14:35.524115 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-729> 2016-12-21 15:14:35.524120 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-728> 2016-12-21 15:14:35.524131 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-727> 2016-12-21 15:14:35.524134 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-726> 2016-12-21 15:14:35.524138 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2c0000~10000
-725> 2016-12-21 15:14:35.524141 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2c0000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-724> 2016-12-21 15:14:35.524144 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-723> 2016-12-21 15:14:35.524146 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x2c0000~10000 target_blob_size 0x80000 compress 0
-722> 2016-12-21 15:14:35.524148 7fb2afe5d700 20 bluestore.BufferSpace(0x7fb3180da058 in 0x7fb302807dc0) _discard 0x0~10000
-721> 2016-12-21 15:14:35.524153 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x2c0000~10000: 0x0~10000 Blob(0x7fb313299210 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3180da000))
-720> 2016-12-21 15:14:35.524157 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305749180 1 blobs
-719> 2016-12-21 15:14:35.524158 7fb2afe5d700 10 bitmapalloc:reserve instance 140406818704320 num_used 138570 total 140320
-718> 2016-12-21 15:14:35.524161 7fb2afe5d700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-717> 2016-12-21 15:14:35.524172 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-716> 2016-12-21 15:14:35.524173 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb313299210 blob([0x21d490000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3180da000)) csum_type none csum_order 12 csum_length 0x10000
-715> 2016-12-21 15:14:35.524178 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb3084b9400 seq 894
-714> 2016-12-21 15:14:35.524182 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-713> 2016-12-21 15:14:35.524184 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2c0000~10000 = 0
-712> 2016-12-21 15:14:35.524189 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-711> 2016-12-21 15:14:35.524215 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-710> 2016-12-21 15:14:35.524218 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305749180 onodes 0x7fb30445af00 shared_blobs
-709> 2016-12-21 15:14:35.524228 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 405 bytes from 50 extents
-708> 2016-12-21 15:14:35.524231 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 789 (378 bytes onode + 2 bytes spanning blobs + 409 bytes inline extents)
-707> 2016-12-21 15:14:35.524232 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304462000 aio_wait
-706> 2016-12-21 15:14:35.524236 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb304462000
-705> 2016-12-21 15:14:35.524237 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304462000 io_done
-704> 2016-12-21 15:14:35.524238 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305749180 prepare
-703> 2016-12-21 15:14:35.524240 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305749180
-702> 2016-12-21 15:14:35.524239 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f49558 in 0x7fb302807340) finish_write buffer(0x7fb3123cc250 space 0x7fb307f49558 0x0~10000 writing nocache)
-701> 2016-12-21 15:14:35.524243 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb30417e280 aio_wait
-700> 2016-12-21 15:14:35.524245 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb30417e280
-699> 2016-12-21 15:14:35.524246 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb30417e280 io_done
-698> 2016-12-21 15:14:35.524247 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3180af018 in 0x7fb302807dc0) finish_write buffer(0x7fb317eedd40 space 0x7fb3180af018 0x0~10000 writing nocache)
-697> 2016-12-21 15:14:35.524250 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305982780 aio_wait
-696> 2016-12-21 15:14:35.524251 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305982780
-695> 2016-12-21 15:14:35.524252 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305982780 io_done
-694> 2016-12-21 15:14:35.524254 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f49858 in 0x7fb302807340) finish_write buffer(0x7fb3123cbdd0 space 0x7fb307f49858 0x0~10000 writing nocache)
-693> 2016-12-21 15:14:35.524256 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb30506a800 aio_wait
-692> 2016-12-21 15:14:35.524257 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb30506a800
-691> 2016-12-21 15:14:35.524258 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb30506a800 io_done
-690> 2016-12-21 15:14:35.524259 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3180aea18 in 0x7fb302807dc0) finish_write buffer(0x7fb313299f00 space 0x7fb3180aea18 0x0~10000 writing nocache)
-689> 2016-12-21 15:14:35.524262 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305683480 aio_wait
-688> 2016-12-21 15:14:35.524263 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305683480
-687> 2016-12-21 15:14:35.524264 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305683480 io_done
-686> 2016-12-21 15:14:35.524265 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f49b58 in 0x7fb302807340) finish_write buffer(0x7fb3123cb950 space 0x7fb307f49b58 0x0~10000 writing nocache)
-685> 2016-12-21 15:14:35.524267 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb30599fb80 aio_wait
-684> 2016-12-21 15:14:35.524268 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb30599fb80
-683> 2016-12-21 15:14:35.524269 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb30599fb80 io_done
-682> 2016-12-21 15:14:35.524271 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3180ae418 in 0x7fb302807dc0) finish_write buffer(0x7fb313299de0 space 0x7fb3180ae418 0x0~10000 writing nocache)
-681> 2016-12-21 15:14:35.524273 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a94c80 aio_wait
-680> 2016-12-21 15:14:35.524274 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305a94c80
-679> 2016-12-21 15:14:35.524275 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a94c80 io_done
-678> 2016-12-21 15:14:35.524276 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f49e58 in 0x7fb302807340) finish_write buffer(0x7fb3123cb4d0 space 0x7fb307f49e58 0x0~10000 writing nocache)
-677> 2016-12-21 15:14:35.524278 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304430580 aio_wait
-676> 2016-12-21 15:14:35.524280 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb304430580
-675> 2016-12-21 15:14:35.524280 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304430580 io_done
-674> 2016-12-21 15:14:35.524282 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb31836fd18 in 0x7fb302807dc0) finish_write buffer(0x7fb313299cc0 space 0x7fb31836fd18 0x0~10000 writing nocache)
-673> 2016-12-21 15:14:35.524284 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305682300 aio_wait
-672> 2016-12-21 15:14:35.524285 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305682300
-671> 2016-12-21 15:14:35.524286 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305682300 io_done
-670> 2016-12-21 15:14:35.524288 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f4a158 in 0x7fb302807340) finish_write buffer(0x7fb3123cdc30 space 0x7fb307f4a158 0x0~10000 writing nocache)
-669> 2016-12-21 15:14:35.524290 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3048a6a00 aio_wait
-668> 2016-12-21 15:14:35.524291 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3048a6a00
-667> 2016-12-21 15:14:35.524292 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3048a6a00 io_done
-666> 2016-12-21 15:14:35.524293 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb31836f718 in 0x7fb302807dc0) finish_write buffer(0x7fb313299ba0 space 0x7fb31836f718 0x0~10000 writing nocache)
-665> 2016-12-21 15:14:35.524295 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305984300 aio_wait
-664> 2016-12-21 15:14:35.524296 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305984300
-663> 2016-12-21 15:14:35.524297 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305984300 io_done
-662> 2016-12-21 15:14:35.524299 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb31836db58 in 0x7fb302807dc0) finish_write buffer(0x7fb313299a80 space 0x7fb31836db58 0x0~10000 writing nocache)
-661> 2016-12-21 15:14:35.524301 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3067f1900 aio_wait
-660> 2016-12-21 15:14:35.524302 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3067f1900
-659> 2016-12-21 15:14:35.524303 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3067f1900 io_done
-658> 2016-12-21 15:14:35.524304 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f4a458 in 0x7fb302807340) finish_write buffer(0x7fb318ad2760 space 0x7fb307f4a458 0x0~10000 writing nocache)
-657> 2016-12-21 15:14:35.524307 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3048a8a80 aio_wait
-656> 2016-12-21 15:14:35.524308 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3048a8a80
-655> 2016-12-21 15:14:35.524309 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3048a8a80 io_done
-654> 2016-12-21 15:14:35.524310 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb31836d558 in 0x7fb302807dc0) finish_write buffer(0x7fb313299960 space 0x7fb31836d558 0x0~10000 writing nocache)
-653> 2016-12-21 15:14:35.524312 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a94280 aio_wait
-652> 2016-12-21 15:14:35.524313 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305a94280
-651> 2016-12-21 15:14:35.524314 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a94280 io_done
-650> 2016-12-21 15:14:35.524316 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f4a758 in 0x7fb302807340) finish_write buffer(0x7fb318ad22e0 space 0x7fb307f4a758 0x0~10000 writing nocache)
-649> 2016-12-21 15:14:35.524335 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3048a9200 aio_wait
-648> 2016-12-21 15:14:35.524337 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3048a9200
-647> 2016-12-21 15:14:35.524338 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3048a9200 io_done
-646> 2016-12-21 15:14:35.524339 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb31836cf58 in 0x7fb302807dc0) finish_write buffer(0x7fb313299840 space 0x7fb31836cf58 0x0~10000 writing nocache)
-645> 2016-12-21 15:14:35.524342 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305bb6780 aio_wait
-644> 2016-12-21 15:14:35.524343 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305bb6780
-643> 2016-12-21 15:14:35.524344 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305bb6780 io_done
-642> 2016-12-21 15:14:35.524345 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f4aa58 in 0x7fb302807340) finish_write buffer(0x7fb318ad1e60 space 0x7fb307f4aa58 0x0~10000 writing nocache)
-641> 2016-12-21 15:14:35.524350 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305972f00 aio_wait
-640> 2016-12-21 15:14:35.524351 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305972f00
-639> 2016-12-21 15:14:35.524352 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305972f00 blocked by 0x7fb3059a1480 aio_wait
-638> 2016-12-21 15:14:35.524354 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3048a8800 aio_wait
-637> 2016-12-21 15:14:35.524355 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3048a8800
-636> 2016-12-21 15:14:35.524356 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3048a8800 io_done
-635> 2016-12-21 15:14:35.524357 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb31680dcd8 in 0x7fb302807dc0) finish_write buffer(0x7fb313299720 space 0x7fb31680dcd8 0x0~10000 writing nocache)
-634> 2016-12-21 15:14:35.524359 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059a1480 aio_wait
-633> 2016-12-21 15:14:35.524360 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3059a1480
-632> 2016-12-21 15:14:35.524361 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059a1480 io_done
-631> 2016-12-21 15:14:35.524363 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f4ad58 in 0x7fb302807340) finish_write buffer(0x7fb318ad19e0 space 0x7fb307f4ad58 0x0~10000 writing nocache)
-630> 2016-12-21 15:14:35.524365 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305972f00 io_done
-629> 2016-12-21 15:14:35.524366 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f4b058 in 0x7fb302807340) finish_write buffer(0x7fb318ad1560 space 0x7fb307f4b058 0x0~10000 writing nocache)
-628> 2016-12-21 15:14:35.524368 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3043f0800 aio_wait
-627> 2016-12-21 15:14:35.524369 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3043f0800
-626> 2016-12-21 15:14:35.524370 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3043f0800 io_done
-625> 2016-12-21 15:14:35.524367 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-624> 2016-12-21 15:14:35.524372 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb31680e458 in 0x7fb302807dc0) finish_write buffer(0x7fb313299600 space 0x7fb31680e458 0x0~10000 writing nocache)
-623> 2016-12-21 15:14:35.524375 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3053d6a80 aio_wait
-622> 2016-12-21 15:14:35.524377 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3053d6a80
-621> 2016-12-21 15:14:35.524378 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3053d6a80 io_done
-620> 2016-12-21 15:14:35.524377 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb3054c1b80 seq 636
-619> 2016-12-21 15:14:35.524399 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb31680f298 in 0x7fb302807dc0) finish_write buffer(0x7fb3132994e0 space 0x7fb31680f298 0x0~10000 writing nocache)
-618> 2016-12-21 15:14:35.524406 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3042bcf80 aio_wait
-617> 2016-12-21 15:14:35.524407 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3042bcf80
-616> 2016-12-21 15:14:35.524408 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3042bcf80 io_done
-615> 2016-12-21 15:14:35.524410 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f4b358 in 0x7fb302807340) finish_write buffer(0x7fb318ad10e0 space 0x7fb307f4b358 0x0~10000 writing nocache)
-614> 2016-12-21 15:14:35.524412 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059c3400 aio_wait
-613> 2016-12-21 15:14:35.524413 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3059c3400
-612> 2016-12-21 15:14:35.524414 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059c3400 io_done
-611> 2016-12-21 15:14:35.524415 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb31680c7d8 in 0x7fb302807dc0) finish_write buffer(0x7fb3132993c0 space 0x7fb31680c7d8 0x0~10000 writing nocache)
-610> 2016-12-21 15:14:35.524417 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3043dd980 aio_wait
-609> 2016-12-21 15:14:35.524418 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3043dd980
-608> 2016-12-21 15:14:35.524419 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3043dd980 io_done
-607> 2016-12-21 15:14:35.524421 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3180da658 in 0x7fb302807dc0) finish_write buffer(0x7fb3132992a0 space 0x7fb3180da658 0x0~10000 writing nocache)
-606> 2016-12-21 15:14:35.524423 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3084bb700 aio_wait
-605> 2016-12-21 15:14:35.524425 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3084bb700
-604> 2016-12-21 15:14:35.524426 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3084bb700 io_done
-603> 2016-12-21 15:14:35.524427 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f4b658 in 0x7fb302807340) finish_write buffer(0x7fb318ad0c60 space 0x7fb307f4b658 0x0~10000 writing nocache)
-602> 2016-12-21 15:14:35.524429 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305749180 aio_wait
-601> 2016-12-21 15:14:35.524430 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305749180
-600> 2016-12-21 15:14:35.524431 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305749180 io_done
-599> 2016-12-21 15:14:35.524432 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3180da058 in 0x7fb302807dc0) finish_write buffer(0x7fb313299180 space 0x7fb3180da058 0x0~10000 writing nocache)
-598> 2016-12-21 15:14:35.524446 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-597> 2016-12-21 15:14:35.524453 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-596> 2016-12-21 15:14:35.524457 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x340000~10000
-595> 2016-12-21 15:14:35.524460 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x340000~10000 - have 0x340000 (3407872) bytes fadvise_flags 0x0
-594> 2016-12-21 15:14:35.524463 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-593> 2016-12-21 15:14:35.524465 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x340000~10000 target_blob_size 0x80000 compress 0
-592> 2016-12-21 15:14:35.524468 7fb2aee5b700 20 bluestore.BufferSpace(0x7fb307f4b958 in 0x7fb302807340) _discard 0x0~10000
-591> 2016-12-21 15:14:35.524472 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x340000~10000: 0x0~10000 Blob(0x7fb318ad3de0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb307f4b900))
-590> 2016-12-21 15:14:35.524477 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3084b9400 1 blobs
-589> 2016-12-21 15:14:35.524478 7fb2aee5b700 10 bitmapalloc:reserve instance 140406818704320 num_used 138571 total 140320
-588> 2016-12-21 15:14:35.524481 7fb2aee5b700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-587> 2016-12-21 15:14:35.524493 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb318ad3de0 blob([0x21d4a0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb307f4b900)) csum_type none csum_order 12 csum_length 0x10000
-586> 2016-12-21 15:14:35.524499 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-585> 2016-12-21 15:14:35.524501 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x350000
-584> 2016-12-21 15:14:35.524502 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x340000~10000 = 0
-583> 2016-12-21 15:14:35.524508 7fb2aee5b700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-582> 2016-12-21 15:14:35.524515 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-581> 2016-12-21 15:14:35.524518 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3084b9400 onodes 0x7fb304bffc00 shared_blobs
-580> 2016-12-21 15:14:35.524527 7fb2aee5b700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 427 bytes from 53 extents
-579> 2016-12-21 15:14:35.524530 7fb2aee5b700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 811 (378 bytes onode + 2 bytes spanning blobs + 431 bytes inline extents)
-578> 2016-12-21 15:14:35.524538 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3084b9400 prepare
-577> 2016-12-21 15:14:35.524540 7fb2aee5b700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3084b9400
-576> 2016-12-21 15:14:35.524706 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-575> 2016-12-21 15:14:35.524713 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb305a41700 seq 895
-574> 2016-12-21 15:14:35.524738 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-573> 2016-12-21 15:14:35.524744 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-572> 2016-12-21 15:14:35.524748 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x350000~10000
-571> 2016-12-21 15:14:35.524751 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x350000~10000 - have 0x350000 (3473408) bytes fadvise_flags 0x0
-570> 2016-12-21 15:14:35.524754 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-569> 2016-12-21 15:14:35.524756 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x350000~10000 target_blob_size 0x80000 compress 0
-568> 2016-12-21 15:14:35.524759 7fb2b1660700 20 bluestore.BufferSpace(0x7fb319a6f418 in 0x7fb302807340) _discard 0x0~10000
-567> 2016-12-21 15:14:35.524764 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x350000~10000: 0x0~10000 Blob(0x7fb3123ca900 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6f3c0))
-566> 2016-12-21 15:14:35.524768 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305a41700 1 blobs
-565> 2016-12-21 15:14:35.524770 7fb2b1660700 10 bitmapalloc:reserve instance 140406818704320 num_used 138572 total 140320
-564> 2016-12-21 15:14:35.524772 7fb2b1660700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-563> 2016-12-21 15:14:35.524784 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3123ca900 blob([0x21d4b0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6f3c0)) csum_type none csum_order 12 csum_length 0x10000
-562> 2016-12-21 15:14:35.524790 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-561> 2016-12-21 15:14:35.524792 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x360000
-560> 2016-12-21 15:14:35.524794 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x350000~10000 = 0
-559> 2016-12-21 15:14:35.524799 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-558> 2016-12-21 15:14:35.524806 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-557> 2016-12-21 15:14:35.524809 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305a41700 onodes 0x7fb304bffc00 shared_blobs
-556> 2016-12-21 15:14:35.524834 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 435 bytes from 54 extents
-555> 2016-12-21 15:14:35.524840 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 819 (378 bytes onode + 2 bytes spanning blobs + 439 bytes inline extents)
-554> 2016-12-21 15:14:35.524848 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a41700 prepare
-553> 2016-12-21 15:14:35.524850 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305a41700
-552> 2016-12-21 15:14:35.525002 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-551> 2016-12-21 15:14:35.525009 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb305a40800 seq 896
-550> 2016-12-21 15:14:35.525031 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-549> 2016-12-21 15:14:35.525036 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-548> 2016-12-21 15:14:35.525040 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x360000~10000
-547> 2016-12-21 15:14:35.525043 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x360000~10000 - have 0x360000 (3538944) bytes fadvise_flags 0x0
-546> 2016-12-21 15:14:35.525047 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-545> 2016-12-21 15:14:35.525048 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x360000~10000 target_blob_size 0x80000 compress 0
-544> 2016-12-21 15:14:35.525051 7fb2b1660700 20 bluestore.BufferSpace(0x7fb319a6f298 in 0x7fb302807340) _discard 0x0~10000
-543> 2016-12-21 15:14:35.525056 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x360000~10000: 0x0~10000 Blob(0x7fb3123ca7e0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6f240))
-542> 2016-12-21 15:14:35.525060 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305a40800 1 blobs
-541> 2016-12-21 15:14:35.525061 7fb2b1660700 10 bitmapalloc:reserve instance 140406818704320 num_used 138573 total 140320
-540> 2016-12-21 15:14:35.525064 7fb2b1660700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-539> 2016-12-21 15:14:35.525076 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3123ca7e0 blob([0x21d4c0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6f240)) csum_type none csum_order 12 csum_length 0x10000
-538> 2016-12-21 15:14:35.525082 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-537> 2016-12-21 15:14:35.525083 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x370000
-536> 2016-12-21 15:14:35.525085 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x360000~10000 = 0
-535> 2016-12-21 15:14:35.525090 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-534> 2016-12-21 15:14:35.525097 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-533> 2016-12-21 15:14:35.525100 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305a40800 onodes 0x7fb304bffc00 shared_blobs
-532> 2016-12-21 15:14:35.525109 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 443 bytes from 55 extents
-531> 2016-12-21 15:14:35.525111 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 827 (378 bytes onode + 2 bytes spanning blobs + 447 bytes inline extents)
-530> 2016-12-21 15:14:35.525118 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a40800 prepare
-529> 2016-12-21 15:14:35.525120 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305a40800
-528> 2016-12-21 15:14:35.525267 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-527> 2016-12-21 15:14:35.525273 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb305a41200 seq 897
-526> 2016-12-21 15:14:35.525294 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-525> 2016-12-21 15:14:35.525300 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-524> 2016-12-21 15:14:35.525304 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x370000~10000
-523> 2016-12-21 15:14:35.525307 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x370000~10000 - have 0x370000 (3604480) bytes fadvise_flags 0x0
-522> 2016-12-21 15:14:35.525310 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-521> 2016-12-21 15:14:35.525332 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x370000~10000 target_blob_size 0x80000 compress 0
-520> 2016-12-21 15:14:35.525335 7fb2b1660700 20 bluestore.BufferSpace(0x7fb319a6f118 in 0x7fb302807340) _discard 0x0~10000
-519> 2016-12-21 15:14:35.525340 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x370000~10000: 0x0~10000 Blob(0x7fb3123ca6c0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6f0c0))
-518> 2016-12-21 15:14:35.525344 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305a41200 1 blobs
-517> 2016-12-21 15:14:35.525345 7fb2b1660700 10 bitmapalloc:reserve instance 140406818704320 num_used 138574 total 140320
-516> 2016-12-21 15:14:35.525348 7fb2b1660700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-515> 2016-12-21 15:14:35.525360 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3123ca6c0 blob([0x21d4d0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6f0c0)) csum_type none csum_order 12 csum_length 0x10000
-514> 2016-12-21 15:14:35.525366 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-513> 2016-12-21 15:14:35.525368 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x380000
-512> 2016-12-21 15:14:35.525369 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x370000~10000 = 0
-511> 2016-12-21 15:14:35.525375 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-510> 2016-12-21 15:14:35.525382 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-509> 2016-12-21 15:14:35.525385 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305a41200 onodes 0x7fb304bffc00 shared_blobs
-508> 2016-12-21 15:14:35.525393 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 451 bytes from 56 extents
-507> 2016-12-21 15:14:35.525397 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 835 (378 bytes onode + 2 bytes spanning blobs + 455 bytes inline extents)
-506> 2016-12-21 15:14:35.525404 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a41200 prepare
-505> 2016-12-21 15:14:35.525406 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305a41200
-504> 2016-12-21 15:14:35.525658 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-503> 2016-12-21 15:14:35.525664 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb305a3e000 seq 898
-502> 2016-12-21 15:14:35.525686 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-501> 2016-12-21 15:14:35.525692 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-500> 2016-12-21 15:14:35.525696 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x380000~10000
-499> 2016-12-21 15:14:35.525699 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x380000~10000 - have 0x380000 (3670016) bytes fadvise_flags 0x0
-498> 2016-12-21 15:14:35.525703 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-497> 2016-12-21 15:14:35.525705 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x380000~10000 target_blob_size 0x80000 compress 0
-496> 2016-12-21 15:14:35.525707 7fb2b1660700 20 bluestore.BufferSpace(0x7fb319a6ef98 in 0x7fb302807340) _discard 0x0~10000
-495> 2016-12-21 15:14:35.525713 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x380000~10000: 0x0~10000 Blob(0x7fb3123ca2d0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6ef40))
-494> 2016-12-21 15:14:35.525717 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305a3e000 1 blobs
-493> 2016-12-21 15:14:35.525718 7fb2b1660700 10 bitmapalloc:reserve instance 140406818704320 num_used 138575 total 140320
-492> 2016-12-21 15:14:35.525721 7fb2b1660700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-491> 2016-12-21 15:14:35.525733 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3123ca2d0 blob([0x21d4e0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6ef40)) csum_type none csum_order 12 csum_length 0x10000
-490> 2016-12-21 15:14:35.525739 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-489> 2016-12-21 15:14:35.525741 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x390000
-488> 2016-12-21 15:14:35.525742 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x380000~10000 = 0
-487> 2016-12-21 15:14:35.525748 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-486> 2016-12-21 15:14:35.525756 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-485> 2016-12-21 15:14:35.525758 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305a3e000 onodes 0x7fb304bffc00 shared_blobs
-484> 2016-12-21 15:14:35.525768 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 459 bytes from 57 extents
-483> 2016-12-21 15:14:35.525771 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 843 (378 bytes onode + 2 bytes spanning blobs + 463 bytes inline extents)
-482> 2016-12-21 15:14:35.525778 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a3e000 prepare
-481> 2016-12-21 15:14:35.525780 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305a3e000
-480> 2016-12-21 15:14:35.525942 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-479> 2016-12-21 15:14:35.525949 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb305a40a80 seq 899
-478> 2016-12-21 15:14:35.525971 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-477> 2016-12-21 15:14:35.525976 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-476> 2016-12-21 15:14:35.525981 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x390000~10000
-475> 2016-12-21 15:14:35.525984 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x390000~10000 - have 0x390000 (3735552) bytes fadvise_flags 0x0
-474> 2016-12-21 15:14:35.525987 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-473> 2016-12-21 15:14:35.525989 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x390000~10000 target_blob_size 0x80000 compress 0
-472> 2016-12-21 15:14:35.525992 7fb2b1660700 20 bluestore.BufferSpace(0x7fb319a6ee18 in 0x7fb302807340) _discard 0x0~10000
-471> 2016-12-21 15:14:35.525997 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x390000~10000: 0x0~10000 Blob(0x7fb3123ca1b0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6edc0))
-470> 2016-12-21 15:14:35.526001 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305a40a80 1 blobs
-469> 2016-12-21 15:14:35.526002 7fb2b1660700 10 bitmapalloc:reserve instance 140406818704320 num_used 138576 total 140320
-468> 2016-12-21 15:14:35.526005 7fb2b1660700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-467> 2016-12-21 15:14:35.526017 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3123ca1b0 blob([0x21d4f0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6edc0)) csum_type none csum_order 12 csum_length 0x10000
-466> 2016-12-21 15:14:35.526023 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-465> 2016-12-21 15:14:35.526025 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x3a0000
-464> 2016-12-21 15:14:35.526026 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x390000~10000 = 0
-463> 2016-12-21 15:14:35.526032 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-462> 2016-12-21 15:14:35.526039 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-461> 2016-12-21 15:14:35.526042 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305a40a80 onodes 0x7fb304bffc00 shared_blobs
-460> 2016-12-21 15:14:35.526051 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 467 bytes from 58 extents
-459> 2016-12-21 15:14:35.526054 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 851 (378 bytes onode + 2 bytes spanning blobs + 471 bytes inline extents)
-458> 2016-12-21 15:14:35.526061 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a40a80 prepare
-457> 2016-12-21 15:14:35.526063 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305a40a80
-456> 2016-12-21 15:14:35.526221 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303d27840 osr(0.97 0x7fb303c688b0)
-455> 2016-12-21 15:14:35.526227 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303d27840 = 0x7fb305a41480 seq 900
-454> 2016-12-21 15:14:35.526248 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys
-453> 2016-12-21 15:14:35.526254 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 2 keys = 0
-452> 2016-12-21 15:14:35.526258 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x3a0000~10000
-451> 2016-12-21 15:14:35.526261 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x3a0000~10000 - have 0x3a0000 (3801088) bytes fadvise_flags 0x0
-450> 2016-12-21 15:14:35.526264 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-449> 2016-12-21 15:14:35.526266 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x3a0000~10000 target_blob_size 0x80000 compress 0
-448> 2016-12-21 15:14:35.526269 7fb2b1660700 20 bluestore.BufferSpace(0x7fb319a6ec98 in 0x7fb302807340) _discard 0x0~10000
-447> 2016-12-21 15:14:35.526274 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x3a0000~10000: 0x0~10000 Blob(0x7fb3123ca090 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6ec40))
-446> 2016-12-21 15:14:35.526278 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305a41480 1 blobs
-445> 2016-12-21 15:14:35.526279 7fb2b1660700 10 bitmapalloc:reserve instance 140406818704320 num_used 138577 total 140320
-444> 2016-12-21 15:14:35.526282 7fb2b1660700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-443> 2016-12-21 15:14:35.526294 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3123ca090 blob([0x21d500000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb319a6ec40)) csum_type none csum_order 12 csum_length 0x10000
-442> 2016-12-21 15:14:35.526300 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) dirty_range mark inline shard dirty
-441> 2016-12-21 15:14:35.526302 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write extending size to 0x3b0000
-440> 2016-12-21 15:14:35.526303 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.97_head #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# 0x3a0000~10000 = 0
-439> 2016-12-21 15:14:35.526309 7fb2b1660700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head#
-438> 2016-12-21 15:14:35.526317 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.97_head #0:e9000000::::head# = 0
-437> 2016-12-21 15:14:35.526320 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305a41480 onodes 0x7fb304bffc00 shared_blobs
-436> 2016-12-21 15:14:35.526329 7fb2b1660700 20 bluestore.extentmap(0x7fb304bffce8) update inline shard 475 bytes from 59 extents
-435> 2016-12-21 15:14:35.526332 7fb2b1660700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:e9984156:::rbd_data.100b6b8b4567.0000000000000774:head# is 859 (378 bytes onode + 2 bytes spanning blobs + 479 bytes inline extents)
-434> 2016-12-21 15:14:35.526339 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a41480 prepare
-433> 2016-12-21 15:14:35.526341 7fb2b1660700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305a41480
-432> 2016-12-21 15:14:35.526403 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3084b9400 aio_wait
-431> 2016-12-21 15:14:35.526407 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3084b9400
-430> 2016-12-21 15:14:35.526408 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3084b9400 io_done
-429> 2016-12-21 15:14:35.526409 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb307f4b958 in 0x7fb302807340) finish_write buffer(0x7fb318ad3ba0 space 0x7fb307f4b958 0x0~10000 writing nocache)
-428> 2016-12-21 15:14:35.526413 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a41700 aio_wait
-427> 2016-12-21 15:14:35.526415 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305a41700
-426> 2016-12-21 15:14:35.526416 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a41700 io_done
-425> 2016-12-21 15:14:35.526417 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb319a6f418 in 0x7fb302807340) finish_write buffer(0x7fb3123ca870 space 0x7fb319a6f418 0x0~10000 writing nocache)
-424> 2016-12-21 15:14:35.526419 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a40800 aio_wait
-423> 2016-12-21 15:14:35.526420 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305a40800
-422> 2016-12-21 15:14:35.526421 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a40800 io_done
-421> 2016-12-21 15:14:35.526423 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb319a6f298 in 0x7fb302807340) finish_write buffer(0x7fb3123ca750 space 0x7fb319a6f298 0x0~10000 writing nocache)
-420> 2016-12-21 15:14:35.526425 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a41200 aio_wait
-419> 2016-12-21 15:14:35.526426 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305a41200
-418> 2016-12-21 15:14:35.526427 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a41200 io_done
-417> 2016-12-21 15:14:35.526428 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb319a6f118 in 0x7fb302807340) finish_write buffer(0x7fb3123ca630 space 0x7fb319a6f118 0x0~10000 writing nocache)
-416> 2016-12-21 15:14:35.526430 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a3e000 aio_wait
-415> 2016-12-21 15:14:35.526432 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305a3e000
-414> 2016-12-21 15:14:35.526432 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a3e000 io_done
-413> 2016-12-21 15:14:35.526434 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb319a6ef98 in 0x7fb302807340) finish_write buffer(0x7fb3123ca240 space 0x7fb319a6ef98 0x0~10000 writing nocache)
-412> 2016-12-21 15:14:35.526436 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a40a80 aio_wait
-411> 2016-12-21 15:14:35.526437 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305a40a80
-410> 2016-12-21 15:14:35.526438 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a40a80 io_done
-409> 2016-12-21 15:14:35.526439 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb319a6ee18 in 0x7fb302807340) finish_write buffer(0x7fb3123ca120 space 0x7fb319a6ee18 0x0~10000 writing nocache)
-408> 2016-12-21 15:14:35.526806 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a41480 aio_wait
-407> 2016-12-21 15:14:35.526810 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305a41480
-406> 2016-12-21 15:14:35.526811 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a41480 io_done
-405> 2016-12-21 15:14:35.526813 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb319a6ec98 in 0x7fb302807340) finish_write buffer(0x7fb3123ca000 space 0x7fb319a6ec98 0x0~10000 writing nocache)
-404> 2016-12-21 15:14:35.528860 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-403> 2016-12-21 15:14:35.528869 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-402> 2016-12-21 15:14:35.528874 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-401> 2016-12-21 15:14:35.528878 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-400> 2016-12-21 15:14:35.528882 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2d0000~10000
-399> 2016-12-21 15:14:35.528885 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2d0000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-398> 2016-12-21 15:14:35.528889 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-397> 2016-12-21 15:14:35.528891 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x2d0000~10000 target_blob_size 0x80000 compress 0
-396> 2016-12-21 15:14:35.528894 7fb2afe5d700 20 bluestore.BufferSpace(0x7fb318adfa18 in 0x7fb302807dc0) _discard 0x0~10000
-395> 2016-12-21 15:14:35.528900 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x2d0000~10000: 0x0~10000 Blob(0x7fb3132990f0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb318adf9c0))
-394> 2016-12-21 15:14:35.528904 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3054c1b80 1 blobs
-393> 2016-12-21 15:14:35.528905 7fb2afe5d700 10 bitmapalloc:reserve instance 140406818704320 num_used 138578 total 140320
-392> 2016-12-21 15:14:35.528908 7fb2afe5d700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-391> 2016-12-21 15:14:35.528920 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3132990f0 blob([0x21d510000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb318adf9c0)) csum_type none csum_order 12 csum_length 0x10000
-390> 2016-12-21 15:14:35.528927 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-389> 2016-12-21 15:14:35.528929 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2d0000~10000 = 0
-388> 2016-12-21 15:14:35.528934 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-387> 2016-12-21 15:14:35.528942 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-386> 2016-12-21 15:14:35.528945 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3054c1b80 onodes 0x7fb30445af00 shared_blobs
-385> 2016-12-21 15:14:35.528957 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 413 bytes from 51 extents
-384> 2016-12-21 15:14:35.528960 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 797 (378 bytes onode + 2 bytes spanning blobs + 417 bytes inline extents)
-383> 2016-12-21 15:14:35.528968 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3054c1b80 prepare
-382> 2016-12-21 15:14:35.528970 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3054c1b80
-381> 2016-12-21 15:14:35.529094 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3054c1b80 aio_wait
-380> 2016-12-21 15:14:35.529100 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3054c1b80
-379> 2016-12-21 15:14:35.529101 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3054c1b80 io_done
-378> 2016-12-21 15:14:35.529103 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb318adfa18 in 0x7fb302807dc0) finish_write buffer(0x7fb313299060 space 0x7fb318adfa18 0x0~10000 writing nocache)
-377> 2016-12-21 15:14:35.529126 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-376> 2016-12-21 15:14:35.529132 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb304d10a00 seq 637
-375> 2016-12-21 15:14:35.529155 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-374> 2016-12-21 15:14:35.529161 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-373> 2016-12-21 15:14:35.529165 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-372> 2016-12-21 15:14:35.529168 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-371> 2016-12-21 15:14:35.529172 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2e0000~10000
-370> 2016-12-21 15:14:35.529175 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2e0000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-369> 2016-12-21 15:14:35.529179 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-368> 2016-12-21 15:14:35.529181 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x2e0000~10000 target_blob_size 0x80000 compress 0
-367> 2016-12-21 15:14:35.529183 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123cee98 in 0x7fb302807dc0) _discard 0x0~10000
-366> 2016-12-21 15:14:35.529189 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x2e0000~10000: 0x0~10000 Blob(0x7fb3184f7320 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cee40))
-365> 2016-12-21 15:14:35.529193 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb304d10a00 1 blobs
-364> 2016-12-21 15:14:35.529195 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138579 total 140320
-363> 2016-12-21 15:14:35.529198 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-362> 2016-12-21 15:14:35.529219 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3184f7320 blob([0x21d520000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cee40)) csum_type none csum_order 12 csum_length 0x10000
-361> 2016-12-21 15:14:35.529227 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-360> 2016-12-21 15:14:35.529229 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2e0000~10000 = 0
-359> 2016-12-21 15:14:35.529234 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-358> 2016-12-21 15:14:35.529243 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-357> 2016-12-21 15:14:35.529245 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb304d10a00 onodes 0x7fb30445af00 shared_blobs
-356> 2016-12-21 15:14:35.529257 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 421 bytes from 52 extents
-355> 2016-12-21 15:14:35.529260 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 805 (378 bytes onode + 2 bytes spanning blobs + 425 bytes inline extents)
-354> 2016-12-21 15:14:35.529267 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304d10a00 prepare
-353> 2016-12-21 15:14:35.529269 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb304d10a00
-352> 2016-12-21 15:14:35.529370 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304d10a00 aio_wait
-351> 2016-12-21 15:14:35.529374 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb304d10a00
-350> 2016-12-21 15:14:35.529375 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304d10a00 io_done
-349> 2016-12-21 15:14:35.529377 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123cee98 in 0x7fb302807dc0) finish_write buffer(0x7fb3184f70e0 space 0x7fb3123cee98 0x0~10000 writing nocache)
-348> 2016-12-21 15:14:35.529408 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-347> 2016-12-21 15:14:35.529414 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb3059b2500 seq 638
-346> 2016-12-21 15:14:35.529436 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-345> 2016-12-21 15:14:35.529442 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-344> 2016-12-21 15:14:35.529446 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-343> 2016-12-21 15:14:35.529449 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-342> 2016-12-21 15:14:35.529453 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2f0000~10000
-341> 2016-12-21 15:14:35.529455 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2f0000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-340> 2016-12-21 15:14:35.529459 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-339> 2016-12-21 15:14:35.529460 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x2f0000~10000 target_blob_size 0x80000 compress 0
-338> 2016-12-21 15:14:35.529463 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123cf198 in 0x7fb302807dc0) _discard 0x0~10000
-337> 2016-12-21 15:14:35.529468 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x2f0000~10000: 0x0~10000 Blob(0x7fb3184f6ea0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cf140))
-336> 2016-12-21 15:14:35.529472 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3059b2500 1 blobs
-335> 2016-12-21 15:14:35.529474 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138580 total 140320
-334> 2016-12-21 15:14:35.529476 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-333> 2016-12-21 15:14:35.529488 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3184f6ea0 blob([0x21d530000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cf140)) csum_type none csum_order 12 csum_length 0x10000
-332> 2016-12-21 15:14:35.529495 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-331> 2016-12-21 15:14:35.529497 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x2f0000~10000 = 0
-330> 2016-12-21 15:14:35.529502 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-329> 2016-12-21 15:14:35.529509 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-328> 2016-12-21 15:14:35.529512 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3059b2500 onodes 0x7fb30445af00 shared_blobs
-327> 2016-12-21 15:14:35.529521 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 429 bytes from 53 extents
-326> 2016-12-21 15:14:35.529523 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 813 (378 bytes onode + 2 bytes spanning blobs + 433 bytes inline extents)
-325> 2016-12-21 15:14:35.529531 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059b2500 prepare
-324> 2016-12-21 15:14:35.529533 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3059b2500
-323> 2016-12-21 15:14:35.529626 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059b2500 aio_wait
-322> 2016-12-21 15:14:35.529630 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3059b2500
-321> 2016-12-21 15:14:35.529631 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059b2500 io_done
-320> 2016-12-21 15:14:35.529632 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123cf198 in 0x7fb302807dc0) finish_write buffer(0x7fb3184f6c60 space 0x7fb3123cf198 0x0~10000 writing nocache)
-319> 2016-12-21 15:14:35.529667 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-318> 2016-12-21 15:14:35.529673 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb308030280 seq 639
-317> 2016-12-21 15:14:35.529694 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-316> 2016-12-21 15:14:35.529700 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-315> 2016-12-21 15:14:35.529704 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-314> 2016-12-21 15:14:35.529707 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-313> 2016-12-21 15:14:35.529711 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x300000~10000
-312> 2016-12-21 15:14:35.529714 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x300000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-311> 2016-12-21 15:14:35.529717 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-310> 2016-12-21 15:14:35.529719 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x300000~10000 target_blob_size 0x80000 compress 0
-309> 2016-12-21 15:14:35.529721 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123cf498 in 0x7fb302807dc0) _discard 0x0~10000
-308> 2016-12-21 15:14:35.529726 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x300000~10000: 0x0~10000 Blob(0x7fb3184f6a20 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cf440))
-307> 2016-12-21 15:14:35.529730 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb308030280 1 blobs
-306> 2016-12-21 15:14:35.529731 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138581 total 140320
-305> 2016-12-21 15:14:35.529734 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-304> 2016-12-21 15:14:35.529745 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3184f6a20 blob([0x21d540000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cf440)) csum_type none csum_order 12 csum_length 0x10000
-303> 2016-12-21 15:14:35.529751 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-302> 2016-12-21 15:14:35.529753 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x300000~10000 = 0
-301> 2016-12-21 15:14:35.529758 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-300> 2016-12-21 15:14:35.529766 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-299> 2016-12-21 15:14:35.529768 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb308030280 onodes 0x7fb30445af00 shared_blobs
-298> 2016-12-21 15:14:35.529778 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 437 bytes from 54 extents
-297> 2016-12-21 15:14:35.529781 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 821 (378 bytes onode + 2 bytes spanning blobs + 441 bytes inline extents)
-296> 2016-12-21 15:14:35.529788 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb308030280 prepare
-295> 2016-12-21 15:14:35.529789 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb308030280
-294> 2016-12-21 15:14:35.529878 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb308030280 aio_wait
-293> 2016-12-21 15:14:35.529881 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb308030280
-292> 2016-12-21 15:14:35.529882 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb308030280 io_done
-291> 2016-12-21 15:14:35.529884 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123cf498 in 0x7fb302807dc0) finish_write buffer(0x7fb3184f67e0 space 0x7fb3123cf498 0x0~10000 writing nocache)
-290> 2016-12-21 15:14:35.529922 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-289> 2016-12-21 15:14:35.529928 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb307c10500 seq 640
-288> 2016-12-21 15:14:35.529949 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-287> 2016-12-21 15:14:35.529954 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-286> 2016-12-21 15:14:35.529958 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-285> 2016-12-21 15:14:35.529961 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-284> 2016-12-21 15:14:35.529965 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x310000~10000
-283> 2016-12-21 15:14:35.529968 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x310000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-282> 2016-12-21 15:14:35.529971 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-281> 2016-12-21 15:14:35.529973 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x310000~10000 target_blob_size 0x80000 compress 0
-280> 2016-12-21 15:14:35.529975 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123cf798 in 0x7fb302807dc0) _discard 0x0~10000
-279> 2016-12-21 15:14:35.529980 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x310000~10000: 0x0~10000 Blob(0x7fb3184f65a0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cf740))
-278> 2016-12-21 15:14:35.530003 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb307c10500 1 blobs
-277> 2016-12-21 15:14:35.530004 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138582 total 140320
-276> 2016-12-21 15:14:35.530007 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-275> 2016-12-21 15:14:35.530018 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3184f65a0 blob([0x21d550000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cf740)) csum_type none csum_order 12 csum_length 0x10000
-274> 2016-12-21 15:14:35.530025 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-273> 2016-12-21 15:14:35.530027 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x310000~10000 = 0
-272> 2016-12-21 15:14:35.530032 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-271> 2016-12-21 15:14:35.530039 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-270> 2016-12-21 15:14:35.530042 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb307c10500 onodes 0x7fb30445af00 shared_blobs
-269> 2016-12-21 15:14:35.530051 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 445 bytes from 55 extents
-268> 2016-12-21 15:14:35.530054 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 829 (378 bytes onode + 2 bytes spanning blobs + 449 bytes inline extents)
-267> 2016-12-21 15:14:35.530061 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb307c10500 prepare
-266> 2016-12-21 15:14:35.530063 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb307c10500
-265> 2016-12-21 15:14:35.530149 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb307c10500 aio_wait
-264> 2016-12-21 15:14:35.530152 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb307c10500
-263> 2016-12-21 15:14:35.530153 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb307c10500 io_done
-262> 2016-12-21 15:14:35.530155 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123cf798 in 0x7fb302807dc0) finish_write buffer(0x7fb3184f6360 space 0x7fb3123cf798 0x0~10000 writing nocache)
-261> 2016-12-21 15:14:35.530187 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-260> 2016-12-21 15:14:35.530193 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb3043dc580 seq 641
-259> 2016-12-21 15:14:35.530214 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-258> 2016-12-21 15:14:35.530219 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-257> 2016-12-21 15:14:35.530223 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-256> 2016-12-21 15:14:35.530226 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-255> 2016-12-21 15:14:35.530230 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x320000~10000
-254> 2016-12-21 15:14:35.530233 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x320000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-253> 2016-12-21 15:14:35.530236 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-252> 2016-12-21 15:14:35.530237 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x320000~10000 target_blob_size 0x80000 compress 0
-251> 2016-12-21 15:14:35.530240 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123cfa98 in 0x7fb302807dc0) _discard 0x0~10000
-250> 2016-12-21 15:14:35.530245 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x320000~10000: 0x0~10000 Blob(0x7fb3184f6120 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cfa40))
-249> 2016-12-21 15:14:35.530249 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3043dc580 1 blobs
-248> 2016-12-21 15:14:35.530251 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138583 total 140320
-247> 2016-12-21 15:14:35.530253 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-246> 2016-12-21 15:14:35.530264 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3184f6120 blob([0x21d560000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cfa40)) csum_type none csum_order 12 csum_length 0x10000
-245> 2016-12-21 15:14:35.530271 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-244> 2016-12-21 15:14:35.530272 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x320000~10000 = 0
-243> 2016-12-21 15:14:35.530277 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-242> 2016-12-21 15:14:35.530285 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-241> 2016-12-21 15:14:35.530287 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3043dc580 onodes 0x7fb30445af00 shared_blobs
-240> 2016-12-21 15:14:35.530296 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 453 bytes from 56 extents
-239> 2016-12-21 15:14:35.530299 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 837 (378 bytes onode + 2 bytes spanning blobs + 457 bytes inline extents)
-238> 2016-12-21 15:14:35.530306 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3043dc580 prepare
-237> 2016-12-21 15:14:35.530308 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3043dc580
-236> 2016-12-21 15:14:35.530398 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3043dc580 aio_wait
-235> 2016-12-21 15:14:35.530401 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3043dc580
-234> 2016-12-21 15:14:35.530402 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3043dc580 io_done
-233> 2016-12-21 15:14:35.530404 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123cfa98 in 0x7fb302807dc0) finish_write buffer(0x7fb3140f26d0 space 0x7fb3123cfa98 0x0~10000 writing nocache)
-232> 2016-12-21 15:14:35.530435 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-231> 2016-12-21 15:14:35.530441 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb307c12080 seq 642
-230> 2016-12-21 15:14:35.530462 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-229> 2016-12-21 15:14:35.530468 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-228> 2016-12-21 15:14:35.530472 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-227> 2016-12-21 15:14:35.530475 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-226> 2016-12-21 15:14:35.530479 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x330000~10000
-225> 2016-12-21 15:14:35.530482 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x330000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-224> 2016-12-21 15:14:35.530485 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-223> 2016-12-21 15:14:35.530487 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x330000~10000 target_blob_size 0x80000 compress 0
-222> 2016-12-21 15:14:35.530489 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123cfd98 in 0x7fb302807dc0) _discard 0x0~10000
-221> 2016-12-21 15:14:35.530494 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x330000~10000: 0x0~10000 Blob(0x7fb3140f27f0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cfd40))
-220> 2016-12-21 15:14:35.530497 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb307c12080 1 blobs
-219> 2016-12-21 15:14:35.530499 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138584 total 140320
-218> 2016-12-21 15:14:35.530501 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-217> 2016-12-21 15:14:35.530513 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3140f27f0 blob([0x21d570000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123cfd40)) csum_type none csum_order 12 csum_length 0x10000
-216> 2016-12-21 15:14:35.530519 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-215> 2016-12-21 15:14:35.530521 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x330000~10000 = 0
-214> 2016-12-21 15:14:35.530526 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-213> 2016-12-21 15:14:35.530532 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-212> 2016-12-21 15:14:35.530535 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb307c12080 onodes 0x7fb30445af00 shared_blobs
-211> 2016-12-21 15:14:35.530544 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 461 bytes from 57 extents
-210> 2016-12-21 15:14:35.530547 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 845 (378 bytes onode + 2 bytes spanning blobs + 465 bytes inline extents)
-209> 2016-12-21 15:14:35.530554 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb307c12080 prepare
-208> 2016-12-21 15:14:35.530556 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb307c12080
-207> 2016-12-21 15:14:35.530688 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-206> 2016-12-21 15:14:35.530694 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb305a56a00 seq 643
-205> 2016-12-21 15:14:35.530695 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb307c12080 aio_wait
-204> 2016-12-21 15:14:35.530701 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb307c12080
-203> 2016-12-21 15:14:35.530702 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb307c12080 io_done
-202> 2016-12-21 15:14:35.530704 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123cfd98 in 0x7fb302807dc0) finish_write buffer(0x7fb3140f2910 space 0x7fb3123cfd98 0x0~10000 writing nocache)
-201> 2016-12-21 15:14:35.530716 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-200> 2016-12-21 15:14:35.530721 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-199> 2016-12-21 15:14:35.530725 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-198> 2016-12-21 15:14:35.530728 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-197> 2016-12-21 15:14:35.530731 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x340000~10000
-196> 2016-12-21 15:14:35.530734 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x340000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-195> 2016-12-21 15:14:35.530738 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-194> 2016-12-21 15:14:35.530739 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x340000~10000 target_blob_size 0x80000 compress 0
-193> 2016-12-21 15:14:35.530742 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123d0098 in 0x7fb302807dc0) _discard 0x0~10000
-192> 2016-12-21 15:14:35.530746 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x340000~10000: 0x0~10000 Blob(0x7fb3140f2a30 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0040))
-191> 2016-12-21 15:14:35.530750 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305a56a00 1 blobs
-190> 2016-12-21 15:14:35.530752 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138585 total 140320
-189> 2016-12-21 15:14:35.530754 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-188> 2016-12-21 15:14:35.530766 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3140f2a30 blob([0x21d580000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0040)) csum_type none csum_order 12 csum_length 0x10000
-187> 2016-12-21 15:14:35.530772 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-186> 2016-12-21 15:14:35.530774 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x340000~10000 = 0
-185> 2016-12-21 15:14:35.530779 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-184> 2016-12-21 15:14:35.530786 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-183> 2016-12-21 15:14:35.530788 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305a56a00 onodes 0x7fb30445af00 shared_blobs
-182> 2016-12-21 15:14:35.530798 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 469 bytes from 58 extents
-181> 2016-12-21 15:14:35.530801 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 853 (378 bytes onode + 2 bytes spanning blobs + 473 bytes inline extents)
-180> 2016-12-21 15:14:35.530808 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a56a00 prepare
-179> 2016-12-21 15:14:35.530810 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305a56a00
-178> 2016-12-21 15:14:35.530932 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-177> 2016-12-21 15:14:35.530938 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb3059c4800 seq 644
-176> 2016-12-21 15:14:35.530959 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-175> 2016-12-21 15:14:35.530965 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-174> 2016-12-21 15:14:35.530969 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-173> 2016-12-21 15:14:35.530972 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-172> 2016-12-21 15:14:35.530975 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x350000~10000
-171> 2016-12-21 15:14:35.530978 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x350000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-170> 2016-12-21 15:14:35.530982 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-169> 2016-12-21 15:14:35.530983 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x350000~10000 target_blob_size 0x80000 compress 0
-168> 2016-12-21 15:14:35.530986 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123d02d8 in 0x7fb302807dc0) _discard 0x0~10000
-167> 2016-12-21 15:14:35.530991 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x350000~10000: 0x0~10000 Blob(0x7fb3140f2c70 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0280))
-166> 2016-12-21 15:14:35.530995 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb3059c4800 1 blobs
-165> 2016-12-21 15:14:35.530996 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138586 total 140320
-164> 2016-12-21 15:14:35.530998 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-163> 2016-12-21 15:14:35.531012 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3140f2c70 blob([0x21d590000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0280)) csum_type none csum_order 12 csum_length 0x10000
-162> 2016-12-21 15:14:35.531018 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-161> 2016-12-21 15:14:35.531020 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x350000~10000 = 0
-160> 2016-12-21 15:14:35.531025 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-159> 2016-12-21 15:14:35.531032 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-158> 2016-12-21 15:14:35.531034 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb3059c4800 onodes 0x7fb30445af00 shared_blobs
-157> 2016-12-21 15:14:35.531044 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 477 bytes from 59 extents
-156> 2016-12-21 15:14:35.531047 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 861 (378 bytes onode + 2 bytes spanning blobs + 481 bytes inline extents)
-155> 2016-12-21 15:14:35.531054 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059c4800 prepare
-154> 2016-12-21 15:14:35.531056 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb3059c4800
-153> 2016-12-21 15:14:35.531171 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-152> 2016-12-21 15:14:35.531177 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb304802800 seq 645
-151> 2016-12-21 15:14:35.531198 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-150> 2016-12-21 15:14:35.531204 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-149> 2016-12-21 15:14:35.531222 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-148> 2016-12-21 15:14:35.531225 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-147> 2016-12-21 15:14:35.531229 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x360000~10000
-146> 2016-12-21 15:14:35.531232 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x360000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-145> 2016-12-21 15:14:35.531235 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-144> 2016-12-21 15:14:35.531237 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x360000~10000 target_blob_size 0x80000 compress 0
-143> 2016-12-21 15:14:35.531239 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123d0698 in 0x7fb302807dc0) _discard 0x0~10000
-142> 2016-12-21 15:14:35.531244 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x360000~10000: 0x0~10000 Blob(0x7fb3140f2eb0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0640))
-141> 2016-12-21 15:14:35.531248 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb304802800 1 blobs
-140> 2016-12-21 15:14:35.531249 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138587 total 140320
-139> 2016-12-21 15:14:35.531251 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-138> 2016-12-21 15:14:35.531263 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3140f2eb0 blob([0x21d5a0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0640)) csum_type none csum_order 12 csum_length 0x10000
-137> 2016-12-21 15:14:35.531269 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-136> 2016-12-21 15:14:35.531271 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x360000~10000 = 0
-135> 2016-12-21 15:14:35.531275 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-134> 2016-12-21 15:14:35.531282 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-133> 2016-12-21 15:14:35.531285 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb304802800 onodes 0x7fb30445af00 shared_blobs
-132> 2016-12-21 15:14:35.531295 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 485 bytes from 60 extents
-131> 2016-12-21 15:14:35.531298 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 869 (378 bytes onode + 2 bytes spanning blobs + 489 bytes inline extents)
-130> 2016-12-21 15:14:35.531305 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304802800 prepare
-129> 2016-12-21 15:14:35.531307 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb304802800
-128> 2016-12-21 15:14:35.531425 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-127> 2016-12-21 15:14:35.531431 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb305bc2000 seq 646
-126> 2016-12-21 15:14:35.531452 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-125> 2016-12-21 15:14:35.531457 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-124> 2016-12-21 15:14:35.531461 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-123> 2016-12-21 15:14:35.531464 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-122> 2016-12-21 15:14:35.531468 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x370000~10000
-121> 2016-12-21 15:14:35.531471 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x370000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-120> 2016-12-21 15:14:35.531474 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-119> 2016-12-21 15:14:35.531475 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x370000~10000 target_blob_size 0x80000 compress 0
-118> 2016-12-21 15:14:35.531478 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123d0998 in 0x7fb302807dc0) _discard 0x0~10000
-117> 2016-12-21 15:14:35.531482 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x370000~10000: 0x0~10000 Blob(0x7fb3140f30f0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0940))
-116> 2016-12-21 15:14:35.531486 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305bc2000 1 blobs
-115> 2016-12-21 15:14:35.531487 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138588 total 140320
-114> 2016-12-21 15:14:35.531490 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-113> 2016-12-21 15:14:35.531501 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3140f30f0 blob([0x21d5b0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0940)) csum_type none csum_order 12 csum_length 0x10000
-112> 2016-12-21 15:14:35.531507 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-111> 2016-12-21 15:14:35.531509 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x370000~10000 = 0
-110> 2016-12-21 15:14:35.531514 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-109> 2016-12-21 15:14:35.531521 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-108> 2016-12-21 15:14:35.531524 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305bc2000 onodes 0x7fb30445af00 shared_blobs
-107> 2016-12-21 15:14:35.531534 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 493 bytes from 61 extents
-106> 2016-12-21 15:14:35.531536 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 877 (378 bytes onode + 2 bytes spanning blobs + 497 bytes inline extents)
-105> 2016-12-21 15:14:35.531543 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305bc2000 prepare
-104> 2016-12-21 15:14:35.531545 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305bc2000
-103> 2016-12-21 15:14:35.531665 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-102> 2016-12-21 15:14:35.531671 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb304037900 seq 647
-101> 2016-12-21 15:14:35.531692 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-100> 2016-12-21 15:14:35.531698 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-99> 2016-12-21 15:14:35.531701 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-98> 2016-12-21 15:14:35.531704 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-97> 2016-12-21 15:14:35.531708 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x380000~10000
-96> 2016-12-21 15:14:35.531711 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x380000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-95> 2016-12-21 15:14:35.531714 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-94> 2016-12-21 15:14:35.531715 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x380000~10000 target_blob_size 0x80000 compress 0
-93> 2016-12-21 15:14:35.531718 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123d0c98 in 0x7fb302807dc0) _discard 0x0~10000
-92> 2016-12-21 15:14:35.531722 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x380000~10000: 0x0~10000 Blob(0x7fb3140f3330 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0c40))
-91> 2016-12-21 15:14:35.531726 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb304037900 1 blobs
-90> 2016-12-21 15:14:35.531728 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138589 total 140320
-89> 2016-12-21 15:14:35.531730 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-88> 2016-12-21 15:14:35.531742 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3140f3330 blob([0x21d5c0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0c40)) csum_type none csum_order 12 csum_length 0x10000
-87> 2016-12-21 15:14:35.531748 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-86> 2016-12-21 15:14:35.531749 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x380000~10000 = 0
-85> 2016-12-21 15:14:35.531754 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-84> 2016-12-21 15:14:35.531762 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-83> 2016-12-21 15:14:35.531764 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb304037900 onodes 0x7fb30445af00 shared_blobs
-82> 2016-12-21 15:14:35.531774 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 501 bytes from 62 extents
-81> 2016-12-21 15:14:35.531777 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 885 (378 bytes onode + 2 bytes spanning blobs + 505 bytes inline extents)
-80> 2016-12-21 15:14:35.531784 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304037900 prepare
-79> 2016-12-21 15:14:35.531785 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb304037900
-78> 2016-12-21 15:14:35.531904 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-77> 2016-12-21 15:14:35.531910 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb305bc5480 seq 648
-76> 2016-12-21 15:14:35.531930 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-75> 2016-12-21 15:14:35.531935 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-74> 2016-12-21 15:14:35.531939 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-73> 2016-12-21 15:14:35.531942 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-72> 2016-12-21 15:14:35.531946 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x390000~10000
-71> 2016-12-21 15:14:35.531949 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x390000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-70> 2016-12-21 15:14:35.531952 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-69> 2016-12-21 15:14:35.531953 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x390000~10000 target_blob_size 0x80000 compress 0
-68> 2016-12-21 15:14:35.531956 7fb2ad658700 20 bluestore.BufferSpace(0x7fb3123d0f98 in 0x7fb302807dc0) _discard 0x0~10000
-67> 2016-12-21 15:14:35.531961 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x390000~10000: 0x0~10000 Blob(0x7fb3140f3570 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0f40))
-66> 2016-12-21 15:14:35.531965 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb305bc5480 1 blobs
-65> 2016-12-21 15:14:35.531966 7fb2ad658700 10 bitmapalloc:reserve instance 140406818704320 num_used 138590 total 140320
-64> 2016-12-21 15:14:35.531968 7fb2ad658700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-63> 2016-12-21 15:14:35.531980 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb3140f3570 blob([0x21d5d0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb3123d0f40)) csum_type none csum_order 12 csum_length 0x10000
-62> 2016-12-21 15:14:35.531986 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-61> 2016-12-21 15:14:35.531988 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x390000~10000 = 0
-60> 2016-12-21 15:14:35.531993 7fb2ad658700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-59> 2016-12-21 15:14:35.532000 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-58> 2016-12-21 15:14:35.532002 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb305bc5480 onodes 0x7fb30445af00 shared_blobs
-57> 2016-12-21 15:14:35.532012 7fb2ad658700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 508 bytes from 63 extents
-56> 2016-12-21 15:14:35.532014 7fb2ad658700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 892 (378 bytes onode + 2 bytes spanning blobs + 512 bytes inline extents)
-55> 2016-12-21 15:14:35.532021 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305bc5480 prepare
-54> 2016-12-21 15:14:35.532023 7fb2ad658700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb305bc5480
-53> 2016-12-21 15:14:35.533230 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a56a00 aio_wait
-52> 2016-12-21 15:14:35.533236 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305a56a00
-51> 2016-12-21 15:14:35.533238 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305a56a00 io_done
-50> 2016-12-21 15:14:35.533240 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123d0098 in 0x7fb302807dc0) finish_write buffer(0x7fb3140f2b50 space 0x7fb3123d0098 0x0~10000 writing nocache)
-49> 2016-12-21 15:14:35.533245 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059c4800 aio_wait
-48> 2016-12-21 15:14:35.533247 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb3059c4800
-47> 2016-12-21 15:14:35.533248 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb3059c4800 io_done
-46> 2016-12-21 15:14:35.533250 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123d02d8 in 0x7fb302807dc0) finish_write buffer(0x7fb3140f2d90 space 0x7fb3123d02d8 0x0~10000 writing nocache)
-45> 2016-12-21 15:14:35.533253 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304802800 aio_wait
-44> 2016-12-21 15:14:35.533255 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb304802800
-43> 2016-12-21 15:14:35.533256 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304802800 io_done
-42> 2016-12-21 15:14:35.533258 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123d0698 in 0x7fb302807dc0) finish_write buffer(0x7fb3140f2fd0 space 0x7fb3123d0698 0x0~10000 writing nocache)
-41> 2016-12-21 15:14:35.533261 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305bc2000 aio_wait
-40> 2016-12-21 15:14:35.533262 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305bc2000
-39> 2016-12-21 15:14:35.533264 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305bc2000 io_done
-38> 2016-12-21 15:14:35.533265 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123d0998 in 0x7fb302807dc0) finish_write buffer(0x7fb3140f3210 space 0x7fb3123d0998 0x0~10000 writing nocache)
-37> 2016-12-21 15:14:35.533268 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304037900 aio_wait
-36> 2016-12-21 15:14:35.533270 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb304037900
-35> 2016-12-21 15:14:35.533271 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb304037900 io_done
-34> 2016-12-21 15:14:35.533273 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123d0c98 in 0x7fb302807dc0) finish_write buffer(0x7fb3140f3450 space 0x7fb3123d0c98 0x0~10000 writing nocache)
-33> 2016-12-21 15:14:35.533276 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305bc5480 aio_wait
-32> 2016-12-21 15:14:35.533277 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb305bc5480
-31> 2016-12-21 15:14:35.533279 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb305bc5480 io_done
-30> 2016-12-21 15:14:35.533280 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb3123d0f98 in 0x7fb302807dc0) finish_write buffer(0x7fb3140f3690 space 0x7fb3123d0f98 0x0~10000 writing nocache)
-29> 2016-12-21 15:14:35.535779 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) queue_transactions existing 0x7fb303955b10 osr(0.e 0x7fb303980360)
-28> 2016-12-21 15:14:35.535802 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_create osr 0x7fb303955b10 = 0x7fb30b64a080 seq 649
-27> 2016-12-21 15:14:35.535826 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys
-26> 2016-12-21 15:14:35.535832 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _setattrs 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 2 keys = 0
-25> 2016-12-21 15:14:35.535836 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags -
-24> 2016-12-21 15:14:35.535839 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _set_alloc_hint 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# object_size 4194304 write_size 4194304 flags - = 0
-23> 2016-12-21 15:14:35.535843 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x3a0000~10000
-22> 2016-12-21 15:14:35.535847 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x3a0000~10000 - have 0x400000 (4194304) bytes fadvise_flags 0x0
-21> 2016-12-21 15:14:35.535850 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write prefer csum_order 12 target_blob_size 0x80000
-20> 2016-12-21 15:14:35.535852 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big 0x3a0000~10000 target_blob_size 0x80000 compress 0
-19> 2016-12-21 15:14:35.535855 7fb2afe5d700 20 bluestore.BufferSpace(0x7fb318adf418 in 0x7fb302807dc0) _discard 0x0~10000
-18> 2016-12-21 15:14:35.535860 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_write_big lex 0x3a0000~10000: 0x0~10000 Blob(0x7fb313298fd0 blob([] none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb318adf3c0))
-17> 2016-12-21 15:14:35.535864 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write txc 0x7fb30b64a080 1 blobs
-16> 2016-12-21 15:14:35.535866 7fb2afe5d700 10 bitmapalloc:reserve instance 140406818704320 num_used 138591 total 140320
-15> 2016-12-21 15:14:35.535869 7fb2afe5d700 10 bitmapalloc:allocate instance 140406818704320 want_size 65536 alloc_unit 65536 hint 0
-14> 2016-12-21 15:14:35.535881 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _do_alloc_write blob Blob(0x7fb313298fd0 blob([0x21d5e0000~10000] mutable none/0x1) ref_map(0x0~10000=1) SharedBlob(0x7fb318adf3c0)) csum_type none csum_order 12 csum_length 0x10000
-13> 2016-12-21 15:14:35.535887 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) dirty_range mark inline shard dirty
-12> 2016-12-21 15:14:35.535889 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _write 0.e_head #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# 0x3a0000~10000 = 0
-11> 2016-12-21 15:14:35.535895 7fb2afe5d700 15 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head#
-10> 2016-12-21 15:14:35.535902 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _omap_setkeys 0.e_head #0:70000000::::head# = 0
-9> 2016-12-21 15:14:35.535905 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_write_nodes txc 0x7fb30b64a080 onodes 0x7fb30445af00 shared_blobs
-8> 2016-12-21 15:14:35.535917 7fb2afe5d700 20 bluestore.extentmap(0x7fb30445afe8) update inline shard 515 bytes from 64 extents
-7> 2016-12-21 15:14:35.535920 7fb2afe5d700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) onode #0:707563e3:::rbd_data.100b6b8b4567.0000000000000773:head# is 899 (378 bytes onode + 2 bytes spanning blobs + 519 bytes inline extents)
-6> 2016-12-21 15:14:35.535928 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb30b64a080 prepare
-5> 2016-12-21 15:14:35.535930 7fb2afe5d700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_aio_submit txc 0x7fb30b64a080
-4> 2016-12-21 15:14:35.536046 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb30b64a080 aio_wait
-3> 2016-12-21 15:14:35.536061 7fb2e4ec7700 20 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_finish_io 0x7fb30b64a080
-2> 2016-12-21 15:14:35.536062 7fb2e4ec7700 10 bluestore(/var/lib/ceph/osd/osd-device-0-data) _txc_state_proc txc 0x7fb30b64a080 io_done
-1> 2016-12-21 15:14:35.536064 7fb2e4ec7700 20 bluestore.BufferSpace(0x7fb318adf418 in 0x7fb302807dc0) finish_write buffer(0x7fb313298f40 space 0x7fb318adf418 0x0~10000 writing nocache)
0> 2016-12-21 15:14:35.552886 7fb2ce69a700 -1 *** Caught signal (Aborted) **
in thread 7fb2ce69a700 thread_name:bstore_kv_sync

ceph version 11.1.0-6122-gdc639fd (dc639fd9e5113d42cae3df6e029b3b0462353579)
1: (()+0x8fd8f2) [0x7fb2f79218f2]
2: (()+0x10340) [0x7fb2f60d6340]
3: (gsignal()+0x39) [0x7fb2f475df79]
4: (abort()+0x148) [0x7fb2f4761388]
5: (()+0x2fe36) [0x7fb2f4756e36]
6: (()+0x2fee2) [0x7fb2f4756ee2]
7: (BlueStore::_balance_bluefs_freespace(std::vector<bluestore_pextent_t, std::allocator<bluestore_pextent_t> >*)+0x163c) [0x7fb2f7840c0c]
8: (BlueStore::_kv_sync_thread()+0x1492) [0x7fb2f78421f2]
9: (BlueStore::KVSyncThread::entry()+0xd) [0x7fb2f7864a1d]
10: (()+0x8182) [0x7fb2f60ce182]
11: (clone()+0x6d) [0x7fb2f482230d]
NOTE: a copy of the executable, or `objdump -rdS <executable>` is needed to interpret this.

--- logging levels ---
0/ 5 none
0/ 0 lockdep
0/ 0 context
0/ 0 crush
0/ 0 mds
0/ 0 mds_balancer
0/ 0 mds_locker
0/ 0 mds_log
0/ 0 mds_log_expire
0/ 0 mds_migrator
0/ 0 buffer
0/ 0 timer
0/ 0 filer
0/ 1 striper
0/ 0 objecter
0/ 0 rados
0/ 0 rbd
0/ 5 rbd_mirror
0/ 5 rbd_replay
0/ 0 journaler
0/ 0 objectcacher
0/ 0 client
0/ 0 osd
0/ 0 optracker
0/ 0 objclass
0/ 0 filestore
0/ 0 journal
0/ 0 ms
0/ 0 mon
0/ 0 monc
0/ 0 paxos
0/ 0 tp
0/ 0 auth
1/ 5 crypto
0/ 0 finisher
0/ 0 heartbeatmap
0/ 0 perfcounter
0/ 0 rgw
1/10 civetweb
1/ 5 javaclient
0/ 0 asok
0/ 0 throttle
0/ 0 refs
1/ 5 xio
1/ 5 compressor
1/ 5 newstore
20/20 bluestore
0/ 0 bluefs
0/ 0 bdev
1/ 5 kstore
0/ 0 rocksdb
4/ 5 leveldb
4/ 5 memdb
1/ 5 kinetic
1/ 5 fuse
1/ 5 mgr
1/ 5 mgrc
1/ 5 dpdk
-2/-2 (syslog threshold)
-1/-1 (stderr threshold)
max_recent 10000
max_new 1000
log_file /var/log/ceph/ceph-osd.0.log
--- end dump of recent events ---
    (1-1/1)