Bug 106422 - i915 intel_tv_detect time out with recent kernels: [drm_kms_helper] flip_done timed out
Summary: i915 intel_tv_detect time out with recent kernels: [drm_kms_helper] flip_done...
Status: CLOSED DUPLICATE of bug 93782
Alias: None
Product: DRI
Classification: Unclassified
Component: DRM/Intel (show other bugs)
Version: unspecified
Hardware: x86-64 (AMD64) Linux (All)
: low normal
Assignee: Intel GFX Bugs mailing list
QA Contact: Intel GFX Bugs mailing list
URL:
Whiteboard: Triaged, ReadyForDev
Keywords:
Depends on:
Blocks:
 
Reported: 2018-05-06 16:30 UTC by Zakhar
Modified: 2018-09-26 15:57 UTC (History)
4 users (show)

See Also:
i915 platform: I965GM
i915 features: display/Other


Attachments
dmesg with drm-tip 4.17.0-rc3+ kernel (1.75 MB, text/plain)
2018-05-07 13:25 UTC, Zakhar
no flags Details
i915.vbt as requested (7.00 KB, application/octet-stream)
2018-06-02 06:53 UTC, Zakhar
no flags Details

Description Zakhar 2018-05-06 16:30:56 UTC
Currently running Ubuntu 14.04 32 bits - kernel 3.13

With this kernel, the kernel only outputs complaints in the log, but it has no other visible effect but some line in the log.

It was also working with 16.04.1 (live USB) the same way.

From 16.04.4 (kernel 4.13) and with 18.04 (kernel 4.15), we now have 10 seconds time outs at startup. This cause booting a live USB to be very slow (around 5 minutes).

I guess the regression is between the 16.04.1's kernel (sorry I removed it, can't check the version number!) and 4.15


Booting with 'nomodeset' removes the time out... but removes also all GPU acceleration which makes watching a HD movie impossible or Firefox or GLXGears much slower.


Below a full detail for what happens in 14.04/kernel 3.13 (just some logging) and what happens in 18.04/kernel 4.15

This is also posted on Ubuntu Launchpad, at the end of an already existing bug:

https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1731784


14.04 32bits:
______


$ uname -a; lsb_release -a
Linux rose-Inspiron-1525 3.13.0-145-generic #194-Ubuntu SMP Thu Apr 5 15:18:37 UTC 2018 i686 i686 i686 GNU/Linux
No LSB modules are available.
Distributor ID:	Ubuntu
Description:	Ubuntu 14.04.5 LTS
Release:	14.04
Codename:	trusty


