All of lore.kernel.org
 help / color / mirror / Atom feed
* Kernel panic on Lenovo X60 with tracing enabled
@ 2017-03-09 12:12 Paul Menzel
  2017-03-09 14:36 ` Steven Rostedt
  0 siblings, 1 reply; 8+ messages in thread
From: Paul Menzel @ 2017-03-09 12:12 UTC (permalink / raw)
  To: Steven Rostedt, Ingo Molnar; +Cc: linux-kernel

[-- Attachment #1: Type: text/plain, Size: 4435 bytes --]

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.

 > […]
> [   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/

[-- Warning: decoded text below may be mangled, UTF-8 assumed --]
[-- Attachment #2: x60-suspend-panic.log --]
[-- Type: text/x-log; name="x60-suspend-panic.log", Size: 16957 bytes --]

[   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
[   59.392532]  [<c40b76ff>] ? suspend_devices_and_enter+0xbf/0x720
[   59.392532]  [<c404de04>] ? prepare_ftrace_return+0x94/0xc0
[   59.392532]  [<c416b9c3>] ? pm_restrict_gfp_mask+0x3/0x70
[   59.392532]  [<c416b9c3>] ? pm_restrict_gfp_mask+0x3/0x70
[   59.392532]  [<c40b806d>] ? pm_suspend+0x30d/0x380
[   59.392532]  [<c459c535>] ? ftrace_graph_caller+0x15/0x19
[   59.392532]  [<c40b68fb>] ? state_store+0x5b/0xb0
[   59.392532]  [<c40b68a0>] ? wakeup_count_show+0x70/0x70
[   59.392532]  [<c42f0935>] ? kobj_attr_store+0x15/0x30
[   59.392532]  [<c424cca5>] ? sysfs_kf_write+0x35/0x50
[   59.392532]  [<c424cc70>] ? sysfs_kf_bin_read+0xc0/0xc0
[   59.392532]  [<c424c26f>] ? kernfs_fop_write+0xff/0x1a0
[   59.392532]  [<c424cc70>] ? sysfs_kf_bin_read+0xc0/0xc0
[   59.392532]  [<c424c170>] ? kernfs_seq_start+0x80/0x80
[   59.392532]  [<c41d6520>] ? __vfs_write+0x20/0x50
[   59.392532]  [<c41d6ada>] ? vfs_write+0x9a/0x1c0
[   59.392532]  [<c41f4039>] ? __fget_light+0x9/0x60
[   59.392532]  [<c41d7b59>] ? SyS_write+0x49/0xb0
[   59.392532]  [<c4003802>] ? do_fast_syscall_32+0x92/0x220
[   59.392532]  [<c459b4ca>] ? sysenter_past_esp+0x47/0x75
[   59.688878] hpet1: lost 19 rtc interrupts
[   59.694680] ---[ end trace eb0d52cad33050f7 ]---
[   59.701013] Kernel panic - not syncing:
[   59.705005] CPU: 0 PID: 774 Comm: analyze_suspend Tainted: G        W   E   4.9.0-0.bpo.2-686-pae #1 Debian 4.9.13-1~bpo8+1
[   59.705005] Hardware name: LENOVO 636338U/636338U, BIOS CBET4000 4.5-1169-g7129ccb 03/09/2017
[   59.705005]  f6919d9c c42ee6ec f765e800 c47ce480 c41640d0 c47ce480 f765e800 c47ce480
[   59.705005]  00000002 f6919db0 00000000 f36f49d4 00000000 00000002 f6919db8 c459c539
[   59.705005]  f6919dec c459c539 00000002 af057ff9 f765e800 00000002 00000000 00000000
[   59.705005] Call Trace:
[   59.705005]  [<c42ee6ec>] ? dump_stack+0x55/0x79
[   59.705005]  [<c41640d0>] ? panic+0x97/0x1c9
[   59.705005]  [<c459c539>] ? ftrace_graph_caller+0x19/0x19
[   59.705005]  [<c43b2e12>] ? pnp_bus_suspend+0x12/0x20
[   59.705005]  [<c459c539>] ? ftrace_graph_caller+0x19/0x19
[   59.705005]  [<c4427c66>] ? dpm_run_callback+0x46/0x120
[   59.705005]  [<c43b2e00>] ? pnp_bus_freeze+0x20/0x20
[   59.705005]  [<c459c539>] ? ftrace_graph_caller+0x19/0x19
[   59.705005]  [<c4428659>] ? __device_suspend+0xe9/0x2a0
[   59.705005]  [<c4429d08>] ? dpm_suspend+0xf8/0x290
[   59.705005]  [<c40b76ff>] ? suspend_devices_and_enter+0xbf/0x720
[   59.705005]  [<c404de04>] ? prepare_ftrace_return+0x94/0xc0
[   59.705005]  [<c416b9c3>] ? pm_restrict_gfp_mask+0x3/0x70
[   59.705005]  [<c416b9c3>] ? pm_restrict_gfp_mask+0x3/0x70
[   59.705005]  [<c40b806d>] ? pm_suspend+0x30d/0x380
[   59.705005]  [<c459c535>] ? ftrace_graph_caller+0x15/0x19
[   59.705005]  [<c40b68fb>] ? state_store+0x5b/0xb0
[   59.705005]  [<c40b68a0>] ? wakeup_count_show+0x70/0x70
[   59.705005]  [<c42f0935>] ? kobj_attr_store+0x15/0x30
[   59.705005]  [<c424cca5>] ? sysfs_kf_write+0x35/0x50
[   59.705005]  [<c424cc70>] ? sysfs_kf_bin_read+0xc0/0xc0
[   59.705005]  [<c424c26f>] ? kernfs_fop_write+0xff/0x1a0
[   59.705005]  [<c424cc70>] ? sysfs_kf_bin_read+0xc0/0xc0
[   59.705005]  [<c424c170>] ? kernfs_seq_start+0x80/0x80
[   59.705005]  [<c41d6520>] ? __vfs_write+0x20/0x50
[   59.705005]  [<c41d6ada>] ? vfs_write+0x9a/0x1c0
[   59.705005]  [<c41f4039>] ? __fget_light+0x9/0x60
[   59.705005]  [<c41d7b59>] ? SyS_write+0x49/0xb0
[   59.705005]  [<c4003802>] ? do_fast_syscall_32+0x92/0x220
[   59.705005]  [<c459b4ca>] ? sysenter_past_esp+0x47/0x75
[   59.705005] Kernel Offset: 0x3000000 from 0xc1000000 (relocation range: 0xc0000000-0xf81fdfff)
[   59.705005] ---[ end Kernel panic - not syncing:
[   59.985747] ------------[ cut here ]------------
[   59.989743] WARNING: CPU: 0 PID: 774 at /build/linux-ROBWaj/linux-4.9.13/arch/x86/kernel/smp.c:127 trigger_load_balance+0xef/0x180
[   59.989743] 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.989743] CPU: 0 PID: 774 Comm: analyze_suspend Tainted: G        W   E   4.9.0-0.bpo.2-686-pae #1 Debian 4.9.13-1~bpo8+1
[   59.989743] Hardware name: LENOVO 636338U/636338U, BIOS CBET4000 4.5-1169-g7129ccb 03/09/2017
[   59.989743]  f6919c40 c42ee6ec 00000000 00000000 c4067c0a c46944b0 00000000 00000306
[   59.989743]  c4689f04 0000007f c40a50af 0000007f 00000009 c40a50af 00000001 00000000
[   59.989743]  ffff159d f6919c54 c4067d1a 00000009 00000000 00000000 f6919c68 c40a50af
[   59.989743] Call Trace:
[   59.989743]  [<c42ee6ec>] ? dump_stack+0x55/0x79
[   59.989743]  [<c4067c0a>] ? __warn+0xea/0x110
[   59.989743]  [<c40a50af>] ? trigger_load_balance+0xef/0x180
[   59.989743]  [<c40a50af>] ? trigger_load_balance+0xef/0x180
[   59.989743]  [<c4067d1a>] ? warn_slowpath_null+0x2a/0x30
[   59.989743]  [<c40a50af>] ? trigger_load_balance+0xef/0x180
[   59.989743]  [<c40d6b2f>] ? update_process_times+0x3f/0x50
[   59.989743]  [<c40e7b66>] ? tick_sched_handle.isra.11+0x26/0x60
[   59.989743]  [<c40e86f7>] ? tick_sched_timer+0x37/0x80
[   59.989743]  [<c40d757a>] ? __remove_hrtimer+0x3a/0x70
[   59.989743]  [<c40d7b57>] ? __hrtimer_run_queues+0xc7/0x260
[   59.989743]  [<c40e86c0>] ? tick_sched_do_timer+0x40/0x40
[   59.989743]  [<c40d7fbc>] ? hrtimer_interrupt+0x8c/0x1a0
[   59.989743]  [<c4047310>] ? local_apic_timer_interrupt+0x30/0x60
[   59.989743]  [<c459c863>] ? smp_apic_timer_interrupt+0x33/0x50
[   59.989743]  [<c459bf78>] ? apic_timer_interrupt+0x34/0x3c
[   59.989743]  [<c41641c3>] ? panic+0x18a/0x1c9
[   59.989743]  [<c459c539>] ? ftrace_graph_caller+0x19/0x19
[   59.989743]  [<c43b2e12>] ? pnp_bus_suspend+0x12/0x20
[   59.989743]  [<c459c539>] ? ftrace_graph_caller+0x19/0x19
[   59.989743]  [<c4427c66>] ? dpm_run_callback+0x46/0x120
[   59.989743]  [<c43b2e00>] ? pnp_bus_freeze+0x20/0x20
[   59.989743]  [<c459c539>] ? ftrace_graph_caller+0x19/0x19
[   59.989743]  [<c4428659>] ? __device_suspend+0xe9/0x2a0
[   59.989743]  [<c4429d08>] ? dpm_suspend+0xf8/0x290
[   59.989743]  [<c40b76ff>] ? suspend_devices_and_enter+0xbf/0x720
[   59.989743]  [<c404de04>] ? prepare_ftrace_return+0x94/0xc0
[   59.989743]  [<c416b9c3>] ? pm_restrict_gfp_mask+0x3/0x70
[   59.989743]  [<c416b9c3>] ? pm_restrict_gfp_mask+0x3/0x70
[   59.989743]  [<c40b806d>] ? pm_suspend+0x30d/0x380
[   59.989743]  [<c459c535>] ? ftrace_graph_caller+0x15/0x19
[   59.989743]  [<c40b68fb>] ? state_store+0x5b/0xb0
[   59.989743]  [<c40b68a0>] ? wakeup_count_show+0x70/0x70
[   59.989743]  [<c42f0935>] ? kobj_attr_store+0x15/0x30
[   59.989743]  [<c424cca5>] ? sysfs_kf_write+0x35/0x50
[   59.989743]  [<c424cc70>] ? sysfs_kf_bin_read+0xc0/0xc0
[   59.989743]  [<c424c26f>] ? kernfs_fop_write+0xff/0x1a0
[   59.989743]  [<c424cc70>] ? sysfs_kf_bin_read+0xc0/0xc0
[   59.989743]  [<c424c170>] ? kernfs_seq_start+0x80/0x80
[   59.989743]  [<c41d6520>] ? __vfs_write+0x20/0x50
[   59.989743]  [<c41d6ada>] ? vfs_write+0x9a/0x1c0
[   59.989743]  [<c41f4039>] ? __fget_light+0x9/0x60
[   59.989743]  [<c41d7b59>] ? SyS_write+0x49/0xb0
[   59.989743]  [<c4003802>] ? do_fast_syscall_32+0x92/0x220
[   59.989743]  [<c459b4ca>] ? sysenter_past_esp+0x47/0x75
[   59.989743] ---[ end trace eb0d52cad33050f8 ]---
[   60.550602] hpet1: lost 54 rtc interrupts
[   60.554527] ------------[ cut here ]------------
[   60.554527] WARNING: CPU: 0 PID: 774 at /build/linux-ROBWaj/linux-4.9.13/kernel/time/tick-broadcast.c:642 tick_handle_oneshot_broadcast+0x184/0x1a0
[   60.554527] 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)
[   60.554527] CPU: 0 PID: 774 Comm: analyze_suspend Tainted: G        W   E   4.9.0-0.bpo.2-686-pae #1 Debian 4.9.13-1~bpo8+1
[   60.554527] Hardware name: LENOVO 636338U/636338U, BIOS CBET4000 4.5-1169-g7129ccb 03/09/2017
[   60.554527]  f742ff34 c42ee6ec 00000000 00000000 c4067c0a c46944b0 00000000 00000306
[   60.554527]  c4699ad8 00000282 c40e6c94 00000282 00000009 c40e6c94 c48b2f74 7fffffff
[   60.554527]  0000000e f742ff48 c4067d1a 00000009 00000000 00000000 f742ff74 c40e6c94
[   60.554527] Call Trace:
[   60.554527]  <IRQ> [   60.554527]  [<c42ee6ec>] ? dump_stack+0x55/0x79
[   60.554527]  [<c4067c0a>] ? __warn+0xea/0x110
[   60.554527]  [<c40e6c94>] ? tick_handle_oneshot_broadcast+0x184/0x1a0
[   60.554527]  [<c40e6c94>] ? tick_handle_oneshot_broadcast+0x184/0x1a0
[   60.554527]  [<c4067d1a>] ? warn_slowpath_null+0x2a/0x30
[   60.554527]  [<c40e6c94>] ? tick_handle_oneshot_broadcast+0x184/0x1a0
[   60.554527]  [<c40251d2>] ? timer_interrupt+0x12/0x20
[   60.554527]  [<c40c42c8>] ? __handle_irq_event_percpu+0x78/0x1a0
[   60.554527]  [<c404dd8e>] ? prepare_ftrace_return+0x1e/0xc0
[   60.554527]  [<c459c535>] ? ftrace_graph_caller+0x15/0x19
[   60.554527]  [<c404dd8e>] ? prepare_ftrace_return+0x1e/0xc0
[   60.554527]  [<c404dd8e>] ? prepare_ftrace_return+0x1e/0xc0
[   60.554527]  [<c40c441b>] ? handle_irq_event_percpu+0x2b/0x70
[   60.554527]  [<c40c448f>] ? handle_irq_event+0x2f/0x50
[   60.554527]  [<c40c77dd>] ? handle_edge_irq+0x6d/0x120
[   60.554527]  [<c40c7770>] ? handle_level_irq+0xe0/0xe0
[   60.554527]  [<c4024d12>] ? handle_irq+0x52/0x70
[   60.554527]  <EOI> [   60.554527]  [<c459c7ac>] ? do_IRQ+0x3c/0xc0
[   60.554527]  [<c40d0c1a>] ? rcu_irq_exit+0xa/0x50
[   60.554527]  [<c459bc73>] ? common_interrupt+0x33/0x38
[   60.554527]  [<c41641c3>] ? panic+0x18a/0x1c9
[   60.554527]  [<c459c539>] ? ftrace_graph_caller+0x19/0x19
[   60.554527]  [<c43b2e12>] ? pnp_bus_suspend+0x12/0x20
[   60.554527]  [<c459c539>] ? ftrace_graph_caller+0x19/0x19
[   60.554527]  [<c4427c66>] ? dpm_run_callback+0x46/0x120
[   60.554527]  [<c43b2e00>] ? pnp_bus_freeze+0x20/0x20
[   60.554527]  [<c459c539>] ? ftrace_graph_caller+0x19/0x19
[   60.554527]  [<c4428659>] ? __device_suspend+0xe9/0x2a0
[   60.554527]  [<c4429d08>] ? dpm_suspend+0xf8/0x290
[   60.554527]  [<c40b76ff>] ? suspend_devices_and_enter+0xbf/0x720
[   60.554527]  [<c404de04>] ? prepare_ftrace_return+0x94/0xc0
[   60.554527]  [<c416b9c3>] ? pm_restrict_gfp_mask+0x3/0x70
[   60.554527]  [<c416b9c3>] ? pm_restrict_gfp_mask+0x3/0x70
[   60.554527]  [<c40b806d>] ? pm_suspend+0x30d/0x380
[   60.554527]  [<c459c535>] ? ftrace_graph_caller+0x15/0x19
[   60.554527]  [<c40b68fb>] ? state_store+0x5b/0xb0
[   60.554527]  [<c40b68a0>] ? wakeup_count_show+0x70/0x70
[   60.554527]  [<c42f0935>] ? kobj_attr_store+0x15/0x30
[   60.554527]  [<c424cca5>] ? sysfs_kf_write+0x35/0x50
[   60.554527]  [<c424cc70>] ? sysfs_kf_bin_read+0xc0/0xc0
[   60.554527]  [<c424c26f>] ? kernfs_fop_write+0xff/0x1a0
[   60.554527]  [<c424cc70>] ? sysfs_kf_bin_read+0xc0/0xc0
[   60.554527]  [<c424c170>] ? kernfs_seq_start+0x80/0x80
[   60.554527]  [<c41d6520>] ? __vfs_write+0x20/0x50
[   60.554527]  [<c41d6ada>] ? vfs_write+0x9a/0x1c0
[   60.554527]  [<c41f4039>] ? __fget_light+0x9/0x60
[   60.554527]  [<c41d7b59>] ? SyS_write+0x49/0xb0
[   60.554527]  [<c4003802>] ? do_fast_syscall_32+0x92/0x220
[   60.554527]  [<c459b4ca>] ? sysenter_past_esp+0x47/0x75
[   60.554527] ---[ end trace eb0d52cad33050f9 ]---

^ permalink raw reply	[flat|nested] 8+ messages in thread

* Re: Kernel panic on Lenovo X60 with tracing enabled
  2017-03-09 12:12 Kernel panic on Lenovo X60 with tracing enabled Paul Menzel
@ 2017-03-09 14:36 ` Steven Rostedt
  2017-03-09 16:16   ` Josh Poimboeuf
  0 siblings, 1 reply; 8+ messages in thread
From: Steven Rostedt @ 2017-03-09 14:36 UTC (permalink / raw)
  To: Paul Menzel; +Cc: Ingo Molnar, linux-kernel, Josh Poimboeuf

On Thu, 9 Mar 2017 13:12:28 +0100
Paul Menzel <pmenzel@molgen.mpg.de> 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/

^ permalink raw reply	[flat|nested] 8+ messages in thread

* Re: Kernel panic on Lenovo X60 with tracing enabled
  2017-03-09 14:36 ` Steven Rostedt
