Attachment 130483 Details for Bug 100417 – BSW kms_flip@vblank-vs-dpms-suspend-interruptible

BSW kms_flip@vblank-vs-dpms-suspend-interruptible

bsw_nuc-kms_flip@vblank-vs-dpms-suspend-interruptible.txt (text/plain), 25.76 KB, created by Tomi Sarvela on 2017-03-27 14:53:14 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Tomi Sarvela

Created: 2017-03-27 14:53:14 UTC

Size: 25.76 KB

patch

obsolete

>$ cat dmesg-Panic#1.log 
><7>[ 1049.254729] [drm:intel_dp_detect [i915]] [CONNECTOR:54:DP-1]
><7>[ 1049.254920] [drm:i915_redisable_vga_power_on [i915]] Something enabled VGA plane, disabling it
><7>[ 1049.255377] [drm:intel_power_well_enable [i915]] enabling dpio-common-bc
><7>[ 1049.257918] [drm:chv_dpio_cmn_power_well_enable [i915]] Enabled DPIO PHY0 (PHY_CONTROL=0x050007fd)
><7>[ 1049.260215] [drm:intel_power_well_enable [i915]] enabling dpio-common-d
><7>[ 1049.262671] [drm:chv_dpio_cmn_power_well_enable [i915]] Enabled DPIO PHY1 (PHY_CONTROL=0x050007ff)
><7>[ 1049.264926] [drm:drm_helper_hpd_irq_event] [CONNECTOR:54:DP-1] status updated from disconnected to disconnected
><7>[ 1049.265022] [drm:intel_hdmi_detect [i915]] [CONNECTOR:58:HDMI-A-1]
><7>[ 1049.267243] [drm:gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0050 w(1)
><7>[ 1049.267328] [drm:gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
><7>[ 1049.268639] [drm:gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0050 w(1)
><7>[ 1049.268665] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpb
><7>[ 1049.269610] [drm:intel_atomic_check [i915]] New cdclk calculated to be logical 200000 kHz, actual 200000 kHz
><7>[ 1049.269935] [drm:intel_atomic_commit_tail [i915]] [ENCODER:53:DP B]
><7>[ 1049.270031] [drm:intel_atomic_commit_tail [i915]] [ENCODER:57:HDMI B]
><7>[ 1049.270125] [drm:intel_atomic_commit_tail [i915]] [ENCODER:60:DP D]
><7>[ 1049.270218] [drm:intel_atomic_commit_tail [i915]] [ENCODER:62:HDMI D]
><7>[ 1049.270326] [drm:verify_connector_state.isra.47 [i915]] [CONNECTOR:63:HDMI-A-2]
><7>[ 1049.270444] [drm:intel_atomic_commit_tail [i915]] [CRTC:52:pipe C]
><7>[ 1049.270633] [drm:gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
><7>[ 1049.270725] [drm:gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
><7>[ 1049.272920] [drm:gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
><7>[ 1049.272946] [drm:drm_dp_dual_mode_detect] DP dual mode HDMI ID:  (err -6)
><7>[ 1049.272966] [drm:drm_helper_hpd_irq_event] [CONNECTOR:58:HDMI-A-1] status updated from disconnected to disconnected
><7>[ 1049.273062] [drm:intel_dp_detect [i915]] [CONNECTOR:61:DP-2]
><7>[ 1049.273091] [drm:drm_helper_hpd_irq_event] [CONNECTOR:61:DP-2] status updated from disconnected to disconnected
><7>[ 1049.273183] [drm:intel_hdmi_detect [i915]] [CONNECTOR:63:HDMI-A-2]
><7>[ 1049.315880] [drm:gmbus_xfer [i915]] GMBUS [i915 gmbus panel] NAK for addr: 0040 w(1)
><7>[ 1049.316110] [drm:gmbus_xfer [i915]] GMBUS [i915 gmbus panel] NAK on first message, retry
><7>[ 1049.318528] [drm:gmbus_xfer [i915]] GMBUS [i915 gmbus panel] NAK for addr: 0040 w(1)
><7>[ 1049.318756] [drm:drm_dp_dual_mode_detect] DP dual mode HDMI ID:  (err -6)
><7>[ 1049.318811] [drm:drm_helper_hpd_irq_event] [CONNECTOR:63:HDMI-A-2] status updated from connected to connected
><6>[ 1049.470301] PM: suspend of devices complete after 228.507 msecs
><7>[ 1049.481275] [drm:intel_power_well_disable [i915]] disabling dpio-common-d
><7>[ 1049.481408] [drm:chv_dpio_cmn_power_well_disable [i915]] Disabled DPIO PHY1 (PHY_CONTROL=0x050007fd)
><7>[ 1049.481575] [drm:intel_power_well_disable [i915]] disabling dpio-common-bc
><7>[ 1049.484311] [drm:chv_dpio_cmn_power_well_disable [i915]] Disabled DPIO PHY0 (PHY_CONTROL=0x050007fc)
><7>[ 1049.484477] [drm:intel_power_well_disable [i915]] disabling display
><7>[ 1049.484732] [drm:intel_power_well_disable [i915]] disabling always-on
><6>[ 1049.499250] PM: late suspend of devices complete after 28.905 msecs
><6>[ 1049.510925] r8169 0000:03:00.0: System wakeup enabled by ACPI
><6>[ 1049.515754] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
><6>[ 1049.535805] PM: noirq suspend of devices complete after 36.529 msecs
><6>[ 1049.535890] ACPI: Preparing to enter system sleep state S3
><6>[ 1049.548570] PM: Saving platform NVS memory
><6>[ 1049.548698] Disabling non-boot CPUs ...
><5>[ 1049.569912] Broke affinity for irq 9
><5>[ 1049.570040] Broke affinity for irq 115
><5>[ 1049.570067] Broke affinity for irq 116
><5>[ 1049.570093] Broke affinity for irq 117
><5>[ 1049.570119] Broke affinity for irq 118
><6>[ 1049.571735] smpboot: CPU 1 is now offline
><6>[ 1049.574812] ACPI: Low-level resume complete
><6>[ 1049.575054] PM: Restoring platform NVS memory
><6>[ 1049.575732] Suspended for 20.377 seconds
><6>[ 1049.575811] Enabling non-boot CPUs ...
><6>[ 1049.576069] x86: Booting SMP configuration:
><6>[ 1049.576080] smpboot: Booting Node 0 Processor 1 APIC 0x4
><3>[ 1049.576313] BUG: scheduling while atomic: swapper/1/0/0x00000002
><4>[ 1049.576321] no locks held by swapper/1/0.
><4>[ 1049.576325] Modules linked in: snd_hda_intel i915 vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_codec snd_hwdep snd_hda_core r8169 mii lpc_ich snd_pcm sdhci_pci i2c_designware_pci prime_numbers i2c_hid i2c_designware_platform i2c_designware_core [last unloaded: i915]
><3>[ 1049.576439] Preemption disabled at:
><4>[ 1049.576453] [<ffffffff8103d0fd>] start_secondary+0x1d/0x120
><4>[ 1049.576465] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1049.576471] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1049.576477] Call Trace:
><4>[ 1049.576491]  dump_stack+0x67/0x92
><4>[ 1049.576499]  ? start_secondary+0x1d/0x120
><4>[ 1049.576507]  __schedule_bug+0x7e/0xe0
><4>[ 1049.576517]  __schedule+0x88e/0xbc0
><4>[ 1049.576529]  schedule+0x3b/0x90
><4>[ 1049.576538]  schedule_hrtimeout_range_clock+0xd1/0x150
><4>[ 1049.576548]  ? __hrtimer_run_queues+0x530/0x530
><4>[ 1049.576559]  schedule_hrtimeout+0x12/0x20
><4>[ 1049.576566]  wait_task_inactive+0x288/0x290
><4>[ 1049.576581]  __kthread_bind_mask+0x19/0x70
><4>[ 1049.576589]  kthread_unpark+0x62/0x90
><4>[ 1049.576599]  cpuhp_online_idle+0x50/0x90
><4>[ 1049.576609]  cpu_startup_entry+0x13/0x20
><4>[ 1049.576616]  start_secondary+0x102/0x120
><4>[ 1049.576624]  start_cpu+0x14/0x14
><4>[ 1049.576649] ------------[ cut here ]------------
><4>[ 1049.576655] WARNING: CPU: 1 PID: 0 at kernel/locking/lockdep.c:3548 lock_release+0x4e1/0x4f0
><4>[ 1049.576658] releasing a pinned lock
><4>[ 1049.576659] Modules linked in: snd_hda_intel i915 vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_codec snd_hwdep snd_hda_core r8169 mii lpc_ich snd_pcm sdhci_pci i2c_designware_pci prime_numbers i2c_hid i2c_designware_platform i2c_designware_core [last unloaded: i915]
><4>[ 1049.576690] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1049.576692] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1049.576693] Call Trace:
><4>[ 1049.576697]  dump_stack+0x67/0x92
><4>[ 1049.576701]  __warn+0xc6/0xe0
><4>[ 1049.576704]  ? dequeue_task_idle+0xd/0x30
><4>[ 1049.576708]  warn_slowpath_fmt+0x4a/0x50
><4>[ 1049.576712]  lock_release+0x4e1/0x4f0
><4>[ 1049.576719]  _raw_spin_unlock_irq+0x1a/0x50
><4>[ 1049.576722]  dequeue_task_idle+0xd/0x30
><4>[ 1049.576726]  deactivate_task+0x94/0x120
><4>[ 1049.576729]  __schedule+0x4c1/0xbc0
><4>[ 1049.576733]  schedule+0x3b/0x90
><4>[ 1049.576736]  schedule_hrtimeout_range_clock+0xd1/0x150
><4>[ 1049.576740]  ? __hrtimer_run_queues+0x530/0x530
><4>[ 1049.576744]  schedule_hrtimeout+0x12/0x20
><4>[ 1049.576747]  wait_task_inactive+0x288/0x290
><4>[ 1049.576752]  __kthread_bind_mask+0x19/0x70
><4>[ 1049.576755]  kthread_unpark+0x62/0x90
><4>[ 1049.576759]  cpuhp_online_idle+0x50/0x90
><4>[ 1049.576762]  cpu_startup_entry+0x13/0x20
><4>[ 1049.576765]  start_secondary+0x102/0x120
><4>[ 1049.576769]  start_cpu+0x14/0x14
><4>[ 1049.576774] ---[ end trace 0e6a59f5af8d5d08 ]---
><3>[ 1049.576777] bad: scheduling from the idle thread!
><4>[ 1049.576783] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1049.576788] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1049.576791] Call Trace:
><4>[ 1049.576799]  dump_stack+0x67/0x92
><4>[ 1049.576807]  dequeue_task_idle+0x1e/0x30
><4>[ 1049.576814]  deactivate_task+0x94/0x120
><4>[ 1049.576822]  __schedule+0x4c1/0xbc0
><4>[ 1049.576834]  schedule+0x3b/0x90
><4>[ 1049.576842]  schedule_hrtimeout_range_clock+0xd1/0x150
><4>[ 1049.576850]  ? __hrtimer_run_queues+0x530/0x530
><4>[ 1049.576860]  schedule_hrtimeout+0x12/0x20
><4>[ 1049.576867]  wait_task_inactive+0x288/0x290
><4>[ 1049.576880]  __kthread_bind_mask+0x19/0x70
><4>[ 1049.576888]  kthread_unpark+0x62/0x90
><4>[ 1049.576896]  cpuhp_online_idle+0x50/0x90
><4>[ 1049.576903]  cpu_startup_entry+0x13/0x20
><4>[ 1049.576910]  start_secondary+0x102/0x120
><4>[ 1049.576917]  start_cpu+0x14/0x14
><4>[ 1049.576937] ------------[ cut here ]------------
><4>[ 1049.576942] WARNING: CPU: 1 PID: 0 at kernel/locking/lockdep.c:3670 lock_unpin_lock+0x15b/0x160
><4>[ 1049.576943] unpinning an unpinned lock
><4>[ 1049.576944] Modules linked in: snd_hda_intel i915 vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_codec snd_hwdep snd_hda_core r8169 mii lpc_ich snd_pcm sdhci_pci i2c_designware_pci prime_numbers i2c_hid i2c_designware_platform i2c_designware_core [last unloaded: i915]
><4>[ 1049.576975] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1049.576977] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1049.576978] Call Trace:
><4>[ 1049.576982]  dump_stack+0x67/0x92
><4>[ 1049.576986]  __warn+0xc6/0xe0
><4>[ 1049.576990]  warn_slowpath_fmt+0x4a/0x50
><4>[ 1049.576994]  lock_unpin_lock+0x15b/0x160
><4>[ 1049.576998]  __schedule+0x3d4/0xbc0
><4>[ 1049.577002]  schedule+0x3b/0x90
><4>[ 1049.577006]  schedule_hrtimeout_range_clock+0xd1/0x150
><4>[ 1049.577009]  ? __hrtimer_run_queues+0x530/0x530
><4>[ 1049.577013]  schedule_hrtimeout+0x12/0x20
><4>[ 1049.577016]  wait_task_inactive+0x288/0x290
><4>[ 1049.577021]  __kthread_bind_mask+0x19/0x70
><4>[ 1049.577024]  kthread_unpark+0x62/0x90
><4>[ 1049.577028]  cpuhp_online_idle+0x50/0x90
><4>[ 1049.577031]  cpu_startup_entry+0x13/0x20
><4>[ 1049.577034]  start_secondary+0x102/0x120
><4>[ 1049.577038]  start_cpu+0x14/0x14
><4>[ 1049.577042] ---[ end trace 0e6a59f5af8d5d09 ]---
><4>[ 1049.577932] ------------[ cut here ]------------
><4>[ 1049.578310] WARNING: CPU: 1 PID: 0 at kernel/sched/sched.h:833 set_next_entity+0xb22/0xfe0
><4>[ 1049.578313] rq->clock_update_flags < RQCF_ACT_SKIP
><4>[ 1049.578315] Modules linked in: snd_hda_intel i915 vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_codec snd_hwdep snd_hda_core r8169 mii lpc_ich snd_pcm sdhci_pci i2c_designware_pci prime_numbers i2c_hid i2c_designware_platform i2c_designware_core [last unloaded: i915]
><4>[ 1049.578386] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1049.578389] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1049.578390] Call Trace:
><4>[ 1049.578395]  dump_stack+0x67/0x92
><4>[ 1049.578400]  __warn+0xc6/0xe0
><4>[ 1049.578406]  warn_slowpath_fmt+0x4a/0x50
><4>[ 1049.578413]  set_next_entity+0xb22/0xfe0
><4>[ 1049.578420]  pick_next_task_fair+0x76/0x840
><4>[ 1049.578425]  ? debug_smp_processor_id+0x17/0x20
><4>[ 1049.578429]  ? prandom_u32+0x39/0x60
><4>[ 1049.578436]  __schedule+0x11c/0xbc0
><4>[ 1049.578442]  ? preempt_schedule+0x1f/0x30
><4>[ 1049.578446]  preempt_schedule_common+0x37/0x90
><4>[ 1049.578451]  preempt_schedule+0x1f/0x30
><4>[ 1049.578455]  ___preempt_schedule+0x16/0x18
><4>[ 1049.578462]  _raw_spin_unlock_irqrestore+0x5b/0x60
><4>[ 1049.578467]  try_to_wake_up+0x25a/0x650
><4>[ 1049.578472]  ? _raw_spin_unlock_irqrestore+0x3d/0x60
><4>[ 1049.578478]  wake_up_state+0xb/0x10
><4>[ 1049.578482]  kthread_unpark+0x6f/0x90
><4>[ 1049.578486]  cpuhp_online_idle+0x50/0x90
><4>[ 1049.578491]  cpu_startup_entry+0x13/0x20
><4>[ 1049.578495]  start_secondary+0x102/0x120
><4>[ 1049.578500]  start_cpu+0x14/0x14
><4>[ 1049.578509] ---[ end trace 0e6a59f5af8d5d0a ]---
><4>[ 1049.578515] ------------[ cut here ]------------
><4>[ 1049.578520] WARNING: CPU: 1 PID: 0 at kernel/sched/sched.h:833 __schedule+0x965/0xbc0
><4>[ 1049.578522] rq->clock_update_flags < RQCF_ACT_SKIP
><4>[ 1049.578524] Modules linked in: snd_hda_intel i915 vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_codec snd_hwdep snd_hda_core r8169 mii lpc_ich snd_pcm sdhci_pci i2c_designware_pci prime_numbers i2c_hid i2c_designware_platform i2c_designware_core [last unloaded: i915]
><4>[ 1049.578573] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1049.578576] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1049.578577] Call Trace:
><4>[ 1049.578582]  dump_stack+0x67/0x92
><4>[ 1049.578587]  __warn+0xc6/0xe0
><4>[ 1049.578592]  warn_slowpath_fmt+0x4a/0x50
><4>[ 1049.578599]  __schedule+0x965/0xbc0
><4>[ 1049.578605]  ? preempt_schedule+0x1f/0x30
><4>[ 1049.578609]  preempt_schedule_common+0x37/0x90
><4>[ 1049.578614]  preempt_schedule+0x1f/0x30
><4>[ 1049.578619]  ___preempt_schedule+0x16/0x18
><4>[ 1049.578626]  _raw_spin_unlock_irqrestore+0x5b/0x60
><4>[ 1049.578630]  try_to_wake_up+0x25a/0x650
><4>[ 1049.578635]  ? _raw_spin_unlock_irqrestore+0x3d/0x60
><4>[ 1049.578641]  wake_up_state+0xb/0x10
><4>[ 1049.578645]  kthread_unpark+0x6f/0x90
><4>[ 1049.578650]  cpuhp_online_idle+0x50/0x90
><4>[ 1049.578654]  cpu_startup_entry+0x13/0x20
><4>[ 1049.578658]  start_secondary+0x102/0x120
><4>[ 1049.578663]  start_cpu+0x14/0x14
><4>[ 1049.578671] ---[ end trace 0e6a59f5af8d5d0b ]---
><4>[ 1049.578681] 
><4>[ 1049.578683] ======================================================
><4>[ 1049.578684] [ INFO: possible circular locking dependency detected ]
><4>[ 1049.578685] 4.11.0-rc4-CI-CI_DRM_318+ #1 Tainted: G     U  W      
><4>[ 1049.578686] -------------------------------------------------------
><4>[ 1049.578687] swapper/1/0 is trying to acquire lock:
><4>[ 1049.578688]  ((console_sem).lock){-.-...}, at: [<ffffffff810d9cbf>] down_trylock+0xf/0x40
><4>[ 1049.578692] 
><4>[ 1049.578693] but task is already holding lock:
><4>[ 1049.578694]  (&rq->lock){-.-.-.}, at: [<ffffffff8188aa32>] __schedule+0xa2/0xbc0
><4>[ 1049.578697] 
><4>[ 1049.578698] which lock already depends on the new lock.
><4>[ 1049.578699] 
><4>[ 1049.578700] 
><4>[ 1049.578701] the existing dependency chain (in reverse order) is:
><4>[ 1049.578702] 
><4>[ 1049.578703] -> #2 (&rq->lock){-.-.-.}:
><4>[ 1049.578707]        lock_acquire+0xc9/0x220
><4>[ 1049.578708]        _raw_spin_lock+0x36/0x50
><4>[ 1049.578709]        task_fork_fair+0x35/0xf0
><4>[ 1049.578710]        sched_fork+0x254/0x400
><4>[ 1049.578711]        copy_process.part.6+0x589/0x2000
><4>[ 1049.578712]        _do_fork+0xc8/0x700
><4>[ 1049.578713]        kernel_thread+0x24/0x30
><4>[ 1049.578714]        rest_init+0x1d/0x130
><4>[ 1049.578715]        start_kernel+0x3f1/0x3fe
><4>[ 1049.578716]        x86_64_start_reservations+0x2a/0x2c
><4>[ 1049.578717]        x86_64_start_kernel+0x173/0x186
><4>[ 1049.578718]        verify_cpu+0x0/0xfc
><4>[ 1049.578719] 
><4>[ 1049.578719] -> #1 (&p->pi_lock){-.-.-.}:
><4>[ 1049.578723]        lock_acquire+0xc9/0x220
><4>[ 1049.578724]        _raw_spin_lock_irqsave+0x41/0x60
><4>[ 1049.578725]        try_to_wake_up+0x2b/0x650
><4>[ 1049.578726]        wake_up_process+0x10/0x20
><4>[ 1049.578727]        __up.isra.0+0x47/0x50
><4>[ 1049.578728]        up+0x41/0x50
><4>[ 1049.578730]        __up_console_sem+0x32/0x50
><4>[ 1049.578731]        console_unlock+0x260/0x530
><4>[ 1049.578732]        register_framebuffer+0x262/0x330
><4>[ 1049.578733]        drm_fb_helper_initial_config+0x232/0x410
><4>[ 1049.578734]        intel_fbdev_initial_config+0x13/0x30 [i915]
><4>[ 1049.578735]        async_run_entry_fn+0x34/0x160
><4>[ 1049.578736]        process_one_work+0x1f4/0x6d0
><4>[ 1049.578737]        worker_thread+0x49/0x4a0
><4>[ 1049.578738]        kthread+0x107/0x140
><4>[ 1049.578739]        ret_from_fork+0x2e/0x40
><4>[ 1049.578739] 
><4>[ 1049.578740] -> #0 ((console_sem).lock){-.-...}:
><4>[ 1049.578744]        __lock_acquire+0x1ac0/0x1bb0
><4>[ 1049.578745]        lock_acquire+0xc9/0x220
><4>[ 1049.578746]        _raw_spin_lock_irqsave+0x41/0x60
><4>[ 1049.578747]        down_trylock+0xf/0x40
><4>[ 1049.578748]        __down_trylock_console_sem+0x29/0x80
><4>[ 1049.578749]        console_trylock+0x11/0x80
><4>[ 1049.578750]        vprintk_emit+0x27a/0x380
><4>[ 1049.578751]        vprintk_default+0x1a/0x20
><4>[ 1049.578752]        vprintk_func+0x20/0x80
><4>[ 1049.578753]        printk+0x3e/0x46
><4>[ 1049.578754]        __warn+0x34/0xe0
><4>[ 1049.578755]        warn_slowpath_fmt+0x4a/0x50
><4>[ 1049.578756]        set_next_entity+0xb22/0xfe0
><4>[ 1049.578757]        pick_next_task_fair+0x76/0x840
><4>[ 1049.578758]        __schedule+0x11c/0xbc0
><4>[ 1049.578759]        preempt_schedule_common+0x37/0x90
><4>[ 1049.578760]        preempt_schedule+0x1f/0x30
><4>[ 1049.578761]        ___preempt_schedule+0x16/0x18
><4>[ 1049.578762]        _raw_spin_unlock_irqrestore+0x5b/0x60
><4>[ 1049.578763]        try_to_wake_up+0x25a/0x650
><4>[ 1049.578764]        wake_up_state+0xb/0x10
><4>[ 1049.578765]        kthread_unpark+0x6f/0x90
><4>[ 1049.578766]        cpuhp_online_idle+0x50/0x90
><4>[ 1049.578767]        cpu_startup_entry+0x13/0x20
><4>[ 1049.578767]        start_secondary+0x102/0x120
><4>[ 1049.578768]        verify_cpu+0x0/0xfc
><4>[ 1049.578769] 
><4>[ 1049.578770] other info that might help us debug this:
><4>[ 1049.578771] 
><4>[ 1049.578772] Chain exists of:
><4>[ 1049.578772]   (console_sem).lock --> &p->pi_lock --> &rq->lock
><4>[ 1049.578778] 
><4>[ 1049.578779]  Possible unsafe locking scenario:
><4>[ 1049.578779] 
><4>[ 1049.578780]        CPU0                    CPU1
><4>[ 1049.578781]        ----                    ----
><4>[ 1049.578782]   lock(&rq->lock);
><4>[ 1049.578785]                                lock(&p->pi_lock);
><4>[ 1049.578787]                                lock(&rq->lock);
><4>[ 1049.578789]   lock((console_sem).lock);
><4>[ 1049.578792] 
><4>[ 1049.578793]  *** DEADLOCK ***
><4>[ 1049.578793] 
><4>[ 1049.578794] 1 lock held by swapper/1/0:
><4>[ 1049.578795]  #0:  (&rq->lock){-.-.-.}, at: [<ffffffff8188aa32>] __schedule+0xa2/0xbc0
><4>[ 1049.578799] 
><4>[ 1049.578800] stack backtrace:
><4>[ 1049.578801] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1049.578803] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1049.578804] Call Trace:
><4>[ 1049.578805]  dump_stack+0x67/0x92
><4>[ 1049.578806]  print_circular_bug+0x1e0/0x2e0
><4>[ 1049.578807]  __lock_acquire+0x1ac0/0x1bb0
><4>[ 1049.578808]  ? pointer+0x29b/0x3f0
><4>[ 1049.578809]  lock_acquire+0xc9/0x220
><4>[ 1049.578809]  ? down_trylock+0xf/0x40
><4>[ 1049.578810]  ? vprintk_emit+0x27a/0x380
><4>[ 1049.578811]  _raw_spin_lock_irqsave+0x41/0x60
><4>[ 1049.578812]  ? down_trylock+0xf/0x40
><4>[ 1049.578813]  down_trylock+0xf/0x40
><4>[ 1049.578814]  __down_trylock_console_sem+0x29/0x80
><4>[ 1049.578815]  console_trylock+0x11/0x80
><4>[ 1049.578816]  ? trace_hardirqs_off+0xd/0x10
><4>[ 1049.578817]  vprintk_emit+0x27a/0x380
><4>[ 1049.578818]  ? set_next_entity+0xb22/0xfe0
><4>[ 1049.578819]  vprintk_default+0x1a/0x20
><4>[ 1049.578820]  vprintk_func+0x20/0x80
><4>[ 1049.578821]  printk+0x3e/0x46
><4>[ 1049.578822]  ? set_next_entity+0xb22/0xfe0
><4>[ 1049.578823]  __warn+0x34/0xe0
><4>[ 1049.578824]  warn_slowpath_fmt+0x4a/0x50
><4>[ 1049.578825]  set_next_entity+0xb22/0xfe0
><4>[ 1049.578826]  pick_next_task_fair+0x76/0x840
><4>[ 1049.578828]  ? debug_smp_processor_id+0x17/0x20
><4>[ 1049.578829]  ? prandom_u32+0x39/0x60
><4>[ 1049.578830]  __schedule+0x11c/0xbc0
><4>[ 1049.578831]  ? preempt_schedule+0x1f/0x30
><4>[ 1049.578832]  preempt_schedule_common+0x37/0x90
><4>[ 1049.578833]  preempt_schedule+0x1f/0x30
><4>[ 1049.578834]  ___preempt_schedule+0x16/0x18
><4>[ 1049.578835]  _raw_spin_unlock_irqrestore+0x5b/0x60
><4>[ 1049.578836]  try_to_wake_up+0x25a/0x650
><4>[ 1049.578837]  ? _raw_spin_unlock_irqrestore+0x3d/0x60
><4>[ 1049.578838]  wake_up_state+0xb/0x10
><4>[ 1049.578839]  kthread_unpark+0x6f/0x90
><4>[ 1049.578840]  cpuhp_online_idle+0x50/0x90
><4>[ 1049.578841]  cpu_startup_entry+0x13/0x20
><4>[ 1049.578842]  start_secondary+0x102/0x120
><4>[ 1049.578843]  start_cpu+0x14/0x14
><4>[ 1053.579064] ------------[ cut here ]------------
><4>[ 1053.579076] WARNING: CPU: 1 PID: 0 at kernel/sched/sched.h:833 pick_next_task_rt+0x250/0x260
><4>[ 1053.579079] rq->clock_update_flags < RQCF_ACT_SKIP
><4>[ 1053.579083] Modules linked in: snd_hda_intel i915 vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_codec snd_hwdep snd_hda_core r8169 mii lpc_ich snd_pcm sdhci_pci i2c_designware_pci prime_numbers i2c_hid i2c_designware_platform i2c_designware_core [last unloaded: i915]
><4>[ 1053.579160] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1053.579165] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1053.579168] Call Trace:
><4>[ 1053.579176]  dump_stack+0x67/0x92
><4>[ 1053.579182]  __warn+0xc6/0xe0
><4>[ 1053.579188]  warn_slowpath_fmt+0x4a/0x50
><4>[ 1053.579195]  pick_next_task_rt+0x250/0x260
><4>[ 1053.579202]  __schedule+0x160/0xbc0
><4>[ 1053.579210]  ? trace_hardirqs_on_thunk+0x1a/0x1c
><4>[ 1053.579216]  preempt_schedule_irq+0x3d/0x60
><4>[ 1053.579221]  retint_kernel+0x1b/0x2d
><4>[ 1053.579227] RIP: 0010:mwait_idle+0x93/0x210
><4>[ 1053.579232] RSP: 0000:ffffc9000007beb0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
><4>[ 1053.579237] RAX: 0000000000000000 RBX: ffff88017a830040 RCX: 0000000000000000
><4>[ 1053.579239] RDX: 0000000000000000 RSI: ffffffff81c74edd RDI: ffffffff8189111a
><4>[ 1053.579242] RBP: ffffc9000007bec0 R08: 0000000000000001 R09: 0000000000000000
><4>[ 1053.579244] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001
><4>[ 1053.579247] R13: ffff88017a830040 R14: ffff88017fd23a10 R15: ffffffff81ec59a0
><4>[ 1053.579255]  ? mwait_idle+0x8a/0x210
><4>[ 1053.579263]  arch_cpu_idle+0xa/0x10
><4>[ 1053.579268]  default_idle_call+0x1e/0x30
><4>[ 1053.579273]  do_idle+0x164/0x1f0
><4>[ 1053.579278]  cpu_startup_entry+0x18/0x20
><4>[ 1053.579284]  start_secondary+0x102/0x120
><4>[ 1053.579290]  start_cpu+0x14/0x14
><4>[ 1053.579298] ---[ end trace 0e6a59f5af8d5d0c ]---
><3>[ 1088.020376] INFO: task cpuhp/1:14 blocked for more than 30 seconds.
><3>[ 1088.020396]       Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><3>[ 1088.020405] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
><6>[ 1088.020418] cpuhp/1         D    0    14      2 0x00000000
><4>[ 1088.020442] Call Trace:
><4>[ 1088.020482]  __schedule+0x3fd/0xbc0
><4>[ 1088.020508]  schedule+0x3b/0x90
><4>[ 1088.020528]  _synchronize_rcu_expedited+0x2e2/0x370
><4>[ 1088.020545]  ? rcu_stall_kick_kthreads.part.44+0xd0/0xd0
><4>[ 1088.020561]  ? wake_atomic_t_function+0x30/0x30
><4>[ 1088.020576]  ? rcu_stall_kick_kthreads.part.44+0xd0/0xd0
><4>[ 1088.020593]  ? cond_synchronize_sched+0x20/0x20
><4>[ 1088.020617]  synchronize_sched_expedited+0x70/0xf0
><4>[ 1088.020631]  synchronize_rcu_bh+0x9a/0x120
><4>[ 1088.020655]  ? kmem_cache_alloc_trace+0x1f5/0x2e0
><4>[ 1088.020672]  ? pcrypt_cpumask_change_notify+0x3b/0x80
><4>[ 1088.020692]  pcrypt_cpumask_change_notify+0x55/0x80
><4>[ 1088.020713]  notifier_call_chain+0x34/0xa0
><4>[ 1088.020738]  __blocking_notifier_call_chain+0x48/0x70
><4>[ 1088.020757]  blocking_notifier_call_chain+0x11/0x20
><4>[ 1088.020775]  padata_replace+0x7f/0x110
><4>[ 1088.020794]  padata_cpu_online+0x8d/0xf0
><4>[ 1088.020815]  cpuhp_invoke_callback+0x4e3/0x8a0
><4>[ 1088.020835]  ? padata_replace+0x110/0x110
><4>[ 1088.020853]  cpuhp_up_callbacks+0x31/0xb0
><4>[ 1088.020872]  cpuhp_thread_fun+0xf9/0x110
><4>[ 1088.020887]  smpboot_thread_fn+0x180/0x280
><4>[ 1088.020906]  kthread+0x107/0x140
><4>[ 1088.020917]  ? sort_range+0x20/0x20
><4>[ 1088.020931]  ? kthread_create_on_node+0x40/0x40
><4>[ 1088.020953]  ret_from_fork+0x2e/0x40
><4>[ 1088.020983] INFO: lockdep is turned off.
><4>[ 1088.020994] NMI backtrace for cpu 0
><4>[ 1088.021012] CPU: 0 PID: 24 Comm: khungtaskd Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1088.021022] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1088.021029] Call Trace:
><4>[ 1088.021046]  dump_stack+0x67/0x92
><4>[ 1088.021064]  ? irq_force_complete_move+0xf0/0xf0
><4>[ 1088.021080]  nmi_cpu_backtrace+0x92/0xa0
><4>[ 1088.021094]  ? irq_force_complete_move+0xf0/0xf0
><4>[ 1088.021109]  nmi_trigger_cpumask_backtrace+0xc0/0x100
><4>[ 1088.021127]  arch_trigger_cpumask_backtrace+0x14/0x20
><4>[ 1088.021141]  watchdog+0x433/0x600
><4>[ 1088.021152]  ? watchdog+0x9c/0x600
><4>[ 1088.021171]  kthread+0x107/0x140
><4>[ 1088.021183]  ? reset_hung_task_detector+0x10/0x10
><4>[ 1088.021195]  ? kthread_create_on_node+0x40/0x40
><4>[ 1088.021215]  ret_from_fork+0x2e/0x40
><6>[ 1088.021248] Sending NMI from CPU 0 to CPUs 1:
><4>[ 1088.021272] NMI backtrace for cpu 1 skipped: idling at pc 0xffffffff81891123
><0>[ 1088.022315] Kernel panic - not syncing: hung_task: blocked tasks
><4>[ 1088.022334] CPU: 0 PID: 24 Comm: khungtaskd Tainted: G     U  W       4.11.0-rc4-CI-CI_DRM_318+ #1
><4>[ 1088.022343] Hardware name:                  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
><4>[ 1088.022350] Call Trace:
><4>[ 1088.022369]  dump_stack+0x67/0x92
><4>[ 1088.022385]  panic+0xcf/0x205
><4>[ 1088.022402]  ? irq_force_complete_move+0xf0/0xf0
><4>[ 1088.022423]  watchdog+0x43f/0x600
><4>[ 1088.022434]  ? watchdog+0x9c/0x600
><4>[ 1088.022454]  kthread+0x107/0x140
><4>[ 1088.022465]  ? reset_hung_task_detector+0x10/0x10
><4>[ 1088.022478]  ? kthread_create_on_node+0x40/0x40
><4>[ 1088.022497]  ret_from_fork+0x2e/0x40
><0>[ 1088.022547] Kernel Offset: disabled

Actions: View

Attachments on bug 100417: 130483

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.