[42.936430] Initializing watchdogs [42.936552] /dev/watchdog0 [42.953571] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0412 Intel Haswell (Gen7) Xeon E3-1200 v3/4th Gen Core Processor Integrated Graphics Controller (ThinkCentre M83) [42.959412] [001/162] (960s left) i915_module_load (load) [43.299522] Starting subtest: load [44.816150] Subtest load: SUCCESS (1.516s) [44.850294] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [44.852151] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [44.855032] [002/162] (958s left) core_auth (basic-auth) [44.908100] Starting subtest: basic-auth [44.909200] Subtest basic-auth: SUCCESS (0.001s) [44.987708] [003/162] (958s left) core_debugfs (read-all-entries) [45.031876] Starting subtest: read-all-entries [45.099241] Subtest read-all-entries: SUCCESS (0.067s) [45.187673] [004/162] (958s left) core_sysfs (read-all-entries) [45.236315] Starting subtest: read-all-entries [45.239504] Subtest read-all-entries: SUCCESS (0.004s) [45.325776] [005/162] (958s left) fbdev (eof) [45.384914] Starting subtest: eof [45.385954] Subtest eof: SUCCESS (0.001s) [45.502106] [006/162] (958s left) fbdev (info) [45.544457] Starting subtest: info [45.545619] Subtest info: SUCCESS (0.000s) [45.621388] [007/162] (958s left) fbdev (nullptr) [45.683765] Starting subtest: nullptr [45.684951] Subtest nullptr: SUCCESS (0.000s) [45.754326] [008/162] (958s left) fbdev (read) [45.815832] Starting subtest: read [45.899051] Subtest read: SUCCESS (0.083s) [45.971040] [009/162] (957s left) fbdev (write) [46.033143] Starting subtest: write [46.072930] Subtest write: SUCCESS (0.043s) [46.164119] [010/162] (957s left) gem_basic (bad-close) [46.205938] Starting subtest: bad-close [46.207866] Subtest bad-close: SUCCESS (0.000s) [46.292996] [011/162] (957s left) gem_basic (create-close) [46.336969] Starting subtest: create-close [46.338848] Subtest create-close: SUCCESS (0.000s) [46.433481] [012/162] (957s left) gem_basic (create-fd-close) [46.478628] Starting subtest: create-fd-close [46.480856] Subtest create-fd-close: SUCCESS (0.000s) [46.576564] [013/162] (957s left) gem_busy (busy@all-engines) [46.638696] Starting subtest: busy [46.639707] Starting dynamic subtest: all-engines [46.666250] Dynamic subtest all-engines: SUCCESS (0.028s) [46.667381] Subtest busy: SUCCESS (0.029s) [47.004684] [014/162] (956s left) gem_close_race (basic-process) [47.065127] Starting subtest: basic-process [47.101910] Subtest basic-process: SUCCESS (0.039s) [47.291591] [015/162] (956s left) gem_close_race (basic-threads) [47.349610] Starting subtest: basic-threads [48.522059] Subtest basic-threads: SUCCESS (1.220s) [48.702091] [016/162] (955s left) gem_ctx_create (basic) [48.754384] Starting subtest: basic [48.755462] Subtest basic: SUCCESS (0.000s) [48.943550] [017/162] (955s left) gem_ctx_create (basic-files) [48.998368] Starting subtest: basic-files [51.010912] Subtest basic-files: SUCCESS (2.074s) [51.222587] [018/162] (952s left) gem_ctx_exec (basic) [51.277570] Starting subtest: basic [51.282757] Subtest basic: SUCCESS (0.006s) [51.404625] [019/162] (952s left) gem_exec_basic (basic) [51.456615] Starting subtest: basic [51.457713] Starting dynamic subtest: rcs0-smem [51.458772] Dynamic subtest rcs0-smem: SUCCESS (0.001s) [51.469096] Starting dynamic subtest: bcs0-smem [51.470528] Dynamic subtest bcs0-smem: SUCCESS (0.000s) [51.480440] Starting dynamic subtest: vcs0-smem [51.481589] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [51.491488] Starting dynamic subtest: vecs0-smem [51.492560] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [51.493744] Subtest basic: SUCCESS (0.003s) [51.647879] [020/162] (952s left) gem_exec_create (basic) [51.702962] Starting subtest: basic [51.704059] Starting dynamic subtest: smem [53.753887] Dynamic subtest smem: SUCCESS (2.093s) [53.754997] Subtest basic: SUCCESS (2.094s) [53.952572] [021/162] (950s left) gem_exec_fence (basic-busy) [54.132249] Starting subtest: basic-busy [54.133357] Starting dynamic subtest: rcs0 [54.179401] Dynamic subtest rcs0: SUCCESS (0.048s) [54.189347] Starting dynamic subtest: bcs0 [54.216711] Dynamic subtest bcs0: SUCCESS (0.037s) [54.226627] Starting dynamic subtest: vcs0 [54.252881] Dynamic subtest vcs0: SUCCESS (0.036s) [54.263739] Starting dynamic subtest: vecs0 [54.287102] Dynamic subtest vecs0: SUCCESS (0.034s) [54.288463] Subtest basic-busy: SUCCESS (0.159s) [54.769806] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [54.771973] [022/162] (949s left) gem_exec_fence (basic-wait) [54.942332] Starting subtest: basic-wait [54.943450] Starting dynamic subtest: rcs0 [54.978151] Dynamic subtest rcs0: SUCCESS (0.035s) [54.988504] Starting dynamic subtest: bcs0 [55.018225] Dynamic subtest bcs0: SUCCESS (0.039s) [55.029234] Starting dynamic subtest: vcs0 [55.055062] Dynamic subtest vcs0: SUCCESS (0.036s) [55.065674] Starting dynamic subtest: vecs0 [55.088151] Dynamic subtest vecs0: SUCCESS (0.032s) [55.089273] Subtest basic-wait: SUCCESS (0.148s) [55.558571] [023/162] (948s left) gem_exec_fence (basic-await) [55.729371] Starting subtest: basic-await [55.730441] Starting dynamic subtest: rcs0 [55.805985] Dynamic subtest rcs0: SUCCESS (0.077s) [55.816707] Starting dynamic subtest: bcs0 [55.881858] Dynamic subtest bcs0: SUCCESS (0.076s) [55.891809] Starting dynamic subtest: vcs0 [55.958476] Dynamic subtest vcs0: SUCCESS (0.076s) [55.970141] Starting dynamic subtest: vecs0 [56.033941] Dynamic subtest vecs0: SUCCESS (0.075s) [56.035022] Subtest basic-await: SUCCESS (0.309s) [56.515204] [024/162] (947s left) gem_exec_fence (nb-await) [56.688583] Starting subtest: nb-await [56.689977] Starting dynamic subtest: rcs0 [56.746701] Dynamic subtest rcs0: SUCCESS (0.058s) [56.756375] Starting dynamic subtest: bcs0 [56.799657] Dynamic subtest bcs0: SUCCESS (0.054s) [56.809296] Starting dynamic subtest: vcs0 [56.853895] Dynamic subtest vcs0: SUCCESS (0.054s) [56.865246] Starting dynamic subtest: vecs0 [56.909565] Dynamic subtest vecs0: SUCCESS (0.055s) [56.910616] Subtest nb-await: SUCCESS (0.224s) [57.372116] [025/162] (947s left) gem_exec_gttfill (basic) [57.433522] Starting subtest: basic [61.189308] Subtest basic: SUCCESS (3.789s) [61.386249] [026/162] (943s left) gem_exec_parallel (engines) [61.510227] Starting subtest: engines [61.511413] Starting dynamic subtest: basic [63.168159] Dynamic subtest basic: SUCCESS (1.667s) [63.180550] Starting dynamic subtest: contexts [64.987841] Dynamic subtest contexts: SUCCESS (1.826s) [64.999097] Starting dynamic subtest: fds [66.668146] Dynamic subtest fds: SUCCESS (1.687s) [66.679499] Starting dynamic subtest: userptr [68.170447] Dynamic subtest userptr: SUCCESS (1.506s) [68.171691] Subtest engines: SUCCESS (6.689s) [68.392543] [027/162] (936s left) gem_exec_store (basic) [68.449765] Starting subtest: basic [68.503758] Subtest basic: SUCCESS (0.054s) [68.703344] [028/162] (935s left) gem_flink_basic (bad-flink) [68.753445] Starting subtest: bad-flink [68.754651] Subtest bad-flink: SUCCESS (0.000s) [68.837624] [029/162] (935s left) gem_flink_basic (bad-open) [68.886225] Starting subtest: bad-open [68.887263] Subtest bad-open: SUCCESS (0.000s) [68.978893] [030/162] (935s left) gem_flink_basic (basic) [69.027654] Starting subtest: basic [69.028748] Subtest basic: SUCCESS (0.000s) [69.119134] [031/162] (935s left) gem_flink_basic (double-flink) [69.166424] Starting subtest: double-flink [69.167535] Subtest double-flink: SUCCESS (0.000s) [69.284106] [032/162] (935s left) gem_flink_basic (flink-lifetime) [69.331384] Starting subtest: flink-lifetime [69.332472] Subtest flink-lifetime: SUCCESS (0.001s) [69.435608] [033/162] (935s left) gem_huc_copy (huc-copy) [69.485167] Subtest huc-copy: SKIP (0.000s) [69.545264] [034/162] (935s left) gem_linear_blits (basic) [69.659642] Starting subtest: basic [69.669836] Subtest basic: SUCCESS (0.011s) [69.797479] [035/162] (935s left) gem_mmap (basic) [69.924921] Starting subtest: basic [69.930479] Subtest basic: SUCCESS (0.000s) [70.017097] [036/162] (934s left) gem_mmap_gtt (basic) [70.064360] Starting subtest: basic [70.065382] Subtest basic: SUCCESS (0.001s) [70.180043] [037/162] (934s left) gem_render_linear_blits (basic) [70.230224] Starting subtest: basic [70.247416] Subtest basic: SUCCESS (0.018s) [70.312520] [038/162] (934s left) gem_render_tiled_blits (basic) [70.362719] Starting subtest: basic [70.390713] Subtest basic: SUCCESS (0.028s) [70.460799] [039/162] (934s left) gem_ringfill (basic-all) [70.676153] Starting subtest: basic-all [71.781601] Subtest basic-all: SUCCESS (1.107s) [71.898034] [040/162] (933s left) gem_softpin (allocator-basic) [71.949458] Subtest allocator-basic: SKIP (0.000s) [72.031373] [041/162] (933s left) gem_softpin (allocator-basic-reserve) [72.081280] Subtest allocator-basic-reserve: SKIP (0.000s) [72.161986] [042/162] (933s left) gem_softpin (safe-alignment) [72.215138] Starting subtest: safe-alignment [72.225023] Subtest safe-alignment: SUCCESS (0.011s) [72.348081] [043/162] (932s left) gem_sync (basic-all) [72.410549] Starting subtest: basic-all [74.648247] Subtest basic-all: SUCCESS (2.241s) [74.839836] [044/162] (930s left) gem_sync (basic-each) [74.909782] Starting subtest: basic-each [77.007427] Subtest basic-each: SUCCESS (2.099s) [77.207511] [045/162] (928s left) gem_tiled_blits (basic) [77.343815] Starting subtest: basic [77.357234] Subtest basic: SUCCESS (0.013s) [77.466426] [046/162] (928s left) gem_tiled_fence_blits (basic) [77.663458] Starting subtest: basic [77.670950] Subtest basic: SUCCESS (0.008s) [77.799903] [047/162] (927s left) gem_tiled_pread_basic (basic) [77.928465] Starting subtest: basic [78.066034] Subtest basic: SUCCESS (0.138s) [78.171913] [048/162] (927s left) gem_wait (busy@all-engines) [78.234331] Starting subtest: busy [78.235463] Starting dynamic subtest: all-engines [78.752026] Dynamic subtest all-engines: SUCCESS (0.517s) [78.753102] Subtest busy: SUCCESS (0.518s) [78.971387] [049/162] (926s left) gem_wait (wait@all-engines) [79.035205] Starting subtest: wait [79.036328] Starting dynamic subtest: all-engines [80.066397] Dynamic subtest all-engines: SUCCESS (1.031s) [80.067856] Subtest wait: SUCCESS (1.032s) [80.270550] [050/162] (925s left) i915_getparams_basic (basic-eu-total) [80.320255] Starting subtest: basic-eu-total [80.322377] Subtest basic-eu-total: SUCCESS (0.000s) [80.404699] [051/162] (925s left) i915_getparams_basic (basic-subslice-total) [80.451566] Starting subtest: basic-subslice-total [80.453737] Subtest basic-subslice-total: SUCCESS (0.000s) [80.538610] [052/162] (925s left) i915_hangman (error-state-basic) [80.591632] Starting subtest: error-state-basic [80.596450] Subtest error-state-basic: SUCCESS (0.004s) [80.687904] [053/162] (925s left) i915_pciid [80.821661] [054/162] (925s left) intel_hwmon (hwmon-read) [80.873329] Subtest hwmon-read: SKIP (0.000s) [80.918101] [055/162] (924s left) intel_hwmon (hwmon-write) [80.965121] Subtest hwmon-write: SKIP (0.000s) [81.020377] [056/162] (924s left) kms_addfb_basic (addfb25-4-tiled) [81.118427] Starting subtest: addfb25-4-tiled [81.119468] Subtest addfb25-4-tiled: SUCCESS (0.000s) [81.225497] [057/162] (924s left) kms_addfb_basic (addfb25-bad-modifier) [81.314364] Starting subtest: addfb25-bad-modifier [81.315363] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [81.421663] [058/162] (924s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [81.512368] Starting subtest: addfb25-framebuffer-vs-set-tiling [81.513359] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [81.626402] [059/162] (924s left) kms_addfb_basic (addfb25-modifier-no-flag) [81.717460] Starting subtest: addfb25-modifier-no-flag [81.718515] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [81.825146] [060/162] (924s left) kms_addfb_basic (addfb25-x-tiled-legacy) [81.911840] Starting subtest: addfb25-x-tiled-legacy [81.912921] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [82.021963] [061/162] (924s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [82.109280] Starting subtest: addfb25-x-tiled-mismatch-legacy [82.110368] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [82.207888] [062/162] (924s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [82.294411] Starting subtest: addfb25-yf-tiled-legacy [82.295421] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [82.407439] [063/162] (923s left) kms_addfb_basic (addfb25-y-tiled-legacy) [82.499688] Starting subtest: addfb25-y-tiled-legacy [82.500756] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [82.605032] [064/162] (923s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [82.690389] Starting subtest: addfb25-y-tiled-small-legacy [82.692465] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s) [82.795897] [065/162] (923s left) kms_addfb_basic (bad-pitch-0) [82.842164] Starting subtest: bad-pitch-0 [82.843232] Subtest bad-pitch-0: SUCCESS (0.000s) [82.990820] [066/162] (923s left) kms_addfb_basic (bad-pitch-1024) [83.037200] Starting subtest: bad-pitch-1024 [83.038286] Subtest bad-pitch-1024: SUCCESS (0.000s) [83.191440] [067/162] (923s left) kms_addfb_basic (bad-pitch-128) [83.236158] Starting subtest: bad-pitch-128 [83.237132] Subtest bad-pitch-128: SUCCESS (0.000s) [83.374146] [068/162] (923s left) kms_addfb_basic (bad-pitch-256) [83.421444] Starting subtest: bad-pitch-256 [83.422556] Subtest bad-pitch-256: SUCCESS (0.000s) [83.573072] [069/162] (923s left) kms_addfb_basic (bad-pitch-32) [83.621618] Starting subtest: bad-pitch-32 [83.622776] Subtest bad-pitch-32: SUCCESS (0.000s) [83.755965] [070/162] (922s left) kms_addfb_basic (bad-pitch-63) [83.801996] Starting subtest: bad-pitch-63 [83.803033] Subtest bad-pitch-63: SUCCESS (0.000s) [83.939069] [071/162] (922s left) kms_addfb_basic (bad-pitch-65536) [83.985937] Starting subtest: bad-pitch-65536 [83.987111] Subtest bad-pitch-65536: SUCCESS (0.000s) [84.121879] [072/162] (922s left) kms_addfb_basic (bad-pitch-999) [84.170346] Starting subtest: bad-pitch-999 [84.171420] Subtest bad-pitch-999: SUCCESS (0.000s) [84.306189] [073/162] (922s left) kms_addfb_basic (basic) [84.353029] Starting subtest: basic [84.354079] Subtest basic: SUCCESS (0.000s) [84.491049] [074/162] (922s left) kms_addfb_basic (basic-x-tiled-legacy) [84.537261] Starting subtest: basic-x-tiled-legacy [84.538349] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [84.690678] [075/162] (922s left) kms_addfb_basic (basic-y-tiled-legacy) [84.745348] Starting subtest: basic-y-tiled-legacy [84.746388] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [84.888717] [076/162] (922s left) kms_addfb_basic (bo-too-small) [84.977119] Starting subtest: bo-too-small [84.978140] Subtest bo-too-small: SUCCESS (0.000s) [85.091149] [077/162] (921s left) kms_addfb_basic (bo-too-small-due-to-tiling) [85.179822] Starting subtest: bo-too-small-due-to-tiling [85.180920] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [85.308942] [078/162] (921s left) kms_addfb_basic (clobberred-modifier) [85.355555] Starting subtest: clobberred-modifier [85.356662] Subtest clobberred-modifier: SUCCESS (0.000s) [85.507239] [079/162] (921s left) kms_addfb_basic (framebuffer-vs-set-tiling) [85.554577] Starting subtest: framebuffer-vs-set-tiling [85.555712] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [85.706232] [080/162] (921s left) kms_addfb_basic (invalid-get-prop) [85.753638] Starting subtest: invalid-get-prop [85.754795] Subtest invalid-get-prop: SUCCESS (0.000s) [85.905696] [081/162] (921s left) kms_addfb_basic (invalid-get-prop-any) [85.955845] Starting subtest: invalid-get-prop-any [85.956948] Subtest invalid-get-prop-any: SUCCESS (0.000s) [86.090812] [082/162] (921s left) kms_addfb_basic (invalid-set-prop) [86.143249] Starting subtest: invalid-set-prop [86.144253] Subtest invalid-set-prop: SUCCESS (0.000s) [86.291163] [083/162] (920s left) kms_addfb_basic (invalid-set-prop-any) [86.343837] Starting subtest: invalid-set-prop-any [86.344877] Subtest invalid-set-prop-any: SUCCESS (0.000s) [86.489965] [084/162] (920s left) kms_addfb_basic (no-handle) [86.539832] Starting subtest: no-handle [86.540843] Subtest no-handle: SUCCESS (0.000s) [86.690789] [085/162] (920s left) kms_addfb_basic (size-max) [86.777096] Starting subtest: size-max [86.778184] Subtest size-max: SUCCESS (0.000s) [86.890039] [086/162] (920s left) kms_addfb_basic (small-bo) [86.977313] Starting subtest: small-bo [86.978296] Subtest small-bo: SUCCESS (0.000s) [87.090618] [087/162] (920s left) kms_addfb_basic (tile-pitch-mismatch) [87.140242] Starting subtest: tile-pitch-mismatch [87.141347] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [87.290380] [088/162] (920s left) kms_addfb_basic (too-high) [87.378563] Starting subtest: too-high [87.379656] Subtest too-high: SUCCESS (0.001s) [87.489195] [089/162] (920s left) kms_addfb_basic (too-wide) [87.575999] Starting subtest: too-wide [87.577108] Subtest too-wide: SUCCESS (0.000s) [87.690870] [090/162] (919s left) kms_addfb_basic (unused-handle) [87.738537] Starting subtest: unused-handle [87.739610] Subtest unused-handle: SUCCESS (0.000s) [87.890303] [091/162] (919s left) kms_addfb_basic (unused-modifier) [87.939298] Starting subtest: unused-modifier [87.940422] Subtest unused-modifier: SUCCESS (0.000s) [88.089778] [092/162] (919s left) kms_addfb_basic (unused-offsets) [88.136276] Starting subtest: unused-offsets [88.137317] Subtest unused-offsets: SUCCESS (0.000s) [88.290998] [093/162] (919s left) kms_addfb_basic (unused-pitches) [88.339369] Starting subtest: unused-pitches [88.340406] Subtest unused-pitches: SUCCESS (0.000s) [88.490379] [094/162] (919s left) kms_busy (basic) [88.579611] Starting subtest: basic [88.582144] Starting dynamic subtest: flip [89.034867] Dynamic subtest flip: SUCCESS (0.453s) [89.047393] Starting dynamic subtest: modeset [89.622699] Dynamic subtest modeset: SUCCESS (0.587s) [89.623906] Subtest basic: SUCCESS (1.042s) [89.806618] [095/162] (918s left) kms_prop_blob (basic) [89.856336] Starting subtest: basic [89.858783] Subtest basic: SUCCESS (0.000s) [89.955229] [096/162] (917s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [90.044068] Starting subtest: basic-busy-flip-before-cursor-atomic [90.968861] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.924s) [91.161759] [097/162] (916s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [91.250613] Starting subtest: basic-busy-flip-before-cursor-legacy [92.175237] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.924s) [92.363767] [098/162] (915s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [92.451116] Starting subtest: basic-flip-after-cursor-atomic [93.406255] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.955s) [93.598615] [099/162] (914s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [93.688361] Starting subtest: basic-flip-after-cursor-legacy [94.608611] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.920s) [94.800366] [100/162] (913s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [94.887377] Starting subtest: basic-flip-after-cursor-varying-size [95.827126] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.939s) [96.022796] [101/162] (912s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [96.108193] Starting subtest: basic-flip-before-cursor-atomic [97.034952] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.926s) [97.222448] [102/162] (910s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [97.309321] Starting subtest: basic-flip-before-cursor-legacy [98.247622] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.937s) [98.437217] [103/162] (909s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [98.523417] Starting subtest: basic-flip-before-cursor-varying-size [99.447054] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.923s) [99.638533] [104/162] (908s left) kms_dsc (dsc-basic) [99.726438] Subtest dsc-basic: SKIP (0.000s) [99.778206] [105/162] (908s left) kms_flip (basic-flip-vs-dpms) [99.867353] Starting subtest: basic-flip-vs-dpms [99.869393] Starting dynamic subtest: A-VGA1 [100.811229] Dynamic subtest A-VGA1: SUCCESS (0.942s) [100.823274] Starting dynamic subtest: B-VGA1 [101.714108] Dynamic subtest B-VGA1: SUCCESS (0.902s) [101.726679] Starting dynamic subtest: C-VGA1 [102.622570] Dynamic subtest C-VGA1: SUCCESS (0.907s) [102.623634] Subtest basic-flip-vs-dpms: SUCCESS (2.756s) [102.814205] [106/162] (905s left) kms_flip (basic-flip-vs-modeset) [102.906265] Starting subtest: basic-flip-vs-modeset [102.907309] Starting dynamic subtest: A-VGA1 [103.905857] Dynamic subtest A-VGA1: SUCCESS (1.000s) [103.917771] Starting dynamic subtest: B-VGA1 [104.717964] Dynamic subtest B-VGA1: SUCCESS (0.811s) [104.732967] Starting dynamic subtest: C-VGA1 [105.681502] Dynamic subtest C-VGA1: SUCCESS (0.961s) [105.682577] Subtest basic-flip-vs-modeset: SUCCESS (2.778s) [105.864415] [107/162] (902s left) kms_flip (basic-flip-vs-wf_vblank) [105.952079] Starting subtest: basic-flip-vs-wf_vblank [105.954286] Starting dynamic subtest: A-VGA1 [107.115851] Dynamic subtest A-VGA1: SUCCESS (1.162s) [107.129679] Starting dynamic subtest: B-VGA1 [108.291975] Dynamic subtest B-VGA1: SUCCESS (1.172s) [108.304752] Starting dynamic subtest: C-VGA1 [109.469359] Dynamic subtest C-VGA1: SUCCESS (1.176s) [109.472145] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.519s) [109.705899] [108/162] (898s left) kms_flip (basic-plain-flip) [109.794525] Starting subtest: basic-plain-flip [109.795605] Starting dynamic subtest: A-VGA1 [110.673898] Dynamic subtest A-VGA1: SUCCESS (0.878s) [110.690253] Starting dynamic subtest: B-VGA1 [111.553885] Dynamic subtest B-VGA1: SUCCESS (0.877s) [111.567980] Starting dynamic subtest: C-VGA1 [112.421608] Dynamic subtest C-VGA1: SUCCESS (0.865s) [112.425142] Subtest basic-plain-flip: SUCCESS (2.632s) [112.642779] [109/162] (895s left) kms_force_connector_basic (force-connector-state) [112.705820] Starting subtest: force-connector-state [112.893953] Subtest force-connector-state: SUCCESS (0.189s) [113.141391] [110/162] (895s left) kms_force_connector_basic (force-edid) [113.199318] Starting subtest: force-edid [113.219296] Subtest force-edid: SUCCESS (0.019s) [113.359358] [111/162] (895s left) kms_force_connector_basic (force-load-detect) [113.419473] Starting subtest: force-load-detect [113.632045] Subtest force-load-detect: SUCCESS (0.212s) [113.877446] [112/162] (894s left) kms_force_connector_basic (prune-stale-modes) [113.936567] Starting subtest: prune-stale-modes [113.953466] Subtest prune-stale-modes: SUCCESS (0.018s) [114.094739] [113/162] (894s left) kms_frontbuffer_tracking (basic) [114.188480] Starting subtest: basic [116.114478] Subtest basic: SUCCESS (1.927s) [116.227417] [114/162] (892s left) kms_hdmi_inject (inject-audio) [116.321367] Starting subtest: inject-audio [116.446598] Subtest inject-audio: SUCCESS (0.126s) [116.706115] [115/162] (892s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [116.794377] Starting subtest: compare-crc-sanitycheck-xr24 [116.795405] Starting dynamic subtest: pipe-A-VGA-1 [117.084737] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.289s) [117.097393] Starting dynamic subtest: pipe-B-VGA-1 [117.390727] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.305s) [117.403027] Starting dynamic subtest: pipe-C-VGA-1 [117.699369] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.307s) [117.700395] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.905s) [117.888453] [116/162] (890s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [117.975840] Starting subtest: compare-crc-sanitycheck-nv12 [117.976914] Starting dynamic subtest: pipe-A-VGA-1 [117.979260] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s) [117.992069] Starting dynamic subtest: pipe-B-VGA-1 [117.994524] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s) [118.006462] Starting dynamic subtest: pipe-C-VGA-1 [118.008860] Dynamic subtest pipe-C-VGA-1: SKIP (0.000s) [118.011161] Subtest compare-crc-sanitycheck-nv12: SKIP (0.005s) [118.072458] [117/162] (890s left) kms_pipe_crc_basic (hang-read-crc) [118.158475] Starting subtest: hang-read-crc [118.159524] Starting dynamic subtest: pipe-A-VGA-1 [118.952587] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.793s) [118.965249] Starting dynamic subtest: pipe-B-VGA-1 [119.744714] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.790s) [119.758304] Starting dynamic subtest: pipe-C-VGA-1 [120.523222] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.776s) [120.524355] Subtest hang-read-crc: SUCCESS (2.364s) [120.695500] [118/162] (888s left) kms_pipe_crc_basic (nonblocking-crc) [120.789442] Starting subtest: nonblocking-crc [120.790476] Starting dynamic subtest: pipe-A-VGA-1 [121.239132] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.447s) [121.253350] Starting dynamic subtest: pipe-B-VGA-1 [121.691409] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.451s) [121.705169] Starting dynamic subtest: pipe-C-VGA-1 [122.161823] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.469s) [122.162911] Subtest nonblocking-crc: SUCCESS (1.373s) [122.333852] [119/162] (886s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [122.421907] Starting subtest: nonblocking-crc-frame-sequence [122.422947] Starting dynamic subtest: pipe-A-VGA-1 [122.879313] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.457s) [122.893017] Starting dynamic subtest: pipe-B-VGA-1 [123.335401] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.453s) [123.347755] Starting dynamic subtest: pipe-C-VGA-1 [123.791287] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.455s) [123.792386] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.371s) [123.983495] [120/162] (885s left) kms_pipe_crc_basic (read-crc) [124.071247] Starting subtest: read-crc [124.072324] Starting dynamic subtest: pipe-A-VGA-1 [124.472934] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.400s) [124.485373] Starting dynamic subtest: pipe-B-VGA-1 [124.863491] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.388s) [124.877263] Starting dynamic subtest: pipe-C-VGA-1 [125.249355] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.384s) [125.250402] Subtest read-crc: SUCCESS (1.178s) [125.421864] [121/162] (883s left) kms_pipe_crc_basic (read-crc-frame-sequence) [125.508460] Starting subtest: read-crc-frame-sequence [125.509579] Starting dynamic subtest: pipe-A-VGA-1 [125.897410] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.388s) [125.911416] Starting dynamic subtest: pipe-B-VGA-1 [126.286422] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.388s) [126.303956] Starting dynamic subtest: pipe-C-VGA-1 [126.683120] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.394s) [126.684300] Subtest read-crc-frame-sequence: SUCCESS (1.175s) [126.857479] [122/162] (882s left) kms_pm_backlight (basic-brightness) [126.947524] Subtest basic-brightness: SKIP (0.000s) [127.003179] [123/162] (882s left) kms_pm_rpm (basic-pci-d3-state) [128.370702] Starting subtest: basic-pci-d3-state [128.611355] Subtest basic-pci-d3-state: SUCCESS (0.240s) [128.722299] [124/162] (880s left) kms_pm_rpm (basic-rte) [128.768180] Starting subtest: basic-rte [130.325859] Subtest basic-rte: SUCCESS (1.557s) [130.784844] [125/162] (878s left) kms_psr (psr-primary-page-flip) [130.872339] Subtest psr-primary-page-flip: SKIP (0.000s) [130.929746] [126/162] (878s left) kms_psr (psr-cursor-plane-move) [131.019246] Subtest psr-cursor-plane-move: SKIP (0.000s) [131.077544] [127/162] (878s left) kms_psr (psr-sprite-plane-onoff) [131.164375] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [131.227470] [128/162] (878s left) kms_psr (psr-primary-mmap-gtt) [131.314262] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [131.377691] [129/162] (878s left) kms_setmode (basic-clone-single-crtc) [131.425616] Starting subtest: basic-clone-single-crtc [131.429022] Subtest basic-clone-single-crtc: SKIP (0.000s) [131.514348] [130/162] (877s left) i915_pm_rps (basic-api) [131.569782] Starting subtest: basic-api [131.573311] Subtest basic-api: SUCCESS (0.004s) [131.666431] [131/162] (877s left) prime_self_import (basic-llseek-bad) [131.713518] Starting subtest: basic-llseek-bad [131.715888] Subtest basic-llseek-bad: SUCCESS (0.002s) [131.815651] [132/162] (877s left) prime_self_import (basic-llseek-size) [131.864233] Starting subtest: basic-llseek-size [131.866402] Subtest basic-llseek-size: SUCCESS (0.003s) [131.964973] [133/162] (877s left) prime_self_import (basic-with_fd_dup) [132.014055] Starting subtest: basic-with_fd_dup [132.016207] Subtest basic-with_fd_dup: SUCCESS (0.003s) [132.131466] [134/162] (877s left) prime_self_import (basic-with_one_bo) [132.180321] Starting subtest: basic-with_one_bo [132.182443] Subtest basic-with_one_bo: SUCCESS (0.003s) [132.313259] [135/162] (877s left) prime_self_import (basic-with_one_bo_two_files) [132.358183] Starting subtest: basic-with_one_bo_two_files [132.360389] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [132.465997] [136/162] (877s left) prime_self_import (basic-with_two_bos) [132.512124] Starting subtest: basic-with_two_bos [132.514239] Subtest basic-with_two_bos: SUCCESS (0.003s) [132.617351] [137/162] (877s left) prime_vgem (basic-fence-flip) [132.674386] Starting subtest: basic-fence-flip [133.018426] Subtest basic-fence-flip: SUCCESS (0.344s) [133.133370] [138/162] (876s left) prime_vgem (basic-fence-mmap) [133.190421] Starting subtest: basic-fence-mmap [133.218275] Subtest basic-fence-mmap: SUCCESS (0.028s) [133.331285] [139/162] (876s left) prime_vgem (basic-fence-read) [133.390927] Starting subtest: basic-fence-read [133.444931] Subtest basic-fence-read: SUCCESS (0.053s) [133.551630] [140/162] (876s left) prime_vgem (basic-gtt) [133.607174] Starting subtest: basic-gtt [133.622258] Subtest basic-gtt: SUCCESS (0.015s) [133.733620] [141/162] (876s left) prime_vgem (basic-read) [133.791120] Starting subtest: basic-read [133.818320] Subtest basic-read: SUCCESS (0.027s) [133.948649] [142/162] (876s left) prime_vgem (basic-write) [134.007341] Starting subtest: basic-write [134.034372] Subtest basic-write: SUCCESS (0.027s) [134.151024] [143/162] (875s left) vgem_basic (setversion) [134.201822] Starting subtest: setversion [134.203977] Subtest setversion: SUCCESS (0.000s) [134.283470] [144/162] (875s left) vgem_basic (create) [134.337183] Starting subtest: create [134.338238] Subtest create: SUCCESS (0.000s) [134.432464] [145/162] (875s left) vgem_basic (debugfs) [134.484958] Starting subtest: debugfs [134.485994] Subtest debugfs: SUCCESS (0.000s) [134.566452] [146/162] (875s left) vgem_basic (dmabuf-export) [134.621388] Starting subtest: dmabuf-export [134.623599] Subtest dmabuf-export: SUCCESS (0.002s) [134.731077] [147/162] (875s left) vgem_basic (dmabuf-fence) [134.785858] Starting subtest: dmabuf-fence [134.786977] Subtest dmabuf-fence: SUCCESS (0.001s) [134.866647] [148/162] (875s left) vgem_basic (dmabuf-fence-before) [134.918437] Starting subtest: dmabuf-fence-before [134.919580] Subtest dmabuf-fence-before: SUCCESS (0.001s) [134.998694] [149/162] (875s left) vgem_basic (dmabuf-mmap) [135.053456] Starting subtest: dmabuf-mmap [135.066112] Subtest dmabuf-mmap: SUCCESS (0.015s) [135.148647] [150/162] (875s left) vgem_basic (mmap) [135.203451] Starting subtest: mmap [135.212926] Subtest mmap: SUCCESS (0.011s) [135.281581] [151/162] (875s left) vgem_basic (second-client) [135.335045] Starting subtest: second-client [135.370900] Subtest second-client: SUCCESS (0.034s) [135.447436] [152/162] (875s left) vgem_basic (sysfs) [135.503409] Starting subtest: sysfs [135.504499] Subtest sysfs: SUCCESS (0.001s) [135.616636] [153/162] (875s left) core_hotunplug (unbind-rebind) [135.752315] Starting subtest: unbind-rebind [139.087062] Subtest unbind-rebind: SUCCESS (3.334s) [139.138308] [154/162] (871s left) vgem_basic (unload) [139.194747] Starting subtest: unload [168.160480] Subtest unload: SUCCESS (28.964s) [168.279147] [155/162] (842s left) i915_module_load (reload) [168.326769] Starting subtest: reload [170.888149] Subtest reload: SUCCESS (2.562s) [170.954125] [156/162] (839s left) gem_lmem_swapping (basic) [171.016415] Subtest basic: SKIP (0.000s) [171.079562] [157/162] (839s left) gem_lmem_swapping (parallel-random-engines) [171.129518] Subtest parallel-random-engines: SKIP (0.000s) [171.178667] [158/162] (839s left) gem_lmem_swapping (random-engines) [171.229450] Subtest random-engines: SKIP (0.000s) [171.280416] [159/162] (839s left) gem_lmem_swapping (verify-random) [171.338142] Subtest verify-random: SKIP (0.000s) [171.396588] [160/162] (839s left) i915_pm_rpm (module-reload) [183.001427] Starting subtest: module-reload [210.887449] Subtest module-reload: SUCCESS (27.884s) [210.919690] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [210.920774] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true [210.923520] [161/162] (800s left) i915_selftest (live) [210.978401] Starting subtest: live [210.980224] Starting dynamic subtest: sanitycheck [212.695760] Dynamic subtest sanitycheck: SUCCESS (1.716s) [212.709265] Starting dynamic subtest: uncore [214.362731] Dynamic subtest uncore: SUCCESS (1.664s) [214.379173] Starting dynamic subtest: workarounds [216.044161] Dynamic subtest workarounds: SUCCESS (1.678s) [216.057480] Starting dynamic subtest: gt_engines [217.755025] Dynamic subtest gt_engines: SUCCESS (1.708s) [217.768216] Starting dynamic subtest: gt_timelines [225.595729] Dynamic subtest gt_timelines: SUCCESS (7.837s) [225.608339] Starting dynamic subtest: gt_contexts [227.322514] Dynamic subtest gt_contexts: SUCCESS (1.724s) [227.336728] Starting dynamic subtest: gt_lrc [228.975063] Dynamic subtest gt_lrc: SUCCESS (1.648s) [228.988311] Starting dynamic subtest: gt_mocs [230.427889] Dynamic subtest gt_mocs: SUCCESS (1.449s) [230.440986] Starting dynamic subtest: gt_pm [246.392740] Dynamic subtest gt_pm: SUCCESS (15.961s) [246.406470] Starting dynamic subtest: gt_heartbeat [248.104716] Dynamic subtest gt_heartbeat: SUCCESS (1.709s) [248.118310] Starting dynamic subtest: gt_tlb [249.774532] Dynamic subtest gt_tlb: SUCCESS (1.667s) [249.787742] Starting dynamic subtest: requests [257.272760] Dynamic subtest requests: SUCCESS (7.494s) [257.285827] Starting dynamic subtest: migrate [258.910953] Dynamic subtest migrate: SUCCESS (1.635s) [258.924206] Starting dynamic subtest: active [260.624760] Dynamic subtest active: SUCCESS (1.711s) [260.637651] Starting dynamic subtest: objects [262.405092] Dynamic subtest objects: SUCCESS (1.778s) [262.419885] Starting dynamic subtest: mman [267.279490] Dynamic subtest mman: SUCCESS (4.870s) [267.300934] Starting dynamic subtest: dmabuf [269.006767] Dynamic subtest dmabuf: SUCCESS (1.722s) [269.019948] Starting dynamic subtest: vma [270.682110] Dynamic subtest vma: SUCCESS (1.670s) [270.694197] Starting dynamic subtest: coherency [273.669354] Dynamic subtest coherency: SUCCESS (2.986s) [273.682225] Starting dynamic subtest: gtt [278.646391] Dynamic subtest gtt: SUCCESS (4.974s) [278.659882] Starting dynamic subtest: gem [280.566353] Dynamic subtest gem: SUCCESS (1.917s) [280.579032] Starting dynamic subtest: evict [282.211499] Dynamic subtest evict: SUCCESS (1.642s) [282.224724] Starting dynamic subtest: hugepages [284.382900] Dynamic subtest hugepages: SUCCESS (2.169s) [284.397218] Starting dynamic subtest: gem_contexts [290.096978] Dynamic subtest gem_contexts: SUCCESS (5.711s) [290.111197] Starting dynamic subtest: client [291.795096] Dynamic subtest client: SUCCESS (1.694s) [291.809192] Starting dynamic subtest: gem_migrate [293.252117] Dynamic subtest gem_migrate: SUCCESS (1.454s) [293.265410] Starting dynamic subtest: reset [295.558157] Dynamic subtest reset: SUCCESS (2.303s) [295.578805] Starting dynamic subtest: memory_region [297.085805] Dynamic subtest memory_region: SUCCESS (1.523s) [297.101035] Starting dynamic subtest: hangcheck