$ sudo lshw
foo-inspiron-1525        
    description: Portable Computer
    product: Inspiron 1525 ()
    vendor: Dell Inc.
    serial: GFD4X3J
    width: 32 bits
    capabilities: smbios-2.4 dmi-2.4
    configuration: boot=normal chassis=portable uuid=44454C4C-4600-1044-8034-C7C04F58334A
  *-core
       description: Motherboard
       product: 0U990C
       vendor: Dell Inc.
       physical id: 0
       serial: .GFD4X3J.CN7016686C0ZEM.
     *-firmware
          description: BIOS
          vendor: Dell Inc.
          physical id: 0
          version: A13
          date: 06/27/2008
          size: 64KiB
          capacity: 1984KiB
          capabilities: isa pci pcmcia pnp upgrade shadowing cdboot bootselect int13floppy720 int5printscreen int9keyboard int14serial int17printer int10video acpi usb agp smartbattery biosbootspecification netboot
     *-cpu
          description: CPU
          product: Intel(R) Celeron(R) CPU          550  @ 2.00GHz
          vendor: Intel Corp.
          physical id: 400
          bus info: cpu@0
          version: 6.6.1
          serial: 0001-0661-0000-0000-0000-0000
          slot: Microprocessor
          size: 2GHz
          capacity: 2GHz
          width: 64 bits
          clock: 133MHz
          capabilities: x86-64 fpu fpu_exception wp vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss tm pbe nx constant_tsc arch_perfmon pebs bts aperfmperf pni dtes64 monitor ds_cpl tm2 ssse3 cx16 xtpr pdcm lahf_lm dtherm retpoline
          configuration: cores=1 enabledcores=1 threads=1
        *-cache:0
             description: L1 cache
             physical id: 700
             size: 32KiB
             capacity: 32KiB
             capabilities: internal write-back data
        *-cache:1
             description: L2 cache
             physical id: 701
             size: 1MiB
             capacity: 1MiB
             clock: 66MHz (15.0ns)
             capabilities: pipeline-burst internal varies unified
     *-memory
          description: System Memory
          physical id: 1000
          slot: System board or motherboard
          size: 2GiB
        *-bank:0
             description: DIMM DDR Synchronous 667 MHz (1.5 ns)
             product: HYMP512S64CP8-Y5
             vendor: Hynix Semiconductor (Hyundai Electronics)
             physical id: 0
             serial: 04004277
             slot: DIMM_A
             size: 1GiB
             width: 64 bits
             clock: 667MHz (1.5ns)
        *-bank:1
             description: DIMM DDR Synchronous 667 MHz (1.5 ns)
             product: HYMP512S64CP8-Y5
             vendor: Hynix Semiconductor (Hyundai Electronics)
             physical id: 1
             serial: 03004063
             slot: DIMM_B
             size: 1GiB
             width: 64 bits
             clock: 667MHz (1.5ns)
     *-pci
          description: Host bridge
          product: Mobile PM965/GM965/GL960 Memory Controller Hub
          vendor: Intel Corporation
          physical id: 100
          bus info: pci@0000:00:00.0
          version: 0c
          width: 32 bits
          clock: 33MHz
          configuration: driver=agpgart-intel
          resources: irq:0
        *-display:0
             description: VGA compatible controller
             product: Mobile GM965/GL960 Integrated Graphics Controller (primary)
             vendor: Intel Corporation
             physical id: 2
             bus info: pci@0000:00:02.0
             version: 0c
             width: 64 bits
             clock: 33MHz
             capabilities: msi pm vga_controller bus_master cap_list rom
             configuration: driver=i915 latency=0
             resources: irq:45 memory:fea00000-feafffff memory:e0000000-efffffff ioport:eff8(size=8)
        *-display:1 UNCLAIMED
             description: Display controller
             product: Mobile GM965/GL960 Integrated Graphics Controller (secondary)
             vendor: Intel Corporation
             physical id: 2.1
             bus info: pci@0000:00:02.1
             version: 0c
             width: 64 bits
             clock: 33MHz
             capabilities: pm bus_master cap_list
             configuration: latency=0
             resources: memory:feb00000-febfffff
        *-usb:0
             description: USB controller
             product: 82801H (ICH8 Family) USB UHCI Controller #4
             vendor: Intel Corporation
             physical id: 1a
             bus info: pci@0000:00:1a.0
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: uhci bus_master
             configuration: driver=uhci_hcd latency=0
             resources: irq:20 ioport:6f20(size=32)
        *-usb:1
             description: USB controller
             product: 82801H (ICH8 Family) USB UHCI Controller #5
             vendor: Intel Corporation
             physical id: 1a.1
             bus info: pci@0000:00:1a.1
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: uhci bus_master
             configuration: driver=uhci_hcd latency=0
             resources: irq:21 ioport:6f00(size=32)
        *-usb:2
             description: USB controller
             product: 82801H (ICH8 Family) USB2 EHCI Controller #2
             vendor: Intel Corporation
             physical id: 1a.7
             bus info: pci@0000:00:1a.7
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: pm debug ehci bus_master cap_list
             configuration: driver=ehci-pci latency=0
             resources: irq:22 memory:fed1c400-fed1c7ff
        *-multimedia
             description: Audio device
             product: 82801H (ICH8 Family) HD Audio Controller
             vendor: Intel Corporation
             physical id: 1b
             bus info: pci@0000:00:1b.0
             version: 02
             width: 64 bits
             clock: 33MHz
             capabilities: pm msi pciexpress bus_master cap_list
             configuration: driver=snd_hda_intel latency=0
             resources: irq:47 memory:fe9fc000-fe9fffff
        *-pci:0
             description: PCI bridge
             product: 82801H (ICH8 Family) PCI Express Port 1
             vendor: Intel Corporation
             physical id: 1c
             bus info: pci@0000:00:1c.0
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: pci pciexpress msi pm normal_decode bus_master cap_list
             configuration: driver=pcieport
             resources: irq:40 ioport:d000(size=4096) memory:fe800000-fe8fffff ioport:80000000(size=2097152)
           *-network
                description: Ethernet interface
                product: 88E8040 PCI-E Fast Ethernet Controller
                vendor: Marvell Technology Group Ltd.
                physical id: 0
                bus info: pci@0000:09:00.0
                logical name: eth0
                version: 12
                serial: 00:1d:09:62:5e:e5
                size: 100Mbit/s
                capacity: 100Mbit/s
                width: 64 bits
                clock: 33MHz
                capabilities: pm msi pciexpress bus_master cap_list ethernet physical tp 10bt 10bt-fd 100bt 100bt-fd autonegotiation
                configuration: autonegotiation=on broadcast=yes driver=sky2 driverversion=1.30 duplex=full ip=192.168.1.40 latency=0 link=yes multicast=yes port=twisted pair speed=100Mbit/s
                resources: irq:43 memory:fe8fc000-fe8fffff ioport:de00(size=256)
        *-pci:1
             description: PCI bridge
             product: 82801H (ICH8 Family) PCI Express Port 2
             vendor: Intel Corporation
             physical id: 1c.1
             bus info: pci@0000:00:1c.1
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: pci pciexpress msi pm normal_decode bus_master cap_list
             configuration: driver=pcieport
             resources: irq:41 ioport:2000(size=4096) memory:fe700000-fe7fffff ioport:80200000(size=2097152)
           *-network
                description: Wireless interface
                product: PRO/Wireless 3945ABG [Golan] Network Connection
                vendor: Intel Corporation
                physical id: 0
                bus info: pci@0000:0b:00.0
                logical name: wlan0
                version: 02
                serial: 00:1f:3c:a4:fd:78
                width: 32 bits
                clock: 33MHz
                capabilities: pm msi pciexpress bus_master cap_list ethernet physical wireless
                configuration: broadcast=yes driver=iwl3945 driverversion=3.13.0-145-generic firmware=15.32.2.9 latency=0 link=no multicast=yes wireless=IEEE 802.11abg
                resources: irq:46 memory:fe7ff000-fe7fffff
        *-pci:2
             description: PCI bridge
             product: 82801H (ICH8 Family) PCI Express Port 5
             vendor: Intel Corporation
             physical id: 1c.4
             bus info: pci@0000:00:1c.4
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: pci pciexpress msi pm normal_decode bus_master cap_list
             configuration: driver=pcieport
             resources: irq:42 ioport:c000(size=4096) memory:fe400000-fe6fffff ioport:f0000000(size=2097152)
        *-usb:3
             description: USB controller
             product: 82801H (ICH8 Family) USB UHCI Controller #1
             vendor: Intel Corporation
             physical id: 1d
             bus info: pci@0000:00:1d.0
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: uhci bus_master
             configuration: driver=uhci_hcd latency=0
             resources: irq:20 ioport:6f80(size=32)
        *-usb:4
             description: USB controller
             product: 82801H (ICH8 Family) USB UHCI Controller #2
             vendor: Intel Corporation
             physical id: 1d.1
             bus info: pci@0000:00:1d.1
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: uhci bus_master
             configuration: driver=uhci_hcd latency=0
             resources: irq:21 ioport:6f60(size=32)
        *-usb:5
             description: USB controller
             product: 82801H (ICH8 Family) USB UHCI Controller #3
             vendor: Intel Corporation
             physical id: 1d.2
             bus info: pci@0000:00:1d.2
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: uhci bus_master
             configuration: driver=uhci_hcd latency=0
             resources: irq:22 ioport:6f40(size=32)
        *-usb:6
             description: USB controller
             product: 82801H (ICH8 Family) USB2 EHCI Controller #1
             vendor: Intel Corporation
             physical id: 1d.7
             bus info: pci@0000:00:1d.7
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: pm debug ehci bus_master cap_list
             configuration: driver=ehci-pci latency=0
             resources: irq:20 memory:fed1c000-fed1c3ff
        *-pci:3
             description: PCI bridge
             product: 82801 Mobile PCI Bridge
             vendor: Intel Corporation
             physical id: 1e
             bus info: pci@0000:00:1e.0
             version: f2
             width: 32 bits
             clock: 33MHz
             capabilities: pci subtractive_decode bus_master cap_list
             resources: memory:fe300000-fe3fffff
           *-firewire
                description: FireWire (IEEE 1394)
                product: R5C832 IEEE 1394 Controller
                vendor: Ricoh Co Ltd
                physical id: 9
                bus info: pci@0000:02:09.0
                version: 05
                width: 32 bits
                clock: 33MHz
                capabilities: pm ohci bus_master cap_list
                configuration: driver=firewire_ohci latency=64 maxlatency=4 mingnt=2
                resources: irq:16 memory:fe3ff800-fe3fffff
           *-generic:0
                description: SD Host controller
                product: R5C822 SD/SDIO/MMC/MS/MSPro Host Adapter
                vendor: Ricoh Co Ltd
                physical id: 9.1
                bus info: pci@0000:02:09.1
                version: 22
                width: 32 bits
                clock: 33MHz
                capabilities: pm bus_master cap_list
                configuration: driver=sdhci-pci latency=64
                resources: irq:18 memory:fe3ff400-fe3ff4ff
           *-generic:1
                description: System peripheral
                product: R5C592 Memory Stick Bus Host Adapter
                vendor: Ricoh Co Ltd
                physical id: 9.2
                bus info: pci@0000:02:09.2
                version: 12
                width: 32 bits
                clock: 33MHz
                capabilities: pm bus_master cap_list
                configuration: driver=r592 latency=64
                resources: irq:18 memory:fe3ff600-fe3ff6ff
           *-generic:2
                description: System peripheral
                product: xD-Picture Card Controller
                vendor: Ricoh Co Ltd
                physical id: 9.3
                bus info: pci@0000:02:09.3
                version: 12
                width: 32 bits
                clock: 33MHz
                capabilities: pm bus_master cap_list
                configuration: driver=r852 latency=64
                resources: irq:18 memory:fe3ff700-fe3ff7ff
        *-isa
             description: ISA bridge
             product: 82801HM (ICH8M) LPC Interface Controller
             vendor: Intel Corporation
             physical id: 1f
             bus info: pci@0000:00:1f.0
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: isa bus_master cap_list
             configuration: driver=lpc_ich latency=0
             resources: irq:0
        *-ide
             description: IDE interface
             product: 82801HM/HEM (ICH8M/ICH8M-E) IDE Controller
             vendor: Intel Corporation
             physical id: 1f.1
             bus info: pci@0000:00:1f.1
             version: 02
             width: 32 bits
             clock: 33MHz
             capabilities: ide bus_master
             configuration: driver=ata_piix latency=0
             resources: irq:16 ioport:1f0(size=8) ioport:3f6 ioport:170(size=8) ioport:376 ioport:6fa0(size=16)
        *-storage
             description: SATA controller
             product: 82801HM/HEM (ICH8M/ICH8M-E) SATA Controller [AHCI mode]
             vendor: Intel Corporation
             physical id: 1f.2
             bus info: pci@0000:00:1f.2
             version: 02
             width: 32 bits
             clock: 66MHz
             capabilities: storage msi pm ahci_1.0 bus_master cap_list
             configuration: driver=ahci latency=0
             resources: irq:44 ioport:6eb0(size=8) ioport:6eb8(size=4) ioport:6ec0(size=8) ioport:6ec8(size=4) ioport:6ee0(size=32) memory:fe9fb800-fe9fbfff
        *-serial UNCLAIMED
             description: SMBus
             product: 82801H (ICH8 Family) SMBus Controller
             vendor: Intel Corporation
             physical id: 1f.3
             bus info: pci@0000:00:1f.3
             version: 02
             width: 32 bits
             clock: 33MHz
             configuration: latency=0
             resources: memory:fe9fb700-fe9fb7ff ioport:10c0(size=32)
     *-scsi:0
          physical id: 1
          logical name: scsi0
          capabilities: emulated
        *-cdrom
             description: DVD writer
             product: DVD+-RW GSA-T21N
             vendor: HL-DT-ST
             physical id: 0.0.0
             bus info: scsi@0:0.0.0
             logical name: /dev/cdrom
             logical name: /dev/sr0
             version: A102
             capabilities: removable audio cd-r cd-rw dvd dvd-r
             configuration: ansiversion=5 status=nodisc
     *-scsi:1
          physical id: 2
          logical name: scsi2
          capabilities: emulated
        *-disk
             description: ATA Disk
             product: WDC WD800BEVT-75
             vendor: Western Digital
             physical id: 0.0.0
             bus info: scsi@2:0.0.0
             logical name: /dev/sda
             version: 11.0
             serial: WD-WXH708310204
             size: 74GiB (80GB)
             capabilities: partitioned partitioned:dos
             configuration: ansiversion=5 sectorsize=512 signature=0005d3d6
           *-volume:0
                description: EXT4 volume
                vendor: Linux
                physical id: 1
                bus info: scsi@2:0.0.0,1
                logical name: /dev/sda1
                logical name: /
                version: 1.0
                serial: ed639f04-3c78-4df6-ad28-8319c50999f3
                size: 11GiB
                capacity: 11GiB
                capabilities: primary bootable journaled extended_attributes large_files huge_files dir_nlink recover extents ext4 ext2 initialized
                configuration: created=2014-07-15 09:38:58 filesystem=ext4 lastmountpoint=/ modified=2018-05-06 12:42:33 mount.fstype=ext4 mount.options=rw,relatime,errors=remount-ro,data=ordered mounted=2018-05-06 12:42:33 state=mounted
           *-volume:1
                description: Extended partition
                physical id: 2
                bus info: scsi@2:0.0.0,2
                logical name: /dev/sda2
                size: 63GiB
                capacity: 63GiB
                capabilities: primary extended partitioned partitioned:extended
              *-logicalvolume:0
                   description: Linux swap / Solaris partition
                   physical id: 5
                   logical name: /dev/sda5
                   capacity: 1464MiB
                   capabilities: nofs
              *-logicalvolume:1
                   description: Linux filesystem partition
                   physical id: 6
                   logical name: /dev/sda6
                   logical name: /home
                   capacity: 50GiB
                   configuration: mount.fstype=ext4 mount.options=rw,relatime,data=ordered state=mounted
              *-logicalvolume:2
                   description: Linux filesystem partition
                   physical id: 7
                   logical name: /dev/sda7
                   capacity: 11GiB


