Re: [drm-intel-nightly] 2016y-07m-14d-21h-13m-02s UTC: locking dependency: drm_modeset_lock_all() || __blocking_notifier_call_chain

Started by Jani Nikula on Aug 1, 2016 · 3 messages from 2 authors · Last reply Aug 3, 2016

open the first message on its own page

Jani Nikula

Message details and related content
Open message details and related content
On Fri, 15 Jul 2016, Sedat Dilek [off-list ref] wrote:
Hi,

I see the below call-trace with latest d-i-n, guess latest linux-next
FWIW, "d-i-n" is ambiguous (drm-intel-next vs. drm-intel-nightly) and we
don't use that ourselves.

BR,
Jani.


will cause same issues.
( Beyond this, there exist also a build failure which me and Stephen
have reported. )
The call-trace is reproducible with my setup and seen on every boot.

Not sure if this is a problem in intel-gfx or fbdev.

My linux-config and full dmesg-output is attached.

If you need additional informations, please let me know.

Regards,
- Sedat -

[1] https://lists.freedesktop.org/archives/intel-gfx/2016-July/100693.html
[2] https://lists.freedesktop.org/archives/intel-gfx/2016-July/100691.html

P.S.: INFO: possible circular locking dependency detected

[   16.955703] fbcon: inteldrmfb (fb0) is primary device
[   16.956579]
[   16.956580] ===========================
[   16.956581] [ INFO: possible circular locking dependency detected ]
[   16.956583] 4.7.0-rc7-4-iniza-small #1 Not tainted
[   16.956583] -------------------------------------------------------
[   16.956585] kworker/u16:1/79 is trying to acquire lock:
[   16.956618]  (&dev->mode_config.mutex){+.+.+.}, at:
[<ffffffffa00f2b30>] drm_modeset_lock_all+0x40/0x120 [drm]
[   16.956619]
[   16.956619] but task is already holding lock:
[   16.956626]  ((fb_notifier_list).rwsem){++++.+}, at:
[<ffffffff810aae25>] __blocking_notifier_call_chain+0x35/0x70
[   16.956627]
[   16.956627] which lock already depends on the new lock.
[   16.956627]
[   16.956627]
[   16.956627] the existing dependency chain (in reverse order) is:
[   16.956631]
[   16.956631] -> #1 ((fb_notifier_list).rwsem){++++.+}:
[   16.956635]        [<ffffffff810dff49>] lock_acquire+0x119/0x220

764 more quoted lines