@ 2017-03-09 16:16   ` Josh Poimboeuf
  2017-03-09 16:29     ` Steven Rostedt
  0 siblings, 1 reply; 8+ messages in thread
From: Josh Poimboeuf @ 2017-03-09 16:16 UTC (permalink / raw)
  To: Steven Rostedt; +Cc: Paul Menzel, Ingo Molnar, linux-kernel

On Thu, Mar 09, 2017 at 09:36:30AM -0500, Steven Rostedt wrote:
> On Thu, 9 Mar 2017 13:12:28 +0100
> Paul Menzel <pmenzel@molgen.mpg.de> 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?

Yeah, assuming it's the same problem, then this is caused by
CONFIG_CC_OPTIMIZE_FOR_SIZE.  It would be fixed by changing it to
CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE.

-- 
Josh

^ permalink raw reply	[flat|nested] 8+ messages in thread

* Re: Kernel panic on Lenovo X60 with tracing enabled
  2017-03-09 16:16   ` Josh Poimboeuf
@ 2017-03-09 16:29     ` Steven Rostedt
  2017-03-09 20:43       ` Paul Menzel
  0 siblings, 1 reply; 8+ messages in thread
From: Steven Rostedt @ 2017-03-09 16:29 UTC (permalink / raw)
  To: Josh Poimboeuf; +Cc: Paul Menzel, Ingo Molnar, linux-kernel

On Thu, 9 Mar 2017 10:16:02 -0600
Josh Poimboeuf <jpoimboe@redhat.com> wrote:

> On Thu, Mar 09, 2017 at 09:36:30AM -0500, Steven Rostedt wrote:
> > On Thu, 9 Mar 2017 13:12:28 +0100
> > Paul Menzel <pmenzel@molgen.mpg.de> 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?
> 
> Yeah, assuming it's the same problem, then this is caused by
> CONFIG_CC_OPTIMIZE_FOR_SIZE.  It would be fixed by changing it to
> CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE.
> 

Paul, do you have CONFIG_CC_OPTIMIZE_FOR_SIZE enabled? Can you set it
to PERFORMANCE and see if the problem goes away?

-- Steve

^ permalink raw reply	[flat|nested] 8+ messages in thread

* Re: Kernel panic on Lenovo X60 with tracing enabled
  2017-03-09 16:29     ` Steven Rostedt