$ sudo dmesg
[   33.254851] ------------[ cut here ]------------
[   33.254909] WARNING: CPU: 0 PID: 1111 at /build/linux-8EIBDa/linux-3.13.0/drivers/gpu/drm/i915/intel_display.c:9245 intel_modeset_check_state+0x5e5/0x750 [i915]()
[   33.254912] encoder's hw state doesn't match sw tracking (expected 0, found 1)
[   33.254914] Modules linked in: xt_LOG xt_limit xt_tcpudp nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables zram(C) snd_hda_codec_idt bnep snd_hda_codec_hdmi rfcomm bluetooth snd_hda_intel binfmt_misc snd_hda_codec snd_hwdep snd_pcm gpio_ich dell_wmi sparse_keymap snd_page_alloc snd_seq_midi snd_seq_midi_event snd_rawmidi dell_laptop dcdbas arc4 snd_seq coretemp iwl3945 r852 sm_common snd_seq_device iwlegacy joydev nand i915 snd_timer nand_ecc serio_raw mac80211 drm_kms_helper nand_bch bch r592 snd lpc_ich cfg80211 drm nand_ids memstick mtd shpchp soundcore i2c_algo_bit mac_hid wmi video parport_pc ppdev lp parport hid_generic usbhid hid firewire_ohci ahci firewire_core psmouse sdhci_pci crc_itu_t pata_acpi sdhci libahci sky2
[   33.254975] CPU: 0 PID: 1111 Comm: Xorg Tainted: G        WC    3.13.0-145-generic #194-Ubuntu
[   33.254977] Hardware name: Dell Inc. Inspiron 1525                   /0U990C, BIOS A13 06/27/2008
[   33.254980]  00000000 00000000 ec13db5c c166911d ec13db9c f908ae94 ec13db8c c1059a6e
[   33.254986]  f908d044 ec13dbb8 00000457 f908ae94 0000241d f9040815 f9040815 ed439400
[   33.254992]  f2fc2dc0 00000000 ec13dba4 c1059ac3 00000009 ec13db9c f908d044 ec13dbb8
[   33.254997] Call Trace:
[   33.255008]  [<c166911d>] dump_stack+0x57/0x70
[   33.255014]  [<c1059a6e>] warn_slowpath_common+0x7e/0xa0
[   33.255039]  [<f9040815>] ? intel_modeset_check_state+0x5e5/0x750 [i915]
[   33.255063]  [<f9040815>] ? intel_modeset_check_state+0x5e5/0x750 [i915]
[   33.255066]  [<c1059ac3>] warn_slowpath_fmt+0x33/0x40
[   33.255091]  [<f9040815>] intel_modeset_check_state+0x5e5/0x750 [i915]
[   33.255118]  [<f9040a10>] intel_set_mode+0x30/0x40 [i915]
[   33.255142]  [<f9040ae4>] intel_release_load_detect_pipe+0xc4/0xf0 [i915]
[   33.255172]  [<f90738a0>] ? gen4_read8+0xc0/0xc0 [i915]
[   33.255198]  [<f9068ab1>] intel_tv_detect+0x391/0x570 [i915]
[   33.255205]  [<c150ac01>] ? i2c_register_driver+0xa1/0xd0
[   33.255216]  [<f8ae21dd>] drm_helper_probe_single_connector_modes+0x1dd/0x360 [drm_kms_helper]
[   33.255251]  [<f8c0d526>] ? drm_mode_object_find+0x66/0xa0 [drm]
[   33.255257]  [<f8ae2000>] ? drm_kms_helper_poll_enable+0x90/0x90 [drm_kms_helper]
[   33.255276]  [<f8c10712>] drm_mode_getconnector+0x332/0x390 [drm]
[   33.255283]  [<c10bcc92>] ? clockevents_program_event+0xa2/0x140
[   33.255302]  [<f8c103e0>] ? drm_mode_getcrtc+0xc0/0xc0 [drm]
[   33.255316]  [<f8c0378d>] drm_ioctl+0x47d/0x510 [drm]
[   33.255337]  [<f8c103e0>] ? drm_mode_getcrtc+0xc0/0xc0 [drm]
[   33.255343]  [<c101a004>] ? __restore_xstate_sig+0x2d4/0x4e0
[   33.255358]  [<f8c03310>] ? drm_free_buffer+0x30/0x30 [drm]
[   33.255363]  [<c1190d55>] do_vfs_ioctl+0x2f5/0x4f0
[   33.255368]  [<c1190fb0>] SyS_ioctl+0x60/0x80
[   33.255374]  [<c167762a>] sysenter_do_call+0x17/0x17
[   33.255376] ---[ end trace 920be324e39a6a3c ]---


