[35.852974] Initializing watchdogs [35.853127] /dev/watchdog0 [35.860138] [001/171] (960s left) i915_module_load (load) [35.957830] Starting subtest: load [36.966926] Subtest load: SUCCESS (1.008s) [36.990619] [002/171] (959s left) core_auth (basic-auth) [37.101243] Starting subtest: basic-auth [37.103259] Subtest basic-auth: SUCCESS (0.001s) [37.209239] [003/171] (959s left) debugfs_test (read_all_entries) [37.328430] Starting subtest: read_all_entries [37.411251] Subtest read_all_entries: SUCCESS (0.082s) [37.517965] [004/171] (958s left) debugfs_test (basic-hwmon) [37.605722] Starting subtest: basic-hwmon [37.607660] Subtest basic-hwmon: SKIP (0.000s) [37.719672] [005/171] (958s left) fbdev (eof) [37.815136] Starting subtest: eof [37.816239] Subtest eof: SUCCESS (0.000s) [37.872842] [006/171] (958s left) fbdev (info) [37.953794] Starting subtest: info [37.954753] Subtest info: SUCCESS (0.000s) [38.017942] [007/171] (958s left) fbdev (nullptr) [38.114758] Starting subtest: nullptr [38.115750] Subtest nullptr: SUCCESS (0.000s) [38.168150] [008/171] (958s left) fbdev (read) [38.267234] Starting subtest: read [39.128720] Subtest read: SUCCESS (0.854s) [39.167493] [009/171] (957s left) fbdev (write) [39.265454] Starting subtest: write [39.687080] Subtest write: SUCCESS (0.413s) [39.723847] [010/171] (956s left) gem_basic (bad-close) [39.830723] Starting subtest: bad-close [39.832502] Subtest bad-close: SUCCESS (0.000s) [39.889925] [011/171] (956s left) gem_basic (create-close) [39.972044] Starting subtest: create-close [39.974049] Subtest create-close: SUCCESS (0.000s) [40.042735] [012/171] (956s left) gem_basic (create-fd-close) [40.132227] Starting subtest: create-fd-close [40.134057] Subtest create-fd-close: SUCCESS (0.000s) [40.217278] [013/171] (956s left) gem_busy (busy@all-engines) [40.330143] Starting subtest: busy [40.330977] Starting dynamic subtest: all-engines [40.370435] Dynamic subtest all-engines: SUCCESS (0.041s) [40.371343] Subtest busy: SUCCESS (0.041s) [40.784747] [014/171] (955s left) gem_close_race (basic-process) [40.904275] Starting subtest: basic-process [40.961338] Subtest basic-process: SUCCESS (0.057s) [41.152168] [015/171] (955s left) gem_close_race (basic-threads) [41.269295] Starting subtest: basic-threads [42.420659] Subtest basic-threads: SUCCESS (1.151s) [42.647961] [016/171] (953s left) gem_ctx_create (basic) [42.754962] Starting subtest: basic [42.755869] Subtest basic: SUCCESS (0.000s) [42.997685] [017/171] (953s left) gem_ctx_create (basic-files) [43.099333] Starting subtest: basic-files [45.115980] Subtest basic-files: SUCCESS (2.017s) [45.371492] [018/171] (951s left) gem_ctx_exec (basic) [45.463590] Starting subtest: basic [45.469121] Subtest basic: SUCCESS (0.005s) [45.597671] [019/171] (951s left) gem_exec_basic (basic) [45.699393] Starting subtest: basic [45.700353] Starting dynamic subtest: rcs0-smem [45.702319] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [45.709600] Starting dynamic subtest: bcs0-smem [45.710478] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [45.717079] Starting dynamic subtest: vcs0-smem [45.718166] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [45.724888] Starting dynamic subtest: vecs0-smem [45.726276] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [45.727635] Subtest basic: SUCCESS (0.006s) [45.945329] [020/171] (950s left) gem_exec_create (basic) [46.046687] Starting subtest: basic [46.047786] Starting dynamic subtest: smem [48.090457] Dynamic subtest smem: SUCCESS (2.044s) [48.091487] Subtest basic: SUCCESS (2.044s) [48.314182] [021/171] (948s left) gem_exec_fence (basic-busy) [48.575671] Starting subtest: basic-busy [48.577697] Starting dynamic subtest: rcs0 [48.603241] Dynamic subtest rcs0: SUCCESS (0.028s) [48.610291] Starting dynamic subtest: bcs0 [48.628322] Dynamic subtest bcs0: SUCCESS (0.025s) [48.635161] Starting dynamic subtest: vcs0 [48.649241] Dynamic subtest vcs0: SUCCESS (0.021s) [48.656214] Starting dynamic subtest: vecs0 [48.670220] Dynamic subtest vecs0: SUCCESS (0.021s) [48.671458] Subtest basic-busy: SUCCESS (0.095s) [49.363941] [022/171] (947s left) gem_exec_fence (basic-wait) [49.592232] Starting subtest: basic-wait [49.594105] Starting dynamic subtest: rcs0 [49.620112] Dynamic subtest rcs0: SUCCESS (0.028s) [49.635690] Starting dynamic subtest: bcs0 [49.645374] Dynamic subtest bcs0: SUCCESS (0.026s) [49.653920] Starting dynamic subtest: vcs0 [49.671281] Dynamic subtest vcs0: SUCCESS (0.026s) [49.678427] Starting dynamic subtest: vecs0 [49.696292] Dynamic subtest vecs0: SUCCESS (0.025s) [49.697210] Subtest basic-wait: SUCCESS (0.105s) [50.338506] [023/171] (946s left) gem_exec_fence (basic-await) [50.572265] Starting subtest: basic-await [50.574151] Starting dynamic subtest: rcs0 [50.670673] Dynamic subtest rcs0: SUCCESS (0.098s) [50.681711] Starting dynamic subtest: bcs0 [50.764344] Dynamic subtest bcs0: SUCCESS (0.094s) [50.774485] Starting dynamic subtest: vcs0 [50.857447] Dynamic subtest vcs0: SUCCESS (0.093s) [50.868540] Starting dynamic subtest: vecs0 [50.957378] Dynamic subtest vecs0: SUCCESS (0.100s) [50.958818] Subtest basic-await: SUCCESS (0.385s) [51.698369] [024/171] (945s left) gem_exec_fence (nb-await) [51.935284] Starting subtest: nb-await [51.937548] Starting dynamic subtest: rcs0 [52.007618] Dynamic subtest rcs0: SUCCESS (0.072s) [52.031258] Starting dynamic subtest: bcs0 [52.090440] Dynamic subtest bcs0: SUCCESS (0.081s) [52.132760] Starting dynamic subtest: vcs0 [52.194288] Dynamic subtest vcs0: SUCCESS (0.103s) [52.236075] Starting dynamic subtest: vecs0 [52.302807] Dynamic subtest vecs0: SUCCESS (0.108s) [52.306507] Subtest nb-await: SUCCESS (0.367s) [53.077192] [025/171] (943s left) gem_exec_gttfill (basic) [53.183311] Starting subtest: basic [62.376596] Subtest basic: SUCCESS (9.185s) [62.554628] [026/171] (934s left) gem_exec_parallel (engines) [62.711321] Starting subtest: engines [62.712403] Starting dynamic subtest: basic [65.034307] Dynamic subtest basic: SUCCESS (2.323s) [65.042098] Starting dynamic subtest: contexts [67.697081] Dynamic subtest contexts: SUCCESS (2.654s) [67.705266] Starting dynamic subtest: fds [70.760671] Dynamic subtest fds: SUCCESS (3.072s) [70.769517] Starting dynamic subtest: userptr [73.008577] Dynamic subtest userptr: SUCCESS (2.248s) [73.009497] Subtest engines: SUCCESS (10.297s) [73.219589] [027/171] (923s left) gem_exec_store (basic) [73.323187] Starting subtest: basic [73.369590] Subtest basic: SUCCESS (0.046s) [73.597283] [028/171] (923s left) gem_flink_basic (bad-flink) [73.695076] Starting subtest: bad-flink [73.696113] Subtest bad-flink: SUCCESS (0.000s) [73.814479] [029/171] (923s left) gem_flink_basic (bad-open) [73.910494] Starting subtest: bad-open [73.911601] Subtest bad-open: SUCCESS (0.000s) [74.005287] [030/171] (922s left) gem_flink_basic (basic) [74.096456] Starting subtest: basic [74.097510] Subtest basic: SUCCESS (0.000s) [74.225869] [031/171] (922s left) gem_flink_basic (double-flink) [74.319354] Starting subtest: double-flink [74.320290] Subtest double-flink: SUCCESS (0.000s) [74.444373] [032/171] (922s left) gem_flink_basic (flink-lifetime) [74.534705] Starting subtest: flink-lifetime [74.535753] Subtest flink-lifetime: SUCCESS (0.001s) [74.659552] [033/171] (922s left) gem_huc_copy (huc-copy) [74.755228] Starting subtest: huc-copy [74.757223] Subtest huc-copy: SKIP (0.000s) [74.878127] [034/171] (922s left) gem_linear_blits (basic) [75.120488] Starting subtest: basic [75.131232] Subtest basic: SUCCESS (0.011s) [75.285218] [035/171] (921s left) gem_mmap (basic) [75.427240] Starting subtest: basic [75.431386] Subtest basic: SUCCESS (0.001s) [75.542972] [036/171] (921s left) gem_mmap_gtt (basic) [75.638412] Starting subtest: basic [75.639477] Subtest basic: SUCCESS (0.001s) [75.709551] [037/171] (921s left) gem_render_linear_blits (basic) [75.812964] Starting subtest: basic [75.853609] Subtest basic: SUCCESS (0.040s) [75.907899] [038/171] (921s left) gem_render_tiled_blits (basic) [76.007151] Starting subtest: basic [76.059281] Subtest basic: SUCCESS (0.051s) [76.107763] [039/171] (921s left) gem_ringfill (basic-all) [76.405100] Starting subtest: basic-all [77.567022] Subtest basic-all: SUCCESS (1.154s) [77.682606] [040/171] (919s left) gem_softpin (allocator-basic) [77.791898] Starting subtest: allocator-basic [77.922694] Subtest allocator-basic: SUCCESS (0.122s) [78.047311] [041/171] (919s left) gem_softpin (allocator-basic-reserve) [78.161386] Starting subtest: allocator-basic-reserve [78.290690] Subtest allocator-basic-reserve: SUCCESS (0.121s) [78.428826] [042/171] (918s left) gem_softpin (safe-alignment) [78.527391] Starting subtest: safe-alignment [78.542956] Subtest safe-alignment: SUCCESS (0.015s) [78.687671] [043/171] (918s left) gem_sync (basic-all) [78.794736] Starting subtest: basic-all [80.836421] Subtest basic-all: SUCCESS (2.042s) [81.077977] [044/171] (916s left) gem_sync (basic-each) [81.189338] Starting subtest: basic-each [83.337715] Subtest basic-each: SUCCESS (2.148s) [83.671215] [045/171] (913s left) gem_tiled_blits (basic) [83.923752] Starting subtest: basic [83.949991] Subtest basic: SUCCESS (0.026s) [84.102060] [046/171] (913s left) gem_tiled_fence_blits (basic) [84.393584] Starting subtest: basic [84.407933] Subtest basic: SUCCESS (0.015s) [84.563607] [047/171] (912s left) gem_tiled_pread_basic [84.944071] [048/171] (912s left) gem_wait (busy@all-engines) [85.062821] Starting subtest: busy [85.063891] Starting dynamic subtest: all-engines [85.597475] Dynamic subtest all-engines: SUCCESS (0.534s) [85.598665] Subtest busy: SUCCESS (0.535s) [85.877827] [049/171] (911s left) gem_wait (wait@all-engines) [85.990753] Starting subtest: wait [85.991709] Starting dynamic subtest: all-engines [87.039340] Dynamic subtest all-engines: SUCCESS (1.048s) [87.040890] Subtest wait: SUCCESS (1.048s) [87.308335] [050/171] (910s left) i915_getparams_basic (basic-eu-total) [87.401209] Starting subtest: basic-eu-total [87.403084] Subtest basic-eu-total: SUCCESS (0.000s) [87.497217] [051/171] (909s left) i915_getparams_basic (basic-subslice-total) [87.596724] Starting subtest: basic-subslice-total [87.598551] Subtest basic-subslice-total: SUCCESS (0.000s) [87.718550] [052/171] (909s left) i915_hangman (error-state-basic) [87.838403] Starting subtest: error-state-basic [87.845157] Subtest error-state-basic: SUCCESS (0.007s) [87.933064] [053/171] (909s left) i915_pciid [88.097196] [054/171] (909s left) kms_addfb_basic (addfb25-bad-modifier) [88.234394] Starting subtest: addfb25-bad-modifier [88.235404] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [88.418028] [055/171] (909s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [88.547814] Starting subtest: addfb25-framebuffer-vs-set-tiling [88.548920] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [88.705586] [056/171] (908s left) kms_addfb_basic (addfb25-modifier-no-flag) [88.829402] Starting subtest: addfb25-modifier-no-flag [88.830377] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [88.985443] [057/171] (908s left) kms_addfb_basic (addfb25-x-tiled-legacy) [89.112351] Starting subtest: addfb25-x-tiled-legacy [89.113386] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [89.269817] [058/171] (908s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [89.405923] Starting subtest: addfb25-x-tiled-mismatch-legacy [89.406979] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [89.572034] [059/171] (908s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [89.732718] Starting subtest: addfb25-yf-tiled-legacy [89.733707] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [89.862474] [060/171] (907s left) kms_addfb_basic (addfb25-y-tiled-legacy) [90.014856] Starting subtest: addfb25-y-tiled-legacy [90.015889] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [90.145728] [061/171] (907s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [90.294865] Starting subtest: addfb25-y-tiled-small-legacy [90.295930] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [90.440807] [062/171] (907s left) kms_addfb_basic (bad-pitch-0) [90.525480] Starting subtest: bad-pitch-0 [90.526483] Subtest bad-pitch-0: SUCCESS (0.000s) [90.714167] [063/171] (906s left) kms_addfb_basic (bad-pitch-1024) [90.798816] Starting subtest: bad-pitch-1024 [90.800304] Subtest bad-pitch-1024: SUCCESS (0.000s) [91.008162] [064/171] (906s left) kms_addfb_basic (bad-pitch-128) [91.092181] Starting subtest: bad-pitch-128 [91.093070] Subtest bad-pitch-128: SUCCESS (0.000s) [91.310274] [065/171] (906s left) kms_addfb_basic (bad-pitch-256) [91.397122] Starting subtest: bad-pitch-256 [91.397968] Subtest bad-pitch-256: SUCCESS (0.000s) [91.596494] [066/171] (906s left) kms_addfb_basic (bad-pitch-32) [91.678186] Starting subtest: bad-pitch-32 [91.679166] Subtest bad-pitch-32: SUCCESS (0.000s) [91.871678] [067/171] (905s left) kms_addfb_basic (bad-pitch-63) [91.956324] Starting subtest: bad-pitch-63 [91.957341] Subtest bad-pitch-63: SUCCESS (0.000s) [92.151535] [068/171] (905s left) kms_addfb_basic (bad-pitch-65536) [92.235221] Starting subtest: bad-pitch-65536 [92.236269] Subtest bad-pitch-65536: SUCCESS (0.000s) [92.427188] [069/171] (905s left) kms_addfb_basic (bad-pitch-999) [92.509248] Starting subtest: bad-pitch-999 [92.510233] Subtest bad-pitch-999: SUCCESS (0.000s) [92.699431] [070/171] (905s left) kms_addfb_basic (basic) [92.783077] Starting subtest: basic [92.784039] Subtest basic: SUCCESS (0.000s) [93.001429] [071/171] (904s left) kms_addfb_basic (basic-x-tiled-legacy) [93.181935] Starting subtest: basic-x-tiled-legacy [93.182990] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [93.275931] [072/171] (904s left) kms_addfb_basic (basic-y-tiled-legacy) [93.455315] Starting subtest: basic-y-tiled-legacy [93.456322] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [93.549449] [073/171] (904s left) kms_addfb_basic (bo-too-small) [93.665260] Starting subtest: bo-too-small [93.666220] Subtest bo-too-small: SUCCESS (0.000s) [93.825610] [074/171] (904s left) kms_addfb_basic (bo-too-small-due-to-tiling) [93.942760] Starting subtest: bo-too-small-due-to-tiling [93.943784] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [94.101507] [075/171] (903s left) kms_addfb_basic (clobberred-modifier) [94.183466] Starting subtest: clobberred-modifier [94.184442] Subtest clobberred-modifier: SUCCESS (0.000s) [94.379392] [076/171] (903s left) kms_addfb_basic (framebuffer-vs-set-tiling) [94.557228] Starting subtest: framebuffer-vs-set-tiling [94.558139] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [94.667358] [077/171] (903s left) kms_addfb_basic (invalid-get-prop) [94.858989] Starting subtest: invalid-get-prop [94.859885] Subtest invalid-get-prop: SUCCESS (0.000s) [94.960788] [078/171] (902s left) kms_addfb_basic (invalid-get-prop-any) [95.143312] Starting subtest: invalid-get-prop-any [95.144250] Subtest invalid-get-prop-any: SUCCESS (0.000s) [95.238723] [079/171] (902s left) kms_addfb_basic (invalid-set-prop) [95.420171] Starting subtest: invalid-set-prop [95.421049] Subtest invalid-set-prop: SUCCESS (0.000s) [95.534428] [080/171] (902s left) kms_addfb_basic (invalid-set-prop-any) [95.720277] Starting subtest: invalid-set-prop-any [95.721322] Subtest invalid-set-prop-any: SUCCESS (0.000s) [95.820602] [081/171] (902s left) kms_addfb_basic (no-handle) [95.903307] Starting subtest: no-handle [95.904317] Subtest no-handle: SUCCESS (0.000s) [96.086480] [082/171] (901s left) kms_addfb_basic (size-max) [96.204097] Starting subtest: size-max [96.204932] Subtest size-max: SUCCESS (0.000s) [96.382240] [083/171] (901s left) kms_addfb_basic (small-bo) [96.496562] Starting subtest: small-bo [96.497591] Subtest small-bo: SUCCESS (0.000s) [96.657291] [084/171] (901s left) kms_addfb_basic (tile-pitch-mismatch) [96.835262] Starting subtest: tile-pitch-mismatch [96.836269] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [96.925432] [085/171] (901s left) kms_addfb_basic (too-high) [97.048960] Starting subtest: too-high [97.049882] Subtest too-high: SUCCESS (0.001s) [97.211793] [086/171] (900s left) kms_addfb_basic (too-wide) [97.328999] Starting subtest: too-wide [97.330044] Subtest too-wide: SUCCESS (0.000s) [97.479400] [087/171] (900s left) kms_addfb_basic (unused-handle) [97.565165] Starting subtest: unused-handle [97.566188] Subtest unused-handle: SUCCESS (0.000s) [97.770130] [088/171] (900s left) kms_addfb_basic (unused-modifier) [97.853335] Starting subtest: unused-modifier [97.854324] Subtest unused-modifier: SUCCESS (0.000s) [98.049649] [089/171] (900s left) kms_addfb_basic (unused-offsets) [98.134321] Starting subtest: unused-offsets [98.135379] Subtest unused-offsets: SUCCESS (0.000s) [98.324789] [090/171] (899s left) kms_addfb_basic (unused-pitches) [98.408304] Starting subtest: unused-pitches [98.409336] Subtest unused-pitches: SUCCESS (0.000s) [98.611546] [091/171] (899s left) kms_busy (basic) [98.739347] Starting subtest: basic [98.740338] Starting dynamic subtest: flip [99.511209] Dynamic subtest flip: SUCCESS (0.770s) [99.520796] Starting dynamic subtest: modeset [103.219553] Dynamic subtest modeset: SUCCESS (3.708s) [103.220815] Subtest basic: SUCCESS (4.479s) [104.112054] [092/171] (894s left) kms_chamelium_hpd (dp-hpd-fast) [104.558545] Subtest dp-hpd-fast: SKIP (0.000s) [105.450590] [093/171] (892s left) kms_chamelium_edid (dp-edid-read) [105.903719] Subtest dp-edid-read: SKIP (0.000s) [106.798835] [094/171] (891s left) kms_chamelium_frames (dp-crc-fast) [107.214459] Subtest dp-crc-fast: SKIP (0.000s) [108.080986] [095/171] (890s left) kms_chamelium_hpd (hdmi-hpd-fast) [108.516762] Subtest hdmi-hpd-fast: SKIP (0.000s) [109.427652] [096/171] (888s left) kms_chamelium_edid (hdmi-edid-read) [109.859851] Subtest hdmi-edid-read: SKIP (0.000s) [110.772816] [097/171] (887s left) kms_chamelium_frames (hdmi-crc-fast) [111.202466] Subtest hdmi-crc-fast: SKIP (0.000s) [112.114356] [098/171] (886s left) kms_chamelium_hpd (vga-hpd-fast) [112.546062] Subtest vga-hpd-fast: SKIP (0.000s) [113.455258] [099/171] (884s left) kms_chamelium_edid (vga-edid-read) [113.878089] Subtest vga-edid-read: SKIP (0.000s) [114.736097] [100/171] (883s left) kms_prop_blob (basic) [114.830200] Starting subtest: basic [114.831329] Subtest basic: SUCCESS (0.000s) [114.897499] [101/171] (883s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [115.028600] Starting subtest: basic-busy-flip-before-cursor-atomic [115.030630] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [115.108691] [102/171] (883s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [115.237835] Starting subtest: basic-busy-flip-before-cursor-legacy [115.239678] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [115.324156] [103/171] (883s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [115.452634] Starting subtest: basic-flip-after-cursor-atomic [116.717482] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.264s) [117.659198] [104/171] (880s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [117.784996] Starting subtest: basic-flip-after-cursor-legacy [119.030926] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.245s) [119.962556] [105/171] (878s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [120.092790] Starting subtest: basic-flip-after-cursor-varying-size [121.341138] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.248s) [122.263365] [106/171] (876s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [122.391231] Starting subtest: basic-flip-before-cursor-atomic [123.672968] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.277s) [124.569891] [107/171] (873s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [124.701320] Starting subtest: basic-flip-before-cursor-legacy [125.976984] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.273s) [126.941395] [108/171] (871s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [127.073528] Starting subtest: basic-flip-before-cursor-varying-size [128.433511] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.356s) [129.370444] [109/171] (869s left) kms_flip (basic-flip-vs-dpms) [129.476853] Starting subtest: basic-flip-vs-dpms [129.481768] Starting dynamic subtest: A-eDP1 [132.310516] Dynamic subtest A-eDP1: SUCCESS (2.826s) [132.324079] Starting dynamic subtest: B-eDP1 [134.668855] Dynamic subtest B-eDP1: SUCCESS (2.357s) [134.678236] Starting dynamic subtest: C-eDP1 [137.041432] Dynamic subtest C-eDP1: SUCCESS (2.370s) [137.045058] Subtest basic-flip-vs-dpms: SUCCESS (7.569s) [137.947372] [110/171] (860s left) kms_flip (basic-flip-vs-modeset) [138.054802] Starting subtest: basic-flip-vs-modeset [138.057773] Starting dynamic subtest: A-eDP1 [140.877388] Dynamic subtest A-eDP1: SUCCESS (2.819s) [140.887659] Starting dynamic subtest: B-eDP1 [143.244425] Dynamic subtest B-eDP1: SUCCESS (2.366s) [143.254101] Starting dynamic subtest: C-eDP1 [145.611870] Dynamic subtest C-eDP1: SUCCESS (2.366s) [145.617201] Subtest basic-flip-vs-modeset: SUCCESS (7.564s) [146.521577] [111/171] (852s left) kms_flip (basic-flip-vs-wf_vblank) [146.631578] Starting subtest: basic-flip-vs-wf_vblank [146.636944] Starting dynamic subtest: A-eDP1 [148.921898] Dynamic subtest A-eDP1: SUCCESS (2.285s) [148.934743] Starting dynamic subtest: B-eDP1 [151.224708] Dynamic subtest B-eDP1: SUCCESS (2.300s) [151.234655] Starting dynamic subtest: C-eDP1 [153.530811] Dynamic subtest C-eDP1: SUCCESS (2.304s) [153.535031] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.903s) [154.846194] [112/171] (843s left) kms_flip (basic-plain-flip) [154.952333] Starting subtest: basic-plain-flip [154.955103] Starting dynamic subtest: A-eDP1 [156.964300] Dynamic subtest A-eDP1: SUCCESS (2.007s) [156.973939] Starting dynamic subtest: B-eDP1 [158.947486] Dynamic subtest B-eDP1: SUCCESS (1.979s) [158.984808] Starting dynamic subtest: C-eDP1 [161.003315] Dynamic subtest C-eDP1: SUCCESS (2.041s) [161.014422] Subtest basic-plain-flip: SUCCESS (6.061s) [162.334341] [113/171] (836s left) kms_force_connector_basic (force-connector-state) [162.454153] Starting subtest: force-connector-state [162.972518] Subtest force-connector-state: SUCCESS (0.516s) [164.131503] [114/171] (834s left) kms_force_connector_basic (force-edid) [164.255098] Starting subtest: force-edid [164.289601] Subtest force-edid: SUCCESS (0.035s) [164.440444] [115/171] (834s left) kms_force_connector_basic (force-load-detect) [164.552395] Starting subtest: force-load-detect [164.554415] Subtest force-load-detect: SKIP (0.000s) [164.702280] [116/171] (833s left) kms_force_connector_basic (prune-stale-modes) [164.819152] Starting subtest: prune-stale-modes [164.851052] Subtest prune-stale-modes: SUCCESS (0.032s) [165.004725] [117/171] (833s left) kms_frontbuffer_tracking (basic) [165.134110] Starting subtest: basic [167.936857] Subtest basic: SUCCESS (2.805s) [168.065837] [118/171] (830s left) kms_pipe_crc_basic (compare-crc-sanitycheck) [168.183217] Starting subtest: compare-crc-sanitycheck [168.186167] Starting dynamic subtest: pipe-A-eDP-1 [168.810783] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.626s) [168.821915] Starting dynamic subtest: pipe-B-eDP-1 [170.169444] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.350s) [170.181843] Starting dynamic subtest: pipe-C-eDP-1 [171.505917] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.340s) [171.507684] Subtest compare-crc-sanitycheck: SUCCESS (3.322s) [172.434785] [119/171] (826s left) kms_pipe_crc_basic (hang-read-crc) [172.561669] Starting subtest: hang-read-crc [172.564364] Starting dynamic subtest: pipe-A-eDP-1 [174.802559] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.237s) [174.825029] Starting dynamic subtest: pipe-B-eDP-1 [177.747659] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.938s) [177.763132] Starting dynamic subtest: pipe-C-eDP-1 [180.694993] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.944s) [180.696172] Subtest hang-read-crc: SUCCESS (8.134s) [181.692799] [120/171] (817s left) kms_pipe_crc_basic (nonblocking-crc) [181.824290] Starting subtest: nonblocking-crc [181.827666] Starting dynamic subtest: pipe-A-eDP-1 [182.625574] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.799s) [182.649447] Starting dynamic subtest: pipe-B-eDP-1 [184.221091] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.587s) [184.239423] Starting dynamic subtest: pipe-C-eDP-1 [185.799399] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.576s) [185.800435] Subtest nonblocking-crc: SUCCESS (3.974s) [186.716532] [121/171] (812s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [186.845525] Starting subtest: nonblocking-crc-frame-sequence [186.846523] Starting dynamic subtest: pipe-A-eDP-1 [187.644217] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.795s) [187.660834] Starting dynamic subtest: pipe-B-eDP-1 [189.213821] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.566s) [189.225901] Starting dynamic subtest: pipe-C-eDP-1 [190.793511] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.576s) [190.794673] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.946s) [191.708681] [122/171] (807s left) kms_pipe_crc_basic (read-crc) [191.842534] Starting subtest: read-crc [191.843572] Starting dynamic subtest: pipe-A-eDP-1 [192.585362] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.741s) [192.596790] Starting dynamic subtest: pipe-B-eDP-1 [194.072334] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.485s) [194.086624] Starting dynamic subtest: pipe-C-eDP-1 [195.581370] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.503s) [195.583240] Subtest read-crc: SUCCESS (3.737s) [196.503339] [123/171] (802s left) kms_pipe_crc_basic (read-crc-frame-sequence) [196.631444] Starting subtest: read-crc-frame-sequence [196.632464] Starting dynamic subtest: pipe-A-eDP-1 [197.377618] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.742s) [197.391832] Starting dynamic subtest: pipe-B-eDP-1 [198.874191] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.494s) [198.895816] Starting dynamic subtest: pipe-C-eDP-1 [200.328625] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.448s) [200.329536] Subtest read-crc-frame-sequence: SUCCESS (3.698s) [201.243423] [124/171] (797s left) kms_psr (primary_page_flip) [201.384400] Starting subtest: primary_page_flip [203.424277] Subtest primary_page_flip: SUCCESS (2.040s) [204.388956] [125/171] (794s left) kms_psr (cursor_plane_move) [204.521211] Starting subtest: cursor_plane_move [206.459936] Subtest cursor_plane_move: SUCCESS (1.938s) [207.388486] [126/171] (791s left) kms_psr (sprite_plane_onoff) [207.518524] Starting subtest: sprite_plane_onoff [209.539621] Subtest sprite_plane_onoff: SUCCESS (2.017s) [210.465826] [127/171] (788s left) kms_psr (primary_mmap_gtt) [210.597380] Starting subtest: primary_mmap_gtt [212.626756] Subtest primary_mmap_gtt: SUCCESS (2.026s) [213.531040] [128/171] (785s left) kms_setmode (basic-clone-single-crtc) [213.626614] Starting subtest: basic-clone-single-crtc [213.629185] Subtest basic-clone-single-crtc: SKIP (0.001s) [213.704205] [129/171] (785s left) i915_pm_backlight (basic-brightness) [213.842871] Starting subtest: basic-brightness [213.955263] Starting dynamic subtest: eDP-1 [214.566556] Dynamic subtest eDP-1: SUCCESS (0.612s) [214.569049] Subtest basic-brightness: SUCCESS (0.723s) [215.516775] [130/171] (783s left) i915_pm_rpm (basic-pci-d3-state) [217.258193] Starting subtest: basic-pci-d3-state [221.767632] Subtest basic-pci-d3-state: SUCCESS (4.522s) [221.922821] [131/171] (777s left) i915_pm_rpm (basic-rte) [222.013297] Starting subtest: basic-rte [228.168177] Subtest basic-rte: SUCCESS (6.154s) [233.467896] [132/171] (765s left) i915_pm_rps (basic-api) [233.564819] Starting subtest: basic-api [233.569596] Subtest basic-api: SUCCESS (0.005s) [233.637477] [133/171] (765s left) prime_self_import (basic-llseek-bad) [233.731924] Starting subtest: basic-llseek-bad [233.733136] Subtest basic-llseek-bad: SUCCESS (0.002s) [233.871778] [134/171] (765s left) prime_self_import (basic-llseek-size) [233.960637] Starting subtest: basic-llseek-size [233.963506] Subtest basic-llseek-size: SUCCESS (0.003s) [234.091139] [135/171] (764s left) prime_self_import (basic-with_fd_dup) [234.182626] Starting subtest: basic-with_fd_dup [234.185961] Subtest basic-with_fd_dup: SUCCESS (0.003s) [234.307516] [136/171] (764s left) prime_self_import (basic-with_one_bo) [234.397885] Starting subtest: basic-with_one_bo [234.401874] Subtest basic-with_one_bo: SUCCESS (0.004s) [234.501195] [137/171] (764s left) prime_self_import (basic-with_one_bo_two_files) [234.606054] Starting subtest: basic-with_one_bo_two_files [234.609112] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [234.716531] [138/171] (764s left) prime_self_import (basic-with_two_bos) [234.816991] Starting subtest: basic-with_two_bos [234.820491] Subtest basic-with_two_bos: SUCCESS (0.003s) [234.940859] [139/171] (764s left) prime_vgem (basic-fence-flip) [235.083126] Starting subtest: basic-fence-flip [235.567702] Subtest basic-fence-flip: SUCCESS (0.484s) [235.694037] [140/171] (763s left) prime_vgem (basic-fence-mmap) [235.817085] Starting subtest: basic-fence-mmap [235.847667] Subtest basic-fence-mmap: SUCCESS (0.031s) [235.973557] [141/171] (763s left) prime_vgem (basic-fence-read) [236.097723] Starting subtest: basic-fence-read [236.149648] Subtest basic-fence-read: SUCCESS (0.052s) [236.218559] [142/171] (763s left) prime_vgem (basic-gtt) [236.340708] Starting subtest: basic-gtt [236.355750] Subtest basic-gtt: SUCCESS (0.015s) [236.479692] [143/171] (762s left) prime_vgem (basic-read) [236.602518] Starting subtest: basic-read [236.626860] Subtest basic-read: SUCCESS (0.024s) [236.710041] [144/171] (762s left) prime_vgem (basic-write) [236.831457] Starting subtest: basic-write [236.858887] Subtest basic-write: SUCCESS (0.027s) [236.943075] [145/171] (762s left) prime_vgem (basic-userptr) [237.058149] Starting subtest: basic-userptr [237.067320] Subtest basic-userptr: SKIP (0.008s) [237.180217] [146/171] (762s left) vgem_basic (setversion) [237.301833] Starting subtest: setversion [237.303741] Subtest setversion: SUCCESS (0.000s) [237.365196] [147/171] (761s left) vgem_basic (create) [237.468212] Starting subtest: create [237.469303] Subtest create: SUCCESS (0.000s) [237.525525] [148/171] (761s left) vgem_basic (debugfs) [237.618140] Starting subtest: debugfs [237.619121] Subtest debugfs: SUCCESS (0.000s) [237.675891] [149/171] (761s left) vgem_basic (dmabuf-export) [237.768122] Starting subtest: dmabuf-export [237.771790] Subtest dmabuf-export: SUCCESS (0.002s) [237.842496] [150/171] (761s left) vgem_basic (dmabuf-fence) [237.935025] Starting subtest: dmabuf-fence [237.936039] Subtest dmabuf-fence: SUCCESS (0.001s) [237.992817] [151/171] (761s left) vgem_basic (dmabuf-fence-before) [238.101756] Starting subtest: dmabuf-fence-before [238.102838] Subtest dmabuf-fence-before: SUCCESS (0.001s) [238.166208] [152/171] (761s left) vgem_basic (dmabuf-mmap) [238.268375] Starting subtest: dmabuf-mmap [238.287295] Subtest dmabuf-mmap: SUCCESS (0.019s) [238.342959] [153/171] (761s left) vgem_basic (mmap) [238.451910] Starting subtest: mmap [238.464053] Subtest mmap: SUCCESS (0.012s) [238.510176] [154/171] (760s left) vgem_basic (second-client) [238.618576] Starting subtest: second-client [238.668191] Subtest second-client: SUCCESS (0.049s) [238.738558] [155/171] (760s left) vgem_basic (sysfs) [238.835225] Starting subtest: sysfs [238.836316] Subtest sysfs: SUCCESS (0.001s) [238.892456] [156/171] (760s left) core_hotunplug (unbind-rebind) [239.001958] Starting subtest: unbind-rebind [241.241894] Subtest unbind-rebind: SUCCESS (2.239s) [241.282841] [157/171] (758s left) vgem_basic (unload) [241.429527] Starting subtest: unload [270.304446] Subtest unload: SUCCESS (28.840s) [270.369026] [158/171] (729s left) i915_module_load (reload) [270.458163] Starting subtest: reload [273.372113] Subtest reload: SUCCESS (2.915s) [273.407443] [159/171] (726s left) gem_lmem_swapping (basic) [273.614330] Subtest basic: SKIP (0.000s) [273.667807] [160/171] (725s left) gem_lmem_swapping (parallel-random-engines) [273.764435] Subtest parallel-random-engines: SKIP (0.000s) [273.801103] [161/171] (725s left) gem_lmem_swapping (random-engines) [273.896931] Subtest random-engines: SKIP (0.000s) [273.935163] [162/171] (725s left) gem_lmem_swapping (verify-random) [274.030966] Subtest verify-random: SKIP (0.000s) [274.067603] [163/171] (725s left) i915_pm_rpm (module-reload) [276.687277] Starting subtest: module-reload [293.959341] Subtest module-reload: SUCCESS (17.264s) [293.973394] [164/171] (705s left) i915_selftest (live) [294.075552] Starting subtest: live [294.076482] Starting dynamic subtest: sanitycheck [295.712441] Dynamic subtest sanitycheck: SUCCESS (1.628s) [295.724998] Starting dynamic subtest: uncore [297.306623] Dynamic subtest uncore: SUCCESS (1.593s) [297.317827] Starting dynamic subtest: workarounds [299.107531] Dynamic subtest workarounds: SUCCESS (1.800s) [299.114691] Starting dynamic subtest: gt_engines [301.147303] Dynamic subtest gt_engines: SUCCESS (2.039s) [301.154781] Starting dynamic subtest: gt_timelines [310.777687] Dynamic subtest gt_timelines: SUCCESS (9.629s) [310.785096] Starting dynamic subtest: gt_contexts [312.490491] Dynamic subtest gt_contexts: SUCCESS (1.712s) [312.501731] Starting dynamic subtest: gt_lrc [316.678379] Dynamic subtest gt_lrc: SUCCESS (4.187s) [316.689705] Starting dynamic subtest: gt_mocs [318.388509] Dynamic subtest gt_mocs: SUCCESS (1.709s) [318.396072] Starting dynamic subtest: gt_pm [324.297498] Dynamic subtest gt_pm: SUCCESS (5.908s) [324.305086] Starting dynamic subtest: gt_heartbeat [326.082697] Dynamic subtest gt_heartbeat: SUCCESS (1.784s) [326.090429] Starting dynamic subtest: requests [333.501756] Dynamic subtest requests: SUCCESS (7.418s) [333.513101] Starting dynamic subtest: migrate [337.908680] Dynamic subtest migrate: SUCCESS (4.406s) [337.916413] Starting dynamic subtest: active [339.491591] Dynamic subtest active: SUCCESS (1.582s) [339.499372] Starting dynamic subtest: objects [342.239527] Dynamic subtest objects: SUCCESS (2.747s) [342.247076] Starting dynamic subtest: mman [347.270445] Dynamic subtest mman: SUCCESS (5.030s) [347.281832] Starting dynamic subtest: dmabuf [348.908614] Dynamic subtest dmabuf: SUCCESS (1.637s) [348.920053] Starting dynamic subtest: vma [350.500390] Dynamic subtest vma: SUCCESS (1.591s) [350.507786] Starting dynamic subtest: coherency [353.207543] Dynamic subtest coherency: SUCCESS (2.706s) [353.215233] Starting dynamic subtest: gtt [363.437689] Dynamic subtest gtt: SUCCESS (10.229s) [363.448973] Starting dynamic subtest: gem [365.336444] Dynamic subtest gem: SUCCESS (1.898s) [365.343937] Starting dynamic subtest: evict [368.424508] Dynamic subtest evict: SUCCESS (3.087s) [368.432149] Starting dynamic subtest: hugepages [374.432677] Dynamic subtest hugepages: SUCCESS (6.007s) [374.444069] Starting dynamic subtest: gem_contexts [388.826781] Dynamic subtest gem_contexts: SUCCESS (14.393s) [388.834710] Starting dynamic subtest: client [390.437459] Dynamic subtest client: SUCCESS (1.610s) [390.445350] Starting dynamic subtest: gem_migrate [392.044319] Dynamic subtest gem_migrate: SUCCESS (1.606s) [392.052097] Starting dynamic subtest: reset [394.348667] Dynamic subtest reset: SUCCESS (2.303s) [394.360157] Starting dynamic subtest: memory_region [395.935698] Dynamic subtest memory_region: SUCCESS (1.586s) [395.943496] Starting dynamic subtest: hangcheck [420.056456] Dynamic subtest hangcheck: SUCCESS (24.120s) [420.064346] Starting dynamic subtest: execlists [429.414747] Dynamic subtest execlists: SUCCESS (9.357s) [429.426289] Starting dynamic subtest: ring_submission [431.011784] Dynamic subtest ring_submission: SUCCESS (1.596s) [431.023261] Starting dynamic subtest: perf [432.816726] Dynamic subtest perf: SUCCESS (1.804s) [432.824692] Starting dynamic subtest: slpc [434.480749] Dynamic subtest slpc: SUCCESS (1.663s) [434.492223] Starting dynamic subtest: guc [436.111447] Dynamic subtest guc: SUCCESS (1.630s) [436.119144] Starting dynamic subtest: guc_multi_lrc [437.747404] Dynamic subtest guc_multi_lrc: SUCCESS (1.635s) [437.755209] Starting dynamic subtest: guc_hang [439.321843] Dynamic subtest guc_hang: SUCCESS (1.574s) [439.329727] Starting dynamic subtest: late_gt_pm [440.989636] Dynamic subtest late_gt_pm: SUCCESS (1.667s) [440.990683] Subtest live: SUCCESS (146.906s) [441.055304] [165/171] (558s left) dmabuf (all-tests) [441.153786] Starting subtest: all-tests [441.155707] Starting dynamic subtest: sanitycheck [441.182369] Dynamic subtest sanitycheck: SUCCESS (0.028s) [441.189715] Starting dynamic subtest: dma_fence [441.321369] Dynamic subtest dma_fence: SUCCESS (0.138s) [441.329854] Starting dynamic subtest: dma_fence_chain [457.036029] Dynamic subtest dma_fence_chain: SUCCESS (15.705s) [457.043230] Starting dynamic subtest: dma_fence_unwrap [457.052366] Dynamic subtest dma_fence_unwrap: SUCCESS (0.024s) [457.061337] Starting dynamic subtest: dma_resv [457.074136] Dynamic subtest dma_resv: SUCCESS (0.021s) [457.075024] Subtest all-tests: SUCCESS (15.921s) [457.093453] [166/171] (542s left) i915_suspend (basic-s2idle-without-i915) [457.186417] Starting subtest: basic-s2idle-without-i915 [476.920402] Subtest basic-s2idle-without-i915: SUCCESS (3.979s) [479.279941] [167/171] (538s left) i915_suspend (basic-s3-without-i915) [479.413614] Starting subtest: basic-s3-without-i915 [499.781948] Subtest basic-s3-without-i915: SUCCESS (3.930s) [502.172312] [168/171] (533s left) gem_exec_suspend (basic-s0) [502.293668] Starting subtest: basic-S0 [502.296953] Starting dynamic subtest: smem [520.918242] Dynamic subtest smem: SUCCESS (3.207s) [520.919212] Subtest basic-S0: SUCCESS (3.208s) [523.709091] [169/171] (530s left) gem_exec_suspend (basic-s3) [523.822600] Starting subtest: basic-S3 [523.823717] Starting dynamic subtest: smem [543.750618] Dynamic subtest smem: SUCCESS (4.044s) [543.751528] Subtest basic-S3: SUCCESS (4.046s) [546.411712] [170/171] (526s left) kms_chamelium_hpd (common-hpd-after-suspend) [546.862338] Subtest common-hpd-after-suspend: SKIP (0.000s) [547.785395] [171/171] (524s left) kms_pipe_crc_basic (suspend-read-crc) [547.919414] Starting subtest: suspend-read-crc [547.920463] Starting dynamic subtest: pipe-A-eDP-1 [567.715136] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.101s) [567.727787] Starting dynamic subtest: pipe-B-eDP-1 [587.726266] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.790s) [587.737881] Starting dynamic subtest: pipe-C-eDP-1 [608.004187] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.095s) [608.005182] Subtest suspend-read-crc: SUCCESS (10.994s) [611.881439] Closing watchdogs Done.