[73.236766] Initializing watchdogs [73.237543] /dev/watchdog0 [73.387970] [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 [73.411803] [001/162] (960s left) i915_module_load (load) [74.792100] Starting subtest: load [80.434093] Subtest load: SUCCESS (5.636s) [80.760302] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [80.774675] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [80.792896] [002/162] (953s left) core_auth (basic-auth) [81.206104] Starting subtest: basic-auth [81.223449] Subtest basic-auth: SUCCESS (0.017s) [81.571261] [003/162] (952s left) debugfs_test (read_all_entries) [81.961080] Starting subtest: read_all_entries [82.323945] Subtest read_all_entries: SUCCESS (0.362s) [83.026850] [004/162] (951s left) debugfs_test (basic-hwmon) [83.305305] Starting subtest: basic-hwmon [83.310232] Subtest basic-hwmon: SKIP (0.000s) [84.015607] [005/162] (950s left) debugfs_test (sysfs) [84.293239] Starting subtest: sysfs [84.726106] Subtest sysfs: SUCCESS (0.431s) [85.451523] [006/162] (949s left) fbdev (eof) [85.736887] Starting subtest: eof [85.738702] Subtest eof: SUCCESS (0.004s) [85.948458] [007/162] (949s left) fbdev (info) [86.195266] Starting subtest: info [86.200762] Subtest info: SUCCESS (0.000s) [86.410412] [008/162] (948s left) fbdev (nullptr) [86.680824] Starting subtest: nullptr [86.682515] Subtest nullptr: SUCCESS (0.000s) [86.895619] [009/162] (948s left) fbdev (read) [87.169631] Starting subtest: read [89.249878] Subtest read: SUCCESS (2.075s) [89.442444] [010/162] (946s left) fbdev (write) [89.697320] Starting subtest: write [90.798471] Subtest write: SUCCESS (1.092s) [91.001999] [011/162] (944s left) gem_basic (bad-close) [91.261514] Starting subtest: bad-close [91.266678] Subtest bad-close: SUCCESS (0.000s) [91.483848] [012/162] (944s left) gem_basic (create-close) [91.744195] Starting subtest: create-close [91.748138] Subtest create-close: SUCCESS (0.001s) [91.977258] [013/162] (944s left) gem_basic (create-fd-close) [92.231932] Starting subtest: create-fd-close [92.236487] Subtest create-fd-close: SUCCESS (0.001s) [92.464919] [014/162] (943s left) gem_busy (busy@all-engines) [92.785154] Starting subtest: busy [92.786707] Starting dynamic subtest: all-engines [92.993744] Dynamic subtest all-engines: SUCCESS (0.209s) [92.995509] Subtest busy: SUCCESS (0.213s) [93.564807] [015/162] (942s left) gem_close_race (basic-process) [93.914151] Starting subtest: basic-process [94.068147] Subtest basic-process: SUCCESS (0.161s) [94.423163] [016/162] (942s left) gem_close_race (basic-threads) [94.757088] Starting subtest: basic-threads [96.022521] Subtest basic-threads: SUCCESS (1.265s) [96.387292] [017/162] (940s left) gem_ctx_create (basic) [96.702306] Starting subtest: basic [96.703888] Subtest basic: SUCCESS (0.000s) [97.042183] [018/162] (939s left) gem_ctx_create (basic-files) [97.355114] Starting subtest: basic-files [99.430681] Subtest basic-files: SUCCESS (2.076s) [99.802185] [019/162] (937s left) gem_ctx_exec (basic) [100.067651] Starting subtest: basic [100.093414] Subtest basic: SUCCESS (0.021s) [100.320751] [020/162] (937s left) gem_exec_basic (basic) [100.624697] Starting subtest: basic [100.631156] Starting dynamic subtest: rcs0-smem [100.637114] Dynamic subtest rcs0-smem: SUCCESS (0.009s) [100.666189] Starting dynamic subtest: bcs0-smem [100.668829] Dynamic subtest bcs0-smem: SUCCESS (0.026s) [100.692513] Starting dynamic subtest: vcs0-smem [100.694078] Dynamic subtest vcs0-smem: SUCCESS (0.003s) [100.717764] Starting dynamic subtest: vecs0-smem [100.721880] Dynamic subtest vecs0-smem: SUCCESS (0.047s) [100.726796] Subtest basic: SUCCESS (0.100s) [101.102802] [021/162] (936s left) gem_exec_create (basic) [101.416643] Starting subtest: basic [101.418435] Starting dynamic subtest: smem [103.512041] Dynamic subtest smem: SUCCESS (2.104s) [103.513618] Subtest basic: SUCCESS (2.105s) [103.872912] [022/162] (933s left) gem_exec_fence (basic-busy) [104.358037] Starting subtest: basic-busy [104.360091] Starting dynamic subtest: rcs0 [104.393615] Dynamic subtest rcs0: SUCCESS (0.034s) [104.420664] Starting dynamic subtest: bcs0 [104.446573] Dynamic subtest bcs0: SUCCESS (0.051s) [104.472962] Starting dynamic subtest: vcs0 [104.486742] Dynamic subtest vcs0: SUCCESS (0.032s) [104.511949] Starting dynamic subtest: vecs0 [104.522870] Dynamic subtest vecs0: SUCCESS (0.039s) [104.525506] Subtest basic-busy: SUCCESS (0.168s) [105.547996] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [105.557815] [023/162] (932s left) gem_exec_fence (basic-wait) [106.047614] Starting subtest: basic-wait [106.049429] Starting dynamic subtest: rcs0 [106.069913] Dynamic subtest rcs0: SUCCESS (0.023s) [106.105298] Starting dynamic subtest: bcs0 [106.113123] Dynamic subtest bcs0: SUCCESS (0.041s) [106.148157] Starting dynamic subtest: vcs0 [106.155534] Dynamic subtest vcs0: SUCCESS (0.040s) [106.183676] Starting dynamic subtest: vecs0 [106.197059] Dynamic subtest vecs0: SUCCESS (0.041s) [106.201293] Subtest basic-wait: SUCCESS (0.155s) [107.042952] [024/162] (931s left) gem_exec_fence (basic-await) [107.523816] Starting subtest: basic-await [107.525973] Starting dynamic subtest: rcs0 [107.756510] Dynamic subtest rcs0: SUCCESS (0.224s) [107.781869] Starting dynamic subtest: bcs0 [107.987947] Dynamic subtest bcs0: SUCCESS (0.227s) [108.013882] Starting dynamic subtest: vcs0 [108.219348] Dynamic subtest vcs0: SUCCESS (0.235s) [108.250875] Starting dynamic subtest: vecs0 [108.445182] Dynamic subtest vecs0: SUCCESS (0.220s) [108.446745] Subtest basic-await: SUCCESS (0.915s) [109.300417] [025/162] (928s left) gem_exec_fence (nb-await) [109.787142] Starting subtest: nb-await [109.790670] Starting dynamic subtest: rcs0 [109.879609] Dynamic subtest rcs0: SUCCESS (0.090s) [109.909854] Starting dynamic subtest: bcs0 [109.986764] Dynamic subtest bcs0: SUCCESS (0.097s) [110.011755] Starting dynamic subtest: vcs0 [110.083859] Dynamic subtest vcs0: SUCCESS (0.103s) [110.114297] Starting dynamic subtest: vecs0 [110.180710] Dynamic subtest vecs0: SUCCESS (0.091s) [110.182447] Subtest nb-await: SUCCESS (0.392s) [111.049415] [026/162] (927s left) gem_exec_gttfill (basic) [111.358577] Starting subtest: basic [113.329952] Subtest basic: SKIP (1.935s) [113.745064] [027/162] (924s left) gem_exec_parallel (engines) [114.240554] Starting subtest: engines [114.245138] Starting dynamic subtest: basic [120.787186] Dynamic subtest basic: SUCCESS (6.549s) [120.824520] Starting dynamic subtest: contexts [131.151958] Dynamic subtest contexts: SUCCESS (10.358s) [131.181567] Starting dynamic subtest: fds [142.145997] Dynamic subtest fds: SUCCESS (10.991s) [142.177548] Starting dynamic subtest: userptr [148.432691] Dynamic subtest userptr: SUCCESS (6.284s) [148.434330] Subtest engines: SUCCESS (34.193s) [148.857883] [028/162] (890s left) gem_exec_store (basic) [149.173215] Starting subtest: basic [149.244123] Subtest basic: SUCCESS (0.072s) [149.610355] [029/162] (889s left) gem_flink_basic (bad-flink) [149.870164] Starting subtest: bad-flink [149.872570] Subtest bad-flink: SUCCESS (0.000s) [150.120438] [030/162] (889s left) gem_flink_basic (bad-open) [150.371512] Starting subtest: bad-open [150.373516] Subtest bad-open: SUCCESS (0.000s) [150.630975] [031/162] (888s left) gem_flink_basic (basic) [150.884539] Starting subtest: basic [150.887759] Subtest basic: SUCCESS (0.000s) [151.140590] [032/162] (888s left) gem_flink_basic (double-flink) [151.396507] Starting subtest: double-flink [151.399429] Subtest double-flink: SUCCESS (0.000s) [151.646980] [033/162] (888s left) gem_flink_basic (flink-lifetime) [151.904236] Starting subtest: flink-lifetime [151.913650] Subtest flink-lifetime: SUCCESS (0.010s) [152.156512] [034/162] (887s left) gem_huc_copy (huc-copy) [152.428344] Subtest huc-copy: SKIP (0.000s) [152.644355] [035/162] (887s left) gem_linear_blits (basic) [153.289780] Subtest basic: SKIP (0.000s) [153.538896] [036/162] (886s left) gem_mmap (basic) [154.011165] Starting subtest: basic [154.027205] Subtest basic: SUCCESS (0.002s) [154.248843] [037/162] (886s left) gem_mmap_gtt (basic) [154.506532] Starting subtest: basic [154.508034] Subtest basic: SUCCESS (0.004s) [154.761925] [038/162] (886s left) gem_render_linear_blits (basic) [155.058778] Starting subtest: basic [155.192264] Subtest basic: SUCCESS (0.134s) [155.399807] [039/162] (885s left) gem_render_tiled_blits (basic) [155.677750] Starting subtest: basic [155.827101] Subtest basic: SUCCESS (0.145s) [156.054474] [040/162] (885s left) gem_ringfill (basic-all) [156.548275] Starting subtest: basic-all [158.029732] Subtest basic-all: SUCCESS (1.471s) [158.269664] [041/162] (883s left) gem_softpin (allocator-basic) [158.546338] Starting subtest: allocator-basic [158.913118] Subtest allocator-basic: SUCCESS (0.359s) [159.251662] [042/162] (882s left) gem_softpin (allocator-basic-reserve) [159.525075] Starting subtest: allocator-basic-reserve [159.905579] Subtest allocator-basic-reserve: SUCCESS (0.359s) [160.183442] [043/162] (881s left) gem_softpin (safe-alignment) [160.460484] Starting subtest: safe-alignment [160.529620] Subtest safe-alignment: SUCCESS (0.062s) [160.765395] [044/162] (881s left) gem_sync (basic-all) [161.078547] Starting subtest: basic-all [164.137051] Subtest basic-all: SUCCESS (3.058s) [164.532277] [045/162] (877s left) gem_sync (basic-each) [164.848413] Starting subtest: basic-each [167.798662] Subtest basic-each: SUCCESS (2.952s) [168.198525] [046/162] (874s left) gem_tiled_blits (basic) [168.771993] Subtest basic: SKIP (0.000s) [168.984624] [047/162] (873s left) gem_tiled_fence_blits (basic) [170.258936] Starting subtest: basic [170.346820] Subtest basic: SUCCESS (0.088s) [170.739471] [048/162] (872s left) gem_tiled_pread_basic [172.698988] [049/162] (870s left) gem_wait (busy@all-engines) [173.057223] Starting subtest: busy [173.060198] Starting dynamic subtest: all-engines [173.593069] Dynamic subtest all-engines: SUCCESS (0.536s) [173.596193] Subtest busy: SUCCESS (0.539s) [174.096607] [050/162] (869s left) gem_wait (wait@all-engines) [174.483530] Starting subtest: wait [174.485749] Starting dynamic subtest: all-engines [175.534648] Dynamic subtest all-engines: SUCCESS (1.060s) [175.540919] Subtest wait: SUCCESS (1.065s) [176.023714] [051/162] (867s left) i915_pm_rc6_residency (rc6-idle) [176.284148] Starting subtest: rc6-idle [176.308168] Starting dynamic subtest: gt0-rcs0 [200.707254] Dynamic subtest gt0-rcs0: SUCCESS (24.393s) [200.733585] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 2131 [max: 8192] direct dependencies: 23072 [max: 524288] indirect dependencies: 164308 all direct dependencies: 425250 dependency chains: 31128 [max: 524288] dependency chain hlocks used: 122773 [max: 2621440] dependency chain hlocks lost: 0 in-hardirq chains: 195 in-softirq chains: 766 in-process chains: 30167 stack-trace entries: 232251 [max: 524288] number of stack traces: 10975 number of stack hash chains: 8060 combined max dependencies: 240248480 hardirq-safe locks: 83 hardirq-unsafe locks: 1249 softirq-safe locks: 228 softirq-unsafe locks: 1147 irq-safe locks: 239 irq-unsafe locks: 1249 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: 346 unused locks: 1 max locking depth: 16 max bfs queue depth: 389 max lock class index: 2130 debug_locks: 0 zapped classes: 3 zapped lock chains: 128 large chain blocks: 1 [200.738206] Starting dynamic subtest: gt0-bcs0 [225.006961] Dynamic subtest gt0-bcs0: SUCCESS (24.301s) [225.009858] Starting dynamic subtest: gt0-vcs0 [249.309772] Dynamic subtest gt0-vcs0: SUCCESS (24.300s) [249.313874] Starting dynamic subtest: gt0-vecs0 [273.689624] Dynamic subtest gt0-vecs0: SUCCESS (24.370s) [273.692910] Subtest rc6-idle: SUCCESS (97.397s) [273.824035] Closing watchdogs Done.