@ 2017-03-09 20:43       ` Paul Menzel
  2017-03-09 23:04         ` Josh Poimboeuf
  0 siblings, 1 reply; 8+ messages in thread
From: Paul Menzel @ 2017-03-09 20:43 UTC (permalink / raw)
  To: Steven Rostedt; +Cc: Josh Poimboeuf, Ingo Molnar, linux-kernel

On 2017-03-09 17:29, Steven Rostedt wrote:
> On Thu, 9 Mar 2017 10:16:02 -0600
> Josh Poimboeuf wrote:
> 
>> On Thu, Mar 09, 2017 at 09:36:30AM -0500, Steven Rostedt wrote:
>> > On Thu, 9 Mar 2017 13:12:28 +0100
>> > Paul Menzel wrote:

>> > > 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?
>> 
>> Yeah, assuming it's the same problem, then this is caused by
>> CONFIG_CC_OPTIMIZE_FOR_SIZE.  It would be fixed by changing it to
>> CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE.
>> 
> 
> Paul, do you have CONFIG_CC_OPTIMIZE_FOR_SIZE enabled? Can you set it
> to PERFORMANCE and see if the problem goes away?

As far as I can see, the Debian Linux kernel is built with 
`CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE=y`.

So it might be a different problem?


Kind regards,

