[250.179813] Initializing watchdogs [250.179878] /dev/watchdog0 [250.185367] [001/170] (960s left) i915_module_load (load) [250.242654] Starting subtest: load [251.113553] Subtest load: SUCCESS (0.870s) [251.129641] [002/170] (959s left) core_auth (basic-auth) [251.222980] Starting subtest: basic-auth [251.224513] Subtest basic-auth: SUCCESS (0.000s) [251.274129] [003/170] (959s left) debugfs_test (read_all_entries) [251.331803] Starting subtest: read_all_entries [251.380098] Subtest read_all_entries: SUCCESS (0.048s) [251.491149] [004/170] (959s left) debugfs_test (basic-hwmon) [251.545739] Starting subtest: basic-hwmon [251.548696] Subtest basic-hwmon: SKIP (0.000s) [251.640436] [005/170] (959s left) fbdev (eof) [251.696875] Starting subtest: eof [251.698951] Subtest eof: SUCCESS (0.000s) [251.741753] [006/170] (959s left) fbdev (info) [251.812029] Starting subtest: info [251.813500] Subtest info: SUCCESS (0.000s) [251.860152] [007/170] (958s left) fbdev (nullptr) [251.929800] Starting subtest: nullptr [251.931500] Subtest nullptr: SUCCESS (0.000s) [251.978339] [008/170] (958s left) fbdev (read) [252.063184] Starting subtest: read [252.281469] Subtest read: SUCCESS (0.218s) [252.324285] [009/170] (958s left) fbdev (write) [252.396682] Starting subtest: write [252.506300] Subtest write: SUCCESS (0.110s) [252.541462] [010/170] (958s left) gem_basic (bad-close) [252.598576] Starting subtest: bad-close [252.601945] Subtest bad-close: SUCCESS (0.000s) [252.667332] [011/170] (958s left) gem_basic (create-close) [252.762168] Starting subtest: create-close [252.765351] Subtest create-close: SUCCESS (0.000s) [252.827360] [012/170] (958s left) gem_basic (create-fd-close) [252.903047] Starting subtest: create-fd-close [252.906449] Subtest create-fd-close: SUCCESS (0.000s) [252.980424] [013/170] (957s left) gem_busy (busy@all-engines) [253.069258] Starting subtest: busy [253.070814] Starting dynamic subtest: all-engines [253.091207] Dynamic subtest all-engines: SUCCESS (0.022s) [253.092782] Subtest busy: SUCCESS (0.022s) [253.436194] [014/170] (957s left) gem_close_race (basic-process) [253.496946] Starting subtest: basic-process [253.553896] Subtest basic-process: SUCCESS (0.056s) [253.722676] [015/170] (957s left) gem_close_race (basic-threads) [253.831277] Starting subtest: basic-threads [255.045143] Subtest basic-threads: SUCCESS (1.214s) [255.253884] [016/170] (955s left) gem_ctx_create (basic) [255.324215] Starting subtest: basic [255.325927] Subtest basic: SUCCESS (0.000s) [255.508537] [017/170] (955s left) gem_ctx_create (basic-files) [255.575883] Starting subtest: basic-files [257.586028] Subtest basic-files: SUCCESS (2.010s) [257.842109] [018/170] (953s left) gem_ctx_exec (basic) [257.941217] Starting subtest: basic [257.942787] Subtest basic: SUCCESS (0.002s) [258.023522] [019/170] (952s left) gem_exec_basic (basic) [258.087663] Starting subtest: basic [258.089351] Starting dynamic subtest: rcs0-smem [258.090972] Dynamic subtest rcs0-smem: SUCCESS (0.001s) [258.095955] Starting dynamic subtest: bcs0-smem [258.097701] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [258.102723] Starting dynamic subtest: vcs0-smem [258.104249] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [258.109225] Starting dynamic subtest: vecs0-smem [258.110876] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [258.112447] Subtest basic: SUCCESS (0.002s) [258.301994] [020/170] (952s left) gem_exec_create (basic) [258.360103] Starting subtest: basic [258.361875] Starting dynamic subtest: smem [260.405761] Dynamic subtest smem: SUCCESS (2.045s) [260.407186] Subtest basic: SUCCESS (2.046s) [260.569433] [021/170] (950s left) gem_exec_fence (basic-busy) [260.782583] Starting subtest: basic-busy [260.784194] Starting dynamic subtest: rcs0 [260.813862] Dynamic subtest rcs0: SUCCESS (0.031s) [260.821841] Starting dynamic subtest: bcs0 [260.847614] Dynamic subtest bcs0: SUCCESS (0.033s) [260.868986] Starting dynamic subtest: vcs0 [260.882922] Dynamic subtest vcs0: SUCCESS (0.035s) [260.904177] Starting dynamic subtest: vecs0 [260.913495] Dynamic subtest vecs0: SUCCESS (0.031s) [260.915829] Subtest basic-busy: SUCCESS (0.130s) [261.467571] [022/170] (949s left) gem_exec_fence (basic-wait) [261.719768] Starting subtest: basic-wait [261.722497] Starting dynamic subtest: rcs0 [261.748816] Dynamic subtest rcs0: SUCCESS (0.030s) [261.754079] Starting dynamic subtest: bcs0 [261.774832] Dynamic subtest bcs0: SUCCESS (0.026s) [261.781768] Starting dynamic subtest: vcs0 [261.796684] Dynamic subtest vcs0: SUCCESS (0.022s) [261.802982] Starting dynamic subtest: vecs0 [261.828124] Dynamic subtest vecs0: SUCCESS (0.031s) [261.829985] Subtest basic-wait: SUCCESS (0.109s) [262.353334] [023/170] (948s left) gem_exec_fence (basic-await) [262.523910] Starting subtest: basic-await [262.525540] Starting dynamic subtest: rcs0 [262.593043] Dynamic subtest rcs0: SUCCESS (0.069s) [262.606221] Starting dynamic subtest: bcs0 [262.686383] Dynamic subtest bcs0: SUCCESS (0.094s) [262.698986] Starting dynamic subtest: vcs0 [262.776446] Dynamic subtest vcs0: SUCCESS (0.090s) [262.789808] Starting dynamic subtest: vecs0 [262.865736] Dynamic subtest vecs0: SUCCESS (0.089s) [262.867395] Subtest basic-await: SUCCESS (0.342s) [263.415944] [024/170] (947s left) gem_exec_fence (nb-await) [263.585456] Starting subtest: nb-await [263.586848] Starting dynamic subtest: rcs0 [263.644842] Dynamic subtest rcs0: SUCCESS (0.058s) [263.665981] Starting dynamic subtest: bcs0 [263.717237] Dynamic subtest bcs0: SUCCESS (0.072s) [263.738059] Starting dynamic subtest: vcs0 [263.787662] Dynamic subtest vcs0: SUCCESS (0.070s) [263.808538] Starting dynamic subtest: vecs0 [263.857712] Dynamic subtest vecs0: SUCCESS (0.070s) [263.860162] Subtest nb-await: SUCCESS (0.271s) [264.348259] [025/170] (946s left) gem_exec_gttfill (basic) [264.404668] Starting subtest: basic [269.095980] Subtest basic: SUCCESS (4.691s) [269.258036] [026/170] (941s left) gem_exec_parallel (engines) [269.361846] Starting subtest: engines [269.363396] Starting dynamic subtest: basic [270.799772] Dynamic subtest basic: SUCCESS (1.438s) [270.806131] Starting dynamic subtest: contexts [273.174195] Dynamic subtest contexts: SUCCESS (2.374s) [273.179954] Starting dynamic subtest: fds [275.428836] Dynamic subtest fds: SUCCESS (2.255s) [275.434683] Starting dynamic subtest: userptr [277.153887] Dynamic subtest userptr: SUCCESS (1.725s) [277.155442] Subtest engines: SUCCESS (7.792s) [277.361210] [027/170] (933s left) gem_exec_store (basic) [277.458710] Starting subtest: basic [277.507947] Subtest basic: SUCCESS (0.049s) [277.703391] [028/170] (933s left) gem_flink_basic (bad-flink) [277.798926] Starting subtest: bad-flink [277.800563] Subtest bad-flink: SUCCESS (0.000s) [277.862413] [029/170] (933s left) gem_flink_basic (bad-open) [277.921609] Starting subtest: bad-open [277.923243] Subtest bad-open: SUCCESS (0.000s) [277.977874] [030/170] (933s left) gem_flink_basic (basic) [278.052945] Starting subtest: basic [278.054717] Subtest basic: SUCCESS (0.000s) [278.132295] [031/170] (933s left) gem_flink_basic (double-flink) [278.190661] Starting subtest: double-flink [278.192252] Subtest double-flink: SUCCESS (0.000s) [278.266039] [032/170] (933s left) gem_flink_basic (flink-lifetime) [278.354620] Starting subtest: flink-lifetime [278.356049] Subtest flink-lifetime: SUCCESS (0.001s) [278.430961] [033/170] (932s left) gem_huc_copy (huc-copy) [278.513152] Starting subtest: huc-copy [278.516221] Subtest huc-copy: SKIP (0.000s) [278.641602] [034/170] (932s left) gem_linear_blits (basic) [278.772353] Starting subtest: basic [278.776261] Subtest basic: SUCCESS (0.004s) [278.925286] [035/170] (932s left) gem_mmap (basic) [279.077766] Starting subtest: basic [279.085466] Subtest basic: SUCCESS (0.000s) [279.165922] [036/170] (932s left) gem_mmap_gtt (basic) [279.217756] Starting subtest: basic [279.219194] Subtest basic: SUCCESS (0.000s) [279.270185] [037/170] (932s left) gem_render_linear_blits (basic) [279.339556] Starting subtest: basic [279.358660] Subtest basic: SUCCESS (0.020s) [279.386552] [038/170] (932s left) gem_render_tiled_blits (basic) [279.442871] Starting subtest: basic [279.465320] Subtest basic: SUCCESS (0.023s) [279.508024] [039/170] (931s left) gem_ringfill (basic-all) [279.759785] Starting subtest: basic-all [280.894700] Subtest basic-all: SUCCESS (1.137s) [281.002712] [040/170] (930s left) gem_softpin (allocator-basic) [281.064182] Starting subtest: allocator-basic [281.149159] Subtest allocator-basic: SUCCESS (0.086s) [281.228945] [041/170] (930s left) gem_softpin (allocator-basic-reserve) [281.281987] Starting subtest: allocator-basic-reserve [281.341208] Subtest allocator-basic-reserve: SUCCESS (0.060s) [281.435272] [042/170] (930s left) gem_softpin (safe-alignment) [281.492578] Starting subtest: safe-alignment [281.499776] Subtest safe-alignment: SUCCESS (0.008s) [281.628487] [043/170] (929s left) gem_sync (basic-all) [281.717823] Starting subtest: basic-all [283.755906] Subtest basic-all: SUCCESS (2.045s) [283.928602] [044/170] (927s left) gem_sync (basic-each) [283.994371] Starting subtest: basic-each [286.071389] Subtest basic-each: SUCCESS (2.084s) [286.249625] [045/170] (925s left) gem_tiled_blits (basic) [286.420499] Starting subtest: basic [286.432311] Subtest basic: SUCCESS (0.012s) [286.543493] [046/170] (925s left) gem_tiled_fence_blits (basic) [286.732152] Starting subtest: basic [286.738120] Subtest basic: SUCCESS (0.006s) [286.871174] [047/170] (924s left) gem_tiled_pread_basic [287.168633] [048/170] (924s left) gem_wait (busy@all-engines) [287.238825] Starting subtest: busy [287.240544] Starting dynamic subtest: all-engines [287.754085] Dynamic subtest all-engines: SUCCESS (0.515s) [287.755897] Subtest busy: SUCCESS (0.515s) [287.941844] [049/170] (923s left) gem_wait (wait@all-engines) [288.000829] Starting subtest: wait [288.002469] Starting dynamic subtest: all-engines [289.037093] Dynamic subtest all-engines: SUCCESS (1.035s) [289.039674] Subtest wait: SUCCESS (1.035s) [289.211212] [050/170] (922s left) i915_getparams_basic (basic-eu-total) [289.273414] Starting subtest: basic-eu-total [289.276767] Subtest basic-eu-total: SUCCESS (0.000s) [289.326265] [051/170] (922s left) i915_getparams_basic (basic-subslice-total) [289.383845] Starting subtest: basic-subslice-total [289.386915] Subtest basic-subslice-total: SUCCESS (0.000s) [289.449536] [052/170] (922s left) i915_hangman (error-state-basic) [289.545736] Starting subtest: error-state-basic [289.554189] Subtest error-state-basic: SUCCESS (0.008s) [289.610755] [053/170] (922s left) i915_pciid [289.755321] [054/170] (922s left) kms_addfb_basic (addfb25-bad-modifier) [289.825184] Starting subtest: addfb25-bad-modifier [289.826740] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [289.959320] [055/170] (921s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [290.012064] Starting subtest: addfb25-framebuffer-vs-set-tiling [290.013622] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [290.141904] [056/170] (921s left) kms_addfb_basic (addfb25-modifier-no-flag) [290.201452] Starting subtest: addfb25-modifier-no-flag [290.202992] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [290.309253] [057/170] (921s left) kms_addfb_basic (addfb25-x-tiled-legacy) [290.361323] Starting subtest: addfb25-x-tiled-legacy [290.362880] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [290.475362] [058/170] (921s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [290.528253] Starting subtest: addfb25-x-tiled-mismatch-legacy [290.529732] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [290.659127] [059/170] (921s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [290.760299] Starting subtest: addfb25-yf-tiled-legacy [290.761835] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [290.824965] [060/170] (921s left) kms_addfb_basic (addfb25-y-tiled-legacy) [290.926182] Starting subtest: addfb25-y-tiled-legacy [290.927779] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [291.009088] [061/170] (920s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [291.110127] Starting subtest: addfb25-y-tiled-small-legacy [291.111728] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [291.175288] [062/170] (920s left) kms_addfb_basic (bad-pitch-0) [291.227576] Starting subtest: bad-pitch-0 [291.229145] Subtest bad-pitch-0: SUCCESS (0.000s) [291.359138] [063/170] (920s left) kms_addfb_basic (bad-pitch-1024) [291.417909] Starting subtest: bad-pitch-1024 [291.419449] Subtest bad-pitch-1024: SUCCESS (0.000s) [291.525358] [064/170] (920s left) kms_addfb_basic (bad-pitch-128) [291.576922] Starting subtest: bad-pitch-128 [291.578432] Subtest bad-pitch-128: SUCCESS (0.000s) [291.692378] [065/170] (920s left) kms_addfb_basic (bad-pitch-256) [291.745254] Starting subtest: bad-pitch-256 [291.746684] Subtest bad-pitch-256: SUCCESS (0.000s) [291.858359] [066/170] (920s left) kms_addfb_basic (bad-pitch-32) [291.910206] Starting subtest: bad-pitch-32 [291.911790] Subtest bad-pitch-32: SUCCESS (0.000s) [292.042131] [067/170] (919s left) kms_addfb_basic (bad-pitch-63) [292.101700] Starting subtest: bad-pitch-63 [292.103159] Subtest bad-pitch-63: SUCCESS (0.000s) [292.225308] [068/170] (919s left) kms_addfb_basic (bad-pitch-65536) [292.277382] Starting subtest: bad-pitch-65536 [292.278787] Subtest bad-pitch-65536: SUCCESS (0.000s) [292.408355] [069/170] (919s left) kms_addfb_basic (bad-pitch-999) [292.461027] Starting subtest: bad-pitch-999 [292.462545] Subtest bad-pitch-999: SUCCESS (0.000s) [292.575277] [070/170] (919s left) kms_addfb_basic (basic) [292.627702] Starting subtest: basic [292.629100] Subtest basic: SUCCESS (0.000s) [292.765222] [071/170] (919s left) kms_addfb_basic (basic-x-tiled-legacy) [292.864261] Starting subtest: basic-x-tiled-legacy [292.865595] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [292.991928] [072/170] (919s left) kms_addfb_basic (basic-y-tiled-legacy) [293.044210] Starting subtest: basic-y-tiled-legacy [293.045822] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [293.158742] [073/170] (918s left) kms_addfb_basic (bo-too-small) [293.261418] Starting subtest: bo-too-small [293.262979] Subtest bo-too-small: SUCCESS (0.000s) [293.342108] [074/170] (918s left) kms_addfb_basic (bo-too-small-due-to-tiling) [293.443151] Starting subtest: bo-too-small-due-to-tiling [293.444707] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [293.525734] [075/170] (918s left) kms_addfb_basic (clobberred-modifier) [293.577593] Starting subtest: clobberred-modifier [293.579179] Subtest clobberred-modifier: SUCCESS (0.000s) [293.691977] [076/170] (918s left) kms_addfb_basic (framebuffer-vs-set-tiling) [293.744088] Starting subtest: framebuffer-vs-set-tiling [293.745614] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [293.875622] [077/170] (918s left) kms_addfb_basic (invalid-get-prop) [293.928240] Starting subtest: invalid-get-prop [293.929680] Subtest invalid-get-prop: SUCCESS (0.000s) [294.042099] [078/170] (918s left) kms_addfb_basic (invalid-get-prop-any) [294.094717] Starting subtest: invalid-get-prop-any [294.096290] Subtest invalid-get-prop-any: SUCCESS (0.000s) [294.209180] [079/170] (917s left) kms_addfb_basic (invalid-set-prop) [294.260485] Starting subtest: invalid-set-prop [294.261980] Subtest invalid-set-prop: SUCCESS (0.000s) [294.375049] [080/170] (917s left) kms_addfb_basic (invalid-set-prop-any) [294.427422] Starting subtest: invalid-set-prop-any [294.428843] Subtest invalid-set-prop-any: SUCCESS (0.000s) [294.558956] [081/170] (917s left) kms_addfb_basic (no-handle) [294.611803] Starting subtest: no-handle [294.613364] Subtest no-handle: SUCCESS (0.000s) [294.741946] [082/170] (917s left) kms_addfb_basic (size-max) [294.843094] Starting subtest: size-max [294.844578] Subtest size-max: SUCCESS (0.000s) [294.908376] [083/170] (917s left) kms_addfb_basic (small-bo) [295.008130] Starting subtest: small-bo [295.009610] Subtest small-bo: SUCCESS (0.000s) [295.091710] [084/170] (917s left) kms_addfb_basic (tile-pitch-mismatch) [295.152354] Starting subtest: tile-pitch-mismatch [295.153909] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [295.292383] [085/170] (916s left) kms_addfb_basic (too-high) [295.398162] Starting subtest: too-high [295.399621] Subtest too-high: SUCCESS (0.000s) [295.475135] [086/170] (916s left) kms_addfb_basic (too-wide) [295.582277] Starting subtest: too-wide [295.583802] Subtest too-wide: SUCCESS (0.000s) [295.642347] [087/170] (916s left) kms_addfb_basic (unused-handle) [295.693989] Starting subtest: unused-handle [295.695592] Subtest unused-handle: SUCCESS (0.000s) [295.825304] [088/170] (916s left) kms_addfb_basic (unused-modifier) [295.884571] Starting subtest: unused-modifier [295.886159] Subtest unused-modifier: SUCCESS (0.000s) [296.008400] [089/170] (916s left) kms_addfb_basic (unused-offsets) [296.060729] Starting subtest: unused-offsets [296.062265] Subtest unused-offsets: SUCCESS (0.000s) [296.191987] [090/170] (916s left) kms_addfb_basic (unused-pitches) [296.251279] Starting subtest: unused-pitches [296.252747] Subtest unused-pitches: SUCCESS (0.000s) [296.375357] [091/170] (915s left) kms_busy (basic) [296.482782] Starting subtest: basic [296.484274] Starting dynamic subtest: flip [296.900361] Dynamic subtest flip: SUCCESS (0.418s) [296.907946] Starting dynamic subtest: modeset [297.253530] Dynamic subtest modeset: SUCCESS (0.353s) [297.254821] Subtest basic: SUCCESS (0.772s) [297.373051] [092/170] (914s left) kms_chamelium_hpd (dp-hpd-fast) [297.499285] Subtest dp-hpd-fast: SKIP (0.000s) [297.545300] [093/170] (914s left) kms_chamelium_edid (dp-edid-read) [297.676268] Subtest dp-edid-read: SKIP (0.000s) [297.720055] [094/170] (914s left) kms_chamelium_frames (dp-crc-fast) [297.832770] Subtest dp-crc-fast: SKIP (0.000s) [297.876776] [095/170] (914s left) kms_chamelium_hpd (hdmi-hpd-fast) [297.990866] Subtest hdmi-hpd-fast: SKIP (0.000s) [298.032778] [096/170] (914s left) kms_chamelium_edid (hdmi-edid-read) [298.145756] Subtest hdmi-edid-read: SKIP (0.000s) [298.188948] [097/170] (914s left) kms_chamelium_frames (hdmi-crc-fast) [298.301992] Subtest hdmi-crc-fast: SKIP (0.000s) [298.346132] [098/170] (914s left) kms_chamelium_hpd (vga-hpd-fast) [298.460395] Subtest vga-hpd-fast: SKIP (0.000s) [298.504246] [099/170] (913s left) kms_chamelium_edid (vga-edid-read) [298.634468] Subtest vga-edid-read: SKIP (0.000s) [298.680381] [100/170] (913s left) kms_prop_blob (basic) [298.733154] Starting subtest: basic [298.734662] Subtest basic: SUCCESS (0.000s) [298.782153] [101/170] (913s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [298.882216] Starting subtest: basic-busy-flip-before-cursor-atomic [298.885178] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [298.932501] [102/170] (913s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [299.035150] Starting subtest: basic-busy-flip-before-cursor-legacy [299.038082] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [299.082640] [103/170] (913s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [299.183441] Starting subtest: basic-flip-after-cursor-atomic [300.093285] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.908s) [300.203914] [104/170] (912s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [300.304452] Starting subtest: basic-flip-after-cursor-legacy [301.212350] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.907s) [301.305970] [105/170] (911s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [301.408178] Starting subtest: basic-flip-after-cursor-varying-size [302.331535] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.923s) [302.436559] [106/170] (910s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [302.539197] Starting subtest: basic-flip-before-cursor-atomic [303.444602] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.905s) [303.530223] [107/170] (908s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [303.633102] Starting subtest: basic-flip-before-cursor-legacy [304.555338] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.922s) [304.653013] [108/170] (907s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [304.754080] Starting subtest: basic-flip-before-cursor-varying-size [305.661565] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.906s) [305.758186] [109/170] (906s left) kms_flip (basic-flip-vs-dpms) [305.847049] Starting subtest: basic-flip-vs-dpms [305.848532] Starting dynamic subtest: A-HDMI-A1 [306.668933] Dynamic subtest A-HDMI-A1: SUCCESS (0.821s) [306.675396] Starting dynamic subtest: B-HDMI-A1 [307.467422] Dynamic subtest B-HDMI-A1: SUCCESS (0.798s) [307.473565] Starting dynamic subtest: C-HDMI-A1 [308.262447] Dynamic subtest C-HDMI-A1: SUCCESS (0.795s) [308.263944] Subtest basic-flip-vs-dpms: SUCCESS (2.418s) [308.352902] [110/170] (904s left) kms_flip (basic-flip-vs-modeset) [308.446719] Starting subtest: basic-flip-vs-modeset [308.448212] Starting dynamic subtest: A-HDMI-A1 [309.247470] Dynamic subtest A-HDMI-A1: SUCCESS (0.798s) [309.253696] Starting dynamic subtest: B-HDMI-A1 [310.008756] Dynamic subtest B-HDMI-A1: SUCCESS (0.760s) [310.015465] Starting dynamic subtest: C-HDMI-A1 [310.784863] Dynamic subtest C-HDMI-A1: SUCCESS (0.776s) [310.786231] Subtest basic-flip-vs-modeset: SUCCESS (2.338s) [310.887202] [111/170] (901s left) kms_flip (basic-flip-vs-wf_vblank) [310.980634] Starting subtest: basic-flip-vs-wf_vblank [310.982053] Starting dynamic subtest: A-HDMI-A1 [312.081543] Dynamic subtest A-HDMI-A1: SUCCESS (1.101s) [312.087567] Starting dynamic subtest: B-HDMI-A1 [313.153589] Dynamic subtest B-HDMI-A1: SUCCESS (1.072s) [313.160840] Starting dynamic subtest: C-HDMI-A1 [314.226989] Dynamic subtest C-HDMI-A1: SUCCESS (1.073s) [314.229171] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.251s) [314.348502] [112/170] (898s left) kms_flip (basic-plain-flip) [314.439439] Starting subtest: basic-plain-flip [314.440997] Starting dynamic subtest: A-HDMI-A1 [315.255545] Dynamic subtest A-HDMI-A1: SUCCESS (0.817s) [315.261803] Starting dynamic subtest: B-HDMI-A1 [316.044530] Dynamic subtest B-HDMI-A1: SUCCESS (0.789s) [316.050763] Starting dynamic subtest: C-HDMI-A1 [316.832479] Dynamic subtest C-HDMI-A1: SUCCESS (0.787s) [316.833920] Subtest basic-plain-flip: SUCCESS (2.397s) [316.932374] [113/170] (895s left) kms_force_connector_basic (force-connector-state) [317.084113] Starting subtest: force-connector-state [317.424185] Subtest force-connector-state: SUCCESS (0.339s) [317.833394] [114/170] (894s left) kms_force_connector_basic (force-edid) [317.984401] Starting subtest: force-edid [318.118350] Subtest force-edid: SUCCESS (0.134s) [318.316799] [115/170] (894s left) kms_force_connector_basic (force-load-detect) [318.467425] Starting subtest: force-load-detect [318.470462] Subtest force-load-detect: SKIP (0.000s) [318.666895] [116/170] (893s left) kms_force_connector_basic (prune-stale-modes) [318.817561] Starting subtest: prune-stale-modes [318.951595] Subtest prune-stale-modes: SUCCESS (0.134s) [319.149919] [117/170] (893s left) kms_frontbuffer_tracking (basic) [319.257707] Starting subtest: basic [321.620727] Subtest basic: SUCCESS (2.367s) [321.721643] [118/170] (890s left) kms_pipe_crc_basic (compare-crc-sanitycheck) [321.835381] Starting subtest: compare-crc-sanitycheck [321.836904] Starting dynamic subtest: pipe-A-HDMI-A-1 [322.195341] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.359s) [322.202988] Starting dynamic subtest: pipe-B-HDMI-A-1 [322.545616] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.349s) [322.554930] Starting dynamic subtest: pipe-C-HDMI-A-1 [322.898134] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.350s) [322.899705] Subtest compare-crc-sanitycheck: SUCCESS (1.063s) [322.999782] [119/170] (889s left) kms_pipe_crc_basic (hang-read-crc) [323.103596] Starting subtest: hang-read-crc [323.105165] Starting dynamic subtest: pipe-A-HDMI-A-1 [323.911023] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.806s) [323.917444] Starting dynamic subtest: pipe-B-HDMI-A-1 [324.755341] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.844s) [324.765118] Starting dynamic subtest: pipe-C-HDMI-A-1 [325.650088] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.893s) [325.651631] Subtest hang-read-crc: SUCCESS (2.548s) [325.727525] [120/170] (886s left) kms_pipe_crc_basic (nonblocking-crc) [325.832455] Starting subtest: nonblocking-crc [325.833976] Starting dynamic subtest: pipe-A-HDMI-A-1 [326.318871] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.487s) [326.327425] Starting dynamic subtest: pipe-B-HDMI-A-1 [326.799123] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.476s) [326.805583] Starting dynamic subtest: pipe-C-HDMI-A-1 [327.310793] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.510s) [327.312293] Subtest nonblocking-crc: SUCCESS (1.478s) [327.387328] [121/170] (885s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [327.491287] Starting subtest: nonblocking-crc-frame-sequence [327.492660] Starting dynamic subtest: pipe-A-HDMI-A-1 [327.979985] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.487s) [327.986610] Starting dynamic subtest: pipe-B-HDMI-A-1 [328.457957] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.476s) [328.464705] Starting dynamic subtest: pipe-C-HDMI-A-1 [328.971535] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.512s) [328.972998] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.480s) [329.047670] [122/170] (883s left) kms_pipe_crc_basic (read-crc) [329.149143] Starting subtest: read-crc [329.150531] Starting dynamic subtest: pipe-A-HDMI-A-1 [329.572942] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.423s) [329.582838] Starting dynamic subtest: pipe-B-HDMI-A-1 [329.986024] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.409s) [329.992520] Starting dynamic subtest: pipe-C-HDMI-A-1 [330.429360] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.442s) [330.430772] Subtest read-crc: SUCCESS (1.280s) [330.505617] [123/170] (882s left) kms_pipe_crc_basic (read-crc-frame-sequence) [330.608113] Starting subtest: read-crc-frame-sequence [330.609420] Starting dynamic subtest: pipe-A-HDMI-A-1 [331.049103] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.438s) [331.055756] Starting dynamic subtest: pipe-B-HDMI-A-1 [331.476229] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.428s) [331.486586] Starting dynamic subtest: pipe-C-HDMI-A-1 [331.923702] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.445s) [331.925261] Subtest read-crc-frame-sequence: SUCCESS (1.316s) [332.001765] [124/170] (880s left) kms_psr (primary_page_flip) [332.057866] Subtest primary_page_flip: SKIP (0.000s) [332.080347] [125/170] (880s left) kms_psr (cursor_plane_move) [332.135017] Subtest cursor_plane_move: SKIP (0.000s) [332.163912] [126/170] (880s left) kms_psr (sprite_plane_onoff) [332.222170] Subtest sprite_plane_onoff: SKIP (0.000s) [332.246916] [127/170] (880s left) kms_psr (primary_mmap_gtt) [332.300686] Subtest primary_mmap_gtt: SKIP (0.000s) [332.313879] [128/170] (880s left) kms_setmode (basic-clone-single-crtc) [332.367284] Starting subtest: basic-clone-single-crtc [332.372153] Subtest basic-clone-single-crtc: SKIP (0.000s) [332.420749] [129/170] (880s left) i915_pm_backlight (basic-brightness) [332.545826] Subtest basic-brightness: SKIP (0.000s) [332.563268] [130/170] (880s left) i915_pm_rpm (basic-pci-d3-state) [333.968090] Starting subtest: basic-pci-d3-state [334.442528] Subtest basic-pci-d3-state: SUCCESS (0.474s) [334.570580] [131/170] (878s left) i915_pm_rpm (basic-rte) [334.626537] Starting subtest: basic-rte [336.400162] Subtest basic-rte: SUCCESS (1.771s) [340.541355] [132/170] (872s left) i915_pm_rps (basic-api) [340.597027] Starting subtest: basic-api [340.598853] Subtest basic-api: SUCCESS (0.002s) [340.668640] [133/170] (872s left) prime_self_import (basic-llseek-bad) [340.727972] Starting subtest: basic-llseek-bad [340.731226] Subtest basic-llseek-bad: SUCCESS (0.001s) [340.831326] [134/170] (872s left) prime_self_import (basic-llseek-size) [340.919733] Starting subtest: basic-llseek-size [340.923472] Subtest basic-llseek-size: SUCCESS (0.001s) [340.997448] [135/170] (871s left) prime_self_import (basic-with_fd_dup) [341.075200] Starting subtest: basic-with_fd_dup [341.078241] Subtest basic-with_fd_dup: SUCCESS (0.002s) [341.145752] [136/170] (871s left) prime_self_import (basic-with_one_bo) [341.216748] Starting subtest: basic-with_one_bo [341.219532] Subtest basic-with_one_bo: SUCCESS (0.002s) [341.272321] [137/170] (871s left) prime_self_import (basic-with_one_bo_two_files) [341.325596] Starting subtest: basic-with_one_bo_two_files [341.332209] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s) [341.456936] [138/170] (871s left) prime_self_import (basic-with_two_bos) [341.543218] Starting subtest: basic-with_two_bos [341.546921] Subtest basic-with_two_bos: SUCCESS (0.002s) [341.645232] [139/170] (871s left) prime_vgem (basic-fence-flip) [341.762687] Starting subtest: basic-fence-flip [342.144969] Subtest basic-fence-flip: SUCCESS (0.382s) [342.228140] [140/170] (870s left) prime_vgem (basic-fence-mmap) [342.306892] Starting subtest: basic-fence-mmap [342.322311] Subtest basic-fence-mmap: SUCCESS (0.016s) [342.456932] [141/170] (870s left) prime_vgem (basic-fence-read) [342.549787] Starting subtest: basic-fence-read [342.574968] Subtest basic-fence-read: SUCCESS (0.025s) [342.707926] [142/170] (870s left) prime_vgem (basic-gtt) [342.808478] Starting subtest: basic-gtt [342.815867] Subtest basic-gtt: SUCCESS (0.007s) [342.899440] [143/170] (870s left) prime_vgem (basic-read) [342.972959] Starting subtest: basic-read [342.988195] Subtest basic-read: SUCCESS (0.015s) [343.096640] [144/170] (870s left) prime_vgem (basic-write) [343.205708] Starting subtest: basic-write [343.217636] Subtest basic-write: SUCCESS (0.012s) [343.303749] [145/170] (869s left) vgem_basic (setversion) [343.379860] Starting subtest: setversion [343.383195] Subtest setversion: SUCCESS (0.000s) [343.440368] [146/170] (869s left) vgem_basic (create) [343.546816] Starting subtest: create [343.548463] Subtest create: SUCCESS (0.000s) [343.592189] [147/170] (869s left) vgem_basic (debugfs) [343.663207] Starting subtest: debugfs [343.664834] Subtest debugfs: SUCCESS (0.000s) [343.708595] [148/170] (869s left) vgem_basic (dmabuf-export) [343.779893] Starting subtest: dmabuf-export [343.790287] Subtest dmabuf-export: SUCCESS (0.012s) [343.900978] [149/170] (869s left) vgem_basic (dmabuf-fence) [343.964790] Starting subtest: dmabuf-fence [343.966958] Subtest dmabuf-fence: SUCCESS (0.001s) [344.037596] [150/170] (869s left) vgem_basic (dmabuf-fence-before) [344.147057] Starting subtest: dmabuf-fence-before [344.148786] Subtest dmabuf-fence-before: SUCCESS (0.000s) [344.194299] [151/170] (869s left) vgem_basic (dmabuf-mmap) [344.279649] Starting subtest: dmabuf-mmap [344.287179] Subtest dmabuf-mmap: SUCCESS (0.009s) [344.332982] [152/170] (869s left) vgem_basic (mmap) [344.413126] Starting subtest: mmap [344.417407] Subtest mmap: SUCCESS (0.006s) [344.458277] [153/170] (868s left) vgem_basic (second-client) [344.514648] Starting subtest: second-client [344.561668] Subtest second-client: SUCCESS (0.050s) [344.632154] [154/170] (868s left) vgem_basic (sysfs) [344.696209] Starting subtest: sysfs [344.697714] Subtest sysfs: SUCCESS (0.000s) [344.744064] [155/170] (868s left) core_hotunplug (unbind-rebind) [344.844985] Starting subtest: unbind-rebind [345.693734] Subtest unbind-rebind: SUCCESS (0.849s) [345.710842] [156/170] (867s left) vgem_basic (unload) [345.768126] Starting subtest: unload [374.652732] Subtest unload: SUCCESS (28.884s) [374.730145] [157/170] (838s left) i915_module_load (reload) [374.783703] Starting subtest: reload [376.573351] Subtest reload: SUCCESS (1.790s) [376.589550] [158/170] (836s left) gem_lmem_swapping (basic) [376.661831] Subtest basic: SKIP (0.000s) [376.679904] [159/170] (836s left) gem_lmem_swapping (parallel-random-engines) [376.736943] Subtest parallel-random-engines: SKIP (0.000s) [376.790261] [160/170] (836s left) gem_lmem_swapping (random-engines) [376.872406] Subtest random-engines: SKIP (0.000s) [376.896942] [161/170] (836s left) gem_lmem_swapping (verify-random) [376.957338] Subtest verify-random: SKIP (0.000s) [376.980586] [162/170] (836s left) i915_pm_rpm (module-reload) [378.683541] Starting subtest: module-reload [386.527852] Subtest module-reload: SUCCESS (7.844s) [386.569042] [163/170] (826s left) i915_selftest (live) [386.649043] Starting subtest: live [386.651243] Starting dynamic subtest: sanitycheck [387.119946] Dynamic subtest sanitycheck: SUCCESS (0.470s) [387.125214] Starting dynamic subtest: uncore [387.600905] Dynamic subtest uncore: SUCCESS (0.481s) [387.605894] Starting dynamic subtest: workarounds [388.279950] Dynamic subtest workarounds: SUCCESS (0.679s) [388.285786] Starting dynamic subtest: gt_engines [389.212143] Dynamic subtest gt_engines: SUCCESS (0.931s) [389.218238] Starting dynamic subtest: gt_timelines [395.810162] Dynamic subtest gt_timelines: SUCCESS (6.598s) [395.815641] Starting dynamic subtest: gt_contexts [396.375136] Dynamic subtest gt_contexts: SUCCESS (0.565s) [396.380830] Starting dynamic subtest: gt_lrc [399.152208] Dynamic subtest gt_lrc: SUCCESS (2.777s) [399.158340] Starting dynamic subtest: gt_mocs [399.705873] Dynamic subtest gt_mocs: SUCCESS (0.554s) [399.711623] Starting dynamic subtest: gt_pm [402.048251] Dynamic subtest gt_pm: SUCCESS (2.342s) [402.053720] Starting dynamic subtest: gt_heartbeat [402.613174] Dynamic subtest gt_heartbeat: SUCCESS (0.565s) [402.619021] Starting dynamic subtest: gt_tlb [403.939157] Dynamic subtest gt_tlb: SUCCESS (1.326s) [403.944946] Starting dynamic subtest: requests [410.726220] Dynamic subtest requests: SUCCESS (6.787s) [410.731844] Starting dynamic subtest: migrate [414.127077] Dynamic subtest migrate: SUCCESS (3.401s) [414.132845] Starting dynamic subtest: active [414.611090] Dynamic subtest active: SUCCESS (0.484s) [414.616831] Starting dynamic subtest: objects [415.605114] Dynamic subtest objects: SUCCESS (0.994s) [415.611116] Starting dynamic subtest: mman [419.413207] Dynamic subtest mman: SUCCESS (3.808s) [419.418841] Starting dynamic subtest: dmabuf [419.932121] Dynamic subtest dmabuf: SUCCESS (0.519s) [419.937986] Starting dynamic subtest: vma [420.408363] Dynamic subtest vma: SUCCESS (0.476s) [420.415054] Starting dynamic subtest: coherency [421.431116] Dynamic subtest coherency: SUCCESS (1.022s) [421.436879] Starting dynamic subtest: gtt [429.420128] Dynamic subtest gtt: SUCCESS (7.989s) [429.425927] Starting dynamic subtest: gem [429.958271] Dynamic subtest gem: SUCCESS (0.538s) [429.964089] Starting dynamic subtest: evict [431.141159] Dynamic subtest evict: SUCCESS (1.183s) [431.147033] Starting dynamic subtest: hugepages [436.762432] Dynamic subtest hugepages: SUCCESS (5.621s) [436.768523] Starting dynamic subtest: gem_contexts [448.848331] Dynamic subtest gem_contexts: SUCCESS (12.086s) [448.854180] Starting dynamic subtest: client [449.367149] Dynamic subtest client: SUCCESS (0.519s) [449.373362] Starting dynamic subtest: gem_migrate [449.835123] Dynamic subtest gem_migrate: SUCCESS (0.468s) [449.840576] Starting dynamic subtest: reset [450.611116] Dynamic subtest reset: SUCCESS (0.776s) [450.617155] Starting dynamic subtest: memory_region [451.072160] Dynamic subtest memory_region: SUCCESS (0.461s) [451.078292] Starting dynamic subtest: hangcheck [474.604066] Dynamic subtest hangcheck: SUCCESS (23.532s) [474.609124] Starting dynamic subtest: execlists [482.878099] Dynamic subtest execlists: SUCCESS (8.274s) [482.883634] Starting dynamic subtest: ring_submission [483.327214] Dynamic subtest ring_submission: SUCCESS (0.449s) [483.332988] Starting dynamic subtest: perf [483.976968] Dynamic subtest perf: SUCCESS (0.650s) [483.982756] Starting dynamic subtest: slpc [484.449109] Dynamic subtest slpc: SUCCESS (0.472s) [484.456139] Starting dynamic subtest: guc [484.905949] Dynamic subtest guc: SUCCESS (0.456s) [484.911934] Starting dynamic subtest: guc_multi_lrc [485.375112] Dynamic subtest guc_multi_lrc: SUCCESS (0.468s) [485.381251] Starting dynamic subtest: guc_hang [485.843234] Dynamic subtest guc_hang: SUCCESS (0.468s) [485.849251] Starting dynamic subtest: late_gt_pm [486.333136] Dynamic subtest late_gt_pm: SUCCESS (0.490s) [486.334801] Subtest live: SUCCESS (99.684s) [486.531552] [164/170] (727s left) dmabuf (all-tests) [486.589379] Starting subtest: all-tests [486.591618] Starting dynamic subtest: sanitycheck [486.656028] Dynamic subtest sanitycheck: SUCCESS (0.066s) [486.664414] Starting dynamic subtest: dma_fence [486.824904] Dynamic subtest dma_fence: SUCCESS (0.168s) [486.832917] Starting dynamic subtest: dma_fence_chain [497.354945] Dynamic subtest dma_fence_chain: SUCCESS (10.530s) [497.361639] Starting dynamic subtest: dma_fence_unwrap [497.425063] Dynamic subtest dma_fence_unwrap: SUCCESS (0.069s) [497.434745] Starting dynamic subtest: dma_resv [497.495080] Dynamic subtest dma_resv: SUCCESS (0.069s) [497.496786] Subtest all-tests: SUCCESS (10.906s) [497.515565] [165/170] (716s left) i915_suspend (basic-s2idle-without-i915) [497.568900] Starting subtest: basic-s2idle-without-i915 [515.285383] Subtest basic-s2idle-without-i915: SUCCESS (1.957s) [521.431098] [166/170] (713s left) i915_suspend (basic-s3-without-i915) [521.486332] Starting subtest: basic-s3-without-i915 [539.351965] Subtest basic-s3-without-i915: SUCCESS (1.795s) [545.492629] [167/170] (711s left) gem_exec_suspend (basic-s0) [545.561016] Starting subtest: basic-S0 [545.562788] Starting dynamic subtest: smem [562.346689] Dynamic subtest smem: SUCCESS (1.686s) [562.348614] Subtest basic-S0: SUCCESS (1.686s) [566.499891] [168/170] (710s left) gem_exec_suspend (basic-s3) [566.562492] Starting subtest: basic-S3 [566.564230] Starting dynamic subtest: smem [584.376352] Dynamic subtest smem: SUCCESS (1.819s) [584.378238] Subtest basic-S3: SUCCESS (1.819s) [588.578627] [169/170] (708s left) kms_chamelium_hpd (common-hpd-after-suspend) [588.719853] Subtest common-hpd-after-suspend: SKIP (0.000s) [588.766939] [170/170] (707s left) kms_pipe_crc_basic (suspend-read-crc) [588.872268] Starting subtest: suspend-read-crc [588.874033] Starting dynamic subtest: pipe-A-HDMI-A-1 [606.414537] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.592s) [606.423414] Starting dynamic subtest: pipe-B-HDMI-A-1 [624.484949] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.623s) [624.493271] Starting dynamic subtest: pipe-C-HDMI-A-1 [642.473976] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.602s) [642.475777] Subtest suspend-read-crc: SUCCESS (4.823s) [646.521732] Closing watchdogs Done.