Ceph » RADOS

2017-09-22T15:42:41.943 INFO:tasks.workunit.client.0.smithi157.stderr:/home/ubuntu/cephtest/clone.client.0/qa/standalone/scrub/osd-scrub-snaps.sh:166: TEST_scrub_snaps:  grep log_channel td/osd-scrub-snaps/osd.0.log
2017-09-22T15:42:41.946 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.810632 7f9ead75d700  0 log_channel(cluster) log [DBG] : 2.0 scrub starts
2017-09-22T15:42:41.946 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.813731 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:c52c9666:::obj5:7 no '_' attr
2017-09-22T15:42:41.946 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.813740 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:c52c9666:::obj5:7 is an unexpected clone
2017-09-22T15:42:41.947 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.813809 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:c52c9666:::obj5:4 on disk size (4608) does not match object info size (512) adjusted for ondisk to (512)
2017-09-22T15:42:41.947 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.813903 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:c52c9666:::obj5:head expected clone 2:c52c9666:::obj5:2 1 missing
2017-09-22T15:42:41.947 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.813909 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:c52c9666:::obj5:head expected clone 2:c52c9666:::obj5:1 2 missing
2017-09-22T15:42:41.947 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.813915 7f9ead75d700  0 log_channel(cluster) log [INF] : scrub 2.0 2:c52c9666:::obj5:head 2 missing clone(s)
2017-09-22T15:42:41.947 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814065 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:ab946124:::obj15:head can't decode 'snapset' attr buffer::malformed_input: void SnapSet::decode(ceph::buffer::list::iterator&) no longer understand old encoding version 3 < struct_compat
2017-09-22T15:42:41.947 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814205 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:a557efb1:::obj8:head snaps.seq not set
2017-09-22T15:42:41.947 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814299 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:9a09113a:::obj7:1 is an unexpected clone
2017-09-22T15:42:41.947 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814313 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:8dd16f86:::obj3:head on disk size (3840) does not match object info size (768) adjusted for ondisk to (768)
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814503 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:233a42c1:::obj6:1 is an unexpected clone
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814562 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:104778fc:::obj2:head no 'snapset' attr
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814587 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:104778fc:::obj2:7 clone ignored due to missing snapset
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814630 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:104778fc:::obj2:4 clone ignored due to missing snapset
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814708 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:0ee9ae15:::obj4:head expected clone 2:0ee9ae15:::obj4:7 1 missing
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.814714 7f9ead75d700  0 log_channel(cluster) log [INF] : scrub 2.0 2:0ee9ae15:::obj4:head 1 missing clone(s)
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.816078 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:ff7b1f36:::obj1:1 is an unexpected clone
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.816173 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:fe351e27:::obj9:1 is missing in clone_size
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.816237 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:eeae2a94:::obj11:1 is an unexpected clone
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.816355 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:e0a44829:::obj14:1 size 1032 != clone_size 1033
2017-09-22T15:42:41.948 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.816460 7f9ead75d700 -1 log_channel(cluster) log [ERR] : scrub 2.0 2:d1337354:::obj10:1 is missing in clone_overlap
2017-09-22T15:42:41.949 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.816600 7f9ead75d700 -1 log_channel(cluster) log [ERR] : 2.0 scrub stat mismatch, got 24/34 objects, 10/19 clones, 24/34 dirty, 0/0 omap, 0/0 pinned, 0/0 hit_set_archive, 2/2 whiteouts, 12593/23416 bytes, 0/0 hit_set_archive bytes.
2017-09-22T15:42:41.949 INFO:tasks.workunit.client.0.smithi157.stdout:2017-09-22 15:42:35.816617 7f9ead75d700 -1 log_channel(cluster) log [ERR] : 2.0 scrub 20 errors

/a/sage-2017-09-22_14:27:10-rados-wip-sage-testing-2017-09-21-2230-distro-basic-smithi/1658080

looks like wip-kill-snapset's fault, not sure why that test run didn't catch it.