Paul

^ permalink raw reply	[flat|nested] 8+ messages in thread

* Re: Kernel panic on Lenovo X60 with tracing enabled
  2017-03-09 20:43       ` Paul Menzel
@ 2017-03-09 23:04         ` Josh Poimboeuf
  2017-03-10  8:53           ` Paul Menzel
  0 siblings, 1 reply; 8+ messages in thread
From: Josh Poimboeuf @ 2017-03-09 23:04 UTC (permalink / raw)
  To: Paul Menzel; +Cc: Steven Rostedt, Ingo Molnar, linux-kernel

On Thu, Mar 09, 2017 at 09:43:47PM +0100, Paul Menzel wrote:
> On 2017-03-09 17:29, Steven Rostedt wrote:
> > On Thu, 9 Mar 2017 10:16:02 -0600
> > Josh Poimboeuf wrote:
> > 
> > > On Thu, Mar 09, 2017 at 09:36:30AM -0500, Steven Rostedt wrote:
> > > > On Thu, 9 Mar 2017 13:12:28 +0100
> > > > Paul Menzel wrote:
> 
> > > > > 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?
> > > 
> > > Yeah, assuming it's the same problem, then this is caused by
> > > CONFIG_CC_OPTIMIZE_FOR_SIZE.  It would be fixed by changing it to
> > > CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE.
> > > 
> > 
> > Paul, do you have CONFIG_CC_OPTIMIZE_FOR_SIZE enabled? Can you set it
> > to PERFORMANCE and see if the problem goes away?
> 
> As far as I can see, the Debian Linux kernel is built with
> `CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE=y`.
> 
> So it might be a different problem?

Is it a stock Debian kernel?  If so, do you have a link where it can be
downloaded?

-- 
Josh

^ permalink raw reply	[flat|nested] 8+ messages in thread

* Re: Kernel panic on Lenovo X60 with tracing enabled
  2017-03-09 23:04         ` Josh Poimboeuf
