[40.023306] Initializing watchdogs [40.023432] /dev/watchdog0 [40.030445] [001/170] (960s left) i915_module_load (load) [40.137765] Starting subtest: load [41.183747] Subtest load: SUCCESS (1.043s) [41.211827] [002/170] (959s left) core_auth (basic-auth) [41.337234] Starting subtest: basic-auth [41.338689] Subtest basic-auth: SUCCESS (0.001s) [41.422443] [003/170] (959s left) debugfs_test (read_all_entries) [41.595333] Starting subtest: read_all_entries [41.674313] Subtest read_all_entries: SUCCESS (0.079s) [41.775262] [004/170] (958s left) debugfs_test (basic-hwmon) [41.877110] Starting subtest: basic-hwmon [41.879029] Subtest basic-hwmon: SKIP (0.000s) [41.998140] [005/170] (958s left) fbdev (eof) [42.105339] Starting subtest: eof [42.106338] Subtest eof: SUCCESS (0.000s) [42.166932] [006/170] (958s left) fbdev (info) [42.287478] Starting subtest: info [42.288660] Subtest info: SUCCESS (0.000s) [42.365072] [007/170] (958s left) fbdev (nullptr) [42.477658] Starting subtest: nullptr [42.478674] Subtest nullptr: SUCCESS (0.000s) [42.530950] [008/170] (958s left) fbdev (read) [42.627778] Starting subtest: read [43.473707] Subtest read: SUCCESS (0.837s) [43.516604] [009/170] (957s left) fbdev (write) [43.628644] Starting subtest: write [44.054526] Subtest write: SUCCESS (0.417s) [44.133776] [010/170] (956s left) gem_basic (bad-close) [44.235420] Starting subtest: bad-close [44.237417] Subtest bad-close: SUCCESS (0.000s) [44.295315] [011/170] (956s left) gem_basic (create-close) [44.394780] Starting subtest: create-close [44.396691] Subtest create-close: SUCCESS (0.000s) [44.520943] [012/170] (956s left) gem_basic (create-fd-close) [44.624130] Starting subtest: create-fd-close [44.626063] Subtest create-fd-close: SUCCESS (0.000s) [44.744306] [013/170] (956s left) gem_busy (busy@all-engines) [44.863024] Starting subtest: busy [44.864129] Starting dynamic subtest: all-engines [44.921088] Dynamic subtest all-engines: SUCCESS (0.058s) [44.921980] Subtest busy: SUCCESS (0.058s) [45.356497] [014/170] (955s left) gem_close_race (basic-process) [45.478603] Starting subtest: basic-process [45.542587] Subtest basic-process: SUCCESS (0.064s) [45.766547] [015/170] (955s left) gem_close_race (basic-threads) [45.892951] Starting subtest: basic-threads [47.048051] Subtest basic-threads: SUCCESS (1.155s) [47.308907] [016/170] (953s left) gem_ctx_create (basic) [47.427641] Starting subtest: basic [47.428674] Subtest basic: SUCCESS (0.000s) [47.628229] [017/170] (953s left) gem_ctx_create (basic-files) [47.744122] Starting subtest: basic-files [49.766374] Subtest basic-files: SUCCESS (2.022s) [50.074166] [018/170] (950s left) gem_ctx_exec (basic) [50.182686] Starting subtest: basic [50.186835] Subtest basic: SUCCESS (0.005s) [50.263792] [019/170] (950s left) gem_exec_basic (basic) [50.384300] Starting subtest: basic [50.385424] Starting dynamic subtest: rcs0-smem [50.386544] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [50.394907] Starting dynamic subtest: bcs0-smem [50.395927] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [50.402958] Starting dynamic subtest: vcs0-smem [50.404286] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [50.410973] Starting dynamic subtest: vecs0-smem [50.412020] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [50.413024] Subtest basic: SUCCESS (0.005s) [50.615058] [020/170] (950s left) gem_exec_create (basic) [50.731830] Starting subtest: basic [50.732972] Starting dynamic subtest: smem [52.782688] Dynamic subtest smem: SUCCESS (2.051s) [52.784043] Subtest basic: SUCCESS (2.051s) [53.014810] [021/170] (947s left) gem_exec_fence (basic-busy) [53.346424] Starting subtest: basic-busy [53.349723] Starting dynamic subtest: rcs0 [53.397224] Dynamic subtest rcs0: SUCCESS (0.050s) [53.424943] Starting dynamic subtest: bcs0 [53.443433] Dynamic subtest bcs0: SUCCESS (0.047s) [53.452762] Starting dynamic subtest: vcs0 [53.464370] Dynamic subtest vcs0: SUCCESS (0.021s) [53.472702] Starting dynamic subtest: vecs0 [53.490297] Dynamic subtest vecs0: SUCCESS (0.026s) [53.491344] Subtest basic-busy: SUCCESS (0.146s) [54.283471] [022/170] (946s left) gem_exec_fence (basic-wait) [54.568436] Starting subtest: basic-wait [54.569755] Starting dynamic subtest: rcs0 [54.596193] Dynamic subtest rcs0: SUCCESS (0.028s) [54.603417] Starting dynamic subtest: bcs0 [54.625415] Dynamic subtest bcs0: SUCCESS (0.029s) [54.633179] Starting dynamic subtest: vcs0 [54.651392] Dynamic subtest vcs0: SUCCESS (0.026s) [54.658735] Starting dynamic subtest: vecs0 [54.674225] Dynamic subtest vecs0: SUCCESS (0.023s) [54.675448] Subtest basic-wait: SUCCESS (0.106s) [55.324313] [023/170] (945s left) gem_exec_fence (basic-await) [55.600505] Starting subtest: basic-await [55.602109] Starting dynamic subtest: rcs0 [55.699399] Dynamic subtest rcs0: SUCCESS (0.099s) [55.709614] Starting dynamic subtest: bcs0 [55.797229] Dynamic subtest bcs0: SUCCESS (0.097s) [55.805605] Starting dynamic subtest: vcs0 [55.895506] Dynamic subtest vcs0: SUCCESS (0.098s) [55.904884] Starting dynamic subtest: vecs0 [55.990633] Dynamic subtest vecs0: SUCCESS (0.095s) [55.991520] Subtest basic-await: SUCCESS (0.390s) [56.668923] [024/170] (944s left) gem_exec_fence (nb-await) [56.928649] Starting subtest: nb-await [56.930290] Starting dynamic subtest: rcs0 [57.001882] Dynamic subtest rcs0: SUCCESS (0.073s) [57.021434] Starting dynamic subtest: bcs0 [57.086435] Dynamic subtest bcs0: SUCCESS (0.078s) [57.106660] Starting dynamic subtest: vcs0 [57.163765] Dynamic subtest vcs0: SUCCESS (0.082s) [57.183816] Starting dynamic subtest: vecs0 [57.243452] Dynamic subtest vecs0: SUCCESS (0.077s) [57.247290] Subtest nb-await: SUCCESS (0.313s) [57.980767] [025/170] (943s left) gem_exec_gttfill (basic) [58.107017] Starting subtest: basic [67.453018] Subtest basic: SUCCESS (9.338s) [67.635534] [026/170] (933s left) gem_exec_parallel (engines) [67.811830] Starting subtest: engines [67.812920] Starting dynamic subtest: basic [70.128353] Dynamic subtest basic: SUCCESS (2.308s) [70.135902] Starting dynamic subtest: contexts [73.031563] Dynamic subtest contexts: SUCCESS (2.903s) [73.039800] Starting dynamic subtest: fds [76.266248] Dynamic subtest fds: SUCCESS (3.243s) [76.275370] Starting dynamic subtest: userptr [78.677512] Dynamic subtest userptr: SUCCESS (2.411s) [78.678911] Subtest engines: SUCCESS (10.865s) [78.927841] [027/170] (922s left) gem_exec_store (basic) [79.049383] Starting subtest: basic [79.102539] Subtest basic: SUCCESS (0.053s) [79.319202] [028/170] (921s left) gem_flink_basic (bad-flink) [79.426200] Starting subtest: bad-flink [79.427330] Subtest bad-flink: SUCCESS (0.000s) [79.522937] [029/170] (921s left) gem_flink_basic (bad-open) [79.627136] Starting subtest: bad-open [79.628200] Subtest bad-open: SUCCESS (0.000s) [79.689539] [030/170] (921s left) gem_flink_basic (basic) [79.795113] Starting subtest: basic [79.796177] Subtest basic: SUCCESS (0.000s) [79.896750] [031/170] (921s left) gem_flink_basic (double-flink) [80.003189] Starting subtest: double-flink [80.004128] Subtest double-flink: SUCCESS (0.000s) [80.101951] [032/170] (921s left) gem_flink_basic (flink-lifetime) [80.208527] Starting subtest: flink-lifetime [80.209472] Subtest flink-lifetime: SUCCESS (0.001s) [80.314204] [033/170] (920s left) gem_huc_copy (huc-copy) [80.428672] Starting subtest: huc-copy [80.430796] Subtest huc-copy: SKIP (0.000s) [80.566283] [034/170] (920s left) gem_linear_blits (basic) [80.811841] Starting subtest: basic [80.820763] Subtest basic: SUCCESS (0.009s) [80.973739] [035/170] (920s left) gem_mmap (basic) [81.133577] Starting subtest: basic [81.138354] Subtest basic: SUCCESS (0.000s) [81.209738] [036/170] (920s left) gem_mmap_gtt (basic) [81.315875] Starting subtest: basic [81.317104] Subtest basic: SUCCESS (0.001s) [81.417206] [037/170] (919s left) gem_render_linear_blits (basic) [81.531402] Starting subtest: basic [81.571711] Subtest basic: SUCCESS (0.040s) [81.615628] [038/170] (919s left) gem_render_tiled_blits (basic) [81.730630] Starting subtest: basic [81.776460] Subtest basic: SUCCESS (0.045s) [81.832578] [039/170] (919s left) gem_ringfill (basic-all) [82.137604] Starting subtest: basic-all [83.303055] Subtest basic-all: SUCCESS (1.158s) [83.390251] [040/170] (917s left) gem_softpin (allocator-basic) [83.528375] Starting subtest: allocator-basic [83.664657] Subtest allocator-basic: SUCCESS (0.128s) [83.808806] [041/170] (917s left) gem_softpin (allocator-basic-reserve) [83.923094] Starting subtest: allocator-basic-reserve [84.063681] Subtest allocator-basic-reserve: SUCCESS (0.132s) [84.206443] [042/170] (917s left) gem_softpin (safe-alignment) [84.317503] Starting subtest: safe-alignment [84.332160] Subtest safe-alignment: SUCCESS (0.015s) [84.478060] [043/170] (916s left) gem_sync (basic-all) [84.598730] Starting subtest: basic-all [86.764428] Subtest basic-all: SUCCESS (2.166s) [86.991216] [044/170] (914s left) gem_sync (basic-each) [87.114455] Starting subtest: basic-each [89.280477] Subtest basic-each: SUCCESS (2.166s) [89.570540] [045/170] (911s left) gem_tiled_blits (basic) [89.827377] Starting subtest: basic [89.853374] Subtest basic: SUCCESS (0.026s) [89.998808] [046/170] (911s left) gem_tiled_fence_blits (basic) [90.299925] Starting subtest: basic [90.313097] Subtest basic: SUCCESS (0.013s) [90.462682] [047/170] (911s left) gem_tiled_pread_basic [90.843188] [048/170] (910s left) gem_wait (busy@all-engines) [90.971748] Starting subtest: busy [90.972774] Starting dynamic subtest: all-engines [91.506358] Dynamic subtest all-engines: SUCCESS (0.535s) [91.507353] Subtest busy: SUCCESS (0.535s) [91.745718] [049/170] (909s left) gem_wait (wait@all-engines) [91.873290] Starting subtest: wait [91.874357] Starting dynamic subtest: all-engines [92.937943] Dynamic subtest all-engines: SUCCESS (1.061s) [92.941694] Subtest wait: SUCCESS (1.062s) [93.220151] [050/170] (908s left) i915_getparams_basic (basic-eu-total) [93.328400] Starting subtest: basic-eu-total [93.330473] Subtest basic-eu-total: SUCCESS (0.000s) [93.419800] [051/170] (908s left) i915_getparams_basic (basic-subslice-total) [93.525230] Starting subtest: basic-subslice-total [93.527424] Subtest basic-subslice-total: SUCCESS (0.000s) [93.626199] [052/170] (907s left) i915_hangman (error-state-basic) [93.749255] Starting subtest: error-state-basic [93.760208] Subtest error-state-basic: SUCCESS (0.011s) [93.851704] [053/170] (907s left) i915_pciid [94.053490] [054/170] (907s left) kms_addfb_basic (addfb25-bad-modifier) [94.161062] Starting subtest: addfb25-bad-modifier [94.162138] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [94.318368] [055/170] (907s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [94.426180] Starting subtest: addfb25-framebuffer-vs-set-tiling [94.427068] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [94.572124] [056/170] (907s left) kms_addfb_basic (addfb25-modifier-no-flag) [94.681177] Starting subtest: addfb25-modifier-no-flag [94.682101] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [94.827938] [057/170] (906s left) kms_addfb_basic (addfb25-x-tiled-legacy) [94.930095] Starting subtest: addfb25-x-tiled-legacy [94.931116] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [95.069330] [058/170] (906s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [95.173396] Starting subtest: addfb25-x-tiled-mismatch-legacy [95.174328] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [95.320807] [059/170] (906s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [95.459600] Starting subtest: addfb25-yf-tiled-legacy [95.460581] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [95.553140] [060/170] (906s left) kms_addfb_basic (addfb25-y-tiled-legacy) [95.695087] Starting subtest: addfb25-y-tiled-legacy [95.696044] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [95.793865] [061/170] (905s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [95.937917] Starting subtest: addfb25-y-tiled-small-legacy [95.938956] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [96.046825] [062/170] (905s left) kms_addfb_basic (bad-pitch-0) [96.145091] Starting subtest: bad-pitch-0 [96.146077] Subtest bad-pitch-0: SUCCESS (0.000s) [96.281638] [063/170] (905s left) kms_addfb_basic (bad-pitch-1024) [96.380317] Starting subtest: bad-pitch-1024 [96.381388] Subtest bad-pitch-1024: SUCCESS (0.000s) [96.511374] [064/170] (905s left) kms_addfb_basic (bad-pitch-128) [96.609181] Starting subtest: bad-pitch-128 [96.610102] Subtest bad-pitch-128: SUCCESS (0.000s) [96.742398] [065/170] (905s left) kms_addfb_basic (bad-pitch-256) [96.837712] Starting subtest: bad-pitch-256 [96.838774] Subtest bad-pitch-256: SUCCESS (0.000s) [96.978354] [066/170] (904s left) kms_addfb_basic (bad-pitch-32) [97.075267] Starting subtest: bad-pitch-32 [97.076167] Subtest bad-pitch-32: SUCCESS (0.000s) [97.212408] [067/170] (904s left) kms_addfb_basic (bad-pitch-63) [97.313155] Starting subtest: bad-pitch-63 [97.314173] Subtest bad-pitch-63: SUCCESS (0.000s) [97.461850] [068/170] (904s left) kms_addfb_basic (bad-pitch-65536) [97.556246] Starting subtest: bad-pitch-65536 [97.557486] Subtest bad-pitch-65536: SUCCESS (0.000s) [97.687264] [069/170] (904s left) kms_addfb_basic (bad-pitch-999) [97.784383] Starting subtest: bad-pitch-999 [97.785348] Subtest bad-pitch-999: SUCCESS (0.000s) [97.917872] [070/170] (904s left) kms_addfb_basic (basic) [98.013991] Starting subtest: basic [98.015085] Subtest basic: SUCCESS (0.000s) [98.143901] [071/170] (903s left) kms_addfb_basic (basic-x-tiled-legacy) [98.239310] Starting subtest: basic-x-tiled-legacy [98.240162] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [98.375685] [072/170] (903s left) kms_addfb_basic (basic-y-tiled-legacy) [98.473023] Starting subtest: basic-y-tiled-legacy [98.473859] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [98.606148] [073/170] (903s left) kms_addfb_basic (bo-too-small) [98.737186] Starting subtest: bo-too-small [98.738129] Subtest bo-too-small: SUCCESS (0.000s) [98.834517] [074/170] (903s left) kms_addfb_basic (bo-too-small-due-to-tiling) [98.965749] Starting subtest: bo-too-small-due-to-tiling [98.966827] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [99.068629] [075/170] (902s left) kms_addfb_basic (clobberred-modifier) [99.161733] Starting subtest: clobberred-modifier [99.162956] Subtest clobberred-modifier: SUCCESS (0.000s) [99.283022] [076/170] (902s left) kms_addfb_basic (framebuffer-vs-set-tiling) [99.380064] Starting subtest: framebuffer-vs-set-tiling [99.380935] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [99.524574] [077/170] (902s left) kms_addfb_basic (invalid-get-prop) [99.620426] Starting subtest: invalid-get-prop [99.621395] Subtest invalid-get-prop: SUCCESS (0.000s) [99.763917] [078/170] (902s left) kms_addfb_basic (invalid-get-prop-any) [99.858467] Starting subtest: invalid-get-prop-any [99.859341] Subtest invalid-get-prop-any: SUCCESS (0.000s) [99.992148] [079/170] (902s left) kms_addfb_basic (invalid-set-prop) [100.087812] Starting subtest: invalid-set-prop [100.088802] Subtest invalid-set-prop: SUCCESS (0.000s) [100.214016] [080/170] (901s left) kms_addfb_basic (invalid-set-prop-any) [100.309339] Starting subtest: invalid-set-prop-any [100.310217] Subtest invalid-set-prop-any: SUCCESS (0.000s) [100.454183] [081/170] (901s left) kms_addfb_basic (no-handle) [100.549317] Starting subtest: no-handle [100.550474] Subtest no-handle: SUCCESS (0.000s) [100.689721] [082/170] (901s left) kms_addfb_basic (size-max) [100.826254] Starting subtest: size-max [100.827202] Subtest size-max: SUCCESS (0.000s) [100.921931] [083/170] (901s left) kms_addfb_basic (small-bo) [101.056318] Starting subtest: small-bo [101.057195] Subtest small-bo: SUCCESS (0.000s) [101.155217] [084/170] (901s left) kms_addfb_basic (tile-pitch-mismatch) [101.249528] Starting subtest: tile-pitch-mismatch [101.250574] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [101.377505] [085/170] (900s left) kms_addfb_basic (too-high) [101.509237] Starting subtest: too-high [101.510184] Subtest too-high: SUCCESS (0.001s) [101.609759] [086/170] (900s left) kms_addfb_basic (too-wide) [101.738968] Starting subtest: too-wide [101.739953] Subtest too-wide: SUCCESS (0.000s) [101.834145] [087/170] (900s left) kms_addfb_basic (unused-handle) [101.935508] Starting subtest: unused-handle [101.936534] Subtest unused-handle: SUCCESS (0.000s) [102.065963] [088/170] (900s left) kms_addfb_basic (unused-modifier) [102.162188] Starting subtest: unused-modifier [102.163225] Subtest unused-modifier: SUCCESS (0.000s) [102.296959] [089/170] (899s left) kms_addfb_basic (unused-offsets) [102.392819] Starting subtest: unused-offsets [102.393832] Subtest unused-offsets: SUCCESS (0.000s) [102.535762] [090/170] (899s left) kms_addfb_basic (unused-pitches) [102.631413] Starting subtest: unused-pitches [102.632394] Subtest unused-pitches: SUCCESS (0.000s) [102.758162] [091/170] (899s left) kms_busy (basic) [102.896235] Starting subtest: basic [102.897208] Starting dynamic subtest: flip [103.855718] Dynamic subtest flip: SUCCESS (0.956s) [103.873242] Starting dynamic subtest: modeset [107.625181] Dynamic subtest modeset: SUCCESS (3.769s) [107.626665] Subtest basic: SUCCESS (4.728s) [108.546117] [092/170] (893s left) kms_chamelium_hpd (dp-hpd-fast) [108.987453] Subtest dp-hpd-fast: SKIP (0.000s) [109.910057] [093/170] (892s left) kms_chamelium_edid (dp-edid-read) [110.361436] Subtest dp-edid-read: SKIP (0.000s) [111.253149] [094/170] (891s left) kms_chamelium_frames (dp-crc-fast) [111.707104] Subtest dp-crc-fast: SKIP (0.000s) [112.595646] [095/170] (889s left) kms_chamelium_hpd (hdmi-hpd-fast) [113.053636] Subtest hdmi-hpd-fast: SKIP (0.000s) [113.942637] [096/170] (888s left) kms_chamelium_edid (hdmi-edid-read) [114.395495] Subtest hdmi-edid-read: SKIP (0.000s) [115.282623] [097/170] (887s left) kms_chamelium_frames (hdmi-crc-fast) [115.738857] Subtest hdmi-crc-fast: SKIP (0.000s) [116.630934] [098/170] (885s left) kms_chamelium_hpd (vga-hpd-fast) [117.064547] Subtest vga-hpd-fast: SKIP (0.000s) [117.972250] [099/170] (884s left) kms_chamelium_edid (vga-edid-read) [118.428485] Subtest vga-edid-read: SKIP (0.000s) [119.316442] [100/170] (883s left) kms_prop_blob (basic) [119.430077] Starting subtest: basic [119.430962] Subtest basic: SUCCESS (0.000s) [119.491820] [101/170] (883s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [119.635066] Starting subtest: basic-busy-flip-before-cursor-atomic [119.636904] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [119.721792] [102/170] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [119.864328] Starting subtest: basic-busy-flip-before-cursor-legacy [119.866296] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [119.949140] [103/170] (882s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [120.097160] Starting subtest: basic-flip-after-cursor-atomic [121.356706] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.257s) [122.301571] [104/170] (880s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [122.443091] Starting subtest: basic-flip-after-cursor-legacy [123.718313] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.271s) [124.614245] [105/170] (878s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [124.758110] Starting subtest: basic-flip-after-cursor-varying-size [126.046775] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.285s) [126.976739] [106/170] (875s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [127.119604] Starting subtest: basic-flip-before-cursor-atomic [128.460349] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.337s) [129.410194] [107/170] (873s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [129.552428] Starting subtest: basic-flip-before-cursor-legacy [130.900527] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.348s) [131.843127] [108/170] (870s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [131.987271] Starting subtest: basic-flip-before-cursor-varying-size [133.240675] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.253s) [134.216246] [109/170] (868s left) kms_flip (basic-flip-vs-dpms) [134.338083] Starting subtest: basic-flip-vs-dpms [134.342215] Starting dynamic subtest: A-eDP1 [137.204046] Dynamic subtest A-eDP1: SUCCESS (2.860s) [137.220064] Starting dynamic subtest: B-eDP1 [139.568696] Dynamic subtest B-eDP1: SUCCESS (2.363s) [139.579397] Starting dynamic subtest: C-eDP1 [141.934130] Dynamic subtest C-eDP1: SUCCESS (2.366s) [141.937625] Subtest basic-flip-vs-dpms: SUCCESS (7.600s) [142.843582] [110/170] (859s left) kms_flip (basic-flip-vs-modeset) [142.964091] Starting subtest: basic-flip-vs-modeset [142.968266] Starting dynamic subtest: A-eDP1 [145.770444] Dynamic subtest A-eDP1: SUCCESS (2.801s) [145.786893] Starting dynamic subtest: B-eDP1 [148.145252] Dynamic subtest B-eDP1: SUCCESS (2.373s) [148.155338] Starting dynamic subtest: C-eDP1 [150.511309] Dynamic subtest C-eDP1: SUCCESS (2.366s) [150.514598] Subtest basic-flip-vs-modeset: SUCCESS (7.551s) [151.426099] [111/170] (851s left) kms_flip (basic-flip-vs-wf_vblank) [151.547806] Starting subtest: basic-flip-vs-wf_vblank [151.552194] Starting dynamic subtest: A-eDP1 [153.819263] Dynamic subtest A-eDP1: SUCCESS (2.267s) [153.831105] Starting dynamic subtest: B-eDP1 [156.136431] Dynamic subtest B-eDP1: SUCCESS (2.303s) [156.156253] Starting dynamic subtest: C-eDP1 [158.441507] Dynamic subtest C-eDP1: SUCCESS (2.299s) [158.447110] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.899s) [159.735392] [112/170] (843s left) kms_flip (basic-plain-flip) [159.858584] Starting subtest: basic-plain-flip [159.863101] Starting dynamic subtest: A-eDP1 [161.862442] Dynamic subtest A-eDP1: SUCCESS (1.994s) [161.909800] Starting dynamic subtest: B-eDP1 [163.903690] Dynamic subtest B-eDP1: SUCCESS (2.038s) [163.914820] Starting dynamic subtest: C-eDP1 [165.900563] Dynamic subtest C-eDP1: SUCCESS (1.988s) [165.911187] Subtest basic-plain-flip: SUCCESS (6.052s) [167.233350] [113/170] (835s left) kms_force_connector_basic (force-connector-state) [167.370170] Starting subtest: force-connector-state [167.979137] Subtest force-connector-state: SUCCESS (0.611s) [169.021470] [114/170] (833s left) kms_force_connector_basic (force-edid) [169.151607] Starting subtest: force-edid [169.183338] Subtest force-edid: SUCCESS (0.033s) [169.340853] [115/170] (833s left) kms_force_connector_basic (force-load-detect) [169.469300] Starting subtest: force-load-detect [169.471311] Subtest force-load-detect: SKIP (0.000s) [169.618842] [116/170] (833s left) kms_force_connector_basic (prune-stale-modes) [169.747197] Starting subtest: prune-stale-modes [169.782745] Subtest prune-stale-modes: SUCCESS (0.035s) [169.974379] [117/170] (832s left) kms_frontbuffer_tracking (basic) [170.118015] Starting subtest: basic [172.979453] Subtest basic: SUCCESS (2.861s) [173.103387] [118/170] (829s left) kms_pipe_crc_basic (compare-crc-sanitycheck) [173.240796] Starting subtest: compare-crc-sanitycheck [173.241897] Starting dynamic subtest: pipe-A-eDP-1 [173.870914] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.628s) [173.882120] Starting dynamic subtest: pipe-B-eDP-1 [175.189263] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.316s) [175.201844] Starting dynamic subtest: pipe-C-eDP-1 [176.510483] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.317s) [176.511421] Subtest compare-crc-sanitycheck: SUCCESS (3.271s) [177.459752] [119/170] (825s left) kms_pipe_crc_basic (hang-read-crc) [177.594836] Starting subtest: hang-read-crc [177.595897] Starting dynamic subtest: pipe-A-eDP-1 [179.848114] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.247s) [179.868471] Starting dynamic subtest: pipe-B-eDP-1 [182.746906] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.897s) [182.759690] Starting dynamic subtest: pipe-C-eDP-1 [185.674720] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.919s) [185.676630] Subtest hang-read-crc: SUCCESS (8.077s) [186.607236] [120/170] (816s left) kms_pipe_crc_basic (nonblocking-crc) [186.751462] Starting subtest: nonblocking-crc [186.752486] Starting dynamic subtest: pipe-A-eDP-1 [187.548796] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.796s) [187.565389] Starting dynamic subtest: pipe-B-eDP-1 [189.093418] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.538s) [189.104471] Starting dynamic subtest: pipe-C-eDP-1 [190.631799] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.531s) [190.633738] Subtest nonblocking-crc: SUCCESS (3.878s) [191.552868] [121/170] (811s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [191.697050] Starting subtest: nonblocking-crc-frame-sequence [191.700382] Starting dynamic subtest: pipe-A-eDP-1 [192.478546] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.779s) [192.498787] Starting dynamic subtest: pipe-B-eDP-1 [194.020472] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.532s) [194.031571] Starting dynamic subtest: pipe-C-eDP-1 [195.566338] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.543s) [195.567512] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.867s) [196.482587] [122/170] (806s left) kms_pipe_crc_basic (read-crc) [196.632428] Starting subtest: read-crc [196.633460] Starting dynamic subtest: pipe-A-eDP-1 [197.367303] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.730s) [197.388115] Starting dynamic subtest: pipe-B-eDP-1 [198.846992] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.471s) [198.861651] Starting dynamic subtest: pipe-C-eDP-1 [200.306425] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.457s) [200.307797] Subtest read-crc: SUCCESS (3.675s) [201.213898] [123/170] (801s left) kms_pipe_crc_basic (read-crc-frame-sequence) [201.359595] Starting subtest: read-crc-frame-sequence [201.360712] Starting dynamic subtest: pipe-A-eDP-1 [202.130480] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.767s) [202.155153] Starting dynamic subtest: pipe-B-eDP-1 [203.569913] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.432s) [203.589189] Starting dynamic subtest: pipe-C-eDP-1 [205.040113] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.460s) [205.041888] Subtest read-crc-frame-sequence: SUCCESS (3.677s) [205.953204] [124/170] (797s left) kms_psr (primary_page_flip) [206.100368] Starting subtest: primary_page_flip [208.089619] Subtest primary_page_flip: SUCCESS (1.985s) [209.024842] [125/170] (794s left) kms_psr (cursor_plane_move) [209.171227] Starting subtest: cursor_plane_move [211.071425] Subtest cursor_plane_move: SUCCESS (1.897s) [212.035442] [126/170] (791s left) kms_psr (sprite_plane_onoff) [212.185189] Starting subtest: sprite_plane_onoff [214.216899] Subtest sprite_plane_onoff: SUCCESS (2.029s) [215.168406] [127/170] (787s left) kms_psr (primary_mmap_gtt) [215.314450] Starting subtest: primary_mmap_gtt [217.276456] Subtest primary_mmap_gtt: SUCCESS (1.960s) [218.239728] [128/170] (784s left) kms_setmode (basic-clone-single-crtc) [218.349225] Starting subtest: basic-clone-single-crtc [218.352193] Subtest basic-clone-single-crtc: SKIP (0.001s) [218.483997] [129/170] (784s left) i915_pm_backlight (basic-brightness) [218.631117] Starting subtest: basic-brightness [218.741056] Starting dynamic subtest: eDP-1 [219.365386] Dynamic subtest eDP-1: SUCCESS (0.615s) [219.368594] Subtest basic-brightness: SUCCESS (0.725s) [220.248969] [130/170] (782s left) i915_pm_rpm (basic-pci-d3-state) [222.078451] Starting subtest: basic-pci-d3-state [226.631104] Subtest basic-pci-d3-state: SUCCESS (4.564s) [226.790338] [131/170] (776s left) i915_pm_rpm (basic-rte) [226.895189] Starting subtest: basic-rte [233.065533] Subtest basic-rte: SUCCESS (6.166s) [238.372984] [132/170] (764s left) i915_pm_rps (basic-api) [238.486851] Starting subtest: basic-api [238.491649] Subtest basic-api: SUCCESS (0.005s) [238.616809] [133/170] (764s left) prime_self_import (basic-llseek-bad) [238.722806] Starting subtest: basic-llseek-bad [238.723947] Subtest basic-llseek-bad: SUCCESS (0.002s) [238.827701] [134/170] (764s left) prime_self_import (basic-llseek-size) [238.950252] Starting subtest: basic-llseek-size [238.953969] Subtest basic-llseek-size: SUCCESS (0.003s) [239.064487] [135/170] (764s left) prime_self_import (basic-with_fd_dup) [239.169180] Starting subtest: basic-with_fd_dup [239.172403] Subtest basic-with_fd_dup: SUCCESS (0.003s) [239.296196] [136/170] (764s left) prime_self_import (basic-with_one_bo) [239.407183] Starting subtest: basic-with_one_bo [239.410753] Subtest basic-with_one_bo: SUCCESS (0.004s) [239.482861] [137/170] (763s left) prime_self_import (basic-with_one_bo_two_files) [239.589832] Starting subtest: basic-with_one_bo_two_files [239.593689] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [239.692171] [138/170] (763s left) prime_self_import (basic-with_two_bos) [239.808786] Starting subtest: basic-with_two_bos [239.812472] Subtest basic-with_two_bos: SUCCESS (0.003s) [239.940978] [139/170] (763s left) prime_vgem (basic-fence-flip) [240.083307] Starting subtest: basic-fence-flip [240.591346] Subtest basic-fence-flip: SUCCESS (0.508s) [240.702285] [140/170] (762s left) prime_vgem (basic-fence-mmap) [240.816793] Starting subtest: basic-fence-mmap [240.852968] Subtest basic-fence-mmap: SUCCESS (0.036s) [240.986029] [141/170] (762s left) prime_vgem (basic-fence-read) [241.120677] Starting subtest: basic-fence-read [241.178065] Subtest basic-fence-read: SUCCESS (0.057s) [241.279773] [142/170] (762s left) prime_vgem (basic-gtt) [241.424236] Starting subtest: basic-gtt [241.439570] Subtest basic-gtt: SUCCESS (0.015s) [241.539941] [143/170] (761s left) prime_vgem (basic-read) [241.692203] Starting subtest: basic-read [241.719570] Subtest basic-read: SUCCESS (0.027s) [241.821052] [144/170] (761s left) prime_vgem (basic-write) [241.959850] Starting subtest: basic-write [241.988803] Subtest basic-write: SUCCESS (0.029s) [242.065569] [145/170] (761s left) vgem_basic (setversion) [242.208614] Starting subtest: setversion [242.210765] Subtest setversion: SUCCESS (0.000s) [242.277743] [146/170] (761s left) vgem_basic (create) [242.414260] Starting subtest: create [242.415348] Subtest create: SUCCESS (0.000s) [242.483268] [147/170] (761s left) vgem_basic (debugfs) [242.619346] Starting subtest: debugfs [242.620286] Subtest debugfs: SUCCESS (0.000s) [242.686044] [148/170] (760s left) vgem_basic (dmabuf-export) [242.824470] Starting subtest: dmabuf-export [242.828271] Subtest dmabuf-export: SUCCESS (0.002s) [242.922421] [149/170] (760s left) vgem_basic (dmabuf-fence) [243.062660] Starting subtest: dmabuf-fence [243.063727] Subtest dmabuf-fence: SUCCESS (0.001s) [243.128908] [150/170] (760s left) vgem_basic (dmabuf-fence-before) [243.268124] Starting subtest: dmabuf-fence-before [243.269132] Subtest dmabuf-fence-before: SUCCESS (0.001s) [243.334128] [151/170] (760s left) vgem_basic (dmabuf-mmap) [243.473160] Starting subtest: dmabuf-mmap [243.491800] Subtest dmabuf-mmap: SUCCESS (0.019s) [243.549448] [152/170] (760s left) vgem_basic (mmap) [243.657680] Starting subtest: mmap [243.670500] Subtest mmap: SUCCESS (0.013s) [243.734963] [153/170] (760s left) vgem_basic (second-client) [243.880187] Starting subtest: second-client [243.930575] Subtest second-client: SUCCESS (0.048s) [243.989839] [154/170] (759s left) vgem_basic (sysfs) [244.137415] Starting subtest: sysfs [244.138660] Subtest sysfs: SUCCESS (0.001s) [244.201496] [155/170] (759s left) core_hotunplug (unbind-rebind) [244.367795] Starting subtest: unbind-rebind [246.618249] Subtest unbind-rebind: SUCCESS (2.250s) [246.654074] [156/170] (757s left) vgem_basic (unload) [246.778368] Starting subtest: unload [275.819927] Subtest unload: SUCCESS (29.005s) [275.929349] [157/170] (727s left) i915_module_load (reload) [276.048021] Starting subtest: reload [279.372244] Subtest reload: SUCCESS (3.324s) [279.402526] [158/170] (724s left) gem_lmem_swapping (basic) [279.629245] Subtest basic: SKIP (0.000s) [279.667944] [159/170] (724s left) gem_lmem_swapping (parallel-random-engines) [279.777162] Subtest parallel-random-engines: SKIP (0.000s) [279.814913] [160/170] (724s left) gem_lmem_swapping (random-engines) [279.925361] Subtest random-engines: SKIP (0.000s) [279.966191] [161/170] (724s left) gem_lmem_swapping (verify-random) [280.076031] Subtest verify-random: SKIP (0.000s) [280.115487] [162/170] (723s left) i915_pm_rpm (module-reload) [282.789751] Starting subtest: module-reload [299.301688] Subtest module-reload: SUCCESS (16.500s) [299.341727] [163/170] (704s left) i915_selftest (live) [299.461774] Starting subtest: live [299.462928] Starting dynamic subtest: sanitycheck [301.161668] Dynamic subtest sanitycheck: SUCCESS (1.690s) [301.171767] Starting dynamic subtest: uncore [302.831868] Dynamic subtest uncore: SUCCESS (1.669s) [302.843918] Starting dynamic subtest: workarounds [304.672720] Dynamic subtest workarounds: SUCCESS (1.840s) [304.682856] Starting dynamic subtest: gt_engines [306.811655] Dynamic subtest gt_engines: SUCCESS (2.138s) [306.822382] Starting dynamic subtest: gt_timelines [316.825726] Dynamic subtest gt_timelines: SUCCESS (10.013s) [316.835585] Starting dynamic subtest: gt_contexts [318.593847] Dynamic subtest gt_contexts: SUCCESS (1.767s) [318.601879] Starting dynamic subtest: gt_lrc [322.868652] Dynamic subtest gt_lrc: SUCCESS (4.274s) [322.875938] Starting dynamic subtest: gt_mocs [324.606662] Dynamic subtest gt_mocs: SUCCESS (1.737s) [324.614581] Starting dynamic subtest: gt_pm [330.556816] Dynamic subtest gt_pm: SUCCESS (5.949s) [330.576052] Starting dynamic subtest: gt_heartbeat [332.272739] Dynamic subtest gt_heartbeat: SUCCESS (1.715s) [332.280082] Starting dynamic subtest: gt_tlb [334.779602] Dynamic subtest gt_tlb: SUCCESS (2.506s) [334.789425] Starting dynamic subtest: requests [342.142554] Dynamic subtest requests: SUCCESS (7.362s) [342.149897] Starting dynamic subtest: migrate [346.625896] Dynamic subtest migrate: SUCCESS (4.482s) [346.634313] Starting dynamic subtest: active [348.288669] Dynamic subtest active: SUCCESS (1.662s) [348.298596] Starting dynamic subtest: objects [351.105733] Dynamic subtest objects: SUCCESS (2.816s) [351.115493] Starting dynamic subtest: mman [356.268681] Dynamic subtest mman: SUCCESS (5.162s) [356.277741] Starting dynamic subtest: dmabuf [358.039897] Dynamic subtest dmabuf: SUCCESS (1.770s) [358.048302] Starting dynamic subtest: vma [359.672788] Dynamic subtest vma: SUCCESS (1.632s) [359.684142] Starting dynamic subtest: coherency [363.384713] Dynamic subtest coherency: SUCCESS (3.711s) [363.394979] Starting dynamic subtest: gtt [371.864733] Dynamic subtest gtt: SUCCESS (8.479s) [371.875046] Starting dynamic subtest: gem [373.788619] Dynamic subtest gem: SUCCESS (1.923s) [373.795975] Starting dynamic subtest: evict [376.951756] Dynamic subtest evict: SUCCESS (3.162s) [376.959943] Starting dynamic subtest: hugepages [383.041725] Dynamic subtest hugepages: SUCCESS (6.089s) [383.049153] Starting dynamic subtest: gem_contexts [397.487783] Dynamic subtest gem_contexts: SUCCESS (14.444s) [397.495906] Starting dynamic subtest: client [399.230036] Dynamic subtest client: SUCCESS (1.742s) [399.240385] Starting dynamic subtest: gem_migrate [400.887744] Dynamic subtest gem_migrate: SUCCESS (1.657s) [400.898730] Starting dynamic subtest: reset [403.263923] Dynamic subtest reset: SUCCESS (2.375s) [403.272347] Starting dynamic subtest: memory_region [404.916799] Dynamic subtest memory_region: SUCCESS (1.652s) [404.925516] Starting dynamic subtest: hangcheck [429.062040] Dynamic subtest hangcheck: SUCCESS (24.144s) [429.072375] Starting dynamic subtest: execlists [438.553800] Dynamic subtest execlists: SUCCESS (9.491s) [438.561943] Starting dynamic subtest: ring_submission [440.158736] Dynamic subtest ring_submission: SUCCESS (1.604s) [440.167871] Starting dynamic subtest: perf [441.985623] Dynamic subtest perf: SUCCESS (1.826s) [441.993051] Starting dynamic subtest: slpc [443.619539] Dynamic subtest slpc: SUCCESS (1.633s) [443.626897] Starting dynamic subtest: guc [445.277654] Dynamic subtest guc: SUCCESS (1.657s) [445.285155] Starting dynamic subtest: guc_multi_lrc [446.977839] Dynamic subtest guc_multi_lrc: SUCCESS (1.699s) [446.989092] Starting dynamic subtest: guc_hang [448.639870] Dynamic subtest guc_hang: SUCCESS (1.661s) [448.649075] Starting dynamic subtest: late_gt_pm [450.308824] Dynamic subtest late_gt_pm: SUCCESS (1.668s) [450.309984] Subtest live: SUCCESS (150.838s) [450.563898] [164/170] (553s left) dmabuf (all-tests) [450.676308] Starting subtest: all-tests [450.677769] Starting dynamic subtest: sanitycheck [450.748183] Dynamic subtest sanitycheck: SUCCESS (0.070s) [450.779420] Starting dynamic subtest: dma_fence [450.942547] Dynamic subtest dma_fence: SUCCESS (0.192s) [450.954894] Starting dynamic subtest: dma_fence_chain [466.832681] Dynamic subtest dma_fence_chain: SUCCESS (15.879s) [466.858460] Starting dynamic subtest: dma_fence_unwrap [466.897937] Dynamic subtest dma_fence_unwrap: SUCCESS (0.073s) [466.918131] Starting dynamic subtest: dma_resv [466.994221] Dynamic subtest dma_resv: SUCCESS (0.094s) [466.995811] Subtest all-tests: SUCCESS (16.317s) [467.040930] [165/170] (537s left) i915_suspend (basic-s2idle-without-i915) [467.146738] Starting subtest: basic-s2idle-without-i915 [486.442703] Subtest basic-s2idle-without-i915: SUCCESS (4.266s) [488.817253] [166/170] (532s left) i915_suspend (basic-s3-without-i915) [488.942655] Starting subtest: basic-s3-without-i915 [509.060576] Subtest basic-s3-without-i915: SUCCESS (4.066s) [512.472345] [167/170] (527s left) gem_exec_suspend (basic-s0) [512.634919] Starting subtest: basic-S0 [512.636092] Starting dynamic subtest: smem [531.505182] Dynamic subtest smem: SUCCESS (3.359s) [531.507496] Subtest basic-S0: SUCCESS (3.360s) [534.839045] [168/170] (524s left) gem_exec_suspend (basic-s3) [534.993208] Starting subtest: basic-S3 [534.996572] Starting dynamic subtest: smem [555.714354] Dynamic subtest smem: SUCCESS (4.569s) [555.715358] Subtest basic-S3: SUCCESS (4.571s) [557.915386] [169/170] (519s left) kms_chamelium_hpd (common-hpd-after-suspend) [558.377761] Subtest common-hpd-after-suspend: SKIP (0.000s) [559.270895] [170/170] (517s left) kms_pipe_crc_basic (suspend-read-crc) [559.413271] Starting subtest: suspend-read-crc [559.415183] Starting dynamic subtest: pipe-A-eDP-1 [579.245973] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.160s) [579.258177] Starting dynamic subtest: pipe-B-eDP-1 [599.678655] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.298s) [599.694351] Starting dynamic subtest: pipe-C-eDP-1 [620.430554] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.006s) [620.431618] Subtest suspend-read-crc: SUCCESS (11.478s) [624.354905] Closing watchdogs Done.