Also, is this ceph_test_rados rewriting objects quickly? I think that
the issue is with rewriting objects so if we can tailor the
ceph_test_rados to do that, it might be easier to reproduce.
----------------
Robert LeBlanc
PGP Fingerprint 79A2 9CA4 6CC4 45DD A904 C70E E654 3BB2 FA62 B9F1
On Thu, Mar 17, 2016 at 11:05 AM, Robert LeBlanc <robert@xxxxxxxxxxxxx> wrote:
> I'll miss the Ceph community as well. There was a few things I really
> wanted to work in with Ceph.
>
> I got this:
>
> update_object_version oid 13 v 1166 (ObjNum 1028 snap 0 seq_num 1028)
> dirty exists
> 1038: left oid 13 (ObjNum 1028 snap 0 seq_num 1028)
> 1040: finishing write tid 1 to nodez23350-256
> 1040: finishing write tid 2 to nodez23350-256
> 1040: finishing write tid 3 to nodez23350-256
> 1040: finishing write tid 4 to nodez23350-256
> 1040: finishing write tid 6 to nodez23350-256
> 1035: done (4 left)
> 1037: done (3 left)
> 1038: done (2 left)
> 1043: read oid 430 snap -1
> 1043: expect (ObjNum 429 snap 0 seq_num 429)
> 1040: finishing write tid 7 to nodez23350-256
> update_object_version oid 256 v 661 (ObjNum 1029 snap 0 seq_num 1029)
> dirty exists
> 1040: left oid 256 (ObjNum 1029 snap 0 seq_num 1029)
> 1042: expect (ObjNum 664 snap 0 seq_num 664)
> 1043: Error: oid 430 read returned error code -2
> ./test/osd/RadosModel.h: In function 'virtual void
> ReadOp::_finish(TestOp::CallbackInfo*)' thread 7fa1bf7fe700 time
> 2016-03-17 10:47:19.085414
> ./test/osd/RadosModel.h: 1109: FAILED assert(0)
> ceph version 0.94.6 (e832001feaf8c176593e0325c8298e3f16dfb403)
> 1: (ceph::__ceph_assert_fail(char const*, char const*, int, char
> const*)+0x76) [0x4db956]
> 2: (ReadOp::_finish(TestOp::CallbackInfo*)+0xec) [0x4c959c]
> 3: (()+0x9791d) [0x7fa1d472191d]
> 4: (()+0x72519) [0x7fa1d46fc519]
> 5: (()+0x13c178) [0x7fa1d47c6178]
> 6: (()+0x80a4) [0x7fa1d425a0a4]
> 7: (clone()+0x6d) [0x7fa1d2bd504d]
> NOTE: a copy of the executable, or `objdump -rdS <executable>` is
> needed to interpret this.
> terminate called after throwing an instance of 'ceph::FailedAssertion'
> Aborted
>
> I had to toggle writeback/forward and min_read_recency_for_promote a
> few times to get it, but I don't know if it is because I only have one
> job running. Even with six jobs running, it is not easy to trigger
> with ceph_test_rados, but it is very instant in the RBD VMs.
>
> Here are the six run crashes (I have about the last 2000 lines of each
> if needed):
>
> nodev:
> update_object_version oid 1015 v 1255 (ObjNum 1014 snap 0 seq_num
> 1014) dirty exists
> 1015: left oid 1015 (ObjNum 1014 snap 0 seq_num 1014)
> 1016: finishing write tid 1 to nodev21799-1016
> 1016: finishing write tid 2 to nodev21799-1016
> 1016: finishing write tid 3 to nodev21799-1016
> 1016: finishing write tid 4 to nodev21799-1016
> 1016: finishing write tid 6 to nodev21799-1016
> 1016: finishing write tid 7 to nodev21799-1016
> update_object_version oid 1016 v 1957 (ObjNum 1015 snap 0 seq_num
> 1015) dirty exists
> 1016: left oid 1016 (ObjNum 1015 snap 0 seq_num 1015)
> 1017: finishing write tid 1 to nodev21799-1017
> 1017: finishing write tid 2 to nodev21799-1017
> 1017: finishing write tid 3 to nodev21799-1017
> 1017: finishing write tid 5 to nodev21799-1017
> 1017: finishing write tid 6 to nodev21799-1017
> update_object_version oid 1017 v 1010 (ObjNum 1016 snap 0 seq_num
> 1016) dirty exists
> 1017: left oid 1017 (ObjNum 1016 snap 0 seq_num 1016)
> 1018: finishing write tid 1 to nodev21799-1018
> 1018: finishing write tid 2 to nodev21799-1018
> 1018: finishing write tid 3 to nodev21799-1018
> 1018: finishing write tid 4 to nodev21799-1018
> 1018: finishing write tid 6 to nodev21799-1018
> 1018: finishing write tid 7 to nodev21799-1018
> update_object_version oid 1018 v 1093 (ObjNum 1017 snap 0 seq_num
> 1017) dirty exists
> 1018: left oid 1018 (ObjNum 1017 snap 0 seq_num 1017)
> 1019: finishing write tid 1 to nodev21799-1019
> 1019: finishing write tid 2 to nodev21799-1019
> 1019: finishing write tid 3 to nodev21799-1019
> 1019: finishing write tid 5 to nodev21799-1019
> 1019: finishing write tid 6 to nodev21799-1019
> update_object_version oid 1019 v 462 (ObjNum 1018 snap 0 seq_num 1018)
> dirty exists
> 1019: left oid 1019 (ObjNum 1018 snap 0 seq_num 1018)
> 1021: finishing write tid 1 to nodev21799-1021
> 1020: finishing write tid 1 to nodev21799-1020
> 1020: finishing write tid 2 to nodev21799-1020
> 1020: finishing write tid 3 to nodev21799-1020
> 1020: finishing write tid 5 to nodev21799-1020
> 1020: finishing write tid 6 to nodev21799-1020
> update_object_version oid 1020 v 1287 (ObjNum 1019 snap 0 seq_num
> 1019) dirty exists
> 1020: left oid 1020 (ObjNum 1019 snap 0 seq_num 1019)
> 1021: finishing write tid 2 to nodev21799-1021
> 1021: finishing write tid 3 to nodev21799-1021
> 1021: finishing write tid 5 to nodev21799-1021
> 1021: finishing write tid 6 to nodev21799-1021
> update_object_version oid 1021 v 1077 (ObjNum 1020 snap 0 seq_num
> 1020) dirty exists
> 1021: left oid 1021 (ObjNum 1020 snap 0 seq_num 1020)
> 1022: finishing write tid 1 to nodev21799-1022
> 1022: finishing write tid 2 to nodev21799-1022
> 1022: finishing write tid 3 to nodev21799-1022
> 1022: finishing write tid 5 to nodev21799-1022
> 1022: finishing write tid 6 to nodev21799-1022
> update_object_version oid 1022 v 1213 (ObjNum 1021 snap 0 seq_num
> 1021) dirty exists
> 1022: left oid 1022 (ObjNum 1021 snap 0 seq_num 1021)
> 1023: finishing write tid 1 to nodev21799-1023
> 1023: finishing write tid 2 to nodev21799-1023
> 1023: finishing write tid 3 to nodev21799-1023
> 1023: finishing write tid 5 to nodev21799-1023
> 1023: finishing write tid 6 to nodev21799-1023
> update_object_version oid 1023 v 2612 (ObjNum 1022 snap 0 seq_num
> 1022) dirty exists
> 1023: left oid 1023 (ObjNum 1022 snap 0 seq_num 1022)
> 1024: finishing write tid 1 to nodev21799-1024
> 1025: Error: oid 219 read returned error code -2
> ./test/osd/RadosModel.h: In function 'virtual void
> ReadOp::_finish(TestOp::CallbackInfo*)' thread 7f0df8a16700 time
> 2016-03-17 10:53:43.493575
> ./test/osd/RadosModel.h: 1109: FAILED assert(0)
> ceph version 0.94.6 (e832001feaf8c176593e0325c8298e3f16dfb403)
> 1: (ceph::__ceph_assert_fail(char const*, char const*, int, char
> const*)+0x76) [0x4db956]
> 2: (ReadOp::_finish(TestOp::CallbackInfo*)+0xec) [0x4c959c]
> 3: (()+0x9791d) [0x7f0e015dd91d]
> 4: (()+0x72519) [0x7f0e015b8519]
> 5: (()+0x13c178) [0x7f0e01682178]
> 6: (()+0x80a4) [0x7f0e011160a4]
> 7: (clone()+0x6d) [0x7f0dffa9104d]
> NOTE: a copy of the executable, or `objdump -rdS <executable>` is
> needed to interpret this.
> terminate called after throwing an instance of 'ceph::FailedAssertion'
> Aborted
>
> nodew:
> 1117: expect (ObjNum 8 snap 0 seq_num 8)
> 1120: expect (ObjNum 95 snap 0 seq_num 95)
> 1121: expect (ObjNum 994 snap 0 seq_num 994)
> 1113: expect (ObjNum 362 snap 0 seq_num 362)
> 1118: expect (ObjNum 179 snap 0 seq_num 179)
> 1115: expect (ObjNum 943 snap 0 seq_num 943)
> 1119: expect (ObjNum 250 snap 0 seq_num 250)
> 1124: finishing write tid 1 to nodew21820-361
> 1124: finishing write tid 2 to nodew21820-361
> 1124: finishing write tid 3 to nodew21820-361
> 1124: finishing write tid 4 to nodew21820-361
> 1124: finishing write tid 6 to nodew21820-361
> 1124: finishing write tid 7 to nodew21820-361
> update_object_version oid 361 v 892 (ObjNum 1061 snap 0 seq_num 1061)
> dirty exists
> 1124: left oid 361 (ObjNum 1061 snap 0 seq_num 1061)
> 1125: finishing write tid 1 to nodew21820-486
> 1125: finishing write tid 2 to nodew21820-486
> 1125: finishing write tid 3 to nodew21820-486
> 1125: finishing write tid 5 to nodew21820-486
> 1125: finishing write tid 6 to nodew21820-486
> update_object_version oid 486 v 1317 (ObjNum 1062 snap 0 seq_num 1062)
> dirty exists
> 1125: left oid 486 (ObjNum 1062 snap 0 seq_num 1062)
> 1126: expect (ObjNum 289 snap 0 seq_num 289)
> 1127: finishing write tid 1 to nodew21820-765
> 1127: finishing write tid 2 to nodew21820-765
> 1127: finishing write tid 3 to nodew21820-765
> 1127: finishing write tid 5 to nodew21820-765
> 1127: finishing write tid 6 to nodew21820-765
> update_object_version oid 765 v 1156 (ObjNum 1063 snap 0 seq_num 1063)
> dirty exists
> 1127: left oid 765 (ObjNum 1063 snap 0 seq_num 1063)
> 1128: finishing write tid 1 to nodew21820-40
> 1128: finishing write tid 2 to nodew21820-40
> 1128: finishing write tid 3 to nodew21820-40
> 1128: finishing write tid 5 to nodew21820-40
> 1128: finishing write tid 6 to nodew21820-40
> update_object_version oid 40 v 876 (ObjNum 1064 snap 0 seq_num 1064)
> dirty exists
> 1128: left oid 40 (ObjNum 1064 snap 0 seq_num 1064)
> 1129: expect (ObjNum 616 snap 0 seq_num 616)
> 1110: done (14 left)
> 1113: done (13 left)
> 1115: done (12 left)
> 1117: done (11 left)
> 1118: done (10 left)
> 1119: done (9 left)
> 1120: done (8 left)
> 1121: done (7 left)
> 1124: done (6 left)
> 1125: done (5 left)
> 1126: done (4 left)
> 1127: done (3 left)
> 1128: done (2 left)
> 1129: done (1 left)
> 1131: read oid 29 snap -1
> 1131: expect (ObjNum 28 snap 0 seq_num 28)
> 1132: read oid 764 snap -1
> 1132: expect (ObjNum 763 snap 0 seq_num 763)
> 1133: read oid 469 snap -1
> 1133: expect (ObjNum 468 snap 0 seq_num 468)
> 1134: write oid 243 current snap is 0
> 1134: seq_num 1065 ranges
> {483354=596553,1514502=531232,2509844=632287,3283353=1}
> 1134: writing nodew21820-243 from 483354 to 1079907 tid 1
> 1134: writing nodew21820-243 from 1514502 to 2045734 tid 2
> 1134: writing nodew21820-243 from 2509844 to 3142131 tid 3
> 1134: writing nodew21820-243 from 3283353 to 3283354 tid 4
> 1135: read oid 569 snap -1
> 1135: expect (ObjNum 568 snap 0 seq_num 568)
> 1133: Error: oid 469 read returned error code -2
> ./test/osd/RadosModel.h: In function 'virtual void
> ReadOp::_finish(TestOp::CallbackInfo*)' thread 7fae71d03700 time
> 2016-03-17 11:00:02.124951
> ./test/osd/RadosModel.h: 1109: FAILED assert(0)
> ceph version 0.94.6 (e832001feaf8c176593e0325c8298e3f16dfb403)
> 1: (ceph::__ceph_assert_fail(char const*, char const*, int, char
> const*)+0x76) [0x4db956]
> 2: (ReadOp::_finish(TestOp::CallbackInfo*)+0xec) [0x4c959c]
> 3: (()+0x9791d) [0x7fae7a8ca91d]
> 4: (()+0x72519) [0x7fae7a8a5519]
> 5: (()+0x13c178) [0x7fae7a96f178]
> 6: (()+0x80a4) [0x7fae7a4030a4]
> 7: (clone()+0x6d) [0x7fae78d7e04d]
> NOTE: a copy of the executable, or `objdump -rdS <executable>` is
> needed to interpret this.
> terminate called after throwing an instance of 'ceph::FailedAssertion'
> Aborted
>
> nodex:
>
> 1024: finishing write tid 1 to nodex22014-1024
> 1025: expect (ObjNum 75 snap 0 seq_num 75)
> 1024: finishing write tid 2 to nodex22014-1024
> 1024: finishing write tid 3 to nodex22014-1024
> 1024: finishing write tid 5 to nodex22014-1024
> 1024: finishing write tid 6 to nodex22014-1024
> update_object_version oid 1024 v 753 (ObjNum 1023 snap 0 seq_num 1023)
> dirty exists
> 1024: left oid 1024 (ObjNum 1023 snap 0 seq_num 1023)
> 982: done (44 left)
> 983: done (43 left)
> 984: done (42 left)
> 985: done (41 left)
> 986: done (40 left)
> 987: done (39 left)
> 988: done (38 left)
> 989: done (37 left)
> 990: done (36 left)
> 991: done (35 left)
> 992: done (34 left)
> 993: done (33 left)
> 994: done (32 left)
> 995: done (31 left)
> 996: done (30 left)
> 997: done (29 left)
> 998: done (28 left)
> 999: done (27 left)
> 1000: done (26 left)
> 1001: done (25 left)
> 1002: done (24 left)
> 1003: done (23 left)
> 1004: done (22 left)
> 1005: done (21 left)
> 1006: done (20 left)
> 1007: done (19 left)
> 1008: done (18 left)
> 1009: done (17 left)
> 1010: done (16 left)
> 1011: done (15 left)
> 1012: done (14 left)
> 1013: done (13 left)
> 1014: done (12 left)
> 1015: done (11 left)
> 1016: done (10 left)
> 1017: done (9 left)
> 1018: done (8 left)
> 1019: done (7 left)
> 1020: done (6 left)
> 1021: done (5 left)
> 1022: done (4 left)
> 1023: done (3 left)
> 1024: done (2 left)
> 1025: done (1 left)
> 1026: done (0 left)
> 1027: delete oid 101 current snap is 0
> 1027: done (0 left)
> 1028: read oid 156 snap -1
> 1028: expect (ObjNum 155 snap 0 seq_num 155)
> 1029: read oid 691 snap -1
> 1029: expect (ObjNum 690 snap 0 seq_num 690)
> 1030: read oid 282 snap -1
> 1030: expect (ObjNum 281 snap 0 seq_num 281)
> 1031: read oid 979 snap -1
> 1031: expect (ObjNum 978 snap 0 seq_num 978)
> 1032: read oid 203 snap -1
> 1032: expect (ObjNum 202 snap 0 seq_num 202)
> 1033: setattr oid 464 current snap is 0
> 1032: Error: oid 203 read returned error code -2
> ./test/osd/RadosModel.h: In function 'virtual void
> ReadOp::_finish(TestOp::CallbackInfo*)' thread 7fafee64a700 time
> 2016-03-17 10:53:44.291343
> ./test/osd/RadosModel.h: 1109: FAILED assert(0)
> ceph version 0.94.6 (e832001feaf8c176593e0325c8298e3f16dfb403)
> 1: (ceph::__ceph_assert_fail(char const*, char const*, int, char
> const*)+0x76) [0x4db956]
> 2: (ReadOp::_finish(TestOp::CallbackInfo*)+0xec) [0x4c959c]
> 3: (()+0x9791d) [0x7faff721191d]
> 4: (()+0x72519) [0x7faff71ec519]
> 5: (()+0x13c178) [0x7faff72b6178]
> 6: (()+0x80a4) [0x7faff6d4a0a4]
> 7: (clone()+0x6d) [0x7faff56c504d]
> NOTE: a copy of the executable, or `objdump -rdS <executable>` is
> needed to interpret this.
> terminate called after throwing an instance of 'ceph::FailedAssertion'
> Aborted
>
> nodey:
>
> 974: done (52 left)
> 975: done (51 left)
> 976: done (50 left)
> 977: done (49 left)
> 978: done (48 left)
> 979: done (47 left)
> 980: done (46 left)
> 981: done (45 left)
> 982: done (44 left)
> 983: done (43 left)
> 984: done (42 left)
> 985: done (41 left)
> 986: done (40 left)
> 987: done (39 left)
> 988: done (38 left)
> 989: done (37 left)
> 990: done (36 left)
> 991: done (35 left)
> 992: done (34 left)
> 993: done (33 left)
> 994: done (32 left)
> 995: done (31 left)
> 996: done (30 left)
> 997: done (29 left)
> 998: done (28 left)
> 999: done (27 left)
> 1000: done (26 left)
> 1001: done (25 left)
> 1002: done (24 left)
> 1003: done (23 left)
> 1004: done (22 left)
> 1005: done (21 left)
> 1006: done (20 left)
> 1007: done (19 left)
> 1008: done (18 left)
> 1009: done (17 left)
> 1010: done (16 left)
> 1011: done (15 left)
> 1012: done (14 left)
> 1013: done (13 left)
> 1014: done (12 left)
> 1015: done (11 left)
> 1016: done (10 left)
> 1017: done (9 left)
> 1018: done (8 left)
> 1019: done (7 left)
> 1020: done (6 left)
> 1021: done (5 left)
> 1022: done (4 left)
> 1023: done (3 left)
> 1024: done (2 left)
> 1025: done (1 left)
> 1026: done (0 left)
> 1027: delete oid 101 current snap is 0
> 1027: done (0 left)
> 1028: read oid 156 snap -1
> 1028: expect (ObjNum 155 snap 0 seq_num 155)
> 1029: read oid 691 snap -1
> 1029: expect (ObjNum 690 snap 0 seq_num 690)
> 1030: read oid 282 snap -1
> 1030: expect (ObjNum 281 snap 0 seq_num 281)
> 1031: read oid 979 snap -1
> 1031: expect (ObjNum 978 snap 0 seq_num 978)
> 1032: read oid 203 snap -1
> 1032: expect (ObjNum 202 snap 0 seq_num 202)
> 1033: setattr oid 464 current snap is 0
> 1028: Error: oid 156 read returned error code -2
> ./test/osd/RadosModel.h: In function 'virtual void
> ReadOp::_finish(TestOp::CallbackInfo*)' thread 7f55fa3c6700 time
> 2016-03-17 10:53:57.082571
> ./test/osd/RadosModel.h: 1109: FAILED assert(0)
> ceph version 0.94.6 (e832001feaf8c176593e0325c8298e3f16dfb403)
> 1: (ceph::__ceph_assert_fail(char const*, char const*, int, char
> const*)+0x76) [0x4db956]
> 2: (ReadOp::_finish(TestOp::CallbackInfo*)+0xec) [0x4c959c]
> 3: (()+0x9791d) [0x7f5602f8d91d]
> 4: (()+0x72519) [0x7f5602f68519]
> 5: (()+0x13c178) [0x7f5603032178]
> 6: (()+0x80a4) [0x7f5602ac60a4]
> 7: (clone()+0x6d) [0x7f560144104d]
> NOTE: a copy of the executable, or `objdump -rdS <executable>` is
> needed to interpret this.
> terminate called after throwing an instance of 'ceph::FailedAssertion'
> Aborted
>
> nodez:
>
> 1014: done (11 left)
> 1026: delete oid 717 current snap is 0
> 1015: finishing write tid 2 to nodez24249-1015
> 1015: finishing write tid 4 to nodez24249-1015
> 1015: finishing write tid 5 to nodez24249-1015
> update_object_version oid 1015 v 3003 (ObjNum 1014 snap 0 seq_num
> 1014) dirty exists
> 1015: left oid 1015 (ObjNum 1014 snap 0 seq_num 1014)
> 1016: finishing write tid 1 to nodez24249-1016
> 1016: finishing write tid 2 to nodez24249-1016
> 1016: finishing write tid 3 to nodez24249-1016
> 1016: finishing write tid 4 to nodez24249-1016
> 1016: finishing write tid 6 to nodez24249-1016
> 1016: finishing write tid 7 to nodez24249-1016
> update_object_version oid 1016 v 1201 (ObjNum 1015 snap 0 seq_num
> 1015) dirty exists
> 1016: left oid 1016 (ObjNum 1015 snap 0 seq_num 1015)
> 1017: finishing write tid 1 to nodez24249-1017
> 1017: finishing write tid 2 to nodez24249-1017
> 1017: finishing write tid 3 to nodez24249-1017
> 1017: finishing write tid 5 to nodez24249-1017
> 1017: finishing write tid 6 to nodez24249-1017
> update_object_version oid 1017 v 3007 (ObjNum 1016 snap 0 seq_num
> 1016) dirty exists
> 1017: left oid 1017 (ObjNum 1016 snap 0 seq_num 1016)
> 1018: finishing write tid 1 to nodez24249-1018
> 1018: finishing write tid 2 to nodez24249-1018
> 1018: finishing write tid 3 to nodez24249-1018
> 1018: finishing write tid 4 to nodez24249-1018
> 1018: finishing write tid 6 to nodez24249-1018
> 1018: finishing write tid 7 to nodez24249-1018
> update_object_version oid 1018 v 1283 (ObjNum 1017 snap 0 seq_num
> 1017) dirty exists
> 1018: left oid 1018 (ObjNum 1017 snap 0 seq_num 1017)
> 1019: finishing write tid 1 to nodez24249-1019
> 1019: finishing write tid 2 to nodez24249-1019
> 1019: finishing write tid 3 to nodez24249-1019
> 1019: finishing write tid 5 to nodez24249-1019
> 1019: finishing write tid 6 to nodez24249-1019
> update_object_version oid 1019 v 999 (ObjNum 1018 snap 0 seq_num 1018)
> dirty exists
> 1019: left oid 1019 (ObjNum 1018 snap 0 seq_num 1018)
> 1020: finishing write tid 1 to nodez24249-1020
> 1020: finishing write tid 2 to nodez24249-1020
> 1020: finishing write tid 3 to nodez24249-1020
> 1020: finishing write tid 5 to nodez24249-1020
> 1020: finishing write tid 6 to nodez24249-1020
> update_object_version oid 1020 v 813 (ObjNum 1019 snap 0 seq_num 1019)
> dirty exists
> 1020: left oid 1020 (ObjNum 1019 snap 0 seq_num 1019)
> 1021: finishing write tid 1 to nodez24249-1021
> 1021: finishing write tid 2 to nodez24249-1021
> 1021: finishing write tid 3 to nodez24249-1021
> 1021: finishing write tid 5 to nodez24249-1021
> 1021: finishing write tid 6 to nodez24249-1021
> update_object_version oid 1021 v 1038 (ObjNum 1020 snap 0 seq_num
> 1020) dirty exists
> 1021: left oid 1021 (ObjNum 1020 snap 0 seq_num 1020)
> 1022: finishing write tid 1 to nodez24249-1022
> 1022: finishing write tid 2 to nodez24249-1022
> 1022: finishing write tid 3 to nodez24249-1022
> 1022: finishing write tid 5 to nodez24249-1022
> 1022: finishing write tid 6 to nodez24249-1022
> update_object_version oid 1022 v 781 (ObjNum 1021 snap 0 seq_num 1021)
> dirty exists
> 1022: left oid 1022 (ObjNum 1021 snap 0 seq_num 1021)
> 1023: finishing write tid 1 to nodez24249-1023
> 1023: finishing write tid 2 to nodez24249-1023
> 1023: finishing write tid 3 to nodez24249-1023
> 1023: finishing write tid 5 to nodez24249-1023
> 1023: finishing write tid 6 to nodez24249-1023
> update_object_version oid 1023 v 1537 (ObjNum 1022 snap 0 seq_num
> 1022) dirty exists
> 1023: left oid 1023 (ObjNum 1022 snap 0 seq_num 1022)
> 1024: finishing write tid 1 to nodez24249-1024
> 1025: Error: oid 230 read returned error code -2
> ./test/osd/RadosModel.h: In function 'virtual void
> ReadOp::_finish(TestOp::CallbackInfo*)' thread 7fd9bb7fe700 time
> 2016-03-17 10:53:41.757921
> ./test/osd/RadosModel.h: 1109: FAILED assert(0)
> ceph version 0.94.6 (e832001feaf8c176593e0325c8298e3f16dfb403)
> 1: (ceph::__ceph_assert_fail(char const*, char const*, int, char
> const*)+0x76) [0x4db956]
> 2: (ReadOp::_finish(TestOp::CallbackInfo*)+0xec) [0x4c959c]
> 3: (()+0x9791d) [0x7fd9d088d91d]
> 4: (()+0x72519) [0x7fd9d0868519]
> 5: (()+0x13c178) [0x7fd9d0932178]
> 6: (()+0x80a4) [0x7fd9d03c60a4]
> 7: (clone()+0x6d) [0x7fd9ced4104d]
> NOTE: a copy of the executable, or `objdump -rdS <executable>` is
> needed to interpret this.
> terminate called after throwing an instance of 'ceph::FailedAssertion'
> Aborted
>
> nodezz:
>
> 1015: finishing write tid 1 to nodezz25161-1015
> 1015: finishing write tid 2 to nodezz25161-1015
> 1015: finishing write tid 4 to nodezz25161-1015
> 1015: finishing write tid 5 to nodezz25161-1015
> update_object_version oid 1015 v 900 (ObjNum 1014 snap 0 seq_num 1014)
> dirty exists
> 1015: left oid 1015 (ObjNum 1014 snap 0 seq_num 1014)
> 1016: finishing write tid 1 to nodezz25161-1016
> 1016: finishing write tid 2 to nodezz25161-1016
> 1016: finishing write tid 3 to nodezz25161-1016
> 1016: finishing write tid 4 to nodezz25161-1016
> 1016: finishing write tid 6 to nodezz25161-1016
> 1016: finishing write tid 7 to nodezz25161-1016
> update_object_version oid 1016 v 1021 (ObjNum 1015 snap 0 seq_num
> 1015) dirty exists
> 1016: left oid 1016 (ObjNum 1015 snap 0 seq_num 1015)
> 1017: finishing write tid 1 to nodezz25161-1017
> 1017: finishing write tid 2 to nodezz25161-1017
> 1017: finishing write tid 3 to nodezz25161-1017
> 1017: finishing write tid 5 to nodezz25161-1017
> 1017: finishing write tid 6 to nodezz25161-1017
> update_object_version oid 1017 v 3011 (ObjNum 1016 snap 0 seq_num
> 1016) dirty exists
> 1017: left oid 1017 (ObjNum 1016 snap 0 seq_num 1016)
> 1018: finishing write tid 1 to nodezz25161-1018
> 1018: finishing write tid 2 to nodezz25161-1018
> 1018: finishing write tid 3 to nodezz25161-1018
> 1018: finishing write tid 4 to nodezz25161-1018
> 1018: finishing write tid 6 to nodezz25161-1018
> 1018: finishing write tid 7 to nodezz25161-1018
> update_object_version oid 1018 v 1099 (ObjNum 1017 snap 0 seq_num
> 1017) dirty exists
> 1018: left oid 1018 (ObjNum 1017 snap 0 seq_num 1017)
> 1019: finishing write tid 1 to nodezz25161-1019
> 1019: finishing write tid 2 to nodezz25161-1019
> 1019: finishing write tid 3 to nodezz25161-1019
> 1019: finishing write tid 5 to nodezz25161-1019
> 1019: finishing write tid 6 to nodezz25161-1019
> update_object_version oid 1019 v 1300 (ObjNum 1018 snap 0 seq_num
> 1018) dirty exists
> 1019: left oid 1019 (ObjNum 1018 snap 0 seq_num 1018)
> 1020: finishing write tid 1 to nodezz25161-1020
> 1020: finishing write tid 2 to nodezz25161-1020
> 1020: finishing write tid 3 to nodezz25161-1020
> 1020: finishing write tid 5 to nodezz25161-1020
> 1020: finishing write tid 6 to nodezz25161-1020
> update_object_version oid 1020 v 1324 (ObjNum 1019 snap 0 seq_num
> 1019) dirty exists
> 1020: left oid 1020 (ObjNum 1019 snap 0 seq_num 1019)
> 1021: finishing write tid 1 to nodezz25161-1021
> 1021: finishing write tid 2 to nodezz25161-1021
> 1021: finishing write tid 3 to nodezz25161-1021
> 1021: finishing write tid 5 to nodezz25161-1021
> 1021: finishing write tid 6 to nodezz25161-1021
> update_object_version oid 1021 v 890 (ObjNum 1020 snap 0 seq_num 1020)
> dirty exists
> 1021: left oid 1021 (ObjNum 1020 snap 0 seq_num 1020)
> 1022: finishing write tid 1 to nodezz25161-1022
> 1022: finishing write tid 2 to nodezz25161-1022
> 1022: finishing write tid 3 to nodezz25161-1022
> 1022: finishing write tid 5 to nodezz25161-1022
> 1022: finishing write tid 6 to nodezz25161-1022
> update_object_version oid 1022 v 464 (ObjNum 1021 snap 0 seq_num 1021)
> dirty exists
> 1022: left oid 1022 (ObjNum 1021 snap 0 seq_num 1021)
> 1023: finishing write tid 1 to nodezz25161-1023
> 1023: finishing write tid 2 to nodezz25161-1023
> 1023: finishing write tid 3 to nodezz25161-1023
> 1023: finishing write tid 5 to nodezz25161-1023
> 1023: finishing write tid 6 to nodezz25161-1023
> update_object_version oid 1023 v 1516 (ObjNum 1022 snap 0 seq_num
> 1022) dirty exists
> 1023: left oid 1023 (ObjNum 1022 snap 0 seq_num 1022)
> 1024: finishing write tid 1 to nodezz25161-1024
> 1024: finishing write tid 2 to nodezz25161-1024
> 1025: Error: oid 219 read returned error code -2
> ./test/osd/RadosModel.h: In function 'virtual void
> ReadOp::_finish(TestOp::CallbackInfo*)' thread 7fbb1bfff700 time
> 2016-03-17 10:53:53.071338
> ./test/osd/RadosModel.h: 1109: FAILED assert(0)
> ceph version 0.94.6 (e832001feaf8c176593e0325c8298e3f16dfb403)
> 1: (ceph::__ceph_assert_fail(char const*, char const*, int, char
> const*)+0x76) [0x4db956]
> 2: (ReadOp::_finish(TestOp::CallbackInfo*)+0xec) [0x4c959c]
> 3: (()+0x9791d) [0x7fbb30ff191d]
> 4: (()+0x72519) [0x7fbb30fcc519]
> 5: (()+0x13c178) [0x7fbb31096178]
> 6: (()+0x80a4) [0x7fbb30b2a0a4]
> 7: (clone()+0x6d) [0x7fbb2f4a504d]
> NOTE: a copy of the executable, or `objdump -rdS <executable>` is
> needed to interpret this.
> terminate called after throwing an instance of 'ceph::FailedAssertion'
> Aborted
> ----------------
> Robert LeBlanc
> PGP Fingerprint 79A2 9CA4 6CC4 45DD A904 C70E E654 3BB2 FA62 B9F1
>
>
> On Thu, Mar 17, 2016 at 10:39 AM, Sage Weil <sweil@xxxxxxxxxx> wrote:
>> On Thu, 17 Mar 2016, Robert LeBlanc wrote:
>>> -----BEGIN PGP SIGNED MESSAGE-----
>>> Hash: SHA256
>>>
>>> I'm having trouble finding documentation about using ceph_test_rados. Can I
>>> run this on the existing cluster and will that provide useful info? It seems
>>> running it in the build will not have the caching set up (vstart.sh).
>>>
>>> I have accepted a job with another company and only have until Wednesday to
>>> help with getting information about this bug. My new job will not be using C
>>> eph, so I won't be able to provide any additional info after Tuesday. I want
>>> to leave the company on a good trajectory for upgrading, so any input you c
>>> an provide will be helpful.
>>
>> I'm sorry to hear it! You'll be missed. :)
>>
>>> I've found:
>>>
>>> ./ceph_test_rados --op read 100 --op write 100 --op delete 50
>>> - --max-ops 400000 --objects 1024 --max-in-flight 64 --size 4000000
>>> - --min-stride-size 400000 --max-stride-size 800000 --max-seconds 600
>>> - --op copy_from 50 --op snap_create 50 --op snap_remove 50 --op
>>> rollback 50 --op setattr 25 --op rmattr 25 --pool unique_pool_0
>>>
>>> Is that enough if I change --pool to the cached pool and do the toggling whi
>>> le ceph_test_rados is running? I think this will run for 10 minutes.
>>
>> Precisely. You can probably drop copy_from and snap ops from the list
>> since your workload wasn't exercising those.
>>
>> Thanks!
>> sage
>>
>>
_______________________________________________
ceph-users mailing list
ceph-users@xxxxxxxxxxxxxx
http://lists.ceph.com/listinfo.cgi/ceph-users-ceph.com
