[45.429295] Initializing watchdogs [45.429471] /dev/watchdog0 [45.440112] [001/171] (960s left) i915_module_load (load) [45.591707] Starting subtest: load [48.143843] Subtest load: SUCCESS (2.552s) [48.173459] [002/171] (957s left) core_auth (basic-auth) [48.319821] Starting subtest: basic-auth [48.321076] Subtest basic-auth: SUCCESS (0.002s) [48.393158] [003/171] (957s left) debugfs_test (read_all_entries) [48.545399] Starting subtest: read_all_entries [48.611663] Subtest read_all_entries: SUCCESS (0.066s) [48.725716] [004/171] (957s left) debugfs_test (basic-hwmon) [48.870092] Starting subtest: basic-hwmon [48.872162] Subtest basic-hwmon: SKIP (0.000s) [48.979075] [005/171] (957s left) fbdev (eof) [49.182277] Starting subtest: eof [49.186994] Subtest eof: SUCCESS (0.001s) [49.281155] [006/171] (956s left) fbdev (info) [49.429288] Starting subtest: info [49.430441] Subtest info: SUCCESS (0.000s) [49.510193] [007/171] (956s left) fbdev (nullptr) [49.660781] Starting subtest: nullptr [49.661986] Subtest nullptr: SUCCESS (0.000s) [49.727187] [008/171] (956s left) fbdev (read) [49.877364] Starting subtest: read [56.965937] Subtest read: SUCCESS (7.054s) [57.010905] [009/171] (949s left) fbdev (write) [57.145521] Starting subtest: write [60.890709] Subtest write: SUCCESS (3.736s) [60.960233] [010/171] (945s left) gem_basic (bad-close) [61.095473] Starting subtest: bad-close [61.097649] Subtest bad-close: SUCCESS (0.000s) [61.176961] [011/171] (945s left) gem_basic (create-close) [61.314636] Starting subtest: create-close [61.316872] Subtest create-close: SUCCESS (0.000s) [61.409639] [012/171] (944s left) gem_basic (create-fd-close) [61.547328] Starting subtest: create-fd-close [61.549344] Subtest create-fd-close: SUCCESS (0.000s) [61.642984] [013/171] (944s left) gem_busy (busy@all-engines) [61.799522] Starting subtest: busy [61.800767] Starting dynamic subtest: all-engines [61.861458] Dynamic subtest all-engines: SUCCESS (0.062s) [61.862497] Subtest busy: SUCCESS (0.063s) [62.260056] [014/171] (944s left) gem_close_race (basic-process) [62.420552] Starting subtest: basic-process [62.490586] Subtest basic-process: SUCCESS (0.069s) [62.693870] [015/171] (943s left) gem_close_race (basic-threads) [62.852754] Starting subtest: basic-threads [64.022936] Subtest basic-threads: SUCCESS (1.171s) [64.231192] [016/171] (942s left) gem_ctx_create (basic) [64.390930] Starting subtest: basic [64.392112] Subtest basic: SUCCESS (0.000s) [64.594590] [017/171] (941s left) gem_ctx_create (basic-files) [64.746074] Starting subtest: basic-files [66.769751] Subtest basic-files: SUCCESS (2.024s) [66.990440] [018/171] (939s left) gem_ctx_exec (basic) [67.137483] Starting subtest: basic [67.143118] Subtest basic: SUCCESS (0.006s) [67.277193] [019/171] (939s left) gem_exec_basic (basic) [67.426736] Starting subtest: basic [67.427952] Starting dynamic subtest: rcs0-smem [67.429141] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [67.441378] Starting dynamic subtest: bcs0-smem [67.442533] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [67.453091] Starting dynamic subtest: vcs0-smem [67.454433] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [67.464907] Starting dynamic subtest: vecs0-smem [67.466247] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [67.467392] Subtest basic: SUCCESS (0.005s) [67.628132] [020/171] (938s left) gem_exec_create (basic) [67.772425] Starting subtest: basic [67.773587] Starting dynamic subtest: smem [69.836649] Dynamic subtest smem: SUCCESS (2.041s) [69.838057] Subtest basic: SUCCESS (2.042s) [70.038827] [021/171] (936s left) gem_exec_fence (basic-busy) [70.350884] Starting subtest: basic-busy [70.352451] Starting dynamic subtest: rcs0 [70.372703] Dynamic subtest rcs0: SUCCESS (0.027s) [70.385539] Starting dynamic subtest: bcs0 [70.391457] Dynamic subtest bcs0: SUCCESS (0.019s) [70.402648] Starting dynamic subtest: vcs0 [70.411231] Dynamic subtest vcs0: SUCCESS (0.020s) [70.422563] Starting dynamic subtest: vecs0 [70.440525] Dynamic subtest vecs0: SUCCESS (0.029s) [70.441740] Subtest basic-busy: SUCCESS (0.095s) [71.045316] [022/171] (935s left) gem_exec_fence (basic-wait) [71.345100] Starting subtest: basic-wait [71.346474] Starting dynamic subtest: rcs0 [71.375629] Dynamic subtest rcs0: SUCCESS (0.038s) [71.391357] Starting dynamic subtest: bcs0 [71.399409] Dynamic subtest bcs0: SUCCESS (0.024s) [71.411136] Starting dynamic subtest: vcs0 [71.430110] Dynamic subtest vcs0: SUCCESS (0.030s) [71.441672] Starting dynamic subtest: vecs0 [71.457071] Dynamic subtest vecs0: SUCCESS (0.027s) [71.458368] Subtest basic-wait: SUCCESS (0.121s) [71.995165] [023/171] (934s left) gem_exec_fence (basic-await) [72.298500] Starting subtest: basic-await [72.299662] Starting dynamic subtest: rcs0 [72.389819] Dynamic subtest rcs0: SUCCESS (0.099s) [72.417561] Starting dynamic subtest: bcs0 [72.515390] Dynamic subtest bcs0: SUCCESS (0.122s) [72.542488] Starting dynamic subtest: vcs0 [72.651966] Dynamic subtest vcs0: SUCCESS (0.129s) [72.678530] Starting dynamic subtest: vecs0 [72.778486] Dynamic subtest vecs0: SUCCESS (0.133s) [72.780478] Subtest basic-await: SUCCESS (0.486s) [73.378957] [024/171] (933s left) gem_exec_fence (nb-await) [73.680607] Starting subtest: nb-await [73.681796] Starting dynamic subtest: rcs0 [73.737815] Dynamic subtest rcs0: SUCCESS (0.064s) [73.757983] Starting dynamic subtest: bcs0 [73.809918] Dynamic subtest bcs0: SUCCESS (0.071s) [73.837443] Starting dynamic subtest: vcs0 [73.884249] Dynamic subtest vcs0: SUCCESS (0.075s) [73.911498] Starting dynamic subtest: vecs0 [73.960852] Dynamic subtest vecs0: SUCCESS (0.076s) [73.962877] Subtest nb-await: SUCCESS (0.288s) [74.529665] [025/171] (932s left) gem_exec_gttfill (basic) [74.684038] Starting subtest: basic [80.669397] Subtest basic: SUCCESS (5.984s) [80.865101] [026/171] (925s left) gem_exec_parallel (engines) [81.083950] Starting subtest: engines [81.085365] Starting dynamic subtest: basic [83.611633] Dynamic subtest basic: SUCCESS (2.528s) [83.625126] Starting dynamic subtest: contexts [87.004082] Dynamic subtest contexts: SUCCESS (3.383s) [87.017609] Starting dynamic subtest: fds [91.011368] Dynamic subtest fds: SUCCESS (4.016s) [91.027654] Starting dynamic subtest: userptr [93.660537] Dynamic subtest userptr: SUCCESS (2.619s) [93.662563] Subtest engines: SUCCESS (12.547s) [93.844075] [027/171] (912s left) gem_exec_store (basic) [93.999029] Starting subtest: basic [94.052655] Subtest basic: SUCCESS (0.054s) [94.264309] [028/171] (912s left) gem_flink_basic (bad-flink) [94.399922] Starting subtest: bad-flink [94.401231] Subtest bad-flink: SUCCESS (0.000s) [94.480235] [029/171] (912s left) gem_flink_basic (bad-open) [94.616856] Starting subtest: bad-open [94.618166] Subtest bad-open: SUCCESS (0.000s) [94.698302] [030/171] (911s left) gem_flink_basic (basic) [94.835704] Starting subtest: basic [94.836851] Subtest basic: SUCCESS (0.000s) [94.946681] [031/171] (911s left) gem_flink_basic (double-flink) [95.077509] Starting subtest: double-flink [95.078681] Subtest double-flink: SUCCESS (0.000s) [95.180495] [032/171] (911s left) gem_flink_basic (flink-lifetime) [95.319780] Starting subtest: flink-lifetime [95.321134] Subtest flink-lifetime: SUCCESS (0.002s) [95.430320] [033/171] (911s left) gem_huc_copy (huc-copy) [95.572998] Starting subtest: huc-copy [95.575594] Subtest huc-copy: SKIP (0.000s) [95.697440] [034/171] (911s left) gem_linear_blits (basic) [95.990984] Starting subtest: basic [96.003288] Subtest basic: SUCCESS (0.012s) [96.130218] [035/171] (910s left) gem_mmap (basic) [96.327524] Starting subtest: basic [96.332411] Subtest basic: SUCCESS (0.001s) [96.430772] [036/171] (910s left) gem_mmap_gtt (basic) [96.569361] Starting subtest: basic [96.570570] Subtest basic: SUCCESS (0.001s) [96.646532] [037/171] (910s left) gem_render_linear_blits (basic) [96.787924] Starting subtest: basic [96.837183] Subtest basic: SUCCESS (0.049s) [96.897465] [038/171] (910s left) gem_render_tiled_blits (basic) [97.033982] Starting subtest: basic [97.092812] Subtest basic: SUCCESS (0.059s) [97.166258] [039/171] (909s left) gem_ringfill (basic-all) [97.447795] Starting subtest: basic-all [98.678804] Subtest basic-all: SUCCESS (1.223s) [98.748703] [040/171] (908s left) gem_softpin (allocator-basic) [98.892684] Starting subtest: allocator-basic [99.061960] Subtest allocator-basic: SUCCESS (0.160s) [99.181872] [041/171] (907s left) gem_softpin (allocator-basic-reserve) [99.332388] Starting subtest: allocator-basic-reserve [99.503263] Subtest allocator-basic-reserve: SUCCESS (0.163s) [99.625393] [042/171] (907s left) gem_softpin (safe-alignment) [99.782319] Starting subtest: safe-alignment [99.801275] Subtest safe-alignment: SUCCESS (0.019s) [99.913612] [043/171] (907s left) gem_sync (basic-all) [100.069971] Starting subtest: basic-all [102.328838] Subtest basic-all: SUCCESS (2.234s) [102.515354] [044/171] (904s left) gem_sync (basic-each) [102.666469] Starting subtest: basic-each [104.846375] Subtest basic-each: SUCCESS (2.180s) [105.074951] [045/171] (902s left) gem_tiled_blits (basic) [105.367260] Starting subtest: basic [105.396216] Subtest basic: SUCCESS (0.029s) [105.532135] [046/171] (901s left) gem_tiled_fence_blits (basic) [105.887946] Starting subtest: basic [105.915362] Subtest basic: SUCCESS (0.028s) [106.044178] [047/171] (901s left) gem_tiled_pread_basic [106.581805] [048/171] (900s left) gem_wait (busy@all-engines) [106.748075] Starting subtest: busy [106.749372] Starting dynamic subtest: all-engines [107.274476] Dynamic subtest all-engines: SUCCESS (0.527s) [107.275712] Subtest busy: SUCCESS (0.527s) [107.515432] [049/171] (899s left) gem_wait (wait@all-engines) [107.678664] Starting subtest: wait [107.679860] Starting dynamic subtest: all-engines [108.724535] Dynamic subtest all-engines: SUCCESS (1.041s) [108.726094] Subtest wait: SUCCESS (1.041s) [108.965590] [050/171] (898s left) i915_getparams_basic (basic-eu-total) [109.095629] Starting subtest: basic-eu-total [109.098078] Subtest basic-eu-total: SUCCESS (0.000s) [109.180376] [051/171] (898s left) i915_getparams_basic (basic-subslice-total) [109.318934] Starting subtest: basic-subslice-total [109.321545] Subtest basic-subslice-total: SUCCESS (0.000s) [109.397235] [052/171] (897s left) i915_hangman (error-state-basic) [109.550491] Starting subtest: error-state-basic [109.562920] Subtest error-state-basic: SUCCESS (0.013s) [109.647337] [053/171] (897s left) i915_pciid [109.864260] [054/171] (897s left) kms_addfb_basic (addfb25-bad-modifier) [110.003888] Starting subtest: addfb25-bad-modifier [110.005389] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [110.130121] [055/171] (897s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [110.270068] Starting subtest: addfb25-framebuffer-vs-set-tiling [110.271147] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [110.397405] [056/171] (897s left) kms_addfb_basic (addfb25-modifier-no-flag) [110.535919] Starting subtest: addfb25-modifier-no-flag [110.537086] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [110.663602] [057/171] (896s left) kms_addfb_basic (addfb25-x-tiled-legacy) [110.803038] Starting subtest: addfb25-x-tiled-legacy [110.804256] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [110.970021] [058/171] (896s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [111.110168] Starting subtest: addfb25-x-tiled-mismatch-legacy [111.111253] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [111.247279] [059/171] (896s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [111.415690] Starting subtest: addfb25-yf-tiled-legacy [111.416839] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [111.513307] [060/171] (896s left) kms_addfb_basic (addfb25-y-tiled-legacy) [111.681316] Starting subtest: addfb25-y-tiled-legacy [111.682488] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [111.780659] [061/171] (895s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [111.948383] Starting subtest: addfb25-y-tiled-small-legacy [111.949530] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [112.046577] [062/171] (895s left) kms_addfb_basic (bad-pitch-0) [112.186195] Starting subtest: bad-pitch-0 [112.187375] Subtest bad-pitch-0: SUCCESS (0.000s) [112.314537] [063/171] (895s left) kms_addfb_basic (bad-pitch-1024) [112.446489] Starting subtest: bad-pitch-1024 [112.447647] Subtest bad-pitch-1024: SUCCESS (0.000s) [112.580288] [064/171] (895s left) kms_addfb_basic (bad-pitch-128) [112.720127] Starting subtest: bad-pitch-128 [112.721349] Subtest bad-pitch-128: SUCCESS (0.000s) [112.846627] [065/171] (894s left) kms_addfb_basic (bad-pitch-256) [112.985507] Starting subtest: bad-pitch-256 [112.986681] Subtest bad-pitch-256: SUCCESS (0.000s) [113.114062] [066/171] (894s left) kms_addfb_basic (bad-pitch-32) [113.252890] Starting subtest: bad-pitch-32 [113.254064] Subtest bad-pitch-32: SUCCESS (0.000s) [113.380371] [067/171] (894s left) kms_addfb_basic (bad-pitch-63) [113.519073] Starting subtest: bad-pitch-63 [113.520149] Subtest bad-pitch-63: SUCCESS (0.000s) [113.647195] [068/171] (894s left) kms_addfb_basic (bad-pitch-65536) [113.787382] Starting subtest: bad-pitch-65536 [113.788554] Subtest bad-pitch-65536: SUCCESS (0.000s) [113.913823] [069/171] (893s left) kms_addfb_basic (bad-pitch-999) [114.052731] Starting subtest: bad-pitch-999 [114.053915] Subtest bad-pitch-999: SUCCESS (0.000s) [114.180555] [070/171] (893s left) kms_addfb_basic (basic) [114.318964] Starting subtest: basic [114.320193] Subtest basic: SUCCESS (0.000s) [114.447089] [071/171] (893s left) kms_addfb_basic (basic-x-tiled-legacy) [114.587213] Starting subtest: basic-x-tiled-legacy [114.588356] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [114.713246] [072/171] (893s left) kms_addfb_basic (basic-y-tiled-legacy) [114.854171] Starting subtest: basic-y-tiled-legacy [114.855355] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [114.980027] [073/171] (892s left) kms_addfb_basic (bo-too-small) [115.147341] Starting subtest: bo-too-small [115.148512] Subtest bo-too-small: SUCCESS (0.000s) [115.246739] [074/171] (892s left) kms_addfb_basic (bo-too-small-due-to-tiling) [115.416271] Starting subtest: bo-too-small-due-to-tiling [115.417400] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [115.530597] [075/171] (892s left) kms_addfb_basic (clobberred-modifier) [115.669481] Starting subtest: clobberred-modifier [115.670677] Subtest clobberred-modifier: SUCCESS (0.000s) [115.796915] [076/171] (892s left) kms_addfb_basic (framebuffer-vs-set-tiling) [115.940188] Starting subtest: framebuffer-vs-set-tiling [115.941257] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [116.068382] [077/171] (891s left) kms_addfb_basic (invalid-get-prop) [116.207933] Starting subtest: invalid-get-prop [116.209090] Subtest invalid-get-prop: SUCCESS (0.000s) [116.330230] [078/171] (891s left) kms_addfb_basic (invalid-get-prop-any) [116.468552] Starting subtest: invalid-get-prop-any [116.469639] Subtest invalid-get-prop-any: SUCCESS (0.000s) [116.597368] [079/171] (891s left) kms_addfb_basic (invalid-set-prop) [116.735204] Starting subtest: invalid-set-prop [116.736272] Subtest invalid-set-prop: SUCCESS (0.000s) [116.863465] [080/171] (891s left) kms_addfb_basic (invalid-set-prop-any) [117.000170] Starting subtest: invalid-set-prop-any [117.001265] Subtest invalid-set-prop-any: SUCCESS (0.000s) [117.130315] [081/171] (891s left) kms_addfb_basic (no-handle) [117.269248] Starting subtest: no-handle [117.270340] Subtest no-handle: SUCCESS (0.000s) [117.397064] [082/171] (890s left) kms_addfb_basic (size-max) [117.564389] Starting subtest: size-max [117.565585] Subtest size-max: SUCCESS (0.000s) [117.663411] [083/171] (890s left) kms_addfb_basic (small-bo) [117.830392] Starting subtest: small-bo [117.831442] Subtest small-bo: SUCCESS (0.000s) [117.932333] [084/171] (890s left) kms_addfb_basic (tile-pitch-mismatch) [118.070662] Starting subtest: tile-pitch-mismatch [118.071827] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [118.217309] [085/171] (890s left) kms_addfb_basic (too-high) [118.384418] Starting subtest: too-high [118.385618] Subtest too-high: SUCCESS (0.001s) [118.480512] [086/171] (889s left) kms_addfb_basic (too-wide) [118.648352] Starting subtest: too-wide [118.649503] Subtest too-wide: SUCCESS (0.000s) [118.764940] [087/171] (889s left) kms_addfb_basic (unused-handle) [118.904226] Starting subtest: unused-handle [118.905388] Subtest unused-handle: SUCCESS (0.000s) [119.030252] [088/171] (889s left) kms_addfb_basic (unused-modifier) [119.167854] Starting subtest: unused-modifier [119.169092] Subtest unused-modifier: SUCCESS (0.000s) [119.297232] [089/171] (889s left) kms_addfb_basic (unused-offsets) [119.435390] Starting subtest: unused-offsets [119.436466] Subtest unused-offsets: SUCCESS (0.000s) [119.563782] [090/171] (888s left) kms_addfb_basic (unused-pitches) [119.701645] Starting subtest: unused-pitches [119.703783] Subtest unused-pitches: SUCCESS (0.000s) [119.829769] [091/171] (888s left) kms_busy (basic) [120.003406] Starting subtest: basic [120.004510] Starting dynamic subtest: flip [121.081505] Dynamic subtest flip: SUCCESS (1.077s) [121.100346] Starting dynamic subtest: modeset [124.974075] Dynamic subtest modeset: SUCCESS (3.886s) [124.975320] Subtest basic: SUCCESS (4.966s) [125.941451] [092/171] (882s left) kms_chamelium_hpd (dp-hpd-fast) [126.425196] Subtest dp-hpd-fast: SKIP (0.000s) [127.304415] [093/171] (881s left) kms_chamelium_edid (dp-edid-read) [127.783400] Subtest dp-edid-read: SKIP (0.000s) [128.710125] [094/171] (879s left) kms_chamelium_frames (dp-crc-fast) [129.195552] Subtest dp-crc-fast: SKIP (0.000s) [130.114631] [095/171] (878s left) kms_chamelium_hpd (hdmi-hpd-fast) [130.597725] Subtest hdmi-hpd-fast: SKIP (0.000s) [131.457883] [096/171] (877s left) kms_chamelium_edid (hdmi-edid-read) [131.923394] Subtest hdmi-edid-read: SKIP (0.000s) [132.800527] [097/171] (875s left) kms_chamelium_frames (hdmi-crc-fast) [133.283276] Subtest hdmi-crc-fast: SKIP (0.000s) [134.144261] [098/171] (874s left) kms_chamelium_hpd (vga-hpd-fast) [134.624545] Subtest vga-hpd-fast: SKIP (0.000s) [135.488608] [099/171] (873s left) kms_chamelium_edid (vga-edid-read) [135.970472] Subtest vga-edid-read: SKIP (0.000s) [136.835980] [100/171] (871s left) kms_prop_blob (basic) [136.974729] Starting subtest: basic [136.976022] Subtest basic: SUCCESS (0.000s) [137.055490] [101/171] (871s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [137.221404] Starting subtest: basic-busy-flip-before-cursor-atomic [137.223494] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [137.301076] [102/171] (871s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [137.466146] Starting subtest: basic-busy-flip-before-cursor-legacy [137.468390] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [137.550911] [103/171] (871s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [137.717238] Starting subtest: basic-flip-after-cursor-atomic [138.979451] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.262s) [139.894129] [104/171] (868s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [140.062348] Starting subtest: basic-flip-after-cursor-legacy [141.357493] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.290s) [142.323395] [105/171] (866s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [142.489622] Starting subtest: basic-flip-after-cursor-varying-size [143.799862] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.310s) [144.760472] [106/171] (864s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [144.925835] Starting subtest: basic-flip-before-cursor-atomic [146.214092] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.288s) [147.188549] [107/171] (861s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [147.354079] Starting subtest: basic-flip-before-cursor-legacy [148.629358] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.275s) [149.568800] [108/171] (859s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [149.733999] Starting subtest: basic-flip-before-cursor-varying-size [150.995105] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.260s) [151.923484] [109/171] (857s left) kms_flip (basic-flip-vs-dpms) [152.071158] Starting subtest: basic-flip-vs-dpms [152.074203] Starting dynamic subtest: A-eDP1 [154.946186] Dynamic subtest A-eDP1: SUCCESS (2.872s) [154.963144] Starting dynamic subtest: B-eDP1 [157.370099] Dynamic subtest B-eDP1: SUCCESS (2.415s) [157.385159] Starting dynamic subtest: C-eDP1 [159.730534] Dynamic subtest C-eDP1: SUCCESS (2.367s) [159.735392] Subtest basic-flip-vs-dpms: SUCCESS (7.664s) [160.636871] [110/171] (848s left) kms_flip (basic-flip-vs-modeset) [160.776057] Starting subtest: basic-flip-vs-modeset [160.778854] Starting dynamic subtest: A-eDP1 [163.624215] Dynamic subtest A-eDP1: SUCCESS (2.846s) [163.641536] Starting dynamic subtest: B-eDP1 [165.980770] Dynamic subtest B-eDP1: SUCCESS (2.351s) [165.996190] Starting dynamic subtest: C-eDP1 [168.350275] Dynamic subtest C-eDP1: SUCCESS (2.372s) [168.365276] Subtest basic-flip-vs-modeset: SUCCESS (7.579s) [169.268092] [111/171] (839s left) kms_flip (basic-flip-vs-wf_vblank) [169.416306] Starting subtest: basic-flip-vs-wf_vblank [169.419495] Starting dynamic subtest: A-eDP1 [171.730346] Dynamic subtest A-eDP1: SUCCESS (2.311s) [171.748129] Starting dynamic subtest: B-eDP1 [173.978890] Dynamic subtest B-eDP1: SUCCESS (2.247s) [173.996699] Starting dynamic subtest: C-eDP1 [176.260165] Dynamic subtest C-eDP1: SUCCESS (2.280s) [176.263246] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.849s) [177.588718] [112/171] (831s left) kms_flip (basic-plain-flip) [177.735313] Starting subtest: basic-plain-flip [177.738467] Starting dynamic subtest: A-eDP1 [179.767900] Dynamic subtest A-eDP1: SUCCESS (2.030s) [179.785620] Starting dynamic subtest: B-eDP1 [181.734450] Dynamic subtest B-eDP1: SUCCESS (1.965s) [181.752466] Starting dynamic subtest: C-eDP1 [183.721564] Dynamic subtest C-eDP1: SUCCESS (1.986s) [183.725664] Subtest basic-plain-flip: SUCCESS (5.991s) [185.015249] [113/171] (824s left) kms_force_connector_basic (force-connector-state) [185.159862] Starting subtest: force-connector-state [186.884353] Subtest force-connector-state: SUCCESS (1.724s) [187.505391] [114/171] (821s left) kms_force_connector_basic (force-edid) [187.654123] Starting subtest: force-edid [187.904416] Subtest force-edid: SUCCESS (0.252s) [188.038775] [115/171] (821s left) kms_force_connector_basic (force-load-detect) [188.185447] Starting subtest: force-load-detect [188.188057] Subtest force-load-detect: SKIP (0.000s) [188.305090] [116/171] (820s left) kms_force_connector_basic (prune-stale-modes) [188.454214] Starting subtest: prune-stale-modes [188.764323] Subtest prune-stale-modes: SUCCESS (0.306s) [188.888672] [117/171] (820s left) kms_frontbuffer_tracking (basic) [189.077141] Starting subtest: basic [192.347698] Subtest basic: SUCCESS (3.258s) [192.456641] [118/171] (816s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [192.627867] Starting subtest: compare-crc-sanitycheck-xr24 [192.630791] Starting dynamic subtest: pipe-A-eDP-1 [193.299926] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.669s) [193.321359] Starting dynamic subtest: pipe-B-eDP-1 [194.678407] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.363s) [194.695693] Starting dynamic subtest: pipe-C-eDP-1 [196.209156] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.539s) [196.210323] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.581s) [197.167716] [119/171] (812s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [197.336308] Starting subtest: compare-crc-sanitycheck-nv12 [197.337624] Starting dynamic subtest: pipe-A-eDP-1 [198.241197] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.908s) [198.267395] Starting dynamic subtest: pipe-B-eDP-1 [199.696046] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.450s) [199.721730] Starting dynamic subtest: pipe-C-eDP-1 [201.481072] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.775s) [201.482393] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.144s) [202.417629] [120/171] (806s left) kms_pipe_crc_basic (hang-read-crc) [202.580242] Starting subtest: hang-read-crc [202.583668] Starting dynamic subtest: pipe-A-eDP-1 [204.894173] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.311s) [204.915936] Starting dynamic subtest: pipe-B-eDP-1 [207.901426] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.003s) [207.920534] Starting dynamic subtest: pipe-C-eDP-1 [211.225431] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.321s) [211.226701] Subtest hang-read-crc: SUCCESS (8.646s) [212.143861] [121/171] (797s left) kms_pipe_crc_basic (nonblocking-crc) [212.313473] Starting subtest: nonblocking-crc [212.314719] Starting dynamic subtest: pipe-A-eDP-1 [213.169124] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.854s) [213.190698] Starting dynamic subtest: pipe-B-eDP-1 [214.723429] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.549s) [214.742832] Starting dynamic subtest: pipe-C-eDP-1 [216.456197] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.730s) [216.457365] Subtest nonblocking-crc: SUCCESS (4.145s) [217.391786] [122/171] (791s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [217.563864] Starting subtest: nonblocking-crc-frame-sequence [217.565092] Starting dynamic subtest: pipe-A-eDP-1 [218.416228] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.851s) [218.436092] Starting dynamic subtest: pipe-B-eDP-1 [219.969773] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.549s) [219.988676] Starting dynamic subtest: pipe-C-eDP-1 [221.694904] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.721s) [221.696150] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.133s) [222.639866] [123/171] (786s left) kms_pipe_crc_basic (read-crc) [222.811815] Starting subtest: read-crc [222.815181] Starting dynamic subtest: pipe-A-eDP-1 [223.596590] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.782s) [223.616066] Starting dynamic subtest: pipe-B-eDP-1 [225.085283] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.485s) [225.104684] Starting dynamic subtest: pipe-C-eDP-1 [226.748440] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.658s) [226.749556] Subtest read-crc: SUCCESS (3.936s) [227.696883] [124/171] (781s left) kms_pipe_crc_basic (read-crc-frame-sequence) [227.865911] Starting subtest: read-crc-frame-sequence [227.869278] Starting dynamic subtest: pipe-A-eDP-1 [228.654181] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.785s) [228.674403] Starting dynamic subtest: pipe-B-eDP-1 [230.142228] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.484s) [230.161970] Starting dynamic subtest: pipe-C-eDP-1 [231.795434] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.649s) [231.796776] Subtest read-crc-frame-sequence: SUCCESS (3.930s) [232.751860] [125/171] (776s left) kms_psr (primary_page_flip) [232.926300] Starting subtest: primary_page_flip [237.114127] Subtest primary_page_flip: SUCCESS (4.187s) [238.063870] [126/171] (771s left) kms_psr (cursor_plane_move) [238.236660] Starting subtest: cursor_plane_move [242.325383] Subtest cursor_plane_move: SUCCESS (4.088s) [243.248069] [127/171] (766s left) kms_psr (sprite_plane_onoff) [243.418720] Starting subtest: sprite_plane_onoff [247.677937] Subtest sprite_plane_onoff: SUCCESS (4.258s) [248.624207] [128/171] (760s left) kms_psr (primary_mmap_gtt) [248.797373] Starting subtest: primary_mmap_gtt [252.996395] Subtest primary_mmap_gtt: SUCCESS (4.186s) [253.935842] [129/171] (755s left) kms_setmode (basic-clone-single-crtc) [254.078894] Starting subtest: basic-clone-single-crtc [254.082596] Subtest basic-clone-single-crtc: SKIP (0.000s) [254.168855] [130/171] (755s left) i915_pm_backlight (basic-brightness) [254.339346] Starting subtest: basic-brightness [254.435862] Starting dynamic subtest: eDP-1 [255.002210] Dynamic subtest eDP-1: SUCCESS (0.560s) [255.003801] Subtest basic-brightness: SUCCESS (0.656s) [255.920953] [131/171] (753s left) i915_pm_rpm (basic-pci-d3-state) [257.811221] Starting subtest: basic-pci-d3-state [262.217746] Subtest basic-pci-d3-state: SUCCESS (4.408s) [262.421592] [132/171] (747s left) i915_pm_rpm (basic-rte) [262.558817] Starting subtest: basic-rte [268.684511] Subtest basic-rte: SUCCESS (6.122s) [270.154102] [133/171] (739s left) i915_pm_rps (basic-api) [270.302646] Starting subtest: basic-api [270.306892] Subtest basic-api: SUCCESS (0.004s) [270.403415] [134/171] (739s left) prime_self_import (basic-llseek-bad) [270.539995] Starting subtest: basic-llseek-bad [270.544674] Subtest basic-llseek-bad: SUCCESS (0.003s) [270.636138] [135/171] (739s left) prime_self_import (basic-llseek-size) [270.773569] Starting subtest: basic-llseek-size [270.778198] Subtest basic-llseek-size: SUCCESS (0.004s) [270.886241] [136/171] (738s left) prime_self_import (basic-with_fd_dup) [271.023434] Starting subtest: basic-with_fd_dup [271.028217] Subtest basic-with_fd_dup: SUCCESS (0.005s) [271.136415] [137/171] (738s left) prime_self_import (basic-with_one_bo) [271.263783] Starting subtest: basic-with_one_bo [271.269200] Subtest basic-with_one_bo: SUCCESS (0.005s) [271.351364] [138/171] (738s left) prime_self_import (basic-with_one_bo_two_files) [271.487479] Starting subtest: basic-with_one_bo_two_files [271.492339] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s) [271.586276] [139/171] (738s left) prime_self_import (basic-with_two_bos) [271.722656] Starting subtest: basic-with_two_bos [271.727498] Subtest basic-with_two_bos: SUCCESS (0.005s) [271.836033] [140/171] (738s left) prime_vgem (basic-fence-flip) [271.989474] Starting subtest: basic-fence-flip [272.463682] Subtest basic-fence-flip: SUCCESS (0.474s) [272.589611] [141/171] (737s left) prime_vgem (basic-fence-mmap) [272.739628] Starting subtest: basic-fence-mmap [272.787882] Subtest basic-fence-mmap: SUCCESS (0.048s) [272.886899] [142/171] (737s left) prime_vgem (basic-fence-read) [273.039583] Starting subtest: basic-fence-read [273.113910] Subtest basic-fence-read: SUCCESS (0.074s) [273.205136] [143/171] (736s left) prime_vgem (basic-gtt) [273.355760] Starting subtest: basic-gtt [273.379352] Subtest basic-gtt: SUCCESS (0.023s) [273.469494] [144/171] (736s left) prime_vgem (basic-read) [273.622523] Starting subtest: basic-read [273.660379] Subtest basic-read: SUCCESS (0.035s) [273.769682] [145/171] (736s left) prime_vgem (basic-write) [273.922537] Starting subtest: basic-write [273.964578] Subtest basic-write: SUCCESS (0.042s) [274.053053] [146/171] (736s left) vgem_basic (setversion) [274.198390] Starting subtest: setversion [274.200710] Subtest setversion: SUCCESS (0.000s) [274.269932] [147/171] (735s left) vgem_basic (create) [274.415339] Starting subtest: create [274.416644] Subtest create: SUCCESS (0.000s) [274.486079] [148/171] (735s left) vgem_basic (debugfs) [274.631831] Starting subtest: debugfs [274.633167] Subtest debugfs: SUCCESS (0.001s) [274.703138] [149/171] (735s left) vgem_basic (dmabuf-export) [274.848568] Starting subtest: dmabuf-export [274.853274] Subtest dmabuf-export: SUCCESS (0.003s) [274.953049] [150/171] (735s left) vgem_basic (dmabuf-fence) [275.098432] Starting subtest: dmabuf-fence [275.099673] Subtest dmabuf-fence: SUCCESS (0.001s) [275.169725] [151/171] (735s left) vgem_basic (dmabuf-fence-before) [275.334722] Starting subtest: dmabuf-fence-before [275.335958] Subtest dmabuf-fence-before: SUCCESS (0.000s) [275.404296] [152/171] (734s left) vgem_basic (dmabuf-mmap) [275.567927] Starting subtest: dmabuf-mmap [275.591990] Subtest dmabuf-mmap: SUCCESS (0.026s) [275.671358] [153/171] (734s left) vgem_basic (mmap) [275.834453] Starting subtest: mmap [275.849413] Subtest mmap: SUCCESS (0.017s) [275.920959] [154/171] (734s left) vgem_basic (second-client) [276.068437] Starting subtest: second-client [276.100609] Subtest second-client: SUCCESS (0.032s) [276.171087] [155/171] (734s left) vgem_basic (sysfs) [276.334645] Starting subtest: sysfs [276.335780] Subtest sysfs: SUCCESS (0.001s) [276.404459] [156/171] (734s left) core_hotunplug (unbind-rebind) [276.583416] Starting subtest: unbind-rebind [279.068025] Subtest unbind-rebind: SUCCESS (2.486s) [279.130882] [157/171] (731s left) vgem_basic (unload) [279.272726] Starting subtest: unload [308.331064] Subtest unload: SUCCESS (29.020s) [308.442889] [158/171] (702s left) i915_module_load (reload) [308.584055] Starting subtest: reload [312.161570] Subtest reload: SUCCESS (3.578s) [312.194924] [159/171] (698s left) gem_lmem_swapping (basic) [312.357167] Subtest basic: SKIP (0.000s) [312.413053] [160/171] (698s left) gem_lmem_swapping (parallel-random-engines) [312.560227] Subtest parallel-random-engines: SKIP (0.000s) [312.623126] [161/171] (697s left) gem_lmem_swapping (random-engines) [312.769483] Subtest random-engines: SKIP (0.000s) [312.812742] [162/171] (697s left) gem_lmem_swapping (verify-random) [312.957362] Subtest verify-random: SKIP (0.000s) [313.013343] [163/171] (697s left) i915_pm_rpm (module-reload) [325.519303] Starting subtest: module-reload [360.497782] Subtest module-reload: SUCCESS (34.976s) [360.521860] [164/171] (650s left) i915_selftest (live) [360.663192] Starting subtest: live [360.664641] Starting dynamic subtest: sanitycheck [362.520458] Dynamic subtest sanitycheck: SUCCESS (1.848s) [362.535067] Starting dynamic subtest: uncore [364.403582] Dynamic subtest uncore: SUCCESS (1.879s) [364.418372] Starting dynamic subtest: workarounds [366.502570] Dynamic subtest workarounds: SUCCESS (2.096s) [366.517030] Starting dynamic subtest: gt_engines [368.794278] Dynamic subtest gt_engines: SUCCESS (2.288s) [368.809366] Starting dynamic subtest: gt_timelines [381.573526] Dynamic subtest gt_timelines: SUCCESS (12.776s) [381.588521] Starting dynamic subtest: gt_contexts [383.527601] Dynamic subtest gt_contexts: SUCCESS (1.951s) [383.542524] Starting dynamic subtest: gt_lrc [387.816487] Dynamic subtest gt_lrc: SUCCESS (4.286s) [387.831457] Starting dynamic subtest: gt_mocs [389.761726] Dynamic subtest gt_mocs: SUCCESS (1.942s) [389.776450] Starting dynamic subtest: gt_pm [394.816910] Dynamic subtest gt_pm: SUCCESS (5.051s) [394.831998] Starting dynamic subtest: gt_heartbeat [396.700488] Dynamic subtest gt_heartbeat: SUCCESS (1.881s) [396.715272] Starting dynamic subtest: gt_tlb [399.861839] Dynamic subtest gt_tlb: SUCCESS (3.157s) [399.876435] Starting dynamic subtest: requests [407.450122] Dynamic subtest requests: SUCCESS (7.584s) [407.464740] Starting dynamic subtest: migrate [412.066498] Dynamic subtest migrate: SUCCESS (4.613s) [412.081376] Starting dynamic subtest: active [413.909382] Dynamic subtest active: SUCCESS (1.840s) [413.924307] Starting dynamic subtest: objects [417.302616] Dynamic subtest objects: SUCCESS (3.390s) [417.317254] Starting dynamic subtest: mman [424.239378] Dynamic subtest mman: SUCCESS (6.933s) [424.254417] Starting dynamic subtest: dmabuf [426.086421] Dynamic subtest dmabuf: SUCCESS (1.844s) [426.101523] Starting dynamic subtest: vma [427.934619] Dynamic subtest vma: SUCCESS (1.845s) [427.949523] Starting dynamic subtest: coherency [432.413726] Dynamic subtest coherency: SUCCESS (4.475s) [432.428452] Starting dynamic subtest: gtt [441.828577] Dynamic subtest gtt: SUCCESS (9.411s) [441.843580] Starting dynamic subtest: gem [443.758476] Dynamic subtest gem: SUCCESS (1.927s) [443.773388] Starting dynamic subtest: evict [447.589318] Dynamic subtest evict: SUCCESS (3.827s) [447.604582] Starting dynamic subtest: hugepages [455.367642] Dynamic subtest hugepages: SUCCESS (7.775s) [455.382911] Starting dynamic subtest: gem_contexts [470.806831] Dynamic subtest gem_contexts: SUCCESS (15.410s) [470.822340] Starting dynamic subtest: client [472.675777] Dynamic subtest client: SUCCESS (1.892s) [472.691295] Starting dynamic subtest: gem_migrate [474.515267] Dynamic subtest gem_migrate: SUCCESS (1.836s) [474.530954] Starting dynamic subtest: reset [479.922558] Dynamic subtest reset: SUCCESS (5.404s) [479.937878] Starting dynamic subtest: memory_region [481.746450] Dynamic subtest memory_region: SUCCESS (1.820s) [481.761905] Starting dynamic subtest: hangcheck [506.175638] Dynamic subtest hangcheck: SUCCESS (24.426s) [506.191277] Starting dynamic subtest: execlists [516.364674] Dynamic subtest execlists: SUCCESS (10.186s) [516.380250] Starting dynamic subtest: ring_submission [518.228279] Dynamic subtest ring_submission: SUCCESS (1.860s) [518.244070] Starting dynamic subtest: perf [520.179497] Dynamic subtest perf: SUCCESS (1.948s) [520.194885] Starting dynamic subtest: slpc [522.013379] Dynamic subtest slpc: SUCCESS (1.830s) [522.029568] Starting dynamic subtest: guc [523.800425] Dynamic subtest guc: SUCCESS (1.784s) [523.815730] Starting dynamic subtest: guc_multi_lrc [525.666523] Dynamic subtest guc_multi_lrc: SUCCESS (1.863s) [525.682054] Starting dynamic subtest: guc_hang [527.515433] Dynamic subtest guc_hang: SUCCESS (1.846s) [527.531072] Starting dynamic subtest: late_gt_pm [529.390692] Dynamic subtest late_gt_pm: SUCCESS (1.872s) [529.392106] Subtest live: SUCCESS (168.720s) [529.593192] [165/171] (481s left) dmabuf (all-tests) [529.734068] Starting subtest: all-tests [529.735401] Starting dynamic subtest: sanitycheck [529.799856] Dynamic subtest sanitycheck: SUCCESS (0.064s) [529.818474] Starting dynamic subtest: dma_fence [529.985470] Dynamic subtest dma_fence: SUCCESS (0.181s) [530.003434] Starting dynamic subtest: dma_fence_chain [548.049221] Dynamic subtest dma_fence_chain: SUCCESS (18.052s) [548.062233] Starting dynamic subtest: dma_fence_unwrap [548.118990] Dynamic subtest dma_fence_unwrap: SUCCESS (0.074s) [548.138170] Starting dynamic subtest: dma_resv [548.192698] Dynamic subtest dma_resv: SUCCESS (0.070s) [548.194946] Subtest all-tests: SUCCESS (18.460s) [548.226553] [166/171] (462s left) i915_suspend (basic-s2idle-without-i915) [548.365229] Starting subtest: basic-s2idle-without-i915 [571.864495] Subtest basic-s2idle-without-i915: SUCCESS (8.404s) [572.165421] [167/171] (453s left) i915_suspend (basic-s3-without-i915) [572.305280] Starting subtest: basic-s3-without-i915 [595.921442] Subtest basic-s3-without-i915: SUCCESS (7.124s) [596.237292] [168/171] (446s left) gem_exec_suspend (basic-s0) [596.393637] Starting subtest: basic-S0 [596.394945] Starting dynamic subtest: smem [616.166448] Dynamic subtest smem: SUCCESS (4.054s) [616.167714] Subtest basic-S0: SUCCESS (4.055s) [616.355405] [169/171] (441s left) gem_exec_suspend (basic-s3) [616.504098] Starting subtest: basic-S3 [616.505263] Starting dynamic subtest: smem [634.989920] Dynamic subtest smem: SUCCESS (3.640s) [634.991118] Subtest basic-S3: SUCCESS (3.642s) [635.161737] [170/171] (437s left) kms_chamelium_hpd (common-hpd-after-suspend) [635.645726] Subtest common-hpd-after-suspend: SKIP (0.000s) [636.556175] [171/171] (436s left) kms_pipe_crc_basic (suspend-read-crc) [636.718290] Starting subtest: suspend-read-crc [636.721487] Starting dynamic subtest: pipe-A-eDP-1 [654.933471] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.404s) [654.957837] Starting dynamic subtest: pipe-B-eDP-1 [672.908483] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.078s) [672.931504] Starting dynamic subtest: pipe-C-eDP-1 [691.905245] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.271s) [691.906502] Subtest suspend-read-crc: SUCCESS (8.765s) [692.834424] Closing watchdogs Done.