[132.810533] Initializing watchdogs [132.810903] /dev/watchdog0 [132.896700] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:22b1 Intel Cherryview (Gen8) Atom/Celeron/Pentium Processor x5-E8000/J3xxx/N3xxx Integrated Graphics Controller [132.916743] [001/162] (960s left) i915_module_load (load) [133.193955] Starting subtest: load [138.949958] Subtest load: SUCCESS (5.740s) [139.250561] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [139.269543] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [139.281239] [002/162] (954s left) core_auth (basic-auth) [139.559212] Starting subtest: basic-auth [139.564206] Subtest basic-auth: SUCCESS (0.006s) [139.748694] [003/162] (954s left) core_debugfs (read-all-entries) [139.982774] Starting subtest: read-all-entries [139.994461] Subtest read-all-entries: SUCCESS (0.013s) [140.174967] [004/162] (953s left) core_sysfs (read-all-entries) [140.440212] Starting subtest: read-all-entries [140.473177] Subtest read-all-entries: SUCCESS (0.027s) [140.707701] [005/162] (953s left) fbdev (eof) [140.983253] Subtest eof: SKIP (0.000s) [141.147187] [006/162] (953s left) fbdev (info) [141.423631] Subtest info: SKIP (0.000s) [141.601698] [007/162] (953s left) fbdev (nullptr) [141.885840] Subtest nullptr: SKIP (0.000s) [142.050308] [008/162] (952s left) fbdev (read) [142.317173] Subtest read: SKIP (0.000s) [142.486723] [009/162] (952s left) fbdev (write) [142.766816] Subtest write: SKIP (0.000s) [142.934751] [010/162] (952s left) gem_basic (bad-close) [143.195401] Starting subtest: bad-close [143.207242] Subtest bad-close: SUCCESS (0.000s) [143.390584] [011/162] (951s left) gem_basic (create-close) [143.630929] Starting subtest: create-close [143.637569] Subtest create-close: SUCCESS (0.001s) [143.815753] [012/162] (951s left) gem_basic (create-fd-close) [144.044484] Starting subtest: create-fd-close [144.051140] Subtest create-fd-close: SUCCESS (0.001s) [144.222230] [013/162] (951s left) gem_busy (busy@all-engines) [144.525974] Starting subtest: busy [144.529774] Starting dynamic subtest: all-engines [144.704057] Dynamic subtest all-engines: SUCCESS (0.182s) [144.707596] Subtest busy: SUCCESS (0.185s) [145.231895] [014/162] (950s left) gem_close_race (basic-process) [145.552201] Starting subtest: basic-process [145.715850] Subtest basic-process: SUCCESS (0.169s) [146.029346] [015/162] (950s left) gem_close_race (basic-threads) [146.332474] Starting subtest: basic-threads [147.597283] Subtest basic-threads: SUCCESS (1.267s) [147.921249] [016/162] (948s left) gem_ctx_create (basic) [148.208635] Starting subtest: basic [148.214358] Subtest basic: SUCCESS (0.001s) [148.604582] [017/162] (947s left) gem_ctx_create (basic-files) [148.902237] Starting subtest: basic-files [150.977981] Subtest basic-files: SUCCESS (2.075s) [151.320469] [018/162] (945s left) gem_ctx_exec (basic) [151.563991] Starting subtest: basic [151.584504] Subtest basic: SUCCESS (0.017s) [151.770510] [019/162] (945s left) gem_exec_basic (basic) [152.019202] Starting subtest: basic [152.024485] Starting dynamic subtest: rcs0-smem [152.028229] Dynamic subtest rcs0-smem: SUCCESS (0.008s) [152.059376] Starting dynamic subtest: bcs0-smem [152.061197] Dynamic subtest bcs0-smem: SUCCESS (0.003s) [152.088823] Starting dynamic subtest: vcs0-smem [152.090544] Dynamic subtest vcs0-smem: SUCCESS (0.004s) [152.118179] Starting dynamic subtest: vecs0-smem [152.119852] Dynamic subtest vecs0-smem: SUCCESS (0.008s) [152.121665] Subtest basic: SUCCESS (0.034s) [152.382655] [020/162] (944s left) gem_exec_create (basic) [152.675150] Starting subtest: basic [152.679554] Starting dynamic subtest: smem [154.779925] Dynamic subtest smem: SUCCESS (2.100s) [154.782221] Subtest basic: SUCCESS (2.102s) [155.078484] [021/162] (942s left) gem_exec_fence (basic-busy) [155.539984] Starting subtest: basic-busy [155.542280] Starting dynamic subtest: rcs0 [155.574108] Dynamic subtest rcs0: SUCCESS (0.036s) [155.604388] Starting dynamic subtest: bcs0 [155.607981] Dynamic subtest bcs0: SUCCESS (0.034s) [155.639032] Starting dynamic subtest: vcs0 [155.648403] Dynamic subtest vcs0: SUCCESS (0.038s) [155.677949] Starting dynamic subtest: vecs0 [155.688253] Dynamic subtest vecs0: SUCCESS (0.035s) [155.690210] Subtest basic-busy: SUCCESS (0.158s) [156.668793] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [156.678757] [022/162] (940s left) gem_exec_fence (basic-wait) [157.142348] Starting subtest: basic-wait [157.145067] Starting dynamic subtest: rcs0 [157.170735] Dynamic subtest rcs0: SUCCESS (0.034s) [157.201257] Starting dynamic subtest: bcs0 [157.202965] Dynamic subtest bcs0: SUCCESS (0.022s) [157.232085] Starting dynamic subtest: vcs0 [157.233877] Dynamic subtest vcs0: SUCCESS (0.024s) [157.265430] Starting dynamic subtest: vecs0 [157.267184] Dynamic subtest vecs0: SUCCESS (0.027s) [157.269097] Subtest basic-wait: SUCCESS (0.119s) [158.054942] [023/162] (939s left) gem_exec_fence (basic-await) [158.505234] Starting subtest: basic-await [158.508177] Starting dynamic subtest: rcs0 [158.789166] Dynamic subtest rcs0: SUCCESS (0.284s) [158.847078] Starting dynamic subtest: bcs0 [159.005167] Dynamic subtest bcs0: SUCCESS (0.213s) [159.035462] Starting dynamic subtest: vcs0 [159.224763] Dynamic subtest vcs0: SUCCESS (0.217s) [159.257675] Starting dynamic subtest: vecs0 [159.422260] Dynamic subtest vecs0: SUCCESS (0.193s) [159.424409] Subtest basic-await: SUCCESS (0.917s) [160.225909] [024/162] (937s left) gem_exec_fence (nb-await) [160.670225] Starting subtest: nb-await [160.674359] Starting dynamic subtest: rcs0 [160.784307] Dynamic subtest rcs0: SUCCESS (0.106s) [160.824358] Starting dynamic subtest: bcs0 [160.874547] Dynamic subtest bcs0: SUCCESS (0.085s) [160.911900] Starting dynamic subtest: vcs0 [160.959922] Dynamic subtest vcs0: SUCCESS (0.085s) [160.996778] Starting dynamic subtest: vecs0 [161.039771] Dynamic subtest vecs0: SUCCESS (0.085s) [161.042525] Subtest nb-await: SUCCESS (0.367s) [161.849754] [025/162] (936s left) gem_exec_gttfill (basic) [162.112136] Starting subtest: basic [195.601171] Subtest basic: SUCCESS (33.485s) [195.979100] [026/162] (902s left) gem_exec_parallel (engines) [196.426838] Starting subtest: engines [196.432197] Starting dynamic subtest: basic [207.271287] Dynamic subtest basic: SUCCESS (10.842s) [207.311618] Starting dynamic subtest: contexts [218.365564] Dynamic subtest contexts: SUCCESS (11.092s) [218.402567] Starting dynamic subtest: fds [231.178839] Dynamic subtest fds: SUCCESS (12.815s) [231.222127] Starting dynamic subtest: userptr [237.728720] Dynamic subtest userptr: SUCCESS (6.547s) [237.731628] Subtest engines: SUCCESS (41.304s) [238.085956] [027/162] (860s left) gem_exec_store (basic) [238.357247] Starting subtest: basic [238.426928] Subtest basic: SUCCESS (0.073s) [238.762757] [028/162] (859s left) gem_flink_basic (bad-flink) [238.997060] Starting subtest: bad-flink [239.000531] Subtest bad-flink: SUCCESS (0.000s) [239.205941] [029/162] (859s left) gem_flink_basic (bad-open) [239.423172] Starting subtest: bad-open [239.428211] Subtest bad-open: SUCCESS (0.000s) [239.622628] [030/162] (859s left) gem_flink_basic (basic) [239.850838] Starting subtest: basic [239.854474] Subtest basic: SUCCESS (0.001s) [240.072759] [031/162] (859s left) gem_flink_basic (double-flink) [240.296941] Starting subtest: double-flink [240.303173] Subtest double-flink: SUCCESS (0.000s) [240.512637] [032/162] (858s left) gem_flink_basic (flink-lifetime) [240.792189] Starting subtest: flink-lifetime [240.803682] Subtest flink-lifetime: SUCCESS (0.008s) [241.017888] [033/162] (858s left) gem_huc_copy (huc-copy) [241.262187] Subtest huc-copy: SKIP (0.000s) [241.477991] [034/162] (858s left) gem_linear_blits (basic) [242.012098] Starting subtest: basic [242.053457] Subtest basic: SUCCESS (0.043s) [242.310846] [035/162] (857s left) gem_mmap (basic) [242.706985] Starting subtest: basic [242.727684] Subtest basic: SUCCESS (0.001s) [242.917163] [036/162] (857s left) gem_mmap_gtt (basic) [243.138697] Starting subtest: basic [243.148224] Subtest basic: SUCCESS (0.004s) [243.362238] [037/162] (857s left) gem_render_linear_blits (basic) [243.612914] Starting subtest: basic [243.748276] Subtest basic: SUCCESS (0.130s) [243.958620] [038/162] (856s left) gem_render_tiled_blits (basic) [244.200453] Starting subtest: basic [244.371060] Subtest basic: SUCCESS (0.168s) [244.587877] [039/162] (856s left) gem_ringfill (basic-all) [245.080752] Starting subtest: basic-all [246.429236] Subtest basic-all: SUCCESS (1.349s) [246.640301] [040/162] (854s left) gem_softpin (allocator-basic) [246.901220] Starting subtest: allocator-basic [247.322483] Subtest allocator-basic: SUCCESS (0.418s) [247.581424] [041/162] (853s left) gem_softpin (allocator-basic-reserve) [247.814371] Starting subtest: allocator-basic-reserve [248.233124] Subtest allocator-basic-reserve: SUCCESS (0.415s) [248.514401] [042/162] (853s left) gem_softpin (safe-alignment) [248.755186] Starting subtest: safe-alignment [248.801726] Subtest safe-alignment: SUCCESS (0.050s) [249.024884] [043/162] (852s left) gem_sync (basic-all) [249.308784] Starting subtest: basic-all [252.937847] Subtest basic-all: SUCCESS (3.632s) [253.279250] [044/162] (848s left) gem_sync (basic-each) [253.564855] Starting subtest: basic-each [256.397320] Subtest basic-each: SUCCESS (2.830s) [256.737050] [045/162] (845s left) gem_tiled_blits (basic) [257.196065] Starting subtest: basic [257.275840] Subtest basic: SUCCESS (0.087s) [257.525154] [046/162] (844s left) gem_tiled_fence_blits (basic) [258.182573] Starting subtest: basic [258.250306] Subtest basic: SUCCESS (0.069s) [258.484232] [047/162] (844s left) gem_tiled_pread_basic (basic) [258.889332] Starting subtest: basic [260.002587] Subtest basic: SUCCESS (1.112s) [260.220678] [048/162] (842s left) gem_wait (busy@all-engines) [260.632780] Starting subtest: busy [260.637104] Starting dynamic subtest: all-engines [261.161174] Dynamic subtest all-engines: SUCCESS (0.559s) [261.174147] Subtest busy: SUCCESS (0.565s) [261.573473] [049/162] (841s left) gem_wait (wait@all-engines) [261.878271] Starting subtest: wait [261.880379] Starting dynamic subtest: all-engines [262.921149] Dynamic subtest all-engines: SUCCESS (1.050s) [262.924871] Subtest wait: SUCCESS (1.055s) [263.352702] [050/162] (839s left) i915_getparams_basic (basic-eu-total) [263.573171] Starting subtest: basic-eu-total [263.579781] Subtest basic-eu-total: SUCCESS (0.000s) [263.778677] [051/162] (839s left) i915_getparams_basic (basic-subslice-total) [264.004204] Starting subtest: basic-subslice-total [264.010398] Subtest basic-subslice-total: SUCCESS (0.000s) [264.215691] [052/162] (839s left) i915_hangman (error-state-basic) [264.481117] Starting subtest: error-state-basic [264.507192] Subtest error-state-basic: SUCCESS (0.024s) [264.755857] [053/162] (839s left) i915_pciid [265.187876] [054/162] (838s left) intel_hwmon (hwmon-read) [265.414230] Subtest hwmon-read: SKIP (0.000s) [265.621167] [055/162] (838s left) intel_hwmon (hwmon-write) [265.842096] Subtest hwmon-write: SKIP (0.000s) [266.046457] [056/162] (838s left) kms_addfb_basic (addfb25-4-tiled) [266.605099] Starting subtest: addfb25-4-tiled [266.608803] Subtest addfb25-4-tiled: SUCCESS (0.001s) [266.812877] [057/162] (837s left) kms_addfb_basic (addfb25-bad-modifier) [267.323889] Starting subtest: addfb25-bad-modifier [267.328614] Subtest addfb25-bad-modifier: SUCCESS (0.002s) [267.548508] [058/162] (837s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [268.173666] Starting subtest: addfb25-framebuffer-vs-set-tiling [268.179153] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.001s) [268.392671] [059/162] (836s left) kms_addfb_basic (addfb25-modifier-no-flag) [268.959700] Starting subtest: addfb25-modifier-no-flag [268.964345] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [269.175977] [060/162] (836s left) kms_addfb_basic (addfb25-x-tiled-legacy) [269.747233] Starting subtest: addfb25-x-tiled-legacy [269.752216] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [269.971575] [061/162] (835s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [270.507652] Starting subtest: addfb25-x-tiled-mismatch-legacy [270.512871] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s) [270.733564] [062/162] (835s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [271.336890] Starting subtest: addfb25-yf-tiled-legacy [271.341199] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s) [271.550375] [063/162] (834s left) kms_addfb_basic (addfb25-y-tiled-legacy) [272.050704] Starting subtest: addfb25-y-tiled-legacy [272.058126] Subtest addfb25-y-tiled-legacy: SUCCESS (0.002s) [272.267322] [064/162] (834s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [272.798206] Starting subtest: addfb25-y-tiled-small-legacy [272.804203] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s) [273.012322] [065/162] (833s left) kms_addfb_basic (bad-pitch-0) [273.259284] Starting subtest: bad-pitch-0 [273.262239] Subtest bad-pitch-0: SUCCESS (0.000s) [273.833525] [066/162] (832s left) kms_addfb_basic (bad-pitch-1024) [274.077696] Starting subtest: bad-pitch-1024 [274.081537] Subtest bad-pitch-1024: SUCCESS (0.001s) [274.655549] [067/162] (832s left) kms_addfb_basic (bad-pitch-128) [274.898125] Starting subtest: bad-pitch-128 [274.900283] Subtest bad-pitch-128: SUCCESS (0.000s) [275.485394] [068/162] (831s left) kms_addfb_basic (bad-pitch-256) [275.752408] Starting subtest: bad-pitch-256 [275.756225] Subtest bad-pitch-256: SUCCESS (0.000s) [276.285685] [069/162] (831s left) kms_addfb_basic (bad-pitch-32) [276.523430] Starting subtest: bad-pitch-32 [276.527463] Subtest bad-pitch-32: SUCCESS (0.000s) [277.092300] [070/162] (830s left) kms_addfb_basic (bad-pitch-63) [277.329839] Starting subtest: bad-pitch-63 [277.333242] Subtest bad-pitch-63: SUCCESS (0.000s) [277.834579] [071/162] (829s left) kms_addfb_basic (bad-pitch-65536) [278.061225] Starting subtest: bad-pitch-65536 [278.065246] Subtest bad-pitch-65536: SUCCESS (0.001s) [278.665256] [072/162] (829s left) kms_addfb_basic (bad-pitch-999) [278.910671] Starting subtest: bad-pitch-999 [278.914104] Subtest bad-pitch-999: SUCCESS (0.000s) [279.433807] [073/162] (828s left) kms_addfb_basic (basic) [279.676925] Starting subtest: basic [279.680769] Subtest basic: SUCCESS (0.000s) [280.296407] [074/162] (828s left) kms_addfb_basic (basic-x-tiled-legacy) [280.535131] Starting subtest: basic-x-tiled-legacy [280.538220] Subtest basic-x-tiled-legacy: SUCCESS (0.001s) [281.092886] [075/162] (827s left) kms_addfb_basic (basic-y-tiled-legacy) [281.359404] Starting subtest: basic-y-tiled-legacy [281.365885] Subtest basic-y-tiled-legacy: SUCCESS (0.003s) [282.056815] [076/162] (826s left) kms_addfb_basic (bo-too-small) [282.623583] Starting subtest: bo-too-small [282.625594] Subtest bo-too-small: SUCCESS (0.002s) [282.844699] [077/162] (826s left) kms_addfb_basic (bo-too-small-due-to-tiling) [283.427684] Starting subtest: bo-too-small-due-to-tiling [283.432840] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s) [283.646590] [078/162] (825s left) kms_addfb_basic (clobberred-modifier) [283.866197] Starting subtest: clobberred-modifier [283.870082] Subtest clobberred-modifier: SUCCESS (0.001s) [284.392958] [079/162] (825s left) kms_addfb_basic (framebuffer-vs-set-tiling) [284.645782] Starting subtest: framebuffer-vs-set-tiling [284.649862] Subtest framebuffer-vs-set-tiling: SUCCESS (0.004s) [285.243571] [080/162] (824s left) kms_addfb_basic (invalid-get-prop) [285.470858] Starting subtest: invalid-get-prop [285.475256] Subtest invalid-get-prop: SUCCESS (0.001s) [286.061411] [081/162] (823s left) kms_addfb_basic (invalid-get-prop-any) [286.293765] Starting subtest: invalid-get-prop-any [286.298159] Subtest invalid-get-prop-any: SUCCESS (0.000s) [286.853701] [082/162] (823s left) kms_addfb_basic (invalid-set-prop) [287.086411] Starting subtest: invalid-set-prop [287.090206] Subtest invalid-set-prop: SUCCESS (0.000s) [287.589308] [083/162] (822s left) kms_addfb_basic (invalid-set-prop-any) [287.819327] Starting subtest: invalid-set-prop-any [287.822966] Subtest invalid-set-prop-any: SUCCESS (0.000s) [288.332613] [084/162] (822s left) kms_addfb_basic (no-handle) [288.566266] Starting subtest: no-handle [288.569276] Subtest no-handle: SUCCESS (0.000s) [289.133779] [085/162] (821s left) kms_addfb_basic (size-max) [289.693442] Starting subtest: size-max [289.696970] Subtest size-max: SUCCESS (0.001s) [289.911917] [086/162] (821s left) kms_addfb_basic (small-bo) [290.584877] Starting subtest: small-bo [290.590140] Subtest small-bo: SUCCESS (0.002s) [290.807431] [087/162] (820s left) kms_addfb_basic (tile-pitch-mismatch) [291.035223] Starting subtest: tile-pitch-mismatch [291.038377] Subtest tile-pitch-mismatch: SUCCESS (0.002s) [291.583617] [088/162] (819s left) kms_addfb_basic (too-high) [292.181907] Starting subtest: too-high [292.185939] Subtest too-high: SUCCESS (0.004s) [292.417393] [089/162] (819s left) kms_addfb_basic (too-wide) [293.020530] Starting subtest: too-wide [293.025975] Subtest too-wide: SUCCESS (0.000s) [293.240347] [090/162] (818s left) kms_addfb_basic (unused-handle) [293.466554] Starting subtest: unused-handle [293.469379] Subtest unused-handle: SUCCESS (0.000s) [294.039618] [091/162] (817s left) kms_addfb_basic (unused-modifier) [294.272697] Starting subtest: unused-modifier [294.279334] Subtest unused-modifier: SUCCESS (0.000s) [294.847506] [092/162] (817s left) kms_addfb_basic (unused-offsets) [295.073255] Starting subtest: unused-offsets [295.076451] Subtest unused-offsets: SUCCESS (0.000s) [295.605423] [093/162] (816s left) kms_addfb_basic (unused-pitches) [295.841256] Starting subtest: unused-pitches [295.845625] Subtest unused-pitches: SUCCESS (0.000s) [296.434149] [094/162] (816s left) kms_busy (basic) [297.011221] Subtest basic: SKIP (0.000s) [297.229835] [095/162] (815s left) kms_prop_blob (basic) [297.455595] Starting subtest: basic [297.460276] Subtest basic: SUCCESS (0.000s) [297.660745] [096/162] (815s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [298.260202] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [298.458443] [097/162] (814s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [299.043285] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [299.238702] [098/162] (814s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [299.837667] Subtest basic-flip-after-cursor-atomic: SKIP (0.000s) [300.033517] [099/162] (813s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [300.640227] Subtest basic-flip-after-cursor-legacy: SKIP (0.000s) [300.839472] [100/162] (813s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [301.428374] Subtest basic-flip-after-cursor-varying-size: SKIP (0.000s) [301.628559] [101/162] (812s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [302.244284] Subtest basic-flip-before-cursor-atomic: SKIP (0.000s) [302.436603] [102/162] (811s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [302.996361] Subtest basic-flip-before-cursor-legacy: SKIP (0.000s) [303.190759] [103/162] (811s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [303.805663] Subtest basic-flip-before-cursor-varying-size: SKIP (0.000s) [303.990669] [104/162] (810s left) kms_dsc (dsc-basic) [304.582780] Subtest dsc-basic: SKIP (0.000s) [304.806799] [105/162] (810s left) kms_flip (basic-flip-vs-dpms) [305.374847] Starting subtest: basic-flip-vs-dpms [305.394604] Subtest basic-flip-vs-dpms: SKIP (0.018s) [305.768215] [106/162] (809s left) kms_flip (basic-flip-vs-modeset) [306.291601] Starting subtest: basic-flip-vs-modeset [306.305158] Subtest basic-flip-vs-modeset: SKIP (0.007s) [306.570293] [107/162] (808s left) kms_flip (basic-flip-vs-wf_vblank) [307.087948] Starting subtest: basic-flip-vs-wf_vblank [307.128088] Subtest basic-flip-vs-wf_vblank: SKIP (0.047s) [307.392683] [108/162] (808s left) kms_flip (basic-plain-flip) [307.936253] Starting subtest: basic-plain-flip [307.954250] Subtest basic-plain-flip: SKIP (0.009s) [308.214617] [109/162] (807s left) kms_force_connector_basic (force-connector-state) [308.592094] Starting subtest: force-connector-state [309.381230] Subtest force-connector-state: SUCCESS (0.790s) [311.173723] [110/162] (804s left) kms_force_connector_basic (force-edid) [311.616284] Starting subtest: force-edid [311.992837] Subtest force-edid: SUCCESS (0.380s) [312.592840] [111/162] (803s left) kms_force_connector_basic (force-load-detect) [313.041223] Starting subtest: force-load-detect [313.051759] Subtest force-load-detect: SKIP (0.000s) [313.615609] [112/162] (802s left) kms_force_connector_basic (prune-stale-modes) [314.012289] Starting subtest: prune-stale-modes [314.348576] Subtest prune-stale-modes: SUCCESS (0.343s) [314.949633] [113/162] (801s left) kms_frontbuffer_tracking (basic) [315.467494] Subtest basic: SKIP (0.000s) [315.685595] [114/162] (801s left) kms_hdmi_inject (inject-audio) [315.918409] Starting subtest: inject-audio [316.469960] Subtest inject-audio: SUCCESS (0.554s) [316.956221] [115/162] (800s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [317.561820] Subtest compare-crc-sanitycheck-xr24: SKIP (0.000s) [317.794180] [116/162] (799s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [318.496160] Subtest compare-crc-sanitycheck-nv12: SKIP (0.000s) [318.759436] [117/162] (798s left) kms_pipe_crc_basic (hang-read-crc) [319.344240] Subtest hang-read-crc: SKIP (0.000s) [319.573759] [118/162] (798s left) kms_pipe_crc_basic (nonblocking-crc) [320.149161] Subtest nonblocking-crc: SKIP (0.000s) [320.401972] [119/162] (797s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [321.025198] Subtest nonblocking-crc-frame-sequence: SKIP (0.000s) [321.237162] [120/162] (797s left) kms_pipe_crc_basic (read-crc) [321.847618] Subtest read-crc: SKIP (0.000s) [322.081567] [121/162] (796s left) kms_pipe_crc_basic (read-crc-frame-sequence) [322.668405] Subtest read-crc-frame-sequence: SKIP (0.000s) [322.894308] [122/162] (795s left) kms_pm_backlight (basic-brightness) [323.432920] Subtest basic-brightness: SKIP (0.000s) [323.665408] [123/162] (795s left) kms_pm_rpm (basic-pci-d3-state) [325.610666] Starting subtest: basic-pci-d3-state [325.829226] Subtest basic-pci-d3-state: SUCCESS (0.216s) [326.053853] [124/162] (793s left) kms_pm_rpm (basic-rte) [326.271141] Starting subtest: basic-rte [328.078594] Subtest basic-rte: SUCCESS (1.808s) [328.550191] [125/162] (790s left) kms_psr (psr-primary-page-flip) [329.165739] Subtest psr-primary-page-flip: SKIP (0.000s) [329.385224] [126/162] (790s left) kms_psr (psr-cursor-plane-move) [329.937169] Subtest psr-cursor-plane-move: SKIP (0.000s) [330.154888] [127/162] (789s left) kms_psr (psr-sprite-plane-onoff) [330.749260] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [330.966450] [128/162] (789s left) kms_psr (psr-primary-mmap-gtt) [331.554424] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [331.791626] [129/162] (788s left) kms_setmode (basic-clone-single-crtc) [332.025383] Starting subtest: basic-clone-single-crtc [332.035552] Subtest basic-clone-single-crtc: SKIP (0.002s) [332.270407] [130/162] (788s left) i915_pm_rps (basic-api) [332.550957] Starting subtest: basic-api [332.566164] Subtest basic-api: SUCCESS (0.016s) [332.791655] [131/162] (787s left) prime_self_import (basic-llseek-bad) [333.012124] Starting subtest: basic-llseek-bad [333.021234] Subtest basic-llseek-bad: SUCCESS (0.010s) [333.254519] [132/162] (787s left) prime_self_import (basic-llseek-size) [333.477962] Starting subtest: basic-llseek-size [333.499968] Subtest basic-llseek-size: SUCCESS (0.023s) [333.741304] [133/162] (787s left) prime_self_import (basic-with_fd_dup) [333.972472] Starting subtest: basic-with_fd_dup [333.982910] Subtest basic-with_fd_dup: SUCCESS (0.018s) [334.212558] [134/162] (787s left) prime_self_import (basic-with_one_bo) [334.439549] Starting subtest: basic-with_one_bo [334.453436] Subtest basic-with_one_bo: SUCCESS (0.020s) [334.695392] [135/162] (786s left) prime_self_import (basic-with_one_bo_two_files) [334.916803] Starting subtest: basic-with_one_bo_two_files [334.934784] Subtest basic-with_one_bo_two_files: SUCCESS (0.020s) [335.172918] [136/162] (786s left) prime_self_import (basic-with_two_bos) [335.413153] Starting subtest: basic-with_two_bos [335.475477] Subtest basic-with_two_bos: SUCCESS (0.056s) [335.768934] [137/162] (786s left) prime_vgem (basic-fence-flip) [336.043299] Starting subtest: basic-fence-flip [336.340018] Subtest basic-fence-flip: SKIP (0.296s) [336.577982] [138/162] (785s left) prime_vgem (basic-fence-mmap) [336.858419] Starting subtest: basic-fence-mmap [336.999416] Subtest basic-fence-mmap: SUCCESS (0.139s) [337.256771] [139/162] (785s left) prime_vgem (basic-fence-read) [337.565300] Starting subtest: basic-fence-read [337.840633] Subtest basic-fence-read: SUCCESS (0.273s) [338.115742] [140/162] (784s left) prime_vgem (basic-gtt) [338.392590] Starting subtest: basic-gtt [338.449060] Subtest basic-gtt: SUCCESS (0.066s) [338.708406] [141/162] (784s left) prime_vgem (basic-read) [338.978577] Starting subtest: basic-read [339.116918] Subtest basic-read: SUCCESS (0.141s) [339.365175] [142/162] (783s left) prime_vgem (basic-write) [339.633274] Starting subtest: basic-write [339.800236] Subtest basic-write: SUCCESS (0.174s) [340.041842] [143/162] (783s left) vgem_basic (setversion) [340.275673] Starting subtest: setversion [340.283163] Subtest setversion: SUCCESS (0.002s) [340.514336] [144/162] (782s left) vgem_basic (create) [340.745162] Starting subtest: create [340.749247] Subtest create: SUCCESS (0.001s) [340.958716] [145/162] (782s left) vgem_basic (debugfs) [341.199191] Starting subtest: debugfs [341.207214] Subtest debugfs: SUCCESS (0.002s) [341.432867] [146/162] (782s left) vgem_basic (dmabuf-export) [341.671850] Starting subtest: dmabuf-export [341.689601] Subtest dmabuf-export: SUCCESS (0.018s) [341.936847] [147/162] (782s left) vgem_basic (dmabuf-fence) [342.180222] Starting subtest: dmabuf-fence [342.184204] Subtest dmabuf-fence: SUCCESS (0.004s) [342.406831] [148/162] (781s left) vgem_basic (dmabuf-fence-before) [342.635301] Starting subtest: dmabuf-fence-before [342.638540] Subtest dmabuf-fence-before: SUCCESS (0.002s) [342.857551] [149/162] (781s left) vgem_basic (dmabuf-mmap) [343.090208] Starting subtest: dmabuf-mmap [343.133680] Subtest dmabuf-mmap: SUCCESS (0.046s) [343.352605] [150/162] (781s left) vgem_basic (mmap) [343.579191] Starting subtest: mmap [343.602951] Subtest mmap: SUCCESS (0.031s) [343.834293] [151/162] (781s left) vgem_basic (second-client) [344.075173] Starting subtest: second-client [344.089541] Subtest second-client: SUCCESS (0.019s) [344.313906] [152/162] (780s left) vgem_basic (sysfs) [344.543193] Starting subtest: sysfs [344.546623] Subtest sysfs: SUCCESS (0.003s) [344.764999] [153/162] (780s left) core_hotunplug (unbind-rebind) [345.194686] Starting subtest: unbind-rebind [350.380280] Subtest unbind-rebind: SUCCESS (5.171s) [350.643335] [154/162] (774s left) vgem_basic (unload) [350.876166] Starting subtest: unload [380.108734] Subtest unload: SUCCESS (29.234s) [380.475532] [155/162] (745s left) i915_module_load (reload) [380.707824] Starting subtest: reload [388.727255] Subtest reload: SUCCESS (8.006s) [389.012518] [156/162] (737s left) gem_lmem_swapping (basic) [389.324921] Subtest basic: SKIP (0.000s) [389.537679] [157/162] (736s left) gem_lmem_swapping (parallel-random-engines) [389.777159] Subtest parallel-random-engines: SKIP (0.000s) [390.000401] [158/162] (736s left) gem_lmem_swapping (random-engines) [390.249119] Subtest random-engines: SKIP (0.000s) [390.467851] [159/162] (736s left) gem_lmem_swapping (verify-random) [390.704410] Subtest verify-random: SKIP (0.000s) [390.929912] [160/162] (736s left) i915_pm_rpm (module-reload) [392.861703] Starting subtest: module-reload [412.442454] Subtest module-reload: SUCCESS (19.575s) [412.580979] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [412.586622] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true [412.593522] [161/162] (714s left) i915_selftest (live)