[123.601622] Initializing watchdogs
[123.602497]   /dev/watchdog0
[123.742251] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:a001 Intel Pineview (Gen3) Atom Processor D4xx/D5xx/N4xx/N5xx Integrated Graphics Controller (DeskTop Board D510MO)
[123.777343] [001/162] (960s left) i915_module_load (load)
[124.294120] Starting subtest: load
[132.985524] Subtest load: SUCCESS (8.707s)
[133.335340] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[133.343294] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[133.362784] [002/162] (951s left) core_auth (basic-auth)
[133.792956] Starting subtest: basic-auth
[133.798360] Subtest basic-auth: SUCCESS (0.005s)
[134.135992] [003/162] (950s left) core_debugfs (read-all-entries)
[134.534984] Starting subtest: read-all-entries
[134.634918] Subtest read-all-entries: SUCCESS (0.103s)
[134.977710] [004/162] (950s left) core_sysfs (read-all-entries)
[135.366329] Starting subtest: read-all-entries
[135.381665] Subtest read-all-entries: SUCCESS (0.014s)
[135.717942] [005/162] (949s left) fbdev (eof)
[136.133774] Starting subtest: eof
[136.138693] Subtest eof: SUCCESS (0.003s)
[136.460897] [006/162] (949s left) fbdev (info)
[136.865133] Starting subtest: info
[136.869799] Subtest info: SUCCESS (0.000s)
[137.161151] [007/162] (948s left) fbdev (nullptr)
[137.559707] Starting subtest: nullptr
[137.564763] Subtest nullptr: SUCCESS (0.000s)
[137.866638] [008/162] (948s left) fbdev (read)
[138.257759] Starting subtest: read
[138.582852] Subtest read: SUCCESS (0.326s)
[138.895931] [009/162] (947s left) fbdev (write)
[139.360145] Starting subtest: write
[139.538413] Subtest write: SUCCESS (0.183s)
[139.849521] [010/162] (947s left) gem_basic (bad-close)
[140.243638] Starting subtest: bad-close
[140.252564] Subtest bad-close: SUCCESS (0.000s)
[140.567637] [011/162] (946s left) gem_basic (create-close)
[140.950674] Starting subtest: create-close
[140.964248] Subtest create-close: SUCCESS (0.001s)
[141.265372] [012/162] (946s left) gem_basic (create-fd-close)
[141.651666] Starting subtest: create-fd-close
[141.661523] Subtest create-fd-close: SUCCESS (0.001s)
[141.971991] [013/162] (945s left) gem_busy (busy@all-engines)
[142.454210] Starting subtest: busy
[142.458912] Starting dynamic subtest: all-engines
[142.568385] Dynamic subtest all-engines: SUCCESS (0.115s)
[142.572341] Subtest busy: SUCCESS (0.121s)
[143.251732] [014/162] (944s left) gem_close_race (basic-process)
[143.729167] Starting subtest: basic-process
[143.926499] Subtest basic-process: SUCCESS (0.204s)
[144.387636] [015/162] (943s left) gem_close_race (basic-threads)
[144.847729] Starting subtest: basic-threads
[146.317627] Subtest basic-threads: SUCCESS (1.470s)
[146.773214] [016/162] (941s left) gem_ctx_create (basic)
[147.185649] Subtest basic: SKIP (0.000s)
[147.490259] [017/162] (941s left) gem_ctx_create (basic-files)
[147.908606] Subtest basic-files: SKIP (0.000s)
[148.205899] [018/162] (940s left) gem_ctx_exec (basic)
[148.604771] Subtest basic: SKIP (0.000s)
[148.942464] [019/162] (940s left) gem_exec_basic (basic)
[149.385142] Starting subtest: basic
[149.388952] Starting dynamic subtest: rcs0-smem
[149.392751] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[149.396324] Subtest basic: SUCCESS (0.009s)
[149.839406] [020/162] (939s left) gem_exec_create (basic)
[150.308164] Starting subtest: basic
[150.316259] Starting dynamic subtest: smem
[152.441616] Dynamic subtest smem: SUCCESS (2.135s)
[152.445298] Subtest basic: SUCCESS (2.140s)
[152.914427] [021/162] (937s left) gem_exec_fence (basic-busy)
[153.580814] Starting subtest: basic-busy
[153.584768] Starting dynamic subtest: rcs0
[153.627978] Dynamic subtest rcs0: SUCCESS (0.054s)
[153.632314] Subtest basic-busy: SUCCESS (0.060s)
[154.820843] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[154.833894] [022/162] (935s left) gem_exec_fence (basic-wait)
[155.503279] Starting subtest: basic-wait
[155.507322] Starting dynamic subtest: rcs0
[155.538571] Dynamic subtest rcs0: SUCCESS (0.037s)
[155.543461] Subtest basic-wait: SUCCESS (0.041s)
[156.459864] [023/162] (934s left) gem_exec_fence (basic-await)
[157.133350] Starting subtest: basic-await
[157.137470] Starting dynamic subtest: rcs0
[157.249854] Dynamic subtest rcs0: SUCCESS (0.119s)
[157.254503] Subtest basic-await: SUCCESS (0.125s)
[158.175174] [024/162] (932s left) gem_exec_fence (nb-await)
[158.833645] Starting subtest: nb-await
[158.838457] Starting dynamic subtest: rcs0
[158.900297] Dynamic subtest rcs0: SUCCESS (0.069s)
[158.904216] Subtest nb-await: SUCCESS (0.074s)
[159.852256] [025/162] (931s left) gem_exec_gttfill (basic)
[160.309167] Starting subtest: basic
[168.636929] Subtest basic: SUCCESS (8.329s)
[169.291120] [026/162] (922s left) gem_exec_parallel (engines)
[170.047922] Starting subtest: engines
[170.051815] Starting dynamic subtest: basic
[173.972454] Dynamic subtest basic: SUCCESS (3.930s)
[174.046257] Starting dynamic subtest: contexts
[174.054988] Dynamic subtest contexts: SKIP (0.003s)
[174.111787] Starting dynamic subtest: fds
[174.117929] Dynamic subtest fds: SKIP (0.004s)
[174.171262] Starting dynamic subtest: userptr
[178.035636] Dynamic subtest userptr: SUCCESS (4.045s)
[178.039159] Subtest engines: SUCCESS (7.997s)
[178.539391] [027/162] (913s left) gem_exec_store (basic)
[179.008484] Starting subtest: basic
[179.058477] Subtest basic: SUCCESS (0.054s)
[179.565547] [028/162] (912s left) gem_flink_basic (bad-flink)
[179.958430] Starting subtest: bad-flink
[179.963142] Subtest bad-flink: SUCCESS (0.000s)
[180.313882] [029/162] (912s left) gem_flink_basic (bad-open)
[180.707751] Starting subtest: bad-open
[180.712667] Subtest bad-open: SUCCESS (0.000s)
[181.061705] [030/162] (911s left) gem_flink_basic (basic)
[181.459378] Starting subtest: basic
[181.462922] Subtest basic: SUCCESS (0.001s)
[181.834883] [031/162] (911s left) gem_flink_basic (double-flink)
[182.225311] Starting subtest: double-flink
[182.230356] Subtest double-flink: SUCCESS (0.001s)
[182.599702] [032/162] (910s left) gem_flink_basic (flink-lifetime)
[182.988171] Starting subtest: flink-lifetime
[183.003633] Subtest flink-lifetime: SUCCESS (0.013s)
[183.366211] [033/162] (910s left) gem_huc_copy (huc-copy)
[183.764479] Subtest huc-copy: SKIP (0.000s)
[184.150587] [034/162] (910s left) gem_linear_blits (basic)
[184.910359] Starting subtest: basic
[184.970518] Subtest basic: SUCCESS (0.062s)
[185.396752] [035/162] (909s left) gem_mmap (basic)
[186.060317] Starting subtest: basic
[186.078302] Subtest basic: SUCCESS (0.003s)
[186.418527] [036/162] (908s left) gem_mmap_gtt (basic)
[186.810624] Starting subtest: basic
[186.815372] Subtest basic: SUCCESS (0.006s)
[187.189511] [037/162] (908s left) gem_render_linear_blits (basic)
[187.608458] Starting subtest: basic
[187.779819] Subtest basic: SUCCESS (0.165s)
[188.121293] [038/162] (907s left) gem_render_tiled_blits (basic)
[188.522922] Starting subtest: basic
[188.695589] Subtest basic: SUCCESS (0.169s)
[189.044441] [039/162] (906s left) gem_ringfill (basic-all)
[189.582080] Starting subtest: basic-all
[190.776599] Subtest basic-all: SUCCESS (1.194s)
[191.140411] [040/162] (905s left) gem_softpin (allocator-basic)
[191.548563] Subtest allocator-basic: SKIP (0.000s)
[191.932136] [041/162] (904s left) gem_softpin (allocator-basic-reserve)
[192.332941] Subtest allocator-basic-reserve: SKIP (0.000s)
[192.685968] [042/162] (904s left) gem_softpin (safe-alignment)
[193.084986] Starting subtest: safe-alignment
[193.105652] Subtest safe-alignment: SUCCESS (0.026s)
[193.479253] [043/162] (903s left) gem_sync (basic-all)
[193.949197] Starting subtest: basic-all
[196.282903] Subtest basic-all: SUCCESS (2.335s)
[196.798930] [044/162] (900s left) gem_sync (basic-each)
[197.277782] Starting subtest: basic-each
[199.711879] Subtest basic-each: SUCCESS (2.434s)
[200.197351] [045/162] (897s left) gem_tiled_blits (basic)
[200.955255] Starting subtest: basic
[201.066114] Subtest basic: SUCCESS (0.111s)
[201.496170] [046/162] (896s left) gem_tiled_fence_blits (basic)
[202.569515] Starting subtest: basic
[202.631589] Subtest basic: SUCCESS (0.067s)
[203.047434] [047/162] (895s left) gem_tiled_pread_basic (basic)
[203.703316] Starting subtest: basic
[206.054275] Subtest basic: SUCCESS (2.354s)
[206.419128] [048/162] (892s left) gem_wait (busy@all-engines)
[206.919177] Starting subtest: busy
[206.923202] Starting dynamic subtest: all-engines
[207.448875] Dynamic subtest all-engines: SUCCESS (0.534s)
[207.453967] Subtest busy: SUCCESS (0.541s)
[208.072615] [049/162] (891s left) gem_wait (wait@all-engines)
[208.576587] Starting subtest: wait
[208.581351] Starting dynamic subtest: all-engines
[209.621981] Dynamic subtest all-engines: SUCCESS (1.050s)
[209.627457] Subtest wait: SUCCESS (1.056s)
[210.256485] [050/162] (889s left) i915_getparams_basic (basic-eu-total)
[210.658929] Starting subtest: basic-eu-total
[210.670332] Subtest basic-eu-total: SUCCESS (0.000s)
[211.022628] [051/162] (888s left) i915_getparams_basic (basic-subslice-total)
[211.409396] Starting subtest: basic-subslice-total
[211.420711] Subtest basic-subslice-total: SUCCESS (0.000s)
[211.771698] [052/162] (888s left) i915_hangman (error-state-basic)
[212.200078] Starting subtest: error-state-basic
[212.642668] Subtest error-state-basic: SUCCESS (0.432s)
[212.995775] [053/162] (887s left) i915_pciid
[213.748780] [054/162] (887s left) intel_hwmon (hwmon-read)
[214.195157] Subtest hwmon-read: SKIP (0.000s)
[214.564354] [055/162] (886s left) intel_hwmon (hwmon-write)
[214.954339] Subtest hwmon-write: SKIP (0.000s)
[215.285223] [056/162] (886s left) kms_addfb_basic (addfb25-4-tiled)
[215.770877] Starting subtest: addfb25-4-tiled
[215.774820] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[216.153717] [057/162] (885s left) kms_addfb_basic (addfb25-bad-modifier)
[216.600517] Starting subtest: addfb25-bad-modifier
[216.605149] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[216.980628] [058/162] (885s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[217.417511] Starting subtest: addfb25-framebuffer-vs-set-tiling
[217.422149] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[217.805591] [059/162] (884s left) kms_addfb_basic (addfb25-modifier-no-flag)
[218.267121] Starting subtest: addfb25-modifier-no-flag
[218.270607] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[218.657253] [060/162] (884s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[219.102833] Starting subtest: addfb25-x-tiled-legacy
[219.106725] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[219.489545] [061/162] (883s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[219.940232] Starting subtest: addfb25-x-tiled-mismatch-legacy
[219.945989] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[220.304178] [062/162] (883s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[220.756805] Starting subtest: addfb25-yf-tiled-legacy
[220.762400] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[221.128997] [063/162] (882s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[221.588729] Starting subtest: addfb25-y-tiled-legacy
[221.593728] Subtest addfb25-y-tiled-legacy: SUCCESS (0.002s)
[221.965987] [064/162] (882s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[222.421955] Starting subtest: addfb25-y-tiled-small-legacy
[222.431362] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[222.813184] [065/162] (881s left) kms_addfb_basic (bad-pitch-0)
[223.209893] Starting subtest: bad-pitch-0
[223.215357] Subtest bad-pitch-0: SUCCESS (0.000s)
[223.647766] [066/162] (881s left) kms_addfb_basic (bad-pitch-1024)
[224.049723] Starting subtest: bad-pitch-1024
[224.053933] Subtest bad-pitch-1024: SUCCESS (0.000s)
[224.467363] [067/162] (880s left) kms_addfb_basic (bad-pitch-128)
[224.866586] Starting subtest: bad-pitch-128
[224.870460] Subtest bad-pitch-128: SUCCESS (0.000s)
[225.323945] [068/162] (880s left) kms_addfb_basic (bad-pitch-256)
[225.718321] Starting subtest: bad-pitch-256
[225.722783] Subtest bad-pitch-256: SUCCESS (0.000s)
[226.139784] [069/162] (879s left) kms_addfb_basic (bad-pitch-32)
[226.535426] Starting subtest: bad-pitch-32
[226.540476] Subtest bad-pitch-32: SUCCESS (0.000s)
[226.970838] [070/162] (879s left) kms_addfb_basic (bad-pitch-63)
[227.370700] Starting subtest: bad-pitch-63
[227.375377] Subtest bad-pitch-63: SUCCESS (0.000s)
[227.806860] [071/162] (878s left) kms_addfb_basic (bad-pitch-65536)
[228.195785] Starting subtest: bad-pitch-65536
[228.200469] Subtest bad-pitch-65536: SUCCESS (0.002s)
[228.628694] [072/162] (878s left) kms_addfb_basic (bad-pitch-999)
[229.036439] Starting subtest: bad-pitch-999
[229.041118] Subtest bad-pitch-999: SUCCESS (0.000s)
[229.579418] [073/162] (877s left) kms_addfb_basic (basic)
[229.978300] Starting subtest: basic
[229.983419] Subtest basic: SUCCESS (0.000s)
[230.415709] [074/162] (877s left) kms_addfb_basic (basic-x-tiled-legacy)
[230.824774] Starting subtest: basic-x-tiled-legacy
[230.829226] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[231.249261] [075/162] (876s left) kms_addfb_basic (basic-y-tiled-legacy)
[231.648222] Starting subtest: basic-y-tiled-legacy
[231.651776] Subtest basic-y-tiled-legacy: SUCCESS (0.003s)
[232.097286] [076/162] (875s left) kms_addfb_basic (bo-too-small)
[232.541638] Starting subtest: bo-too-small
[232.546632] Subtest bo-too-small: SUCCESS (0.002s)
[232.942555] [077/162] (875s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[233.392799] Starting subtest: bo-too-small-due-to-tiling
[233.397765] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[233.786795] [078/162] (874s left) kms_addfb_basic (clobberred-modifier)
[234.178356] Starting subtest: clobberred-modifier
[234.183193] Subtest clobberred-modifier: SUCCESS (0.001s)
[234.602731] [079/162] (874s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[235.001651] Starting subtest: framebuffer-vs-set-tiling
[235.005620] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[235.430825] [080/162] (873s left) kms_addfb_basic (invalid-get-prop)
[235.837862] Starting subtest: invalid-get-prop
[235.842646] Subtest invalid-get-prop: SUCCESS (0.000s)
[236.263876] [081/162] (873s left) kms_addfb_basic (invalid-get-prop-any)
[236.654972] Starting subtest: invalid-get-prop-any
[236.660628] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[237.098301] [082/162] (872s left) kms_addfb_basic (invalid-set-prop)
[237.497584] Starting subtest: invalid-set-prop
[237.501958] Subtest invalid-set-prop: SUCCESS (0.000s)
[237.910406] [083/162] (872s left) kms_addfb_basic (invalid-set-prop-any)
[238.297388] Starting subtest: invalid-set-prop-any
[238.301764] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[238.758381] [084/162] (872s left) kms_addfb_basic (no-handle)
[239.161776] Starting subtest: no-handle
[239.165929] Subtest no-handle: SUCCESS (0.000s)
[239.605448] [085/162] (871s left) kms_addfb_basic (size-max)
[240.048869] Starting subtest: size-max
[240.054332] Subtest size-max: SUCCESS (0.001s)
[240.417845] [086/162] (871s left) kms_addfb_basic (small-bo)
[240.877666] Starting subtest: small-bo
[240.881661] Subtest small-bo: SUCCESS (0.001s)
[241.268275] [087/162] (870s left) kms_addfb_basic (tile-pitch-mismatch)
[241.669630] Starting subtest: tile-pitch-mismatch
[241.673879] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[242.095483] [088/162] (869s left) kms_addfb_basic (too-high)
[242.547437] Starting subtest: too-high
[242.551693] Subtest too-high: SUCCESS (0.007s)
[242.963110] [089/162] (869s left) kms_addfb_basic (too-wide)
[243.411942] Starting subtest: too-wide
[243.418795] Subtest too-wide: SUCCESS (0.000s)
[243.811636] [090/162] (868s left) kms_addfb_basic (unused-handle)
[244.276871] Starting subtest: unused-handle
[244.281701] Subtest unused-handle: SUCCESS (0.000s)
[244.731717] [091/162] (868s left) kms_addfb_basic (unused-modifier)
[245.122796] Starting subtest: unused-modifier
[245.128326] Subtest unused-modifier: SUCCESS (0.000s)
[245.582973] [092/162] (867s left) kms_addfb_basic (unused-offsets)
[245.978798] Starting subtest: unused-offsets
[245.983823] Subtest unused-offsets: SUCCESS (0.000s)
[246.429732] [093/162] (867s left) kms_addfb_basic (unused-pitches)
[246.822378] Starting subtest: unused-pitches
[246.826723] Subtest unused-pitches: SUCCESS (0.000s)
[247.254303] [094/162] (866s left) kms_busy (basic)
[247.734460] Starting subtest: basic
[247.739241] Starting dynamic subtest: flip
[248.757566] Dynamic subtest flip: SUCCESS (1.011s)
[248.821565] Starting dynamic subtest: modeset
[248.829970] Dynamic subtest modeset: SKIP (0.000s)
[248.834354] Subtest basic: SUCCESS (1.023s)
[249.298727] [095/162] (865s left) kms_prop_blob (basic)
[249.697890] Starting subtest: basic
[249.707215] Subtest basic: SUCCESS (0.000s)
[250.051459] [096/162] (864s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[250.501428] Starting subtest: basic-busy-flip-before-cursor-atomic
[250.523783] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.017s)
[250.892338] [097/162] (864s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[251.317783] Starting subtest: basic-busy-flip-before-cursor-legacy
[252.516667] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (1.196s)
[253.080944] [098/162] (862s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[253.523520] Starting subtest: basic-flip-after-cursor-atomic
[253.532906] Subtest basic-flip-after-cursor-atomic: SKIP (0.000s)
[253.892983] [099/162] (861s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[254.356974] Starting subtest: basic-flip-after-cursor-legacy
[255.539670] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.181s)
[256.094503] [100/162] (859s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[256.546408] Starting subtest: basic-flip-after-cursor-varying-size
[257.747447] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.195s)
[258.266977] [101/162] (858s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[258.723890] Starting subtest: basic-flip-before-cursor-atomic
[258.733189] Subtest basic-flip-before-cursor-atomic: SKIP (0.000s)
[259.163991] [102/162] (857s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[259.619981] Starting subtest: basic-flip-before-cursor-legacy
[260.821681] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.199s)
[261.358361] [103/162] (855s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[261.804297] Starting subtest: basic-flip-before-cursor-varying-size
[262.985283] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.181s)
[263.510659] [104/162] (853s left) kms_dsc (dsc-basic)
[263.953775] Subtest dsc-basic: SKIP (0.000s)
[264.303374] [105/162] (853s left) kms_flip (basic-flip-vs-dpms)
[264.742716] Starting subtest: basic-flip-vs-dpms
[264.748316] Starting dynamic subtest: A-VGA1
[266.509467] Dynamic subtest A-VGA1: SUCCESS (1.767s)
[266.586789] Starting dynamic subtest: B-VGA1
[268.100657] Dynamic subtest B-VGA1: SUCCESS (1.583s)
[268.105307] Subtest basic-flip-vs-dpms: SUCCESS (3.364s)
[268.793409] [106/162] (849s left) kms_flip (basic-flip-vs-modeset)
[269.254831] Starting subtest: basic-flip-vs-modeset
[269.259547] Starting dynamic subtest: A-VGA1
[271.028658] Dynamic subtest A-VGA1: SUCCESS (1.770s)
[271.098700] Starting dynamic subtest: B-VGA1
[272.608656] Dynamic subtest B-VGA1: SUCCESS (1.575s)
[272.613640] Subtest basic-flip-vs-modeset: SUCCESS (3.358s)
[273.224600] [107/162] (845s left) kms_flip (basic-flip-vs-wf_vblank)
[273.673253] Starting subtest: basic-flip-vs-wf_vblank
[273.678414] Starting dynamic subtest: A-VGA1
[275.609149] Dynamic subtest A-VGA1: SUCCESS (1.930s)
[275.672381] Starting dynamic subtest: B-VGA1
[277.538205] Dynamic subtest B-VGA1: SUCCESS (1.923s)
[277.542989] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.872s)
[278.271963] [108/162] (840s left) kms_flip (basic-plain-flip)
[278.717622] Starting subtest: basic-plain-flip
[278.722588] Starting dynamic subtest: A-VGA1
[280.306322] Dynamic subtest A-VGA1: SUCCESS (1.586s)
[280.373287] Starting dynamic subtest: B-VGA1
[281.789935] Dynamic subtest B-VGA1: SUCCESS (1.471s)
[281.794554] Subtest basic-plain-flip: SUCCESS (3.078s)
[282.506936] [109/162] (836s left) kms_force_connector_basic (force-connector-state)
[282.999907] Starting subtest: force-connector-state
[283.332766] Subtest force-connector-state: SUCCESS (0.323s)
[284.033103] [110/162] (835s left) kms_force_connector_basic (force-edid)
[284.527548] Starting subtest: force-edid
[284.645522] Subtest force-edid: SUCCESS (0.115s)
[285.141786] [111/162] (834s left) kms_force_connector_basic (force-load-detect)
[285.652703] Starting subtest: force-load-detect
[286.145228] Subtest force-load-detect: SUCCESS (0.505s)
[286.790568] [112/162] (833s left) kms_force_connector_basic (prune-stale-modes)
[287.280152] Starting subtest: prune-stale-modes
[287.367506] Subtest prune-stale-modes: SUCCESS (0.097s)
[287.840762] [113/162] (832s left) kms_frontbuffer_tracking (basic)
[288.292758] Subtest basic: SKIP (0.000s)
[288.652699] [114/162] (832s left) kms_hdmi_inject (inject-audio)
[289.105359] Subtest inject-audio: SKIP (0.000s)
[289.528197] [115/162] (831s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[289.978763] Starting subtest: compare-crc-sanitycheck-xr24
[289.983835] Starting dynamic subtest: pipe-A-VGA-1
[290.369789] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.389s)
[290.449800] Starting dynamic subtest: pipe-B-VGA-1
[290.929898] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.550s)
[290.935214] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.959s)
[291.474527] [116/162] (830s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[291.918841] Starting subtest: compare-crc-sanitycheck-nv12
[291.923937] Starting dynamic subtest: pipe-A-VGA-1
[291.933448] Dynamic subtest pipe-A-VGA-1: SKIP (0.002s)
[291.996983] Starting dynamic subtest: pipe-B-VGA-1
[292.005393] Dynamic subtest pipe-B-VGA-1: SKIP (0.001s)
[292.013225] Subtest compare-crc-sanitycheck-nv12: SKIP (0.023s)
[292.320563] [117/162] (829s left) kms_pipe_crc_basic (hang-read-crc)
[292.747637] Starting subtest: hang-read-crc
[292.752506] Starting dynamic subtest: pipe-A-VGA-1
[294.112098] Dynamic subtest pipe-A-VGA-1: SUCCESS (1.356s)
[294.204857] Starting dynamic subtest: pipe-B-VGA-1
[295.577390] Dynamic subtest pipe-B-VGA-1: SUCCESS (1.449s)
[295.589838] Subtest hang-read-crc: SUCCESS (2.824s)
[296.092901] [118/162] (826s left) kms_pipe_crc_basic (nonblocking-crc)
[296.529703] Starting subtest: nonblocking-crc
[296.542537] Starting dynamic subtest: pipe-A-VGA-1
[297.165783] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.621s)
[297.245166] Starting dynamic subtest: pipe-B-VGA-1
[297.915559] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.742s)
[297.920418] Subtest nonblocking-crc: SUCCESS (1.385s)
[298.454669] [119/162] (824s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[298.893088] Starting subtest: nonblocking-crc-frame-sequence
[298.898604] Starting dynamic subtest: pipe-A-VGA-1
[299.495640] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.592s)
[299.570555] Starting dynamic subtest: pipe-B-VGA-1
[300.241612] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.741s)
[300.246738] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.351s)
[300.760998] [120/162] (822s left) kms_pipe_crc_basic (read-crc)
[301.188485] Starting subtest: read-crc
[301.192458] Starting dynamic subtest: pipe-A-VGA-1
[301.737328] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.539s)
[301.822599] Starting dynamic subtest: pipe-B-VGA-1
[302.414456] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.671s)
[302.418937] Subtest read-crc: SUCCESS (1.230s)
[302.941993] [121/162] (820s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[303.382461] Starting subtest: read-crc-frame-sequence
[303.394388] Starting dynamic subtest: pipe-A-VGA-1
[303.914473] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.522s)
[304.026616] Starting dynamic subtest: pipe-B-VGA-1
[304.634416] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.711s)
[304.639352] Subtest read-crc-frame-sequence: SUCCESS (1.252s)
[305.156749] [122/162] (818s left) kms_pm_backlight (basic-brightness)
[305.601090] Subtest basic-brightness: SKIP (0.000s)
[305.957421] [123/162] (817s left) kms_pm_rpm (basic-pci-d3-state)
[317.833405] Subtest basic-pci-d3-state: SKIP (0.000s)
[318.187359] [124/162] (806s left) kms_pm_rpm (basic-rte)
[318.571355] Starting subtest: basic-rte
[330.076385] Subtest basic-rte: SKIP (11.497s)
[330.429647] [125/162] (794s left) kms_psr (psr-primary-page-flip)
[330.889694] Subtest psr-primary-page-flip: SKIP (0.000s)
[331.235749] [126/162] (793s left) kms_psr (psr-cursor-plane-move)
[331.668463] Subtest psr-cursor-plane-move: SKIP (0.000s)
[332.006319] [127/162] (793s left) kms_psr (psr-sprite-plane-onoff)
[332.447994] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[332.810139] [128/162] (792s left) kms_psr (psr-primary-mmap-gtt)
[333.269357] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[333.629936] [129/162] (792s left) kms_setmode (basic-clone-single-crtc)
[334.058632] Starting subtest: basic-clone-single-crtc
[334.072412] Subtest basic-clone-single-crtc: SKIP (0.001s)
[334.468334] [130/162] (791s left) i915_pm_rps (basic-api)
[334.882975] Subtest basic-api: SKIP (0.000s)
[335.232788] [131/162] (791s left) prime_self_import (basic-llseek-bad)
[335.617892] Starting subtest: basic-llseek-bad
[335.637302] Subtest basic-llseek-bad: SUCCESS (0.018s)
[336.010626] [132/162] (790s left) prime_self_import (basic-llseek-size)
[336.390988] Starting subtest: basic-llseek-size
[336.419313] Subtest basic-llseek-size: SUCCESS (0.031s)
[336.796291] [133/162] (790s left) prime_self_import (basic-with_fd_dup)
[337.168453] Starting subtest: basic-with_fd_dup
[337.192753] Subtest basic-with_fd_dup: SUCCESS (0.027s)
[337.579672] [134/162] (789s left) prime_self_import (basic-with_one_bo)
[337.953314] Starting subtest: basic-with_one_bo
[337.977147] Subtest basic-with_one_bo: SUCCESS (0.028s)
[338.363438] [135/162] (789s left) prime_self_import (basic-with_one_bo_two_files)
[338.734854] Starting subtest: basic-with_one_bo_two_files
[338.753681] Subtest basic-with_one_bo_two_files: SUCCESS (0.020s)
[339.125962] [136/162] (789s left) prime_self_import (basic-with_two_bos)
[339.514222] Starting subtest: basic-with_two_bos
[339.540359] Subtest basic-with_two_bos: SUCCESS (0.027s)
[339.914760] [137/162] (788s left) prime_vgem (basic-fence-flip)
[340.357987] Starting subtest: basic-fence-flip
[340.991393] Subtest basic-fence-flip: SUCCESS (0.629s)
[341.397409] [138/162] (787s left) prime_vgem (basic-fence-mmap)
[341.828452] Starting subtest: basic-fence-mmap
[342.034663] Subtest basic-fence-mmap: SUCCESS (0.207s)
[342.423134] [139/162] (786s left) prime_vgem (basic-fence-read)
[342.860415] Starting subtest: basic-fence-read
[343.120459] Subtest basic-fence-read: SUCCESS (0.265s)
[343.500892] [140/162] (786s left) prime_vgem (basic-gtt)
[343.945442] Starting subtest: basic-gtt
[344.038640] Subtest basic-gtt: SUCCESS (0.098s)
[344.419638] [141/162] (785s left) prime_vgem (basic-read)
[344.842627] Starting subtest: basic-read
[344.965153] Subtest basic-read: SUCCESS (0.127s)
[345.350148] [142/162] (784s left) prime_vgem (basic-write)
[345.774757] Starting subtest: basic-write
[345.883838] Subtest basic-write: SUCCESS (0.114s)
[346.271708] [143/162] (784s left) vgem_basic (setversion)
[346.662959] Starting subtest: setversion
[346.671711] Subtest setversion: SUCCESS (0.000s)
[347.015299] [144/162] (783s left) vgem_basic (create)
[347.413740] Starting subtest: create
[347.417671] Subtest create: SUCCESS (0.003s)
[347.773459] [145/162] (783s left) vgem_basic (debugfs)
[348.161929] Starting subtest: debugfs
[348.166866] Subtest debugfs: SUCCESS (0.004s)
[348.514485] [146/162] (782s left) vgem_basic (dmabuf-export)
[348.920370] Starting subtest: dmabuf-export
[348.930205] Subtest dmabuf-export: SUCCESS (0.017s)
[349.380199] [147/162] (782s left) vgem_basic (dmabuf-fence)
[349.779730] Starting subtest: dmabuf-fence
[349.788524] Subtest dmabuf-fence: SUCCESS (0.008s)
[350.156990] [148/162] (782s left) vgem_basic (dmabuf-fence-before)
[350.551119] Starting subtest: dmabuf-fence-before
[350.556216] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[350.903141] [149/162] (781s left) vgem_basic (dmabuf-mmap)
[351.303641] Starting subtest: dmabuf-mmap
[351.376199] Subtest dmabuf-mmap: SUCCESS (0.081s)
[351.734832] [150/162] (781s left) vgem_basic (mmap)
[352.134186] Starting subtest: mmap
[352.192551] Subtest mmap: SUCCESS (0.064s)
[352.553466] [151/162] (780s left) vgem_basic (second-client)
[352.953360] Starting subtest: second-client
[352.985121] Subtest second-client: SUCCESS (0.033s)
[353.334960] [152/162] (780s left) vgem_basic (sysfs)
[353.734709] Starting subtest: sysfs
[353.738899] Subtest sysfs: SUCCESS (0.007s)
[354.093304] [153/162] (779s left) core_hotunplug (unbind-rebind)
[354.781223] Starting subtest: unbind-rebind
[361.002593] Subtest unbind-rebind: SUCCESS (6.216s)
[361.339568] [154/162] (772s left) vgem_basic (unload)
[361.734421] Starting subtest: unload
[391.367370] Subtest unload: SUCCESS (29.631s)
[391.934288] [155/162] (742s left) i915_module_load (reload)
[392.315322] Starting subtest: reload
[403.385904] Subtest reload: SUCCESS (11.071s)
[404.242113] [156/162] (731s left) gem_lmem_swapping (basic)
[404.768114] Subtest basic: SKIP (0.000s)
[405.112554] [157/162] (730s left) gem_lmem_swapping (parallel-random-engines)
[405.538229] Subtest parallel-random-engines: SKIP (0.000s)
[405.877134] [158/162] (730s left) gem_lmem_swapping (random-engines)
[406.285942] Subtest random-engines: SKIP (0.000s)
[406.618262] [159/162] (729s left) gem_lmem_swapping (verify-random)
[407.046595] Subtest verify-random: SKIP (0.000s)
[407.382937] [160/162] (729s left) i915_pm_rpm (module-reload)
[419.300913] Subtest module-reload: SKIP (0.000s)
[419.633857] [161/162] (717s left) i915_selftest (live)