$ sudo lsmod
Module                  Size  Used by
xt_LOG                 17445  1 
xt_limit               12541  1 
xt_tcpudp              12756  2 
nf_conntrack_ipv6      18414  1 
nf_defrag_ipv6         26163  1 nf_conntrack_ipv6
ip6table_filter        12711  1 
ip6_tables             17883  1 ip6table_filter
nf_conntrack_ipv4      14492  1 
nf_defrag_ipv4         12649  1 nf_conntrack_ipv4
xt_conntrack           12664  2 
nf_conntrack           83878  3 xt_conntrack,nf_conntrack_ipv4,nf_conntrack_ipv6
iptable_filter         12706  1 
ip_tables              18051  1 iptable_filter
x_tables               22456  8 ip6table_filter,ip_tables,xt_tcpudp,xt_limit,xt_conntrack,xt_LOG,iptable_filter,ip6_tables
zram                   18032  1 
snd_hda_codec_idt      53340  1 
bnep                   18895  2 
snd_hda_codec_hdmi     45440  1 
rfcomm                 53664  0 
bluetooth             342221  10 bnep,rfcomm
snd_hda_intel          42858  3 
binfmt_misc            13140  1 
snd_hda_codec         168250  3 snd_hda_codec_hdmi,snd_hda_codec_idt,snd_hda_intel
snd_hwdep              13272  1 snd_hda_codec
snd_pcm                85501  3 snd_hda_codec_hdmi,snd_hda_codec,snd_hda_intel
gpio_ich               13229  0 
dell_wmi               12665  0 
sparse_keymap          13708  1 dell_wmi
snd_page_alloc         14230  2 snd_pcm,snd_hda_intel
snd_seq_midi           13132  0 
snd_seq_midi_event     14475  1 snd_seq_midi
snd_rawmidi            25543  1 snd_seq_midi
dell_laptop            17781  0 
dcdbas                 14448  1 dell_laptop
arc4                   12536  2 
snd_seq                55431  2 snd_seq_midi_event,snd_seq_midi
coretemp               13195  0 
iwl3945                63619  0 
r852                   17722  0 
sm_common              16772  1 r852
snd_seq_device         14137  3 snd_seq,snd_rawmidi,snd_seq_midi
iwlegacy               88016  1 iwl3945
joydev                 17101  0 
nand                   58760  2 r852,sm_common
i915                  730537  3 
snd_timer              28569  2 snd_pcm,snd_seq
nand_ecc               13136  1 nand
serio_raw              13230  0 
mac80211              554291  2 iwl3945,iwlegacy
drm_kms_helper         52964  1 i915
nand_bch               13067  1 nand
bch                    17197  1 nand_bch
r592                   17711  0 
snd                    60939  17 snd_hwdep,snd_timer,snd_hda_codec_hdmi,snd_hda_codec_idt,snd_pcm,snd_seq,snd_rawmidi,snd_hda_codec,snd_hda_intel,snd_seq_device,snd_seq_midi
lpc_ich                16864  0 
cfg80211              417586  3 iwl3945,iwlegacy,mac80211
drm                   244037  4 i915,drm_kms_helper
nand_ids                8547  1 nand
memstick               16174  1 r592
mtd                    52813  2 nand,sm_common
shpchp                 32128  0 
soundcore              12600  1 snd
i2c_algo_bit           13197  1 i915
mac_hid                13037  0 
wmi                    18673  1 dell_wmi
video                  18903  1 i915
parport_pc             31981  0 
ppdev                  17391  0 
lp                     13299  0 
parport                40836  3 lp,ppdev,parport_pc
hid_generic            12492  0 
usbhid                 47070  0 
hid                    87604  2 hid_generic,usbhid
firewire_ohci          35529  0 
ahci                   29739  3 
firewire_core          61909  1 firewire_ohci
psmouse                95439  0 
sdhci_pci              18535  0 
crc_itu_t              12627  1 firewire_core
pata_acpi              12886  0 
sdhci                  37787  1 sdhci_pci
libahci                31310  1 ahci
sky2                   52946  0 


