Result:
integration-manifest git-log-oneline i915_display_info22 igt_runner22 runtimes22 results22.json results22-i915-load.json guc_logs22.tar i915_display_info_post_exec22 boot22 dmesg22
| Detail | Value |
|---|---|
| Duration | unknown |
| Hostname |
shard-mtlp-8 |
| Igt-Version |
IGT-Version: 2.4-g98b65acc4 (x86_64) (Linux: 7.1.0-rc5-Patchwork_164512v4-g7ce39e849680+ x86_64) |
| Out |
Using IGT_SRANDOM=1779694725 for randomisation Opened device: /dev/dri/card0 Starting subtest: close-race Opened device: /dev/dri/card1 runner: This test was killed due to a kernel taint (0x4244). This test caused an abort condition: Child refuses to die, tainted 0x4244. |
| Err |
Starting subtest: close-race Received signal SIGQUIT. Stack trace: Received signal SIGQUIT. Received signal SIGQUIT. Stack trace: Stack trace: # 0# [0f a[tfaalt_asli_gs_ihga_nhdalnedrl+e0rx+10 7xb1#]7 b] 0 [fatal_sig_handler+0x17b] ##11 [_[__s_isgia gcatci#otin1o+n0+ x05x[05]0_ ] _sigaction+0x50] #2 [#p2o l[lw+a0ixt44d+]0 x51] #3 [igt_fork_hang_detector+0x138] #3 [__igt_waitchildren+0x57] #2 [msgrcv+0x5d ]##44 [[i_g_ti_gwta_iuntiqcueh_i_l_d_rreaeln_m+ai0n4x722d+]0 x158d] #5 # 5[ _[m_aiign+t0_xu2nd]i que____real_ma i#n3 4[7ms2g+qu0euex_r1ecdv_0re1q]+0 x74] #6 [__libc_init_first+0x8a ] #6 [main+0x2d] #4 [a l##l77o c[[a__t__ollrii_bbtcch__risentaiadtr__tlf_oimorapsi+tn0++x008xx388]ab ] ] #8 [_start+0x25] #5 [pthread_condattr_setpshared+0x684] #8 [__libc_start_main+0x8b] #9 [_start+0x25] #6 [__clone+0x24c] |
| Dmesg |
<6> [356.895080] Console: switching to colour dummy device 80x25
<6> [356.895448] [IGT] gem_busy: executing
<7> [356.899078] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [356.900351] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [356.915456] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [356.916407] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [356.924959] [IGT] gem_busy: starting subtest close-race
<7> [356.928496] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [356.940388] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [356.951553] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [356.964077] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [356.976601] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [356.988207] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [357.042537] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [357.043540] gem_busy (2842): drop_caches: 4
<7> [357.043910] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7> [357.753533] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [357.753572] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [357.754684] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4
<7> [357.754676] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [358.886857] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7> [359.002845] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [359.226121] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD off
<7> [359.226546] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [359.226811] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [359.227130] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [359.227450] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<3> [444.402215] rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
<3> [444.408354] rcu: Tasks blocked on level-1 rcu_node (CPUs 11-21): P2854
<3> [444.415100] rcu: (detected by 10, t=65014 jiffies, g=151113, q=104486 ncpus=22)
<6> [444.422479] task:gem_busy state:R running task stack:0 pid:2854 tgid:2854 ppid:2842 task_flags:0x400040 flags:0x00080800
<6> [444.422485] Call Trace:
<6> [444.422487] <TASK>
<6> [444.422489] ? seqcount_lockdep_reader_access.constprop.0+0x4d/0xc0
<6> [444.422498] ? ktime_expiry_to_cycles+0x43/0x140
<6> [444.422500] ? ktime_expiry_to_cycles+0x43/0x140
<6> [444.422503] ? find_held_lock+0x31/0x90
<6> [444.422508] ? __hrtimer_rearm_deferred+0xc5/0x1f0
<6> [444.422510] ? lock_release+0xd0/0x2b0
<6> [444.422513] ? sysvec_apic_timer_interrupt+0x57/0xc0
<6> [444.422518] ? irqentry_exit+0x2a2/0x870
<6> [444.422520] ? sysvec_apic_timer_interrupt+0x57/0xc0
<6> [444.422522] ? trace_hardirqs_on+0x22/0xf0
<6> [444.422528] ? irqentry_exit+0x2a2/0x870
<6> [444.422530] ? trace_hardirqs_off_finish+0xcf/0xf0
<6> [444.422533] ? sysvec_apic_timer_interrupt+0x57/0xc0
<6> [444.422535] ? asm_sysvec_apic_timer_interrupt+0x1b/0x20
<6> [444.422540] ? lock_is_held_type+0xa3/0x130
<6> [444.422542] ? lock_is_held_type+0xa3/0x130
<6> [444.422544] ? rcu_read_lock_held+0x2e/0x60
<6> [444.422549] ? dma_resv_iter_walk_unlocked.part.0+0x170/0x1d0
<6> [444.422555] ? dma_resv_iter_first_unlocked+0x69/0x290
<6> [444.422561] ? i915_gem_busy_ioctl+0xb8/0xb80 [i915]
<6> [444.422772] ? __pfx_i915_gem_busy_ioctl+0x10/0x10 [i915]
<6> [444.422911] ? drm_ioctl_kernel+0xb3/0x120
<6> [444.422918] ? drm_ioctl+0x2d4/0x5a0
<6> [444.422920] ? __pfx_i915_gem_busy_ioctl+0x10/0x10 [i915]
<6> [444.423058] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [444.423064] ? __x64_sys_ioctl+0xa5/0x100
<6> [444.423069] ? x64_sys_call+0x1250/0x26e0
<6> [444.423074] ? do_syscall_64+0x103/0x6d0
<6> [444.423077] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [444.423080] ? do_syscall_64+0x34/0x6d0
<6> [444.423082] ? entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [444.423084] ? trace_hardirqs_on+0x22/0xf0
<6> [444.423086] ? do_syscall_64+0xb8/0x6d0
<6> [444.423089] ? entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [444.423094] </TASK>
<3> [524.333543] INFO: task systemd:1 blocked for more than 61 seconds.
<3> [524.339857] Tainted: G S U W L 7.1.0-rc5-Patchwork_164512v4-g7ce39e849680+ #1
<3> [524.348749] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6> [524.356585] task:systemd state:D stack:0 pid:1 tgid:1 ppid:0 task_flags:0x400100 flags:0x00080000
<6> [524.356590] Call Trace:
<6> [524.356592] <TASK>
<6> [524.356595] __schedule+0x5f0/0x1fa0
<6> [524.356605] ? lock_acquire+0xc4/0x300
<6> [524.356612] ? schedule+0x10e/0x180
<6> [524.356614] ? lock_release+0xd0/0x2b0
<6> [524.356618] schedule+0x3a/0x180
<6> [524.356620] schedule_timeout+0x117/0x130
<6> [524.356623] ? __wait_for_common+0xb4/0x1f0
<6> [524.356626] ? _raw_spin_unlock_irq+0x27/0x70
<6> [524.356629] ? __wait_for_common+0xb4/0x1f0
<6> [524.356631] ? trace_hardirqs_on+0x22/0xf0
<6> [524.356638] __wait_for_common+0xc0/0x1f0
<6> [524.356641] ? __pfx_schedule_timeout+0x10/0x10
<6> [524.356644] wait_for_completion_state+0x21/0x50
<6> [524.356646] __wait_rcu_gp+0x1a0/0x1b0
<6> [524.356652] ? cgroup_procs_write_start+0x5e/0x3f0
<6> [524.356656] synchronize_rcu_normal.part.0+0x45/0x70
<6> [524.356659] ? __pfx_call_rcu_hurry+0x10/0x10
<6> [524.356662] ? __pfx_wakeme_after_rcu+0x10/0x10
<6> [524.356668] synchronize_rcu_normal+0xe3/0xf0
<6> [524.356670] ? lock_is_held_type+0xa3/0x130
<6> [524.356674] ? cgroup_procs_write_start+0x5e/0x3f0
<6> [524.356677] ? __might_sleep+0x49/0x60
<6> [524.356683] synchronize_rcu+0x1c4/0x1d0
<6> [524.356685] rcu_sync_enter+0x58/0x110
<6> [524.356689] percpu_down_write+0x56/0x2e0
<6> [524.356692] ? cgroup_procs_write_start+0x233/0x3f0
<6> [524.356694] ? cgroup_procs_write_start+0x5e/0x3f0
<6> [524.356697] ? cgroup_procs_write_start+0x5e/0x3f0
<6> [524.356699] cgroup_procs_write_start+0x233/0x3f0
<6> [524.356701] ? mutex_lock_nested+0x1b/0x30
<6> [524.356705] __cgroup_procs_write+0x5c/0x2e0
<6> [524.356709] cgroup_procs_write+0x17/0x30
<6> [524.356711] cgroup_file_write+0xa6/0x360
<6> [524.356713] ? lock_is_held_type+0xa3/0x130
<6> [524.356716] kernfs_fop_write_iter+0x188/0x240
<6> [524.356722] vfs_write+0x283/0x540
<6> [524.356726] ? lock_release+0xd0/0x2b0
<6> [524.356732] ksys_write+0x6f/0xf0
<6> [524.356735] __x64_sys_write+0x19/0x30
<6> [524.356737] x64_sys_call+0x259/0x26e0
<6> [524.356743] do_syscall_64+0x103/0x6d0
<6> [524.356745] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [524.356749] ? do_syscall_64+0x23d/0x6d0
<6> [524.356751] ? __pcs_replace_full_main+0x10/0x720
<6> [524.356756] ? putname+0x41/0x90
<6> [524.356759] ? kmem_cache_free+0x171/0x510
<6> [524.356765] ? putname+0x41/0x90
<6> [524.356768] ? do_sys_openat2+0x85/0xd0
<6> [524.356772] ? __x64_sys_openat+0x54/0xa0
<6> [524.356774] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [524.356777] ? do_syscall_64+0x23d/0x6d0
<6> [524.356782] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [524.356785] ? do_syscall_64+0x23d/0x6d0
<6> [524.356787] ? exit_to_user_mode_loop+0x3ba/0x560
<6> [524.356790] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [524.356793] ? do_syscall_64+0x23d/0x6d0
<6> [524.356795] ? putname+0x41/0x90
<6> [524.356797] ? kmem_cache_free+0x171/0x510
<6> [524.356801] ? putname+0x41/0x90
<6> [524.356804] ? do_readlinkat+0xab/0x150
<6> [524.356808] ? do_syscall_64+0x34/0x6d0
<6> [524.356809] ? entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [524.356812] ? trace_hardirqs_on+0x22/0xf0
<6> [524.356815] ? do_syscall_64+0xb8/0x6d0
<6> [524.356817] ? exc_page_fault+0xbd/0x2b0
<6> [524.356820] entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [524.356822] RIP: 0033:0x7d2ce051c5a4
<6> [524.356826] RSP: 002b:00007ffed1dab128 EFLAGS: 00000202 ORIG_RAX: 0000000000000001
<6> [524.356829] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007d2ce051c5a4
<6> [524.356831] RDX: 0000000000000005 RSI: 00007ffed1dab2fa RDI: 000000000000006b
<6> [524.356832] RBP: 00007ffed1dab150 R08: 00007d2ce0602228 R09: 0000000000000000
<6> [524.356833] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000005
<6> [524.356834] R13: 00007ffed1dab2fa R14: 00005661e107be10 R15: 0000000000000005
<6> [524.356839] </TASK>
<3> [524.356944] INFO: task (sa1):2870 blocked for more than 61 seconds.
<3> [524.363247] Tainted: G S U W L 7.1.0-rc5-Patchwork_164512v4-g7ce39e849680+ #1
<3> [524.371857] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6> [524.379674] task:(sa1) state:D stack:0 pid:2870 tgid:2870 ppid:1 task_flags:0x400100 flags:0x00080000
<6> [524.379678] Call Trace:
<6> [524.379680] <TASK>
<6> [524.379683] __schedule+0x5f0/0x1fa0
<6> [524.379686] ? lock_acquire+0xc4/0x300
<6> [524.379690] ? schedule+0x10e/0x180
<6> [524.379693] ? lock_release+0xd0/0x2b0
<6> [524.379698] schedule+0x3a/0x180
<6> [524.379700] schedule_preempt_disabled+0x15/0x30
<6> [524.379702] __mutex_lock+0x777/0x10a0
<6> [524.379706] ? cgroup_kn_lock_live+0x4f/0x250
<6> [524.379712] mutex_lock_nested+0x1b/0x30
<6> [524.379715] ? kernfs_break_active_protection+0x4a/0x80
<6> [524.379717] ? mutex_lock_nested+0x1b/0x30
<6> [524.379720] cgroup_kn_lock_live+0x4f/0x250
<6> [524.379723] __cgroup_procs_write+0x3d/0x2e0
<6> [524.379726] ? kernfs_root+0x80/0x1b0
<6> [524.379731] cgroup_procs_write+0x17/0x30
<6> [524.379734] cgroup_file_write+0xa6/0x360
<6> [524.379736] ? lock_is_held_type+0xa3/0x130
<6> [524.379739] kernfs_fop_write_iter+0x188/0x240
<6> [524.379743] vfs_write+0x283/0x540
<6> [524.379750] ksys_write+0x6f/0xf0
<6> [524.379754] __x64_sys_write+0x19/0x30
<6> [524.379757] x64_sys_call+0x259/0x26e0
<6> [524.379760] do_syscall_64+0x103/0x6d0
<6> [524.379763] ? do_syscall_64+0xb8/0x6d0
<6> [524.379765] ? exc_page_fault+0xbd/0x2b0
<6> [524.379768] entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [524.379770] RIP: 0033:0x7630f5b1c5a4
<6> [524.379772] RSP: 002b:00007ffdd543df38 EFLAGS: 00000202 ORIG_RAX: 0000000000000001
<6> [524.379775] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007630f5b1c5a4
<6> [524.379776] RDX: 0000000000000005 RSI: 00007ffdd543e10a RDI: 0000000000000003
<6> [524.379778] RBP: 00007ffdd543df60 R08: 00007630f5c02228 R09: 0000000000000000
<6> [524.379779] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000005
<6> [524.379781] R13: 00007ffdd543e10a R14: 000062717ecd62d0 R15: 0000000000000005
<6> [524.379787] </TASK>
<3> [524.379789] INFO: task (sa1):2870 is blocked on a mutex likely owned by task systemd:1.
<4> [524.387798]
Showing all locks held in the system:
<4> [524.387801] 5 locks held by systemd/1:
<4> [524.387804] #0: ffff8881134fc410 (sb_writers#8){.+.+}-{0:0}, at: ksys_write+0x6f/0xf0
<4> [524.387813] #1: ffff888120b62c80 (&of->mutex#2){+.+.}-{3:3}, at: kernfs_fop_write_iter+0x127/0x240
<4> [524.387820] #2: ffffffff8369abc0 (cgroup_mutex){+.+.}-{3:3}, at: cgroup_kn_lock_live+0x4f/0x250
<4> [524.387826] #3: ffffffff83487c70 (cpu_hotplug_lock){++++}-{0:0}, at: cgroup_procs_write_start+0x227/0x3f0
<4> [524.387832] #4: ffffffff8369a9d0 (cgroup_threadgroup_rwsem){++++}-{0:0}, at: cgroup_procs_write_start+0x233/0x3f0
<4> [524.387847] 1 lock held by khungtaskd/157:
<4> [524.387848] #0: ffffffff835c4060 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x37/0x220
<4> [524.387864] 1 lock held by in:imklog/933:
<4> [524.387865] #0: ffff888132404930 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0
<4> [524.387877] 1 lock held by dmesg/2396:
<4> [524.387878] #0: ffff88812ebec0c8 (&user->lock){+.+.}-{3:3}, at: devkmsg_read+0x5a/0x210
<4> [524.387888] 2 locks held by gem_busy/2854:
<4> [524.387890] 3 locks held by (sa1)/2870:
<4> [524.387891] #0: ffff8881134fc410 (sb_writers#8){.+.+}-{0:0}, at: ksys_write+0x6f/0xf0
<4> [524.387897] #1: ffff888120b65680 (&of->mutex#2){+.+.}-{3:3}, at: kernfs_fop_write_iter+0x127/0x240
<4> [524.387903] #2: ffffffff8369abc0 (cgroup_mutex){+.+.}-{3:3}, at: cgroup_kn_lock_live+0x4f/0x250
<4> [524.387909]
<4> [524.387910] =============================================
<3> [585.773557] INFO: task systemd:1 blocked for more than 122 seconds.
<3> [585.779867] Tainted: G S U W L 7.1.0-rc5-Patchwork_164512v4-g7ce39e849680+ #1
<3> [585.788484] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6> [585.796334] task:systemd state:D stack:0 pid:1 tgid:1 ppid:0 task_flags:0x400100 flags:0x00080000
<6> [585.796339] Call Trace:
<6> [585.796340] <TASK>
<6> [585.796344] __schedule+0x5f0/0x1fa0
<6> [585.796350] ? lock_acquire+0xc4/0x300
<6> [585.796356] ? schedule+0x10e/0x180
<6> [585.796359] ? lock_release+0xd0/0x2b0
<6> [585.796365] schedule+0x3a/0x180
<6> [585.796367] schedule_timeout+0x117/0x130
<6> [585.796370] ? __wait_for_common+0xb4/0x1f0
<6> [585.796373] ? _raw_spin_unlock_irq+0x27/0x70
<6> [585.796375] ? __wait_for_common+0xb4/0x1f0
<6> [585.796378] ? trace_hardirqs_on+0x22/0xf0
<6> [585.796385] __wait_for_common+0xc0/0x1f0
<6> [585.796389] ? __pfx_schedule_timeout+0x10/0x10
<6> [585.796394] wait_for_completion_state+0x21/0x50
<6> [585.796397] __wait_rcu_gp+0x1a0/0x1b0
<6> [585.796402] ? cgroup_procs_write_start+0x5e/0x3f0
<6> [585.796406] synchronize_rcu_normal.part.0+0x45/0x70
<6> [585.796410] ? __pfx_call_rcu_hurry+0x10/0x10
<6> [585.796414] ? __pfx_wakeme_after_rcu+0x10/0x10
<6> [585.796422] synchronize_rcu_normal+0xe3/0xf0
<6> [585.796424] ? lock_is_held_type+0xa3/0x130
<6> [585.796429] ? cgroup_procs_write_start+0x5e/0x3f0
<6> [585.796433] ? __might_sleep+0x49/0x60
<6> [585.796439] synchronize_rcu+0x1c4/0x1d0
<6> [585.796442] rcu_sync_enter+0x58/0x110
<6> [585.796446] percpu_down_write+0x56/0x2e0
<6> [585.796449] ? cgroup_procs_write_start+0x233/0x3f0
<6> [585.796451] ? cgroup_procs_write_start+0x5e/0x3f0
<6> [585.796454] ? cgroup_procs_write_start+0x5e/0x3f0
<6> [585.796457] cgroup_procs_write_start+0x233/0x3f0
<6> [585.796459] ? mutex_lock_nested+0x1b/0x30
<6> [585.796464] __cgroup_procs_write+0x5c/0x2e0
<6> [585.796470] cgroup_procs_write+0x17/0x30
<6> [585.796474] cgroup_file_write+0xa6/0x360
<6> [585.796476] ? lock_is_held_type+0xa3/0x130
<6> [585.796481] kernfs_fop_write_iter+0x188/0x240
<6> [585.796487] vfs_write+0x283/0x540
<6> [585.796491] ? lock_release+0xd0/0x2b0
<6> [585.796498] ksys_write+0x6f/0xf0
<6> [585.796503] __x64_sys_write+0x19/0x30
<6> [585.796505] x64_sys_call+0x259/0x26e0
<6> [585.796510] do_syscall_64+0x103/0x6d0
<6> [585.796513] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [585.796516] ? do_syscall_64+0x23d/0x6d0
<6> [585.796518] ? __pcs_replace_full_main+0x10/0x720
<6> [585.796523] ? putname+0x41/0x90
<6> [585.796535] ? kmem_cache_free+0x171/0x510
<6> [585.796545] ? putname+0x41/0x90
<6> [585.796551] ? do_sys_openat2+0x85/0xd0
<6> [585.796557] ? __x64_sys_openat+0x54/0xa0
<6> [585.796561] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [585.796567] ? do_syscall_64+0x23d/0x6d0
<6> [585.796577] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [585.796581] ? do_syscall_64+0x23d/0x6d0
<6> [585.796584] ? exit_to_user_mode_loop+0x3ba/0x560
<6> [585.796588] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [585.796591] ? do_syscall_64+0x23d/0x6d0
<6> [585.796593] ? putname+0x41/0x90
<6> [585.796596] ? kmem_cache_free+0x171/0x510
<6> [585.796602] ? putname+0x41/0x90
<6> [585.796605] ? do_readlinkat+0xab/0x150
<6> [585.796611] ? do_syscall_64+0x34/0x6d0
<6> [585.796613] ? entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [585.796616] ? trace_hardirqs_on+0x22/0xf0
<6> [585.796620] ? do_syscall_64+0xb8/0x6d0
<6> [585.796622] ? exc_page_fault+0xbd/0x2b0
<6> [585.796626] entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [585.796628] RIP: 0033:0x7d2ce051c5a4
<6> [585.796632] RSP: 002b:00007ffed1dab128 EFLAGS: 00000202 ORIG_RAX: 0000000000000001
<6> [585.796635] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007d2ce051c5a4
<6> [585.796637] RDX: 0000000000000005 RSI: 00007ffed1dab2fa RDI: 000000000000006b
<6> [585.796638] RBP: 00007ffed1dab150 R08: 00007d2ce0602228 R09: 0000000000000000
<6> [585.796640] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000005
<6> [585.796641] R13: 00007ffed1dab2fa R14: 00005661e107be10 R15: 0000000000000005
<6> [585.796649] </TASK>
<3> [585.796703] INFO: task (sa1):2870 blocked for more than 122 seconds.
<3> [585.803071] Tainted: G S U W L 7.1.0-rc5-Patchwork_164512v4-g7ce39e849680+ #1
<3> [585.811680] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6> [585.819497] task:(sa1) state:D stack:0 pid:2870 tgid:2870 ppid:1 task_flags:0x400100 flags:0x00080000
<6> [585.819500] Call Trace:
<6> [585.819502] <TASK>
<6> [585.819505] __schedule+0x5f0/0x1fa0
<6> [585.819507] ? lock_acquire+0xc4/0x300
<6> [585.819511] ? schedule+0x10e/0x180
<6> [585.819513] ? lock_release+0xd0/0x2b0
<6> [585.819517] schedule+0x3a/0x180
<6> [585.819519] schedule_preempt_disabled+0x15/0x30
<6> [585.819521] __mutex_lock+0x777/0x10a0
<6> [585.819525] ? cgroup_kn_lock_live+0x4f/0x250
<6> [585.819537] mutex_lock_nested+0x1b/0x30
<6> [585.819539] ? kernfs_break_active_protection+0x4a/0x80
<6> [585.819542] ? mutex_lock_nested+0x1b/0x30
<6> [585.819544] cgroup_kn_lock_live+0x4f/0x250
<6> [585.819548] __cgroup_procs_write+0x3d/0x2e0
<6> [585.819552] ? kernfs_root+0x80/0x1b0
<6> [585.819558] cgroup_procs_write+0x17/0x30
<6> [585.819562] cgroup_file_write+0xa6/0x360
<6> [585.819564] ? lock_is_held_type+0xa3/0x130
<6> [585.819569] kernfs_fop_write_iter+0x188/0x240
<6> [585.819574] vfs_write+0x283/0x540
<6> [585.819582] ksys_write+0x6f/0xf0
<6> [585.819587] __x64_sys_write+0x19/0x30
<6> [585.819590] x64_sys_call+0x259/0x26e0
<6> [585.819594] do_syscall_64+0x103/0x6d0
<6> [585.819598] ? do_syscall_64+0xb8/0x6d0
<6> [585.819600] ? exc_page_fault+0xbd/0x2b0
<6> [585.819605] entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [585.819608] RIP: 0033:0x7630f5b1c5a4
<6> [585.819610] RSP: 002b:00007ffdd543df38 EFLAGS: 00000202 ORIG_RAX: 0000000000000001
<6> [585.819614] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007630f5b1c5a4
<6> [585.819616] RDX: 0000000000000005 RSI: 00007ffdd543e10a RDI: 0000000000000003
<6> [585.819617] RBP: 00007ffdd543df60 R08: 00007630f5c02228 R09: 0000000000000000
<6> [585.819619] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000005
<6> [585.819621] R13: 00007ffdd543e10a R14: 000062717ecd62d0 R15: 0000000000000005
<6> [585.819630] </TASK>
<3> [585.819633] INFO: task (sa1):2870 is blocked on a mutex likely owned by task systemd:1.
<4> [585.827637]
Showing all locks held in the system:
<4> [585.827639] 5 locks held by systemd/1:
<4> [585.827641] #0: ffff8881134fc410 (sb_writers#8){.+.+}-{0:0}, at: ksys_write+0x6f/0xf0
<4> [585.827648] #1: ffff888120b62c80 (&of->mutex#2){+.+.}-{3:3}, at: kernfs_fop_write_iter+0x127/0x240
<4> [585.827654] #2: ffffffff8369abc0 (cgroup_mutex){+.+.}-{3:3}, at: cgroup_kn_lock_live+0x4f/0x250
<4> [585.827659] #3: ffffffff83487c70 (cpu_hotplug_lock){++++}-{0:0}, at: cgroup_procs_write_start+0x227/0x3f0
<4> [585.827664] #4: ffffffff8369a9d0 (cgroup_threadgroup_rwsem){++++}-{0:0}, at: cgroup_procs_write_start+0x233/0x3f0
<4> [585.827677] 1 lock held by khungtaskd/157:
<4> [585.827678] #0: ffffffff835c4060 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x37/0x220
<4> [585.827703] 1 lock held by in:imklog/933:
<4> [585.827704] #0: ffff888132404930 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0
<4> [585.827723] 1 lock held by dmesg/2396:
<4> [585.827724] #0: ffff88812ebec0c8 (&user->lock){+.+.}-{3:3}, at: devkmsg_read+0x5a/0x210
<4> [585.827738] 2 locks held by gem_busy/2854:
<4> [585.827740] 3 locks held by (sa1)/2870:
<4> [585.827740] #0: ffff8881134fc410 (sb_writers#8){.+.+}-{0:0}, at: ksys_write+0x6f/0xf0
<4> [585.827746] #1: ffff888120b65680 (&of->mutex#2){+.+.}-{3:3}, at: kernfs_fop_write_iter+0x127/0x240
<4> [585.827751] #2: ffffffff8369abc0 (cgroup_mutex){+.+.}-{3:3}, at: cgroup_kn_lock_live+0x4f/0x250
<4> [585.827757]
<4> [585.827758] =============================================
<3> [639.417530] rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
<3> [639.423669] rcu: Tasks blocked on level-1 rcu_node (CPUs 11-21): P2854
<3> [639.430286] rcu: (detected by 17, t=260039 jiffies, g=151113, q=247000 ncpus=22)
<6> [639.437759] task:gem_busy state:R running task stack:0 pid:2854 tgid:2854 ppid:2842 task_flags:0x400040 flags:0x00080802
<6> [639.437766] Call Trace:
<6> [639.437768] <TASK>
<6> [639.437770] ? ktime_expiry_to_cycles+0x43/0x140
<6> [639.437783] ? ktime_expiry_to_cycles+0x43/0x140
<6> [639.437788] find_held_lock+0x31/0x90
<6> [639.437796] ? __hrtimer_rearm_deferred+0xc5/0x1f0
<6> [639.437800] lock_release+0xd0/0x2b0
<6> [639.437805] ? sysvec_apic_timer_interrupt+0x57/0xc0
<6> [639.437813] irqentry_exit+0x2a2/0x870
<6> [639.437817] ? sysvec_apic_timer_interrupt+0x57/0xc0
<6> [639.437820] ? trace_hardirqs_on+0x22/0xf0
<6> [639.437829] ? irqentry_exit+0x2a2/0x870
<6> [639.437832] ? trace_hardirqs_off_finish+0xcf/0xf0
<6> [639.437837] ? sysvec_apic_timer_interrupt+0x57/0xc0
<6> [639.437841] ? asm_sysvec_apic_timer_interrupt+0x1b/0x20
<6> [639.437856] ? dma_resv_list_entry+0x7a/0xd0
<6> [639.437865] ? dma_resv_iter_walk_unlocked.part.0+0x65/0x1d0
<6> [639.437869] ? dma_resv_iter_first_unlocked+0x69/0x290
<6> [639.437876] ? i915_gem_busy_ioctl+0xb8/0xb80 [i915]
<6> [639.438206] ? __pfx_i915_gem_busy_ioctl+0x10/0x10 [i915]
<6> [639.438410] ? drm_ioctl_kernel+0xb3/0x120
<6> [639.438421] ? drm_ioctl+0x2d4/0x5a0
<6> [639.438424] ? __pfx_i915_gem_busy_ioctl+0x10/0x10 [i915]
<6> [639.438621] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [639.438633] ? __x64_sys_ioctl+0xa5/0x100
<6> [639.438641] ? x64_sys_call+0x1250/0x26e0
<6> [639.438648] ? do_syscall_64+0x103/0x6d0
<6> [639.438654] ? trace_hardirqs_on_prepare+0xd7/0xf0
<6> [639.438659] ? do_syscall_64+0x34/0x6d0
<6> [639.438661] ? entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [639.438664] ? trace_hardirqs_on+0x22/0xf0
<6> [639.438668] ? do_syscall_64+0xb8/0x6d0
<6> [639.438673] ? entry_SYSCALL_64_after_hwframe+0x76/0x7e
<6> [639.438684] </TASK>
|