[95.556616] Initializing watchdogs [95.556789] /dev/watchdog0 [95.567058] [001/171] (960s left) i915_module_load (load) [95.709538] Starting subtest: load [98.278226] Subtest load: SUCCESS (2.569s) [98.307215] [002/171] (957s left) core_auth (basic-auth) [98.450208] Starting subtest: basic-auth [98.451314] Subtest basic-auth: SUCCESS (0.001s) [98.545473] [003/171] (957s left) debugfs_test (read_all_entries) [98.690168] Starting subtest: read_all_entries [98.763568] Subtest read_all_entries: SUCCESS (0.073s) [98.876371] [004/171] (957s left) debugfs_test (basic-hwmon) [99.019818] Starting subtest: basic-hwmon [99.022040] Subtest basic-hwmon: SKIP (0.000s) [99.138373] [005/171] (957s left) fbdev (eof) [99.364183] Starting subtest: eof [99.365415] Subtest eof: SUCCESS (0.000s) [99.444351] [006/171] (956s left) fbdev (info) [99.586461] Starting subtest: info [99.587672] Subtest info: SUCCESS (0.000s) [99.661087] [007/171] (956s left) fbdev (nullptr) [99.812398] Starting subtest: nullptr [99.813559] Subtest nullptr: SUCCESS (0.000s) [99.877425] [008/171] (956s left) fbdev (read) [100.028932] Starting subtest: read [107.090766] Subtest read: SUCCESS (7.060s) [107.160704] [009/171] (949s left) fbdev (write) [107.313819] Starting subtest: write [111.095606] Subtest write: SUCCESS (3.748s) [111.127953] [010/171] (945s left) gem_basic (bad-close) [111.259378] Starting subtest: bad-close [111.261945] Subtest bad-close: SUCCESS (0.000s) [111.344300] [011/171] (944s left) gem_basic (create-close) [111.478523] Starting subtest: create-close [111.480656] Subtest create-close: SUCCESS (0.000s) [111.577182] [012/171] (944s left) gem_basic (create-fd-close) [111.716511] Starting subtest: create-fd-close [111.718522] Subtest create-fd-close: SUCCESS (0.000s) [111.810296] [013/171] (944s left) gem_busy (busy@all-engines) [111.969274] Starting subtest: busy [111.970499] Starting dynamic subtest: all-engines [112.027738] Dynamic subtest all-engines: SUCCESS (0.058s) [112.029029] Subtest busy: SUCCESS (0.059s) [112.428272] [014/171] (943s left) gem_close_race (basic-process) [112.579513] Starting subtest: basic-process [112.643620] Subtest basic-process: SUCCESS (0.064s) [112.846438] [015/171] (943s left) gem_close_race (basic-threads) [113.002687] Starting subtest: basic-threads [114.163873] Subtest basic-threads: SUCCESS (1.162s) [114.386123] [016/171] (942s left) gem_ctx_create (basic) [114.558215] Starting subtest: basic [114.559640] Subtest basic: SUCCESS (0.000s) [114.762322] [017/171] (941s left) gem_ctx_create (basic-files) [114.913486] Starting subtest: basic-files [116.936868] Subtest basic-files: SUCCESS (2.023s) [117.197415] [018/171] (939s left) gem_ctx_exec (basic) [117.339349] Starting subtest: basic [117.344429] Subtest basic: SUCCESS (0.005s) [117.460467] [019/171] (939s left) gem_exec_basic (basic) [117.600524] Starting subtest: basic [117.601822] Starting dynamic subtest: rcs0-smem [117.602987] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [117.614808] Starting dynamic subtest: bcs0-smem [117.616090] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [117.625931] Starting dynamic subtest: vcs0-smem [117.627166] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [117.636962] Starting dynamic subtest: vecs0-smem [117.638284] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [117.639609] Subtest basic: SUCCESS (0.005s) [117.811569] [020/171] (938s left) gem_exec_create (basic) [117.956579] Starting subtest: basic [117.957731] Starting dynamic subtest: smem [119.999781] Dynamic subtest smem: SUCCESS (2.043s) [120.000994] Subtest basic: SUCCESS (2.043s) [120.214266] [021/171] (936s left) gem_exec_fence (basic-busy) [120.528478] Starting subtest: basic-busy [120.529769] Starting dynamic subtest: rcs0 [120.556102] Dynamic subtest rcs0: SUCCESS (0.036s) [120.568927] Starting dynamic subtest: bcs0 [120.587655] Dynamic subtest bcs0: SUCCESS (0.032s) [120.599050] Starting dynamic subtest: vcs0 [120.608326] Dynamic subtest vcs0: SUCCESS (0.021s) [120.618783] Starting dynamic subtest: vecs0 [120.640794] Dynamic subtest vecs0: SUCCESS (0.032s) [120.642190] Subtest basic-busy: SUCCESS (0.121s) [121.246697] [022/171] (935s left) gem_exec_fence (basic-wait) [121.549781] Starting subtest: basic-wait [121.551190] Starting dynamic subtest: rcs0 [121.580901] Dynamic subtest rcs0: SUCCESS (0.039s) [121.595451] Starting dynamic subtest: bcs0 [121.614192] Dynamic subtest bcs0: SUCCESS (0.034s) [121.626361] Starting dynamic subtest: vcs0 [121.634431] Dynamic subtest vcs0: SUCCESS (0.020s) [121.645663] Starting dynamic subtest: vecs0 [121.655583] Dynamic subtest vecs0: SUCCESS (0.021s) [121.656977] Subtest basic-wait: SUCCESS (0.115s) [122.196619] [023/171] (934s left) gem_exec_fence (basic-await) [122.498306] Starting subtest: basic-await [122.499425] Starting dynamic subtest: rcs0 [122.587564] Dynamic subtest rcs0: SUCCESS (0.096s) [122.613257] Starting dynamic subtest: bcs0 [122.703930] Dynamic subtest bcs0: SUCCESS (0.116s) [122.729180] Starting dynamic subtest: vcs0 [122.834479] Dynamic subtest vcs0: SUCCESS (0.129s) [122.860320] Starting dynamic subtest: vecs0 [122.963736] Dynamic subtest vecs0: SUCCESS (0.129s) [122.966091] Subtest basic-await: SUCCESS (0.473s) [123.530614] [024/171] (933s left) gem_exec_fence (nb-await) [123.834408] Starting subtest: nb-await [123.835618] Starting dynamic subtest: rcs0 [123.892624] Dynamic subtest rcs0: SUCCESS (0.065s) [123.910870] Starting dynamic subtest: bcs0 [123.963095] Dynamic subtest bcs0: SUCCESS (0.071s) [123.986876] Starting dynamic subtest: vcs0 [124.036578] Dynamic subtest vcs0: SUCCESS (0.073s) [124.062719] Starting dynamic subtest: vecs0 [124.110900] Dynamic subtest vecs0: SUCCESS (0.074s) [124.112976] Subtest nb-await: SUCCESS (0.284s) [124.647291] [025/171] (932s left) gem_exec_gttfill (basic) [124.793900] Starting subtest: basic [132.714761] Subtest basic: SUCCESS (7.913s) [132.901442] [026/171] (923s left) gem_exec_parallel (engines) [133.130370] Starting subtest: engines [133.131575] Starting dynamic subtest: basic [135.556215] Dynamic subtest basic: SUCCESS (2.402s) [135.568676] Starting dynamic subtest: contexts [138.879582] Dynamic subtest contexts: SUCCESS (3.339s) [138.897421] Starting dynamic subtest: fds [142.430772] Dynamic subtest fds: SUCCESS (3.560s) [142.446671] Starting dynamic subtest: userptr [145.028422] Dynamic subtest userptr: SUCCESS (2.572s) [145.029803] Subtest engines: SUCCESS (11.875s) [145.200270] [027/171] (911s left) gem_exec_store (basic) [145.348665] Starting subtest: basic [145.417249] Subtest basic: SUCCESS (0.070s) [145.614810] [028/171] (911s left) gem_flink_basic (bad-flink) [145.749432] Starting subtest: bad-flink [145.750541] Subtest bad-flink: SUCCESS (0.000s) [145.830213] [029/171] (910s left) gem_flink_basic (bad-open) [145.967306] Starting subtest: bad-open [145.968404] Subtest bad-open: SUCCESS (0.000s) [146.047095] [030/171] (910s left) gem_flink_basic (basic) [146.183492] Starting subtest: basic [146.184600] Subtest basic: SUCCESS (0.000s) [146.280558] [031/171] (910s left) gem_flink_basic (double-flink) [146.417626] Starting subtest: double-flink [146.418697] Subtest double-flink: SUCCESS (0.000s) [146.514617] [032/171] (910s left) gem_flink_basic (flink-lifetime) [146.651571] Starting subtest: flink-lifetime [146.654149] Subtest flink-lifetime: SUCCESS (0.002s) [146.763816] [033/171] (910s left) gem_huc_copy (huc-copy) [146.907344] Starting subtest: huc-copy [146.909470] Subtest huc-copy: SKIP (0.000s) [147.013487] [034/171] (909s left) gem_linear_blits (basic) [147.299460] Starting subtest: basic [147.311260] Subtest basic: SUCCESS (0.012s) [147.449380] [035/171] (909s left) gem_mmap (basic) [147.648642] Starting subtest: basic [147.653801] Subtest basic: SUCCESS (0.001s) [147.747109] [036/171] (909s left) gem_mmap_gtt (basic) [147.878182] Starting subtest: basic [147.879328] Subtest basic: SUCCESS (0.001s) [147.963230] [037/171] (909s left) gem_render_linear_blits (basic) [148.098256] Starting subtest: basic [148.148700] Subtest basic: SUCCESS (0.050s) [148.217066] [038/171] (908s left) gem_render_tiled_blits (basic) [148.352036] Starting subtest: basic [148.413157] Subtest basic: SUCCESS (0.061s) [148.480532] [039/171] (908s left) gem_ringfill (basic-all) [148.771799] Starting subtest: basic-all [150.029421] Subtest basic-all: SUCCESS (1.250s) [150.098718] [040/171] (906s left) gem_softpin (allocator-basic) [150.249973] Starting subtest: allocator-basic [150.418112] Subtest allocator-basic: SUCCESS (0.159s) [150.534723] [041/171] (906s left) gem_softpin (allocator-basic-reserve) [150.688580] Starting subtest: allocator-basic-reserve [150.856630] Subtest allocator-basic-reserve: SUCCESS (0.159s) [150.981123] [042/171] (906s left) gem_softpin (safe-alignment) [151.133940] Starting subtest: safe-alignment [151.151143] Subtest safe-alignment: SUCCESS (0.017s) [151.247225] [043/171] (905s left) gem_sync (basic-all) [151.394923] Starting subtest: basic-all [153.576534] Subtest basic-all: SUCCESS (2.158s) [153.764602] [044/171] (903s left) gem_sync (basic-each) [153.917908] Starting subtest: basic-each [156.284515] Subtest basic-each: SUCCESS (2.367s) [156.499113] [045/171] (900s left) gem_tiled_blits (basic) [156.774450] Starting subtest: basic [156.802428] Subtest basic: SUCCESS (0.028s) [156.932793] [046/171] (900s left) gem_tiled_fence_blits (basic) [157.284119] Starting subtest: basic [157.311266] Subtest basic: SUCCESS (0.027s) [157.447439] [047/171] (899s left) gem_tiled_pread_basic [157.982577] [048/171] (899s left) gem_wait (busy@all-engines) [158.138201] Starting subtest: busy [158.139450] Starting dynamic subtest: all-engines [158.673078] Dynamic subtest all-engines: SUCCESS (0.535s) [158.674268] Subtest busy: SUCCESS (0.535s) [158.916164] [049/171] (898s left) gem_wait (wait@all-engines) [159.077908] Starting subtest: wait [159.079196] Starting dynamic subtest: all-engines [160.106143] Dynamic subtest all-engines: SUCCESS (1.028s) [160.107860] Subtest wait: SUCCESS (1.028s) [160.366401] [050/171] (896s left) i915_getparams_basic (basic-eu-total) [160.503489] Starting subtest: basic-eu-total [160.505682] Subtest basic-eu-total: SUCCESS (0.000s) [160.581035] [051/171] (896s left) i915_getparams_basic (basic-subslice-total) [160.720656] Starting subtest: basic-subslice-total [160.722944] Subtest basic-subslice-total: SUCCESS (0.000s) [160.797722] [052/171] (896s left) i915_hangman (error-state-basic) [160.941876] Starting subtest: error-state-basic [160.954887] Subtest error-state-basic: SUCCESS (0.013s) [161.048083] [053/171] (896s left) i915_pciid [161.264732] [054/171] (896s left) kms_addfb_basic (addfb25-bad-modifier) [161.396125] Starting subtest: addfb25-bad-modifier [161.397273] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [161.530521] [055/171] (895s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [161.669956] Starting subtest: addfb25-framebuffer-vs-set-tiling [161.671129] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [161.798458] [056/171] (895s left) kms_addfb_basic (addfb25-modifier-no-flag) [161.937559] Starting subtest: addfb25-modifier-no-flag [161.938987] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [162.063940] [057/171] (895s left) kms_addfb_basic (addfb25-x-tiled-legacy) [162.201635] Starting subtest: addfb25-x-tiled-legacy [162.202821] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [162.331178] [058/171] (895s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [162.470907] Starting subtest: addfb25-x-tiled-mismatch-legacy [162.472137] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [162.597778] [059/171] (894s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [162.766192] Starting subtest: addfb25-yf-tiled-legacy [162.767370] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [162.864401] [060/171] (894s left) kms_addfb_basic (addfb25-y-tiled-legacy) [163.030768] Starting subtest: addfb25-y-tiled-legacy [163.032091] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [163.131058] [061/171] (894s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [163.300938] Starting subtest: addfb25-y-tiled-small-legacy [163.302169] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [163.397525] [062/171] (894s left) kms_addfb_basic (bad-pitch-0) [163.535926] Starting subtest: bad-pitch-0 [163.537099] Subtest bad-pitch-0: SUCCESS (0.000s) [163.664050] [063/171] (894s left) kms_addfb_basic (bad-pitch-1024) [163.802276] Starting subtest: bad-pitch-1024 [163.803348] Subtest bad-pitch-1024: SUCCESS (0.000s) [163.930978] [064/171] (893s left) kms_addfb_basic (bad-pitch-128) [164.069092] Starting subtest: bad-pitch-128 [164.070176] Subtest bad-pitch-128: SUCCESS (0.000s) [164.197726] [065/171] (893s left) kms_addfb_basic (bad-pitch-256) [164.335921] Starting subtest: bad-pitch-256 [164.337104] Subtest bad-pitch-256: SUCCESS (0.000s) [164.464850] [066/171] (893s left) kms_addfb_basic (bad-pitch-32) [164.603729] Starting subtest: bad-pitch-32 [164.604884] Subtest bad-pitch-32: SUCCESS (0.000s) [164.753511] [067/171] (893s left) kms_addfb_basic (bad-pitch-63) [164.892490] Starting subtest: bad-pitch-63 [164.893580] Subtest bad-pitch-63: SUCCESS (0.000s) [165.033981] [068/171] (892s left) kms_addfb_basic (bad-pitch-65536) [165.170793] Starting subtest: bad-pitch-65536 [165.172349] Subtest bad-pitch-65536: SUCCESS (0.000s) [165.297635] [069/171] (892s left) kms_addfb_basic (bad-pitch-999) [165.439485] Starting subtest: bad-pitch-999 [165.440654] Subtest bad-pitch-999: SUCCESS (0.000s) [165.564645] [070/171] (892s left) kms_addfb_basic (basic) [165.703368] Starting subtest: basic [165.704463] Subtest basic: SUCCESS (0.000s) [165.830560] [071/171] (892s left) kms_addfb_basic (basic-x-tiled-legacy) [165.970724] Starting subtest: basic-x-tiled-legacy [165.971889] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [166.097765] [072/171] (891s left) kms_addfb_basic (basic-y-tiled-legacy) [166.239150] Starting subtest: basic-y-tiled-legacy [166.240228] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [166.364623] [073/171] (891s left) kms_addfb_basic (bo-too-small) [166.532529] Starting subtest: bo-too-small [166.533660] Subtest bo-too-small: SUCCESS (0.000s) [166.631033] [074/171] (891s left) kms_addfb_basic (bo-too-small-due-to-tiling) [166.799345] Starting subtest: bo-too-small-due-to-tiling [166.800475] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [166.897460] [075/171] (891s left) kms_addfb_basic (clobberred-modifier) [167.037634] Starting subtest: clobberred-modifier [167.038794] Subtest clobberred-modifier: SUCCESS (0.000s) [167.164654] [076/171] (890s left) kms_addfb_basic (framebuffer-vs-set-tiling) [167.305193] Starting subtest: framebuffer-vs-set-tiling [167.306307] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [167.430874] [077/171] (890s left) kms_addfb_basic (invalid-get-prop) [167.571127] Starting subtest: invalid-get-prop [167.572191] Subtest invalid-get-prop: SUCCESS (0.000s) [167.697776] [078/171] (890s left) kms_addfb_basic (invalid-get-prop-any) [167.836828] Starting subtest: invalid-get-prop-any [167.838155] Subtest invalid-get-prop-any: SUCCESS (0.000s) [167.963833] [079/171] (890s left) kms_addfb_basic (invalid-set-prop) [168.102490] Starting subtest: invalid-set-prop [168.103553] Subtest invalid-set-prop: SUCCESS (0.000s) [168.231790] [080/171] (889s left) kms_addfb_basic (invalid-set-prop-any) [168.370785] Starting subtest: invalid-set-prop-any [168.372153] Subtest invalid-set-prop-any: SUCCESS (0.000s) [168.497319] [081/171] (889s left) kms_addfb_basic (no-handle) [168.635788] Starting subtest: no-handle [168.636971] Subtest no-handle: SUCCESS (0.000s) [168.764264] [082/171] (889s left) kms_addfb_basic (size-max) [168.932242] Starting subtest: size-max [168.933362] Subtest size-max: SUCCESS (0.000s) [169.032315] [083/171] (889s left) kms_addfb_basic (small-bo) [169.200711] Starting subtest: small-bo [169.201834] Subtest small-bo: SUCCESS (0.000s) [169.297420] [084/171] (888s left) kms_addfb_basic (tile-pitch-mismatch) [169.437802] Starting subtest: tile-pitch-mismatch [169.438959] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [169.564317] [085/171] (888s left) kms_addfb_basic (too-high) [169.732602] Starting subtest: too-high [169.733800] Subtest too-high: SUCCESS (0.001s) [169.830866] [086/171] (888s left) kms_addfb_basic (too-wide) [170.000159] Starting subtest: too-wide [170.001429] Subtest too-wide: SUCCESS (0.000s) [170.097450] [087/171] (888s left) kms_addfb_basic (unused-handle) [170.235898] Starting subtest: unused-handle [170.237351] Subtest unused-handle: SUCCESS (0.000s) [170.364876] [088/171] (888s left) kms_addfb_basic (unused-modifier) [170.503909] Starting subtest: unused-modifier [170.505189] Subtest unused-modifier: SUCCESS (0.000s) [170.631422] [089/171] (887s left) kms_addfb_basic (unused-offsets) [170.771175] Starting subtest: unused-offsets [170.772256] Subtest unused-offsets: SUCCESS (0.000s) [170.897408] [090/171] (887s left) kms_addfb_basic (unused-pitches) [171.035461] Starting subtest: unused-pitches [171.036811] Subtest unused-pitches: SUCCESS (0.000s) [171.164557] [091/171] (887s left) kms_busy (basic) [171.335570] Starting subtest: basic [171.338164] Starting dynamic subtest: flip [172.318111] Dynamic subtest flip: SUCCESS (0.978s) [172.333752] Starting dynamic subtest: modeset [176.179724] Dynamic subtest modeset: SUCCESS (3.864s) [176.180831] Subtest basic: SUCCESS (4.845s) [177.148609] [092/171] (881s left) kms_chamelium_hpd (dp-hpd-fast) [177.631898] Subtest dp-hpd-fast: SKIP (0.000s) [178.504834] [093/171] (879s left) kms_chamelium_edid (dp-edid-read) [178.988800] Subtest dp-edid-read: SKIP (0.000s) [179.851621] [094/171] (878s left) kms_chamelium_frames (dp-crc-fast) [180.330501] Subtest dp-crc-fast: SKIP (0.000s) [181.193809] [095/171] (877s left) kms_chamelium_hpd (hdmi-hpd-fast) [181.675297] Subtest hdmi-hpd-fast: SKIP (0.000s) [182.561108] [096/171] (876s left) kms_chamelium_edid (hdmi-edid-read) [183.046238] Subtest hdmi-edid-read: SKIP (0.000s) [183.950813] [097/171] (874s left) kms_chamelium_frames (hdmi-crc-fast) [184.429909] Subtest hdmi-crc-fast: SKIP (0.000s) [185.290342] [098/171] (873s left) kms_chamelium_hpd (vga-hpd-fast) [185.774991] Subtest vga-hpd-fast: SKIP (0.000s) [186.639084] [099/171] (872s left) kms_chamelium_edid (vga-edid-read) [187.123792] Subtest vga-edid-read: SKIP (0.000s) [188.038919] [100/171] (870s left) kms_prop_blob (basic) [188.169740] Starting subtest: basic [188.170986] Subtest basic: SUCCESS (0.000s) [188.260779] [101/171] (870s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [188.420170] Starting subtest: basic-busy-flip-before-cursor-atomic [188.422227] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [188.505168] [102/171] (870s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [188.671600] Starting subtest: basic-busy-flip-before-cursor-legacy [188.673735] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [188.754942] [103/171] (870s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [188.921100] Starting subtest: basic-flip-after-cursor-atomic [190.200630] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.280s) [191.167842] [104/171] (867s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [191.334745] Starting subtest: basic-flip-after-cursor-legacy [192.627827] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.292s) [193.595191] [105/171] (865s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [193.761901] Starting subtest: basic-flip-after-cursor-varying-size [195.060980] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.293s) [196.026608] [106/171] (862s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [196.194779] Starting subtest: basic-flip-before-cursor-atomic [197.487123] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.292s) [198.459217] [107/171] (860s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [198.624545] Starting subtest: basic-flip-before-cursor-legacy [199.918732] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.295s) [200.891723] [108/171] (858s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [201.058652] Starting subtest: basic-flip-before-cursor-varying-size [202.342852] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.284s) [203.259339] [109/171] (855s left) kms_flip (basic-flip-vs-dpms) [203.399136] Starting subtest: basic-flip-vs-dpms [203.402138] Starting dynamic subtest: A-eDP1 [206.263319] Dynamic subtest A-eDP1: SUCCESS (2.861s) [206.279528] Starting dynamic subtest: B-eDP1 [208.617392] Dynamic subtest B-eDP1: SUCCESS (2.354s) [208.636932] Starting dynamic subtest: C-eDP1 [211.003153] Dynamic subtest C-eDP1: SUCCESS (2.384s) [211.004890] Subtest basic-flip-vs-dpms: SUCCESS (7.607s) [211.965948] [110/171] (847s left) kms_flip (basic-flip-vs-modeset) [212.112607] Starting subtest: basic-flip-vs-modeset [212.116096] Starting dynamic subtest: A-eDP1 [214.960636] Dynamic subtest A-eDP1: SUCCESS (2.845s) [214.977016] Starting dynamic subtest: B-eDP1 [217.312467] Dynamic subtest B-eDP1: SUCCESS (2.350s) [217.331948] Starting dynamic subtest: C-eDP1 [219.697892] Dynamic subtest C-eDP1: SUCCESS (2.385s) [219.704265] Subtest basic-flip-vs-modeset: SUCCESS (7.588s) [220.666242] [111/171] (838s left) kms_flip (basic-flip-vs-wf_vblank) [220.811157] Starting subtest: basic-flip-vs-wf_vblank [220.816799] Starting dynamic subtest: A-eDP1 [223.129581] Dynamic subtest A-eDP1: SUCCESS (2.314s) [223.145405] Starting dynamic subtest: B-eDP1 [225.355326] Dynamic subtest B-eDP1: SUCCESS (2.225s) [225.371629] Starting dynamic subtest: C-eDP1 [227.595155] Dynamic subtest C-eDP1: SUCCESS (2.239s) [227.597742] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.787s) [228.860656] [112/171] (830s left) kms_flip (basic-plain-flip) [229.004862] Starting subtest: basic-plain-flip [229.008633] Starting dynamic subtest: A-eDP1 [231.040998] Dynamic subtest A-eDP1: SUCCESS (2.032s) [231.057096] Starting dynamic subtest: B-eDP1 [233.005279] Dynamic subtest B-eDP1: SUCCESS (1.963s) [233.021581] Starting dynamic subtest: C-eDP1 [234.994500] Dynamic subtest C-eDP1: SUCCESS (1.988s) [234.997203] Subtest basic-plain-flip: SUCCESS (5.993s) [236.285209] [113/171] (822s left) kms_force_connector_basic (force-connector-state) [236.421465] Starting subtest: force-connector-state [238.126193] Subtest force-connector-state: SUCCESS (1.704s) [238.743597] [114/171] (820s left) kms_force_connector_basic (force-edid) [238.886148] Starting subtest: force-edid [239.144978] Subtest force-edid: SUCCESS (0.255s) [239.276938] [115/171] (819s left) kms_force_connector_basic (force-load-detect) [239.419419] Starting subtest: force-load-detect [239.421624] Subtest force-load-detect: SKIP (0.000s) [239.526947] [116/171] (819s left) kms_force_connector_basic (prune-stale-modes) [239.669049] Starting subtest: prune-stale-modes [239.980392] Subtest prune-stale-modes: SUCCESS (0.305s) [240.110072] [117/171] (819s left) kms_frontbuffer_tracking (basic) [240.276407] Starting subtest: basic [243.592262] Subtest basic: SUCCESS (3.316s) [243.719354] [118/171] (815s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [243.881695] Starting subtest: compare-crc-sanitycheck-xr24 [243.883239] Starting dynamic subtest: pipe-A-eDP-1 [244.557868] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.675s) [244.579245] Starting dynamic subtest: pipe-B-eDP-1 [245.950505] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.387s) [245.969237] Starting dynamic subtest: pipe-C-eDP-1 [247.502118] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.546s) [247.503202] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.620s) [248.438423] [119/171] (810s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [248.610353] Starting subtest: compare-crc-sanitycheck-nv12 [248.613340] Starting dynamic subtest: pipe-A-eDP-1 [249.529907] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.916s) [249.548250] Starting dynamic subtest: pipe-B-eDP-1 [250.997668] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.463s) [251.016648] Starting dynamic subtest: pipe-C-eDP-1 [252.761086] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.759s) [252.762230] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.151s) [253.689450] [120/171] (805s left) kms_pipe_crc_basic (hang-read-crc) [253.857792] Starting subtest: hang-read-crc [253.860797] Starting dynamic subtest: pipe-A-eDP-1 [256.180154] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.318s) [256.197395] Starting dynamic subtest: pipe-B-eDP-1 [259.159308] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.975s) [259.177438] Starting dynamic subtest: pipe-C-eDP-1 [262.440388] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.277s) [262.441564] Subtest hang-read-crc: SUCCESS (8.582s) [263.357855] [121/171] (795s left) kms_pipe_crc_basic (nonblocking-crc) [263.528804] Starting subtest: nonblocking-crc [263.531955] Starting dynamic subtest: pipe-A-eDP-1 [264.384511] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.852s) [264.402106] Starting dynamic subtest: pipe-B-eDP-1 [265.938504] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.550s) [265.956754] Starting dynamic subtest: pipe-C-eDP-1 [267.644430] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.700s) [267.645826] Subtest nonblocking-crc: SUCCESS (4.114s) [268.598963] [122/171] (790s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [268.780213] Starting subtest: nonblocking-crc-frame-sequence [268.783459] Starting dynamic subtest: pipe-A-eDP-1 [269.640150] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.856s) [269.658267] Starting dynamic subtest: pipe-B-eDP-1 [271.192827] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.549s) [271.211914] Starting dynamic subtest: pipe-C-eDP-1 [272.902810] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.705s) [272.904177] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.123s) [273.848123] [123/171] (785s left) kms_pipe_crc_basic (read-crc) [274.013314] Starting subtest: read-crc [274.016508] Starting dynamic subtest: pipe-A-eDP-1 [274.806080] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.788s) [274.824103] Starting dynamic subtest: pipe-B-eDP-1 [276.294408] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.483s) [276.314177] Starting dynamic subtest: pipe-C-eDP-1 [277.931788] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.633s) [277.932942] Subtest read-crc: SUCCESS (3.918s) [278.902623] [124/171] (780s left) kms_pipe_crc_basic (read-crc-frame-sequence) [279.071199] Starting subtest: read-crc-frame-sequence [279.074618] Starting dynamic subtest: pipe-A-eDP-1 [279.862108] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.788s) [279.882823] Starting dynamic subtest: pipe-B-eDP-1 [281.350458] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.482s) [281.368044] Starting dynamic subtest: pipe-C-eDP-1 [282.990666] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.636s) [282.991844] Subtest read-crc-frame-sequence: SUCCESS (3.919s) [283.958887] [125/171] (775s left) kms_psr (primary_page_flip) [284.132240] Starting subtest: primary_page_flip [288.400434] Subtest primary_page_flip: SUCCESS (4.251s) [289.333936] [126/171] (770s left) kms_psr (cursor_plane_move) [289.505652] Starting subtest: cursor_plane_move [293.703571] Subtest cursor_plane_move: SUCCESS (4.192s) [294.645956] [127/171] (764s left) kms_psr (sprite_plane_onoff) [294.811829] Starting subtest: sprite_plane_onoff [299.033990] Subtest sprite_plane_onoff: SUCCESS (4.221s) [299.958875] [128/171] (759s left) kms_psr (primary_mmap_gtt) [300.130118] Starting subtest: primary_mmap_gtt [304.362312] Subtest primary_mmap_gtt: SUCCESS (4.231s) [305.271095] [129/171] (754s left) kms_setmode (basic-clone-single-crtc) [305.412878] Starting subtest: basic-clone-single-crtc [305.416337] Subtest basic-clone-single-crtc: SKIP (0.000s) [305.487290] [130/171] (754s left) i915_pm_backlight (basic-brightness) [305.646021] Starting subtest: basic-brightness [305.761787] Starting dynamic subtest: eDP-1 [306.313078] Dynamic subtest eDP-1: SUCCESS (0.560s) [306.314258] Subtest basic-brightness: SUCCESS (0.667s) [307.256086] [131/171] (752s left) i915_pm_rpm (basic-pci-d3-state) [309.144034] Starting subtest: basic-pci-d3-state [313.555054] Subtest basic-pci-d3-state: SUCCESS (4.410s) [313.757179] [132/171] (745s left) i915_pm_rpm (basic-rte) [313.890232] Starting subtest: basic-rte [320.022681] Subtest basic-rte: SUCCESS (6.127s) [325.326466] [133/171] (734s left) i915_pm_rps (basic-api) [325.473772] Starting subtest: basic-api [325.477972] Subtest basic-api: SUCCESS (0.004s) [325.562336] [134/171] (734s left) prime_self_import (basic-llseek-bad) [325.689923] Starting subtest: basic-llseek-bad [325.694478] Subtest basic-llseek-bad: SUCCESS (0.003s) [325.795394] [135/171] (733s left) prime_self_import (basic-llseek-size) [325.931120] Starting subtest: basic-llseek-size [325.935664] Subtest basic-llseek-size: SUCCESS (0.004s) [326.046377] [136/171] (733s left) prime_self_import (basic-with_fd_dup) [326.181374] Starting subtest: basic-with_fd_dup [326.186173] Subtest basic-with_fd_dup: SUCCESS (0.005s) [326.296516] [137/171] (733s left) prime_self_import (basic-with_one_bo) [326.432280] Starting subtest: basic-with_one_bo [326.437491] Subtest basic-with_one_bo: SUCCESS (0.005s) [326.523946] [138/171] (733s left) prime_self_import (basic-with_one_bo_two_files) [326.658246] Starting subtest: basic-with_one_bo_two_files [326.662970] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s) [326.764320] [139/171] (733s left) prime_self_import (basic-with_two_bos) [326.899721] Starting subtest: basic-with_two_bos [326.904747] Subtest basic-with_two_bos: SUCCESS (0.005s) [327.012575] [140/171] (732s left) prime_vgem (basic-fence-flip) [327.167644] Starting subtest: basic-fence-flip [327.688221] Subtest basic-fence-flip: SUCCESS (0.519s) [327.811382] [141/171] (732s left) prime_vgem (basic-fence-mmap) [327.962083] Starting subtest: basic-fence-mmap [328.010209] Subtest basic-fence-mmap: SUCCESS (0.048s) [328.129873] [142/171] (731s left) prime_vgem (basic-fence-read) [328.279369] Starting subtest: basic-fence-read [328.352413] Subtest basic-fence-read: SUCCESS (0.073s) [328.448034] [143/171] (731s left) prime_vgem (basic-gtt) [328.595336] Starting subtest: basic-gtt [328.618561] Subtest basic-gtt: SUCCESS (0.023s) [328.729647] [144/171] (731s left) prime_vgem (basic-read) [328.878735] Starting subtest: basic-read [328.914480] Subtest basic-read: SUCCESS (0.035s) [329.011788] [145/171] (731s left) prime_vgem (basic-write) [329.162211] Starting subtest: basic-write [329.203581] Subtest basic-write: SUCCESS (0.041s) [329.294980] [146/171] (730s left) vgem_basic (setversion) [329.437835] Starting subtest: setversion [329.440172] Subtest setversion: SUCCESS (0.000s) [329.510959] [147/171] (730s left) vgem_basic (create) [329.655917] Starting subtest: create [329.657484] Subtest create: SUCCESS (0.000s) [329.728773] [148/171] (730s left) vgem_basic (debugfs) [329.871250] Starting subtest: debugfs [329.872427] Subtest debugfs: SUCCESS (0.001s) [329.945243] [149/171] (730s left) vgem_basic (dmabuf-export) [330.087926] Starting subtest: dmabuf-export [330.092623] Subtest dmabuf-export: SUCCESS (0.003s) [330.196171] [150/171] (729s left) vgem_basic (dmabuf-fence) [330.338144] Starting subtest: dmabuf-fence [330.339424] Subtest dmabuf-fence: SUCCESS (0.001s) [330.410565] [151/171] (729s left) vgem_basic (dmabuf-fence-before) [330.557374] Starting subtest: dmabuf-fence-before [330.558486] Subtest dmabuf-fence-before: SUCCESS (0.000s) [330.627803] [152/171] (729s left) vgem_basic (dmabuf-mmap) [330.773550] Starting subtest: dmabuf-mmap [330.798681] Subtest dmabuf-mmap: SUCCESS (0.026s) [330.875613] [153/171] (729s left) vgem_basic (mmap) [331.023413] Starting subtest: mmap [331.038785] Subtest mmap: SUCCESS (0.016s) [331.108724] [154/171] (729s left) vgem_basic (second-client) [331.255856] Starting subtest: second-client [331.290420] Subtest second-client: SUCCESS (0.033s) [331.359904] [155/171] (728s left) vgem_basic (sysfs) [331.506322] Starting subtest: sysfs [331.507470] Subtest sysfs: SUCCESS (0.001s) [331.575644] [156/171] (728s left) core_hotunplug (unbind-rebind) [331.756341] Starting subtest: unbind-rebind [334.182360] Subtest unbind-rebind: SUCCESS (2.424s) [334.216811] [157/171] (726s left) vgem_basic (unload) [334.356687] Starting subtest: unload [363.372381] Subtest unload: SUCCESS (28.980s) [363.488598] [158/171] (696s left) i915_module_load (reload) [363.629033] Starting subtest: reload [367.099630] Subtest reload: SUCCESS (3.469s) [367.128536] [159/171] (693s left) gem_lmem_swapping (basic) [367.287932] Subtest basic: SKIP (0.000s) [367.332867] [160/171] (693s left) gem_lmem_swapping (parallel-random-engines) [367.477711] Subtest parallel-random-engines: SKIP (0.000s) [367.536411] [161/171] (692s left) gem_lmem_swapping (random-engines) [367.681661] Subtest random-engines: SKIP (0.000s) [367.732529] [162/171] (692s left) gem_lmem_swapping (verify-random) [367.879360] Subtest verify-random: SKIP (0.000s) [367.933044] [163/171] (692s left) i915_pm_rpm (module-reload) [380.436431] Starting subtest: module-reload [415.287799] Subtest module-reload: SUCCESS (34.844s) [415.312110] [164/171] (645s left) i915_selftest (live) [415.455416] Starting subtest: live [415.456882] Starting dynamic subtest: sanitycheck [417.302912] Dynamic subtest sanitycheck: SUCCESS (1.837s) [417.318197] Starting dynamic subtest: uncore [419.176490] Dynamic subtest uncore: SUCCESS (1.872s) [419.190466] Starting dynamic subtest: workarounds [421.232290] Dynamic subtest workarounds: SUCCESS (2.054s) [421.246456] Starting dynamic subtest: gt_engines [423.519442] Dynamic subtest gt_engines: SUCCESS (2.285s) [423.533475] Starting dynamic subtest: gt_timelines [436.119794] Dynamic subtest gt_timelines: SUCCESS (12.598s) [436.133874] Starting dynamic subtest: gt_contexts [438.028832] Dynamic subtest gt_contexts: SUCCESS (1.907s) [438.045424] Starting dynamic subtest: gt_lrc [442.415198] Dynamic subtest gt_lrc: SUCCESS (4.385s) [442.429414] Starting dynamic subtest: gt_mocs [444.311252] Dynamic subtest gt_mocs: SUCCESS (1.894s) [444.325502] Starting dynamic subtest: gt_pm [449.409929] Dynamic subtest gt_pm: SUCCESS (5.096s) [449.424088] Starting dynamic subtest: gt_heartbeat [451.291850] Dynamic subtest gt_heartbeat: SUCCESS (1.880s) [451.306270] Starting dynamic subtest: gt_tlb [454.373682] Dynamic subtest gt_tlb: SUCCESS (3.080s) [454.388138] Starting dynamic subtest: requests [462.021481] Dynamic subtest requests: SUCCESS (7.646s) [462.035771] Starting dynamic subtest: migrate [466.569305] Dynamic subtest migrate: SUCCESS (4.546s) [466.583509] Starting dynamic subtest: active [468.397589] Dynamic subtest active: SUCCESS (1.826s) [468.411804] Starting dynamic subtest: objects [471.932328] Dynamic subtest objects: SUCCESS (3.533s) [471.946546] Starting dynamic subtest: mman [478.893544] Dynamic subtest mman: SUCCESS (6.959s) [478.908070] Starting dynamic subtest: dmabuf [480.727110] Dynamic subtest dmabuf: SUCCESS (1.831s) [480.744882] Starting dynamic subtest: vma [482.576511] Dynamic subtest vma: SUCCESS (1.848s) [482.590946] Starting dynamic subtest: coherency [487.007466] Dynamic subtest coherency: SUCCESS (4.429s) [487.021877] Starting dynamic subtest: gtt [496.365409] Dynamic subtest gtt: SUCCESS (9.356s) [496.379761] Starting dynamic subtest: gem [498.261426] Dynamic subtest gem: SUCCESS (1.894s) [498.275963] Starting dynamic subtest: evict [502.117549] Dynamic subtest evict: SUCCESS (3.854s) [502.132068] Starting dynamic subtest: hugepages [508.979514] Dynamic subtest hugepages: SUCCESS (6.860s) [508.994378] Starting dynamic subtest: gem_contexts [524.240894] Dynamic subtest gem_contexts: SUCCESS (15.259s) [524.256125] Starting dynamic subtest: client [526.123345] Dynamic subtest client: SUCCESS (1.881s) [526.138228] Starting dynamic subtest: gem_migrate [527.959663] Dynamic subtest gem_migrate: SUCCESS (1.834s) [527.974847] Starting dynamic subtest: reset [533.359474] Dynamic subtest reset: SUCCESS (5.398s) [533.374535] Starting dynamic subtest: memory_region [535.190417] Dynamic subtest memory_region: SUCCESS (1.829s) [535.205355] Starting dynamic subtest: hangcheck [559.556483] Dynamic subtest hangcheck: SUCCESS (24.364s) [559.571520] Starting dynamic subtest: execlists [569.584118] Dynamic subtest execlists: SUCCESS (10.026s) [569.599043] Starting dynamic subtest: ring_submission [571.417524] Dynamic subtest ring_submission: SUCCESS (1.832s) [571.432586] Starting dynamic subtest: perf [573.341830] Dynamic subtest perf: SUCCESS (1.922s) [573.357044] Starting dynamic subtest: slpc [575.128261] Dynamic subtest slpc: SUCCESS (1.785s) [575.143415] Starting dynamic subtest: guc [576.995423] Dynamic subtest guc: SUCCESS (1.866s) [577.010445] Starting dynamic subtest: guc_multi_lrc [578.789499] Dynamic subtest guc_multi_lrc: SUCCESS (1.792s) [578.804495] Starting dynamic subtest: guc_hang [580.631449] Dynamic subtest guc_hang: SUCCESS (1.840s) [580.646670] Starting dynamic subtest: late_gt_pm [582.422496] Dynamic subtest late_gt_pm: SUCCESS (1.789s) [582.423837] Subtest live: SUCCESS (166.958s) [582.621846] [165/171] (477s left) dmabuf (all-tests) [582.764423] Starting subtest: all-tests [582.765604] Starting dynamic subtest: sanitycheck [582.827154] Dynamic subtest sanitycheck: SUCCESS (0.061s) [582.840923] Starting dynamic subtest: dma_fence [583.026066] Dynamic subtest dma_fence: SUCCESS (0.197s) [583.046546] Starting dynamic subtest: dma_fence_chain [600.758569] Dynamic subtest dma_fence_chain: SUCCESS (17.722s) [600.773210] Starting dynamic subtest: dma_fence_unwrap [600.829623] Dynamic subtest dma_fence_unwrap: SUCCESS (0.078s) [600.846168] Starting dynamic subtest: dma_resv [600.916759] Dynamic subtest dma_resv: SUCCESS (0.085s) [600.919069] Subtest all-tests: SUCCESS (18.152s) [600.950310] [166/171] (459s left) i915_suspend (basic-s2idle-without-i915) [601.078188] Starting subtest: basic-s2idle-without-i915 [624.888780] Subtest basic-s2idle-without-i915: SUCCESS (8.459s) [625.175410] [167/171] (450s left) i915_suspend (basic-s3-without-i915) [625.317649] Starting subtest: basic-s3-without-i915 [648.942045] Subtest basic-s3-without-i915: SUCCESS (7.105s) [649.248922] [168/171] (443s left) gem_exec_suspend (basic-s0) [649.405007] Starting subtest: basic-S0 [649.406350] Starting dynamic subtest: smem [669.038739] Dynamic subtest smem: SUCCESS (3.800s) [669.039917] Subtest basic-S0: SUCCESS (3.802s) [669.212771] [169/171] (439s left) gem_exec_suspend (basic-s3) [669.359833] Starting subtest: basic-S3 [669.361029] Starting dynamic subtest: smem [687.998384] Dynamic subtest smem: SUCCESS (3.687s) [687.999612] Subtest basic-S3: SUCCESS (3.688s) [688.156114] [170/171] (435s left) kms_chamelium_hpd (common-hpd-after-suspend) [688.630186] Subtest common-hpd-after-suspend: SKIP (0.000s) [689.545575] [171/171] (433s left) kms_pipe_crc_basic (suspend-read-crc) [689.707040] Starting subtest: suspend-read-crc [689.709608] Starting dynamic subtest: pipe-A-eDP-1 [707.973536] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.448s) [707.996548] Starting dynamic subtest: pipe-B-eDP-1 [725.944438] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.136s) [725.966585] Starting dynamic subtest: pipe-C-eDP-1 [744.917639] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.250s) [744.918849] Subtest suspend-read-crc: SUCCESS (8.849s) [745.825671] Closing watchdogs Done.