[Bug 101074] [BAT][CTG] igt at kms_pipe_crc_basic@suspend-read-crc-pipe-* tests take 4x longer than expected

bugzilla-daemon at freedesktop.org bugzilla-daemon at freedesktop.org
Wed May 17 13:45:04 UTC 2017


https://bugs.freedesktop.org/show_bug.cgi?id=101074

--- Comment #2 from Tomi Sarvela <tomi.p.sarvela at intel.com> ---
Probably related to locking issue in MOUSE_PS2. Possibly relevant splat from
CI_DRM_2614:

MOUSE_PS2 disabled from kconfig/debug for now.

[  365.524011] [drm:drm_dp_dual_mode_detect] DP dual mode HDMI ID:  (err -6)
[  365.524011] [drm:drm_helper_hpd_irq_event] [CONNECTOR:57:HDMI-A-2] status
updated from disconnected to disconnected
[  365.524011] [drm:intel_dp_detect [i915]] [CONNECTOR:59:DP-2]
[  365.535130] [drm:intel_dp_aux_ch [i915]] dp_aux_ch timeout status 0x51450085
[  365.545347] [drm:intel_dp_aux_ch [i915]] dp_aux_ch timeout status 0x51450085

[  365.545014] ======================================================
[  365.545014] WARNING: possible circular locking dependency detected
[  365.545014] 4.12.0-rc1-CI-CI_DRM_2614+ #1 Not tainted
[  365.545014] ------------------------------------------------------
[  365.545014] kworker/0:1/22 is trying to acquire lock:
[  365.545014]  (s_active#62){++++.+}, at: [<ffffffff8127d060>]
kernfs_remove_by_name_ns+0x40/0x90
[  365.545014] 
               but task is already holding lock:
[  365.545014]  (psmouse_mutex){+.+.+.}, at: [<ffffffff816a7ea7>]
psmouse_disconnect+0x67/0x160
[  365.545014] 
               which lock already depends on the new lock.

[  365.545014] 
               the existing dependency chain (in reverse order) is:
[  365.545014] 
               -> #1 (psmouse_mutex){+.+.+.}:
[  365.545014]        lock_acquire+0xb5/0x210
[  365.545014]        __mutex_lock+0x83/0x980
[  365.545014]        mutex_lock_interruptible_nested+0x16/0x20
[  365.545014]        psmouse_attr_set_helper+0x2c/0x140
[  365.545014]        dev_attr_store+0x13/0x20
[  365.545014]        sysfs_kf_write+0x40/0x50
[  365.545014]        kernfs_fop_write+0x130/0x1b0
[  365.545014]        __vfs_write+0x23/0x120
[  365.545014]        vfs_write+0xc5/0x1d0
[  365.545014]        SyS_write+0x44/0xb0
[  365.545014]        entry_SYSCALL_64_fastpath+0x1c/0xb1
[  365.545014] 
               -> #0 (s_active#62){++++.+}:
[  365.545014]        __lock_acquire+0x189f/0x1960
[  365.545014]        lock_acquire+0xb5/0x210
[  365.545014]        __kernfs_remove+0x243/0x2b0
[  365.545014]        kernfs_remove_by_name_ns+0x40/0x90
[  365.545014]        remove_files.isra.0+0x31/0x70
[  365.545014]        sysfs_remove_group+0x3f/0x80
[  365.545014]        trackpoint_disconnect+0x1f/0x40
[  365.545014]        psmouse_disconnect+0x95/0x160
[  365.545014]        serio_disconnect_driver+0x2d/0x40
[  365.545014]        serio_driver_remove+0x10/0x20
[  365.545014]        device_release_driver_internal+0x158/0x210
[  365.545014]        device_release_driver+0xd/0x10
[  365.545014]        serio_disconnect_port+0x75/0xb0
[  365.545014]        serio_handle_event+0x1c6/0x260
[  365.545014]        process_one_work+0x1fe/0x670
[  365.545014]        worker_thread+0x49/0x3b0
[  365.545014]        kthread+0x10f/0x150
[  365.545014]        ret_from_fork+0x2e/0x40
[  365.545014] 
               other info that might help us debug this:

[  365.545014]  Possible unsafe locking scenario:

[  365.545014]        CPU0                    CPU1
[  365.545014]        ----                    ----
[  365.545014]   lock(psmouse_mutex);
[  365.545014]                                lock(s_active#62);
[  365.545014]                                lock(psmouse_mutex);
[  365.545014]   lock(s_active#62);
[  365.545014] 
                *** DEADLOCK ***

[  365.545014] 6 locks held by kworker/0:1/22:
[  365.545014]  #0:  ("events_long"){.+.+.+}, at: [<ffffffff8109a138>]
process_one_work+0x178/0x670
[  365.545014]  #1:  (serio_event_work){+.+.+.}, at: [<ffffffff8109a138>]
process_one_work+0x178/0x670
[  365.545014]  #2:  (serio_mutex){+.+.+.}, at: [<ffffffff816982ef>]
serio_handle_event+0x1f/0x260
[  365.545014]  #3:  (&dev->mutex){......}, at: [<ffffffff815b9d34>]
device_release_driver_internal+0x34/0x210
[  365.545014]  #4:  (&serio->drv_mutex){+.+.+.}, at: [<ffffffff8169734b>]
serio_disconnect_driver+0x1b/0x40
[  365.545014]  #5:  (psmouse_mutex){+.+.+.}, at: [<ffffffff816a7ea7>]
psmouse_disconnect+0x67/0x160
[  365.545014] 
               stack backtrace:
[  365.545014] CPU: 0 PID: 22 Comm: kworker/0:1 Not tainted
4.12.0-rc1-CI-CI_DRM_2614+ #1
[  365.545014] Hardware name: LENOVO 7465CTO/7465CTO, BIOS 6DET44WW (2.08 )
04/22/2009
[  365.545014] Workqueue: events_long serio_handle_event
[  365.545014] Call Trace:
[  365.545014]  dump_stack+0x67/0x97
[  365.545014]  print_circular_bug+0x1e0/0x2d0
[  365.545014]  __lock_acquire+0x189f/0x1960
[  365.545014]  ? kernfs_remove_by_name_ns+0x25/0x90
[  365.545014]  ? __kernfs_remove+0x18b/0x2b0
[  365.545014]  lock_acquire+0xb5/0x210
[  365.545014]  ? lock_acquire+0xb5/0x210
[  365.545014]  ? kernfs_remove_by_name_ns+0x40/0x90
[  365.545014]  __kernfs_remove+0x243/0x2b0
[  365.545014]  ? kernfs_remove_by_name_ns+0x40/0x90
[  365.545014]  ? kernfs_find_ns+0x8f/0x110
[  365.545014]  kernfs_remove_by_name_ns+0x40/0x90
[  365.545014]  remove_files.isra.0+0x31/0x70
[  365.545014]  sysfs_remove_group+0x3f/0x80
[  365.545014]  trackpoint_disconnect+0x1f/0x40
[  365.545014]  psmouse_disconnect+0x95/0x160
[  365.545014]  serio_disconnect_driver+0x2d/0x40
[  365.545014]  serio_driver_remove+0x10/0x20
[  365.545014]  device_release_driver_internal+0x158/0x210
[  365.545014]  device_release_driver+0xd/0x10
[  365.545014]  serio_disconnect_port+0x75/0xb0
[  365.545014]  serio_handle_event+0x1c6/0x260
[  365.545014]  process_one_work+0x1fe/0x670
[  365.545014]  worker_thread+0x49/0x3b0
[  365.545014]  kthread+0x10f/0x150
[  365.545014]  ? process_one_work+0x670/0x670
[  365.545014]  ? kthread_create_on_node+0x40/0x40
[  365.545014]  ret_from_fork+0x2e/0x40
[  365.555007] [drm:intel_dp_aux_ch [i915]] dp_aux_ch timeout status 0x51450085
[  365.565142] [drm:intel_dp_aux_ch [i915]] dp_aux_ch timeout status 0x51450085
[  365.575283] [drm:intel_dp_aux_ch [i915]] dp_aux_ch timeout status 0x51450085
[  365.585422] [drm:intel_dp_aux_ch [i915]] dp_aux_ch timeout status 0x51450085
[  365.589462] PM: restore of devices complete after 582.090 msecs
[  365.595030] PM: Image restored successfully.
[  365.595030] PM: Basic memory bitmaps freed
[  365.595030] OOM killer enabled.
[  365.595030] Restarting tasks ... 
[  365.595442] [drm:intel_dp_aux_ch [i915]] dp_aux_ch timeout status 0x51450085
[  365.611007] [drm:intel_dp_aux_ch [i915]] dp_aux_ch timeout status 0x51450085
[  365.611007] done.
[  365.619008] video LNXVIDEO:00: Restoring backlight state

-- 
You are receiving this mail because:
You are the assignee for the bug.
You are the QA Contact for the bug.
You are on the CC list for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/intel-gfx-bugs/attachments/20170517/05844151/attachment-0001.html>


More information about the intel-gfx-bugs mailing list