6b, idProduct02
[    1.473212] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.473220] usb usb1: Product: EHCI Host Controller
[    1.473225] usb usb1: Manufacturer: Linux 4.7.0-rc7-4-iniza-small ehci_hcd
[    1.473231] usb usb1: SerialNumber: 0000:00:1a.0
[    1.474253] hub 1-0:1.0: USB hub found
[    1.474308] hub 1-0:1.0: 2 ports detected
[    1.475718] ehci-pci 0000:00:1d.0: EHCI Host Controller
[    1.475742] ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 2
[    1.475778] ehci-pci 0000:00:1d.0: debug port 2
[    1.479703] ehci-pci 0000:00:1d.0: cache line size of 64 is not supported
[    1.479747] ehci-pci 0000:00:1d.0: irq 23, io mem 0xf0709000
[    1.488911] ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00
[    1.489079] usb usb2: New USB device found, idVendor
6b, idProduct02
[    1.489086] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.489094] usb usb2: Product: EHCI Host Controller
[    1.489099] usb usb2: Manufacturer: Linux 4.7.0-rc7-4-iniza-small ehci_hcd
[    1.489104] usb usb2: SerialNumber: 0000:00:1d.0
[    1.489741] hub 2-0:1.0: USB hub found
[    1.489770] hub 2-0:1.0: 2 ports detected
[    1.490298] ehci-platform: EHCI generic platform driver
[    1.490344] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.490359] ohci-pci: OHCI PCI platform driver
[    1.490397] ohci-platform: OHCI generic platform driver
[    1.490426] uhci_hcd: USB Universal Host Controller Interface driver
[    1.490661] xhci_hcd 0000:03:00.0: xHCI Host Controller
[    1.490689] xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 3
[    1.500427] xhci_hcd 0000:03:00.0: hcc params 0x0200f180 hci version 0x96 quirks 0x00080000
[    1.501073] usb usb3: New USB device found, idVendor
6b, idProduct02
[    1.501081] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.501090] usb usb3: Product: xHCI Host Controller
[    1.501095] usb usb3: Manufacturer: Linux 4.7.0-rc7-4-iniza-small xhci-hcd
[    1.501102] usb usb3: SerialNumber: 0000:03:00.0
[    1.501748] hub 3-0:1.0: USB hub found
[    1.501827] hub 3-0:1.0: 2 ports detected
[    1.502294] xhci_hcd 0000:03:00.0: xHCI Host Controller
[    1.502312] xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 4
[    1.502443] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[    1.502545] usb usb4: New USB device found, idVendor
6b, idProduct03
[    1.502552] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.502560] usb usb4: Product: xHCI Host Controller
[    1.502565] usb usb4: Manufacturer: Linux 4.7.0-rc7-4-iniza-small xhci-hcd
[    1.502570] usb usb4: SerialNumber: 0000:03:00.0
[    1.503224] hub 4-0:1.0: USB hub found
[    1.503273] hub 4-0:1.0: 2 ports detected
[    1.503918] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:EPAD] at 0x60,0x64 irq 1,12
[    1.509828] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.509878] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.510766] mousedev: PS/2 mouse device common for all mice
[    1.512233] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0
[    1.512278] rtc_cmos 00:01: alarms up to one month, y3k, 242 bytes nvram, hpet irqs
[    1.519012] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[    1.537039] device-mapper: uevent: version 1.0.3
[    1.549317] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com
[    1.549378] ledtrig-cpu: registered to indicate activity on CPUs
[    1.621021] NET: Registered protocol family 10
[    1.633711] NET: Registered protocol family 17
[    1.633741] Key type dns_resolver registered
[    1.634700] microcode: CPU0 sig=0x206a7, pf=0x10, revision=0x28
[    1.634750] microcode: CPU1 sig=0x206a7, pf=0x10, revision=0x28
[    1.634776] microcode: CPU2 sig=0x206a7, pf=0x10, revision=0x28
[    1.634816] microcode: CPU3 sig=0x206a7, pf=0x10, revision=0x28
[    1.635094] microcode: Microcode Update Driver: v2.01 [off-list ref], Peter Oruba
[    1.636008] registered taskstats version 1
[    1.636018] Loading compiled-in X.509 certificates
[    1.643359] Loaded X.509 cert 'Build time autogenerated kernel key: 00b89b0b54e2e50c779dcb6b1667e75e5be8f532'
[    1.648048] Key type trusted registered
[    1.656281] Key type encrypted registered
[    1.656299] AppArmor: AppArmor sha1 policy hashing enabled
[    1.656306] evm: HMAC attrs: 0x1
[    1.657482] rtc_cmos 00:01: setting system clock to 2016-07-15 07:45:08 UTC (1468568708)
[    1.657685] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    1.657691] EDD information not available.
[    1.765348] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    1.766773] ata1.00: ATA-8: Hitachi HTS545050A7E380, GG2OA6C0, max UDMA/133
[    1.766783] ata1.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[    1.768393] ata1.00: configured for UDMA/133
[    1.769527] scsi 0:0:0:0: Direct-Access     ATA      Hitachi HTS54505 A6C0 PQ: 0 ANSI: 5
[    1.784971] usb 1-1: new high-speed USB device number 2 using ehci-pci

322 more quoted lines

-- 
Jani Nikula, Intel Open Source Technology Center

Sedat Dilek replied to Jani Nikula

Message details and related content
Open message details and related content
On Mon, Aug 1, 2016 at 3:33 PM, Jani Nikula [off-list ref] wrote:
On Fri, 15 Jul 2016, Sedat Dilek [off-list ref] wrote:
quoted
Hi,

I see the below call-trace with latest d-i-n, guess latest linux-next
FWIW, "d-i-n" is ambiguous (drm-intel-next vs. drm-intel-nightly) and we
don't use that ourselves.
Oh, sorry with d-i-n I meant drm-intel-nightly.
Normally, I also point to the "integration manifest"
like...drm-intel-nightly: 2016y-08m-02d-14h-10m-12s UTC integration
manifest.
You do not test d-i-n yourself?
Strange.
The Ubuntu kernel-team is regularly building packages see [1] if you
did not know.