________________________________________________________________________________________________
18.04 (64bits - released Apr 26th)
_______


$ dmesg
[   84.192059] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:41:pipe B] flip_done timed out
[   84.295089] ------------[ cut here ]------------
[   84.295090] vblank wait timed out on crtc 1
[   84.295171] WARNING: CPU: 0 PID: 31 at /build/linux-5s7Xkn/linux-4.15.0/drivers/gpu/drm/drm_vblank.c:1073 drm_wait_one_vblank+0x181/0x190 [drm]
[   84.295173] Modules linked in: dm_mirror dm_region_hash dm_log hid_generic usbhid hid i915 i2c_algo_bit drm_kms_helper psmouse syscopyarea sysfillrect sysimgblt ahci libahci fb_sys_fops firewire_ohci pata_acpi sdhci_pci sdhci firewire_core crc_itu_t drm sky2 wmi uas video usb_storage
[   84.295197] CPU: 0 PID: 31 Comm: kworker/0:1 Tainted: G        W        4.15.0-20-generic #21-Ubuntu
[   84.295198] Hardware name: Dell Inc. Inspiron 1525                   /0U990C, BIOS A13 06/27/2008
[   84.295207] Workqueue: events output_poll_execute [drm_kms_helper]
[   84.295223] RIP: 0010:drm_wait_one_vblank+0x181/0x190 [drm]
[   84.295224] RSP: 0018:ffffbf6fc042ba00 EFLAGS: 00010286
[   84.295227] RAX: 0000000000000000 RBX: ffff97b275838000 RCX: ffffffff9c6628a8
[   84.295228] RDX: 0000000000000001 RSI: 0000000000000082 RDI: 0000000000000247
[   84.295229] RBP: ffffbf6fc042ba58 R08: 0000000000000001 R09: 0000000000000372
[   84.295231] R10: 000000000000003a R11: 0000000000000000 R12: 0000000000000001
[   84.295232] R13: 0000000000000000 R14: 0000000003000745 R15: ffff97b2bb527168
[   84.295234] FS:  0000000000000000(0000) GS:ffff97b2bf400000(0000) knlGS:0000000000000000
[   84.295236] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   84.295237] CR2: 00007f006503df50 CR3: 0000000031c0a000 CR4: 00000000000006f0
[   84.295239] Call Trace:
[   84.295248]  ? wait_woken+0x80/0x80
[   84.295321]  intel_get_load_detect_pipe+0x5b7/0x620 [i915]
[   84.295356]  intel_tv_detect+0x147/0x510 [i915]
[   84.295360]  ? __i2c_transfer+0x123/0x400
[   84.295365]  ? _cond_resched+0x19/0x40
[   84.295368]  ? __ww_mutex_lock_slowpath+0x16/0x20
[   84.295376]  drm_helper_probe_detect+0x50/0x90 [drm_kms_helper]
[   84.295383]  ? drm_helper_probe_detect+0x50/0x90 [drm_kms_helper]
[   84.295391]  drm_helper_probe_single_connector_modes+0xe2/0x710 [drm_kms_helper]
[   84.295399]  drm_setup_crtcs+0x13f/0xa30 [drm_kms_helper]
[   84.295402]  ? _cond_resched+0x19/0x40
[   84.295403]  ? ww_mutex_lock+0x17/0x70
[   84.295411]  drm_fb_helper_hotplug_event.part.31+0x8f/0xb0 [drm_kms_helper]
[   84.295419]  drm_fb_helper_hotplug_event+0x1a/0x20 [drm_kms_helper]
[   84.295453]  intel_fbdev_output_poll_changed+0x34/0x60 [i915]
[   84.295460]  drm_kms_helper_hotplug_event+0x2a/0x30 [drm_kms_helper]
[   84.295467]  output_poll_execute+0x187/0x1b0 [drm_kms_helper]
[   84.295471]  process_one_work+0x1de/0x410
[   84.295473]  worker_thread+0x253/0x410
[   84.295476]  kthread+0x121/0x140
[   84.295478]  ? process_one_work+0x410/0x410
[   84.295480]  ? kthread_create_worker_on_cpu+0x70/0x70
[   84.295482]  ret_from_fork+0x35/0x40
[   84.295484] Code: ff ff e8 a3 e3 08 db 48 8d 75 a8 4c 89 ff e8 97 44 0d db 45 85 ed 0f 85 ff fe ff ff 44 89 e6 48 c7 c7 10 c4 21 c0 e8 df e0 08 db <0f> 0b e9 e9 fe ff ff 0f 1f 84 00 00 00 00 00 66 66 66 66 90 55 
[   84.295520] ---[ end trace 5828a7ea2f32e88a ]---
[   94.432042] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:41:pipe B] flip_done timed out
[  104.672039] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:35:plane B] flip_done timed out
[  114.912062] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:41:pipe B] flip_done timed out


