Re: 4.8-rc1: it is now common that machine needs re-run of xrandr after resume

[Pavel Machek]

On Wed 2016-09-14 10:38:18, Jani Nikula wrote:
> On Wed, 14 Sep 2016, Pavel Machek <pavel@xxxxxx> wrote:
> > Hi!
> >
> >> I have
> >> 
> >> 00:02.0 VGA compatible controller: Intel Corporation 4 Series Chipset
> >> Integrated Graphics Controller (rev 03)
> >> 
> >> In previous kernels, resume worked ok. With 4.8-rc1, I quite often (1
> >> in 10 resumes?) get in state where primary monitor (DVI) is dead (in
> >> powersave) and all windows move to secondary monitor (VGA). Running
> >> "xrandr" fixes that.
> >> 
> >> I'll update to newer rc and see if it happens again, but if you have
> >> any ideas, now would be good time.
> >
> > Ok. With -rc6, X are completely broken. I got notification "could not
> > restore CRTC config for screen 63" or something like that, and window
> > manager just does not start.
> 
> Ugh. Can you bisect from v4.7, assuming it worked? That's probably the
> fastest way to resolve this.

The "completely broken" part -- something broke in my userland, as
booting to the old kernel does not fix it. I'll have to figure it out.

For the "sometimes need xrandr after resume": I don't think I can
bisect that. It only happens sometimes :-(. But there's something
helpful in the logs:

Best regards,
								Pavel
								
[ 1856.213154] CPU1 is up
[ 1856.213167] ACPI: Waking up from system sleep state S3
[ 1856.217998] clocksource: Switched to clocksource hpet
[ 1856.218170] uhci_hcd 0000:00:1d.0: System wakeup disabled by ACPI
[ 1856.218470] uhci_hcd 0000:00:1d.2: System wakeup disabled by ACPI
[ 1856.218656] uhci_hcd 0000:00:1d.1: System wakeup disabled by ACPI
[ 1856.218665] uhci_hcd 0000:00:1d.3: System wakeup disabled by ACPI
[ 1856.218863] ehci-pci 0000:00:1d.7: System wakeup disabled by ACPI
[ 1856.218863] PM: noirq resume of devices complete after 19.597 msecs
[ 1856.218863] PM: early resume of devices complete after 1.092 msecs
[ 1856.218863] usb usb2: root hub lost power or was reset
[ 1856.218863] usb usb3: root hub lost power or was reset
[ 1856.218863] usb usb4: root hub lost power or was reset
[ 1856.218863] usb usb5: root hub lost power or was reset
[ 1856.218863] pcieport 0000:00:1c.1: System wakeup disabled by ACPI
[ 1856.218863] serial 00:03: activated
[ 1856.218863] parport_pc 00:04: activated
[ 1856.218863] rtc_cmos 00:05: System wakeup disabled by ACPI
[ 1856.218863] ata2: port disabled--ignoring
[ 1856.218863] r8169 0000:03:00.0 eth0: link down
[ 1856.218863] sd 2:0:0:0: [sda] Starting disk
[ 1856.218863] sd 2:0:1:0: [sdb] Starting disk
[ 1856.218863] ata4.01: NODEV after polling detection
[ 1856.218863] ata3.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES)
filtered out
[ 1856.218863] ata3.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES)
filtered out
[ 1856.218863] ata3.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE
LOCK) filtered out
[ 1856.218863] ata3.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES)
filtered out
[ 1856.218863] ata3.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES)
filtered out
[ 1856.218863] ata3.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE
MODE) succeeded
[ 1856.218863] ata3.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE
LOCK) filtered out
[ 1856.218863] ata3.00: configured for UDMA/133
[ 1856.218863] ata4.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES)
filtered out
[ 1856.218863] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES)
filtered out
[ 1856.218863] ata4.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE
LOCK) filtered out
[ 1856.218863] ata3.01: configured for UDMA/133
[ 1856.218863] ata4.00: configured for UDMA/133
[ 1856.218863] [drm:drm_edid_block_valid] *ERROR* EDID checksum is
invalid, remainder is 130
[ 1856.218863] Raw EDID:
[ 1856.218863]     00 ff ff ff ff ff ff 00 ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863] [drm:drm_edid_block_valid] *ERROR* EDID checksum is
invalid, remainder is 130
[ 1856.218863] Raw EDID:
[ 1856.218863]     00 ff ff ff ff ff ff 00 ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863] [drm:drm_edid_block_valid] *ERROR* EDID checksum is
invalid, remainder is 130
[ 1856.218863] Raw EDID:
[ 1856.218863]     00 ff ff ff ff ff ff 00 ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863] [drm:drm_edid_block_valid] *ERROR* EDID checksum is
invalid, remainder is 130
[ 1856.218863] Raw EDID:
[ 1856.218863]     00 ff ff ff ff ff ff 00 ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863]     ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 1856.218863] i915 0000:00:02.0: HDMI-A-1: EDID block 0 invalid.
[ 1857.416283] r8169 0000:03:00.0 eth0: link up
[ 1858.352216] PM: resume of devices complete after 2990.454 msecs
[ 1858.353245] PM: resume devices took 2.992 seconds
[ 1858.353344] PM: Finishing wakeup.
[ 1858.353346] Restarting tasks ...
[ 1858.353491] usb 1-8: USB disconnect, device number 6
[ 1858.358714] done.
[ 1858.861355] r8169 0000:03:00.0 eth0: link down
[ 1858.861439] r8169 0000:03:00.0 eth0: link down
[ 1860.726118] r8169 0000:03:00.0 eth0: link up
[ 3806.819909] perf: interrupt took too long (3931 > 3922), lowering
kernel.perf_event_max_sample_rate to 50750

									Pavel
-- 
(english) http://www.livejournal.com/~pavelmachek
(cesky, pictures) http://atrey.karlin.mff.cuni.cz/~pavel/picture/horses/blog.html