- Sedat -

[1] http://kernel.ubuntu.com/~kernel-ppa/mainline/drm-intel-nightly/

BR,
Jani.
quoted
will cause same issues.
( Beyond this, there exist also a build failure which me and Stephen
have reported. )
The call-trace is reproducible with my setup and seen on every boot.

Not sure if this is a problem in intel-gfx or fbdev.

My linux-config and full dmesg-output is attached.

If you need additional informations, please let me know.

Regards,
- Sedat -

[1] https://lists.freedesktop.org/archives/intel-gfx/2016-July/100693.html
[2] https://lists.freedesktop.org/archives/intel-gfx/2016-July/100691.html

P.S.: INFO: possible circular locking dependency detected

[   16.955703] fbcon: inteldrmfb (fb0) is primary device
[   16.956579]
[   16.956580] ===========================
[   16.956581] [ INFO: possible circular locking dependency detected ]
[   16.956583] 4.7.0-rc7-4-iniza-small #1 Not tainted
[   16.956583] -------------------------------------------------------
[   16.956585] kworker/u16:1/79 is trying to acquire lock:
[   16.956618]  (&dev->mode_config.mutex){+.+.+.}, at:
[<ffffffffa00f2b30>] drm_modeset_lock_all+0x40/0x120 [drm]
[   16.956619]
[   16.956619] but task is already holding lock:
[   16.956626]  ((fb_notifier_list).rwsem){++++.+}, at:
[<ffffffff810aae25>] __blocking_notifier_call_chain+0x35/0x70
[   16.956627]
[   16.956627] which lock already depends on the new lock.
[   16.956627]
[   16.956627]

768 more quoted lines

