[96.189616] Initializing watchdogs
[96.189977]   /dev/watchdog0
[96.255215] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:22b1 Intel Cherryview (Gen8) Atom/Celeron/Pentium Processor x5-E8000/J3xxx/N3xxx Integrated Graphics Controller
[96.277872] [001/162] (960s left) i915_module_load (load)
[96.537325] Starting subtest: load
[101.991154] Subtest load: SUCCESS (5.450s)
[102.324749] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[102.346169] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[102.369286] [002/162] (954s left) core_auth (basic-auth)
[102.795540] Starting subtest: basic-auth
[102.801992] Subtest basic-auth: SUCCESS (0.007s)
[103.027593] [003/162] (954s left) core_debugfs (read-all-entries)
[103.251535] Starting subtest: read-all-entries
[103.262050] Subtest read-all-entries: SUCCESS (0.013s)
[103.474931] [004/162] (953s left) core_sysfs (read-all-entries)
[103.700646] Starting subtest: read-all-entries
[103.716931] Subtest read-all-entries: SUCCESS (0.019s)
[103.943873] [005/162] (953s left) fbdev (eof)
[104.191868] Starting subtest: eof
[104.197934] Subtest eof: SUCCESS (0.007s)
[104.430174] [006/162] (953s left) fbdev (info)
[104.649386] Starting subtest: info
[104.650907] Subtest info: SUCCESS (0.000s)
[104.862757] [007/162] (953s left) fbdev (nullptr)
[105.084088] Starting subtest: nullptr
[105.088194] Subtest nullptr: SUCCESS (0.001s)
[105.294804] [008/162] (952s left) fbdev (read)
[105.522234] Starting subtest: read
[107.609308] Subtest read: SUCCESS (2.084s)
[107.802773] [009/162] (950s left) fbdev (write)
[108.036882] Starting subtest: write
[109.132856] Subtest write: SUCCESS (1.090s)
[109.326877] [010/162] (949s left) gem_basic (bad-close)
[109.546352] Starting subtest: bad-close
[109.550524] Subtest bad-close: SUCCESS (0.000s)
[109.763445] [011/162] (948s left) gem_basic (create-close)
[109.996743] Starting subtest: create-close
[110.002324] Subtest create-close: SUCCESS (0.000s)
[110.217721] [012/162] (948s left) gem_basic (create-fd-close)
[110.435255] Starting subtest: create-fd-close
[110.439468] Subtest create-fd-close: SUCCESS (0.001s)
[110.659785] [013/162] (948s left) gem_busy (busy@all-engines)
[110.960553] Starting subtest: busy
[110.964671] Starting dynamic subtest: all-engines
[111.126507] Dynamic subtest all-engines: SUCCESS (0.168s)
[111.129184] Subtest busy: SUCCESS (0.171s)
[111.684892] [014/162] (947s left) gem_close_race (basic-process)
[111.983795] Starting subtest: basic-process
[112.105220] Subtest basic-process: SUCCESS (0.130s)
[112.443081] [015/162] (946s left) gem_close_race (basic-threads)
[112.722256] Starting subtest: basic-threads
[113.920259] Subtest basic-threads: SUCCESS (1.194s)
[114.280451] [016/162] (945s left) gem_ctx_create (basic)
[114.548886] Starting subtest: basic
[114.550512] Subtest basic: SUCCESS (0.000s)
[114.894892] [017/162] (944s left) gem_ctx_create (basic-files)
[115.170171] Starting subtest: basic-files
[117.227180] Subtest basic-files: SUCCESS (2.060s)
[117.577483] [018/162] (942s left) gem_ctx_exec (basic)
[117.809561] Starting subtest: basic
[117.827397] Subtest basic: SUCCESS (0.017s)
[118.057858] [019/162] (941s left) gem_exec_basic (basic)
[118.307173] Starting subtest: basic
[118.309932] Starting dynamic subtest: rcs0-smem
[118.314166] Dynamic subtest rcs0-smem: SUCCESS (0.006s)
[118.339782] Starting dynamic subtest: bcs0-smem
[118.341665] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[118.366910] Starting dynamic subtest: vcs0-smem
[118.369884] Dynamic subtest vcs0-smem: SUCCESS (0.004s)
[118.395877] Starting dynamic subtest: vecs0-smem
[118.397608] Dynamic subtest vecs0-smem: SUCCESS (0.050s)
[118.399462] Subtest basic: SUCCESS (0.069s)
[118.734499] [020/162] (941s left) gem_exec_create (basic)
[119.011202] Starting subtest: basic
[119.015320] Starting dynamic subtest: smem
[121.090807] Dynamic subtest smem: SUCCESS (2.087s)
[121.094144] Subtest basic: SUCCESS (2.089s)
[121.444310] [021/162] (938s left) gem_exec_fence (basic-busy)
[121.884292] Starting subtest: basic-busy
[121.886942] Starting dynamic subtest: rcs0
[121.920531] Dynamic subtest rcs0: SUCCESS (0.036s)
[121.947550] Starting dynamic subtest: bcs0
[121.956437] Dynamic subtest bcs0: SUCCESS (0.038s)
[121.983116] Starting dynamic subtest: vcs0
[121.999682] Dynamic subtest vcs0: SUCCESS (0.040s)
[122.036191] Starting dynamic subtest: vecs0
[122.043587] Dynamic subtest vecs0: SUCCESS (0.044s)
[122.046840] Subtest basic-busy: SUCCESS (0.164s)
[123.011214] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[123.022989] [022/162] (937s left) gem_exec_fence (basic-wait)
[123.453639] Starting subtest: basic-wait
[123.457294] Starting dynamic subtest: rcs0
[123.483944] Dynamic subtest rcs0: SUCCESS (0.032s)
[123.510342] Starting dynamic subtest: bcs0
[123.520550] Dynamic subtest bcs0: SUCCESS (0.036s)
[123.552537] Starting dynamic subtest: vcs0
[123.565717] Dynamic subtest vcs0: SUCCESS (0.042s)
[123.593815] Starting dynamic subtest: vecs0
[123.602813] Dynamic subtest vecs0: SUCCESS (0.034s)
[123.604837] Subtest basic-wait: SUCCESS (0.155s)
[124.405627] [023/162] (936s left) gem_exec_fence (basic-await)
[124.863117] Starting subtest: basic-await
[124.867156] Starting dynamic subtest: rcs0
[125.066489] Dynamic subtest rcs0: SUCCESS (0.196s)
[125.094893] Starting dynamic subtest: bcs0
[125.261227] Dynamic subtest bcs0: SUCCESS (0.192s)
[125.287384] Starting dynamic subtest: vcs0
[125.451093] Dynamic subtest vcs0: SUCCESS (0.186s)
[125.479646] Starting dynamic subtest: vecs0
[125.648789] Dynamic subtest vecs0: SUCCESS (0.195s)
[125.650607] Subtest basic-await: SUCCESS (0.780s)
[126.467760] [024/162] (934s left) gem_exec_fence (nb-await)
[126.890747] Starting subtest: nb-await
[126.894732] Starting dynamic subtest: rcs0
[126.987840] Dynamic subtest rcs0: SUCCESS (0.095s)
[127.014519] Starting dynamic subtest: bcs0
[127.090165] Dynamic subtest bcs0: SUCCESS (0.098s)
[127.120779] Starting dynamic subtest: vcs0
[127.201033] Dynamic subtest vcs0: SUCCESS (0.099s)
[127.226910] Starting dynamic subtest: vecs0
[127.296170] Dynamic subtest vecs0: SUCCESS (0.105s)
[127.304531] Subtest nb-await: SUCCESS (0.406s)
[128.110329] [025/162] (932s left) gem_exec_gttfill (basic)
[128.376194] Starting subtest: basic
[129.469454] Subtest basic: SKIP (1.059s)
[129.821954] [026/162] (931s left) gem_exec_parallel (engines)
[130.287969] Starting subtest: engines
[130.293975] Starting dynamic subtest: basic
[136.518365] Dynamic subtest basic: SUCCESS (6.224s)
[136.583735] Starting dynamic subtest: contexts
[146.981545] Dynamic subtest contexts: SUCCESS (10.462s)
[147.011564] Starting dynamic subtest: fds
[158.658145] Dynamic subtest fds: SUCCESS (11.671s)
[158.700275] Starting dynamic subtest: userptr
[164.843395] Dynamic subtest userptr: SUCCESS (6.180s)
[164.845305] Subtest engines: SUCCESS (34.549s)
[165.233836] [027/162] (896s left) gem_exec_store (basic)
[165.507232] Starting subtest: basic
[165.579933] Subtest basic: SUCCESS (0.080s)
[165.944528] [028/162] (895s left) gem_flink_basic (bad-flink)
[166.170248] Starting subtest: bad-flink
[166.176948] Subtest bad-flink: SUCCESS (0.000s)
[166.412363] [029/162] (895s left) gem_flink_basic (bad-open)
[166.635998] Starting subtest: bad-open
[166.637576] Subtest bad-open: SUCCESS (0.000s)
[166.866092] [030/162] (895s left) gem_flink_basic (basic)
[167.085360] Starting subtest: basic
[167.087088] Subtest basic: SUCCESS (0.001s)
[167.314113] [031/162] (894s left) gem_flink_basic (double-flink)
[167.534847] Starting subtest: double-flink
[167.538046] Subtest double-flink: SUCCESS (0.000s)
[167.781408] [032/162] (894s left) gem_flink_basic (flink-lifetime)
[168.003909] Starting subtest: flink-lifetime
[168.013700] Subtest flink-lifetime: SUCCESS (0.013s)
[168.273592] [033/162] (894s left) gem_huc_copy (huc-copy)
[168.512438] Subtest huc-copy: SKIP (0.000s)
[168.723623] [034/162] (894s left) gem_linear_blits (basic)
[169.291451] Subtest basic: SKIP (0.000s)
[169.497422] [035/162] (893s left) gem_mmap (basic)
[169.940752] Starting subtest: basic
[169.965071] Subtest basic: SUCCESS (0.001s)
[170.165350] [036/162] (892s left) gem_mmap_gtt (basic)
[170.391298] Starting subtest: basic
[170.392968] Subtest basic: SUCCESS (0.005s)
[170.637067] [037/162] (892s left) gem_render_linear_blits (basic)
[170.877602] Starting subtest: basic
[171.010516] Subtest basic: SUCCESS (0.127s)
[171.212331] [038/162] (892s left) gem_render_tiled_blits (basic)
[171.456507] Starting subtest: basic
[171.626343] Subtest basic: SUCCESS (0.160s)
[171.843842] [039/162] (891s left) gem_ringfill (basic-all)
[172.343495] Starting subtest: basic-all
[173.695566] Subtest basic-all: SUCCESS (1.346s)
[173.954849] [040/162] (889s left) gem_softpin (allocator-basic)
[174.186048] Starting subtest: allocator-basic
[174.582202] Subtest allocator-basic: SUCCESS (0.395s)
[174.886187] [041/162] (889s left) gem_softpin (allocator-basic-reserve)
[175.121338] Starting subtest: allocator-basic-reserve
[175.518229] Subtest allocator-basic-reserve: SUCCESS (0.392s)
[175.835702] [042/162] (888s left) gem_softpin (safe-alignment)
[176.081932] Starting subtest: safe-alignment
[176.136140] Subtest safe-alignment: SUCCESS (0.053s)
[176.397851] [043/162] (888s left) gem_sync (basic-all)
[176.661107] Starting subtest: basic-all
[179.836476] Subtest basic-all: SUCCESS (3.172s)
[180.201623] [044/162] (884s left) gem_sync (basic-each)
[180.465091] Starting subtest: basic-each
[184.212195] Subtest basic-each: SUCCESS (3.744s)
[184.882559] [045/162] (880s left) gem_tiled_blits (basic)
[186.105963] Subtest basic: SKIP (0.000s)
[186.682819] [046/162] (879s left) gem_tiled_fence_blits (basic)
[187.828286] Starting subtest: basic
[187.902177] Subtest basic: SUCCESS (0.072s)
[188.172806] [047/162] (877s left) gem_tiled_pread_basic
[189.598347] [048/162] (876s left) gem_wait (busy@all-engines)
[189.887820] Starting subtest: busy
[189.891976] Starting dynamic subtest: all-engines
[190.419534] Dynamic subtest all-engines: SUCCESS (0.533s)
[190.420902] Subtest busy: SUCCESS (0.534s)
[190.871725] [049/162] (875s left) gem_wait (wait@all-engines)
[191.172701] Starting subtest: wait
[191.175506] Starting dynamic subtest: all-engines
[192.230152] Dynamic subtest all-engines: SUCCESS (1.062s)
[192.234533] Subtest wait: SUCCESS (1.065s)
[192.673363] [050/162] (873s left) i915_getparams_basic (basic-eu-total)
[192.897402] Starting subtest: basic-eu-total
[192.903473] Subtest basic-eu-total: SUCCESS (0.000s)
[193.138753] [051/162] (873s left) i915_getparams_basic (basic-subslice-total)
[193.358473] Starting subtest: basic-subslice-total
[193.363179] Subtest basic-subslice-total: SUCCESS (0.000s)
[193.582092] [052/162] (873s left) i915_hangman (error-state-basic)
[193.842531] Starting subtest: error-state-basic
[193.880253] Subtest error-state-basic: SUCCESS (0.042s)
[194.167463] [053/162] (873s left) i915_pciid
[194.615488] [054/162] (872s left) intel_hwmon (hwmon-read)
[194.838109] Subtest hwmon-read: SKIP (0.000s)
[195.043856] [055/162] (872s left) intel_hwmon (hwmon-write)
[195.267979] Subtest hwmon-write: SKIP (0.000s)
[195.457648] [056/162] (872s left) kms_addfb_basic (addfb25-4-tiled)
[196.201157] Starting subtest: addfb25-4-tiled
[196.207349] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[196.456200] [057/162] (871s left) kms_addfb_basic (addfb25-bad-modifier)
[197.259853] Starting subtest: addfb25-bad-modifier
[197.262433] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[197.498290] [058/162] (870s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[198.256802] Starting subtest: addfb25-framebuffer-vs-set-tiling
[198.258230] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[198.483396] [059/162] (869s left) kms_addfb_basic (addfb25-modifier-no-flag)
[199.190902] Starting subtest: addfb25-modifier-no-flag
[199.194228] Subtest addfb25-modifier-no-flag: SUCCESS (0.002s)
[199.528774] [060/162] (869s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[200.449437] Starting subtest: addfb25-x-tiled-legacy
[200.451960] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[200.703582] [061/162] (868s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[201.435598] Starting subtest: addfb25-x-tiled-mismatch-legacy
[201.438185] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[201.671779] [062/162] (867s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[202.388381] Starting subtest: addfb25-yf-tiled-legacy
[202.389837] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s)
[202.640178] [063/162] (866s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[203.325958] Starting subtest: addfb25-y-tiled-legacy
[203.330385] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[203.575678] [064/162] (865s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[204.299463] Starting subtest: addfb25-y-tiled-small-legacy
[204.306400] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[204.551796] [065/162] (865s left) kms_addfb_basic (bad-pitch-0)
[204.775654] Starting subtest: bad-pitch-0
[204.777841] Subtest bad-pitch-0: SUCCESS (0.000s)
[205.526989] [066/162] (864s left) kms_addfb_basic (bad-pitch-1024)
[205.761593] Starting subtest: bad-pitch-1024
[205.764593] Subtest bad-pitch-1024: SUCCESS (0.000s)
[206.454658] [067/162] (863s left) kms_addfb_basic (bad-pitch-128)
[206.683615] Starting subtest: bad-pitch-128
[206.685289] Subtest bad-pitch-128: SUCCESS (0.000s)
[207.435374] [068/162] (862s left) kms_addfb_basic (bad-pitch-256)
[207.662574] Starting subtest: bad-pitch-256
[207.664886] Subtest bad-pitch-256: SUCCESS (0.000s)
[208.356358] [069/162] (862s left) kms_addfb_basic (bad-pitch-32)
[208.584587] Starting subtest: bad-pitch-32
[208.586303] Subtest bad-pitch-32: SUCCESS (0.000s)
[209.333675] [070/162] (861s left) kms_addfb_basic (bad-pitch-63)
[209.563310] Starting subtest: bad-pitch-63
[209.566809] Subtest bad-pitch-63: SUCCESS (0.000s)
[210.294152] [071/162] (860s left) kms_addfb_basic (bad-pitch-65536)
[210.512664] Starting subtest: bad-pitch-65536
[210.515315] Subtest bad-pitch-65536: SUCCESS (0.002s)
[211.239731] [072/162] (859s left) kms_addfb_basic (bad-pitch-999)
[211.461990] Starting subtest: bad-pitch-999
[211.464897] Subtest bad-pitch-999: SUCCESS (0.000s)
[212.198235] [073/162] (858s left) kms_addfb_basic (basic)
[212.427285] Starting subtest: basic
[212.430792] Subtest basic: SUCCESS (0.000s)
[213.155939] [074/162] (858s left) kms_addfb_basic (basic-x-tiled-legacy)
[213.381573] Starting subtest: basic-x-tiled-legacy
[213.383151] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[214.122568] [075/162] (857s left) kms_addfb_basic (basic-y-tiled-legacy)
[214.356217] Starting subtest: basic-y-tiled-legacy
[214.359279] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[215.167588] [076/162] (856s left) kms_addfb_basic (bo-too-small)
[215.880055] Starting subtest: bo-too-small
[215.893923] Subtest bo-too-small: SUCCESS (0.006s)
[216.133644] [077/162] (855s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[216.848092] Starting subtest: bo-too-small-due-to-tiling
[216.851462] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[217.100787] [078/162] (854s left) kms_addfb_basic (clobberred-modifier)
[217.326603] Starting subtest: clobberred-modifier
[217.330696] Subtest clobberred-modifier: SUCCESS (0.001s)
[218.076638] [079/162] (854s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[218.299902] Starting subtest: framebuffer-vs-set-tiling
[218.301485] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[219.014231] [080/162] (853s left) kms_addfb_basic (invalid-get-prop)
[219.233621] Starting subtest: invalid-get-prop
[219.236447] Subtest invalid-get-prop: SUCCESS (0.000s)
[219.942586] [081/162] (852s left) kms_addfb_basic (invalid-get-prop-any)
[220.174521] Starting subtest: invalid-get-prop-any
[220.177296] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[220.927115] [082/162] (851s left) kms_addfb_basic (invalid-set-prop)
[221.155598] Starting subtest: invalid-set-prop
[221.158243] Subtest invalid-set-prop: SUCCESS (0.000s)
[221.868638] [083/162] (851s left) kms_addfb_basic (invalid-set-prop-any)
[222.095164] Starting subtest: invalid-set-prop-any
[222.097852] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[222.836338] [084/162] (850s left) kms_addfb_basic (no-handle)
[223.059840] Starting subtest: no-handle
[223.064740] Subtest no-handle: SUCCESS (0.000s)
[223.780473] [085/162] (849s left) kms_addfb_basic (size-max)
[224.472715] Starting subtest: size-max
[224.474972] Subtest size-max: SUCCESS (0.001s)
[224.715456] [086/162] (848s left) kms_addfb_basic (small-bo)
[225.434721] Starting subtest: small-bo
[225.437896] Subtest small-bo: SUCCESS (0.002s)
[225.683652] [087/162] (848s left) kms_addfb_basic (tile-pitch-mismatch)
[225.913909] Starting subtest: tile-pitch-mismatch
[225.917797] Subtest tile-pitch-mismatch: SUCCESS (0.003s)
[226.667909] [088/162] (847s left) kms_addfb_basic (too-high)
[227.360136] Starting subtest: too-high
[227.363841] Subtest too-high: SUCCESS (0.005s)
[227.602949] [089/162] (846s left) kms_addfb_basic (too-wide)
[228.305444] Starting subtest: too-wide
[228.307115] Subtest too-wide: SUCCESS (0.000s)
[228.558465] [090/162] (845s left) kms_addfb_basic (unused-handle)
[228.783661] Starting subtest: unused-handle
[228.787392] Subtest unused-handle: SUCCESS (0.000s)
[229.526691] [091/162] (844s left) kms_addfb_basic (unused-modifier)
[229.762285] Starting subtest: unused-modifier
[229.768241] Subtest unused-modifier: SUCCESS (0.000s)
[230.572970] [092/162] (844s left) kms_addfb_basic (unused-offsets)
[230.803745] Starting subtest: unused-offsets
[230.807330] Subtest unused-offsets: SUCCESS (0.001s)
[231.555712] [093/162] (843s left) kms_addfb_basic (unused-pitches)
[231.778117] Starting subtest: unused-pitches
[231.779833] Subtest unused-pitches: SUCCESS (0.000s)
[232.504454] [094/162] (842s left) kms_busy (basic)
[233.246197] Starting subtest: basic
[233.247699] Starting dynamic subtest: flip
[234.145964] Dynamic subtest flip: SUCCESS (0.901s)
[234.179905] Starting dynamic subtest: modeset
[238.249215] Dynamic subtest modeset: SUCCESS (4.063s)
[238.272064] Subtest basic: SUCCESS (4.977s)
[240.011822] [095/162] (835s left) kms_prop_blob (basic)
[241.618403] Starting subtest: basic
[241.620091] Subtest basic: SUCCESS (0.000s)
[242.079621] [096/162] (833s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[245.493244] Starting subtest: basic-busy-flip-before-cursor-atomic
[247.328112] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (1.823s)
[249.375709] [097/162] (827s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[252.123868] Starting subtest: basic-busy-flip-before-cursor-legacy
[254.478378] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (2.330s)
[256.949545] [098/162] (821s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[259.207129] Starting subtest: basic-flip-after-cursor-atomic
[263.984174] Subtest basic-flip-after-cursor-atomic: FAIL (2.820s)
[265.841465] [099/162] (814s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[268.662461] Starting subtest: basic-flip-after-cursor-legacy
[270.306463] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.575s)
[271.977699] [100/162] (808s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[275.672394] Starting subtest: basic-flip-after-cursor-varying-size
[278.633086] Subtest basic-flip-after-cursor-varying-size: SUCCESS (2.929s)
[281.005624] [101/162] (800s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[283.430031] Starting subtest: basic-flip-before-cursor-atomic
[285.222771] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.799s)
[286.168765] [102/162] (796s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[287.356203] Starting subtest: basic-flip-before-cursor-legacy
[289.038664] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.693s)
[291.953942] [103/162] (792s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[293.490440] Starting subtest: basic-flip-before-cursor-varying-size
[294.869161] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.378s)
[295.734571] [104/162] (788s left) kms_dsc (dsc-basic)
[296.983195] Subtest dsc-basic: SKIP (0.000s)
[297.270858] [105/162] (787s left) kms_flip (basic-flip-vs-dpms)
[300.254167] Starting subtest: basic-flip-vs-dpms
[300.284113] Starting dynamic subtest: C-HDMI-A2
[303.998686] Dynamic subtest C-HDMI-A2: SUCCESS (3.716s)
[304.005115] Subtest basic-flip-vs-dpms: SUCCESS (3.753s)
[308.362166] [106/162] (779s left) kms_flip (basic-flip-vs-modeset)
[311.524866] Starting subtest: basic-flip-vs-modeset
[311.596872] Starting dynamic subtest: C-HDMI-A2
[315.342683] Dynamic subtest C-HDMI-A2: SUCCESS (3.728s)
[315.355136] Subtest basic-flip-vs-modeset: SUCCESS (3.801s)
[317.455160] [107/162] (770s left) kms_flip (basic-flip-vs-wf_vblank)
[323.128512] Starting subtest: basic-flip-vs-wf_vblank
[323.243213] Starting dynamic subtest: C-HDMI-A2
[336.981115] Dynamic subtest C-HDMI-A2: FAIL (12.942s)
[336.982541] Subtest basic-flip-vs-wf_vblank: FAIL (13.072s)
[339.464992] [108/162] (749s left) kms_flip (basic-plain-flip)
[341.633901] Starting subtest: basic-plain-flip
[341.686818] Starting dynamic subtest: C-HDMI-A2
[345.863144] Dynamic subtest C-HDMI-A2: SUCCESS (4.173s)
[345.866178] Subtest basic-plain-flip: SUCCESS (4.229s)
[346.240928] [109/162] (743s left) kms_force_connector_basic (force-connector-state)
[347.578172] Starting subtest: force-connector-state
[350.166794] Subtest force-connector-state: SUCCESS (2.592s)
[354.331313] [110/162] (735s left) kms_force_connector_basic (force-edid)
[355.119148] Starting subtest: force-edid
[355.420194] Subtest force-edid: SUCCESS (0.475s)
[358.705149] [111/162] (731s left) kms_force_connector_basic (force-load-detect)
[359.306175] Starting subtest: force-load-detect
[359.311150] Subtest force-load-detect: SKIP (0.000s)
[362.884329] [112/162] (730s left) kms_force_connector_basic (prune-stale-modes)
[367.242714] Starting subtest: prune-stale-modes
[368.466600] Subtest prune-stale-modes: SUCCESS (1.220s)
[374.210151] [113/162] (721s left) kms_frontbuffer_tracking (basic)
[375.318102] Starting subtest: basic
[395.088829] Subtest basic: SUCCESS (19.682s)
[395.471190] [114/162] (700s left) kms_hdmi_inject (inject-audio)
[397.121157] Starting subtest: inject-audio
[397.762516] Subtest inject-audio: SUCCESS (0.650s)
[400.094419] [115/162] (695s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[402.795098] Starting subtest: compare-crc-sanitycheck-xr24
[402.798986] Starting dynamic subtest: pipe-C-HDMI-A-2
[404.129288] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.340s)
[404.134426] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.351s)
[406.836222] [116/162] (691s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[410.987064] Starting subtest: compare-crc-sanitycheck-nv12
[410.997859] Starting dynamic subtest: pipe-C-HDMI-A-2
[411.018090] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[411.022900] Subtest compare-crc-sanitycheck-nv12: SKIP (0.035s)
[411.329150] [117/162] (687s left) kms_pipe_crc_basic (hang-read-crc)
[414.423418] Starting subtest: hang-read-crc
[414.443219] Starting dynamic subtest: pipe-C-HDMI-A-2
[418.481182] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (4.281s)
[418.494343] Subtest hang-read-crc: SUCCESS (4.293s)
[419.835436] [118/162] (679s left) kms_pipe_crc_basic (nonblocking-crc)
[421.387089] Starting subtest: nonblocking-crc
[421.389984] Starting dynamic subtest: pipe-C-HDMI-A-2
[424.559162] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (3.171s)
[424.564153] Subtest nonblocking-crc: SUCCESS (3.181s)
[427.683956] [119/162] (674s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[431.417061] Starting subtest: nonblocking-crc-frame-sequence
[431.548263] Starting dynamic subtest: pipe-C-HDMI-A-2
[432.587167] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.069s)
[432.593643] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.431s)
[433.160182] [120/162] (669s left) kms_pipe_crc_basic (read-crc)
[436.259311] Starting subtest: read-crc
[436.280591] Starting dynamic subtest: pipe-C-HDMI-A-2
[437.121564] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.839s)
[437.125108] Subtest read-crc: SUCCESS (0.964s)
[439.278110] [121/162] (664s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[440.740569] Starting subtest: read-crc-frame-sequence
[440.749305] Starting dynamic subtest: pipe-C-HDMI-A-2
[442.547371] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.794s)
[442.556919] Subtest read-crc-frame-sequence: SUCCESS (1.815s)
[445.066422] [122/162] (661s left) kms_pm_backlight (basic-brightness)
[446.221498] Subtest basic-brightness: SKIP (0.000s)
[448.381854] [123/162] (660s left) kms_pm_rpm (basic-pci-d3-state)
[454.787473] Starting subtest: basic-pci-d3-state
[455.359387] Subtest basic-pci-d3-state: SUCCESS (0.583s)
[455.883170] [124/162] (652s left) kms_pm_rpm (basic-rte)
[456.215990] Starting subtest: basic-rte
[460.278219] Subtest basic-rte: SUCCESS (4.016s)
[461.596112] [125/162] (647s left) kms_psr (psr-primary-page-flip)
[464.010839] Subtest psr-primary-page-flip: SKIP (0.000s)
[465.496817] [126/162] (644s left) kms_psr (psr-cursor-plane-move)
[469.426084] Subtest psr-cursor-plane-move: SKIP (0.000s)
[469.922184] [127/162] (640s left) kms_psr (psr-sprite-plane-onoff)
[474.367129] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[474.726139] [128/162] (636s left) kms_psr (psr-primary-mmap-gtt)
[476.451107] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[477.654225] [129/162] (634s left) kms_setmode (basic-clone-single-crtc)
[481.007982] Starting subtest: basic-clone-single-crtc
[481.015986] Subtest basic-clone-single-crtc: SKIP (0.001s)
[481.429609] [130/162] (632s left) i915_pm_rps (basic-api)
[483.242081] Starting subtest: basic-api
[484.259979] Subtest basic-api: SUCCESS (1.011s)
[486.150502] [131/162] (629s left) prime_self_import (basic-llseek-bad)
[486.877074] Starting subtest: basic-llseek-bad
[486.896253] Subtest basic-llseek-bad: SUCCESS (0.020s)
[487.210162] [132/162] (628s left) prime_self_import (basic-llseek-size)
[487.489226] Starting subtest: basic-llseek-size
[487.520809] Subtest basic-llseek-size: SUCCESS (0.033s)
[490.969820] [133/162] (628s left) prime_self_import (basic-with_fd_dup)
[491.320399] Starting subtest: basic-with_fd_dup
[491.350951] Subtest basic-with_fd_dup: SUCCESS (0.031s)
[491.597521] [134/162] (627s left) prime_self_import (basic-with_one_bo)
[491.829944] Starting subtest: basic-with_one_bo
[491.869242] Subtest basic-with_one_bo: SUCCESS (0.040s)
[492.148775] [135/162] (627s left) prime_self_import (basic-with_one_bo_two_files)
[492.408313] Starting subtest: basic-with_one_bo_two_files
[492.448194] Subtest basic-with_one_bo_two_files: SUCCESS (0.045s)
[492.696201] [136/162] (627s left) prime_self_import (basic-with_two_bos)
[496.848613] Starting subtest: basic-with_two_bos
[496.989542] Subtest basic-with_two_bos: SUCCESS (0.020s)
[497.298368] [137/162] (622s left) prime_vgem (basic-fence-flip)
[497.698177] Starting subtest: basic-fence-flip
[498.750136] Subtest basic-fence-flip: SKIP (1.035s)
[499.068134] [138/162] (621s left) prime_vgem (basic-fence-mmap)
[499.348141] Starting subtest: basic-fence-mmap
[499.464092] Subtest basic-fence-mmap: SUCCESS (0.110s)
[499.706449] [139/162] (620s left) prime_vgem (basic-fence-read)
[500.173598] Starting subtest: basic-fence-read
[500.432653] Subtest basic-fence-read: SUCCESS (0.264s)
[500.689409] [140/162] (620s left) prime_vgem (basic-gtt)
[501.019274] Starting subtest: basic-gtt
[501.067951] Subtest basic-gtt: SUCCESS (0.055s)
[501.359342] [141/162] (619s left) prime_vgem (basic-read)
[501.668493] Starting subtest: basic-read
[501.797229] Subtest basic-read: SUCCESS (0.121s)
[502.033574] [142/162] (619s left) prime_vgem (basic-write)
[503.499086] Starting subtest: basic-write
[504.176096] Subtest basic-write: SUCCESS (0.702s)
[506.593223] [143/162] (615s left) vgem_basic (setversion)
[506.920606] Starting subtest: setversion
[506.933116] Subtest setversion: SUCCESS (0.000s)
[507.164788] [144/162] (614s left) vgem_basic (create)
[507.493994] Starting subtest: create
[507.495516] Subtest create: SUCCESS (0.005s)
[507.724745] [145/162] (614s left) vgem_basic (debugfs)
[507.987891] Starting subtest: debugfs
[507.999911] Subtest debugfs: SUCCESS (0.012s)
[508.241315] [146/162] (614s left) vgem_basic (dmabuf-export)
[508.576816] Starting subtest: dmabuf-export
[508.598655] Subtest dmabuf-export: SUCCESS (0.024s)
[511.722414] [147/162] (613s left) vgem_basic (dmabuf-fence)
[513.903098] Starting subtest: dmabuf-fence
[513.908634] Subtest dmabuf-fence: SUCCESS (0.007s)
[514.270193] [148/162] (612s left) vgem_basic (dmabuf-fence-before)
[514.589839] Starting subtest: dmabuf-fence-before
[514.593295] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[514.988208] [149/162] (611s left) vgem_basic (dmabuf-mmap)
[517.154367] Starting subtest: dmabuf-mmap
[517.237206] Subtest dmabuf-mmap: SUCCESS (0.049s)
[520.294242] [150/162] (609s left) vgem_basic (mmap)
[520.777611] Starting subtest: mmap
[520.810079] Subtest mmap: SUCCESS (0.037s)
[521.094158] [151/162] (608s left) vgem_basic (second-client)
[521.407193] Starting subtest: second-client
[521.479274] Subtest second-client: SUCCESS (0.062s)
[523.956156] [152/162] (608s left) vgem_basic (sysfs)
[525.886270] Starting subtest: sysfs
[525.905575] Subtest sysfs: SUCCESS (0.015s)
[526.325754] [153/162] (606s left) core_hotunplug (unbind-rebind)
[526.865166] Starting subtest: unbind-rebind
[542.403988] Subtest unbind-rebind: SUCCESS (15.537s)
[542.639172] [154/162] (590s left) vgem_basic (unload)
[543.118246] Starting subtest: unload
[578.774795] Subtest unload: SUCCESS (35.775s)
[581.923156] [155/162] (551s left) i915_module_load (reload)
[582.199326] Starting subtest: reload
[595.059067] Subtest reload: SUCCESS (12.864s)
[595.442169] [156/162] (538s left) gem_lmem_swapping (basic)
[596.056062] Subtest basic: SKIP (0.000s)
[598.224482] [157/162] (537s left) gem_lmem_swapping (parallel-random-engines)
[599.434165] Subtest parallel-random-engines: SKIP (0.000s)
[600.195948] [158/162] (536s left) gem_lmem_swapping (random-engines)
[601.519684] Subtest random-engines: SKIP (0.000s)
[601.959820] [159/162] (535s left) gem_lmem_swapping (verify-random)
[602.547636] Subtest verify-random: SKIP (0.000s)
[602.847154] [160/162] (534s left) i915_pm_rpm (module-reload)
[606.869000] Starting subtest: module-reload
[641.570387] Abort requested by /sbin/init [1] via Terminated, terminating children
[642.261335] Abort requested by /sbin/init [1] via Hangup, terminating children
[642.261557] Exiting gracefully, currently running test will have a 'notrun' result
[642.400598] Abort requested by sudo IGT_PING_HOSTNAME=10.211.124.1 IGT_CI_META_TEST=yes IGT_REBOOT_ON_FATAL_ERROR=yes IGT_CI_RUN=1 IGT_FORCE_DRIVER=i9 [1373] via Terminated, terminating children
[645.885664] Closing watchdogs
results: parsing output: 0/ for test: i915_module_load
results: parsing output: 1/ for test: core_auth
results: parsing output: 2/ for test: core_debugfs
results: parsing output: 3/ for test: core_sysfs
results: parsing output: 4/ for test: fbdev
results: parsing output: 5/ for test: fbdev
results: parsing output: 6/ for test: fbdev
results: parsing output: 7/ for test: fbdev
results: parsing output: 8/ for test: fbdev
results: parsing output: 9/ for test: gem_basic
results: parsing output: 10/ for test: gem_basic
results: parsing output: 11/ for test: gem_basic
results: parsing output: 12/ for test: gem_busy
results: parsing output: 13/ for test: gem_close_race
results: parsing output: 14/ for test: gem_close_race
results: parsing output: 15/ for test: gem_ctx_create
results: parsing output: 16/ for test: gem_ctx_create
results: parsing output: 17/ for test: gem_ctx_exec
results: parsing output: 18/ for test: gem_exec_basic
results: parsing output: 19/ for test: gem_exec_create
results: parsing output: 20/ for test: gem_exec_fence
results: parsing output: 21/ for test: gem_exec_fence
results: parsing output: 22/ for test: gem_exec_fence
results: parsing output: 23/ for test: gem_exec_fence
results: parsing output: 24/ for test: gem_exec_gttfill
results: parsing output: 25/ for test: gem_exec_parallel
results: parsing output: 26/ for test: gem_exec_store
results: parsing output: 27/ for test: gem_flink_basic
results: parsing output: 28/ for test: gem_flink_basic
results: parsing output: 29/ for test: gem_flink_basic
results: parsing output: 30/ for test: gem_flink_basic
results: parsing output: 31/ for test: gem_flink_basic
results: parsing output: 32/ for test: gem_huc_copy
results: parsing output: 33/ for test: gem_linear_blits
results: parsing output: 34/ for test: gem_mmap
results: parsing output: 35/ for test: gem_mmap_gtt
results: parsing output: 36/ for test: gem_render_linear_blits
results: parsing output: 37/ for test: gem_render_tiled_blits
results: parsing output: 38/ for test: gem_ringfill
results: parsing output: 39/ for test: gem_softpin
results: parsing output: 40/ for test: gem_softpin
results: parsing output: 41/ for test: gem_softpin
results: parsing output: 42/ for test: gem_sync
results: parsing output: 43/ for test: gem_sync
results: parsing output: 44/ for test: gem_tiled_blits
results: parsing output: 45/ for test: gem_tiled_fence_blits
results: parsing output: 46/ for test: gem_tiled_pread_basic
results: parsing output: 47/ for test: gem_wait
results: parsing output: 48/ for test: gem_wait
results: parsing output: 49/ for test: i915_getparams_basic
results: parsing output: 50/ for test: i915_getparams_basic
results: parsing output: 51/ for test: i915_hangman
results: parsing output: 52/ for test: i915_pciid
results: parsing output: 53/ for test: intel_hwmon
results: parsing output: 54/ for test: intel_hwmon
results: parsing output: 55/ for test: kms_addfb_basic
results: parsing output: 56/ for test: kms_addfb_basic
results: parsing output: 57/ for test: kms_addfb_basic
results: parsing output: 58/ for test: kms_addfb_basic
results: parsing output: 59/ for test: kms_addfb_basic
results: parsing output: 60/ for test: kms_addfb_basic
results: parsing output: 61/ for test: kms_addfb_basic
results: parsing output: 62/ for test: kms_addfb_basic
results: parsing output: 63/ for test: kms_addfb_basic
results: parsing output: 64/ for test: kms_addfb_basic
results: parsing output: 65/ for test: kms_addfb_basic
results: parsing output: 66/ for test: kms_addfb_basic
results: parsing output: 67/ for test: kms_addfb_basic
results: parsing output: 68/ for test: kms_addfb_basic
results: parsing output: 69/ for test: kms_addfb_basic
results: parsing output: 70/ for test: kms_addfb_basic
results: parsing output: 71/ for test: kms_addfb_basic
results: parsing output: 72/ for test: kms_addfb_basic
results: parsing output: 73/ for test: kms_addfb_basic
results: parsing output: 74/ for test: kms_addfb_basic
results: parsing output: 75/ for test: kms_addfb_basic
results: parsing output: 76/ for test: kms_addfb_basic
results: parsing output: 77/ for test: kms_addfb_basic
results: parsing output: 78/ for test: kms_addfb_basic
results: parsing output: 79/ for test: kms_addfb_basic
results: parsing output: 80/ for test: kms_addfb_basic
results: parsing output: 81/ for test: kms_addfb_basic
results: parsing output: 82/ for test: kms_addfb_basic
results: parsing output: 83/ for test: kms_addfb_basic
results: parsing output: 84/ for test: kms_addfb_basic
results: parsing output: 85/ for test: kms_addfb_basic
results: parsing output: 86/ for test: kms_addfb_basic
results: parsing output: 87/ for test: kms_addfb_basic
results: parsing output: 88/ for test: kms_addfb_basic
results: parsing output: 89/ for test: kms_addfb_basic
results: parsing output: 90/ for test: kms_addfb_basic
results: parsing output: 91/ for test: kms_addfb_basic
results: parsing output: 92/ for test: kms_addfb_basic
results: parsing output: 93/ for test: kms_busy
results: parsing output: 94/ for test: kms_prop_blob
results: parsing output: 95/ for test: kms_cursor_legacy
results: parsing output: 96/ for test: kms_cursor_legacy
results: parsing output: 97/ for test: kms_cursor_legacy
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_cursor_legacy
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: kms_cursor_legacy
results: parsing output: 102/ for test: kms_cursor_legacy
results: parsing output: 103/ for test: kms_dsc
results: parsing output: 104/ for test: kms_flip
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: kms_force_connector_basic
results: parsing output: 109/ for test: kms_force_connector_basic
results: parsing output: 110/ for test: kms_force_connector_basic
results: parsing output: 111/ for test: kms_force_connector_basic
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_hdmi_inject
results: parsing output: 114/ for test: kms_pipe_crc_basic
results: parsing output: 115/ for test: kms_pipe_crc_basic
results: parsing output: 116/ for test: kms_pipe_crc_basic
results: parsing output: 117/ for test: kms_pipe_crc_basic
results: parsing output: 118/ for test: kms_pipe_crc_basic
results: parsing output: 119/ for test: kms_pipe_crc_basic
results: parsing output: 120/ for test: kms_pipe_crc_basic
results: parsing output: 121/ for test: kms_pm_backlight
results: parsing output: 122/ for test: kms_pm_rpm
results: parsing output: 123/ for test: kms_pm_rpm
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: kms_psr
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: kms_setmode
results: parsing output: 129/ for test: i915_pm_rps
results: parsing output: 130/ for test: prime_self_import
results: parsing output: 131/ for test: prime_self_import
results: parsing output: 132/ for test: prime_self_import
results: parsing output: 133/ for test: prime_self_import
results: parsing output: 134/ for test: prime_self_import
results: parsing output: 135/ for test: prime_self_import
results: parsing output: 136/ for test: prime_vgem
results: parsing output: 137/ for test: prime_vgem
results: parsing output: 138/ for test: prime_vgem
results: parsing output: 139/ for test: prime_vgem
results: parsing output: 140/ for test: prime_vgem
results: parsing output: 141/ for test: prime_vgem
results: parsing output: 142/ for test: vgem_basic
results: parsing output: 143/ for test: vgem_basic
results: parsing output: 144/ for test: vgem_basic
results: parsing output: 145/ for test: vgem_basic
results: parsing output: 146/ for test: vgem_basic
results: parsing output: 147/ for test: vgem_basic
results: parsing output: 148/ for test: vgem_basic
results: parsing output: 149/ for test: vgem_basic
results: parsing output: 150/ for test: vgem_basic
results: parsing output: 151/ for test: vgem_basic
results: parsing output: 152/ for test: core_hotunplug
results: parsing output: 153/ for test: vgem_basic
results: parsing output: 154/ for test: i915_module_load
results: parsing output: 155/ for test: gem_lmem_swapping
results: parsing output: 156/ for test: gem_lmem_swapping
results: parsing output: 157/ for test: gem_lmem_swapping
results: parsing output: 158/ for test: gem_lmem_swapping
results: parsing output: 159/ for test: i915_pm_rpm
results: parsing output: 160/ for test: i915_selftest
results: no output, setting notrun
results: parsing output: 161/ for test: dmabuf
results: no output, setting notrun
Done.