[42.224798] Initializing watchdogs
[42.225046]   /dev/watchdog0
[42.242507] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0162 Intel Ivybridge (Gen7) IvyBridge GT2 [HD Graphics 4000]
[42.267111] [001/162] (960s left) i915_module_load (load)
[42.442946] Starting subtest: load
[44.206194] Subtest load: SUCCESS (1.740s)
[44.348345] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[44.349827] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[44.379401] [002/162] (958s left) core_auth (basic-auth)
[44.614706] Starting subtest: basic-auth
[44.640195] Subtest basic-auth: SUCCESS (0.001s)
[44.822329] [003/162] (958s left) core_debugfs (read-all-entries)
[44.989648] Starting subtest: read-all-entries
[45.015075] Subtest read-all-entries: SUCCESS (0.065s)
[45.191118] [004/162] (958s left) core_sysfs (read-all-entries)
[45.385366] Starting subtest: read-all-entries
[45.404816] Subtest read-all-entries: SUCCESS (0.003s)
[45.543774] [005/162] (957s left) fbdev (eof)
[45.695102] Starting subtest: eof
[45.716292] Subtest eof: SUCCESS (0.000s)
[45.851878] [006/162] (957s left) fbdev (info)
[45.995031] Starting subtest: info
[46.015582] Subtest info: SUCCESS (0.000s)
[46.148776] [007/162] (957s left) fbdev (nullptr)
[46.297731] Starting subtest: nullptr
[46.317048] Subtest nullptr: SUCCESS (0.000s)
[46.456244] [008/162] (957s left) fbdev (read)
[46.601033] Starting subtest: read
[46.706600] Subtest read: SUCCESS (0.132s)
[46.842348] [009/162] (957s left) fbdev (write)
[46.955181] Starting subtest: write
[47.013622] Subtest write: SUCCESS (0.070s)
[47.148870] [010/162] (956s left) gem_basic (bad-close)
[47.300216] Starting subtest: bad-close
[47.339113] Subtest bad-close: SUCCESS (0.000s)
[47.478149] [011/162] (956s left) gem_basic (create-close)
[47.623519] Starting subtest: create-close
[47.667713] Subtest create-close: SUCCESS (0.000s)
[47.804792] [012/162] (956s left) gem_basic (create-fd-close)
[47.956761] Starting subtest: create-fd-close
[47.997562] Subtest create-fd-close: SUCCESS (0.000s)
[48.134765] [013/162] (956s left) gem_busy (busy@all-engines)
[48.315030] Starting subtest: busy
[48.335508] Starting dynamic subtest: all-engines
[48.357430] Dynamic subtest all-engines: SUCCESS (0.027s)
[48.378791] Subtest busy: SUCCESS (0.028s)
[48.723433] [014/162] (955s left) gem_close_race (basic-process)
[48.856194] Starting subtest: basic-process
[48.898408] Subtest basic-process: SUCCESS (0.041s)
[49.142278] [015/162] (955s left) gem_close_race (basic-threads)
[49.314674] Starting subtest: basic-threads
[50.460979] Subtest basic-threads: SUCCESS (1.190s)
[50.731275] [016/162] (954s left) gem_ctx_create (basic)
[50.884933] Starting subtest: basic
[50.906244] Subtest basic: SUCCESS (0.000s)
[51.101614] [017/162] (953s left) gem_ctx_create (basic-files)
[51.273602] Starting subtest: basic-files
[53.283624] Subtest basic-files: SUCCESS (2.035s)
[53.730300] [018/162] (951s left) gem_ctx_exec (basic)
[53.891270] Starting subtest: basic
[53.912117] Subtest basic: SUCCESS (0.003s)
[54.094144] [019/162] (951s left) gem_exec_basic (basic)
[54.245422] Starting subtest: basic
[54.267674] Starting dynamic subtest: rcs0-smem
[54.289096] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[54.321347] Starting dynamic subtest: bcs0-smem
[54.341102] Dynamic subtest bcs0-smem: SUCCESS (0.000s)
[54.370877] Starting dynamic subtest: vcs0-smem
[54.390350] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[54.410877] Subtest basic: SUCCESS (0.004s)
[54.552833] [020/162] (950s left) gem_exec_create (basic)
[54.711930] Starting subtest: basic
[54.733534] Starting dynamic subtest: smem
[56.732307] Dynamic subtest smem: SUCCESS (2.048s)
[56.751913] Subtest basic: SUCCESS (2.049s)
[56.985536] [021/162] (948s left) gem_exec_fence (basic-busy)
[57.226354] Starting subtest: basic-busy
[57.248944] Starting dynamic subtest: rcs0
[57.293347] Dynamic subtest rcs0: SUCCESS (0.029s)
[57.325040] Starting dynamic subtest: bcs0
[57.364752] Dynamic subtest bcs0: SUCCESS (0.030s)
[57.393143] Starting dynamic subtest: vcs0
[57.432219] Dynamic subtest vcs0: SUCCESS (0.027s)
[57.453039] Subtest basic-busy: SUCCESS (0.087s)
[57.878892] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[57.901895] [022/162] (947s left) gem_exec_fence (basic-wait)
[58.150363] Starting subtest: basic-wait
[58.169753] Starting dynamic subtest: rcs0
[58.188865] Dynamic subtest rcs0: SUCCESS (0.034s)
[58.221685] Starting dynamic subtest: bcs0
[58.243950] Dynamic subtest bcs0: SUCCESS (0.033s)
[58.275198] Starting dynamic subtest: vcs0
[58.296565] Dynamic subtest vcs0: SUCCESS (0.026s)
[58.316118] Subtest basic-wait: SUCCESS (0.096s)
[58.770838] [023/162] (947s left) gem_exec_fence (basic-await)
[59.008468] Starting subtest: basic-await
[59.029951] Starting dynamic subtest: rcs0
[59.069338] Dynamic subtest rcs0: SUCCESS (0.075s)
[59.096372] Starting dynamic subtest: bcs0
[59.137953] Dynamic subtest bcs0: SUCCESS (0.070s)
[59.216129] Starting dynamic subtest: vcs0
[59.237314] Dynamic subtest vcs0: SUCCESS (0.081s)
[59.258237] Subtest basic-await: SUCCESS (0.227s)
[59.764596] [024/162] (946s left) gem_exec_fence (nb-await)
[60.023175] Starting subtest: nb-await
[60.044565] Starting dynamic subtest: rcs0
[60.084265] Dynamic subtest rcs0: SUCCESS (0.054s)
[60.111479] Starting dynamic subtest: bcs0
[60.129552] Dynamic subtest bcs0: SUCCESS (0.052s)
[60.155834] Starting dynamic subtest: vcs0
[60.175509] Dynamic subtest vcs0: SUCCESS (0.052s)
[60.196316] Subtest nb-await: SUCCESS (0.160s)
[60.737577] [025/162] (945s left) gem_exec_gttfill (basic)
[60.889811] Starting subtest: basic
[63.033115] Subtest basic: SUCCESS (2.175s)
[63.357440] [026/162] (942s left) gem_exec_parallel (engines)
[63.656830] Starting subtest: engines
[63.678055] Starting dynamic subtest: basic
[65.157578] Dynamic subtest basic: SUCCESS (1.543s)
[65.185908] Starting dynamic subtest: contexts
[66.768214] Dynamic subtest contexts: SUCCESS (1.613s)
[66.797285] Starting dynamic subtest: fds
[68.480601] Dynamic subtest fds: SUCCESS (1.714s)
[68.509642] Starting dynamic subtest: userptr
[71.103745] Dynamic subtest userptr: SUCCESS (2.428s)
[71.123680] Subtest engines: SUCCESS (7.300s)
[71.268484] [027/162] (935s left) gem_exec_store (basic)
[71.504770] Starting subtest: basic
[71.526671] Subtest basic: SUCCESS (0.045s)
[71.733231] [028/162] (934s left) gem_flink_basic (bad-flink)
[71.877708] Starting subtest: bad-flink
[71.897243] Subtest bad-flink: SUCCESS (0.000s)
[72.047842] [029/162] (934s left) gem_flink_basic (bad-open)
[72.215631] Starting subtest: bad-open
[72.236938] Subtest bad-open: SUCCESS (0.000s)
[72.377793] [030/162] (934s left) gem_flink_basic (basic)
[72.549243] Starting subtest: basic
[72.569172] Subtest basic: SUCCESS (0.000s)
[72.709949] [031/162] (934s left) gem_flink_basic (double-flink)
[72.874972] Starting subtest: double-flink
[72.896945] Subtest double-flink: SUCCESS (0.000s)
[73.044880] [032/162] (934s left) gem_flink_basic (flink-lifetime)
[73.172252] Starting subtest: flink-lifetime
[73.193650] Subtest flink-lifetime: SUCCESS (0.001s)
[73.362036] [033/162] (933s left) gem_huc_copy (huc-copy)
[73.551367] Subtest huc-copy: SKIP (0.000s)
[73.692971] [034/162] (933s left) gem_linear_blits (basic)
[73.889704] Starting subtest: basic
[73.911797] Subtest basic: SUCCESS (0.005s)
[74.109359] [035/162] (933s left) gem_mmap (basic)
[74.358381] Starting subtest: basic
[74.461374] Subtest basic: SUCCESS (0.000s)
[74.607760] [036/162] (933s left) gem_mmap_gtt (basic)
[74.768670] Starting subtest: basic
[74.789101] Subtest basic: SUCCESS (0.001s)
[74.928276] [037/162] (932s left) gem_render_linear_blits (basic)
[75.077905] Starting subtest: basic
[75.180432] Subtest basic: SUCCESS (0.017s)
[75.321822] [038/162] (932s left) gem_render_tiled_blits (basic)
[75.478947] Starting subtest: basic
[75.599757] Subtest basic: SUCCESS (0.025s)
[75.752299] [039/162] (932s left) gem_ringfill (basic-all)
[76.021281] Starting subtest: basic-all
[77.103126] Subtest basic-all: SUCCESS (1.109s)
[77.306080] [040/162] (930s left) gem_softpin (allocator-basic)
[77.481291] Subtest allocator-basic: SKIP (0.000s)
[77.650501] [041/162] (930s left) gem_softpin (allocator-basic-reserve)
[77.819361] Subtest allocator-basic-reserve: SKIP (0.000s)
[77.981204] [042/162] (930s left) gem_softpin (safe-alignment)
[78.191093] Starting subtest: safe-alignment
[78.213284] Subtest safe-alignment: SUCCESS (0.006s)
[78.357253] [043/162] (930s left) gem_sync (basic-all)
[78.552602] Starting subtest: basic-all
[80.875332] Subtest basic-all: SUCCESS (2.213s)
[81.044262] [044/162] (927s left) gem_sync (basic-each)
[81.227886] Starting subtest: basic-each
[83.344989] Subtest basic-each: SUCCESS (2.157s)
[83.594189] [045/162] (925s left) gem_tiled_blits (basic)
[83.794300] Starting subtest: basic
[83.817162] Subtest basic: SUCCESS (0.010s)
[84.014874] [046/162] (925s left) gem_tiled_fence_blits (basic)
[84.302102] Starting subtest: basic
[84.330649] Subtest basic: SUCCESS (0.007s)
[84.509948] [047/162] (924s left) gem_tiled_pread_basic (basic)
[84.755738] Starting subtest: basic
[84.853068] Subtest basic: SUCCESS (0.133s)
[85.022304] [048/162] (924s left) gem_wait (busy@all-engines)
[85.173875] Starting subtest: busy
[85.197943] Starting dynamic subtest: all-engines
[85.679812] Dynamic subtest all-engines: SUCCESS (0.522s)
[85.699678] Subtest busy: SUCCESS (0.522s)
[85.976050] [049/162] (923s left) gem_wait (wait@all-engines)
[86.126953] Starting subtest: wait
[86.148421] Starting dynamic subtest: all-engines
[87.131927] Dynamic subtest all-engines: SUCCESS (1.017s)
[87.151392] Subtest wait: SUCCESS (1.018s)
[87.420377] [050/162] (922s left) i915_getparams_basic (basic-eu-total)
[87.593621] Starting subtest: basic-eu-total
[87.632670] Subtest basic-eu-total: SUCCESS (0.000s)
[87.775945] [051/162] (922s left) i915_getparams_basic (basic-subslice-total)
[87.920997] Starting subtest: basic-subslice-total
[87.965157] Subtest basic-subslice-total: SUCCESS (0.000s)
[88.111427] [052/162] (921s left) i915_hangman (error-state-basic)
[88.270888] Starting subtest: error-state-basic
[88.291663] Subtest error-state-basic: SUCCESS (0.007s)
[88.439287] [053/162] (921s left) i915_pciid
[88.757781] [054/162] (921s left) intel_hwmon (hwmon-read)
[88.903152] Subtest hwmon-read: SKIP (0.000s)
[89.049045] [055/162] (921s left) intel_hwmon (hwmon-write)
[89.218891] Subtest hwmon-write: SKIP (0.000s)
[89.365392] [056/162] (921s left) kms_addfb_basic (addfb25-4-tiled)
[89.587067] Starting subtest: addfb25-4-tiled
[89.606553] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[89.750411] [057/162] (920s left) kms_addfb_basic (addfb25-bad-modifier)
[89.897288] Starting subtest: addfb25-bad-modifier
[89.919305] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[90.067987] [058/162] (920s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[90.234485] Starting subtest: addfb25-framebuffer-vs-set-tiling
[90.254768] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[90.395695] [059/162] (920s left) kms_addfb_basic (addfb25-modifier-no-flag)
[90.564232] Starting subtest: addfb25-modifier-no-flag
[90.583044] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[90.729774] [060/162] (920s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[90.872716] Starting subtest: addfb25-x-tiled-legacy
[90.894812] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[91.041349] [061/162] (920s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[91.192317] Starting subtest: addfb25-x-tiled-mismatch-legacy
[91.214039] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[91.353185] [062/162] (919s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[91.505864] Starting subtest: addfb25-yf-tiled-legacy
[91.528491] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[91.691322] [063/162] (919s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[91.863914] Starting subtest: addfb25-y-tiled-legacy
[91.888868] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[92.059731] [064/162] (919s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[92.228265] Starting subtest: addfb25-y-tiled-small-legacy
[92.280593] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[92.443256] [065/162] (919s left) kms_addfb_basic (bad-pitch-0)
[92.620449] Starting subtest: bad-pitch-0
[92.643516] Subtest bad-pitch-0: SUCCESS (0.000s)
[92.811990] [066/162] (919s left) kms_addfb_basic (bad-pitch-1024)
[93.004562] Starting subtest: bad-pitch-1024
[93.029530] Subtest bad-pitch-1024: SUCCESS (0.000s)
[93.174031] [067/162] (918s left) kms_addfb_basic (bad-pitch-128)
[93.326681] Starting subtest: bad-pitch-128
[93.346920] Subtest bad-pitch-128: SUCCESS (0.000s)
[93.485485] [068/162] (918s left) kms_addfb_basic (bad-pitch-256)
[93.637438] Starting subtest: bad-pitch-256
[93.656856] Subtest bad-pitch-256: SUCCESS (0.000s)
[93.800164] [069/162] (918s left) kms_addfb_basic (bad-pitch-32)
[93.945208] Starting subtest: bad-pitch-32
[93.966075] Subtest bad-pitch-32: SUCCESS (0.000s)
[94.119942] [070/162] (918s left) kms_addfb_basic (bad-pitch-63)
[94.290831] Starting subtest: bad-pitch-63
[94.312411] Subtest bad-pitch-63: SUCCESS (0.000s)
[94.453267] [071/162] (918s left) kms_addfb_basic (bad-pitch-65536)
[94.603975] Starting subtest: bad-pitch-65536
[94.623493] Subtest bad-pitch-65536: SUCCESS (0.000s)
[94.766322] [072/162] (917s left) kms_addfb_basic (bad-pitch-999)
[94.930131] Starting subtest: bad-pitch-999
[94.951763] Subtest bad-pitch-999: SUCCESS (0.000s)
[95.104661] [073/162] (917s left) kms_addfb_basic (basic)
[95.273014] Starting subtest: basic
[95.293921] Subtest basic: SUCCESS (0.000s)
[95.434450] [074/162] (917s left) kms_addfb_basic (basic-x-tiled-legacy)
[95.585615] Starting subtest: basic-x-tiled-legacy
[95.605104] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[95.760634] [075/162] (917s left) kms_addfb_basic (basic-y-tiled-legacy)
[95.926436] Starting subtest: basic-y-tiled-legacy
[95.954686] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[96.097547] [076/162] (917s left) kms_addfb_basic (bo-too-small)
[96.246188] Starting subtest: bo-too-small
[96.267674] Subtest bo-too-small: SUCCESS (0.000s)
[96.408289] [077/162] (916s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[96.561134] Starting subtest: bo-too-small-due-to-tiling
[96.580246] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[96.747343] [078/162] (916s left) kms_addfb_basic (clobberred-modifier)
[96.891465] Starting subtest: clobberred-modifier
[96.913675] Subtest clobberred-modifier: SUCCESS (0.000s)
[97.059928] [079/162] (916s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[97.207706] Starting subtest: framebuffer-vs-set-tiling
[97.228310] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[97.368712] [080/162] (916s left) kms_addfb_basic (invalid-get-prop)
[97.519879] Starting subtest: invalid-get-prop
[97.539323] Subtest invalid-get-prop: SUCCESS (0.000s)
[97.683520] [081/162] (916s left) kms_addfb_basic (invalid-get-prop-any)
[97.845820] Starting subtest: invalid-get-prop-any
[97.866978] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[98.012346] [082/162] (916s left) kms_addfb_basic (invalid-set-prop)
[98.194098] Starting subtest: invalid-set-prop
[98.213295] Subtest invalid-set-prop: SUCCESS (0.000s)
[98.353139] [083/162] (915s left) kms_addfb_basic (invalid-set-prop-any)
[98.501609] Starting subtest: invalid-set-prop-any
[98.520978] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[98.665052] [084/162] (915s left) kms_addfb_basic (no-handle)
[98.828637] Starting subtest: no-handle
[98.850638] Subtest no-handle: SUCCESS (0.000s)
[98.997898] [085/162] (915s left) kms_addfb_basic (size-max)
[99.149219] Starting subtest: size-max
[99.170875] Subtest size-max: SUCCESS (0.000s)
[99.310918] [086/162] (915s left) kms_addfb_basic (small-bo)
[99.462486] Starting subtest: small-bo
[99.482069] Subtest small-bo: SUCCESS (0.000s)
[99.624899] [087/162] (915s left) kms_addfb_basic (tile-pitch-mismatch)
[99.772969] Starting subtest: tile-pitch-mismatch
[99.792195] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[99.959665] [088/162] (914s left) kms_addfb_basic (too-high)
[100.109304] Starting subtest: too-high
[100.130421] Subtest too-high: SUCCESS (0.001s)
[100.271374] [089/162] (914s left) kms_addfb_basic (too-wide)
[100.440292] Starting subtest: too-wide
[100.459349] Subtest too-wide: SUCCESS (0.000s)
[100.602614] [090/162] (914s left) kms_addfb_basic (unused-handle)
[100.754170] Starting subtest: unused-handle
[100.776258] Subtest unused-handle: SUCCESS (0.000s)
[100.926473] [091/162] (914s left) kms_addfb_basic (unused-modifier)
[101.071818] Starting subtest: unused-modifier
[101.093577] Subtest unused-modifier: SUCCESS (0.000s)
[101.241814] [092/162] (914s left) kms_addfb_basic (unused-offsets)
[101.409976] Starting subtest: unused-offsets
[101.429632] Subtest unused-offsets: SUCCESS (0.000s)
[101.574668] [093/162] (913s left) kms_addfb_basic (unused-pitches)
[101.719939] Starting subtest: unused-pitches
[101.740821] Subtest unused-pitches: SUCCESS (0.000s)
[101.887288] [094/162] (913s left) kms_busy (basic)
[102.046238] Starting subtest: basic
[102.068073] Starting dynamic subtest: flip
[102.570450] Dynamic subtest flip: SUCCESS (0.526s)
[102.600188] Starting dynamic subtest: modeset
[103.203906] Dynamic subtest modeset: SUCCESS (0.626s)
[103.222471] Subtest basic: SUCCESS (1.154s)
[103.453870] [095/162] (912s left) kms_prop_blob (basic)
[103.629986] Starting subtest: basic
[103.652299] Subtest basic: SUCCESS (0.000s)
[103.794342] [096/162] (912s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[103.948440] Starting subtest: basic-busy-flip-before-cursor-atomic
[104.874379] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.932s)
[105.121730] [097/162] (910s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[105.272371] Starting subtest: basic-busy-flip-before-cursor-legacy
[106.205146] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.927s)
[106.447201] [098/162] (909s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[106.594319] Starting subtest: basic-flip-after-cursor-atomic
[107.508889] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.913s)
[107.751096] [099/162] (908s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[107.917941] Starting subtest: basic-flip-after-cursor-legacy
[108.822616] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.926s)
[109.079211] [100/162] (907s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[109.224857] Starting subtest: basic-flip-after-cursor-varying-size
[110.157019] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.941s)
[110.404864] [101/162] (906s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[110.553674] Starting subtest: basic-flip-before-cursor-atomic
[111.459533] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.912s)
[111.705128] [102/162] (905s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[111.870552] Starting subtest: basic-flip-before-cursor-legacy
[112.795639] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.919s)
[113.045301] [103/162] (903s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[113.224409] Starting subtest: basic-flip-before-cursor-varying-size
[114.097319] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.914s)
[114.364290] [104/162] (902s left) kms_dsc (dsc-basic)
[114.597872] Subtest dsc-basic: SKIP (0.000s)
[114.769509] [105/162] (902s left) kms_flip (basic-flip-vs-dpms)
[114.995468] Starting subtest: basic-flip-vs-dpms
[115.020471] Starting dynamic subtest: A-VGA1
[115.874577] Dynamic subtest A-VGA1: SUCCESS (0.941s)
[115.908499] Starting dynamic subtest: B-VGA1
[116.779311] Dynamic subtest B-VGA1: SUCCESS (0.898s)
[116.812347] Starting dynamic subtest: C-VGA1
[117.683689] Dynamic subtest C-VGA1: SUCCESS (0.901s)
[117.703927] Subtest basic-flip-vs-dpms: SUCCESS (2.744s)
[117.983151] [106/162] (899s left) kms_flip (basic-flip-vs-modeset)
[118.153462] Starting subtest: basic-flip-vs-modeset
[118.174091] Starting dynamic subtest: A-VGA1
[119.133109] Dynamic subtest A-VGA1: SUCCESS (1.008s)
[119.167108] Starting dynamic subtest: B-VGA1
[120.082050] Dynamic subtest B-VGA1: SUCCESS (0.945s)
[120.119622] Starting dynamic subtest: C-VGA1
[120.888973] Dynamic subtest C-VGA1: SUCCESS (0.809s)
[120.907424] Subtest basic-flip-vs-modeset: SUCCESS (2.766s)
[121.146153] [107/162] (896s left) kms_flip (basic-flip-vs-wf_vblank)
[121.346640] Starting subtest: basic-flip-vs-wf_vblank
[121.365443] Starting dynamic subtest: A-VGA1
[122.473207] Dynamic subtest A-VGA1: SUCCESS (1.155s)
[122.506067] Starting dynamic subtest: B-VGA1
[123.645475] Dynamic subtest B-VGA1: SUCCESS (1.165s)
[123.677535] Starting dynamic subtest: C-VGA1
[124.799635] Dynamic subtest C-VGA1: SUCCESS (1.149s)
[124.820921] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.476s)
[125.060340] [108/162] (892s left) kms_flip (basic-plain-flip)
[125.250924] Starting subtest: basic-plain-flip
[125.271970] Starting dynamic subtest: A-VGA1
[126.082651] Dynamic subtest A-VGA1: SUCCESS (0.879s)
[126.118255] Starting dynamic subtest: B-VGA1
[126.945466] Dynamic subtest B-VGA1: SUCCESS (0.864s)
[126.979392] Starting dynamic subtest: C-VGA1
[127.814113] Dynamic subtest C-VGA1: SUCCESS (0.866s)
[127.832095] Subtest basic-plain-flip: SUCCESS (2.615s)
[128.096488] [109/162] (889s left) kms_force_connector_basic (force-connector-state)
[128.277915] Starting subtest: force-connector-state
[128.409479] Subtest force-connector-state: SUCCESS (0.158s)
[128.701422] [110/162] (889s left) kms_force_connector_basic (force-edid)
[128.858119] Starting subtest: force-edid
[128.878150] Subtest force-edid: SUCCESS (0.017s)
[129.040193] [111/162] (888s left) kms_force_connector_basic (force-load-detect)
[129.216138] Starting subtest: force-load-detect
[129.354468] Subtest force-load-detect: SUCCESS (0.179s)
[129.647240] [112/162] (888s left) kms_force_connector_basic (prune-stale-modes)
[129.810828] Starting subtest: prune-stale-modes
[129.831316] Subtest prune-stale-modes: SUCCESS (0.017s)
[129.972142] [113/162] (888s left) kms_frontbuffer_tracking (basic)
[130.206898] Starting subtest: basic
[132.034068] Subtest basic: SUCCESS (1.913s)
[132.224897] [114/162] (886s left) kms_hdmi_inject (inject-audio)
[132.399364] Starting subtest: inject-audio
[132.439623] Subtest inject-audio: SKIP (0.000s)
[132.630696] [115/162] (885s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[132.780329] Starting subtest: compare-crc-sanitycheck-xr24
[132.802209] Starting dynamic subtest: pipe-A-VGA-1
[133.063231] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.280s)
[133.095094] Starting dynamic subtest: pipe-B-VGA-1
[133.354951] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.284s)
[133.387370] Starting dynamic subtest: pipe-C-VGA-1
[133.633656] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.288s)
[133.663924] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.856s)
[133.912641] [116/162] (884s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[134.103079] Starting subtest: compare-crc-sanitycheck-nv12
[134.128146] Starting dynamic subtest: pipe-A-VGA-1
[134.177869] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[134.213828] Starting dynamic subtest: pipe-B-VGA-1
[134.261224] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[134.299974] Starting dynamic subtest: pipe-C-VGA-1
[134.352392] Dynamic subtest pipe-C-VGA-1: SKIP (0.000s)
[134.401721] Subtest compare-crc-sanitycheck-nv12: SKIP (0.004s)
[134.575273] [117/162] (884s left) kms_pipe_crc_basic (hang-read-crc)
[134.738319] Starting subtest: hang-read-crc
[134.763610] Starting dynamic subtest: pipe-A-VGA-1
[135.535236] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.804s)
[135.565436] Starting dynamic subtest: pipe-B-VGA-1
[136.341244] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.807s)
[136.375625] Starting dynamic subtest: pipe-C-VGA-1
[137.154110] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.804s)
[137.174237] Subtest hang-read-crc: SUCCESS (2.420s)
[137.384149] [118/162] (881s left) kms_pipe_crc_basic (nonblocking-crc)
[137.573949] Starting subtest: nonblocking-crc
[137.594158] Starting dynamic subtest: pipe-A-VGA-1
[138.033479] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.484s)
[138.065422] Starting dynamic subtest: pipe-B-VGA-1
[138.500515] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.468s)
[138.532808] Starting dynamic subtest: pipe-C-VGA-1
[138.969126] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.469s)
[138.988021] Subtest nonblocking-crc: SUCCESS (1.425s)
[139.209548] [119/162] (879s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[139.386972] Starting subtest: nonblocking-crc-frame-sequence
[139.407328] Starting dynamic subtest: pipe-A-VGA-1
[139.821099] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.459s)
[139.851455] Starting dynamic subtest: pipe-B-VGA-1
[140.288998] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.469s)
[140.322320] Starting dynamic subtest: pipe-C-VGA-1
[140.766173] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.468s)
[140.785199] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.400s)
[140.995370] [120/162] (878s left) kms_pipe_crc_basic (read-crc)
[141.143670] Starting subtest: read-crc
[141.165662] Starting dynamic subtest: pipe-A-VGA-1
[141.532162] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.404s)
[141.562399] Starting dynamic subtest: pipe-B-VGA-1
[141.935656] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.402s)
[141.966537] Starting dynamic subtest: pipe-C-VGA-1
[142.336447] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.402s)
[142.357522] Subtest read-crc: SUCCESS (1.212s)
[142.566616] [121/162] (876s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[142.733070] Starting subtest: read-crc-frame-sequence
[142.755995] Starting dynamic subtest: pipe-A-VGA-1
[143.123183] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.402s)
[143.155416] Starting dynamic subtest: pipe-B-VGA-1
[143.522415] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.400s)
[143.553526] Starting dynamic subtest: pipe-C-VGA-1
[143.923479] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.400s)
[143.945116] Subtest read-crc-frame-sequence: SUCCESS (1.207s)
[144.153069] [122/162] (875s left) kms_pm_backlight (basic-brightness)
[144.349975] Subtest basic-brightness: SKIP (0.000s)
[144.500831] [123/162] (875s left) kms_pm_rpm (basic-pci-d3-state)
[155.929081] Subtest basic-pci-d3-state: SKIP (0.000s)
[156.077565] [124/162] (863s left) kms_pm_rpm (basic-rte)
[156.203768] Starting subtest: basic-rte
[167.525295] Subtest basic-rte: SKIP (11.094s)
[167.707844] [125/162] (852s left) kms_psr (psr-primary-page-flip)
[167.891694] Subtest psr-primary-page-flip: SKIP (0.000s)
[168.043645] [126/162] (852s left) kms_psr (psr-cursor-plane-move)
[168.233316] Subtest psr-cursor-plane-move: SKIP (0.000s)
[168.377126] [127/162] (851s left) kms_psr (psr-sprite-plane-onoff)
[168.575717] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[168.729434] [128/162] (851s left) kms_psr (psr-primary-mmap-gtt)
[168.892562] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[169.040484] [129/162] (851s left) kms_setmode (basic-clone-single-crtc)
[169.200173] Starting subtest: basic-clone-single-crtc
[169.260979] Subtest basic-clone-single-crtc: SKIP (0.000s)
[169.403921] [130/162] (851s left) i915_pm_rps (basic-api)
[169.563118] Starting subtest: basic-api
[169.582645] Subtest basic-api: SUCCESS (0.003s)
[169.751845] [131/162] (851s left) prime_self_import (basic-llseek-bad)
[169.908215] Starting subtest: basic-llseek-bad
[169.951226] Subtest basic-llseek-bad: SUCCESS (0.002s)
[170.093522] [132/162] (850s left) prime_self_import (basic-llseek-size)
[170.204405] Starting subtest: basic-llseek-size
[170.245635] Subtest basic-llseek-size: SUCCESS (0.004s)
[170.391159] [133/162] (850s left) prime_self_import (basic-with_fd_dup)
[170.543581] Starting subtest: basic-with_fd_dup
[170.581343] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[170.728698] [134/162] (850s left) prime_self_import (basic-with_one_bo)
[170.856940] Starting subtest: basic-with_one_bo
[170.899382] Subtest basic-with_one_bo: SUCCESS (0.003s)
[171.045799] [135/162] (850s left) prime_self_import (basic-with_one_bo_two_files)
[171.174652] Starting subtest: basic-with_one_bo_two_files
[171.217869] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s)
[171.359840] [136/162] (850s left) prime_self_import (basic-with_two_bos)
[171.496971] Starting subtest: basic-with_two_bos
[171.535713] Subtest basic-with_two_bos: SUCCESS (0.003s)
[171.680885] [137/162] (850s left) prime_vgem (basic-fence-flip)
[171.876794] Starting subtest: basic-fence-flip
[172.166115] Subtest basic-fence-flip: SUCCESS (0.342s)
[172.368440] [138/162] (849s left) prime_vgem (basic-fence-mmap)
[172.558599] Starting subtest: basic-fence-mmap
[172.579665] Subtest basic-fence-mmap: SUCCESS (0.035s)
[172.742829] [139/162] (849s left) prime_vgem (basic-fence-read)
[172.923445] Starting subtest: basic-fence-read
[172.946178] Subtest basic-fence-read: SUCCESS (0.041s)
[173.141716] [140/162] (849s left) prime_vgem (basic-gtt)
[173.347267] Starting subtest: basic-gtt
[173.372791] Subtest basic-gtt: SUCCESS (0.016s)
[173.558940] [141/162] (848s left) prime_vgem (basic-read)
[173.772237] Starting subtest: basic-read
[173.796268] Subtest basic-read: SUCCESS (0.018s)
[173.991918] [142/162] (848s left) prime_vgem (basic-write)
[174.213911] Starting subtest: basic-write
[174.239403] Subtest basic-write: SUCCESS (0.020s)
[174.433254] [143/162] (848s left) vgem_basic (setversion)
[174.622314] Starting subtest: setversion
[174.672101] Subtest setversion: SUCCESS (0.000s)
[174.839203] [144/162] (848s left) vgem_basic (create)
[175.016247] Starting subtest: create
[175.035815] Subtest create: SUCCESS (0.001s)
[175.179592] [145/162] (847s left) vgem_basic (debugfs)
[175.329487] Starting subtest: debugfs
[175.348941] Subtest debugfs: SUCCESS (0.001s)
[175.500431] [146/162] (847s left) vgem_basic (dmabuf-export)
[175.669399] Starting subtest: dmabuf-export
[175.711294] Subtest dmabuf-export: SUCCESS (0.002s)
[175.854340] [147/162] (847s left) vgem_basic (dmabuf-fence)
[176.004533] Starting subtest: dmabuf-fence
[176.024131] Subtest dmabuf-fence: SUCCESS (0.001s)
[176.172725] [148/162] (847s left) vgem_basic (dmabuf-fence-before)
[176.343226] Starting subtest: dmabuf-fence-before
[176.365162] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[176.510736] [149/162] (847s left) vgem_basic (dmabuf-mmap)
[176.682282] Starting subtest: dmabuf-mmap
[176.702578] Subtest dmabuf-mmap: SUCCESS (0.016s)
[176.844244] [150/162] (846s left) vgem_basic (mmap)
[176.992645] Starting subtest: mmap
[177.012049] Subtest mmap: SUCCESS (0.013s)
[177.162375] [151/162] (846s left) vgem_basic (second-client)
[177.339505] Starting subtest: second-client
[177.382103] Subtest second-client: SUCCESS (0.034s)
[177.525295] [152/162] (846s left) vgem_basic (sysfs)
[177.700937] Starting subtest: sysfs
[177.720342] Subtest sysfs: SUCCESS (0.001s)
[177.871633] [153/162] (846s left) core_hotunplug (unbind-rebind)
[178.131294] Starting subtest: unbind-rebind
[181.170032] Subtest unbind-rebind: SUCCESS (3.059s)
[181.314099] [154/162] (843s left) vgem_basic (unload)
[181.472704] Starting subtest: unload
[210.629044] Subtest unload: SUCCESS (29.020s)
[210.774576] [155/162] (813s left) i915_module_load (reload)
[210.923184] Starting subtest: reload
[213.162136] Subtest reload: SUCCESS (2.259s)
[213.305829] [156/162] (811s left) gem_lmem_swapping (basic)
[213.484281] Subtest basic: SKIP (0.000s)
[213.634254] [157/162] (811s left) gem_lmem_swapping (parallel-random-engines)
[213.824201] Subtest parallel-random-engines: SKIP (0.000s)
[213.968414] [158/162] (811s left) gem_lmem_swapping (random-engines)
[214.147404] Subtest random-engines: SKIP (0.000s)
[214.288966] [159/162] (810s left) gem_lmem_swapping (verify-random)
[214.454861] Subtest verify-random: SKIP (0.000s)
[214.606389] [160/162] (810s left) i915_pm_rpm (module-reload)
[226.056415] Subtest module-reload: SKIP (0.000s)
[226.197111] [161/162] (799s left) i915_selftest (live)
[227.094845] Starting subtest: live
[227.115720] Starting dynamic subtest: sanitycheck
[228.773205] Dynamic subtest sanitycheck: SUCCESS (1.678s)
[228.804915] Starting dynamic subtest: uncore
[230.439899] Dynamic subtest uncore: SUCCESS (1.666s)
[230.471363] Starting dynamic subtest: workarounds
[232.188312] Dynamic subtest workarounds: SUCCESS (1.743s)
[232.219700] Starting dynamic subtest: gt_engines
[233.867716] Dynamic subtest gt_engines: SUCCESS (1.677s)
[233.898410] Starting dynamic subtest: gt_timelines
[240.841701] Dynamic subtest gt_timelines: SUCCESS (6.972s)
[240.876421] Starting dynamic subtest: gt_contexts
[242.604895] Dynamic subtest gt_contexts: SUCCESS (1.762s)
[242.634052] Starting dynamic subtest: gt_lrc
[244.189329] Dynamic subtest gt_lrc: SUCCESS (1.583s)
[244.220145] Starting dynamic subtest: gt_mocs
[245.896350] Dynamic subtest gt_mocs: SUCCESS (1.698s)
[245.925109] Starting dynamic subtest: gt_pm
[257.857574] Dynamic subtest gt_pm: SUCCESS (11.965s)
[257.888972] Starting dynamic subtest: gt_heartbeat
[259.546695] Dynamic subtest gt_heartbeat: SUCCESS (1.690s)
[259.579558] Starting dynamic subtest: gt_tlb
[261.205451] Dynamic subtest gt_tlb: SUCCESS (1.657s)
[261.236493] Starting dynamic subtest: requests
[267.551179] Dynamic subtest requests: SUCCESS (6.342s)
[267.581083] Starting dynamic subtest: migrate
[269.138745] Dynamic subtest migrate: SUCCESS (1.584s)
[269.168610] Starting dynamic subtest: active
[270.763621] Dynamic subtest active: SUCCESS (1.621s)
[270.794258] Starting dynamic subtest: objects
[272.786878] Dynamic subtest objects: SUCCESS (2.022s)
[272.816562] Starting dynamic subtest: mman
[277.574330] Dynamic subtest mman: SUCCESS (4.783s)
[277.607690] Starting dynamic subtest: dmabuf
[279.309382] Dynamic subtest dmabuf: SUCCESS (1.733s)
[279.339013] Starting dynamic subtest: vma
[281.017683] Dynamic subtest vma: SUCCESS (1.705s)
[281.050373] Starting dynamic subtest: coherency
[284.711974] Dynamic subtest coherency: SUCCESS (3.690s)
[284.743073] Starting dynamic subtest: gtt
[289.271956] Dynamic subtest gtt: SUCCESS (4.560s)
[289.305059] Starting dynamic subtest: gem
[291.045652] Dynamic subtest gem: SUCCESS (1.768s)
[291.077530] Starting dynamic subtest: evict
[292.713428] Dynamic subtest evict: SUCCESS (1.662s)
[292.746433] Starting dynamic subtest: hugepages