[101.659490] Initializing watchdogs [101.659560] /dev/watchdog0 [101.673299] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:591d Intel Kabylake (Gen9) HD Graphics P630 [101.680754] [001/162] (960s left) i915_module_load (load) [102.084137] Starting subtest: load [104.380179] Subtest load: SUCCESS (2.297s) [104.411272] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [104.412460] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [104.416951] [002/162] (957s left) core_auth (basic-auth) [104.479238] Starting subtest: basic-auth [104.482493] Subtest basic-auth: SUCCESS (0.001s) [104.522900] [003/162] (957s left) core_debugfs (read-all-entries) [104.571591] Starting subtest: read-all-entries [104.574597] Subtest read-all-entries: SUCCESS (0.002s) [104.611531] [004/162] (957s left) core_sysfs (read-all-entries) [104.660418] Starting subtest: read-all-entries [104.663484] Subtest read-all-entries: SUCCESS (0.003s) [104.700323] [005/162] (957s left) fbdev (eof) [104.748173] Subtest eof: SKIP (0.000s) [104.784364] [006/162] (957s left) fbdev (info) [104.835169] Subtest info: SKIP (0.000s) [104.874109] [007/162] (957s left) fbdev (nullptr) [104.925510] Subtest nullptr: SKIP (0.000s) [104.962172] [008/162] (957s left) fbdev (read) [105.010819] Subtest read: SKIP (0.000s) [105.047193] [009/162] (957s left) fbdev (write) [105.095563] Subtest write: SKIP (0.000s) [105.132368] [010/162] (957s left) gem_basic (bad-close) [105.177812] Starting subtest: bad-close [105.184582] Subtest bad-close: SUCCESS (0.000s) [105.225484] [011/162] (957s left) gem_basic (create-close) [105.272535] Starting subtest: create-close [105.279133] Subtest create-close: SUCCESS (0.000s) [105.324374] [012/162] (957s left) gem_basic (create-fd-close) [105.370031] Starting subtest: create-fd-close [105.376622] Subtest create-fd-close: SUCCESS (0.000s) [105.413751] [013/162] (957s left) gem_busy (busy@all-engines) [105.471026] Starting subtest: busy [105.474139] Starting dynamic subtest: all-engines [105.495686] Dynamic subtest all-engines: SUCCESS (0.025s) [105.498696] Subtest busy: SUCCESS (0.025s) [105.831887] [014/162] (956s left) gem_close_race (basic-process) [105.889602] Starting subtest: basic-process [105.965054] Subtest basic-process: SUCCESS (0.075s) [106.106631] [015/162] (956s left) gem_close_race (basic-threads) [106.162132] Starting subtest: basic-threads [107.423129] Subtest basic-threads: SUCCESS (1.261s) [107.571492] [016/162] (955s left) gem_ctx_create (basic) [107.624336] Starting subtest: basic [107.627493] Subtest basic: SUCCESS (0.000s) [107.771754] [017/162] (955s left) gem_ctx_create (basic-files) [107.821920] Starting subtest: basic-files [109.835745] Subtest basic-files: SUCCESS (2.011s) [110.023961] [018/162] (952s left) gem_ctx_exec (basic) [110.072656] Starting subtest: basic [110.075736] Subtest basic: SUCCESS (0.004s) [110.167564] [019/162] (952s left) gem_exec_basic (basic) [110.213605] Starting subtest: basic [110.216744] Starting dynamic subtest: rcs0-smem [110.219994] Dynamic subtest rcs0-smem: SUCCESS (0.003s) [110.229781] Starting dynamic subtest: bcs0-smem [110.232935] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [110.242715] Starting dynamic subtest: vcs0-smem [110.246109] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [110.255725] Starting dynamic subtest: vecs0-smem [110.259092] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [110.262277] Subtest basic: SUCCESS (0.005s) [110.403402] [020/162] (952s left) gem_exec_create (basic) [110.455804] Starting subtest: basic [110.458880] Starting dynamic subtest: smem [112.495264] Dynamic subtest smem: SUCCESS (2.039s) [112.498511] Subtest basic: SUCCESS (2.040s) [112.642663] [021/162] (950s left) gem_exec_fence (basic-busy) [112.803739] Starting subtest: basic-busy [112.806913] Starting dynamic subtest: rcs0 [112.866937] Dynamic subtest rcs0: SUCCESS (0.063s) [112.886992] Starting dynamic subtest: bcs0 [112.909301] Dynamic subtest bcs0: SUCCESS (0.042s) [112.919850] Starting dynamic subtest: vcs0 [112.958524] Dynamic subtest vcs0: SUCCESS (0.049s) [112.974323] Starting dynamic subtest: vecs0 [113.016861] Dynamic subtest vecs0: SUCCESS (0.058s) [113.020305] Subtest basic-busy: SUCCESS (0.213s) [113.555028] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [113.558879] [022/162] (949s left) gem_exec_fence (basic-wait) [113.727814] Starting subtest: basic-wait [113.731393] Starting dynamic subtest: rcs0 [113.762331] Dynamic subtest rcs0: SUCCESS (0.034s) [113.777952] Starting dynamic subtest: bcs0 [113.793410] Dynamic subtest bcs0: SUCCESS (0.031s) [113.812974] Starting dynamic subtest: vcs0 [113.829424] Dynamic subtest vcs0: SUCCESS (0.035s) [113.844893] Starting dynamic subtest: vecs0 [113.856496] Dynamic subtest vecs0: SUCCESS (0.027s) [113.859794] Subtest basic-wait: SUCCESS (0.129s) [114.356370] [023/162] (948s left) gem_exec_fence (basic-await) [114.527292] Starting subtest: basic-await [114.530299] Starting dynamic subtest: rcs0 [114.599130] Dynamic subtest rcs0: SUCCESS (0.074s) [114.616168] Starting dynamic subtest: bcs0 [114.683245] Dynamic subtest bcs0: SUCCESS (0.084s) [114.698534] Starting dynamic subtest: vcs0 [114.768655] Dynamic subtest vcs0: SUCCESS (0.085s) [114.788365] Starting dynamic subtest: vecs0 [114.858633] Dynamic subtest vecs0: SUCCESS (0.090s) [114.862088] Subtest basic-await: SUCCESS (0.334s) [115.405076] [024/162] (947s left) gem_exec_fence (nb-await) [115.932725] Starting subtest: nb-await [115.997097] Starting dynamic subtest: rcs0 [116.062769] Dynamic subtest rcs0: SUCCESS (0.062s) [116.127305] Starting dynamic subtest: bcs0 [116.197132] Dynamic subtest bcs0: SUCCESS (0.064s) [116.268701] Starting dynamic subtest: vcs0 [116.338042] Dynamic subtest vcs0: SUCCESS (0.064s) [116.409500] Starting dynamic subtest: vecs0 [116.480408] Dynamic subtest vecs0: SUCCESS (0.064s) [116.552370] Subtest nb-await: SUCCESS (0.258s) [117.027215] [025/162] (946s left) gem_exec_gttfill (basic) [117.080077] Starting subtest: basic [120.377186] Subtest basic: SUCCESS (3.297s) [120.535674] [026/162] (942s left) gem_exec_parallel (engines) [120.654238] Starting subtest: engines [120.657285] Starting dynamic subtest: basic [122.518467] Dynamic subtest basic: SUCCESS (1.864s) [122.529184] Starting dynamic subtest: contexts [126.690808] Dynamic subtest contexts: SUCCESS (4.171s) [126.704870] Starting dynamic subtest: fds [130.984294] Dynamic subtest fds: SUCCESS (4.293s) [130.994874] Starting dynamic subtest: userptr [133.199660] Dynamic subtest userptr: SUCCESS (2.216s) [133.203094] Subtest engines: SUCCESS (12.545s) [133.358089] [027/162] (930s left) gem_exec_store (basic) [133.445324] Starting subtest: basic [133.507379] Subtest basic: SUCCESS (0.083s) [133.655840] [028/162] (929s left) gem_flink_basic (bad-flink) [133.703477] Starting subtest: bad-flink [133.706440] Subtest bad-flink: SUCCESS (0.000s) [133.748662] [029/162] (929s left) gem_flink_basic (bad-open) [133.795833] Starting subtest: bad-open [133.798832] Subtest bad-open: SUCCESS (0.000s) [133.841610] [030/162] (929s left) gem_flink_basic (basic) [133.887207] Starting subtest: basic [133.890238] Subtest basic: SUCCESS (0.000s) [133.948989] [031/162] (929s left) gem_flink_basic (double-flink) [133.994059] Starting subtest: double-flink [133.997171] Subtest double-flink: SUCCESS (0.000s) [134.048750] [032/162] (929s left) gem_flink_basic (flink-lifetime) [134.097392] Starting subtest: flink-lifetime [134.100561] Subtest flink-lifetime: SUCCESS (0.001s) [134.163969] [033/162] (929s left) gem_huc_copy (huc-copy) [134.212540] Starting subtest: huc-copy [134.218670] Subtest huc-copy: SKIP (0.000s) [134.287469] [034/162] (929s left) gem_linear_blits (basic) [134.407889] Starting subtest: basic [134.415697] Subtest basic: SUCCESS (0.007s) [134.536503] [035/162] (929s left) gem_mmap (basic) [134.656997] Starting subtest: basic [134.673260] Subtest basic: SUCCESS (0.000s) [134.714896] [036/162] (929s left) gem_mmap_gtt (basic) [134.767736] Starting subtest: basic [134.770905] Subtest basic: SUCCESS (0.001s) [134.812145] [037/162] (929s left) gem_render_linear_blits (basic) [134.865570] Starting subtest: basic [134.882541] Subtest basic: SUCCESS (0.021s) [134.939434] [038/162] (929s left) gem_render_tiled_blits (basic) [134.991723] Starting subtest: basic [135.018668] Subtest basic: SUCCESS (0.030s) [135.078152] [039/162] (928s left) gem_ringfill (basic-all) [135.302097] Starting subtest: basic-all [136.441124] Subtest basic-all: SUCCESS (1.135s) [136.516816] [040/162] (927s left) gem_softpin (allocator-basic) [136.569586] Starting subtest: allocator-basic [136.662351] Subtest allocator-basic: SUCCESS (0.091s) [136.759471] [041/162] (927s left) gem_softpin (allocator-basic-reserve) [136.810224] Starting subtest: allocator-basic-reserve [136.896078] Subtest allocator-basic-reserve: SUCCESS (0.087s) [136.979674] [042/162] (927s left) gem_softpin (safe-alignment) [137.032572] Starting subtest: safe-alignment [137.039094] Subtest safe-alignment: SUCCESS (0.009s) [137.136450] [043/162] (927s left) gem_sync (basic-all) [137.212389] Starting subtest: basic-all [139.365533] Subtest basic-all: SUCCESS (2.170s) [139.511816] [044/162] (924s left) gem_sync (basic-each) [139.586911] Starting subtest: basic-each [141.689124] Subtest basic-each: SUCCESS (2.115s) [141.828482] [045/162] (922s left) gem_tiled_blits (basic) [141.922205] Starting subtest: basic [141.936077] Subtest basic: SUCCESS (0.013s) [142.042882] [046/162] (922s left) gem_tiled_fence_blits (basic) [142.216817] Starting subtest: basic [142.225870] Subtest basic: SUCCESS (0.008s) [142.314589] [047/162] (922s left) gem_tiled_pread_basic (basic) [142.443132] Starting subtest: basic [142.561197] Subtest basic: SUCCESS (0.122s) [142.623973] [048/162] (921s left) gem_wait (busy@all-engines) [142.682180] Starting subtest: busy [142.685054] Starting dynamic subtest: all-engines [143.207268] Dynamic subtest all-engines: SUCCESS (0.525s) [143.210216] Subtest busy: SUCCESS (0.525s) [143.365781] [049/162] (921s left) gem_wait (wait@all-engines) [143.422085] Starting subtest: wait [143.425242] Starting dynamic subtest: all-engines [144.443749] Dynamic subtest all-engines: SUCCESS (1.021s) [144.447073] Subtest wait: SUCCESS (1.022s) [144.607044] [050/162] (919s left) i915_getparams_basic (basic-eu-total) [144.653790] Starting subtest: basic-eu-total [144.659569] Subtest basic-eu-total: SUCCESS (0.000s) [144.700989] [051/162] (919s left) i915_getparams_basic (basic-subslice-total) [144.746392] Starting subtest: basic-subslice-total [144.752115] Subtest basic-subslice-total: SUCCESS (0.000s) [144.793724] [052/162] (919s left) i915_hangman (error-state-basic) [144.844394] Starting subtest: error-state-basic [144.852223] Subtest error-state-basic: SUCCESS (0.005s) [144.926345] [053/162] (919s left) i915_pciid [145.022720] [054/162] (919s left) intel_hwmon (hwmon-read) [145.073496] Subtest hwmon-read: SKIP (0.000s) [145.116503] [055/162] (919s left) intel_hwmon (hwmon-write) [145.163282] Subtest hwmon-write: SKIP (0.000s) [145.208492] [056/162] (919s left) kms_addfb_basic (addfb25-4-tiled) [145.298486] Starting subtest: addfb25-4-tiled [145.301515] Subtest addfb25-4-tiled: SUCCESS (0.000s) [145.357844] [057/162] (919s left) kms_addfb_basic (addfb25-bad-modifier) [145.433541] Starting subtest: addfb25-bad-modifier [145.436825] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [145.507929] [058/162] (919s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [145.583123] Starting subtest: addfb25-framebuffer-vs-set-tiling [145.586579] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [145.674384] [059/162] (919s left) kms_addfb_basic (addfb25-modifier-no-flag) [145.751318] Starting subtest: addfb25-modifier-no-flag [145.754191] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [145.810506] [060/162] (919s left) kms_addfb_basic (addfb25-x-tiled-legacy) [145.886163] Starting subtest: addfb25-x-tiled-legacy [145.889346] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [145.944264] [061/162] (919s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [146.022786] Starting subtest: addfb25-x-tiled-mismatch-legacy [146.026058] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [146.103707] [062/162] (918s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [146.179387] Starting subtest: addfb25-yf-tiled-legacy [146.182895] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [146.239013] [063/162] (918s left) kms_addfb_basic (addfb25-y-tiled-legacy) [146.313987] Starting subtest: addfb25-y-tiled-legacy [146.317071] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [146.405334] [064/162] (918s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [146.481467] Starting subtest: addfb25-y-tiled-small-legacy [146.484541] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [146.542488] [065/162] (918s left) kms_addfb_basic (bad-pitch-0) [146.594365] Starting subtest: bad-pitch-0 [146.597431] Subtest bad-pitch-0: SUCCESS (0.000s) [146.687352] [066/162] (918s left) kms_addfb_basic (bad-pitch-1024) [146.761369] Starting subtest: bad-pitch-1024 [146.764687] Subtest bad-pitch-1024: SUCCESS (0.000s) [146.840972] [067/162] (918s left) kms_addfb_basic (bad-pitch-128) [146.891697] Starting subtest: bad-pitch-128 [146.895154] Subtest bad-pitch-128: SUCCESS (0.000s) [146.976333] [068/162] (918s left) kms_addfb_basic (bad-pitch-256) [147.023751] Starting subtest: bad-pitch-256 [147.027736] Subtest bad-pitch-256: SUCCESS (0.000s) [147.119103] [069/162] (918s left) kms_addfb_basic (bad-pitch-32) [147.166326] Starting subtest: bad-pitch-32 [147.170182] Subtest bad-pitch-32: SUCCESS (0.000s) [147.246077] [070/162] (918s left) kms_addfb_basic (bad-pitch-63) [147.295445] Starting subtest: bad-pitch-63 [147.298960] Subtest bad-pitch-63: SUCCESS (0.000s) [147.399540] [071/162] (918s left) kms_addfb_basic (bad-pitch-65536) [147.446905] Starting subtest: bad-pitch-65536 [147.450804] Subtest bad-pitch-65536: SUCCESS (0.000s) [147.541028] [072/162] (917s left) kms_addfb_basic (bad-pitch-999) [147.593613] Starting subtest: bad-pitch-999 [147.596546] Subtest bad-pitch-999: SUCCESS (0.000s) [147.684727] [073/162] (917s left) kms_addfb_basic (basic) [147.735595] Starting subtest: basic [147.739050] Subtest basic: SUCCESS (0.000s) [147.837734] [074/162] (917s left) kms_addfb_basic (basic-x-tiled-legacy) [147.885566] Starting subtest: basic-x-tiled-legacy [147.890046] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [147.977828] [075/162] (917s left) kms_addfb_basic (basic-y-tiled-legacy) [148.028577] Starting subtest: basic-y-tiled-legacy [148.031850] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [148.127764] [076/162] (917s left) kms_addfb_basic (bo-too-small) [148.204490] Starting subtest: bo-too-small [148.207811] Subtest bo-too-small: SUCCESS (0.000s) [148.264187] [077/162] (917s left) kms_addfb_basic (bo-too-small-due-to-tiling) [148.341597] Starting subtest: bo-too-small-due-to-tiling [148.344729] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [148.409207] [078/162] (917s left) kms_addfb_basic (clobberred-modifier) [148.457550] Starting subtest: clobberred-modifier [148.462180] Subtest clobberred-modifier: SUCCESS (0.000s) [148.558812] [079/162] (917s left) kms_addfb_basic (framebuffer-vs-set-tiling) [148.607768] Starting subtest: framebuffer-vs-set-tiling [148.611618] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [148.694583] [080/162] (917s left) kms_addfb_basic (invalid-get-prop) [148.742975] Starting subtest: invalid-get-prop [148.746457] Subtest invalid-get-prop: SUCCESS (0.000s) [148.846451] [081/162] (917s left) kms_addfb_basic (invalid-get-prop-any) [148.896554] Starting subtest: invalid-get-prop-any [148.899358] Subtest invalid-get-prop-any: SUCCESS (0.000s) [148.987024] [082/162] (916s left) kms_addfb_basic (invalid-set-prop) [149.038125] Starting subtest: invalid-set-prop [149.041073] Subtest invalid-set-prop: SUCCESS (0.000s) [149.143353] [083/162] (916s left) kms_addfb_basic (invalid-set-prop-any) [149.193706] Starting subtest: invalid-set-prop-any [149.196935] Subtest invalid-set-prop-any: SUCCESS (0.000s) [149.291658] [084/162] (916s left) kms_addfb_basic (no-handle) [149.344138] Starting subtest: no-handle [149.346979] Subtest no-handle: SUCCESS (0.000s) [149.461626] [085/162] (916s left) kms_addfb_basic (size-max) [149.536133] Starting subtest: size-max [149.539647] Subtest size-max: SUCCESS (0.000s) [149.615484] [086/162] (916s left) kms_addfb_basic (small-bo) [149.692455] Starting subtest: small-bo [149.695672] Subtest small-bo: SUCCESS (0.000s) [149.753561] [087/162] (916s left) kms_addfb_basic (tile-pitch-mismatch) [149.803878] Starting subtest: tile-pitch-mismatch [149.807390] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [149.893685] [088/162] (916s left) kms_addfb_basic (too-high) [149.968913] Starting subtest: too-high [149.972177] Subtest too-high: SUCCESS (0.001s) [150.032525] [089/162] (916s left) kms_addfb_basic (too-wide) [150.108546] Starting subtest: too-wide [150.111523] Subtest too-wide: SUCCESS (0.000s) [150.169847] [090/162] (916s left) kms_addfb_basic (unused-handle) [150.218353] Starting subtest: unused-handle [150.222146] Subtest unused-handle: SUCCESS (0.000s) [150.303075] [091/162] (916s left) kms_addfb_basic (unused-modifier) [150.355813] Starting subtest: unused-modifier [150.359617] Subtest unused-modifier: SUCCESS (0.000s) [150.449376] [092/162] (915s left) kms_addfb_basic (unused-offsets) [150.497841] Starting subtest: unused-offsets [150.501811] Subtest unused-offsets: SUCCESS (0.000s) [150.598983] [093/162] (915s left) kms_addfb_basic (unused-pitches) [150.646575] Starting subtest: unused-pitches [150.650458] Subtest unused-pitches: SUCCESS (0.000s) [150.733724] [094/162] (915s left) kms_busy (basic) [150.814842] Subtest basic: SKIP (0.000s) [150.864332] [095/162] (915s left) kms_prop_blob (basic) [150.909849] Starting subtest: basic [150.912964] Subtest basic: SUCCESS (0.000s) [150.954552] [096/162] (915s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [151.052621] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [151.098847] [097/162] (915s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [151.198088] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [151.241915] [098/162] (915s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [151.341040] Subtest basic-flip-after-cursor-atomic: SKIP (0.000s) [151.385862] [099/162] (915s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [151.484322] Subtest basic-flip-after-cursor-legacy: SKIP (0.000s) [151.527469] [100/162] (915s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [151.625494] Subtest basic-flip-after-cursor-varying-size: SKIP (0.000s) [151.668682] [101/162] (915s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [151.767452] Subtest basic-flip-before-cursor-atomic: SKIP (0.000s) [151.812220] [102/162] (914s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [151.923180] Subtest basic-flip-before-cursor-legacy: SKIP (0.000s) [151.969192] [103/162] (914s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [152.068632] Subtest basic-flip-before-cursor-varying-size: SKIP (0.000s) [152.113484] [104/162] (914s left) kms_dsc (dsc-basic) [152.191653] Subtest dsc-basic: SKIP (0.000s) [152.233790] [105/162] (914s left) kms_flip (basic-flip-vs-dpms) [152.311670] Starting subtest: basic-flip-vs-dpms [152.318122] Subtest basic-flip-vs-dpms: SKIP (0.001s) [152.390380] [106/162] (914s left) kms_flip (basic-flip-vs-modeset) [152.465551] Starting subtest: basic-flip-vs-modeset [152.471317] Subtest basic-flip-vs-modeset: SKIP (0.001s) [152.555323] [107/162] (914s left) kms_flip (basic-flip-vs-wf_vblank) [152.632080] Starting subtest: basic-flip-vs-wf_vblank [152.638170] Subtest basic-flip-vs-wf_vblank: SKIP (0.001s) [152.710992] [108/162] (914s left) kms_flip (basic-plain-flip) [152.788851] Starting subtest: basic-plain-flip [152.795329] Subtest basic-plain-flip: SKIP (0.001s) [152.844408] [109/162] (914s left) kms_force_connector_basic (force-connector-state) [152.994400] Starting subtest: force-connector-state [153.071744] Subtest force-connector-state: SUCCESS (0.078s) [153.220867] [110/162] (913s left) kms_force_connector_basic (force-edid) [153.367531] Starting subtest: force-edid [153.541426] Subtest force-edid: SUCCESS (0.173s) [153.621333] [111/162] (913s left) kms_force_connector_basic (force-load-detect) [153.766698] Starting subtest: force-load-detect [153.773676] Subtest force-load-detect: SKIP (0.000s) [153.838338] [112/162] (913s left) kms_force_connector_basic (prune-stale-modes) [153.982847] Starting subtest: prune-stale-modes [154.147845] Subtest prune-stale-modes: SUCCESS (0.168s) [154.230730] [113/162] (913s left) kms_frontbuffer_tracking (basic) [154.308978] Subtest basic: SKIP (0.000s) [154.361679] [114/162] (912s left) kms_hdmi_inject (inject-audio) [154.410103] Starting subtest: inject-audio [154.416143] Subtest inject-audio: SKIP (0.000s) [154.463913] [115/162] (912s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [154.563328] Subtest compare-crc-sanitycheck-xr24: SKIP (0.000s) [154.605711] [116/162] (912s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [154.685584] Subtest compare-crc-sanitycheck-nv12: SKIP (0.000s) [154.727805] [117/162] (912s left) kms_pipe_crc_basic (hang-read-crc) [154.805174] Subtest hang-read-crc: SKIP (0.000s) [154.846915] [118/162] (912s left) kms_pipe_crc_basic (nonblocking-crc) [154.927563] Subtest nonblocking-crc: SKIP (0.000s) [154.970131] [119/162] (912s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [155.047944] Subtest nonblocking-crc-frame-sequence: SKIP (0.000s) [155.090617] [120/162] (912s left) kms_pipe_crc_basic (read-crc) [155.171187] Subtest read-crc: SKIP (0.000s) [155.213900] [121/162] (912s left) kms_pipe_crc_basic (read-crc-frame-sequence) [155.293737] Subtest read-crc-frame-sequence: SKIP (0.000s) [155.336174] [122/162] (912s left) kms_pm_backlight (basic-brightness) [155.415556] Subtest basic-brightness: SKIP (0.000s) [155.457637] [123/162] (912s left) kms_pm_rpm (basic-pci-d3-state) [166.855608] Starting subtest: basic-pci-d3-state [167.020777] Subtest basic-pci-d3-state: SUCCESS (0.167s) [167.072959] [124/162] (900s left) kms_pm_rpm (basic-rte) [167.117011] Starting subtest: basic-rte [178.661507] Subtest basic-rte: SUCCESS (11.543s) [178.953783] [125/162] (888s left) kms_psr (psr-primary-page-flip) [179.031774] Subtest psr-primary-page-flip: SKIP (0.000s) [179.074040] [126/162] (888s left) kms_psr (psr-cursor-plane-move) [179.150687] Subtest psr-cursor-plane-move: SKIP (0.000s) [179.192492] [127/162] (888s left) kms_psr (psr-sprite-plane-onoff) [179.269254] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [179.311563] [128/162] (888s left) kms_psr (psr-primary-mmap-gtt) [179.388168] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [179.429923] [129/162] (888s left) kms_setmode (basic-clone-single-crtc) [179.478761] Starting subtest: basic-clone-single-crtc [179.487964] Subtest basic-clone-single-crtc: SKIP (0.000s) [179.538764] [130/162] (888s left) i915_pm_rps (basic-api) [179.591545] Starting subtest: basic-api [179.594605] Subtest basic-api: SUCCESS (0.003s) [179.639760] [131/162] (888s left) prime_self_import (basic-llseek-bad) [179.688485] Starting subtest: basic-llseek-bad [179.694736] Subtest basic-llseek-bad: SUCCESS (0.002s) [179.745451] [132/162] (888s left) prime_self_import (basic-llseek-size) [179.803012] Starting subtest: basic-llseek-size [179.809045] Subtest basic-llseek-size: SUCCESS (0.002s) [179.851584] [133/162] (888s left) prime_self_import (basic-with_fd_dup) [179.899308] Starting subtest: basic-with_fd_dup [179.905970] Subtest basic-with_fd_dup: SUCCESS (0.003s) [179.984104] [134/162] (888s left) prime_self_import (basic-with_one_bo) [180.028630] Starting subtest: basic-with_one_bo [180.035247] Subtest basic-with_one_bo: SUCCESS (0.002s) [180.081187] [135/162] (888s left) prime_self_import (basic-with_one_bo_two_files) [180.124721] Starting subtest: basic-with_one_bo_two_files [180.131147] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [180.194701] [136/162] (888s left) prime_self_import (basic-with_two_bos) [180.239899] Starting subtest: basic-with_two_bos [180.246166] Subtest basic-with_two_bos: SUCCESS (0.002s) [180.318297] [137/162] (888s left) prime_vgem (basic-fence-flip) [180.370639] Starting subtest: basic-fence-flip [180.427111] Subtest basic-fence-flip: SKIP (0.053s) [180.472694] [138/162] (887s left) prime_vgem (basic-fence-mmap) [180.525524] Starting subtest: basic-fence-mmap [180.541637] Subtest basic-fence-mmap: SUCCESS (0.018s) [180.633847] [139/162] (887s left) prime_vgem (basic-fence-read) [180.684495] Starting subtest: basic-fence-read [180.724451] Subtest basic-fence-read: SUCCESS (0.041s) [180.799368] [140/162] (887s left) prime_vgem (basic-gtt) [180.849965] Starting subtest: basic-gtt [180.857220] Subtest basic-gtt: SUCCESS (0.008s) [180.927450] [141/162] (887s left) prime_vgem (basic-read) [180.976352] Starting subtest: basic-read [180.995915] Subtest basic-read: SUCCESS (0.019s) [181.071790] [142/162] (887s left) prime_vgem (basic-write) [181.123251] Starting subtest: basic-write [181.141517] Subtest basic-write: SUCCESS (0.021s) [181.216562] [143/162] (887s left) vgem_basic (setversion) [181.266160] Starting subtest: setversion [181.272305] Subtest setversion: SUCCESS (0.000s) [181.317901] [144/162] (887s left) vgem_basic (create) [181.368232] Starting subtest: create [181.371505] Subtest create: SUCCESS (0.000s) [181.420218] [145/162] (887s left) vgem_basic (debugfs) [181.470859] Starting subtest: debugfs [181.474242] Subtest debugfs: SUCCESS (0.000s) [181.522381] [146/162] (887s left) vgem_basic (dmabuf-export) [181.569300] Starting subtest: dmabuf-export [181.576162] Subtest dmabuf-export: SUCCESS (0.002s) [181.638034] [147/162] (887s left) vgem_basic (dmabuf-fence) [181.685523] Starting subtest: dmabuf-fence [181.688478] Subtest dmabuf-fence: SUCCESS (0.000s) [181.737287] [148/162] (887s left) vgem_basic (dmabuf-fence-before) [181.787971] Starting subtest: dmabuf-fence-before [181.791238] Subtest dmabuf-fence-before: SUCCESS (0.000s) [181.840633] [149/162] (887s left) vgem_basic (dmabuf-mmap) [181.888307] Starting subtest: dmabuf-mmap [181.894863] Subtest dmabuf-mmap: SUCCESS (0.008s) [181.941273] [150/162] (887s left) vgem_basic (mmap) [181.988852] Starting subtest: mmap [181.992080] Subtest mmap: SUCCESS (0.005s) [182.039506] [151/162] (886s left) vgem_basic (second-client) [182.089341] Starting subtest: second-client [182.095507] Subtest second-client: SUCCESS (0.002s) [182.141204] [152/162] (886s left) vgem_basic (sysfs) [182.192406] Starting subtest: sysfs [182.195509] Subtest sysfs: SUCCESS (0.000s) [182.241454] [153/162] (886s left) core_hotunplug (unbind-rebind) [182.341944] Starting subtest: unbind-rebind [186.211425] Subtest unbind-rebind: SUCCESS (3.769s) [186.590987] [154/162] (882s left) vgem_basic (unload) [186.638811] Starting subtest: unload [215.563788] Subtest unload: SUCCESS (28.930s) [215.626221] [155/162] (854s left) i915_module_load (reload) [215.675365] Starting subtest: reload [218.560862] Subtest reload: SUCCESS (2.888s) [218.602679] [156/162] (851s left) gem_lmem_swapping (basic) [218.659167] Subtest basic: SKIP (0.000s) [218.701660] [157/162] (851s left) gem_lmem_swapping (parallel-random-engines) [218.751176] Subtest parallel-random-engines: SKIP (0.000s) [218.792891] [158/162] (850s left) gem_lmem_swapping (random-engines) [218.842163] Subtest random-engines: SKIP (0.000s) [218.883680] [159/162] (850s left) gem_lmem_swapping (verify-random) [218.933392] Subtest verify-random: SKIP (0.000s) [218.975683] [160/162] (850s left) i915_pm_rpm (module-reload) [230.308972] Starting subtest: module-reload [258.961748] Subtest module-reload: SUCCESS (28.653s) [258.991873] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [258.992882] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true [258.996985] [161/162] (810s left) i915_selftest (live) [259.045509] Starting subtest: live [259.048772] Starting dynamic subtest: sanitycheck [261.623901] Dynamic subtest sanitycheck: SUCCESS (2.580s) [261.644639] Starting dynamic subtest: uncore [264.080536] Dynamic subtest uncore: SUCCESS (2.447s) [264.098945] Starting dynamic subtest: workarounds [266.760178] Dynamic subtest workarounds: SUCCESS (2.682s) [266.773202] Starting dynamic subtest: gt_engines [269.640438] Dynamic subtest gt_engines: SUCCESS (2.876s) [269.651874] Starting dynamic subtest: gt_timelines [278.510906] Dynamic subtest gt_timelines: SUCCESS (8.880s) [278.523421] Starting dynamic subtest: gt_contexts [281.130320] Dynamic subtest gt_contexts: SUCCESS (2.612s) [281.141182] Starting dynamic subtest: gt_lrc [286.024098] Dynamic subtest gt_lrc: SUCCESS (4.900s) [286.046146] Starting dynamic subtest: gt_mocs [288.660292] Dynamic subtest gt_mocs: SUCCESS (2.635s) [288.675450] Starting dynamic subtest: gt_pm [295.490846] Dynamic subtest gt_pm: SUCCESS (6.830s) [295.504408] Starting dynamic subtest: gt_heartbeat [297.960610] Dynamic subtest gt_heartbeat: SUCCESS (2.463s) [297.971307] Starting dynamic subtest: gt_tlb [301.650210] Dynamic subtest gt_tlb: SUCCESS (3.694s) [301.661801] Starting dynamic subtest: requests [310.352573] Dynamic subtest requests: SUCCESS (8.702s) [310.365930] Starting dynamic subtest: migrate [316.002513] Dynamic subtest migrate: SUCCESS (5.645s) [316.015148] Starting dynamic subtest: active [318.500444] Dynamic subtest active: SUCCESS (2.492s) [318.512945] Starting dynamic subtest: objects [321.720623] Dynamic subtest objects: SUCCESS (3.220s) [321.747710] Starting dynamic subtest: mman [327.470957] Dynamic subtest mman: SUCCESS (5.756s) [327.482615] Starting dynamic subtest: dmabuf [330.010758] Dynamic subtest dmabuf: SUCCESS (2.532s) [330.032338] Starting dynamic subtest: vma [332.508126] Dynamic subtest vma: SUCCESS (2.445s) [332.574048] Starting dynamic subtest: coherency [335.690942] Dynamic subtest coherency: SUCCESS (3.231s) [335.710394] Starting dynamic subtest: gtt [345.687703] Dynamic subtest gtt: SUCCESS (10.005s) [345.699774] Starting dynamic subtest: gem [348.340748] Dynamic subtest gem: SUCCESS (2.648s) [348.353663] Starting dynamic subtest: evict [351.964086] Dynamic subtest evict: SUCCESS (3.626s) [351.976839] Starting dynamic subtest: hugepages [360.987044] Dynamic subtest hugepages: SUCCESS (9.022s) [360.998909] Starting dynamic subtest: gem_contexts [376.118465] Dynamic subtest gem_contexts: SUCCESS (15.131s) [376.129073] Starting dynamic subtest: client [378.657324] Dynamic subtest client: SUCCESS (2.526s) [378.673320] Starting dynamic subtest: gem_migrate [381.200698] Dynamic subtest gem_migrate: SUCCESS (2.550s) [381.213170] Starting dynamic subtest: reset [384.000586] Dynamic subtest reset: SUCCESS (2.799s) [384.013994] Starting dynamic subtest: memory_region [386.621014] Dynamic subtest memory_region: SUCCESS (2.618s) [386.631166] Starting dynamic subtest: hangcheck [412.412176] Dynamic subtest hangcheck: SUCCESS (25.794s) [412.425079] Starting dynamic subtest: execlists [423.366173] Dynamic subtest execlists: SUCCESS (10.953s) [423.378957] Starting dynamic subtest: ring_submission [425.890908] Dynamic subtest ring_submission: SUCCESS (2.521s) [425.906017] Starting dynamic subtest: perf [428.460887] Dynamic subtest perf: SUCCESS (2.567s) [428.471519] Starting dynamic subtest: slpc [430.920628] Dynamic subtest slpc: SUCCESS (2.456s) [430.931612] Starting dynamic subtest: guc [433.440766] Dynamic subtest guc: SUCCESS (2.523s) [433.453193] Starting dynamic subtest: guc_multi_lrc [435.891093] Dynamic subtest guc_multi_lrc: SUCCESS (2.441s) [435.901391] Starting dynamic subtest: guc_hang [438.340759] Dynamic subtest guc_hang: SUCCESS (2.453s) [438.353754] Starting dynamic subtest: late_gt_pm [440.930658] Dynamic subtest late_gt_pm: SUCCESS (2.588s) [440.933803] Subtest live: SUCCESS (181.883s) [440.970489] [162/162] (628s left) dmabuf (all-tests) [441.026018] Starting subtest: all-tests [441.029298] Starting dynamic subtest: sanitycheck [441.118787] Dynamic subtest sanitycheck: SUCCESS (0.094s) [441.142279] Starting dynamic subtest: dma_fence [441.352901] Dynamic subtest dma_fence: SUCCESS (0.231s) [441.378092] Starting dynamic subtest: dma_fence_chain [450.902848] Dynamic subtest dma_fence_chain: SUCCESS (9.547s) [450.915151] Starting dynamic subtest: dma_fence_unwrap [451.008851] Dynamic subtest dma_fence_unwrap: SUCCESS (0.103s) [451.032589] Starting dynamic subtest: dma_resv [451.117216] Dynamic subtest dma_resv: SUCCESS (0.105s) [451.120398] Subtest all-tests: SUCCESS (10.094s) [451.152357] Closing watchdogs results: parsing output: 0/ for test: i915_module_load results: parsing output: 1/ for test: core_auth results: parsing output: 2/ for test: core_debugfs results: parsing output: 3/ for test: core_sysfs results: parsing output: 4/ for test: fbdev results: parsing output: 5/ for test: fbdev results: parsing output: 6/ for test: fbdev results: parsing output: 7/ for test: fbdev results: parsing output: 8/ for test: fbdev results: parsing output: 9/ for test: gem_basic results: parsing output: 10/ for test: gem_basic results: parsing output: 11/ for test: gem_basic results: parsing output: 12/ for test: gem_busy results: parsing output: 13/ for test: gem_close_race results: parsing output: 14/ for test: gem_close_race results: parsing output: 15/ for test: gem_ctx_create results: parsing output: 16/ for test: gem_ctx_create results: parsing output: 17/ for test: gem_ctx_exec results: parsing output: 18/ for test: gem_exec_basic results: parsing output: 19/ for test: gem_exec_create results: parsing output: 20/ for test: gem_exec_fence results: parsing output: 21/ for test: gem_exec_fence results: parsing output: 22/ for test: gem_exec_fence results: parsing output: 23/ for test: gem_exec_fence results: parsing output: 24/ for test: gem_exec_gttfill results: parsing output: 25/ for test: gem_exec_parallel results: parsing output: 26/ for test: gem_exec_store results: parsing output: 27/ for test: gem_flink_basic results: parsing output: 28/ for test: gem_flink_basic results: parsing output: 29/ for test: gem_flink_basic results: parsing output: 30/ for test: gem_flink_basic results: parsing output: 31/ for test: gem_flink_basic results: parsing output: 32/ for test: gem_huc_copy results: parsing output: 33/ for test: gem_linear_blits results: parsing output: 34/ for test: gem_mmap results: parsing output: 35/ for test: gem_mmap_gtt results: parsing output: 36/ for test: gem_render_linear_blits results: parsing output: 37/ for test: gem_render_tiled_blits results: parsing output: 38/ for test: gem_ringfill results: parsing output: 39/ for test: gem_softpin results: parsing output: 40/ for test: gem_softpin results: parsing output: 41/ for test: gem_softpin results: parsing output: 42/ for test: gem_sync results: parsing output: 43/ for test: gem_sync results: parsing output: 44/ for test: gem_tiled_blits results: parsing output: 45/ for test: gem_tiled_fence_blits results: parsing output: 46/ for test: gem_tiled_pread_basic results: parsing output: 47/ for test: gem_wait results: parsing output: 48/ for test: gem_wait results: parsing output: 49/ for test: i915_getparams_basic results: parsing output: 50/ for test: i915_getparams_basic results: parsing output: 51/ for test: i915_hangman results: parsing output: 52/ for test: i915_pciid results: parsing output: 53/ for test: intel_hwmon results: parsing output: 54/ for test: intel_hwmon results: parsing output: 55/ for test: kms_addfb_basic results: parsing output: 56/ for test: kms_addfb_basic results: parsing output: 57/ for test: kms_addfb_basic results: parsing output: 58/ for test: kms_addfb_basic results: parsing output: 59/ for test: kms_addfb_basic results: parsing output: 60/ for test: kms_addfb_basic results: parsing output: 61/ for test: kms_addfb_basic results: parsing output: 62/ for test: kms_addfb_basic results: parsing output: 63/ for test: kms_addfb_basic results: parsing output: 64/ for test: kms_addfb_basic results: parsing output: 65/ for test: kms_addfb_basic results: parsing output: 66/ for test: kms_addfb_basic results: parsing output: 67/ for test: kms_addfb_basic results: parsing output: 68/ for test: kms_addfb_basic results: parsing output: 69/ for test: kms_addfb_basic results: parsing output: 70/ for test: kms_addfb_basic results: parsing output: 71/ for test: kms_addfb_basic results: parsing output: 72/ for test: kms_addfb_basic results: parsing output: 73/ for test: kms_addfb_basic results: parsing output: 74/ for test: kms_addfb_basic results: parsing output: 75/ for test: kms_addfb_basic results: parsing output: 76/ for test: kms_addfb_basic results: parsing output: 77/ for test: kms_addfb_basic results: parsing output: 78/ for test: kms_addfb_basic results: parsing output: 79/ for test: kms_addfb_basic results: parsing output: 80/ for test: kms_addfb_basic results: parsing output: 81/ for test: kms_addfb_basic results: parsing output: 82/ for test: kms_addfb_basic results: parsing output: 83/ for test: kms_addfb_basic results: parsing output: 84/ for test: kms_addfb_basic results: parsing output: 85/ for test: kms_addfb_basic results: parsing output: 86/ for test: kms_addfb_basic results: parsing output: 87/ for test: kms_addfb_basic results: parsing output: 88/ for test: kms_addfb_basic results: parsing output: 89/ for test: kms_addfb_basic results: parsing output: 90/ for test: kms_addfb_basic results: parsing output: 91/ for test: kms_addfb_basic results: parsing output: 92/ for test: kms_addfb_basic results: parsing output: 93/ for test: kms_busy results: parsing output: 94/ for test: kms_prop_blob results: parsing output: 95/ for test: kms_cursor_legacy results: parsing output: 96/ for test: kms_cursor_legacy results: parsing output: 97/ for test: kms_cursor_legacy results: parsing output: 98/ for test: kms_cursor_legacy results: parsing output: 99/ for test: kms_cursor_legacy results: parsing output: 100/ for test: kms_cursor_legacy results: parsing output: 101/ for test: kms_cursor_legacy results: parsing output: 102/ for test: kms_cursor_legacy results: parsing output: 103/ for test: kms_dsc results: parsing output: 104/ for test: kms_flip results: parsing output: 105/ for test: kms_flip results: parsing output: 106/ for test: kms_flip results: parsing output: 107/ for test: kms_flip results: parsing output: 108/ for test: kms_force_connector_basic results: parsing output: 109/ for test: kms_force_connector_basic results: parsing output: 110/ for test: kms_force_connector_basic results: parsing output: 111/ for test: kms_force_connector_basic results: parsing output: 112/ for test: kms_frontbuffer_tracking results: parsing output: 113/ for test: kms_hdmi_inject results: parsing output: 114/ for test: kms_pipe_crc_basic results: parsing output: 115/ for test: kms_pipe_crc_basic results: parsing output: 116/ for test: kms_pipe_crc_basic results: parsing output: 117/ for test: kms_pipe_crc_basic results: parsing output: 118/ for test: kms_pipe_crc_basic results: parsing output: 119/ for test: kms_pipe_crc_basic results: parsing output: 120/ for test: kms_pipe_crc_basic results: parsing output: 121/ for test: kms_pm_backlight results: parsing output: 122/ for test: kms_pm_rpm results: parsing output: 123/ for test: kms_pm_rpm results: parsing output: 124/ for test: kms_psr results: parsing output: 125/ for test: kms_psr results: parsing output: 126/ for test: kms_psr results: parsing output: 127/ for test: kms_psr results: parsing output: 128/ for test: kms_setmode results: parsing output: 129/ for test: i915_pm_rps results: parsing output: 130/ for test: prime_self_import results: parsing output: 131/ for test: prime_self_import results: parsing output: 132/ for test: prime_self_import results: parsing output: 133/ for test: prime_self_import results: parsing output: 134/ for test: prime_self_import results: parsing output: 135/ for test: prime_self_import results: parsing output: 136/ for test: prime_vgem results: parsing output: 137/ for test: prime_vgem results: parsing output: 138/ for test: prime_vgem results: parsing output: 139/ for test: prime_vgem results: parsing output: 140/ for test: prime_vgem results: parsing output: 141/ for test: prime_vgem results: parsing output: 142/ for test: vgem_basic results: parsing output: 143/ for test: vgem_basic results: parsing output: 144/ for test: vgem_basic results: parsing output: 145/ for test: vgem_basic results: parsing output: 146/ for test: vgem_basic results: parsing output: 147/ for test: vgem_basic results: parsing output: 148/ for test: vgem_basic results: parsing output: 149/ for test: vgem_basic results: parsing output: 150/ for test: vgem_basic results: parsing output: 151/ for test: vgem_basic results: parsing output: 152/ for test: core_hotunplug results: parsing output: 153/ for test: vgem_basic results: parsing output: 154/ for test: i915_module_load results: parsing output: 155/ for test: gem_lmem_swapping results: parsing output: 156/ for test: gem_lmem_swapping results: parsing output: 157/ for test: gem_lmem_swapping results: parsing output: 158/ for test: gem_lmem_swapping results: parsing output: 159/ for test: i915_pm_rpm results: parsing output: 160/ for test: i915_selftest results: parsing output: 161/ for test: dmabuf Done.