[86.395651] Initializing watchdogs
[86.395998]   /dev/watchdog0
[86.421233] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0126 Intel Sandybridge (Gen6) 2nd Generation Core Processor Family Integrated Graphics Controller
[86.494069] [001/161] (960s left) i915_module_load (load)
[88.774516] Starting subtest: load
[92.966846] Subtest load: SUCCESS (4.226s)
[93.200965] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[93.204929] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[93.219442] [002/161] (953s left) core_auth (basic-auth)
[93.853282] Starting subtest: basic-auth
[93.895099] Subtest basic-auth: SUCCESS (0.001s)
[94.062766] [003/161] (953s left) debugfs_test (read_all_entries)
[94.555311] Starting subtest: read_all_entries
[94.597574] Subtest read_all_entries: SUCCESS (0.068s)
[95.102396] [004/161] (952s left) debugfs_test (basic-hwmon)
[95.595192] Starting subtest: basic-hwmon
[95.670327] Subtest basic-hwmon: SKIP (0.000s)
[96.132334] [005/161] (951s left) debugfs_test (sysfs)
[96.593031] Starting subtest: sysfs
[96.634917] Subtest sysfs: SUCCESS (0.105s)
[97.255827] [006/161] (950s left) fbdev (eof)
[97.723683] Starting subtest: eof
[97.765425] Subtest eof: SUCCESS (0.000s)
[97.908231] [007/161] (949s left) fbdev (info)
[98.267184] Starting subtest: info
[98.308771] Subtest info: SUCCESS (0.000s)
[98.451685] [008/161] (949s left) fbdev (nullptr)
[98.812234] Starting subtest: nullptr
[98.854081] Subtest nullptr: SUCCESS (0.000s)
[99.022607] [009/161] (949s left) fbdev (read)
[99.363796] Starting subtest: read
[99.839265] Subtest read: SUCCESS (0.503s)
[99.998760] [010/161] (948s left) fbdev (write)
[100.340856] Starting subtest: write
[100.408251] Subtest write: SUCCESS (0.119s)
[100.568235] [011/161] (947s left) gem_basic (bad-close)
[100.976748] Starting subtest: bad-close
[101.060376] Subtest bad-close: SUCCESS (0.000s)
[101.236765] [012/161] (947s left) gem_basic (create-close)
[101.713868] Starting subtest: create-close
[101.780825] Subtest create-close: SUCCESS (0.000s)
[101.931767] [013/161] (946s left) gem_basic (create-fd-close)
[102.374577] Starting subtest: create-fd-close
[102.458119] Subtest create-fd-close: SUCCESS (0.000s)
[102.634538] [014/161] (946s left) gem_busy (busy@all-engines)
[103.134953] Starting subtest: busy
[103.168624] Starting dynamic subtest: all-engines
[103.202213] Dynamic subtest all-engines: SUCCESS (0.052s)
[103.235744] Subtest busy: SUCCESS (0.053s)
[103.512532] [015/161] (945s left) gem_close_race (basic-process)
[104.046727] Starting subtest: basic-process
[104.090375] Subtest basic-process: SUCCESS (0.058s)
[104.233562] [016/161] (944s left) gem_close_race (basic-threads)
[104.667207] Starting subtest: basic-threads
[105.754526] Subtest basic-threads: SUCCESS (1.194s)
[105.990478] [017/161] (943s left) gem_ctx_create (basic)
[106.490201] Starting subtest: basic
[106.533947] Subtest basic: SUCCESS (0.000s)
[106.701774] [018/161] (942s left) gem_ctx_create (basic-files)
[107.093718] Starting subtest: basic-files
[109.106836] Subtest basic-files: SUCCESS (2.023s)
[109.491743] [019/161] (940s left) gem_ctx_exec (basic)
[110.059999] Starting subtest: basic
[110.126717] Subtest basic: SUCCESS (0.001s)
[110.346206] [020/161] (939s left) gem_exec_basic (basic)
[110.830844] Starting subtest: basic
[110.872595] Starting dynamic subtest: rcs0-smem
[110.922914] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[110.981263] Starting dynamic subtest: bcs0-smem
[111.023171] Dynamic subtest bcs0-smem: SUCCESS (0.000s)
[111.081575] Starting dynamic subtest: vcs0-smem
[111.123498] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[111.165289] Subtest basic: SUCCESS (0.002s)
[111.349941] [021/161] (938s left) gem_exec_create (basic)
[111.841742] Starting subtest: basic
[111.941937] Starting dynamic subtest: smem
[113.718191] Dynamic subtest smem: SUCCESS (2.055s)
[113.759915] Subtest basic: SUCCESS (2.056s)
[113.977982] [022/161] (936s left) gem_exec_fence (basic-busy)
[114.689343] Subtest basic-busy: SKIP (0.000s)
[115.122722] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[115.142260] [023/161] (935s left) gem_exec_fence (basic-wait)
[115.651356] Subtest basic-wait: SKIP (0.000s)
[115.978627] [024/161] (934s left) gem_exec_fence (basic-await)
[116.663043] Subtest basic-await: SKIP (0.000s)
[116.982234] [025/161] (933s left) gem_exec_fence (nb-await)
[117.600918] Subtest nb-await: SKIP (0.000s)
[117.903268] [026/161] (932s left) gem_exec_gttfill (basic)
[118.327904] Starting subtest: basic
[123.880791] Subtest basic: SUCCESS (5.611s)
[124.365452] [027/161] (926s left) gem_exec_parallel (engines)
[126.676894] Starting subtest: engines
[126.710416] Starting dynamic subtest: basic
[128.369360] Dynamic subtest basic: SUCCESS (1.815s)
[128.419540] Starting dynamic subtest: contexts
[131.028671] Dynamic subtest contexts: SUCCESS (2.651s)
[131.078812] Starting dynamic subtest: fds
[134.382672] Dynamic subtest fds: SUCCESS (3.351s)
[134.466260] Starting dynamic subtest: userptr
[136.242314] Dynamic subtest userptr: SUCCESS (1.843s)
[136.284893] Subtest engines: SUCCESS (9.663s)
[136.519255] [028/161] (914s left) gem_exec_store (basic)
[137.212062] Starting subtest: basic
[137.262236] Subtest basic: SUCCESS (0.044s)
[137.466709] [029/161] (914s left) gem_flink_basic (bad-flink)
[137.940925] Starting subtest: bad-flink
[137.974482] Subtest bad-flink: SUCCESS (0.000s)
[138.133694] [030/161] (913s left) gem_flink_basic (bad-open)
[138.626643] Starting subtest: bad-open
[138.668481] Subtest bad-open: SUCCESS (0.000s)
[138.844595] [031/161] (912s left) gem_flink_basic (basic)
[139.295295] Starting subtest: basic
[139.345429] Subtest basic: SUCCESS (0.000s)
[139.530544] [032/161] (912s left) gem_flink_basic (double-flink)
[139.988975] Starting subtest: double-flink
[140.030918] Subtest double-flink: SUCCESS (0.000s)
[140.216654] [033/161] (912s left) gem_flink_basic (flink-lifetime)
[140.609353] Starting subtest: flink-lifetime
[140.642886] Subtest flink-lifetime: SUCCESS (0.002s)
[140.785681] [034/161] (911s left) gem_huc_copy (huc-copy)
[141.236477] Subtest huc-copy: SKIP (0.000s)
[141.379718] [035/161] (911s left) gem_linear_blits (basic)
[141.855164] Starting subtest: basic
[141.930599] Subtest basic: SUCCESS (0.009s)
[142.498407] [036/161] (910s left) gem_mmap (basic)
[144.399554] Starting subtest: basic
[144.608296] Subtest basic: SUCCESS (0.001s)
[144.786182] [037/161] (907s left) gem_mmap_gtt (basic)
[145.278720] Starting subtest: basic
[145.312215] Subtest basic: SUCCESS (0.001s)
[145.455228] [038/161] (907s left) gem_render_linear_blits (basic)
[145.889080] Starting subtest: basic
[146.056315] Subtest basic: SUCCESS (0.029s)
[146.241163] [039/161] (906s left) gem_render_tiled_blits (basic)
[146.732985] Starting subtest: basic
[146.983970] Subtest basic: SUCCESS (0.038s)
[147.218853] [040/161] (906s left) gem_ringfill (basic-all)
[147.779702] Starting subtest: basic-all
[148.888649] Subtest basic-all: SUCCESS (1.111s)
[149.073698] [041/161] (904s left) gem_softpin (allocator-basic)
[149.574098] Subtest allocator-basic: SKIP (0.000s)
[149.800754] [042/161] (903s left) gem_softpin (allocator-basic-reserve)
[150.234406] Subtest allocator-basic-reserve: SKIP (0.000s)
[150.427933] [043/161] (903s left) gem_softpin (safe-alignment)
[150.961783] Starting subtest: safe-alignment
[150.995314] Subtest safe-alignment: SUCCESS (0.004s)
[151.172969] [044/161] (902s left) gem_sync (basic-all)
[151.656854] Starting subtest: basic-all
[153.641307] Subtest basic-all: SUCCESS (2.086s)
[153.901239] [045/161] (900s left) gem_sync (basic-each)
[154.410131] Starting subtest: basic-each
[156.411506] Subtest basic-each: SUCCESS (2.148s)
[156.679961] [046/161] (897s left) gem_tiled_blits (basic)
[157.264194] Starting subtest: basic
[157.374418] Subtest basic: SUCCESS (0.018s)
[157.901168] [047/161] (896s left) gem_tiled_fence_blits (basic)
[159.951514] Starting subtest: basic
[160.060056] Subtest basic: SUCCESS (0.009s)
[160.653689] [048/161] (893s left) gem_tiled_pread_basic
[162.781385] [049/161] (891s left) gem_wait (busy@all-engines)
[163.189498] Starting subtest: busy
[163.222999] Starting dynamic subtest: all-engines
[163.682439] Dynamic subtest all-engines: SUCCESS (0.515s)
[163.724562] Subtest busy: SUCCESS (0.516s)
[163.942711] [050/161] (890s left) gem_wait (wait@all-engines)
[164.319360] Starting subtest: wait
[164.352830] Starting dynamic subtest: all-engines
[165.270640] Dynamic subtest all-engines: SUCCESS (1.017s)
[165.312436] Subtest wait: SUCCESS (1.017s)
[165.589452] [051/161] (889s left) i915_getparams_basic (basic-eu-total)
[166.140456] Starting subtest: basic-eu-total
[166.207308] Subtest basic-eu-total: SUCCESS (0.000s)
[166.358301] [052/161] (888s left) i915_getparams_basic (basic-subslice-total)
[166.800855] Starting subtest: basic-subslice-total
[166.901088] Subtest basic-subslice-total: SUCCESS (0.000s)
[167.068640] [053/161] (888s left) i915_hangman (error-state-basic)
[167.713081] Starting subtest: error-state-basic
[167.813340] Subtest error-state-basic: SUCCESS (0.008s)
[168.073442] [054/161] (887s left) i915_pciid
[168.767172] [055/161] (886s left) kms_addfb_basic (addfb25-4-tiled)
[169.577724] Starting subtest: addfb25-4-tiled
[169.619616] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[169.982157] [056/161] (885s left) kms_addfb_basic (addfb25-bad-modifier)
[170.575588] Starting subtest: addfb25-bad-modifier
[170.609031] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[170.978879] [057/161] (884s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[171.555239] Starting subtest: addfb25-framebuffer-vs-set-tiling
[171.597157] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[171.952113] [058/161] (884s left) kms_addfb_basic (addfb25-modifier-no-flag)
[172.561334] Starting subtest: addfb25-modifier-no-flag
[172.603173] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[172.955942] [059/161] (883s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[173.532722] Starting subtest: addfb25-x-tiled-legacy
[173.566299] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[173.954090] [060/161] (882s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[174.564017] Starting subtest: addfb25-x-tiled-mismatch-legacy
[174.605721] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[175.000647] [061/161] (881s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[175.593404] Starting subtest: addfb25-yf-tiled-legacy
[175.628393] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[176.047796] [062/161] (880s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[176.624241] Starting subtest: addfb25-y-tiled-legacy
[176.666331] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[177.043407] [063/161] (879s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[177.653762] Starting subtest: addfb25-y-tiled-small-legacy
[177.730643] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[178.050213] [064/161] (879s left) kms_addfb_basic (bad-pitch-0)
[178.534386] Starting subtest: bad-pitch-0
[178.575765] Subtest bad-pitch-0: SUCCESS (0.000s)
[179.045707] [065/161] (878s left) kms_addfb_basic (bad-pitch-1024)
[179.555179] Starting subtest: bad-pitch-1024
[179.596950] Subtest bad-pitch-1024: SUCCESS (0.000s)
[180.136575] [066/161] (877s left) kms_addfb_basic (bad-pitch-128)
[180.620273] Starting subtest: bad-pitch-128
[180.662026] Subtest bad-pitch-128: SUCCESS (0.000s)
[181.148554] [067/161] (876s left) kms_addfb_basic (bad-pitch-256)
[181.609448] Starting subtest: bad-pitch-256
[181.642915] Subtest bad-pitch-256: SUCCESS (0.000s)
[182.155268] [068/161] (875s left) kms_addfb_basic (bad-pitch-32)
[182.655916] Starting subtest: bad-pitch-32
[182.698061] Subtest bad-pitch-32: SUCCESS (0.000s)
[183.125987] [069/161] (874s left) kms_addfb_basic (bad-pitch-63)
[183.636736] Starting subtest: bad-pitch-63
[183.712346] Subtest bad-pitch-63: SUCCESS (0.000s)
[184.240748] [070/161] (873s left) kms_addfb_basic (bad-pitch-65536)
[184.724187] Starting subtest: bad-pitch-65536
[184.757758] Subtest bad-pitch-65536: SUCCESS (0.000s)
[185.253396] [071/161] (872s left) kms_addfb_basic (bad-pitch-999)
[185.730755] Starting subtest: bad-pitch-999
[185.772448] Subtest bad-pitch-999: SUCCESS (0.000s)
[186.276167] [072/161] (872s left) kms_addfb_basic (basic)
[186.802150] Starting subtest: basic
[186.843741] Subtest basic: SUCCESS (0.000s)
[187.296538] [073/161] (871s left) kms_addfb_basic (basic-x-tiled-legacy)
[187.766281] Starting subtest: basic-x-tiled-legacy
[187.807789] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[188.269951] [074/161] (870s left) kms_addfb_basic (basic-y-tiled-legacy)
[188.753994] Starting subtest: basic-y-tiled-legacy
[188.787453] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[189.301581] [075/161] (869s left) kms_addfb_basic (bo-too-small)
[189.877651] Starting subtest: bo-too-small
[189.910922] Subtest bo-too-small: SUCCESS (0.001s)
[190.314465] [076/161] (868s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[190.865701] Starting subtest: bo-too-small-due-to-tiling
[190.899095] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[191.320202] [077/161] (867s left) kms_addfb_basic (clobberred-modifier)
[191.921217] Starting subtest: clobberred-modifier
[191.963353] Subtest clobberred-modifier: SUCCESS (0.000s)
[192.400283] [078/161] (866s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[192.825625] Starting subtest: framebuffer-vs-set-tiling
[192.859153] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[193.433836] [079/161] (865s left) kms_addfb_basic (invalid-get-prop)
[193.940797] Starting subtest: invalid-get-prop
[193.973883] Subtest invalid-get-prop: SUCCESS (0.000s)
[194.477665] [080/161] (865s left) kms_addfb_basic (invalid-get-prop-any)
[194.903531] Starting subtest: invalid-get-prop-any
[194.944933] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[195.476220] [081/161] (864s left) kms_addfb_basic (invalid-set-prop)
[196.059684] Starting subtest: invalid-set-prop
[196.101978] Subtest invalid-set-prop: SUCCESS (0.000s)
[196.530678] [082/161] (863s left) kms_addfb_basic (invalid-set-prop-any)
[197.005673] Starting subtest: invalid-set-prop-any
[197.052086] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[197.586676] [083/161] (862s left) kms_addfb_basic (no-handle)
[198.095216] Starting subtest: no-handle
[198.138374] Subtest no-handle: SUCCESS (0.000s)
[198.590150] [084/161] (861s left) kms_addfb_basic (size-max)
[199.176974] Starting subtest: size-max
[199.226924] Subtest size-max: SUCCESS (0.000s)
[199.671954] [085/161] (860s left) kms_addfb_basic (small-bo)
[200.284564] Starting subtest: small-bo
[200.315044] Subtest small-bo: SUCCESS (0.000s)
[200.693559] [086/161] (859s left) kms_addfb_basic (tile-pitch-mismatch)
[201.203995] Starting subtest: tile-pitch-mismatch
[201.245900] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[201.750267] [087/161] (858s left) kms_addfb_basic (too-high)
[202.409489] Starting subtest: too-high
[202.451226] Subtest too-high: SUCCESS (0.002s)
[202.822815] [088/161] (858s left) kms_addfb_basic (too-wide)
[203.549081] Starting subtest: too-wide
[203.582868] Subtest too-wide: SUCCESS (0.000s)
[203.918808] [089/161] (857s left) kms_addfb_basic (unused-handle)
[204.386469] Starting subtest: unused-handle
[204.429084] Subtest unused-handle: SUCCESS (0.000s)
[204.967713] [090/161] (856s left) kms_addfb_basic (unused-modifier)
[205.367342] Starting subtest: unused-modifier
[205.401085] Subtest unused-modifier: SUCCESS (0.000s)
[205.914534] [091/161] (855s left) kms_addfb_basic (unused-offsets)
[206.488615] Starting subtest: unused-offsets
[206.530531] Subtest unused-offsets: SUCCESS (0.000s)
[206.977343] [092/161] (854s left) kms_addfb_basic (unused-pitches)
[207.511599] Starting subtest: unused-pitches
[207.553425] Subtest unused-pitches: SUCCESS (0.000s)
[208.049544] [093/161] (853s left) kms_busy (basic)
[208.683690] Starting subtest: basic
[208.767459] Starting dynamic subtest: flip
[210.129242] Dynamic subtest flip: SUCCESS (1.443s)
[210.179818] Starting dynamic subtest: modeset
[212.526423] Dynamic subtest modeset: SUCCESS (2.412s)
[212.560266] Subtest basic: SUCCESS (3.858s)
[213.591756] [094/161] (848s left) kms_prop_blob (basic)
[214.108322] Starting subtest: basic
[214.150183] Subtest basic: SUCCESS (0.000s)
[214.294303] [095/161] (847s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[214.886365] Starting subtest: basic-busy-flip-before-cursor-atomic
[216.020190] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (1.171s)
[217.051762] [096/161] (845s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[217.668772] Starting subtest: basic-busy-flip-before-cursor-legacy
[218.844345] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (1.142s)
[219.824204] [097/161] (842s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[220.393187] Starting subtest: basic-flip-after-cursor-atomic
[221.527241] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.135s)
[222.564663] [098/161] (839s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[223.224772] Starting subtest: basic-flip-after-cursor-legacy
[224.350224] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.140s)
[225.372419] [099/161] (837s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[225.973966] Starting subtest: basic-flip-after-cursor-varying-size
[227.107900] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.144s)
[228.154431] [100/161] (834s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[228.647394] Starting subtest: basic-flip-before-cursor-atomic
[229.781503] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.136s)
[230.836280] [101/161] (832s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[231.378535] Starting subtest: basic-flip-before-cursor-legacy
[232.528881] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.134s)
[233.611224] [102/161] (829s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[234.227314] Starting subtest: basic-flip-before-cursor-varying-size
[235.403407] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.153s)
[236.426405] [103/161] (826s left) kms_dsc (dsc-basic)
[237.160218] Subtest dsc-basic: SKIP (0.000s)
[237.398617] [104/161] (825s left) kms_flip (basic-flip-vs-dpms)
[238.138755] Starting subtest: basic-flip-vs-dpms
[238.189078] Starting dynamic subtest: A-LVDS1
[239.860590] Dynamic subtest A-LVDS1: SUCCESS (1.758s)
[239.919181] Starting dynamic subtest: B-LVDS1
[241.364096] Dynamic subtest B-LVDS1: SUCCESS (1.489s)
[241.422866] Starting dynamic subtest: A-VGA1
[242.104123] Dynamic subtest A-VGA1: SUCCESS (0.762s)
[242.162613] Starting dynamic subtest: B-VGA1
[242.867223] Dynamic subtest B-VGA1: SUCCESS (0.760s)
[242.909150] Subtest basic-flip-vs-dpms: SUCCESS (4.781s)
[243.396457] [105/161] (820s left) kms_flip (basic-flip-vs-modeset)
[244.082940] Starting subtest: basic-flip-vs-modeset
[244.125104] Starting dynamic subtest: A-LVDS1
[245.829653] Dynamic subtest A-LVDS1: SUCCESS (1.793s)
[245.888079] Starting dynamic subtest: B-LVDS1
[247.334739] Dynamic subtest B-LVDS1: SUCCESS (1.482s)
[247.385207] Starting dynamic subtest: A-VGA1
[248.081897] Dynamic subtest A-VGA1: SUCCESS (0.776s)
[248.140420] Starting dynamic subtest: B-VGA1
[248.914043] Dynamic subtest B-VGA1: SUCCESS (0.777s)
[248.955592] Subtest basic-flip-vs-modeset: SUCCESS (4.841s)
[249.410438] [106/161] (814s left) kms_flip (basic-flip-vs-wf_vblank)
[250.179969] Starting subtest: basic-flip-vs-wf_vblank
[250.230067] Starting dynamic subtest: A-LVDS1
[252.040517] Dynamic subtest A-LVDS1: SUCCESS (1.924s)
[252.091055] Starting dynamic subtest: B-LVDS1
[253.793424] Dynamic subtest B-LVDS1: SUCCESS (1.745s)
[253.852145] Starting dynamic subtest: A-VGA1
[255.030428] Dynamic subtest A-VGA1: SUCCESS (1.220s)
[255.089294] Starting dynamic subtest: B-VGA1
[256.032400] Dynamic subtest B-VGA1: SUCCESS (0.996s)
[256.082229] Subtest basic-flip-vs-wf_vblank: SUCCESS (5.902s)
[256.563429] [107/161] (807s left) kms_flip (basic-plain-flip)
[257.171441] Starting subtest: basic-plain-flip
[257.213174] Starting dynamic subtest: A-LVDS1
[258.608327] Dynamic subtest A-LVDS1: SUCCESS (1.484s)
[258.658859] Starting dynamic subtest: B-LVDS1
[260.127364] Dynamic subtest B-LVDS1: SUCCESS (1.502s)
[260.177679] Starting dynamic subtest: A-VGA1
[261.071597] Dynamic subtest A-VGA1: SUCCESS (0.938s)
[261.129999] Starting dynamic subtest: B-VGA1
[261.797864] Dynamic subtest B-VGA1: SUCCESS (0.706s)
[261.839933] Subtest basic-plain-flip: SUCCESS (4.648s)
[262.322953] [108/161] (801s left) kms_force_connector_basic (force-connector-state)
[262.888387] Starting subtest: force-connector-state
[263.348404] Subtest force-connector-state: SUCCESS (0.601s)
[264.530136] [109/161] (799s left) kms_force_connector_basic (force-edid)
[264.972160] Starting subtest: force-edid
[265.006033] Subtest force-edid: SUCCESS (0.121s)
[265.568997] [110/161] (798s left) kms_force_connector_basic (force-load-detect)
[266.127100] Starting subtest: force-load-detect
[266.413463] Subtest force-load-detect: SUCCESS (0.423s)
[267.502788] [111/161] (796s left) kms_force_connector_basic (prune-stale-modes)
[267.977341] Starting subtest: prune-stale-modes
[268.010634] Subtest prune-stale-modes: SUCCESS (0.029s)
[268.451835] [112/161] (796s left) kms_frontbuffer_tracking (basic)
[269.143270] Starting subtest: basic
[271.378093] Subtest basic: SUCCESS (2.344s)
[271.798404] [113/161] (792s left) kms_hdmi_inject (inject-audio)
[272.473942] Starting subtest: inject-audio
[273.070373] Subtest inject-audio: SUCCESS (0.640s)
[273.465359] [114/161] (791s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[274.058644] Starting subtest: compare-crc-sanitycheck-xr24
[274.100328] Starting dynamic subtest: pipe-A-LVDS-1
[274.637459] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.566s)
[274.687583] Starting dynamic subtest: pipe-B-LVDS-1
[275.531632] Dynamic subtest pipe-B-LVDS-1: SUCCESS (0.905s)
[275.573150] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.477s)
[276.511329] [115/161] (788s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[277.097867] Starting subtest: compare-crc-sanitycheck-nv12
[277.139221] Starting dynamic subtest: pipe-A-LVDS-1
[277.214873] Dynamic subtest pipe-A-LVDS-1: SKIP (0.000s)
[277.340218] Starting dynamic subtest: pipe-B-LVDS-1
[277.424042] Dynamic subtest pipe-B-LVDS-1: SKIP (0.000s)
[277.507545] Subtest compare-crc-sanitycheck-nv12: SKIP (0.006s)
[277.677211] [116/161] (787s left) kms_pipe_crc_basic (hang-read-crc)
[278.302352] Starting subtest: hang-read-crc
[278.344380] Starting dynamic subtest: pipe-A-LVDS-1
[280.141860] Dynamic subtest pipe-A-LVDS-1: SUCCESS (1.851s)
[280.192361] Starting dynamic subtest: pipe-B-LVDS-1
[282.556588] Dynamic subtest pipe-B-LVDS-1: SUCCESS (2.407s)
[282.598176] Subtest hang-read-crc: SUCCESS (4.265s)
[283.588132] [117/161] (781s left) kms_pipe_crc_basic (nonblocking-crc)
[284.205628] Starting subtest: nonblocking-crc
[284.247539] Starting dynamic subtest: pipe-A-LVDS-1
[284.958037] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.748s)
[285.000090] Starting dynamic subtest: pipe-B-LVDS-1
[286.221097] Dynamic subtest pipe-B-LVDS-1: SUCCESS (1.241s)
[286.262956] Subtest nonblocking-crc: SUCCESS (1.997s)
[287.235659] [118/161] (778s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[287.759937] Starting subtest: nonblocking-crc-frame-sequence
[287.810076] Starting dynamic subtest: pipe-A-LVDS-1
[288.513776] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.728s)
[288.564332] Starting dynamic subtest: pipe-B-LVDS-1
[289.841944] Dynamic subtest pipe-B-LVDS-1: SUCCESS (1.318s)
[289.883585] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.054s)
[290.880646] [119/161] (774s left) kms_pipe_crc_basic (read-crc)
[291.448778] Starting subtest: read-crc
[291.490829] Starting dynamic subtest: pipe-A-LVDS-1
[292.109327] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.665s)
[292.176514] Starting dynamic subtest: pipe-B-LVDS-1
[293.387483] Dynamic subtest pipe-B-LVDS-1: SUCCESS (1.248s)
[293.437845] Subtest read-crc: SUCCESS (1.918s)
[294.369152] [120/161] (771s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[294.885823] Starting subtest: read-crc-frame-sequence
[294.927670] Starting dynamic subtest: pipe-A-LVDS-1
[295.629495] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.668s)
[295.696490] Starting dynamic subtest: pipe-B-LVDS-1
[296.732436] Dynamic subtest pipe-B-LVDS-1: SUCCESS (1.160s)
[296.773769] Subtest read-crc-frame-sequence: SUCCESS (1.836s)
[297.746205] [121/161] (767s left) kms_pm_backlight (basic-brightness)
[298.380532] Starting subtest: basic-brightness
[298.464659] Starting dynamic subtest: LVDS-1
[298.740351] Dynamic subtest LVDS-1: SUCCESS (0.302s)
[298.773524] Subtest basic-brightness: SUCCESS (0.352s)
[299.727556] [122/161] (766s left) kms_pm_rpm (basic-pci-d3-state)
[301.540468] Subtest basic-pci-d3-state: SKIP (0.000s)
[301.761477] [123/161] (764s left) kms_pm_rpm (basic-rte)
[302.235031] Starting subtest: basic-rte
[303.605204] Subtest basic-rte: SKIP (1.386s)
[303.827312] [124/161] (762s left) kms_psr (psr-primary-page-flip)
[304.525914] Subtest psr-primary-page-flip: SKIP (0.000s)
[304.762256] [125/161] (761s left) kms_psr (psr-cursor-plane-move)
[305.346559] Subtest psr-cursor-plane-move: SKIP (0.000s)
[305.585689] [126/161] (760s left) kms_psr (psr-sprite-plane-onoff)
[306.193709] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[306.408220] [127/161] (760s left) kms_psr (psr-primary-mmap-gtt)
[307.064114] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[307.295755] [128/161] (759s left) kms_setmode (basic-clone-single-crtc)
[307.794194] Starting subtest: basic-clone-single-crtc
[307.919580] Subtest basic-clone-single-crtc: SKIP (0.001s)
[308.073298] [129/161] (759s left) i915_pm_rps (basic-api)
[308.596580] Starting subtest: basic-api
[308.638405] Subtest basic-api: SUCCESS (0.004s)
[308.832909] [130/161] (758s left) prime_self_import (basic-llseek-bad)
[309.290546] Starting subtest: basic-llseek-bad
[309.374118] Subtest basic-llseek-bad: SUCCESS (0.002s)
[309.510497] [131/161] (757s left) prime_self_import (basic-llseek-size)
[309.844112] Starting subtest: basic-llseek-size
[309.911078] Subtest basic-llseek-size: SUCCESS (0.003s)
[310.054304] [132/161] (757s left) prime_self_import (basic-with_fd_dup)
[310.387714] Starting subtest: basic-with_fd_dup
[310.454570] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[310.599162] [133/161] (757s left) prime_self_import (basic-with_one_bo)
[311.048300] Starting subtest: basic-with_one_bo
[311.131923] Subtest basic-with_one_bo: SUCCESS (0.004s)
[311.284536] [134/161] (756s left) prime_self_import (basic-with_one_bo_two_files)
[311.650396] Starting subtest: basic-with_one_bo_two_files
[311.734084] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s)
[311.889201] [135/161] (756s left) prime_self_import (basic-with_two_bos)
[312.312562] Starting subtest: basic-with_two_bos
[312.396123] Subtest basic-with_two_bos: SUCCESS (0.003s)
[312.557379] [136/161] (755s left) prime_vgem (basic-fence-flip)
[313.098322] Starting subtest: basic-fence-flip
[313.274293] Subtest basic-fence-flip: SKIP (0.260s)
[313.602813] [137/161] (754s left) prime_vgem (basic-fence-mmap)
[314.194838] Starting subtest: basic-fence-mmap
[314.236709] Subtest basic-fence-mmap: SUCCESS (0.041s)
[314.483749] [138/161] (754s left) prime_vgem (basic-fence-read)
[314.899205] Starting subtest: basic-fence-read
[314.932528] Subtest basic-fence-read: SUCCESS (0.060s)
[315.110604] [139/161] (753s left) prime_vgem (basic-gtt)
[315.659969] Starting subtest: basic-gtt
[315.701848] Subtest basic-gtt: SUCCESS (0.015s)
[315.898608] [140/161] (752s left) prime_vgem (basic-read)
[316.378773] Starting subtest: basic-read
[316.420406] Subtest basic-read: SUCCESS (0.019s)
[316.630860] [141/161] (752s left) prime_vgem (basic-write)
[317.116066] Starting subtest: basic-write
[317.149481] Subtest basic-write: SUCCESS (0.020s)
[317.342758] [142/161] (751s left) vgem_basic (setversion)
[317.810346] Starting subtest: setversion
[317.877285] Subtest setversion: SUCCESS (0.000s)
[318.022203] [143/161] (751s left) vgem_basic (create)
[318.437506] Starting subtest: create
[318.479330] Subtest create: SUCCESS (0.001s)
[318.631950] [144/161] (750s left) vgem_basic (debugfs)
[319.056294] Starting subtest: debugfs
[319.098120] Subtest debugfs: SUCCESS (0.000s)
[319.252572] [145/161] (750s left) vgem_basic (dmabuf-export)
[319.734917] Starting subtest: dmabuf-export
[319.801914] Subtest dmabuf-export: SUCCESS (0.003s)
[319.970152] [146/161] (749s left) vgem_basic (dmabuf-fence)
[320.378930] Starting subtest: dmabuf-fence
[320.412389] Subtest dmabuf-fence: SUCCESS (0.001s)
[320.575369] [147/161] (749s left) vgem_basic (dmabuf-fence-before)
[321.056097] Starting subtest: dmabuf-fence-before
[321.097813] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[321.259946] [148/161] (748s left) vgem_basic (dmabuf-mmap)
[321.724622] Starting subtest: dmabuf-mmap
[321.766465] Subtest dmabuf-mmap: SUCCESS (0.017s)
[321.918989] [149/161] (748s left) vgem_basic (mmap)
[322.411947] Starting subtest: mmap
[322.445213] Subtest mmap: SUCCESS (0.012s)
[322.597875] [150/161] (747s left) vgem_basic (second-client)
[323.063981] Starting subtest: second-client
[323.147549] Subtest second-client: SUCCESS (0.033s)
[323.333275] [151/161] (747s left) vgem_basic (sysfs)
[323.749218] Starting subtest: sysfs
[323.791088] Subtest sysfs: SUCCESS (0.001s)
[323.952755] [152/161] (746s left) core_hotunplug (unbind-rebind)
[324.860297] Starting subtest: unbind-rebind
[329.151882] Subtest unbind-rebind: SUCCESS (4.403s)
[329.371298] [153/161] (741s left) vgem_basic (unload)
[329.979242] Starting subtest: unload
[359.292909] Subtest unload: SUCCESS (29.132s)
[359.470994] [154/161] (711s left) i915_module_load (reload)
[359.878359] Starting subtest: reload
[364.303929] Subtest reload: SUCCESS (4.545s)
[364.473069] [155/161] (706s left) gem_lmem_swapping (basic)
[365.182087] Subtest basic: SKIP (0.000s)
[365.333933] [156/161] (706s left) gem_lmem_swapping (parallel-random-engines)
[365.834102] Subtest parallel-random-engines: SKIP (0.000s)
[365.995852] [157/161] (705s left) gem_lmem_swapping (random-engines)
[366.463090] Subtest random-engines: SKIP (0.000s)
[366.625737] [158/161] (705s left) gem_lmem_swapping (verify-random)
[367.131711] Subtest verify-random: SKIP (0.000s)
[367.311905] [159/161] (704s left) i915_pm_rpm (module-reload)
[369.152633] Subtest module-reload: SKIP (0.000s)
[369.324783] [160/161] (702s left) i915_selftest (live)
[371.300170] Starting subtest: live
[371.342059] Starting dynamic subtest: sanitycheck
[373.236401] Dynamic subtest sanitycheck: SUCCESS (1.921s)
[373.286551] Starting dynamic subtest: uncore
[375.224678] Dynamic subtest uncore: SUCCESS (1.985s)
[376.133071] Starting dynamic subtest: workarounds
[377.169797] Dynamic subtest workarounds: SUCCESS (1.950s)
[377.228128] Starting dynamic subtest: gt_engines
[379.105930] Dynamic subtest gt_engines: SUCCESS (1.916s)
[380.006145] Starting dynamic subtest: gt_timelines
[383.870756] Dynamic subtest gt_timelines: SUCCESS (4.765s)
[383.929243] Starting dynamic subtest: gt_contexts
[385.782786] Dynamic subtest gt_contexts: SUCCESS (1.899s)
[386.707945] Starting dynamic subtest: gt_lrc
[387.779221] Dynamic subtest gt_lrc: SUCCESS (2.018s)
[387.829421] Starting dynamic subtest: gt_mocs
[389.782446] Dynamic subtest gt_mocs: SUCCESS (1.993s)
[389.832466] Starting dynamic subtest: gt_pm
[399.523318] Dynamic subtest gt_pm: SUCCESS (9.738s)
[401.281951] Starting dynamic subtest: gt_heartbeat
[402.226890] Dynamic subtest gt_heartbeat: SUCCESS (2.688s)
[402.277098] Starting dynamic subtest: gt_tlb
[404.273686] Dynamic subtest gt_tlb: SUCCESS (2.053s)
[404.332090] Starting dynamic subtest: requests
[410.804762] Dynamic subtest requests: SUCCESS (6.525s)
[410.854940] Starting dynamic subtest: migrate
[412.818222] Dynamic subtest migrate: SUCCESS (2.011s)
[412.868252] Starting dynamic subtest: active
[414.829602] Dynamic subtest active: SUCCESS (2.016s)
[414.879734] Starting dynamic subtest: objects
[417.299509] Dynamic subtest objects: SUCCESS (2.451s)
[418.216475] Starting dynamic subtest: mman
[423.890881] Dynamic subtest mman: SUCCESS (6.584s)
[423.949301] Starting dynamic subtest: dmabuf
[425.860858] Dynamic subtest dmabuf: SUCCESS (1.976s)
[426.777774] Starting dynamic subtest: vma
[427.891133] Dynamic subtest vma: SUCCESS (2.009s)
[428.799655] Starting dynamic subtest: coherency
[431.069314] Dynamic subtest coherency: SUCCESS (3.207s)
[431.119478] Starting dynamic subtest: gtt
[436.112559] Dynamic subtest gtt: SUCCESS (5.018s)
[436.162713] Starting dynamic subtest: gem
[438.658448] Dynamic subtest gem: SUCCESS (2.557s)
[438.716955] Starting dynamic subtest: evict
[440.555418] Dynamic subtest evict: SUCCESS (1.880s)
[440.613412] Starting dynamic subtest: hugepages
[443.000135] Dynamic subtest hugepages: SUCCESS (2.442s)
[443.058638] Starting dynamic subtest: gem_contexts
[448.558365] Dynamic subtest gem_contexts: SUCCESS (5.553s)
[448.616771] Starting dynamic subtest: client
[450.428270] Dynamic subtest client: SUCCESS (1.874s)
[450.480059] Starting dynamic subtest: gem_migrate
[452.441627] Dynamic subtest gem_migrate: SUCCESS (2.008s)
[452.491783] Starting dynamic subtest: reset
[454.537220] Dynamic subtest reset: SUCCESS (2.111s)
[454.587369] Starting dynamic subtest: memory_region
[456.541894] Dynamic subtest memory_region: SUCCESS (1.974s)
[457.450453] Starting dynamic subtest: hangcheck
[460.163359] Dynamic subtest hangcheck: SUCCESS (3.631s)
[460.213526] Starting dynamic subtest: execlists
[462.191405] Dynamic subtest execlists: SUCCESS (2.017s)
[462.241561] Starting dynamic subtest: ring_submission
[464.237995] Dynamic subtest ring_submission: SUCCESS (2.047s)
[464.288134] Starting dynamic subtest: perf
[466.274362] Dynamic subtest perf: SUCCESS (2.032s)
[467.191312] Starting dynamic subtest: slpc
[468.202099] Dynamic subtest slpc: SUCCESS (1.921s)
[469.110671] Starting dynamic subtest: guc
[470.032123] Dynamic subtest guc: SUCCESS (1.826s)
[470.082305] Starting dynamic subtest: guc_multi_lrc
[471.960092] Dynamic subtest guc_multi_lrc: SUCCESS (1.924s)
[472.868691] Starting dynamic subtest: guc_hang
[473.931532] Dynamic subtest guc_hang: SUCCESS (1.982s)
[474.848344] Starting dynamic subtest: late_gt_pm
[475.918061] Dynamic subtest late_gt_pm: SUCCESS (1.969s)
[475.959908] Subtest live: SUCCESS (104.610s)
[476.110396] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[476.112086] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[476.129801] [161/161] (596s left) dmabuf (all-tests)
[476.695379] Starting subtest: all-tests
[476.729160] Starting dynamic subtest: sanitycheck
[476.804220] Dynamic subtest sanitycheck: SUCCESS (0.107s)
[476.846121] Starting dynamic subtest: dma_fence
[477.030066] Dynamic subtest dma_fence: SUCCESS (0.217s)
[477.081371] Starting dynamic subtest: dma_fence_chain
[489.248884] Dynamic subtest dma_fence_chain: SUCCESS (12.218s)
[489.307274] Starting dynamic subtest: dma_fence_unwrap
[489.340847] Dynamic subtest dma_fence_unwrap: SUCCESS (0.084s)
[489.409318] Starting dynamic subtest: dma_resv
[489.451356] Dynamic subtest dma_resv: SUCCESS (0.098s)
[489.493187] Subtest all-tests: SUCCESS (12.742s)
[489.640233] Closing watchdogs
Done.