[78.080872] Initializing watchdogs [78.081486] /dev/watchdog0 [78.145234] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:22b1 Intel Cherryview (Gen8) Atom/Celeron/Pentium Processor x5-E8000/J3xxx/N3xxx Integrated Graphics Controller [78.169353] [001/162] (960s left) i915_module_load (load) [79.031624] Starting subtest: load [84.279355] Subtest load: SUCCESS (5.261s) [84.664885] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [84.687731] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [84.711248] [002/162] (954s left) core_auth (basic-auth) [85.194583] Starting subtest: basic-auth [85.203420] Subtest basic-auth: SUCCESS (0.011s) [85.653267] [003/162] (953s left) debugfs_test (read_all_entries) [86.001443] Starting subtest: read_all_entries [86.271216] Subtest read_all_entries: SUCCESS (0.278s) [87.122394] [004/162] (952s left) debugfs_test (basic-hwmon) [87.409552] Starting subtest: basic-hwmon [87.413554] Subtest basic-hwmon: SKIP (0.000s) [88.075992] [005/162] (951s left) debugfs_test (sysfs) [88.359845] Starting subtest: sysfs [88.819718] Subtest sysfs: SUCCESS (0.459s) [89.543714] [006/162] (950s left) fbdev (eof) [89.820057] Starting subtest: eof [89.822882] Subtest eof: SUCCESS (0.002s) [90.026103] [007/162] (950s left) fbdev (info) [90.278175] Starting subtest: info [90.279816] Subtest info: SUCCESS (0.000s) [90.515777] [008/162] (949s left) fbdev (nullptr) [90.776727] Starting subtest: nullptr [90.778805] Subtest nullptr: SUCCESS (0.000s) [90.996467] [009/162] (949s left) fbdev (read) [91.260398] Starting subtest: read [93.348544] Subtest read: SUCCESS (2.079s) [93.540625] [010/162] (947s left) fbdev (write) [93.807120] Starting subtest: write [94.909508] Subtest write: SUCCESS (1.101s) [95.120960] [011/162] (945s left) gem_basic (bad-close) [95.374480] Starting subtest: bad-close [95.380152] Subtest bad-close: SUCCESS (0.000s) [95.596553] [012/162] (945s left) gem_basic (create-close) [95.853670] Starting subtest: create-close [95.858175] Subtest create-close: SUCCESS (0.001s) [96.084643] [013/162] (944s left) gem_basic (create-fd-close) [96.343537] Starting subtest: create-fd-close [96.346613] Subtest create-fd-close: SUCCESS (0.001s) [96.560705] [014/162] (944s left) gem_busy (busy@all-engines) [96.882305] Starting subtest: busy [96.883873] Starting dynamic subtest: all-engines [97.096855] Dynamic subtest all-engines: SUCCESS (0.220s) [97.098565] Subtest busy: SUCCESS (0.221s) [97.648494] [015/162] (943s left) gem_close_race (basic-process) [97.986560] Starting subtest: basic-process [98.164658] Subtest basic-process: SUCCESS (0.182s) [98.520842] [016/162] (943s left) gem_close_race (basic-threads) [98.852090] Starting subtest: basic-threads [100.073414] Subtest basic-threads: SUCCESS (1.222s) [100.418615] [017/162] (941s left) gem_ctx_create (basic) [100.742237] Starting subtest: basic [100.745187] Subtest basic: SUCCESS (0.001s) [101.103502] [018/162] (940s left) gem_ctx_create (basic-files) [101.460112] Starting subtest: basic-files [103.548551] Subtest basic-files: SUCCESS (2.093s) [103.899890] [019/162] (938s left) gem_ctx_exec (basic) [104.160631] Starting subtest: basic [104.182667] Subtest basic: SUCCESS (0.019s) [104.406356] [020/162] (937s left) gem_exec_basic (basic) [104.705160] Starting subtest: basic [104.706954] Starting dynamic subtest: rcs0-smem [104.712118] Dynamic subtest rcs0-smem: SUCCESS (0.005s) [104.747491] Starting dynamic subtest: bcs0-smem [104.749285] Dynamic subtest bcs0-smem: SUCCESS (0.003s) [104.787187] Starting dynamic subtest: vcs0-smem [104.791111] Dynamic subtest vcs0-smem: SUCCESS (0.003s) [104.815538] Starting dynamic subtest: vecs0-smem [104.819985] Dynamic subtest vecs0-smem: SUCCESS (0.023s) [104.822489] Subtest basic: SUCCESS (0.040s) [105.095731] [021/162] (937s left) gem_exec_create (basic) [105.453160] Starting subtest: basic [105.457150] Starting dynamic subtest: smem [107.630386] Dynamic subtest smem: SUCCESS (2.170s) [107.632171] Subtest basic: SUCCESS (2.174s) [108.053760] [022/162] (934s left) gem_exec_fence (basic-busy) [108.562224] Starting subtest: basic-busy [108.565603] Starting dynamic subtest: rcs0 [108.599938] Dynamic subtest rcs0: SUCCESS (0.034s) [108.624885] Starting dynamic subtest: bcs0 [108.637906] Dynamic subtest bcs0: SUCCESS (0.041s) [108.673630] Starting dynamic subtest: vcs0 [108.685353] Dynamic subtest vcs0: SUCCESS (0.046s) [108.709130] Starting dynamic subtest: vecs0 [108.718901] Dynamic subtest vecs0: SUCCESS (0.031s) [108.724355] Subtest basic-busy: SUCCESS (0.160s) [109.676990] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [109.687231] [023/162] (933s left) gem_exec_fence (basic-wait) [110.192345] Starting subtest: basic-wait [110.195148] Starting dynamic subtest: rcs0 [110.229668] Dynamic subtest rcs0: SUCCESS (0.037s) [110.258049] Starting dynamic subtest: bcs0 [110.278181] Dynamic subtest bcs0: SUCCESS (0.047s) [110.302947] Starting dynamic subtest: vcs0 [110.316540] Dynamic subtest vcs0: SUCCESS (0.038s) [110.347802] Starting dynamic subtest: vecs0 [110.358134] Dynamic subtest vecs0: SUCCESS (0.040s) [110.360891] Subtest basic-wait: SUCCESS (0.171s) [111.180996] [024/162] (931s left) gem_exec_fence (basic-await) [111.676648] Starting subtest: basic-await [111.679301] Starting dynamic subtest: rcs0 [111.910449] Dynamic subtest rcs0: SUCCESS (0.228s) [111.938424] Starting dynamic subtest: bcs0 [112.139964] Dynamic subtest bcs0: SUCCESS (0.219s) [112.167463] Starting dynamic subtest: vcs0 [112.357567] Dynamic subtest vcs0: SUCCESS (0.222s) [112.382457] Starting dynamic subtest: vecs0 [112.589722] Dynamic subtest vecs0: SUCCESS (0.232s) [112.591458] Subtest basic-await: SUCCESS (0.909s) [113.525891] [025/162] (929s left) gem_exec_fence (nb-await) [114.006378] Starting subtest: nb-await [114.008961] Starting dynamic subtest: rcs0 [114.097860] Dynamic subtest rcs0: SUCCESS (0.088s) [114.124534] Starting dynamic subtest: bcs0 [114.195509] Dynamic subtest bcs0: SUCCESS (0.093s) [114.222646] Starting dynamic subtest: vcs0 [114.294796] Dynamic subtest vcs0: SUCCESS (0.096s) [114.320399] Starting dynamic subtest: vecs0 [114.391062] Dynamic subtest vecs0: SUCCESS (0.094s) [114.395245] Subtest nb-await: SUCCESS (0.383s) [115.282152] [026/162] (928s left) gem_exec_gttfill (basic) [115.595946] Starting subtest: basic [117.625171] Subtest basic: SKIP (1.986s) [118.016564] [027/162] (925s left) gem_exec_parallel (engines) [118.517829] Starting subtest: engines [118.522323] Starting dynamic subtest: basic [124.871251] Dynamic subtest basic: SUCCESS (6.350s) [124.898979] Starting dynamic subtest: contexts [135.167636] Dynamic subtest contexts: SUCCESS (10.295s) [135.197283] Starting dynamic subtest: fds [145.967139] Dynamic subtest fds: SUCCESS (10.789s) [145.997288] Starting dynamic subtest: userptr [152.255667] Dynamic subtest userptr: SUCCESS (6.292s) [152.257494] Subtest engines: SUCCESS (33.741s) [152.672240] [028/162] (891s left) gem_exec_store (basic) [152.996569] Starting subtest: basic [153.072254] Subtest basic: SUCCESS (0.078s) [153.430881] [029/162] (890s left) gem_flink_basic (bad-flink) [153.695154] Starting subtest: bad-flink [153.698175] Subtest bad-flink: SUCCESS (0.000s) [153.945264] [030/162] (890s left) gem_flink_basic (bad-open) [154.201689] Starting subtest: bad-open [154.204528] Subtest bad-open: SUCCESS (0.000s) [154.441239] [031/162] (890s left) gem_flink_basic (basic) [154.689258] Starting subtest: basic [154.692682] Subtest basic: SUCCESS (0.000s) [154.944692] [032/162] (889s left) gem_flink_basic (double-flink) [155.197144] Starting subtest: double-flink [155.199529] Subtest double-flink: SUCCESS (0.000s) [155.445778] [033/162] (889s left) gem_flink_basic (flink-lifetime) [155.699976] Starting subtest: flink-lifetime [155.705104] Subtest flink-lifetime: SUCCESS (0.007s) [155.951947] [034/162] (889s left) gem_huc_copy (huc-copy) [156.222082] Subtest huc-copy: SKIP (0.000s) [156.427411] [035/162] (888s left) gem_linear_blits (basic) [157.160561] Subtest basic: SKIP (0.000s) [157.408786] [036/162] (888s left) gem_mmap (basic) [157.848696] Starting subtest: basic [157.862544] Subtest basic: SUCCESS (0.001s) [158.103707] [037/162] (887s left) gem_mmap_gtt (basic) [158.371518] Starting subtest: basic [158.374705] Subtest basic: SUCCESS (0.005s) [158.606692] [038/162] (887s left) gem_render_linear_blits (basic) [158.889440] Starting subtest: basic [159.017191] Subtest basic: SUCCESS (0.119s) [159.226543] [039/162] (886s left) gem_render_tiled_blits (basic) [159.495730] Starting subtest: basic [159.663758] Subtest basic: SUCCESS (0.154s) [159.874071] [040/162] (886s left) gem_ringfill (basic-all) [160.453684] Starting subtest: basic-all [161.895506] Subtest basic-all: SUCCESS (1.442s) [162.162808] [041/162] (884s left) gem_softpin (allocator-basic) [162.446529] Starting subtest: allocator-basic [162.799307] Subtest allocator-basic: SUCCESS (0.353s) [163.123654] [042/162] (883s left) gem_softpin (allocator-basic-reserve) [163.389389] Starting subtest: allocator-basic-reserve [163.756762] Subtest allocator-basic-reserve: SUCCESS (0.362s) [164.040650] [043/162] (882s left) gem_softpin (safe-alignment) [164.320127] Starting subtest: safe-alignment [164.382864] Subtest safe-alignment: SUCCESS (0.058s) [164.623704] [044/162] (882s left) gem_sync (basic-all) [164.935113] Starting subtest: basic-all [168.069294] Subtest basic-all: SUCCESS (3.135s) [168.464938] [045/162] (878s left) gem_sync (basic-each) [168.783979] Starting subtest: basic-each [171.998768] Subtest basic-each: SUCCESS (3.216s) [172.388624] [046/162] (874s left) gem_tiled_blits (basic) [172.937870] Subtest basic: SKIP (0.000s) [173.150410] [047/162] (874s left) gem_tiled_fence_blits (basic) [173.835302] Starting subtest: basic [173.896972] Subtest basic: SUCCESS (0.063s) [174.190898] [048/162] (873s left) gem_tiled_pread_basic [175.643712] [049/162] (872s left) gem_wait (busy@all-engines) [175.999461] Starting subtest: busy [176.002089] Starting dynamic subtest: all-engines [176.540742] Dynamic subtest all-engines: SUCCESS (0.539s) [176.545665] Subtest busy: SUCCESS (0.542s) [177.027710] [050/162] (871s left) gem_wait (wait@all-engines) [177.378171] Starting subtest: wait [177.379788] Starting dynamic subtest: all-engines [178.416409] Dynamic subtest all-engines: SUCCESS (1.043s) [178.417986] Subtest wait: SUCCESS (1.046s) [178.883495] [051/162] (869s left) i915_pm_rc6_residency (rc6-idle) [179.147515] Starting subtest: rc6-idle [179.180834] Starting dynamic subtest: gt0-rcs0 [203.581802] Dynamic subtest gt0-rcs0: SUCCESS (24.407s) [203.607837] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 2127 [max: 8192] direct dependencies: 22998 [max: 524288] indirect dependencies: 159571 all direct dependencies: 419862 dependency chains: 31247 [max: 524288] dependency chain hlocks used: 123554 [max: 2621440] dependency chain hlocks lost: 0 in-hardirq chains: 191 in-softirq chains: 781 in-process chains: 30275 stack-trace entries: 231695 [max: 524288] number of stack traces: 10936 number of stack hash chains: 7974 combined max dependencies: 250792448 hardirq-safe locks: 81 hardirq-unsafe locks: 1248 softirq-safe locks: 229 softirq-unsafe locks: 1147 irq-safe locks: 237 irq-unsafe locks: 1248 hardirq-read-safe locks: 5 hardirq-read-unsafe locks: 427 softirq-read-safe locks: 10 softirq-read-unsafe locks: 422 irq-read-safe locks: 10 irq-read-unsafe locks: 427 uncategorized locks: 344 unused locks: 1 max locking depth: 16 max bfs queue depth: 394 max lock class index: 2126 debug_locks: 0 zapped classes: 3 zapped lock chains: 152 large chain blocks: 1 [203.612401] Starting dynamic subtest: gt0-bcs0 [227.956331] Dynamic subtest gt0-bcs0: SUCCESS (24.367s) [227.957413] Starting dynamic subtest: gt0-vcs0 [252.250882] Dynamic subtest gt0-vcs0: SUCCESS (24.294s) [252.252774] Starting dynamic subtest: gt0-vecs0 [276.619335] Dynamic subtest gt0-vecs0: SUCCESS (24.368s) [276.622560] Subtest rc6-idle: SUCCESS (97.471s) [276.745726] Closing watchdogs Done.