$ lsmod
Module                  Size  Used by
gpio_ich               16384  0
dell_laptop            20480  0
dell_smbios_smm        16384  0
dcdbas                 16384  1 dell_smbios_smm
dell_smm_hwmon         16384  0
arc4                   16384  2
snd_hda_codec_idt      57344  1
snd_hda_codec_generic    73728  1 snd_hda_codec_idt
snd_hda_codec_hdmi     49152  2
coretemp               16384  0
snd_hda_intel          40960  5
snd_hda_codec         126976  4 snd_hda_intel,snd_hda_codec_idt,snd_hda_codec_hdmi,snd_hda_codec_generic
iwl3945                73728  0
snd_hda_core           81920  5 snd_hda_intel,snd_hda_codec,snd_hda_codec_idt,snd_hda_codec_hdmi,snd_hda_codec_generic
dell_smbios_wmi        16384  0
snd_hwdep              20480  1 snd_hda_codec
iwlegacy               98304  1 iwl3945
joydev                 24576  0
dell_wmi               16384  0
snd_pcm                98304  5 snd_hda_intel,snd_hda_codec,snd_hda_core,snd_hda_codec_hdmi
dell_smbios            16384  4 dell_wmi,dell_laptop,dell_smbios_wmi,dell_smbios_smm
input_leds             16384  0
sparse_keymap          16384  1 dell_wmi
mac80211              778240  2 iwlegacy,iwl3945
serio_raw              16384  0
wmi_bmof               16384  0
dell_wmi_descriptor    16384  2 dell_wmi,dell_smbios_wmi
snd_seq_midi           16384  0
snd_seq_midi_event     16384  1 snd_seq_midi
snd_rawmidi            32768  1 snd_seq_midi
r852                   20480  0
sm_common              16384  1 r852
snd_seq                65536  2 snd_seq_midi_event,snd_seq_midi
nand                   77824  2 r852,sm_common
nand_ecc               16384  1 nand
nand_bch               16384  1 nand
bch                    20480  1 nand_bch
cfg80211              622592  3 mac80211,iwlegacy,iwl3945
snd_seq_device         16384  3 snd_seq,snd_rawmidi,snd_seq_midi
snd_timer              32768  2 snd_seq,snd_pcm
mtd                    57344  3 nand_bch,sm_common,nand
lpc_ich                24576  0
r592                   20480  0
memstick               16384  1 r592
snd                    81920  20 snd_hda_intel,snd_hwdep,snd_seq,snd_hda_codec,snd_hda_codec_idt,snd_timer,snd_rawmidi,snd_hda_codec_hdmi,snd_hda_codec_generic,snd_seq_device,snd_pcm
soundcore              16384  1 snd
shpchp                 36864  0
mac_hid                16384  0
sch_fq_codel           20480  6
parport_pc             36864  0
ppdev                  20480  0
lp                     20480  0
parport                49152  3 lp,parport_pc,ppdev
ip_tables              28672  0
x_tables               40960  1 ip_tables
autofs4                40960  2
overlay                77824  1
nls_utf8               16384  1
isofs                  45056  1
jfs                   188416  0
xfs                  1200128  0
libcrc32c              16384  1 xfs
reiserfs              245760  0
dm_mirror              24576  0
dm_region_hash         20480  1 dm_mirror
dm_log                 20480  2 dm_mirror,dm_region_hash
hid_generic            16384  0
usbhid                 49152  0
hid                   118784  2 hid_generic,usbhid
i915                 1617920  11
i2c_algo_bit           16384  1 i915
drm_kms_helper        167936  1 i915
psmouse               147456  0
syscopyarea            16384  1 drm_kms_helper
sysfillrect            16384  1 drm_kms_helper
sysimgblt              16384  1 drm_kms_helper
ahci                   36864  2
libahci                32768  1 ahci
fb_sys_fops            16384  1 drm_kms_helper
firewire_ohci          40960  0
pata_acpi              16384  0
sdhci_pci              28672  0
sdhci                  49152  1 sdhci_pci
firewire_core          65536  1 firewire_ohci
crc_itu_t              16384  1 firewire_core
drm                   401408  5 i915,drm_kms_helper
sky2                   61440  0
wmi                    24576  4 dell_wmi,wmi_bmof,dell_wmi_descriptor,dell_smbios_wmi
uas                    24576  0
video                  40960  3 dell_wmi,dell_laptop,i915
usb_storage            69632  2 uas

