-24> 2014-10-14 11:39:17.294107 7fec40982700 10 osd.13 pg_epoch: 25537 pg[51.0( v 25537'4261760 (25420'4258758,25537'4261760] local-les=25536 n=219 ec=21305 les/c 25536/25536 25535/25535/25535) [13,31] r=0 lpr=25535 crt=25537'4261756 lcod 25537'4261758 mlcod 25537'4261757 active+clean] repop_all_applied: repop tid 757 all applied
-23> 2014-10-14 11:39:17.294113 7fec341b2700 10 osd.13 pg_epoch: 25537 pg[51.65( v 25536'3008473 (25420'3005472,25536'3008473] local-les=25536 n=226 ec=21305 les/c 25536/25536 25535/25535/25535) [13,30] r=0 lpr=25535 crt=25536'3008470 lcod 25536'3008472 mlcod 25536'3008472 active+clean] agent_choose_mode flush_mode: idle evict_mode: idle num_objects: 226 num_bytes: 939794778 num_objects_dirty: 224 num_objects_omap: 0 num_dirty: 224 num_user_objects: 225 pool.info.target_max_bytes: 200000000000 pool.info.target_max_objects: 0
-22> 2014-10-14 11:39:17.294134 7fec341b2700 20 osd.13 pg_epoch: 25537 pg[51.65( v 25536'3008473 (25420'3005472,25536'3008473] local-les=25536 n=226 ec=21305 les/c 25536/25536 25535/25535/25535) [13,30] r=0 lpr=25535 crt=25536'3008470 lcod 25536'3008472 mlcod 25536'3008472 active+clean] agent_choose_mode dirty 0.596145 full 0.598807
-21> 2014-10-14 11:39:17.294123 7fec40982700 10 osd.13 pg_epoch: 25537 pg[51.0( v 25537'4261760 (25420'4258758,25537'4261760] local-les=25536 n=219 ec=21305 les/c 25536/25536 25535/25535/25535) [13,31] r=0 lpr=25535 crt=25537'4261756 lcod 25537'4261758 mlcod 25537'4261757 active+clean] eval_repop repgather(0x13749680 25537'4261760 rep_tid=757 committed?=1 applied?=1 op=osd_op(client.353320.0:11 rbd_data.45132ae8944a.0000000000001a2c [cache-evict] 51.cdeef500 ack+read+ignore_cache+skiprwlocks+ignore_overlay e25537) v4) wants=a
-20> 2014-10-14 11:39:17.294166 7fec341b2700 10 osd.13 pg_epoch: 25537 pg[51.65( v 25536'3008473 (25420'3005472,25536'3008473] local-les=25536 n=226 ec=21305 les/c 25536/25536 25535/25535/25535) [13,30] r=0 lpr=25535 crt=25536'3008470 lcod 25536'3008472 mlcod 25536'3008472 active+clean] sending redirect to pool 47 for op 0x3bd2940
-19> 2014-10-14 11:39:17.294180 7fec40982700 15 osd.13 pg_epoch: 25537 pg[51.0( v 25537'4261760 (25420'4258758,25537'4261760] local-les=25536 n=219 ec=21305 les/c 25536/25536 25535/25535/25535) [13,31] r=0 lpr=25535 crt=25537'4261756 lcod 25537'4261758 mlcod 25537'4261757 active+clean] log_op_stats osd_op(client.353320.0:11 rbd_data.45132ae8944a.0000000000001a2c [cache-evict] 51.cdeef500 ack+read+ignore_cache+skiprwlocks+ignore_overlay e25537) v4 inb 826 outb 0 rlat 0.000000 lat 0.005433
-18> 2014-10-14 11:39:17.294184 7fec341b2700 1 -- 192.168.50.1:6807/32153 --> 192.168.50.2:0/1013740 -- osd_op_reply(62454 rbd_data.53b752ae8944a.000000000000dbda [set-alloc-hint object_size 4194304 write_size 4194304,write 0~4194304] v0'0 uv0 ondisk = -2 ((2) No such file or directory) redirect: { object , locator{@47} }) v6 -- ?+0 0x11c82580 con 0x10cdb5a0
-17> 2014-10-14 11:39:17.294208 7fec40982700 15 osd.13 pg_epoch: 25537 pg[51.0( v 25537'4261760 (25420'4258758,25537'4261760] local-les=25536 n=219 ec=21305 les/c 25536/25536 25535/25535/25535) [13,31] r=0 lpr=25535 crt=25537'4261756 lcod 25537'4261758 mlcod 25537'4261757 active+clean] publish_stats_to_osd 25537:8292246
-16> 2014-10-14 11:39:17.294239 7fec341b2700 10 osd.13 25537 dequeue_op 0x3bd2940 finish
-15> 2014-10-14 11:39:17.294225 7fec40982700 10 osd.13 pg_epoch: 25537 pg[51.0( v 25537'4261760 (25420'4258758,25537'4261760] local-les=25536 n=219 ec=21305 les/c 25536/25536 25535/25535/25535) [13,31] r=0 lpr=25535 crt=25537'4261756 lcod 25537'4261758 mlcod 25537'4261757 active+clean] sending ack on repgather(0x13749680 25537'4261760 rep_tid=757 committed?=1 applied?=1 op=osd_op(client.353320.0:11 rbd_data.45132ae8944a.0000000000001a2c [cache-evict] 51.cdeef500 ack+read+ignore_cache+skiprwlocks+ignore_overlay e25537) v4) 0x11c85280
-14> 2014-10-14 11:39:17.294248 7fec40982700 1 -- 192.168.50.1:6807/32153 --> 192.168.50.1:0/1032885 -- osd_op_reply(11 rbd_data.45132ae8944a.0000000000001a2c [cache-evict] v25537'4261760 uv4664758 ack = 0) v6 -- ?+0 0x11c85280 con 0x110f1600
-13> 2014-10-14 11:39:17.295021 7fec3c3bf700 1 -- 192.168.50.1:6807/32153 <== client.353320 192.168.50.1:0/1032885 12 ==== osd_op(client.353320.0:12 rbd_data.c1362ae8944a.0000000000017a4c [cache-flush] 51.a2a52800 ack+read+ignore_cache+ignore_overlay e25537) v4 ==== 189+0+0 (3027131648 0 0) 0x112baf40 con 0x110f1600
-12> 2014-10-14 11:39:17.295071 7fec3c3bf700 10 osd.13 25537 do_waiters -- start
-11> 2014-10-14 11:39:17.295077 7fec3c3bf700 10 osd.13 25537 do_waiters -- finish
-10> 2014-10-14 11:39:17.295080 7fec3c3bf700 20 osd.13 25537 _dispatch 0x112baf40 osd_op(client.353320.0:12 rbd_data.c1362ae8944a.0000000000017a4c [cache-flush] 51.a2a52800 ack+read+ignore_cache+ignore_overlay e25537) v4
-9> 2014-10-14 11:39:17.295101 7fec3c3bf700 15 osd.13 25537 require_same_or_newer_map 25537 (i am 25537) 0x112baf40
-8> 2014-10-14 11:39:17.295107 7fec3c3bf700 20 osd.13 25537 _share_map_incoming client.353320 192.168.50.1:0/1032885 25537
-7> 2014-10-14 11:39:17.295126 7fec3c3bf700 15 osd.13 25537 enqueue_op 0x3bd12c0 prio 63 cost 0 latency 0.000255 osd_op(client.353320.0:12 rbd_data.c1362ae8944a.0000000000017a4c [cache-flush] 51.a2a52800 ack+read+ignore_cache+ignore_overlay e25537) v4
-6> 2014-10-14 11:39:17.295152 7fec3c3bf700 10 osd.13 25537 do_waiters -- start
-5> 2014-10-14 11:39:17.295156 7fec3c3bf700 10 osd.13 25537 do_waiters -- finish
-4> 2014-10-14 11:39:17.310529 7fec48a8b700 5 osd.13 25537 tick
-3> 2014-10-14 11:39:17.310579 7fec48a8b700 20 osd.13 25537 scrub_random_backoff lost coin flip, randomly backing off
-2> 2014-10-14 11:39:17.310585 7fec48a8b700 10 osd.13 25537 do_waiters -- start
-1> 2014-10-14 11:39:17.310588 7fec48a8b700 10 osd.13 25537 do_waiters -- finish
0> 2014-10-14 11:39:17.323474 7fec40982700 -1 osd/ReplicatedPG.h: In function 'void ReplicatedPG::release_op_ctx_locks(ReplicatedPG::OpContext*)' thread 7fec40982700 time 2014-10-14 11:39:17.294292
osd/ReplicatedPG.h: 762: FAILED assert(ctx->release_snapset_obc == false)
ceph version 0.80.6 (f93610a4421cb670b08e974c6550ee715ac528ae)
1: (ReplicatedPG::release_op_ctx_locks(ReplicatedPG::OpContext*)+0x4eb) [0x8ca0bb]
2: (ReplicatedPG::eval_repop(ReplicatedPG::RepGather*)+0x2f4) [0x84e664]
3: (ReplicatedPG::repop_all_applied(ReplicatedPG::RepGather*)+0xab) [0x84f91b]
4: (Context::complete(int)+0x9) [0x666639]
5: (ReplicatedBackend::op_applied(ReplicatedBackend::InProgressOp*)+0x28c) [0x983d1c]
6: (Context::complete(int)+0x9) [0x666639]
7: (ReplicatedPG::BlessedContext::finish(int)+0x4e) [0x8c810e]
8: (Context::complete(int)+0x9) [0x666639]
9: (finish_contexts(CephContext*, std::list<Context*, std::allocator<Context*> >&, int)+0x95) [0x6707b5]
10: (Context::complete(int)+0x9) [0x666639]
11: (Finisher::finisher_thread_entry()+0x1d8) [0x9ec248]
12: (()+0x79d1) [0x7fec4f2a69d1]
13: (clone()+0x6d) [0x7fec4e23686d]
NOTE: a copy of the executable, or `objdump -rdS <executable>` is needed to interpret this.