[48.165646] Initializing watchdogs
[48.165968]   /dev/watchdog0
[48.199059] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0042 Intel Ironlake (Gen5) Core Processor Integrated Graphics Controller
[48.228757] [001/162] (960s left) i915_module_load (load)
[48.420298] Starting subtest: load
[50.541360] Subtest load: SUCCESS (2.116s)
[50.672744] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[50.675497] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[50.699862] [002/162] (958s left) core_auth (basic-auth)
[50.891939] Starting subtest: basic-auth
[50.911369] Subtest basic-auth: SUCCESS (0.001s)
[51.062487] [003/162] (957s left) core_debugfs (read-all-entries)
[51.247391] Starting subtest: read-all-entries
[51.290845] Subtest read-all-entries: SUCCESS (0.085s)
[51.449424] [004/162] (957s left) core_sysfs (read-all-entries)
[51.618334] Starting subtest: read-all-entries
[51.639694] Subtest read-all-entries: SUCCESS (0.004s)
[51.788327] [005/162] (957s left) fbdev (eof)
[51.944349] Starting subtest: eof
[51.964714] Subtest eof: SUCCESS (0.000s)
[52.118809] [006/162] (957s left) fbdev (info)
[52.266882] Starting subtest: info
[52.285804] Subtest info: SUCCESS (0.000s)
[52.433722] [007/162] (957s left) fbdev (nullptr)
[52.560926] Starting subtest: nullptr
[52.579842] Subtest nullptr: SUCCESS (0.000s)
[52.736909] [008/162] (957s left) fbdev (read)
[52.897778] Starting subtest: read
[53.153397] Subtest read: SUCCESS (0.285s)
[53.334533] [009/162] (956s left) fbdev (write)
[53.477868] Starting subtest: write
[53.605607] Subtest write: SUCCESS (0.145s)
[53.753427] [010/162] (956s left) gem_basic (bad-close)
[53.923447] Starting subtest: bad-close
[53.962045] Subtest bad-close: SUCCESS (0.000s)
[54.117657] [011/162] (956s left) gem_basic (create-close)
[54.297122] Starting subtest: create-close
[54.339819] Subtest create-close: SUCCESS (0.000s)
[54.492498] [012/162] (955s left) gem_basic (create-fd-close)
[54.660868] Starting subtest: create-fd-close
[54.699126] Subtest create-fd-close: SUCCESS (0.001s)
[54.851518] [013/162] (955s left) gem_busy (busy@all-engines)
[55.025797] Starting subtest: busy
[55.045594] Starting dynamic subtest: all-engines
[55.067105] Dynamic subtest all-engines: SUCCESS (0.034s)
[55.089689] Subtest busy: SUCCESS (0.035s)
[55.461299] [014/162] (955s left) gem_close_race (basic-process)
[55.629975] Starting subtest: basic-process
[55.698112] Subtest basic-process: SUCCESS (0.063s)
[55.980450] [015/162] (954s left) gem_close_race (basic-threads)
[56.144993] Starting subtest: basic-threads
[57.300233] Subtest basic-threads: SUCCESS (1.206s)
[57.589622] [016/162] (953s left) gem_ctx_create (basic)
[57.762522] Starting subtest: basic
[57.783769] Subtest basic: SUCCESS (0.000s)
[58.021374] [017/162] (953s left) gem_ctx_create (basic-files)
[58.188176] Starting subtest: basic-files
[60.222788] Subtest basic-files: SUCCESS (2.039s)
[60.745549] [018/162] (950s left) gem_ctx_exec (basic)
[60.941306] Starting subtest: basic
[60.963339] Subtest basic: SUCCESS (0.001s)
[61.144569] [019/162] (950s left) gem_exec_basic (basic)
[61.340663] Starting subtest: basic
[61.365436] Starting dynamic subtest: rcs0-smem
[61.389657] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[61.427690] Starting dynamic subtest: vcs0-smem
[61.449502] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[61.474645] Subtest basic: SUCCESS (0.002s)
[61.652280] [020/162] (950s left) gem_exec_create (basic)
[61.847804] Starting subtest: basic
[61.870470] Starting dynamic subtest: smem
[63.894873] Dynamic subtest smem: SUCCESS (2.070s)
[63.920483] Subtest basic: SUCCESS (2.071s)
[64.227470] [021/162] (947s left) gem_exec_fence (basic-busy)
[64.581175] Starting subtest: basic-busy
[64.600028] Starting dynamic subtest: rcs0
[64.641387] Dynamic subtest rcs0: SUCCESS (0.026s)
[64.673207] Starting dynamic subtest: vcs0
[64.716330] Dynamic subtest vcs0: SUCCESS (0.053s)
[64.736097] Subtest basic-busy: SUCCESS (0.080s)
[65.300976] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[65.323545] [022/162] (946s left) gem_exec_fence (basic-wait)
[65.592268] Starting subtest: basic-wait
[65.611778] Starting dynamic subtest: rcs0
[65.631871] Dynamic subtest rcs0: SUCCESS (0.025s)
[65.664574] Starting dynamic subtest: vcs0
[65.686875] Dynamic subtest vcs0: SUCCESS (0.027s)
[65.708444] Subtest basic-wait: SUCCESS (0.054s)
[66.210396] [023/162] (946s left) gem_exec_fence (basic-await)
[66.488737] Starting subtest: basic-await
[66.508516] Starting dynamic subtest: rcs0
[66.555388] Dynamic subtest rcs0: SUCCESS (0.074s)
[66.587180] Starting dynamic subtest: vcs0
[66.627122] Dynamic subtest vcs0: SUCCESS (0.072s)
[66.646313] Subtest basic-await: SUCCESS (0.148s)
[67.219315] [024/162] (945s left) gem_exec_fence (nb-await)
[67.484663] Starting subtest: nb-await
[67.506167] Starting dynamic subtest: rcs0
[67.546083] Dynamic subtest rcs0: SUCCESS (0.055s)
[67.580419] Starting dynamic subtest: vcs0
[67.599874] Dynamic subtest vcs0: SUCCESS (0.053s)
[67.620652] Subtest nb-await: SUCCESS (0.109s)
[68.163634] [025/162] (944s left) gem_exec_gttfill (basic)
[68.318305] Starting subtest: basic
[78.573116] Subtest basic: SUCCESS (10.296s)
[78.882087] [026/162] (933s left) gem_exec_parallel (engines)
[79.181406] Starting subtest: engines
[79.200453] Starting dynamic subtest: basic
[81.567083] Dynamic subtest basic: SUCCESS (2.240s)
[81.601667] Starting dynamic subtest: contexts
[83.290093] Dynamic subtest contexts: SUCCESS (1.909s)
[83.322803] Starting dynamic subtest: fds
[83.362110] Dynamic subtest fds: SKIP (0.001s)
[83.392449] Starting dynamic subtest: userptr
[88.962472] Dynamic subtest userptr: SUCCESS (5.482s)
[88.981859] Subtest engines: SUCCESS (9.637s)
[89.146430] [027/162] (923s left) gem_exec_store (basic)
[89.389567] Starting subtest: basic
[89.410795] Subtest basic: SUCCESS (0.050s)
[89.647533] [028/162] (923s left) gem_flink_basic (bad-flink)
[89.813646] Starting subtest: bad-flink
[89.836385] Subtest bad-flink: SUCCESS (0.000s)
[90.010394] [029/162] (923s left) gem_flink_basic (bad-open)
[90.200835] Starting subtest: bad-open
[90.222188] Subtest bad-open: SUCCESS (0.000s)
[90.384977] [030/162] (923s left) gem_flink_basic (basic)
[90.560603] Starting subtest: basic
[90.580429] Subtest basic: SUCCESS (0.000s)
[90.746558] [031/162] (922s left) gem_flink_basic (double-flink)
[90.897102] Starting subtest: double-flink
[90.916254] Subtest double-flink: SUCCESS (0.000s)
[91.081724] [032/162] (922s left) gem_flink_basic (flink-lifetime)
[91.229618] Starting subtest: flink-lifetime
[91.250372] Subtest flink-lifetime: SUCCESS (0.002s)
[91.423440] [033/162] (922s left) gem_huc_copy (huc-copy)
[91.611360] Subtest huc-copy: SKIP (0.000s)
[91.773156] [034/162] (922s left) gem_linear_blits (basic)
[92.002762] Starting subtest: basic
[92.022599] Subtest basic: SUCCESS (0.017s)
[92.271263] [035/162] (921s left) gem_mmap (basic)
[92.581876] Starting subtest: basic
[92.682157] Subtest basic: SUCCESS (0.001s)
[92.848076] [036/162] (921s left) gem_mmap_gtt (basic)
[93.020611] Starting subtest: basic
[93.041600] Subtest basic: SUCCESS (0.001s)
[93.209170] [037/162] (921s left) gem_render_linear_blits (basic)
[93.377930] Starting subtest: basic
[93.476612] Subtest basic: SUCCESS (0.039s)
[93.647715] [038/162] (921s left) gem_render_tiled_blits (basic)
[93.820580] Starting subtest: basic
[93.939888] Subtest basic: SUCCESS (0.043s)
[94.105918] [039/162] (920s left) gem_ringfill (basic-all)
[94.359153] Starting subtest: basic-all
[95.441962] Subtest basic-all: SUCCESS (1.107s)
[95.652691] [040/162] (919s left) gem_softpin (allocator-basic)
[95.844438] Subtest allocator-basic: SKIP (0.000s)
[96.027274] [041/162] (919s left) gem_softpin (allocator-basic-reserve)
[96.218954] Subtest allocator-basic-reserve: SKIP (0.000s)
[96.404067] [042/162] (918s left) gem_softpin (safe-alignment)
[96.613851] Starting subtest: safe-alignment
[96.639688] Subtest safe-alignment: SUCCESS (0.007s)
[96.804445] [043/162] (918s left) gem_sync (basic-all)
[97.025472] Starting subtest: basic-all
[99.326563] Subtest basic-all: SUCCESS (2.137s)
[99.505068] [044/162] (916s left) gem_sync (basic-each)
[99.716114] Starting subtest: basic-each
[101.790038] Subtest basic-each: SUCCESS (2.126s)
[102.050687] [045/162] (913s left) gem_tiled_blits (basic)
[102.253097] Starting subtest: basic
[102.296389] Subtest basic: SUCCESS (0.035s)
[102.506384] [046/162] (913s left) gem_tiled_fence_blits (basic)
[102.853819] Starting subtest: basic
[102.873648] Subtest basic: SUCCESS (0.016s)
[103.103355] [047/162] (913s left) gem_tiled_pread_basic (basic)
[103.379511] Starting subtest: basic
[103.589200] Subtest basic: SUCCESS (0.229s)
[103.788518] [048/162] (912s left) gem_wait (busy@all-engines)
[103.977706] Starting subtest: busy
[104.000849] Starting dynamic subtest: all-engines
[104.495914] Dynamic subtest all-engines: SUCCESS (0.518s)
[104.521283] Subtest busy: SUCCESS (0.519s)
[104.835718] [049/162] (911s left) gem_wait (wait@all-engines)
[105.048411] Starting subtest: wait
[105.074253] Starting dynamic subtest: all-engines
[106.042952] Dynamic subtest all-engines: SUCCESS (1.025s)
[106.067515] Subtest wait: SUCCESS (1.026s)
[106.370380] [050/162] (910s left) i915_getparams_basic (basic-eu-total)
[106.536201] Starting subtest: basic-eu-total
[106.579756] Subtest basic-eu-total: SUCCESS (0.000s)
[106.740110] [051/162] (910s left) i915_getparams_basic (basic-subslice-total)
[106.915805] Starting subtest: basic-subslice-total
[106.955432] Subtest basic-subslice-total: SUCCESS (0.000s)
[107.119065] [052/162] (909s left) i915_hangman (error-state-basic)
[107.274040] Starting subtest: error-state-basic
[107.292789] Subtest error-state-basic: SUCCESS (0.005s)
[107.506417] [053/162] (909s left) i915_pciid
[107.879507] [054/162] (909s left) intel_hwmon (hwmon-read)
[108.089144] Subtest hwmon-read: SKIP (0.000s)
[108.278994] [055/162] (909s left) intel_hwmon (hwmon-write)
[108.466049] Subtest hwmon-write: SKIP (0.000s)
[108.629545] [056/162] (909s left) kms_addfb_basic (addfb25-4-tiled)
[108.873684] Starting subtest: addfb25-4-tiled
[108.893088] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[109.059228] [057/162] (908s left) kms_addfb_basic (addfb25-bad-modifier)
[109.225958] Starting subtest: addfb25-bad-modifier
[109.248544] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[109.413693] [058/162] (908s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[109.591871] Starting subtest: addfb25-framebuffer-vs-set-tiling
[109.613585] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[109.776326] [059/162] (908s left) kms_addfb_basic (addfb25-modifier-no-flag)
[109.967705] Starting subtest: addfb25-modifier-no-flag
[109.987158] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[110.156874] [060/162] (908s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[110.339766] Starting subtest: addfb25-x-tiled-legacy
[110.361577] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[110.530266] [061/162] (907s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[110.678933] Starting subtest: addfb25-x-tiled-mismatch-legacy
[110.700697] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[110.865071] [062/162] (907s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[111.030526] Starting subtest: addfb25-yf-tiled-legacy
[111.050195] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[111.212047] [063/162] (907s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[111.362111] Starting subtest: addfb25-y-tiled-legacy
[111.381814] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[111.543567] [064/162] (907s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[111.712714] Starting subtest: addfb25-y-tiled-small-legacy
[111.752220] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[111.919686] [065/162] (907s left) kms_addfb_basic (bad-pitch-0)
[112.097092] Starting subtest: bad-pitch-0
[112.118811] Subtest bad-pitch-0: SUCCESS (0.000s)
[112.286944] [066/162] (906s left) kms_addfb_basic (bad-pitch-1024)
[112.455826] Starting subtest: bad-pitch-1024
[112.476323] Subtest bad-pitch-1024: SUCCESS (0.000s)
[112.639109] [067/162] (906s left) kms_addfb_basic (bad-pitch-128)
[112.828334] Starting subtest: bad-pitch-128
[112.847647] Subtest bad-pitch-128: SUCCESS (0.000s)
[113.017666] [068/162] (906s left) kms_addfb_basic (bad-pitch-256)
[113.187869] Starting subtest: bad-pitch-256
[113.209935] Subtest bad-pitch-256: SUCCESS (0.000s)
[113.379050] [069/162] (906s left) kms_addfb_basic (bad-pitch-32)
[113.546699] Starting subtest: bad-pitch-32
[113.567406] Subtest bad-pitch-32: SUCCESS (0.000s)
[113.730651] [070/162] (906s left) kms_addfb_basic (bad-pitch-63)
[113.904546] Starting subtest: bad-pitch-63
[113.924231] Subtest bad-pitch-63: SUCCESS (0.000s)
[114.092103] [071/162] (905s left) kms_addfb_basic (bad-pitch-65536)
[114.260164] Starting subtest: bad-pitch-65536
[114.279750] Subtest bad-pitch-65536: SUCCESS (0.000s)
[114.449676] [072/162] (905s left) kms_addfb_basic (bad-pitch-999)
[114.618083] Starting subtest: bad-pitch-999
[114.639746] Subtest bad-pitch-999: SUCCESS (0.000s)
[114.807413] [073/162] (905s left) kms_addfb_basic (basic)
[114.997115] Starting subtest: basic
[115.017148] Subtest basic: SUCCESS (0.000s)
[115.180999] [074/162] (905s left) kms_addfb_basic (basic-x-tiled-legacy)
[115.349604] Starting subtest: basic-x-tiled-legacy
[115.368601] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[115.556987] [075/162] (905s left) kms_addfb_basic (basic-y-tiled-legacy)
[115.745296] Starting subtest: basic-y-tiled-legacy
[115.767116] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[115.934716] [076/162] (904s left) kms_addfb_basic (bo-too-small)
[116.127966] Starting subtest: bo-too-small
[116.147326] Subtest bo-too-small: SUCCESS (0.001s)
[116.307681] [077/162] (904s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[116.501456] Starting subtest: bo-too-small-due-to-tiling
[116.522400] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[116.690758] [078/162] (904s left) kms_addfb_basic (clobberred-modifier)
[116.856173] Starting subtest: clobberred-modifier
[116.877768] Subtest clobberred-modifier: SUCCESS (0.000s)
[117.039638] [079/162] (904s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[117.211256] Starting subtest: framebuffer-vs-set-tiling
[117.231564] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[117.394240] [080/162] (904s left) kms_addfb_basic (invalid-get-prop)
[117.564023] Starting subtest: invalid-get-prop
[117.582796] Subtest invalid-get-prop: SUCCESS (0.000s)
[117.751901] [081/162] (903s left) kms_addfb_basic (invalid-get-prop-any)
[117.938370] Starting subtest: invalid-get-prop-any
[117.960134] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[118.129797] [082/162] (903s left) kms_addfb_basic (invalid-set-prop)
[118.315569] Starting subtest: invalid-set-prop
[118.336307] Subtest invalid-set-prop: SUCCESS (0.000s)
[118.499599] [083/162] (903s left) kms_addfb_basic (invalid-set-prop-any)
[118.666693] Starting subtest: invalid-set-prop-any
[118.686091] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[118.853197] [084/162] (903s left) kms_addfb_basic (no-handle)
[119.035873] Starting subtest: no-handle
[119.057963] Subtest no-handle: SUCCESS (0.000s)
[119.226518] [085/162] (903s left) kms_addfb_basic (size-max)
[119.394539] Starting subtest: size-max
[119.416056] Subtest size-max: SUCCESS (0.000s)
[119.577668] [086/162] (902s left) kms_addfb_basic (small-bo)
[119.748332] Starting subtest: small-bo
[119.767860] Subtest small-bo: SUCCESS (0.000s)
[119.934319] [087/162] (902s left) kms_addfb_basic (tile-pitch-mismatch)
[120.123051] Starting subtest: tile-pitch-mismatch
[120.143446] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[120.311322] [088/162] (902s left) kms_addfb_basic (too-high)
[120.476134] Starting subtest: too-high
[120.497862] Subtest too-high: SUCCESS (0.002s)
[120.663574] [089/162] (902s left) kms_addfb_basic (too-wide)
[120.851888] Starting subtest: too-wide
[120.871592] Subtest too-wide: SUCCESS (0.000s)
[121.035762] [090/162] (902s left) kms_addfb_basic (unused-handle)
[121.206876] Starting subtest: unused-handle
[121.225408] Subtest unused-handle: SUCCESS (0.000s)
[121.393136] [091/162] (901s left) kms_addfb_basic (unused-modifier)
[121.577397] Starting subtest: unused-modifier
[121.599315] Subtest unused-modifier: SUCCESS (0.000s)
[121.764817] [092/162] (901s left) kms_addfb_basic (unused-offsets)
[121.934985] Starting subtest: unused-offsets
[121.955110] Subtest unused-offsets: SUCCESS (0.000s)
[122.116311] [093/162] (901s left) kms_addfb_basic (unused-pitches)
[122.292020] Starting subtest: unused-pitches
[122.310813] Subtest unused-pitches: SUCCESS (0.000s)
[122.477383] [094/162] (901s left) kms_busy (basic)
[122.666366] Starting subtest: basic
[122.686721] Starting dynamic subtest: flip
[123.237975] Dynamic subtest flip: SUCCESS (0.580s)
[123.272424] Starting dynamic subtest: modeset
[123.886395] Dynamic subtest modeset: SUCCESS (0.652s)
[123.906934] Subtest basic: SUCCESS (1.234s)
[124.157806] [095/162] (899s left) kms_prop_blob (basic)
[124.334149] Starting subtest: basic
[124.356174] Subtest basic: SUCCESS (0.000s)
[124.524415] [096/162] (899s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[124.697936] Starting subtest: basic-busy-flip-before-cursor-atomic
[125.667406] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.960s)
[125.937514] [097/162] (898s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[126.105984] Starting subtest: basic-busy-flip-before-cursor-legacy
[127.067103] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.950s)
[127.327602] [098/162] (897s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[127.491244] Starting subtest: basic-flip-after-cursor-atomic
[128.441564] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.946s)
[128.702276] [099/162] (895s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[128.881132] Starting subtest: basic-flip-after-cursor-legacy
[129.855052] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.963s)
[130.105081] [100/162] (894s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[130.276671] Starting subtest: basic-flip-after-cursor-varying-size
[131.239968] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.954s)
[131.496497] [101/162] (893s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[131.659070] Starting subtest: basic-flip-before-cursor-atomic
[132.618675] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.954s)
[132.877345] [102/162] (892s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[133.045429] Starting subtest: basic-flip-before-cursor-legacy
[133.996866] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.949s)
[134.247367] [103/162] (890s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[134.419221] Starting subtest: basic-flip-before-cursor-varying-size
[135.377605] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.950s)
[135.613445] [104/162] (889s left) kms_dsc (dsc-basic)
[135.825038] Subtest dsc-basic: SKIP (0.000s)
[135.994515] [105/162] (889s left) kms_flip (basic-flip-vs-dpms)
[136.206204] Starting subtest: basic-flip-vs-dpms
[136.225843] Starting dynamic subtest: A-VGA1
[137.495281] Dynamic subtest A-VGA1: SUCCESS (1.317s)
[137.528471] Starting dynamic subtest: B-VGA1
[138.739369] Dynamic subtest B-VGA1: SUCCESS (1.249s)
[138.758433] Subtest basic-flip-vs-dpms: SUCCESS (2.568s)
[139.011831] [106/162] (886s left) kms_flip (basic-flip-vs-modeset)
[139.205391] Starting subtest: basic-flip-vs-modeset
[139.224917] Starting dynamic subtest: A-VGA1
[140.508122] Dynamic subtest A-VGA1: SUCCESS (1.316s)
[140.541595] Starting dynamic subtest: B-VGA1
[141.748489] Dynamic subtest B-VGA1: SUCCESS (1.239s)
[141.770242] Subtest basic-flip-vs-modeset: SUCCESS (2.559s)
[142.015530] [107/162] (883s left) kms_flip (basic-flip-vs-wf_vblank)
[142.229126] Starting subtest: basic-flip-vs-wf_vblank
[142.247958] Starting dynamic subtest: A-VGA1
[143.724566] Dynamic subtest A-VGA1: SUCCESS (1.517s)
[143.760137] Starting dynamic subtest: B-VGA1
[145.237597] Dynamic subtest B-VGA1: SUCCESS (1.506s)
[145.255887] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.027s)
[145.523812] [108/162] (880s left) kms_flip (basic-plain-flip)
[145.733869] Starting subtest: basic-plain-flip
[145.753070] Starting dynamic subtest: A-VGA1
[146.933600] Dynamic subtest A-VGA1: SUCCESS (1.245s)
[146.966575] Starting dynamic subtest: B-VGA1
[148.159889] Dynamic subtest B-VGA1: SUCCESS (1.228s)
[148.178125] Subtest basic-plain-flip: SUCCESS (2.478s)
[148.487482] [109/162] (877s left) kms_force_connector_basic (force-connector-state)
[148.678766] Starting subtest: force-connector-state
[148.843463] Subtest force-connector-state: SUCCESS (0.175s)
[149.175481] [110/162] (877s left) kms_force_connector_basic (force-edid)
[149.366558] Starting subtest: force-edid
[149.390114] Subtest force-edid: SUCCESS (0.026s)
[149.608931] [111/162] (876s left) kms_force_connector_basic (force-load-detect)
[149.820176] Starting subtest: force-load-detect
[149.986755] Subtest force-load-detect: SUCCESS (0.199s)
[150.339620] [112/162] (876s left) kms_force_connector_basic (prune-stale-modes)
[150.564884] Starting subtest: prune-stale-modes
[150.586436] Subtest prune-stale-modes: SUCCESS (0.022s)
[150.784533] [113/162] (876s left) kms_frontbuffer_tracking (basic)
[151.017127] Starting subtest: basic
[153.235199] Subtest basic: SUCCESS (2.279s)
[153.450801] [114/162] (873s left) kms_hdmi_inject (inject-audio)
[153.639193] Starting subtest: inject-audio
[153.681676] Subtest inject-audio: SKIP (0.000s)
[153.889979] [115/162] (873s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[154.086042] Starting subtest: compare-crc-sanitycheck-xr24
[154.105468] Starting dynamic subtest: pipe-A-VGA-1
[154.415425] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.336s)
[154.452988] Starting dynamic subtest: pipe-B-VGA-1
[154.768695] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.351s)
[154.788308] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.690s)
[155.032144] [116/162] (872s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[155.198094] Starting subtest: compare-crc-sanitycheck-nv12
[155.219395] Starting dynamic subtest: pipe-A-VGA-1
[155.262666] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[155.299502] Starting dynamic subtest: pipe-B-VGA-1
[155.338741] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[155.379437] Subtest compare-crc-sanitycheck-nv12: SKIP (0.004s)
[155.550480] [117/162] (871s left) kms_pipe_crc_basic (hang-read-crc)
[155.714415] Starting subtest: hang-read-crc
[155.736358] Starting dynamic subtest: pipe-A-VGA-1
[156.634806] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.907s)
[156.669878] Starting dynamic subtest: pipe-B-VGA-1
[157.514383] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.885s)
[157.532889] Subtest hang-read-crc: SUCCESS (1.797s)
[157.760902] [118/162] (869s left) kms_pipe_crc_basic (nonblocking-crc)
[157.953693] Starting subtest: nonblocking-crc
[157.975363] Starting dynamic subtest: pipe-A-VGA-1
[158.450542] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.507s)
[158.484048] Starting dynamic subtest: pipe-B-VGA-1
[158.993964] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.534s)
[159.013971] Subtest nonblocking-crc: SUCCESS (1.046s)
[159.228032] [119/162] (868s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[159.411443] Starting subtest: nonblocking-crc-frame-sequence
[159.432540] Starting dynamic subtest: pipe-A-VGA-1
[159.920228] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.513s)
[159.954943] Starting dynamic subtest: pipe-B-VGA-1
[160.445108] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.520s)
[160.465427] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.037s)
[160.674890] [120/162] (867s left) kms_pipe_crc_basic (read-crc)
[160.842177] Starting subtest: read-crc
[160.860859] Starting dynamic subtest: pipe-A-VGA-1
[161.275363] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.425s)
[161.312433] Starting dynamic subtest: pipe-B-VGA-1
[161.737440] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.452s)
[161.757584] Subtest read-crc: SUCCESS (0.882s)
[161.977574] [121/162] (866s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[162.167439] Starting subtest: read-crc-frame-sequence
[162.186973] Starting dynamic subtest: pipe-A-VGA-1
[162.573528] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.429s)
[162.612622] Starting dynamic subtest: pipe-B-VGA-1
[163.034098] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.458s)
[163.052951] Subtest read-crc-frame-sequence: SUCCESS (0.892s)
[163.287674] [122/162] (865s left) kms_pm_backlight (basic-brightness)
[163.502681] Subtest basic-brightness: SKIP (0.000s)
[163.669185] [123/162] (864s left) kms_pm_rpm (basic-pci-d3-state)
[175.182660] Subtest basic-pci-d3-state: SKIP (0.000s)
[175.349468] [124/162] (853s left) kms_pm_rpm (basic-rte)
[175.514577] Starting subtest: basic-rte
[186.851400] Subtest basic-rte: SKIP (11.133s)
[187.061045] [125/162] (841s left) kms_psr (psr-primary-page-flip)
[187.259495] Subtest psr-primary-page-flip: SKIP (0.000s)
[187.427304] [126/162] (841s left) kms_psr (psr-cursor-plane-move)
[187.634133] Subtest psr-cursor-plane-move: SKIP (0.000s)
[187.807982] [127/162] (841s left) kms_psr (psr-sprite-plane-onoff)
[187.996297] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[188.165567] [128/162] (841s left) kms_psr (psr-primary-mmap-gtt)
[188.372039] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[188.539857] [129/162] (841s left) kms_setmode (basic-clone-single-crtc)
[188.696485] Starting subtest: basic-clone-single-crtc
[188.755156] Subtest basic-clone-single-crtc: SKIP (0.000s)
[188.927868] [130/162] (840s left) i915_pm_rps (basic-api)
[189.124362] Subtest basic-api: SKIP (0.000s)
[189.315580] [131/162] (840s left) prime_self_import (basic-llseek-bad)
[189.483233] Starting subtest: basic-llseek-bad
[189.528440] Subtest basic-llseek-bad: SUCCESS (0.003s)
[189.721098] [132/162] (840s left) prime_self_import (basic-llseek-size)
[189.884456] Starting subtest: basic-llseek-size
[189.929604] Subtest basic-llseek-size: SUCCESS (0.006s)
[190.123695] [133/162] (840s left) prime_self_import (basic-with_fd_dup)
[190.291206] Starting subtest: basic-with_fd_dup
[190.343358] Subtest basic-with_fd_dup: SUCCESS (0.004s)
[190.534504] [134/162] (839s left) prime_self_import (basic-with_one_bo)
[190.703763] Starting subtest: basic-with_one_bo
[190.753581] Subtest basic-with_one_bo: SUCCESS (0.005s)
[190.945851] [135/162] (839s left) prime_self_import (basic-with_one_bo_two_files)
[191.134574] Starting subtest: basic-with_one_bo_two_files
[191.182042] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s)
[191.375335] [136/162] (839s left) prime_self_import (basic-with_two_bos)
[191.524609] Starting subtest: basic-with_two_bos
[191.565926] Subtest basic-with_two_bos: SUCCESS (0.004s)
[191.734975] [137/162] (839s left) prime_vgem (basic-fence-flip)
[191.934757] Starting subtest: basic-fence-flip
[192.224755] Subtest basic-fence-flip: SUCCESS (0.343s)
[192.437186] [138/162] (838s left) prime_vgem (basic-fence-mmap)
[192.649715] Starting subtest: basic-fence-mmap
[192.669186] Subtest basic-fence-mmap: SUCCESS (0.051s)
[192.855987] [139/162] (838s left) prime_vgem (basic-fence-read)
[193.040746] Starting subtest: basic-fence-read
[193.063245] Subtest basic-fence-read: SUCCESS (0.056s)
[193.259133] [140/162] (838s left) prime_vgem (basic-gtt)
[193.471226] Starting subtest: basic-gtt
[193.491138] Subtest basic-gtt: SUCCESS (0.031s)
[193.681831] [141/162] (838s left) prime_vgem (basic-read)
[193.871677] Starting subtest: basic-read
[193.893208] Subtest basic-read: SUCCESS (0.033s)
[194.086530] [142/162] (837s left) prime_vgem (basic-write)
[194.294936] Starting subtest: basic-write
[194.313768] Subtest basic-write: SUCCESS (0.027s)
[194.518372] [143/162] (837s left) vgem_basic (setversion)
[194.709388] Starting subtest: setversion
[194.752293] Subtest setversion: SUCCESS (0.000s)
[194.915339] [144/162] (837s left) vgem_basic (create)
[195.102304] Starting subtest: create
[195.122109] Subtest create: SUCCESS (0.001s)
[195.291241] [145/162] (837s left) vgem_basic (debugfs)
[195.458035] Starting subtest: debugfs
[195.480181] Subtest debugfs: SUCCESS (0.000s)
[195.648672] [146/162] (837s left) vgem_basic (dmabuf-export)
[195.816216] Starting subtest: dmabuf-export
[195.857405] Subtest dmabuf-export: SUCCESS (0.004s)
[196.021291] [147/162] (836s left) vgem_basic (dmabuf-fence)
[196.207774] Starting subtest: dmabuf-fence
[196.227271] Subtest dmabuf-fence: SUCCESS (0.001s)
[196.399610] [148/162] (836s left) vgem_basic (dmabuf-fence-before)
[196.587244] Starting subtest: dmabuf-fence-before
[196.608761] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[196.774994] [149/162] (836s left) vgem_basic (dmabuf-mmap)
[196.971101] Starting subtest: dmabuf-mmap
[196.990771] Subtest dmabuf-mmap: SUCCESS (0.026s)
[197.158248] [150/162] (836s left) vgem_basic (mmap)
[197.342450] Starting subtest: mmap
[197.364111] Subtest mmap: SUCCESS (0.018s)
[197.534618] [151/162] (836s left) vgem_basic (second-client)
[197.723791] Starting subtest: second-client
[197.762759] Subtest second-client: SUCCESS (0.033s)
[197.934756] [152/162] (835s left) vgem_basic (sysfs)
[198.122885] Starting subtest: sysfs
[198.143571] Subtest sysfs: SUCCESS (0.001s)
[198.310956] [153/162] (835s left) core_hotunplug (unbind-rebind)
[198.577429] Starting subtest: unbind-rebind
[201.669580] Subtest unbind-rebind: SUCCESS (3.109s)
[201.839594] [154/162] (832s left) vgem_basic (unload)
[202.014484] Starting subtest: unload
[231.200471] Subtest unload: SUCCESS (29.042s)
[231.366627] [155/162] (802s left) i915_module_load (reload)
[231.526104] Starting subtest: reload
[234.384711] Subtest reload: SUCCESS (2.873s)
[234.552979] [156/162] (799s left) gem_lmem_swapping (basic)
[234.770479] Subtest basic: SKIP (0.000s)
[234.936222] [157/162] (799s left) gem_lmem_swapping (parallel-random-engines)
[235.145707] Subtest parallel-random-engines: SKIP (0.000s)
[235.334722] [158/162] (799s left) gem_lmem_swapping (random-engines)
[235.549184] Subtest random-engines: SKIP (0.000s)
[235.718874] [159/162] (799s left) gem_lmem_swapping (verify-random)
[235.933619] Subtest verify-random: SKIP (0.000s)
[236.101887] [160/162] (799s left) i915_pm_rpm (module-reload)
[247.603749] Subtest module-reload: SKIP (0.000s)
[247.763772] [161/162] (787s left) i915_selftest (live)