$ uname -r
4.15.0-20-generic
Comment 1 Zakhar 2018-05-06 20:25:53 UTC
More information.

I have narrowed it down:

16.04.1 - kernel 4.4.0-31-generic
==> Works OK with messages ("encoder detached but still enabled on pipe A.")
No visible slowdown apart for 3 or 4 stack traces in the log.

16.04.2 - kernel 4.8.0-36-generic
==> Shows the "time outs" bug


So there must have been a change that is affecting i915 between kernels 4.4. and 4.8

Steps to reproduce:
- live boot on 16.04.1
- see all is Ok apart from some messages in the log
- live boot on 16.04.2
- see the boot takes much much more time and there are numerous timeouts messages.
Comment 2 Francesco Balestrieri 2018-05-07 06:10:40 UTC
Are you able to reproduce this with the latest drm-tip?
Comment 3 Zakhar 2018-05-07 06:21:40 UTC
Hi, I would need some help, or at least a pointer (link) to instructions on how to try that.

What I tried (the above) can be reproduced 100% of the time with the latest Ubuntu 18.04 and its 4.15 (generic) kernel. The behaviour (several 10 sec time outs) existed since kernel 4.8

I have already done patch of my own on packages like tor, and even done my own packages, but never played with the kernel...
Comment 4 Francesco Balestrieri 2018-05-07 06:37:50 UTC
There are some instructions here: https://01.org/linuxgraphics/documentation/build-guide-0
Comment 5 Zakhar 2018-05-07 06:57:51 UTC
Thanks!

Looks quite simple. Do you suggest I build only the kernel for now, or do I need to build all the shebang?
Comment 6 Jani Saarinen 2018-05-07 07:19:37 UTC
Just kernel (drm-tip)
Comment 7 Jani Saarinen 2018-05-07 07:25:55 UTC
And please send dmesg with drm.debug=0x1e log_buf_len=4M?
Comment 8 Zakhar 2018-05-07 08:26:07 UTC
Ok, will do!

I assume that goes in the "kernel command line" (startup) as instructed by:
https://01.org/linuxgraphics/documentation/how-report-bugs

I am currently doing a full backup of the system in case I mess up things, and will then do installation of 18.04 (on external device) + compile drm-tip kernel and report.

... it takes some time on that machine that was already low specced 10 years ago!
Comment 9 Zakhar 2018-05-07 13:25:40 UTC
Created attachment 139405 [details]
dmesg with drm-tip 4.17.0-rc3+ kernel
Comment 10 Zakhar 2018-05-07 13:28:22 UTC
As requested, I attached the FULL dmesg with drm-tip kernel.

There are a few 'fails' in the startup, but they look unrelated to the video driver (snap, audio driver...)

The "bug" (flip_done timed out) is still there, making the boot process painfully long!