@ 2017-03-10  8:53           ` Paul Menzel
  2017-03-10 18:18             ` Josh Poimboeuf
  0 siblings, 1 reply; 8+ messages in thread
From: Paul Menzel @ 2017-03-10  8:53 UTC (permalink / raw)
  To: Josh Poimboeuf; +Cc: Steven Rostedt, Ingo Molnar, linux-kernel

Dear Josh,


On 03/10/17 00:04, Josh Poimboeuf wrote:
> On Thu, Mar 09, 2017 at 09:43:47PM +0100, Paul Menzel wrote:
>> On 2017-03-09 17:29, Steven Rostedt wrote:
>>> On Thu, 9 Mar 2017 10:16:02 -0600 Josh Poimboeuf wrote:
>>>
>>>> On Thu, Mar 09, 2017 at 09:36:30AM -0500, Steven Rostedt wrote:
>>>>> On Thu, 9 Mar 2017 13:12:28 +0100 Paul Menzel wrote:
>>
>>>>>> 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?
>>>>
>>>> Yeah, assuming it's the same problem, then this is caused by
>>>> CONFIG_CC_OPTIMIZE_FOR_SIZE.  It would be fixed by changing it to
>>>> CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE.
>>>
>>> Paul, do you have CONFIG_CC_OPTIMIZE_FOR_SIZE enabled? Can you set it
>>> to PERFORMANCE and see if the problem goes away?
>>
>> As far as I can see, the Debian Linux kernel is built with
>> `CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE=y`.
>>
>> So it might be a different problem?
>
> Is it a stock Debian kernel?  If so, do you have a link where it can be
> downloaded?

