Storage - software RAID testing: hit test timeout
Snippet of test failure
# https://s3.us-east-1.amazonaws.com/arr-cki-prod-datawarehouse-public/datawarehouse-public/2022/08/31/redhat:627958659/build_x86_64_redhat:627958659_x86_64_debug/tests/1/results_0001/console.log/console.log
[ 459.118318] Running test [R:12535529 T:7 - Storage - software RAID testing - Kernel: 6.0.0-rc3]
[ 480.812846] raid6: skipped pq benchmark and selected sse2x4
[ 480.818524] raid6: using ssse3x2 recovery algorithm
[ 480.884755] xor: measuring software checksum speed
[ 480.891671] prefetch64-sse : 5195 MB/sec
[ 480.898477] generic_sse : 4589 MB/sec
[ 480.902912] xor: using function: prefetch64-sse (5195 MB/sec)
[ 480.979243] async_tx: api initialized (async)
[ 497.818988] loop0: detected capacity change from 0 to 1024000
[-- MARK -- Wed Aug 31 22:50:00 2022]
[ 511.017522] loop1: detected capacity change from 0 to 1024000
[ 524.326181] loop2: detected capacity change from 0 to 1024000
[ 533.621902] perf: interrupt took too long (5015 > 5000), lowering kernel.perf_event_max_sample_rate to 39000
[ 537.634929] loop3: detected capacity change from 0 to 1024000
[ 550.929420] loop4: detected capacity change from 0 to 1024000
[ 564.377420] loop5: detected capacity change from 0 to 1024000
[ 575.899597] md1: detected capacity change from 0 to 5099520
[ 589.213953] XFS (md1): Mounting V5 Filesystem
[ 589.386062] XFS (md1): Ending clean mount
[ 592.422706] XFS (md1): Unmounting Filesystem
[ 593.854377] md1: detected capacity change from 5099520 to 0
[ 593.862096] md: md1 stopped.
[ 622.906011] md/raid1:md1: not clean -- starting background reconstruction
[ 622.912912] md/raid1:md1: active with 5 out of 5 mirrors
[ 623.047354] md1: detected capacity change from 0 to 1021952
[ 623.060875] md: resync of RAID array md1
[ 699.973814] md: md1: resync done.
[ 717.591488] XFS (md1): Mounting V5 Filesystem
[ 718.004104] XFS (md1): Ending clean mount
[ 721.427782] XFS (md1): Unmounting Filesystem
[ 723.215302] md1: detected capacity change from 1021952 to 0
[ 723.221014] md: md1 stopped.
[ 752.798524] md/raid:md1: device loop3 operational as raid disk 3
[ 752.804664] md/raid:md1: device loop2 operational as raid disk 2
[ 752.810774] md/raid:md1: device loop1 operational as raid disk 1
[ 752.816864] md/raid:md1: device loop0 operational as raid disk 0
[ 752.898215] md/raid:md1: raid level 4 active with 4 out of 5 devices, algorithm 0
[ 752.985221] md1: detected capacity change from 0 to 4079616
[ 753.001701] md: recovery of RAID array md1
[-- MARK -- Wed Aug 31 22:55:00 2022]
[ 835.989403] md: md1: recovery done.
[ 850.183328] perf: interrupt took too long (6276 > 6268), lowering kernel.perf_event_max_sample_rate to 31000
[-- MARK -- Wed Aug 31 23:00:00 2022]
[ 1335.592874] XFS (md1): Mounting V5 Filesystem
[ 1335.993343] XFS (md1): Ending clean mount
[-- MARK -- Wed Aug 31 23:05:00 2022]
[ 1529.479193] XFS (md1): Unmounting Filesystem
[ 1532.269248] md1: detected capacity change from 4079616 to 0
[ 1532.274967] md: md1 stopped.
[ 1562.685823] md/raid:md1: device loop3 operational as raid disk 3
[ 1562.691924] md/raid:md1: device loop2 operational as raid disk 2
[ 1562.698055] md/raid:md1: device loop1 operational as raid disk 1
[ 1562.704146] md/raid:md1: device loop0 operational as raid disk 0
[ 1562.763798] md/raid:md1: raid level 5 active with 4 out of 5 devices, algorithm 2
[ 1562.841936] md1: detected capacity change from 0 to 4079616
[ 1562.855391] md: recovery of RAID array md1
[ 1651.118816] md: md1: recovery done.
[-- MARK -- Wed Aug 31 23:10:00 2022]
[-- MARK -- Wed Aug 31 23:15:00 2022]
[ 2089.035881] XFS (md1): Mounting V5 Filesystem
[ 2089.534536] XFS (md1): Ending clean mount
[ 2281.932583] Storage - software RAID testing hit test timeout, aborting it...
[ 2286.037760] sysrq: Show Memory
[ 2286.041390] Mem-Info:
[ 2286.043775] active_anon:158 inactive_anon:13702 isolated_anon:0
[ 2286.043775] active_file:110621 inactive_file:165925 isolated_file:0
[ 2286.043775] unevictable:0 dirty:289 writeback:0
[ 2286.043775] slab_reclaimable:24506 slab_unreclaimable:89731
[ 2286.043775] mapped:12208 shmem:289 pagetables:515 bounce:0
[ 2286.043775] kernel_misc_reclaimable:0
[ 2286.043775] free:1231541 free_pcp:7855 free_cma:0
Test logs on DataWarehouse
DataWarehouse issue
Additional details
- Similar DW issue: https://datawarehouse.cki-project.org/issue/1561
In case opening the links above result in a 404 page on DataWarehouse, please make sure you are correctly logged into DataWarehouse via Red Hat SSO.