Please don't hesitate to tell if you need anymore information for this 'regression'.
Comment 11 Jim Rees 2018-05-17 03:54:57 UTC
I've been having flip_done timeouts for a long time. Two suggestions I've been offered:

1. add "video=SVIDEO-1:d" to your kernel boot options

2. remove the intel_drv.so module

Given the presence of intel_tv_detect in the trace, bug #93782 might be relevant.

Also if you've been seeing this since 4.8, you might try reverting ea0000f0 "Roll out the helper nonblock tracking". This can be done up to kernel 4.14, after that it's too complicated.
Comment 12 Zakhar 2018-05-20 07:24:14 UTC
@Jim Rees

Thanks, your suggestion #1 made the bug disappear... as far as I could see in the log (the PC is remote ~1000km away, so I can't see what is happening on the screen!)

I tested it both with the self compiled drm-debug kernel and with stock Ubuntu 4.15 kernel.
(I didn't test yet on current 3.13 since in 3.13 there are messages in the log but no timeouts, so it is not really an issue)

The suggestion #2 seems very strange. Isn't it like running without the specific intel driver? Sure when I add "nomodeset" (or use the "recovery" grub options) that works fine with no time outs since we use no special video driver. But then we loose graphic acceleration which I still want to keep. No graphic acceleration is noticeable even in Firefox, and would not be a nice option.


I don't completely accept the workaround just now, since I am not in front of the PC and can't see by myself what are the side effects of this boot parameter.

I will also have to find a clean way to make this boot parameter stick, even when a new kernel is proposed on the Ubuntu repos... but I guess this is a common issue unrelated to Intel drivers.


So the option #1 you propose Jim looks promising... sure it is not a fix but a workaround!
I could anyway understand, considering the age of the hardware and how long it has been "end of life" now, that fixing this issue is not Intel engineers priority once I confirm the workaround.
Comment 13 Jani Nikula 2018-05-22 15:00:31 UTC
(In reply to Jim Rees from comment #11)
> 1. add "video=SVIDEO-1:d" to your kernel boot options

What this does is force disable the TV-out, leading to skipping the load detection that times out on vblank wait.

I'm not optimistic on figuring out what caused this without a bisect result.
Comment 14 Zakhar 2018-05-22 15:33:26 UTC
Thanks for the precision on the effect of this parameter.

We have used this PC connected to a TV, but that was with a VGA cable, through the VGA-out socket, the TV having a VGA-In connetor. I am not sure whether that counts as "TV-Out" and is one of the tests I need to do for this workaround.

This PC being "remote", I'll be able to complete that test only 1st week of June, where I'll have access to the hardware.

As for bisecting... do you have a pointer on documentation I could use to see the complexity of that? Sure the time-out appear somewhere between kernels 4.4 and 4.8, and that could be lower/upper bisection limits. Not sure though it is really practical starting bisecting with a remote machine!..
Comment 15 Jani Nikula 2018-05-23 14:44:27 UTC
(In reply to Zakhar from comment #14)
> We have used this PC connected to a TV, but that was with a VGA cable,
> through the VGA-out socket, the TV having a VGA-In connetor. I am not sure
> whether that counts as "TV-Out" and is one of the tests I need to do for
> this workaround.

This is not about VGA (regardless of whether it's connected to a TV), this is about SVIDEO. It's also possible your board doesn't even have a TV-out, which causes the timeout.

Come to think of it, please attach /sys/kernel/debug/dri/0/i915_vbt.

> As for bisecting... do you have a pointer on documentation I could use to
> see the complexity of that? Sure the time-out appear somewhere between
> kernels 4.4 and 4.8, and that could be lower/upper bisection limits. Not
> sure though it is really practical starting bisecting with a remote
> machine!..

https://wiki.ubuntu.com/Kernel/KernelBisection
Comment 16 Ville Syrjala 2018-05-23 14:54:08 UTC

*** This bug has been marked as a duplicate of bug 93782 ***
Comment 17 Jani Nikula 2018-05-24 07:12:27 UTC
(In reply to Ville Syrjala from comment #16)
> 
> *** This bug has been marked as a duplicate of bug 93782 ***

Thanks Ville. This sounded vaguely familiar, dunno why I didn't find the bug.

Zakhar, please do attach the vbt here regardless.
Comment 18 Zakhar 2018-05-26 05:57:00 UTC
Hi, I will provide the file, along with a more complete confirmation (or not!) that the boot option is a complete workaround, between June 2nd and June 10th:  when I will have direct access to the hardware!
Comment 19 Zakhar 2018-06-02 06:53:34 UTC
Created attachment 139963 [details]
i915.vbt as requested

I hope this helps since I had to copy (cp) from the root protected directory to /tmp, in order to avoid to having to sudo firefox for posting that!
Comment 20 Zakhar 2018-06-02 07:59:33 UTC
I provided the i915.vbt as requested. I hope doing a sudo cp to /tmp worked ok.

The hardware (Dell Inspiron 1525) has:
- built in screen (1280x800)
- VGA-Out
- HDMI

With the workaround, I could test built-in screen and VGA-Out work fine. As for HDMI, I don't have a cable (no big deal!)

Graphic acceleration is present.

There are no more messages in the log about slow graphics or timeouts, that is even true for 14.04 (3.13 kernel) where we had messages but no timeout.

As for that hardware the workaround is perfect.

I have read the bissect procedure, considering how slow this old hardware is (it takes quite a long time to compile a kernel!) and the fact that the workaround is about a parameter to add in /etc/default/grub and works fine on this PC with no adverse effect, I won't start bissecting.

I am then Ok with the "low" urgency on this bug. I'll remove the workaround if you ever find why there is now some timeouts in recent kernels on this "TV detection".


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.