[147.831520] Initializing watchdogs
[147.832351]   /dev/watchdog0
[147.960956] [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)
[148.000683] [001/161] (960s left) i915_module_load (load)
[149.637786] Starting subtest: load
[157.908250] Subtest load: SUCCESS (8.252s)
[158.310692] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[158.323417] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[158.354634] [002/161] (950s left) core_auth (basic-auth)
[158.995492] Starting subtest: basic-auth
[158.999788] Subtest basic-auth: SUCCESS (0.005s)
[159.314122] [003/161] (949s left) debugfs_test (read_all_entries)
[159.788983] Starting subtest: read_all_entries
[160.020993] Subtest read_all_entries: SUCCESS (0.227s)
[160.376220] [004/161] (949s left) debugfs_test (basic-hwmon)
[160.859343] Starting subtest: basic-hwmon
[160.867672] Subtest basic-hwmon: SKIP (0.000s)
[161.216663] [005/161] (948s left) debugfs_test (sysfs)
[161.693727] Starting subtest: sysfs
[161.851973] Subtest sysfs: SUCCESS (0.160s)
[162.205792] [006/161] (947s left) fbdev (eof)
[162.732335] Starting subtest: eof
[162.737501] Subtest eof: SUCCESS (0.003s)
[163.025568] [007/161] (947s left) fbdev (info)
[163.466990] Starting subtest: info
[163.470661] Subtest info: SUCCESS (0.000s)
[163.776911] [008/161] (946s left) fbdev (nullptr)
[164.223206] Starting subtest: nullptr
[164.228328] Subtest nullptr: SUCCESS (0.001s)
[164.529742] [009/161] (946s left) fbdev (read)
[164.973305] Starting subtest: read
[165.280969] Subtest read: SUCCESS (0.307s)
[165.578523] [010/161] (945s left) fbdev (write)
[166.044300] Starting subtest: write
[166.224947] Subtest write: SUCCESS (0.184s)
[166.526267] [011/161] (944s left) gem_basic (bad-close)
[166.986605] Starting subtest: bad-close
[166.994818] Subtest bad-close: SUCCESS (0.000s)
[167.293461] [012/161] (944s left) gem_basic (create-close)
[167.761250] Starting subtest: create-close
[167.771365] Subtest create-close: SUCCESS (0.001s)
[168.060781] [013/161] (943s left) gem_basic (create-fd-close)
[168.514336] Starting subtest: create-fd-close
[168.521714] Subtest create-fd-close: SUCCESS (0.002s)
[168.837275] [014/161] (943s left) gem_busy (busy@all-engines)
[169.376485] Starting subtest: busy
[169.381121] Starting dynamic subtest: all-engines
[169.521694] Dynamic subtest all-engines: SUCCESS (0.145s)
[169.526663] Subtest busy: SUCCESS (0.150s)
[170.230157] [015/161] (942s left) gem_close_race (basic-process)
[170.843788] Starting subtest: basic-process
[171.113538] Subtest basic-process: SUCCESS (0.281s)
[171.567953] [016/161] (941s left) gem_close_race (basic-threads)
[172.101826] Starting subtest: basic-threads
[173.785731] Subtest basic-threads: SUCCESS (1.682s)
[174.235325] [017/161] (938s left) gem_ctx_create (basic)
[174.707702] Subtest basic: SKIP (0.000s)
[174.988942] [018/161] (938s left) gem_ctx_create (basic-files)
[175.453533] Subtest basic-files: SKIP (0.000s)
[175.736860] [019/161] (937s left) gem_ctx_exec (basic)
[176.198849] Subtest basic: SKIP (0.000s)
[176.494443] [020/161] (937s left) gem_exec_basic (basic)
[177.020405] Starting subtest: basic
[177.024627] Starting dynamic subtest: rcs0-smem
[177.028289] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[177.031902] Subtest basic: SUCCESS (0.008s)
[177.486785] [021/161] (936s left) gem_exec_create (basic)
[178.012351] Starting subtest: basic
[178.019701] Starting dynamic subtest: smem
[180.184259] Dynamic subtest smem: SUCCESS (2.171s)
[180.188323] Subtest basic: SUCCESS (2.174s)
[180.641433] [022/161] (933s left) gem_exec_fence (basic-busy)
[181.398925] Starting subtest: basic-busy
[181.404924] Starting dynamic subtest: rcs0
[181.435620] Dynamic subtest rcs0: SUCCESS (0.043s)
[181.440674] Subtest basic-busy: SUCCESS (0.047s)
[182.629345] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[182.643562] [023/161] (932s left) gem_exec_fence (basic-wait)
[183.395362] Starting subtest: basic-wait
[183.399694] Starting dynamic subtest: rcs0
[183.435949] Dynamic subtest rcs0: SUCCESS (0.041s)
[183.439482] Subtest basic-wait: SUCCESS (0.044s)
[184.409577] [024/161] (930s left) gem_exec_fence (basic-await)
[185.168264] Starting subtest: basic-await
[185.173095] Starting dynamic subtest: rcs0
[185.327682] Dynamic subtest rcs0: SUCCESS (0.161s)
[185.331773] Subtest basic-await: SUCCESS (0.166s)
[186.370420] [025/161] (929s left) gem_exec_fence (nb-await)
[187.131829] Starting subtest: nb-await
[187.137470] Starting dynamic subtest: rcs0
[187.197870] Dynamic subtest rcs0: SUCCESS (0.067s)
[187.201908] Subtest nb-await: SUCCESS (0.071s)
[188.167329] [026/161] (927s left) gem_exec_gttfill (basic)
[188.693866] Starting subtest: basic
[198.626361] Subtest basic: SUCCESS (9.930s)
[199.354233] [027/161] (916s left) gem_exec_parallel (engines)
[200.369460] Starting subtest: engines
[200.373994] Starting dynamic subtest: basic
[204.628479] Dynamic subtest basic: SUCCESS (4.264s)
[204.678308] Starting dynamic subtest: contexts
[204.685593] Dynamic subtest contexts: SKIP (0.003s)
[204.727070] Starting dynamic subtest: fds
[204.733385] Dynamic subtest fds: SKIP (0.005s)
[204.774740] Starting dynamic subtest: userptr
[208.912647] Dynamic subtest userptr: SUCCESS (4.268s)
[208.917338] Subtest engines: SUCCESS (8.550s)
[209.409844] [028/161] (907s left) gem_exec_store (basic)
[209.952283] Starting subtest: basic
[209.991787] Subtest basic: SUCCESS (0.045s)
[210.494892] [029/161] (906s left) gem_flink_basic (bad-flink)
[210.944248] Starting subtest: bad-flink
[210.949809] Subtest bad-flink: SUCCESS (0.000s)
[211.294634] [030/161] (905s left) gem_flink_basic (bad-open)
[211.750151] Starting subtest: bad-open
[211.754787] Subtest bad-open: SUCCESS (0.000s)
[212.098334] [031/161] (905s left) gem_flink_basic (basic)
[212.540887] Starting subtest: basic
[212.545122] Subtest basic: SUCCESS (0.001s)
[212.915091] [032/161] (904s left) gem_flink_basic (double-flink)
[213.366810] Starting subtest: double-flink
[213.372515] Subtest double-flink: SUCCESS (0.001s)
[213.731939] [033/161] (904s left) gem_flink_basic (flink-lifetime)
[214.179512] Starting subtest: flink-lifetime
[214.190979] Subtest flink-lifetime: SUCCESS (0.010s)
[214.541270] [034/161] (903s left) gem_huc_copy (huc-copy)
[215.008798] Subtest huc-copy: SKIP (0.000s)
[215.334441] [035/161] (903s left) gem_linear_blits (basic)
[216.213302] Starting subtest: basic
[216.258409] Subtest basic: SUCCESS (0.047s)
[216.705139] [036/161] (902s left) gem_mmap (basic)
[217.405573] Starting subtest: basic
[217.427926] Subtest basic: SUCCESS (0.003s)
[217.763693] [037/161] (901s left) gem_mmap_gtt (basic)
[218.217254] Starting subtest: basic
[218.222457] Subtest basic: SUCCESS (0.005s)
[218.570950] [038/161] (901s left) gem_render_linear_blits (basic)
[219.035715] Starting subtest: basic
[219.186673] Subtest basic: SUCCESS (0.151s)
[219.537911] [039/161] (900s left) gem_render_tiled_blits (basic)
[219.997281] Starting subtest: basic
[220.164522] Subtest basic: SUCCESS (0.162s)
[220.491667] [040/161] (899s left) gem_ringfill (basic-all)
[221.059700] Starting subtest: basic-all
[222.290624] Subtest basic-all: SUCCESS (1.231s)
[222.655530] [041/161] (898s left) gem_softpin (allocator-basic)
[223.124859] Subtest allocator-basic: SKIP (0.000s)
[223.466279] [042/161] (897s left) gem_softpin (allocator-basic-reserve)
[223.931472] Subtest allocator-basic-reserve: SKIP (0.000s)
[224.285999] [043/161] (897s left) gem_softpin (safe-alignment)
[224.750329] Starting subtest: safe-alignment
[224.764687] Subtest safe-alignment: SUCCESS (0.022s)
[225.129464] [044/161] (896s left) gem_sync (basic-all)
[225.666631] Starting subtest: basic-all
[227.879315] Subtest basic-all: SUCCESS (2.208s)
[228.372510] [045/161] (893s left) gem_sync (basic-each)
[228.899683] Starting subtest: basic-each
[231.182554] Subtest basic-each: SUCCESS (2.280s)
[231.677279] [046/161] (890s left) gem_tiled_blits (basic)
[232.490791] Starting subtest: basic
[232.608699] Subtest basic: SUCCESS (0.117s)
[233.038994] [047/161] (889s left) gem_tiled_fence_blits (basic)
[234.148957] Starting subtest: basic
[234.214242] Subtest basic: SUCCESS (0.074s)
[234.644330] [048/161] (888s left) gem_tiled_pread_basic
[237.259984] [049/161] (885s left) gem_wait (busy@all-engines)
[237.854129] Starting subtest: busy
[237.860740] Starting dynamic subtest: all-engines
[238.388583] Dynamic subtest all-engines: SUCCESS (0.534s)
[238.392574] Subtest busy: SUCCESS (0.538s)
[239.048275] [050/161] (884s left) gem_wait (wait@all-engines)
[239.651565] Starting subtest: wait
[239.658809] Starting dynamic subtest: all-engines
[240.695756] Dynamic subtest all-engines: SUCCESS (1.050s)
[240.700392] Subtest wait: SUCCESS (1.055s)
[241.365601] [051/161] (882s left) i915_getparams_basic (basic-eu-total)
[241.805565] Starting subtest: basic-eu-total
[241.815318] Subtest basic-eu-total: SUCCESS (0.001s)
[242.161932] [052/161] (882s left) i915_getparams_basic (basic-subslice-total)
[242.619404] Starting subtest: basic-subslice-total
[242.628681] Subtest basic-subslice-total: SUCCESS (0.000s)
[242.960360] [053/161] (881s left) i915_hangman (error-state-basic)
[243.439953] Starting subtest: error-state-basic
[243.829584] Subtest error-state-basic: SUCCESS (0.383s)
[244.155777] [054/161] (880s left) i915_pciid
[244.968223] [055/161] (880s left) kms_addfb_basic (addfb25-4-tiled)
[245.492216] Starting subtest: addfb25-4-tiled
[245.496502] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[245.969692] [056/161] (879s left) kms_addfb_basic (addfb25-bad-modifier)
[246.480154] Starting subtest: addfb25-bad-modifier
[246.486187] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[246.850595] [057/161] (879s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[247.359171] Starting subtest: addfb25-framebuffer-vs-set-tiling
[247.363685] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.001s)
[247.737430] [058/161] (878s left) kms_addfb_basic (addfb25-modifier-no-flag)
[248.240411] Starting subtest: addfb25-modifier-no-flag
[248.245384] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[248.626329] [059/161] (877s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[249.119377] Starting subtest: addfb25-x-tiled-legacy
[249.125524] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[249.482405] [060/161] (877s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[249.972542] Starting subtest: addfb25-x-tiled-mismatch-legacy
[249.977745] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[250.333693] [061/161] (876s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[250.840264] Starting subtest: addfb25-yf-tiled-legacy
[250.845785] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[251.200362] [062/161] (876s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[251.730727] Starting subtest: addfb25-y-tiled-legacy
[251.736721] Subtest addfb25-y-tiled-legacy: SUCCESS (0.002s)
[252.102793] [063/161] (875s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[252.613568] Starting subtest: addfb25-y-tiled-small-legacy
[252.622978] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[252.974477] [064/161] (875s left) kms_addfb_basic (bad-pitch-0)
[253.432104] Starting subtest: bad-pitch-0
[253.437614] Subtest bad-pitch-0: SUCCESS (0.000s)
[253.864618] [065/161] (874s left) kms_addfb_basic (bad-pitch-1024)
[254.322858] Starting subtest: bad-pitch-1024
[254.328628] Subtest bad-pitch-1024: SUCCESS (0.000s)
[254.740815] [066/161] (874s left) kms_addfb_basic (bad-pitch-128)
[255.197493] Starting subtest: bad-pitch-128
[255.203572] Subtest bad-pitch-128: SUCCESS (0.000s)
[255.625642] [067/161] (873s left) kms_addfb_basic (bad-pitch-256)
[256.087416] Starting subtest: bad-pitch-256
[256.091943] Subtest bad-pitch-256: SUCCESS (0.000s)
[256.510449] [068/161] (872s left) kms_addfb_basic (bad-pitch-32)
[256.957869] Starting subtest: bad-pitch-32
[256.962816] Subtest bad-pitch-32: SUCCESS (0.000s)
[257.373884] [069/161] (872s left) kms_addfb_basic (bad-pitch-63)
[257.828722] Starting subtest: bad-pitch-63
[257.832840] Subtest bad-pitch-63: SUCCESS (0.000s)
[258.278898] [070/161] (871s left) kms_addfb_basic (bad-pitch-65536)
[258.722567] Starting subtest: bad-pitch-65536
[258.727694] Subtest bad-pitch-65536: SUCCESS (0.002s)
[259.157426] [071/161] (871s left) kms_addfb_basic (bad-pitch-999)
[259.611672] Starting subtest: bad-pitch-999
[259.616833] Subtest bad-pitch-999: SUCCESS (0.000s)
[260.029666] [072/161] (870s left) kms_addfb_basic (basic)
[260.482245] Starting subtest: basic
[260.487245] Subtest basic: SUCCESS (0.000s)
[260.982530] [073/161] (870s left) kms_addfb_basic (basic-x-tiled-legacy)
[261.447147] Starting subtest: basic-x-tiled-legacy
[261.451686] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[261.871303] [074/161] (869s left) kms_addfb_basic (basic-y-tiled-legacy)
[262.338418] Starting subtest: basic-y-tiled-legacy
[262.342128] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[262.760285] [075/161] (869s left) kms_addfb_basic (bo-too-small)
[263.265380] Starting subtest: bo-too-small
[263.270789] Subtest bo-too-small: SUCCESS (0.002s)
[263.633119] [076/161] (868s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[264.150876] Starting subtest: bo-too-small-due-to-tiling
[264.155991] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[264.505379] [077/161] (867s left) kms_addfb_basic (clobberred-modifier)
[264.952530] Starting subtest: clobberred-modifier
[264.958440] Subtest clobberred-modifier: SUCCESS (0.001s)
[265.381202] [078/161] (867s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[265.841473] Starting subtest: framebuffer-vs-set-tiling
[265.845202] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[266.274104] [079/161] (866s left) kms_addfb_basic (invalid-get-prop)
[266.724962] Starting subtest: invalid-get-prop
[266.729509] Subtest invalid-get-prop: SUCCESS (0.000s)
[267.167152] [080/161] (866s left) kms_addfb_basic (invalid-get-prop-any)
[267.619507] Starting subtest: invalid-get-prop-any
[267.624809] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[268.054222] [081/161] (865s left) kms_addfb_basic (invalid-set-prop)
[268.517806] Starting subtest: invalid-set-prop
[268.522781] Subtest invalid-set-prop: SUCCESS (0.000s)
[268.948257] [082/161] (865s left) kms_addfb_basic (invalid-set-prop-any)
[269.408427] Starting subtest: invalid-set-prop-any
[269.413449] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[269.821774] [083/161] (864s left) kms_addfb_basic (no-handle)
[270.282423] Starting subtest: no-handle
[270.286857] Subtest no-handle: SUCCESS (0.001s)
[270.701297] [084/161] (864s left) kms_addfb_basic (size-max)
[271.200608] Starting subtest: size-max
[271.206678] Subtest size-max: SUCCESS (0.001s)
[271.584764] [085/161] (863s left) kms_addfb_basic (small-bo)
[272.106974] Starting subtest: small-bo
[272.112480] Subtest small-bo: SUCCESS (0.000s)
[272.464238] [086/161] (862s left) kms_addfb_basic (tile-pitch-mismatch)
[272.917833] Starting subtest: tile-pitch-mismatch
[272.923419] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[273.339990] [087/161] (862s left) kms_addfb_basic (too-high)
[273.858857] Starting subtest: too-high
[273.864769] Subtest too-high: SUCCESS (0.006s)
[274.248308] [088/161] (861s left) kms_addfb_basic (too-wide)
[274.763948] Starting subtest: too-wide
[274.768194] Subtest too-wide: SUCCESS (0.000s)
[275.120509] [089/161] (861s left) kms_addfb_basic (unused-handle)
[275.571860] Starting subtest: unused-handle
[275.577482] Subtest unused-handle: SUCCESS (0.000s)
[276.112973] [090/161] (860s left) kms_addfb_basic (unused-modifier)
[276.565373] Starting subtest: unused-modifier
[276.570788] Subtest unused-modifier: SUCCESS (0.000s)
[276.984226] [091/161] (860s left) kms_addfb_basic (unused-offsets)
[277.443109] Starting subtest: unused-offsets
[277.448658] Subtest unused-offsets: SUCCESS (0.000s)
[277.852780] [092/161] (859s left) kms_addfb_basic (unused-pitches)
[278.305967] Starting subtest: unused-pitches
[278.310375] Subtest unused-pitches: SUCCESS (0.000s)
[278.739272] [093/161] (858s left) kms_busy (basic)
[279.236656] Starting subtest: basic
[279.241985] Starting dynamic subtest: flip
[280.301078] Dynamic subtest flip: SUCCESS (1.052s)
[280.358483] Starting dynamic subtest: modeset
[280.367283] Dynamic subtest modeset: SKIP (0.000s)
[280.371985] Subtest basic: SUCCESS (1.061s)
[280.816059] [094/161] (857s left) kms_prop_blob (basic)
[281.282444] Starting subtest: basic
[281.286598] Subtest basic: SUCCESS (0.000s)
[281.637814] [095/161] (856s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[282.151766] Starting subtest: basic-busy-flip-before-cursor-atomic
[282.168631] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.018s)
[282.543925] [096/161] (856s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[283.055952] Starting subtest: basic-busy-flip-before-cursor-legacy
[284.178284] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (1.126s)
[284.799768] [097/161] (854s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[285.350183] Starting subtest: basic-flip-after-cursor-atomic
[285.359976] Subtest basic-flip-after-cursor-atomic: SKIP (0.001s)
[285.772732] [098/161] (853s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[286.306325] Starting subtest: basic-flip-after-cursor-legacy
[287.470970] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.164s)
[288.013153] [099/161] (851s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[288.512951] Starting subtest: basic-flip-after-cursor-varying-size
[289.614824] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.103s)
[290.133842] [100/161] (849s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[290.625798] Starting subtest: basic-flip-before-cursor-atomic
[290.635739] Subtest basic-flip-before-cursor-atomic: SKIP (0.000s)
[291.071288] [101/161] (849s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[291.574894] Starting subtest: basic-flip-before-cursor-legacy
[292.664608] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.088s)
[293.182654] [102/161] (847s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[293.683457] Starting subtest: basic-flip-before-cursor-varying-size
[294.786915] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.105s)
[295.300793] [103/161] (845s left) kms_dsc (dsc-basic)
[295.802138] Subtest dsc-basic: SKIP (0.000s)
[296.139796] [104/161] (845s left) kms_flip (basic-flip-vs-dpms)
[296.652342] Starting subtest: basic-flip-vs-dpms
[296.657475] Starting dynamic subtest: A-VGA1
[298.291325] Dynamic subtest A-VGA1: SUCCESS (1.633s)
[298.353952] Starting dynamic subtest: B-VGA1
[299.823788] Dynamic subtest B-VGA1: SUCCESS (1.523s)
[299.828600] Subtest basic-flip-vs-dpms: SUCCESS (3.170s)
[300.505786] [105/161] (841s left) kms_flip (basic-flip-vs-modeset)
[301.006503] Starting subtest: basic-flip-vs-modeset
[301.011756] Starting dynamic subtest: A-VGA1
[302.703523] Dynamic subtest A-VGA1: SUCCESS (1.687s)
[302.758145] Starting dynamic subtest: B-VGA1
[304.210464] Dynamic subtest B-VGA1: SUCCESS (1.506s)
[304.215628] Subtest basic-flip-vs-modeset: SUCCESS (3.203s)
[304.844516] [106/161] (837s left) kms_flip (basic-flip-vs-wf_vblank)
[305.342215] Starting subtest: basic-flip-vs-wf_vblank
[305.347464] Starting dynamic subtest: A-VGA1
[307.191332] Dynamic subtest A-VGA1: SUCCESS (1.841s)
[307.248113] Starting dynamic subtest: B-VGA1
[308.912976] Dynamic subtest B-VGA1: SUCCESS (1.717s)
[308.917780] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.569s)
[309.620964] [107/161] (832s left) kms_flip (basic-plain-flip)
[310.121943] Starting subtest: basic-plain-flip
[310.127562] Starting dynamic subtest: A-VGA1
[311.689343] Dynamic subtest A-VGA1: SUCCESS (1.554s)
[311.744684] Starting dynamic subtest: B-VGA1
[313.121325] Dynamic subtest B-VGA1: SUCCESS (1.431s)
[313.126257] Subtest basic-plain-flip: SUCCESS (2.995s)
[313.909585] [108/161] (828s left) kms_force_connector_basic (force-connector-state)
[314.512516] Starting subtest: force-connector-state
[314.836704] Subtest force-connector-state: SUCCESS (0.327s)
[315.682118] [109/161] (827s left) kms_force_connector_basic (force-edid)
[316.252732] Starting subtest: force-edid
[316.382392] Subtest force-edid: SUCCESS (0.128s)
[316.832883] [110/161] (826s left) kms_force_connector_basic (force-load-detect)
[317.388459] Starting subtest: force-load-detect
[317.874815] Subtest force-load-detect: SUCCESS (0.485s)
[318.493785] [111/161] (825s left) kms_force_connector_basic (prune-stale-modes)
[319.051600] Starting subtest: prune-stale-modes
[319.166839] Subtest prune-stale-modes: SUCCESS (0.113s)
[319.602985] [112/161] (824s left) kms_frontbuffer_tracking (basic)
[320.132350] Subtest basic: SKIP (0.000s)
[320.473657] [113/161] (824s left) kms_hdmi_inject (inject-audio)
[321.060269] Subtest inject-audio: SKIP (0.000s)
[321.386666] [114/161] (823s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[321.885613] Starting subtest: compare-crc-sanitycheck-xr24
[321.890934] Starting dynamic subtest: pipe-A-VGA-1
[322.266651] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.376s)
[322.333792] Starting dynamic subtest: pipe-B-VGA-1
[322.793723] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.507s)
[322.799587] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.900s)
[323.303338] [115/161] (822s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[323.821685] Starting subtest: compare-crc-sanitycheck-nv12
[323.827848] Starting dynamic subtest: pipe-A-VGA-1
[323.839351] Dynamic subtest pipe-A-VGA-1: SKIP (0.001s)
[323.898910] Starting dynamic subtest: pipe-B-VGA-1
[323.907326] Dynamic subtest pipe-B-VGA-1: SKIP (0.001s)
[323.915970] Subtest compare-crc-sanitycheck-nv12: SKIP (0.018s)
[324.208395] [116/161] (821s left) kms_pipe_crc_basic (hang-read-crc)
[324.702249] Starting subtest: hang-read-crc
[324.707319] Starting dynamic subtest: pipe-A-VGA-1
[326.023869] Dynamic subtest pipe-A-VGA-1: SUCCESS (1.313s)
[326.088793] Starting dynamic subtest: pipe-B-VGA-1
[327.463534] Dynamic subtest pipe-B-VGA-1: SUCCESS (1.434s)
[327.469199] Subtest hang-read-crc: SUCCESS (2.762s)
[327.968234] [117/161] (818s left) kms_pipe_crc_basic (nonblocking-crc)
[328.469926] Starting subtest: nonblocking-crc
[328.475802] Starting dynamic subtest: pipe-A-VGA-1
[329.065908] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.588s)
[329.126538] Starting dynamic subtest: pipe-B-VGA-1
[329.799538] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.727s)
[329.803728] Subtest nonblocking-crc: SUCCESS (1.335s)
[330.336680] [118/161] (816s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[330.824387] Starting subtest: nonblocking-crc-frame-sequence
[330.829739] Starting dynamic subtest: pipe-A-VGA-1
[331.423542] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.594s)
[331.488321] Starting dynamic subtest: pipe-B-VGA-1
[332.130963] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.698s)
[332.136604] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.309s)
[332.638342] [119/161] (814s left) kms_pipe_crc_basic (read-crc)
[333.124184] Starting subtest: read-crc
[333.129498] Starting dynamic subtest: pipe-A-VGA-1
[333.652890] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.526s)
[333.703719] Starting dynamic subtest: pipe-B-VGA-1
[334.372964] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.708s)
[334.377360] Subtest read-crc: SUCCESS (1.252s)
[334.896566] [120/161] (812s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[335.396351] Starting subtest: read-crc-frame-sequence
[335.410635] Starting dynamic subtest: pipe-A-VGA-1
[335.940618] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.528s)
[336.002688] Starting dynamic subtest: pipe-B-VGA-1
[336.610633] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.669s)
[336.614790] Subtest read-crc-frame-sequence: SUCCESS (1.218s)
[337.130775] [121/161] (810s left) kms_pm_backlight (basic-brightness)
[337.638632] Subtest basic-brightness: SKIP (0.000s)
[337.981767] [122/161] (809s left) kms_pm_rpm (basic-pci-d3-state)
[349.915887] Subtest basic-pci-d3-state: SKIP (0.000s)
[350.232475] [123/161] (797s left) kms_pm_rpm (basic-rte)
[350.671806] Starting subtest: basic-rte
[362.232383] Subtest basic-rte: SKIP (11.552s)
[362.558755] [124/161] (785s left) kms_psr (psr-primary-page-flip)
[363.076868] Subtest psr-primary-page-flip: SKIP (0.000s)
[363.418804] [125/161] (785s left) kms_psr (psr-cursor-plane-move)
[363.923566] Subtest psr-cursor-plane-move: SKIP (0.000s)
[364.258361] [126/161] (784s left) kms_psr (psr-sprite-plane-onoff)
[364.754403] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[365.113920] [127/161] (784s left) kms_psr (psr-primary-mmap-gtt)
[365.623234] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[366.041641] [128/161] (783s left) kms_setmode (basic-clone-single-crtc)
[366.509616] Starting subtest: basic-clone-single-crtc
[366.522070] Subtest basic-clone-single-crtc: SKIP (0.001s)
[366.875525] [129/161] (783s left) i915_pm_rps (basic-api)
[367.350770] Subtest basic-api: SKIP (0.000s)
[367.693782] [130/161] (782s left) prime_self_import (basic-llseek-bad)
[368.136922] Starting subtest: basic-llseek-bad
[368.148841] Subtest basic-llseek-bad: SUCCESS (0.013s)
[368.501201] [131/161] (782s left) prime_self_import (basic-llseek-size)
[368.936996] Starting subtest: basic-llseek-size
[368.966959] Subtest basic-llseek-size: SUCCESS (0.030s)
[369.337339] [132/161] (781s left) prime_self_import (basic-with_fd_dup)
[369.777764] Starting subtest: basic-with_fd_dup
[369.800435] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[370.170644] [133/161] (781s left) prime_self_import (basic-with_one_bo)
[370.605889] Starting subtest: basic-with_one_bo
[370.625569] Subtest basic-with_one_bo: SUCCESS (0.023s)
[370.983983] [134/161] (780s left) prime_self_import (basic-with_one_bo_two_files)
[371.423379] Starting subtest: basic-with_one_bo_two_files
[371.443752] Subtest basic-with_one_bo_two_files: SUCCESS (0.018s)
[371.805683] [135/161] (780s left) prime_self_import (basic-with_two_bos)
[372.239587] Starting subtest: basic-with_two_bos
[372.257515] Subtest basic-with_two_bos: SUCCESS (0.019s)
[372.625189] [136/161] (779s left) prime_vgem (basic-fence-flip)
[373.122664] Starting subtest: basic-fence-flip
[373.910694] Subtest basic-fence-flip: SUCCESS (0.786s)
[374.311402] [137/161] (778s left) prime_vgem (basic-fence-mmap)
[374.806900] Starting subtest: basic-fence-mmap
[375.002880] Subtest basic-fence-mmap: SUCCESS (0.201s)
[375.375663] [138/161] (777s left) prime_vgem (basic-fence-read)
[375.850831] Starting subtest: basic-fence-read
[376.114691] Subtest basic-fence-read: SUCCESS (0.263s)
[376.475157] [139/161] (776s left) prime_vgem (basic-gtt)
[376.951150] Starting subtest: basic-gtt
[377.031959] Subtest basic-gtt: SUCCESS (0.080s)
[377.392489] [140/161] (776s left) prime_vgem (basic-read)
[377.868281] Starting subtest: basic-read
[377.974353] Subtest basic-read: SUCCESS (0.107s)
[378.346255] [141/161] (775s left) prime_vgem (basic-write)
[378.817462] Starting subtest: basic-write
[378.931222] Subtest basic-write: SUCCESS (0.114s)
[379.291851] [142/161] (774s left) vgem_basic (setversion)
[379.752792] Starting subtest: setversion
[379.761314] Subtest setversion: SUCCESS (0.000s)
[380.086917] [143/161] (774s left) vgem_basic (create)
[380.535801] Starting subtest: create
[380.541395] Subtest create: SUCCESS (0.003s)
[380.939153] [144/161] (773s left) vgem_basic (debugfs)
[381.396598] Starting subtest: debugfs
[381.402589] Subtest debugfs: SUCCESS (0.004s)
[381.746961] [145/161] (773s left) vgem_basic (dmabuf-export)
[382.197720] Starting subtest: dmabuf-export
[382.215938] Subtest dmabuf-export: SUCCESS (0.013s)
[382.560864] [146/161] (772s left) vgem_basic (dmabuf-fence)
[383.020564] Starting subtest: dmabuf-fence
[383.024627] Subtest dmabuf-fence: SUCCESS (0.005s)
[383.360959] [147/161] (772s left) vgem_basic (dmabuf-fence-before)
[383.821432] Starting subtest: dmabuf-fence-before
[383.826382] Subtest dmabuf-fence-before: SUCCESS (0.004s)
[384.170843] [148/161] (772s left) vgem_basic (dmabuf-mmap)
[384.632804] Starting subtest: dmabuf-mmap
[384.710108] Subtest dmabuf-mmap: SUCCESS (0.079s)
[385.047266] [149/161] (771s left) vgem_basic (mmap)
[385.499786] Starting subtest: mmap
[385.558978] Subtest mmap: SUCCESS (0.061s)
[385.900976] [150/161] (770s left) vgem_basic (second-client)
[386.354368] Starting subtest: second-client
[386.383368] Subtest second-client: SUCCESS (0.032s)
[386.729869] [151/161] (770s left) vgem_basic (sysfs)
[387.185208] Starting subtest: sysfs
[387.190559] Subtest sysfs: SUCCESS (0.006s)
[387.528389] [152/161] (769s left) core_hotunplug (unbind-rebind)
[388.301291] Starting subtest: unbind-rebind
[394.044335] Subtest unbind-rebind: SUCCESS (5.743s)
[394.369604] [153/161] (763s left) vgem_basic (unload)
[394.827264] Starting subtest: unload
[424.500453] Subtest unload: SUCCESS (29.677s)
[425.016674] [154/161] (733s left) i915_module_load (reload)
[425.465349] Starting subtest: reload
[435.519657] Subtest reload: SUCCESS (10.055s)
[436.191185] [155/161] (722s left) gem_lmem_swapping (basic)
[436.966166] Subtest basic: SKIP (0.000s)
[437.293428] [156/161] (721s left) gem_lmem_swapping (parallel-random-engines)
[437.767836] Subtest parallel-random-engines: SKIP (0.000s)
[438.092465] [157/161] (721s left) gem_lmem_swapping (random-engines)
[438.572396] Subtest random-engines: SKIP (0.000s)
[438.894606] [158/161] (720s left) gem_lmem_swapping (verify-random)
[439.368556] Subtest verify-random: SKIP (0.000s)
[439.696809] [159/161] (720s left) i915_pm_rpm (module-reload)
[451.670767] Subtest module-reload: SKIP (0.000s)
[451.998196] [160/161] (708s left) i915_selftest (live)
[454.867672] Starting subtest: live
[454.872796] Starting dynamic subtest: sanitycheck
[462.357431] Dynamic subtest sanitycheck: SUCCESS (7.485s)
[462.439739] Starting dynamic subtest: uncore
[469.764582] Dynamic subtest uncore: SUCCESS (7.405s)
[469.811853] Starting dynamic subtest: workarounds
[477.145647] Dynamic subtest workarounds: SUCCESS (7.375s)
[477.221278] Starting dynamic subtest: gt_engines
[484.600925] Dynamic subtest gt_engines: SUCCESS (7.447s)
[484.660135] Starting dynamic subtest: gt_timelines
[498.282642] Dynamic subtest gt_timelines: SUCCESS (13.676s)
[498.369549] Starting dynamic subtest: gt_contexts
[505.884880] Dynamic subtest gt_contexts: SUCCESS (7.599s)
[505.932850] Starting dynamic subtest: gt_lrc
[513.209460] Dynamic subtest gt_lrc: SUCCESS (7.317s)
[513.284672] Starting dynamic subtest: gt_mocs
[520.667543] Dynamic subtest gt_mocs: SUCCESS (7.452s)
[520.746684] Starting dynamic subtest: gt_pm
[528.482937] Dynamic subtest gt_pm: SUCCESS (7.812s)
[528.530485] Starting dynamic subtest: gt_heartbeat
[535.948739] Dynamic subtest gt_heartbeat: SUCCESS (7.459s)
[535.990452] Starting dynamic subtest: gt_tlb
[543.207286] Dynamic subtest gt_tlb: SUCCESS (7.253s)
[543.256947] Starting dynamic subtest: requests
[553.194564] Dynamic subtest requests: SUCCESS (9.982s)
[553.246398] Starting dynamic subtest: migrate
[560.684396] Dynamic subtest migrate: SUCCESS (7.480s)
[560.749942] Starting dynamic subtest: active
[568.152868] Dynamic subtest active: SUCCESS (7.463s)
[568.235489] Starting dynamic subtest: objects
[576.427860] Dynamic subtest objects: SUCCESS (8.265s)
[576.484401] Starting dynamic subtest: mman
[591.629971] Dynamic subtest mman: SUCCESS (15.201s)
[591.734267] Starting dynamic subtest: dmabuf
[599.319405] Dynamic subtest dmabuf: SUCCESS (7.678s)
[599.367411] Starting dynamic subtest: vma
[606.821384] Dynamic subtest vma: SUCCESS (7.496s)
[606.869549] Starting dynamic subtest: coherency
[619.678216] Dynamic subtest coherency: SUCCESS (12.851s)
[619.769191] Starting dynamic subtest: gtt
[630.073330] Dynamic subtest gtt: SUCCESS (10.388s)
[630.120291] Starting dynamic subtest: gem
[637.835851] Dynamic subtest gem: SUCCESS (7.756s)
[637.925851] Starting dynamic subtest: evict
[645.184481] Dynamic subtest evict: SUCCESS (7.339s)
[645.275103] Starting dynamic subtest: hugepages
[652.634570] Dynamic subtest hugepages: SUCCESS (7.441s)
[652.680083] Starting dynamic subtest: gem_contexts
[660.085394] Dynamic subtest gem_contexts: SUCCESS (7.441s)
[660.129902] Starting dynamic subtest: client
[667.472543] Dynamic subtest client: SUCCESS (7.386s)
[667.524358] Starting dynamic subtest: gem_migrate
[674.865213] Dynamic subtest gem_migrate: SUCCESS (7.384s)
[674.947920] Starting dynamic subtest: reset
[684.410905] Dynamic subtest reset: SUCCESS (9.538s)
[684.457624] Starting dynamic subtest: memory_region
[691.843218] Dynamic subtest memory_region: SUCCESS (7.425s)
[691.941707] Starting dynamic subtest: hangcheck
[701.918347] Dynamic subtest hangcheck: SUCCESS (10.068s)
[701.969281] Starting dynamic subtest: execlists
[709.250844] Dynamic subtest execlists: SUCCESS (7.325s)
[709.297294] Starting dynamic subtest: ring_submission
[716.830403] Dynamic subtest ring_submission: SUCCESS (7.573s)
[716.881853] Starting dynamic subtest: perf
[724.159373] Dynamic subtest perf: SUCCESS (7.322s)
[724.210979] Starting dynamic subtest: slpc
[731.521791] Dynamic subtest slpc: SUCCESS (7.353s)
[731.583954] Starting dynamic subtest: guc
[738.866406] Dynamic subtest guc: SUCCESS (7.337s)
[738.950186] Starting dynamic subtest: guc_multi_lrc
[746.257574] Dynamic subtest guc_multi_lrc: SUCCESS (7.386s)
[746.331140] Starting dynamic subtest: guc_hang
[753.665307] Dynamic subtest guc_hang: SUCCESS (7.400s)
[753.711329] Starting dynamic subtest: late_gt_pm
[761.158202] Dynamic subtest late_gt_pm: SUCCESS (7.487s)
[761.162291] Subtest live: SUCCESS (306.290s)
[761.408610] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[761.418711] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[761.434509] [161/161] (399s left) dmabuf (all-tests)
[761.903507] Starting subtest: all-tests
[761.907315] Starting dynamic subtest: sanitycheck
[762.039506] Dynamic subtest sanitycheck: SUCCESS (0.141s)
[762.097573] Starting dynamic subtest: dma_fence
[762.304826] Dynamic subtest dma_fence: SUCCESS (0.258s)
[762.352983] Starting dynamic subtest: dma_fence_chain