On Thu, 9 Mar 2017 13:12:28 +0100 Paul Menzel <[email protected]> wrote:
> Dear Steven, dear Ingo, > > > Hopefully, I am contacting the right people for my issue. > > Suspending a system with Linux 4.9.13 with tracing enabled, it fails > with the screen still enabled, and the LED blinking. Attaching a serial > console to the dock, shows the messages below. I'm betting this is a compiler bug, as that bug that printed is the internal ftrace check for it. (note the bug is only in x86-32 not x86-64) Funny, we are just talking about this bug in another thread, but with a different symptom. Josh, did you say this goes away if you disable optimize for size or does it need to be enabled? -- Steve > > > […] > > [ 59.063238] PM: Syncing filesystems ... done. > > [ 59.197490] Freezing user space processes ... (elapsed 0.002 seconds) > > done. > > [ 59.207510] Freezing remaining freezable tasks ... (elapsed 0.002 > > seconds) done. > > [ 59.234169] sd 2:0:0:0: [sda] Synchronizing SCSI cache > > [ 59.240355] sd 2:0:0:0: [sda] Stopping disk > > [ 59.250859] ------------[ cut here ]------------ > > [ 59.255562] WARNING: CPU: 0 PID: 774 at > > /build/linux-ROBWaj/linux-4.9.13/kernel/trace/trace_functions_graph.c:233 > > ftrace_return_to_handler+0x1aa/0x1e0 > > [ 59.269228] Bad frame pointer: expected f6919d98, received f6919db0 > > [ 59.269228] from func acpi_pm_device_sleep_wake return to c43b6f9d > > [ 59.281993] Modules linked in: cpufreq_powersave(E) > > cpufreq_conservative(E) cpufreq_userspace(E) iTCO_wdt(E) arc4(E) > > iTCO_vendor_support(E) coretemp(E) pcmcia(E) kvm_intel(E) iwl3945(E) kvm(E) > > iwlegacy(E) mac80211(E) irqbypass(E) i915(E) lpc_ich(E) mfd_core(E) > > evdev(E) yenta_socket(E) serio_raw(E) snd_hda_codec_analog(E) > > pcmcia_rsrc(E) cfg80211(E) snd_hda_codec_generic(E) pcmcia_core(E) > > thinkpad_acpi(E) rng_core(E) snd_hda_intel(E) nvram(E) drm_kms_helper(E) > > rfkill(E) snd_hda_codec(E) battery(E) snd_hda_core(E) snd_hwdep(E) drm(E) > > snd_pcm(E) snd_timer(E) snd(E) soundcore(E) video(E) i2c_algo_bit(E) > > shpchp(E) ac(E) acpi_cpufreq(E) tpm_tis(E) button(E) tpm_tis_core(E) tpm(E) > > fuse(E) parport_pc(E) ppdev(E) lp(E) parport(E) autofs4(E) ext4(E) crc16(E) > > jbd2(E) fscrypto(E) mbcache(E) ecb(E) cbc(E) algif_skcipher(E) af_alg(E) > > dm_crypt(E) dm_mod(E) sr_mod(E) cdrom(E) sg(E) sd_mod(E) ata_generic(E) > > ehci_pci(E) uhci_hcd(E) ehci_hcd(E) ata_piix(E) ahci(E) libahci(E) > > firewire_ohci(E) sdhci_pci(E) psmouse(E) e1000e(E) libata(E) usbcore(E) > > i2c_i801(E) i2c_smbus(E) firewire_core(E) crc_itu_t(E) sdhci(E) mmc_core(E) > > ptp(E) scsi_mod(E) usb_common(E) pps_core(E) thermal(E) > > [ 59.388749] CPU: 0 PID: 774 Comm: analyze_suspend Tainted: G > > E 4.9.0-0.bpo.2-686-pae #1 Debian 4.9.13-1~bpo8+1 > > [ 59.392532] Hardware name: LENOVO 636338U/636338U, BIOS CBET4000 > > 4.5-1169-g7129ccb 03/09/2017 > > [ 59.392532] f6919d28 c42ee6ec f6919d3c 00000000 c4067c0a c469d538 > > f6919d5c 00000306 > > [ 59.392532] c469d4f0 000000e9 c412e95a 000000e9 00000009 c412e95a > > c4164039 c43b6f9d > > [ 59.392532] 00000002 f6919d48 c4067c76 00000009 00000000 f6919d3c > > c469d538 f6919d5c > > [ 59.392532] Call Trace: > > [ 59.392532] [<c42ee6ec>] ? dump_stack+0x55/0x79 > > [ 59.392532] [<c4067c0a>] ? __warn+0xea/0x110 > > [ 59.392532] [<c412e95a>] ? ftrace_return_to_handler+0x1aa/0x1e0 > > [ 59.392532] [<c412e95a>] ? ftrace_return_to_handler+0x1aa/0x1e0 > > [ 59.392532] [<c4164039>] ? > > cgroup_threadgroup_change_end.isra.39+0x1e/0x1e > > [ 59.392532] [<c43b6f9d>] ? pnpacpi_suspend+0x5d/0xe0 > > [ 59.392532] [<c4067c76>] ? warn_slowpath_fmt+0x46/0x60 > > [ 59.392532] [<c412e95a>] ? ftrace_return_to_handler+0x1aa/0x1e0 > > [ 59.392532] [<c43804a0>] ? acpi_pm_device_sleep_wake+0x14/0xa8 > > [ 59.392532] [<c43b6f9d>] ? pnpacpi_suspend+0x5d/0xe0 > > [ 59.392532] [<c43b6f9d>] ? pnpacpi_suspend+0x5d/0xe0 > > [ 59.392532] [<c459c542>] ? return_to_handler+0x9/0xf > > [ 59.392532] [<c459c539>] ? ftrace_graph_caller+0x19/0x19 > > [ 59.392532] [<c43b2e12>] ? pnp_bus_suspend+0x12/0x20 > > [ 59.392532] [<c459c539>] ? ftrace_graph_caller+0x19/0x19 > > [ 59.392532] [<c4427c66>] ? dpm_run_callback+0x46/0x120 > > [ 59.392532] [<c43b2e00>] ? pnp_bus_freeze+0x20/0x20 > > [ 59.392532] [<c459c539>] ? ftrace_graph_caller+0x19/0x19 > > [ 59.392532] [<c4428659>] ? __device_suspend+0xe9/0x2a0 > > [ 59.392532] [<c4429d08>] ? dpm_suspend+0xf8/0x290 > > […] > > The full log from the suspend is attached. > > The tracing is enabled by the script `analyze_suspend.py` [1], which I > call like below. > > ``` > $ sudo ./analyze_suspend.py -config config/suspend-callgraph.cfg \ > -filter i915 > ``` > > Suspend and resume with tracing enabled works fine. > > Please tell me, if you need anything else from my side. > > > Kind regards, > > Paul > > > [1] https://01.org/suspendresume/ > [2] https://github.com/01org/suspendresume/
