[47.193206] Initializing watchdogs
[47.193412]   /dev/watchdog0
[47.209717] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0162 Intel Ivybridge (Gen7) IvyBridge GT2 [HD Graphics 4000]
[47.234325] [001/161] (960s left) i915_module_load (load)
[47.701499] Starting subtest: load
[49.235374] Subtest load: SUCCESS (1.526s)
[49.339700] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[49.341410] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[49.363538] [002/161] (958s left) core_auth (basic-auth)
[49.530598] Starting subtest: basic-auth
[49.553335] Subtest basic-auth: SUCCESS (0.001s)
[49.650509] [003/161] (958s left) debugfs_test (read_all_entries)
[49.808787] Starting subtest: read_all_entries
[49.829110] Subtest read_all_entries: SUCCESS (0.047s)
[49.962605] [004/161] (958s left) debugfs_test (basic-hwmon)
[50.115931] Starting subtest: basic-hwmon
[50.155832] Subtest basic-hwmon: SKIP (0.000s)
[50.256210] [005/161] (957s left) debugfs_test (sysfs)
[50.405272] Starting subtest: sysfs
[50.426098] Subtest sysfs: SUCCESS (0.038s)
[50.542488] [006/161] (957s left) fbdev (eof)
[50.701842] Starting subtest: eof
[50.721368] Subtest eof: SUCCESS (0.001s)
[50.817343] [007/161] (957s left) fbdev (info)
[50.938517] Starting subtest: info
[50.958241] Subtest info: SUCCESS (0.000s)
[51.054616] [008/161] (957s left) fbdev (nullptr)
[51.189349] Starting subtest: nullptr
[51.210986] Subtest nullptr: SUCCESS (0.000s)
[51.307265] [009/161] (957s left) fbdev (read)
[51.424068] Starting subtest: read
[51.558704] Subtest read: SUCCESS (0.144s)
[51.656137] [010/161] (956s left) fbdev (write)
[51.789358] Starting subtest: write
[51.841721] Subtest write: SUCCESS (0.069s)
[51.938566] [011/161] (956s left) gem_basic (bad-close)
[52.095135] Starting subtest: bad-close
[52.136518] Subtest bad-close: SUCCESS (0.000s)
[52.228608] [012/161] (956s left) gem_basic (create-close)
[52.386533] Starting subtest: create-close
[52.425827] Subtest create-close: SUCCESS (0.000s)
[52.531441] [013/161] (956s left) gem_basic (create-fd-close)
[52.705835] Starting subtest: create-fd-close
[52.749487] Subtest create-fd-close: SUCCESS (0.000s)
[52.841874] [014/161] (956s left) gem_busy (busy@all-engines)
[52.994559] Starting subtest: busy
[53.013971] Starting dynamic subtest: all-engines
[53.033277] Dynamic subtest all-engines: SUCCESS (0.036s)
[53.054097] Subtest busy: SUCCESS (0.037s)
[53.373275] [015/161] (955s left) gem_close_race (basic-process)
[53.530812] Starting subtest: basic-process
[53.570251] Subtest basic-process: SUCCESS (0.048s)
[53.793922] [016/161] (955s left) gem_close_race (basic-threads)
[53.950431] Starting subtest: basic-threads
[55.153457] Subtest basic-threads: SUCCESS (1.225s)
[55.373674] [017/161] (953s left) gem_ctx_create (basic)
[55.534579] Starting subtest: basic
[55.554054] Subtest basic: SUCCESS (0.000s)
[55.733715] [018/161] (953s left) gem_ctx_create (basic-files)
[55.877304] Starting subtest: basic-files
[57.914210] Subtest basic-files: SUCCESS (2.015s)
[58.341777] [019/161] (950s left) gem_ctx_exec (basic)
[58.513519] Starting subtest: basic
[58.535431] Subtest basic: SUCCESS (0.002s)
[58.638590] [020/161] (950s left) gem_exec_basic (basic)
[58.828460] Starting subtest: basic
[58.850546] Starting dynamic subtest: rcs0-smem
[58.875140] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[58.908574] Starting dynamic subtest: bcs0-smem
[58.932487] Dynamic subtest bcs0-smem: SUCCESS (0.000s)
[58.976315] Starting dynamic subtest: vcs0-smem
[58.997155] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[59.017942] Subtest basic: SUCCESS (0.002s)
[59.142436] [021/161] (950s left) gem_exec_create (basic)
[59.339694] Starting subtest: basic
[59.361897] Starting dynamic subtest: smem
[61.347622] Dynamic subtest smem: SUCCESS (2.054s)
[61.374565] Subtest basic: SUCCESS (2.055s)
[61.560079] [022/161] (948s left) gem_exec_fence (basic-busy)
[61.840551] Starting subtest: basic-busy
[61.865430] Starting dynamic subtest: rcs0
[61.912074] Dynamic subtest rcs0: SUCCESS (0.041s)
[61.947642] Starting dynamic subtest: bcs0
[61.998040] Dynamic subtest bcs0: SUCCESS (0.038s)
[62.028256] Starting dynamic subtest: vcs0
[62.077140] Dynamic subtest vcs0: SUCCESS (0.026s)
[62.101182] Subtest basic-busy: SUCCESS (0.106s)
[62.511584] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[62.535855] [023/161] (947s left) gem_exec_fence (basic-wait)
[62.809553] Starting subtest: basic-wait
[62.829972] Starting dynamic subtest: rcs0
[62.850222] Dynamic subtest rcs0: SUCCESS (0.027s)
[62.876166] Starting dynamic subtest: bcs0
[62.898170] Dynamic subtest bcs0: SUCCESS (0.025s)
[62.924756] Starting dynamic subtest: vcs0
[62.944183] Dynamic subtest vcs0: SUCCESS (0.071s)
[62.966505] Subtest basic-wait: SUCCESS (0.123s)
[63.483723] [024/161] (946s left) gem_exec_fence (basic-await)
[63.756167] Starting subtest: basic-await
[63.775059] Starting dynamic subtest: rcs0
[63.833247] Dynamic subtest rcs0: SUCCESS (0.082s)
[63.862659] Starting dynamic subtest: bcs0
[63.910540] Dynamic subtest bcs0: SUCCESS (0.078s)
[63.939371] Starting dynamic subtest: vcs0
[63.987016] Dynamic subtest vcs0: SUCCESS (0.079s)
[64.005524] Subtest basic-await: SUCCESS (0.240s)
[64.511673] [025/161] (945s left) gem_exec_fence (nb-await)
[64.768633] Starting subtest: nb-await
[64.787848] Starting dynamic subtest: rcs0
[64.806173] Dynamic subtest rcs0: SUCCESS (0.054s)
[64.833695] Starting dynamic subtest: bcs0
[64.874292] Dynamic subtest bcs0: SUCCESS (0.052s)
[64.900677] Starting dynamic subtest: vcs0
[64.918716] Dynamic subtest vcs0: SUCCESS (0.052s)
[64.936834] Subtest nb-await: SUCCESS (0.159s)
[65.406720] [026/161] (944s left) gem_exec_gttfill (basic)
[65.578785] Starting subtest: basic
[69.640831] Subtest basic: SUCCESS (4.102s)
[69.871095] [027/161] (940s left) gem_exec_parallel (engines)
[70.090904] Starting subtest: engines
[70.111958] Starting dynamic subtest: basic
[71.891203] Dynamic subtest basic: SUCCESS (1.816s)
[71.918062] Starting dynamic subtest: contexts
[73.494518] Dynamic subtest contexts: SUCCESS (1.608s)
[73.525664] Starting dynamic subtest: fds
[75.207340] Dynamic subtest fds: SUCCESS (1.712s)
[75.235926] Starting dynamic subtest: userptr
[77.186746] Dynamic subtest userptr: SUCCESS (1.975s)
[77.208597] Subtest engines: SUCCESS (7.113s)
[77.416952] [028/161] (932s left) gem_exec_store (basic)
[77.640212] Starting subtest: basic
[77.662423] Subtest basic: SUCCESS (0.037s)
[77.828573] [029/161] (932s left) gem_flink_basic (bad-flink)
[77.986896] Starting subtest: bad-flink
[78.006313] Subtest bad-flink: SUCCESS (0.000s)
[78.107303] [030/161] (932s left) gem_flink_basic (bad-open)
[78.259509] Starting subtest: bad-open
[78.278879] Subtest bad-open: SUCCESS (0.000s)
[78.392976] [031/161] (932s left) gem_flink_basic (basic)
[78.534140] Starting subtest: basic
[78.556804] Subtest basic: SUCCESS (0.000s)
[78.661336] [032/161] (931s left) gem_flink_basic (double-flink)
[78.813734] Starting subtest: double-flink
[78.834908] Subtest double-flink: SUCCESS (0.000s)
[78.934540] [033/161] (931s left) gem_flink_basic (flink-lifetime)
[79.093524] Starting subtest: flink-lifetime
[79.112624] Subtest flink-lifetime: SUCCESS (0.001s)
[79.212841] [034/161] (931s left) gem_huc_copy (huc-copy)
[79.386815] Subtest huc-copy: SKIP (0.000s)
[79.492535] [035/161] (931s left) gem_linear_blits (basic)
[79.675928] Starting subtest: basic
[79.698539] Subtest basic: SUCCESS (0.008s)
[79.852437] [036/161] (931s left) gem_mmap (basic)
[80.112637] Starting subtest: basic
[80.216198] Subtest basic: SUCCESS (0.000s)
[80.335300] [037/161] (930s left) gem_mmap_gtt (basic)
[80.495490] Starting subtest: basic
[80.515248] Subtest basic: SUCCESS (0.000s)
[80.612112] [038/161] (930s left) gem_render_linear_blits (basic)
[80.767427] Starting subtest: basic
[80.871249] Subtest basic: SUCCESS (0.018s)
[80.971103] [039/161] (930s left) gem_render_tiled_blits (basic)
[81.128415] Starting subtest: basic
[81.250934] Subtest basic: SUCCESS (0.025s)
[81.355119] [040/161] (930s left) gem_ringfill (basic-all)
[81.611128] Starting subtest: basic-all
[82.704093] Subtest basic-all: SUCCESS (1.114s)
[82.853366] [041/161] (928s left) gem_softpin (allocator-basic)
[83.027966] Subtest allocator-basic: SKIP (0.000s)
[83.153700] [042/161] (928s left) gem_softpin (allocator-basic-reserve)
[83.348678] Subtest allocator-basic-reserve: SKIP (0.000s)
[83.465751] [043/161] (928s left) gem_softpin (safe-alignment)
[83.659908] Starting subtest: safe-alignment
[83.681655] Subtest safe-alignment: SUCCESS (0.006s)
[83.786772] [044/161] (927s left) gem_sync (basic-all)
[83.979895] Starting subtest: basic-all
[86.377290] Subtest basic-all: SUCCESS (2.282s)
[86.504894] [045/161] (925s left) gem_sync (basic-each)
[86.718089] Starting subtest: basic-each
[88.729445] Subtest basic-each: SUCCESS (2.078s)
[88.970500] [046/161] (922s left) gem_tiled_blits (basic)
[89.199363] Starting subtest: basic
[89.224770] Subtest basic: SUCCESS (0.013s)
[89.373294] [047/161] (922s left) gem_tiled_fence_blits (basic)
[89.675359] Starting subtest: basic
[89.694928] Subtest basic: SUCCESS (0.007s)
[89.855589] [048/161] (922s left) gem_tiled_pread_basic
[90.287338] [049/161] (921s left) gem_wait (busy@all-engines)
[90.463889] Starting subtest: busy
[90.482128] Starting dynamic subtest: all-engines
[90.956836] Dynamic subtest all-engines: SUCCESS (0.517s)
[90.978378] Subtest busy: SUCCESS (0.517s)
[91.200279] [050/161] (921s left) gem_wait (wait@all-engines)
[91.356728] Starting subtest: wait
[91.375997] Starting dynamic subtest: all-engines
[92.370943] Dynamic subtest all-engines: SUCCESS (1.028s)
[92.391863] Subtest wait: SUCCESS (1.029s)
[92.625291] [051/161] (919s left) i915_getparams_basic (basic-eu-total)
[92.796776] Starting subtest: basic-eu-total
[92.839368] Subtest basic-eu-total: SUCCESS (0.000s)
[92.939763] [052/161] (919s left) i915_getparams_basic (basic-subslice-total)
[93.100073] Starting subtest: basic-subslice-total
[93.139034] Subtest basic-subslice-total: SUCCESS (0.000s)
[93.241403] [053/161] (919s left) i915_hangman (error-state-basic)
[93.380181] Starting subtest: error-state-basic
[93.399752] Subtest error-state-basic: SUCCESS (0.005s)
[93.506220] [054/161] (919s left) i915_pciid
[93.796539] [055/161] (919s left) kms_addfb_basic (addfb25-4-tiled)
[94.044353] Starting subtest: addfb25-4-tiled
[94.063396] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[94.169411] [056/161] (918s left) kms_addfb_basic (addfb25-bad-modifier)
[94.316752] Starting subtest: addfb25-bad-modifier
[94.338944] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[94.441726] [057/161] (918s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[94.597912] Starting subtest: addfb25-framebuffer-vs-set-tiling
[94.619234] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[94.718258] [058/161] (918s left) kms_addfb_basic (addfb25-modifier-no-flag)
[94.892321] Starting subtest: addfb25-modifier-no-flag
[94.911051] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[95.015348] [059/161] (918s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[95.166403] Starting subtest: addfb25-x-tiled-legacy
[95.187033] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[95.302144] [060/161] (918s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[95.452637] Starting subtest: addfb25-x-tiled-mismatch-legacy
[95.474327] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[95.577706] [061/161] (917s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[95.736129] Starting subtest: addfb25-yf-tiled-legacy
[95.756412] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[95.857634] [062/161] (917s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[96.009735] Starting subtest: addfb25-y-tiled-legacy
[96.029164] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[96.129637] [063/161] (917s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[96.275117] Starting subtest: addfb25-y-tiled-small-legacy
[96.317668] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[96.424224] [064/161] (917s left) kms_addfb_basic (bad-pitch-0)
[96.573326] Starting subtest: bad-pitch-0
[96.594486] Subtest bad-pitch-0: SUCCESS (0.000s)
[96.694731] [065/161] (917s left) kms_addfb_basic (bad-pitch-1024)
[96.852850] Starting subtest: bad-pitch-1024
[96.872381] Subtest bad-pitch-1024: SUCCESS (0.000s)
[96.974970] [066/161] (916s left) kms_addfb_basic (bad-pitch-128)
[97.124302] Starting subtest: bad-pitch-128
[97.143556] Subtest bad-pitch-128: SUCCESS (0.000s)
[97.249610] [067/161] (916s left) kms_addfb_basic (bad-pitch-256)
[97.403881] Starting subtest: bad-pitch-256
[97.425894] Subtest bad-pitch-256: SUCCESS (0.000s)
[97.534784] [068/161] (916s left) kms_addfb_basic (bad-pitch-32)
[97.689190] Starting subtest: bad-pitch-32
[97.710336] Subtest bad-pitch-32: SUCCESS (0.000s)
[97.810662] [069/161] (916s left) kms_addfb_basic (bad-pitch-63)
[97.949990] Starting subtest: bad-pitch-63
[97.969436] Subtest bad-pitch-63: SUCCESS (0.000s)
[98.067630] [070/161] (916s left) kms_addfb_basic (bad-pitch-65536)
[98.241777] Starting subtest: bad-pitch-65536
[98.260873] Subtest bad-pitch-65536: SUCCESS (0.000s)
[98.370476] [071/161] (915s left) kms_addfb_basic (bad-pitch-999)
[98.517926] Starting subtest: bad-pitch-999
[98.539237] Subtest bad-pitch-999: SUCCESS (0.000s)
[98.668536] [072/161] (915s left) kms_addfb_basic (basic)
[98.825076] Starting subtest: basic
[98.844460] Subtest basic: SUCCESS (0.000s)
[98.953827] [073/161] (915s left) kms_addfb_basic (basic-x-tiled-legacy)
[99.125700] Starting subtest: basic-x-tiled-legacy
[99.146540] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[99.251621] [074/161] (915s left) kms_addfb_basic (basic-y-tiled-legacy)
[99.405351] Starting subtest: basic-y-tiled-legacy
[99.426285] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[99.529066] [075/161] (915s left) kms_addfb_basic (bo-too-small)
[99.682475] Starting subtest: bo-too-small
[99.703046] Subtest bo-too-small: SUCCESS (0.000s)
[99.801791] [076/161] (915s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[99.974699] Starting subtest: bo-too-small-due-to-tiling
[99.994087] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[100.098295] [077/161] (914s left) kms_addfb_basic (clobberred-modifier)
[100.250728] Starting subtest: clobberred-modifier
[100.273029] Subtest clobberred-modifier: SUCCESS (0.000s)
[100.380202] [078/161] (914s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[100.535222] Starting subtest: framebuffer-vs-set-tiling
[100.557107] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[100.656102] [079/161] (914s left) kms_addfb_basic (invalid-get-prop)
[100.811524] Starting subtest: invalid-get-prop
[100.831166] Subtest invalid-get-prop: SUCCESS (0.000s)
[100.931963] [080/161] (914s left) kms_addfb_basic (invalid-get-prop-any)
[101.109899] Starting subtest: invalid-get-prop-any
[101.130690] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[101.236641] [081/161] (914s left) kms_addfb_basic (invalid-set-prop)
[101.390385] Starting subtest: invalid-set-prop
[101.412043] Subtest invalid-set-prop: SUCCESS (0.000s)
[101.512879] [082/161] (913s left) kms_addfb_basic (invalid-set-prop-any)
[101.666044] Starting subtest: invalid-set-prop-any
[101.685898] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[101.783268] [083/161] (913s left) kms_addfb_basic (no-handle)
[101.956890] Starting subtest: no-handle
[101.982950] Subtest no-handle: SUCCESS (0.000s)
[102.089364] [084/161] (913s left) kms_addfb_basic (size-max)
[102.255880] Starting subtest: size-max
[102.277356] Subtest size-max: SUCCESS (0.000s)
[102.379623] [085/161] (913s left) kms_addfb_basic (small-bo)
[102.535949] Starting subtest: small-bo
[102.557056] Subtest small-bo: SUCCESS (0.000s)
[102.654768] [086/161] (913s left) kms_addfb_basic (tile-pitch-mismatch)
[102.810284] Starting subtest: tile-pitch-mismatch
[102.829796] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[102.931786] [087/161] (912s left) kms_addfb_basic (too-high)
[103.080937] Starting subtest: too-high
[103.101537] Subtest too-high: SUCCESS (0.001s)
[103.238789] [088/161] (912s left) kms_addfb_basic (too-wide)
[103.389244] Starting subtest: too-wide
[103.410385] Subtest too-wide: SUCCESS (0.000s)
[103.515305] [089/161] (912s left) kms_addfb_basic (unused-handle)
[103.688269] Starting subtest: unused-handle
[103.707192] Subtest unused-handle: SUCCESS (0.000s)
[103.813391] [090/161] (912s left) kms_addfb_basic (unused-modifier)
[103.964227] Starting subtest: unused-modifier
[103.984835] Subtest unused-modifier: SUCCESS (0.000s)
[104.091969] [091/161] (912s left) kms_addfb_basic (unused-offsets)
[104.245268] Starting subtest: unused-offsets
[104.266729] Subtest unused-offsets: SUCCESS (0.000s)
[104.368823] [092/161] (911s left) kms_addfb_basic (unused-pitches)
[104.515971] Starting subtest: unused-pitches
[104.555210] Subtest unused-pitches: SUCCESS (0.000s)
[104.668752] [093/161] (911s left) kms_busy (basic)
[104.842576] Starting subtest: basic
[104.866872] Starting dynamic subtest: flip
[105.413115] Dynamic subtest flip: SUCCESS (0.579s)
[105.442966] Starting dynamic subtest: modeset
[105.978667] Dynamic subtest modeset: SUCCESS (0.568s)
[106.000125] Subtest basic: SUCCESS (1.148s)
[106.174374] [094/161] (910s left) kms_prop_blob (basic)
[106.337084] Starting subtest: basic
[106.356561] Subtest basic: SUCCESS (0.000s)
[106.457090] [095/161] (910s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[106.613496] Starting subtest: basic-busy-flip-before-cursor-atomic
[107.545666] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.938s)
[107.745160] [096/161] (908s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[107.896913] Starting subtest: basic-busy-flip-before-cursor-legacy
[108.805869] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.920s)
[109.001367] [097/161] (907s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[109.156180] Starting subtest: basic-flip-after-cursor-atomic
[110.061727] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.913s)
[110.244616] [098/161] (906s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[110.396328] Starting subtest: basic-flip-after-cursor-legacy
[111.309320] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.924s)
[111.518907] [099/161] (905s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[111.671732] Starting subtest: basic-flip-after-cursor-varying-size
[112.570661] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.913s)
[112.775293] [100/161] (904s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[112.929282] Starting subtest: basic-flip-before-cursor-atomic
[113.840803] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.928s)
[114.048936] [101/161] (903s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[114.205641] Starting subtest: basic-flip-before-cursor-legacy
[115.113645] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.922s)
[115.313638] [102/161] (901s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[115.466150] Starting subtest: basic-flip-before-cursor-varying-size
[116.377161] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.927s)
[116.584177] [103/161] (900s left) kms_dsc (dsc-basic)
[116.762045] Subtest dsc-basic: SKIP (0.000s)
[116.865308] [104/161] (900s left) kms_flip (basic-flip-vs-dpms)
[117.049394] Starting subtest: basic-flip-vs-dpms
[117.069140] Starting dynamic subtest: A-VGA1
[117.864440] Dynamic subtest A-VGA1: SUCCESS (0.849s)
[117.902517] Starting dynamic subtest: B-VGA1
[118.679712] Dynamic subtest B-VGA1: SUCCESS (0.813s)
[118.716427] Starting dynamic subtest: C-VGA1
[119.612908] Dynamic subtest C-VGA1: SUCCESS (0.926s)
[119.631437] Subtest basic-flip-vs-dpms: SUCCESS (2.592s)
[119.813835] [105/161] (897s left) kms_flip (basic-flip-vs-modeset)
[119.988824] Starting subtest: basic-flip-vs-modeset
[120.008969] Starting dynamic subtest: A-VGA1
[120.848135] Dynamic subtest A-VGA1: SUCCESS (0.882s)
[120.879406] Starting dynamic subtest: B-VGA1
[121.681512] Dynamic subtest B-VGA1: SUCCESS (0.834s)
[121.716111] Starting dynamic subtest: C-VGA1
[122.525108] Dynamic subtest C-VGA1: SUCCESS (0.844s)
[122.546120] Subtest basic-flip-vs-modeset: SUCCESS (2.564s)
[122.718328] [106/161] (894s left) kms_flip (basic-flip-vs-wf_vblank)
[122.919774] Starting subtest: basic-flip-vs-wf_vblank
[122.938205] Starting dynamic subtest: A-VGA1
[124.030675] Dynamic subtest A-VGA1: SUCCESS (1.151s)
[124.070644] Starting dynamic subtest: B-VGA1
[125.183154] Dynamic subtest B-VGA1: SUCCESS (1.145s)
[125.217769] Starting dynamic subtest: C-VGA1
[126.315673] Dynamic subtest C-VGA1: SUCCESS (1.133s)
[126.335872] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.434s)
[126.563769] [107/161] (891s left) kms_flip (basic-plain-flip)
[126.755638] Starting subtest: basic-plain-flip
[126.775047] Starting dynamic subtest: A-VGA1
[127.574035] Dynamic subtest A-VGA1: SUCCESS (0.862s)
[127.611539] Starting dynamic subtest: B-VGA1
[128.417248] Dynamic subtest B-VGA1: SUCCESS (0.845s)
[128.446414] Starting dynamic subtest: C-VGA1
[129.261687] Dynamic subtest C-VGA1: SUCCESS (0.846s)
[129.282780] Subtest basic-plain-flip: SUCCESS (2.557s)
[129.483843] [108/161] (888s left) kms_force_connector_basic (force-connector-state)
[129.673948] Starting subtest: force-connector-state
[129.794197] Subtest force-connector-state: SUCCESS (0.158s)
[130.029481] [109/161] (887s left) kms_force_connector_basic (force-edid)
[130.231241] Starting subtest: force-edid
[130.260139] Subtest force-edid: SUCCESS (0.022s)
[130.377556] [110/161] (887s left) kms_force_connector_basic (force-load-detect)
[130.555101] Starting subtest: force-load-detect
[130.719514] Subtest force-load-detect: SUCCESS (0.171s)
[130.946209] [111/161] (887s left) kms_force_connector_basic (prune-stale-modes)
[131.146765] Starting subtest: prune-stale-modes
[131.171842] Subtest prune-stale-modes: SUCCESS (0.017s)
[131.291779] [112/161] (886s left) kms_frontbuffer_tracking (basic)
[131.518443] Starting subtest: basic
[133.358194] Subtest basic: SUCCESS (1.923s)
[133.508094] [113/161] (884s left) kms_hdmi_inject (inject-audio)
[133.681581] Starting subtest: inject-audio
[133.723037] Subtest inject-audio: SKIP (0.000s)
[133.846275] [114/161] (884s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[134.025467] Starting subtest: compare-crc-sanitycheck-xr24
[134.044637] Starting dynamic subtest: pipe-A-VGA-1
[134.290104] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.283s)
[134.320285] Starting dynamic subtest: pipe-B-VGA-1
[134.566247] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.271s)
[134.608476] Starting dynamic subtest: pipe-C-VGA-1
[134.850779] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.279s)
[134.870993] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.837s)
[135.035787] [115/161] (883s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[135.206216] Starting subtest: compare-crc-sanitycheck-nv12
[135.226979] Starting dynamic subtest: pipe-A-VGA-1
[135.270640] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[135.310922] Starting dynamic subtest: pipe-B-VGA-1
[135.351193] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[135.378800] Starting dynamic subtest: pipe-C-VGA-1
[135.418924] Dynamic subtest pipe-C-VGA-1: SKIP (0.000s)
[135.462669] Subtest compare-crc-sanitycheck-nv12: SKIP (0.004s)
[135.569109] [116/161] (883s left) kms_pipe_crc_basic (hang-read-crc)
[135.750553] Starting subtest: hang-read-crc
[135.770076] Starting dynamic subtest: pipe-A-VGA-1
[136.540473] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.814s)
[136.570864] Starting dynamic subtest: pipe-B-VGA-1
[137.300551] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.769s)
[137.329942] Starting dynamic subtest: pipe-C-VGA-1
[138.080251] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.774s)
[138.098273] Subtest hang-read-crc: SUCCESS (2.361s)
[138.262845] [117/161] (880s left) kms_pipe_crc_basic (nonblocking-crc)
[138.418678] Starting subtest: nonblocking-crc
[138.439978] Starting dynamic subtest: pipe-A-VGA-1
[138.879992] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.466s)
[138.907509] Starting dynamic subtest: pipe-B-VGA-1
[139.334932] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.451s)
[139.367637] Starting dynamic subtest: pipe-C-VGA-1
[139.784366] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.451s)
[139.802745] Subtest nonblocking-crc: SUCCESS (1.373s)
[139.965292] [118/161] (878s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[140.139511] Starting subtest: nonblocking-crc-frame-sequence
[140.159057] Starting dynamic subtest: pipe-A-VGA-1
[140.584413] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.476s)
[140.612114] Starting dynamic subtest: pipe-B-VGA-1
[141.042598] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.451s)
[141.072250] Starting dynamic subtest: pipe-C-VGA-1
[141.494148] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.449s)
[141.513195] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.380s)
[141.667882] [119/161] (877s left) kms_pipe_crc_basic (read-crc)
[141.843522] Starting subtest: read-crc
[141.864790] Starting dynamic subtest: pipe-A-VGA-1
[142.220671] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.399s)
[142.248284] Starting dynamic subtest: pipe-B-VGA-1
[142.602915] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.384s)
[142.633613] Starting dynamic subtest: pipe-C-VGA-1
[142.992754] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.385s)
[143.011923] Subtest read-crc: SUCCESS (1.172s)
[143.172312] [120/161] (875s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[143.344055] Starting subtest: read-crc-frame-sequence
[143.365199] Starting dynamic subtest: pipe-A-VGA-1
[143.724109] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.401s)
[143.752052] Starting dynamic subtest: pipe-B-VGA-1
[144.105504] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.384s)
[144.138659] Starting dynamic subtest: pipe-C-VGA-1
[144.499051] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.385s)
[144.518046] Subtest read-crc-frame-sequence: SUCCESS (1.174s)
[144.671846] [121/161] (874s left) kms_pm_backlight (basic-brightness)
[144.851775] Subtest basic-brightness: SKIP (0.000s)
[144.962829] [122/161] (874s left) kms_pm_rpm (basic-pci-d3-state)
[156.441219] Subtest basic-pci-d3-state: SKIP (0.000s)
[156.542795] [123/161] (862s left) kms_pm_rpm (basic-rte)
[156.696398] Starting subtest: basic-rte
[168.019022] Subtest basic-rte: SKIP (11.122s)
[168.173899] [124/161] (851s left) kms_psr (psr-primary-page-flip)
[168.350325] Subtest psr-primary-page-flip: SKIP (0.000s)
[168.455923] [125/161] (851s left) kms_psr (psr-cursor-plane-move)
[168.678164] Subtest psr-cursor-plane-move: SKIP (0.000s)
[168.792347] [126/161] (850s left) kms_psr (psr-sprite-plane-onoff)
[169.022739] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[169.146286] [127/161] (850s left) kms_psr (psr-primary-mmap-gtt)
[169.317254] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[169.447483] [128/161] (850s left) kms_setmode (basic-clone-single-crtc)
[169.605376] Starting subtest: basic-clone-single-crtc
[169.666737] Subtest basic-clone-single-crtc: SKIP (0.000s)
[169.767753] [129/161] (850s left) i915_pm_rps (basic-api)
[169.929405] Starting subtest: basic-api
[169.947972] Subtest basic-api: SUCCESS (0.003s)
[170.079381] [130/161] (850s left) prime_self_import (basic-llseek-bad)
[170.237671] Starting subtest: basic-llseek-bad
[170.280449] Subtest basic-llseek-bad: SUCCESS (0.002s)
[170.382861] [131/161] (849s left) prime_self_import (basic-llseek-size)
[170.540721] Starting subtest: basic-llseek-size
[170.579123] Subtest basic-llseek-size: SUCCESS (0.003s)
[170.686571] [132/161] (849s left) prime_self_import (basic-with_fd_dup)
[170.816678] Starting subtest: basic-with_fd_dup
[170.858215] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[170.968117] [133/161] (849s left) prime_self_import (basic-with_one_bo)
[171.123464] Starting subtest: basic-with_one_bo
[171.166722] Subtest basic-with_one_bo: SUCCESS (0.002s)
[171.269829] [134/161] (849s left) prime_self_import (basic-with_one_bo_two_files)
[171.425550] Starting subtest: basic-with_one_bo_two_files
[171.464123] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s)
[171.574906] [135/161] (849s left) prime_self_import (basic-with_two_bos)
[171.703742] Starting subtest: basic-with_two_bos
[171.745758] Subtest basic-with_two_bos: SUCCESS (0.002s)
[171.851392] [136/161] (848s left) prime_vgem (basic-fence-flip)
[172.024269] Starting subtest: basic-fence-flip
[172.331417] Subtest basic-fence-flip: SUCCESS (0.343s)
[172.507732] [137/161] (848s left) prime_vgem (basic-fence-mmap)
[172.702405] Starting subtest: basic-fence-mmap
[172.721488] Subtest basic-fence-mmap: SUCCESS (0.031s)
[172.847756] [138/161] (848s left) prime_vgem (basic-fence-read)
[173.040907] Starting subtest: basic-fence-read
[173.063117] Subtest basic-fence-read: SUCCESS (0.042s)
[173.188387] [139/161] (847s left) prime_vgem (basic-gtt)
[173.387456] Starting subtest: basic-gtt
[173.408042] Subtest basic-gtt: SUCCESS (0.013s)
[173.534722] [140/161] (847s left) prime_vgem (basic-read)
[173.687618] Starting subtest: basic-read
[173.709818] Subtest basic-read: SUCCESS (0.016s)
[173.839142] [141/161] (847s left) prime_vgem (basic-write)
[174.033882] Starting subtest: basic-write
[174.052763] Subtest basic-write: SUCCESS (0.018s)
[174.183222] [142/161] (847s left) vgem_basic (setversion)
[174.363096] Starting subtest: setversion
[174.405379] Subtest setversion: SUCCESS (0.000s)
[174.506535] [143/161] (847s left) vgem_basic (create)
[174.662076] Starting subtest: create
[174.681747] Subtest create: SUCCESS (0.000s)
[174.783936] [144/161] (846s left) vgem_basic (debugfs)
[174.919381] Starting subtest: debugfs
[174.938890] Subtest debugfs: SUCCESS (0.000s)
[175.047423] [145/161] (846s left) vgem_basic (dmabuf-export)
[175.202113] Starting subtest: dmabuf-export
[175.247175] Subtest dmabuf-export: SUCCESS (0.002s)
[175.353279] [146/161] (846s left) vgem_basic (dmabuf-fence)
[175.525708] Starting subtest: dmabuf-fence
[175.545303] Subtest dmabuf-fence: SUCCESS (0.000s)
[175.644795] [147/161] (846s left) vgem_basic (dmabuf-fence-before)
[175.808386] Starting subtest: dmabuf-fence-before
[175.827766] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[175.934536] [148/161] (846s left) vgem_basic (dmabuf-mmap)
[176.104849] Starting subtest: dmabuf-mmap
[176.126965] Subtest dmabuf-mmap: SUCCESS (0.014s)
[176.232520] [149/161] (845s left) vgem_basic (mmap)
[176.388337] Starting subtest: mmap
[176.409521] Subtest mmap: SUCCESS (0.010s)
[176.509302] [150/161] (845s left) vgem_basic (second-client)
[176.668179] Starting subtest: second-client
[176.706617] Subtest second-client: SUCCESS (0.033s)
[176.817312] [151/161] (845s left) vgem_basic (sysfs)
[176.989169] Starting subtest: sysfs
[177.011248] Subtest sysfs: SUCCESS (0.001s)
[177.117035] [152/161] (845s left) core_hotunplug (unbind-rebind)
[177.359382] Starting subtest: unbind-rebind
[180.725210] Subtest unbind-rebind: SUCCESS (3.371s)
[180.827931] [153/161] (841s left) vgem_basic (unload)
[180.967450] Starting subtest: unload
[210.204453] Subtest unload: SUCCESS (29.081s)
[210.307875] [154/161] (812s left) i915_module_load (reload)
[210.457884] Starting subtest: reload
[213.100790] Subtest reload: SUCCESS (2.646s)
[213.223784] [155/161] (809s left) gem_lmem_swapping (basic)
[213.426056] Subtest basic: SKIP (0.000s)
[213.536701] [156/161] (809s left) gem_lmem_swapping (parallel-random-engines)
[213.714387] Subtest parallel-random-engines: SKIP (0.000s)
[213.818578] [157/161] (809s left) gem_lmem_swapping (random-engines)
[214.001533] Subtest random-engines: SKIP (0.000s)
[214.101532] [158/161] (808s left) gem_lmem_swapping (verify-random)
[214.301146] Subtest verify-random: SKIP (0.000s)
[214.407129] [159/161] (808s left) i915_pm_rpm (module-reload)
[225.717526] Subtest module-reload: SKIP (0.000s)
[225.834193] [160/161] (797s left) i915_selftest (live)
[227.066793] Starting subtest: live
[227.086408] Starting dynamic subtest: sanitycheck
[228.700470] Dynamic subtest sanitycheck: SUCCESS (1.633s)
[228.730701] Starting dynamic subtest: uncore
[230.342826] Dynamic subtest uncore: SUCCESS (1.638s)
[230.372670] Starting dynamic subtest: workarounds
[232.051269] Dynamic subtest workarounds: SUCCESS (1.705s)
[232.082493] Starting dynamic subtest: gt_engines
[233.674505] Dynamic subtest gt_engines: SUCCESS (1.621s)
[233.704792] Starting dynamic subtest: gt_timelines
[239.865473] Dynamic subtest gt_timelines: SUCCESS (6.189s)
[239.899184] Starting dynamic subtest: gt_contexts
[241.546286] Dynamic subtest gt_contexts: SUCCESS (1.680s)
[241.577472] Starting dynamic subtest: gt_lrc
[243.191462] Dynamic subtest gt_lrc: SUCCESS (1.647s)
[243.221022] Starting dynamic subtest: gt_mocs
[244.815348] Dynamic subtest gt_mocs: SUCCESS (1.620s)
[244.843729] Starting dynamic subtest: gt_pm
[256.005032] Dynamic subtest gt_pm: SUCCESS (11.187s)
[256.033427] Starting dynamic subtest: gt_heartbeat
[257.671368] Dynamic subtest gt_heartbeat: SUCCESS (1.664s)
[257.701278] Starting dynamic subtest: gt_tlb
[259.338331] Dynamic subtest gt_tlb: SUCCESS (1.664s)
[259.368370] Starting dynamic subtest: requests
[265.621817] Dynamic subtest requests: SUCCESS (6.284s)
[265.649785] Starting dynamic subtest: migrate
[267.263254] Dynamic subtest migrate: SUCCESS (1.638s)
[267.294089] Starting dynamic subtest: active
[268.886592] Dynamic subtest active: SUCCESS (1.619s)
[268.919446] Starting dynamic subtest: objects
[270.983188] Dynamic subtest objects: SUCCESS (2.096s)
[271.013134] Starting dynamic subtest: mman
[276.383289] Dynamic subtest mman: SUCCESS (5.400s)
[276.416596] Starting dynamic subtest: dmabuf
[279.037206] Dynamic subtest dmabuf: SUCCESS (2.651s)
[279.071778] Starting dynamic subtest: vma
[280.654685] Dynamic subtest vma: SUCCESS (1.615s)
[280.682335] Starting dynamic subtest: coherency
[284.291082] Dynamic subtest coherency: SUCCESS (3.632s)
[284.320679] Starting dynamic subtest: gtt
[288.731221] Dynamic subtest gtt: SUCCESS (4.440s)
[288.761274] Starting dynamic subtest: gem
[290.368597] Dynamic subtest gem: SUCCESS (1.635s)
[290.398864] Starting dynamic subtest: evict
[291.962768] Dynamic subtest evict: SUCCESS (1.591s)
[291.991399] Starting dynamic subtest: hugepages
[294.807288] Dynamic subtest hugepages: SUCCESS (2.840s)
[294.837330] Starting dynamic subtest: gem_contexts
[300.135224] Dynamic subtest gem_contexts: SUCCESS (5.328s)
[300.162580] Starting dynamic subtest: client
[301.719361] Dynamic subtest client: SUCCESS (1.581s)
[301.747680] Starting dynamic subtest: gem_migrate
[303.402107] Dynamic subtest gem_migrate: SUCCESS (1.680s)
[303.430146] Starting dynamic subtest: reset
[305.002093] Dynamic subtest reset: SUCCESS (1.598s)
[305.029574] Starting dynamic subtest: memory_region
[306.613142] Dynamic subtest memory_region: SUCCESS (1.608s)
[306.643498] Starting dynamic subtest: hangcheck
[321.005935] Dynamic subtest hangcheck: SUCCESS (14.391s)
[321.038187] Starting dynamic subtest: execlists
[322.626091] Dynamic subtest execlists: SUCCESS (1.620s)
[322.655000] Starting dynamic subtest: ring_submission
[324.300217] Dynamic subtest ring_submission: SUCCESS (1.670s)
[324.328720] Starting dynamic subtest: perf
[325.854963] Dynamic subtest perf: SUCCESS (1.550s)
[325.885441] Starting dynamic subtest: slpc
[327.490318] Dynamic subtest slpc: SUCCESS (1.634s)
[327.526981] Starting dynamic subtest: guc
[329.120958] Dynamic subtest guc: SUCCESS (1.626s)
[329.148295] Starting dynamic subtest: guc_multi_lrc
[330.719609] Dynamic subtest guc_multi_lrc: SUCCESS (1.595s)
[330.747656] Starting dynamic subtest: guc_hang
[332.328089] Dynamic subtest guc_hang: SUCCESS (1.605s)
[332.358218] Starting dynamic subtest: late_gt_pm
[333.860103] Dynamic subtest late_gt_pm: SUCCESS (1.533s)
[333.879549] Subtest live: SUCCESS (106.798s)
[333.957544] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[333.958780] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[333.981604] [161/161] (689s left) dmabuf (all-tests)
[334.147611] Starting subtest: all-tests
[334.169651] Starting dynamic subtest: sanitycheck
[334.243022] Dynamic subtest sanitycheck: SUCCESS (0.096s)
[334.268405] Starting dynamic subtest: dma_fence
[334.485257] Dynamic subtest dma_fence: SUCCESS (0.239s)
[334.516471] Starting dynamic subtest: dma_fence_chain
[344.750720] Dynamic subtest dma_fence_chain: SUCCESS (10.262s)
[344.790615] Starting dynamic subtest: dma_fence_unwrap
[344.843525] Dynamic subtest dma_fence_unwrap: SUCCESS (0.093s)
[344.877436] Starting dynamic subtest: dma_resv
[344.953769] Dynamic subtest dma_resv: SUCCESS (0.107s)
[344.975755] Subtest all-tests: SUCCESS (10.809s)
[345.051863] Closing watchdogs
Done.