Sure, hopefully [3] works for you.

```
dpkg -x 
linux-image-4.9.0-0.bpo.2-686-pae-unsigned_4.9.13-1~bpo8+1_i386.deb 
target-directory
```


Kind regards,

Paul


[1] 
https://packages.debian.org/jessie-backports/linux-image-4.9.0-0.bpo.2-686-pae-unsigned
[2] 
https://packages.debian.org/jessie-backports/i386/linux-image-4.9.0-0.bpo.2-686-pae-unsigned/download
[3] 
http://ftp.us.debian.org/debian/pool/main/l/linux/linux-image-4.9.0-0.bpo.2-686-pae-unsigned_4.9.13-1~bpo8+1_i386.deb
[4] https://linux.die.net/man/1/dpkg

^ permalink raw reply	[flat|nested] 8+ messages in thread

* Re: Kernel panic on Lenovo X60 with tracing enabled
  2017-03-10  8:53           ` Paul Menzel
@ 2017-03-10 18:18             ` Josh Poimboeuf
  0 siblings, 0 replies; 8+ messages in thread
From: Josh Poimboeuf @ 2017-03-10 18:18 UTC (permalink / raw)
  To: Paul Menzel; +Cc: Steven Rostedt, Ingo Molnar, linux-kernel

On Fri, Mar 10, 2017 at 09:53:29AM +0100, Paul Menzel wrote:
> Dear Josh,
> 
> 
> On 03/10/17 00:04, Josh Poimboeuf wrote:
> > On Thu, Mar 09, 2017 at 09:43:47PM +0100, Paul Menzel wrote:
> > > On 2017-03-09 17:29, Steven Rostedt wrote:
> > > > On Thu, 9 Mar 2017 10:16:02 -0600 Josh Poimboeuf wrote:
> > > > 
> > > > > On Thu, Mar 09, 2017 at 09:36:30AM -0500, Steven Rostedt wrote:
> > > > > > On Thu, 9 Mar 2017 13:12:28 +0100 Paul Menzel wrote:
> > > 
> > > > > > > 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?
> > > > > 
> > > > > Yeah, assuming it's the same problem, then this is caused by
> > > > > CONFIG_CC_OPTIMIZE_FOR_SIZE.  It would be fixed by changing it to
> > > > > CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE.
> > > > 
> > > > Paul, do you have CONFIG_CC_OPTIMIZE_FOR_SIZE enabled? Can you set it
> > > > to PERFORMANCE and see if the problem goes away?
> > > 
> > > As far as I can see, the Debian Linux kernel is built with
> > > `CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE=y`.
> > > 
> > > So it might be a different problem?
> > 
> > Is it a stock Debian kernel?  If so, do you have a link where it can be
> > downloaded?
> 
> Sure, hopefully [3] works for you.
> 
> ```
> dpkg -x linux-image-4.9.0-0.bpo.2-686-pae-unsigned_4.9.13-1~bpo8+1_i386.deb
> target-directory

Thanks, that helped.  One of the functions on the stack trace has the
buggy prologue:

acpi_pm_device_sleep_wake:
  c138048c:	57                   	push   %edi
  c138048d:	8d 7c 24 08          	lea    0x8(%esp),%edi
  c1380491:	83 e4 f8             	and    $0xfffffff8,%esp
  c1380494:	ff 77 fc             	pushl  -0x4(%edi)
  c1380497:	55                   	push   %ebp
  c1380498:	89 e5                	mov    %esp,%ebp
  c138049a:	57                   	push   %edi
  c138049b:	56                   	push   %esi
  c138049c:	53                   	push   %ebx
  c138049d:	83 ec 0c             	sub    $0xc,%esp
  c13804a0:	e8 e7 bf 21 00       	call   0xc159c48c (mcount)

And the config file indeed has CONFIG_CC_OPTIMIZE_FOR_PERFORMANCE=y.

I found the root cause after a little digging.  drivers/acpi/Makefile is
setting '-Os' manually:

  ccflags-y                       := -Os

That line predates git history, so I don't know why it's there, but I'd
guess we can probably remove it.  I'll submit a fix for it along with
the other -maccumulate-outoing-args related patch I was going to do.

-- 
Josh

^ permalink raw reply	[flat|nested] 8+ messages in thread

end of thread, other threads:[~2017-03-10 18:19 UTC | newest]

Thread overview: 8+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2017-03-09 12:12 Kernel panic on Lenovo X60 with tracing enabled Paul Menzel
2017-03-09 14:36 ` Steven Rostedt
2017-03-09 16:16   ` Josh Poimboeuf
2017-03-09 16:29     ` Steven Rostedt
2017-03-09 20:43       ` Paul Menzel
2017-03-09 23:04         ` Josh Poimboeuf
2017-03-10  8:53           ` Paul Menzel
2017-03-10 18:18             ` Josh Poimboeuf

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.