6b, idProduct02
quoted
[    1.473212] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.473220] usb usb1: Product: EHCI Host Controller
[    1.473225] usb usb1: Manufacturer: Linux 4.7.0-rc7-4-iniza-small ehci_hcd
[    1.473231] usb usb1: SerialNumber: 0000:00:1a.0
[    1.474253] hub 1-0:1.0: USB hub found
[    1.474308] hub 1-0:1.0: 2 ports detected
[    1.475718] ehci-pci 0000:00:1d.0: EHCI Host Controller
[    1.475742] ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 2
[    1.475778] ehci-pci 0000:00:1d.0: debug port 2
[    1.479703] ehci-pci 0000:00:1d.0: cache line size of 64 is not supported
[    1.479747] ehci-pci 0000:00:1d.0: irq 23, io mem 0xf0709000
[    1.488911] ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00
[    1.489079] usb usb2: New USB device found, idVendor
6b, idProduct02
quoted
[    1.489086] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.489094] usb usb2: Product: EHCI Host Controller
[    1.489099] usb usb2: Manufacturer: Linux 4.7.0-rc7-4-iniza-small ehci_hcd
[    1.489104] usb usb2: SerialNumber: 0000:00:1d.0
[    1.489741] hub 2-0:1.0: USB hub found
[    1.489770] hub 2-0:1.0: 2 ports detected
[    1.490298] ehci-platform: EHCI generic platform driver
[    1.490344] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.490359] ohci-pci: OHCI PCI platform driver
[    1.490397] ohci-platform: OHCI generic platform driver
[    1.490426] uhci_hcd: USB Universal Host Controller Interface driver
[    1.490661] xhci_hcd 0000:03:00.0: xHCI Host Controller
[    1.490689] xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 3
[    1.500427] xhci_hcd 0000:03:00.0: hcc params 0x0200f180 hci version 0x96 quirks 0x00080000
[    1.501073] usb usb3: New USB device found, idVendor
6b, idProduct02
quoted
[    1.501081] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.501090] usb usb3: Product: xHCI Host Controller
[    1.501095] usb usb3: Manufacturer: Linux 4.7.0-rc7-4-iniza-small xhci-hcd
[    1.501102] usb usb3: SerialNumber: 0000:03:00.0
[    1.501748] hub 3-0:1.0: USB hub found
[    1.501827] hub 3-0:1.0: 2 ports detected
[    1.502294] xhci_hcd 0000:03:00.0: xHCI Host Controller
[    1.502312] xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 4
[    1.502443] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[    1.502545] usb usb4: New USB device found, idVendor
6b, idProduct03
quoted
[    1.502552] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.502560] usb usb4: Product: xHCI Host Controller
[    1.502565] usb usb4: Manufacturer: Linux 4.7.0-rc7-4-iniza-small xhci-hcd
[    1.502570] usb usb4: SerialNumber: 0000:03:00.0
[    1.503224] hub 4-0:1.0: USB hub found
[    1.503273] hub 4-0:1.0: 2 ports detected
[    1.503918] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:EPAD] at 0x60,0x64 irq 1,12
[    1.509828] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.509878] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.510766] mousedev: PS/2 mouse device common for all mice
[    1.512233] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0
[    1.512278] rtc_cmos 00:01: alarms up to one month, y3k, 242 bytes nvram, hpet irqs
[    1.519012] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[    1.537039] device-mapper: uevent: version 1.0.3
[    1.549317] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com
[    1.549378] ledtrig-cpu: registered to indicate activity on CPUs
[    1.621021] NET: Registered protocol family 10
[    1.633711] NET: Registered protocol family 17
[    1.633741] Key type dns_resolver registered
[    1.634700] microcode: CPU0 sig=0x206a7, pf=0x10, revision=0x28
[    1.634750] microcode: CPU1 sig=0x206a7, pf=0x10, revision=0x28
[    1.634776] microcode: CPU2 sig=0x206a7, pf=0x10, revision=0x28
[    1.634816] microcode: CPU3 sig=0x206a7, pf=0x10, revision=0x28
[    1.635094] microcode: Microcode Update Driver: v2.01 [off-list ref], Peter Oruba
[    1.636008] registered taskstats version 1
[    1.636018] Loading compiled-in X.509 certificates
[    1.643359] Loaded X.509 cert 'Build time autogenerated kernel key: 00b89b0b54e2e50c779dcb6b1667e75e5be8f532'
[    1.648048] Key type trusted registered
[    1.656281] Key type encrypted registered
[    1.656299] AppArmor: AppArmor sha1 policy hashing enabled
[    1.656306] evm: HMAC attrs: 0x1
[    1.657482] rtc_cmos 00:01: setting system clock to 2016-07-15 07:45:08 UTC (1468568708)
[    1.657685] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    1.657691] EDD information not available.
[    1.765348] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    1.766773] ata1.00: ATA-8: Hitachi HTS545050A7E380, GG2OA6C0, max UDMA/133
[    1.766783] ata1.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[    1.768393] ata1.00: configured for UDMA/133
[    1.769527] scsi 0:0:0:0: Direct-Access     ATA      Hitachi HTS54505 A6C0 PQ: 0 ANSI: 5
[    1.784971] usb 1-1: new high-speed USB device number 2 using ehci-pci

325 more quoted lines

Jani Nikula replied to Sedat Dilek

Message details and related content
Open message details and related content
On Tue, 02 Aug 2016, Sedat Dilek [off-list ref] wrote:
On Mon, Aug 1, 2016 at 3:33 PM, Jani Nikula [off-list ref] wrote:
quoted
On Fri, 15 Jul 2016, Sedat Dilek [off-list ref] wrote:
quoted
Hi,

I see the below call-trace with latest d-i-n, guess latest linux-next
FWIW, "d-i-n" is ambiguous (drm-intel-next vs. drm-intel-nightly) and we
don't use that ourselves.
Oh, sorry with d-i-n I meant drm-intel-nightly.
Normally, I also point to the "integration manifest"
like...drm-intel-nightly: 2016y-08m-02d-14h-10m-12s UTC integration
manifest.
You do not test d-i-n yourself?
Strange.
We do not use the *acronym* "d-i-n" ourselves because it is
ambiguous. drm-intel-nightly is our main test target.

BR,
Jani.


-- 
Jani Nikula, Intel Open Source Technology Center
Keyboard shortcuts
hback out one level
jnext message in thread / next series-diff position
kprevious message in thread / previous series-diff position
ldrill in
Escclose help / fold thread tree
?toggle this help