Ceph » rbd

[ RUN      ] TestLibRBD.ObjectMapConsistentSnap
using new format!
test/librbd/test_librbd.cc:2868: Failure
Value of: passed
  Actual: false
Expected: true
[  FAILED  ] TestLibRBD.ObjectMapConsistentSnap (236 ms)

Appears to be a race condition between updating the object map and refreshing it:

2015-09-30 16:00:37.785812 7f7e667fc700 20 librbd:  oid rbd_data.0573fd766.0000000000000001 0~3 from [0,3]
2015-09-30 16:00:37.785852 7f7e667fc700 20 librbdwriteback: write will wait for result 0x7f7e5c0d5f00
2015-09-30 16:00:37.785928 7f7e667fc700 10 librbd::ImageCtx: prune_parent_extents image overlap 0, object overlap 0 from image extents []
2015-09-30 16:00:37.785933 7f7e667fc700 20 librbd::AioRequest: send write 0x7f7e5c0c5a60 rbd_data.0573fd766.0000000000000001 0~3
2015-09-30 16:00:37.785964 7f7e667fc700 20 librbd::AioRequest: send_pre 0x7f7e5c0c5a60 rbd_data.0573fd766.0000000000000001 0~3
2015-09-30 16:00:37.785984 7f7e79f76700 10 librbd::ImageWatcher: 0x56054e875ba0 image header updated
2015-09-30 16:00:37.786023 7f7e667fc700 20 librbd::ObjectMap: 0x56054d342fb0 aio_update: start=1, end=2, ->1
2015-09-30 16:00:37.786046 7f7e667fc700 20 librbd::ObjectMap: 0x56054d342fb0 updating object map: [1,2) = ->1
2015-09-30 16:00:37.786150 7f7e667fc700 20 librbd::AioCompletion: AioCompletion::finish_adding_requests 0x7f7e5c131250 pending 1
2015-09-30 16:00:37.786342 7f7e7e64d7c0 20 librbd: snap_create 0x56054d342fb0 snap1
2015-09-30 16:00:37.786365 7f7e7e64d7c0 20 librbd: ictx_check 0x56054d342fb0
2015-09-30 16:00:37.786400 7f7e7e64d7c0 20 librbd: ictx_refresh 0x56054d342fb0
2015-09-30 16:00:37.786867 7f7e7e64d7c0 20 librbd: new snapshot id=2 name=snap0 size=1048576
2015-09-30 16:00:37.786933 7f7e7e64d7c0 10 librbd::ObjectMap: 0x56054d342fb0 refreshing object map
2015-09-30 16:00:37.788122 7f7e77771700 20 librbd::ObjectMap: 0x56054d342fb0 should_complete: r=0
2015-09-30 16:00:37.794103 7f7e7e64d7c0  0 <cls> cls/rbd/cls_rbd.cc:2071: failed to decode object map: buffer::malformed_input: invalid data
 block CRC
2015-09-30 16:00:37.794171 7f7e7e64d7c0 -1 librbd::ObjectMap: 0x56054d342fb0 invalidating object map
2015-09-30 16:00:37.800389 7f7e7e64d7c0 20 librbd::ObjectMap: refreshed object map: 256
2015-09-30 16:00:37.800477 7f7e7e64d7c0 20 librbd::ImageCtx: waiting for cache to be flushed