06:21:16:Lustre: DEBUG MARKER: sh run-llog.sh
06:21:16:Lustre: 10824:0:(llog_test.c:1198:llog_test_setup()) Setup llog-test device over MGS device
06:21:16:Lustre: 10824:0:(llog_test.c:110:llog_test_1()) 1a: create a log with name: 769df0b4
06:21:16:Lustre: 10824:0:(llog_test.c:127:llog_test_1()) 1b: close newly-created log
06:21:16:Lustre: 10824:0:(llog_test.c:151:llog_test_2()) 2a: re-open a log with name: 769df0b4
06:21:16:Lustre: 10824:0:(llog_test.c:232:llog_test_3()) 3a: write one create_rec
06:21:16:Lustre: 10824:0:(llog_test.c:244:llog_test_3()) 3c: write 1000 more log records
06:21:16:Lustre: 10824:0:(llog_test.c:260:llog_test_3()) 3d: write records with variable size until BITMAP_SIZE, return -ENOSPC
06:21:16:Lustre: 10824:0:(llog_test.c:287:llog_test_3()) 3d: wrote 64362 more records before end of llog is reached
06:21:16:Lustre: 10824:0:(llog_test.c:316:llog_test_4()) 4a: create a catalog log with name: 769df0b5
06:21:16:Lustre: 10824:0:(llog_test.c:331:llog_test_4()) 4b: write 1 record into the catalog
06:21:16:Lustre: 10824:0:(llog_test.c:346:llog_test_4()) 4c: cancel 1 log record
06:21:16:Lustre: 10824:0:(llog_test.c:358:llog_test_4()) 4d: write 65536 more log records
06:21:16:Lustre: 10824:0:(llog_test.c:374:llog_test_4()) 4e: add 5 large records, one record per block
06:21:16:Lustre: 10824:0:(llog_test.c:394:llog_test_4()) 4f: put newly-created catalog
06:21:16:Lustre: 10824:0:(llog_test.c:492:llog_test_5()) 5a: re-open catalog by id
06:21:16:Lustre: 10824:0:(llog_test.c:505:llog_test_5()) 5b: print the catalog entries.. we expect 2
06:21:16:Lustre: 10825:0:(llog_test.c:423:cat_print_cb()) seeing record at index 1 - [0x1:0x33:0x0] in log [0xa:0x10:0x0]
06:21:16:Lustre: 10824:0:(llog_test.c:517:llog_test_5()) 5c: Cancel 65536 records, see one log zapped
06:21:16:Lustre: 10824:0:(llog_test.c:525:llog_test_5()) 5c: print the catalog entries.. we expect 1
06:21:16:Lustre: 10826:0:(llog_test.c:423:cat_print_cb()) seeing record at index 2 - [0x1:0x34:0x0] in log [0xa:0x10:0x0]
06:21:16:Lustre: 10826:0:(llog_test.c:423:cat_print_cb()) Skipped 1 previous similar message
06:21:16:Lustre: 10824:0:(llog_test.c:537:llog_test_5()) 5d: add 1 record to the log with many canceled empty pages
06:21:16:Lustre: 10824:0:(llog_test.c:545:llog_test_5()) 5e: print plain log entries.. expect 6
06:21:16:Lustre: 10824:0:(llog_test.c:557:llog_test_5()) 5f: print plain log entries reversely.. expect 6
06:21:16:Lustre: 10824:0:(llog_test.c:571:llog_test_5()) 5g: close re-opened catalog
06:21:16:Lustre: 10824:0:(llog_test.c:601:llog_test_6()) 6a: re-open log 769df0b4 using client API
06:21:16:Lustre: MGS: non-config logname received: 769df0b4
06:21:16:Lustre: 10824:0:(llog_test.c:633:llog_test_6()) 6b: process log 769df0b4 using client API
06:21:16:Lustre: 10824:0:(llog_test.c:637:llog_test_6()) 6b: processed 64362 records
06:21:16:Lustre: 10824:0:(llog_test.c:644:llog_test_6()) 6c: process log 769df0b4 reversely using client API
06:21:16:Lustre: 10824:0:(llog_test.c:648:llog_test_6()) 6c: processed 64362 records
06:21:16:Lustre: 10824:0:(llog_test.c:795:llog_test_7()) 7a: test llog_logid_rec
06:21:16:Lustre: 10824:0:(llog_test.c:806:llog_test_7()) 7b: test llog_unlink64_rec
06:21:16:Lustre: 10824:0:(llog_test.c:817:llog_test_7()) 7c: test llog_setattr64_rec
06:21:16:Lustre: 10824:0:(llog_test.c:828:llog_test_7()) 7d: test llog_size_change_rec
06:21:16:Lustre: 10824:0:(llog_test.c:839:llog_test_7()) 7e: test llog_changelog_rec
06:21:16:Lustre: 10824:0:(llog_test.c:745:llog_test_7_sub()) 7_sub: records are not aligned, written 64071 from 64767
06:21:16:Lustre: 10824:0:(llog_test.c:851:llog_test_7()) 7f: test llog_changelog_user_rec
06:21:16:Lustre: 10824:0:(llog_test.c:745:llog_test_7_sub()) 7_sub: records are not aligned, written 64452 from 64767
06:21:16:Lustre: 10824:0:(llog_test.c:862:llog_test_7()) 7g: test llog_gen_rec
06:21:16:Lustre: 10824:0:(llog_test.c:957:llog_test_8()) 8a: fill the first plain llog
06:21:16:Lustre: 10824:0:(llog_test.c:990:llog_test_8()) 8a: pin llog [0x1:0x3e:0x0]
06:21:16:Lustre: 10824:0:(llog_test.c:1000:llog_test_8()) 8b: fill the second plain llog
06:21:16:Lustre: 10824:0:(llog_test.c:1021:llog_test_8()) 8b: second llog [0x1:0x40:0x0]
06:21:16:Lustre: 10824:0:(llog_test.c:1031:llog_test_8()) 8c: drop two records from the first plain llog
06:21:16:Lustre: 10824:0:(llog_test.c:1034:llog_test_8()) 8d: count survived records
06:21:16:LustreError: 10824:0:(llog.c:527:llog_process_thread()) Local llog found corrupted
06:21:16:Lustre: 10824:0:(llog_test.c:1061:llog_test_8()) 8d: close re-opened catalog
06:21:16:Lustre: DEBUG MARKER: /usr/sbin/lctl mark sanity test_60a: @@@@@@ FAIL: test_60a failed with 3
06:21:16:Lustre: DEBUG MARKER: sanity test_60a: @@@@@@ FAIL: test_60a failed with 3