Re: Suspend-resume failure on Intel Eagle Lake Core2Duo

[Tomi Sarvela]

On 28/07/17 17:50, Thomas Gleixner wrote:
> On Fri, 28 Jul 2017, Tomi Sarvela wrote:
> > On 28/07/17 17:13, Thomas Gleixner wrote:
> > > On Fri, 28 Jul 2017, Tomi Sarvela wrote:
> > > > On 28/07/17 16:15, Thomas Gleixner wrote:
> > > > > Another question. Is the machine completely dead or not?
> > > >
> > > > Completely dead. Powerled is on, so host isn't shut down.
> > >
> > > So that means it does not even power the machine down. That's what I
> > > expected least.
> > >
> > > > Serial or network if don't give any signs of life.
> > >
> > > > Patch applies cleanly but still getting the same error:
> > >
> > > Sorry for the noise. I'm an idiot trying to do 10 things at once. This time
> > > it actually compiles and links.
> > >
> > > If the machine does still not powerdown with this applied, then please redo
> > > the 'platform' test and grab the trace for that one.
> >
> > This patch fixes the issue. Below is the dmesg from the testrun (sorry for the
> > spam, we're primarily testing i915 issues).
>
> Can you please retrieve the trace data from:
>
> /sys/kernel/debug/tracing/trace
>
> and provide that. The dmesg does not help much.

Right, here you go.

$ sudo cat /sys/kernel/debug/tracing/trace
# tracer: nop
#
#                              _-----=> irqs-off
#                             / _----=> need-resched
#                            | / _---=> hardirq/softirq
#                            || / _--=> preempt-depth
#                            ||| /     delay
#           TASK-PID   CPU#  ||||    TIMESTAMP  FUNCTION
#              | |       |   ||||       |         |
         rtcwake-1332  [000] d..1    64.411098: suspend_device_irqs: 
presuspend 0 state 00400600
         rtcwake-1332  [000] d..1    64.411101: suspend_device_irqs: 
postsuspend 0 state 00400600
         rtcwake-1332  [000] d..1    64.411102: suspend_device_irqs: 
presuspend 1 state 00030000
         rtcwake-1332  [000] d..1    64.411103: suspend_device_irqs: 
postsuspend 1 state 00030000
         rtcwake-1332  [000] d..1    64.411104: suspend_device_irqs: 
presuspend 2 state 00030000
         rtcwake-1332  [000] d..1    64.411104: suspend_device_irqs: 
postsuspend 2 state 00030000
         rtcwake-1332  [000] d..1    64.411105: suspend_device_irqs: 
presuspend 3 state 00030000
         rtcwake-1332  [000] d..1    64.411106: suspend_device_irqs: 
postsuspend 3 state 00030000
         rtcwake-1332  [000] d..1    64.411107: suspend_device_irqs: 
presuspend 4 state 00031000
         rtcwake-1332  [000] d..1    64.411107: suspend_device_irqs: 
postsuspend 4 state 00031000
         rtcwake-1332  [000] d..1    64.411108: suspend_device_irqs: 
presuspend 5 state 00030000
         rtcwake-1332  [000] d..1    64.411108: suspend_device_irqs: 
postsuspend 5 state 00030000
         rtcwake-1332  [000] d..1    64.411109: suspend_device_irqs: 
presuspend 6 state 00030000
         rtcwake-1332  [000] d..1    64.411110: suspend_device_irqs: 
postsuspend 6 state 00030000
         rtcwake-1332  [000] d..1    64.411110: suspend_device_irqs: 
presuspend 7 state 00030000
         rtcwake-1332  [000] d..1    64.411111: suspend_device_irqs: 
postsuspend 7 state 00030000
         rtcwake-1332  [000] d..1    64.411112: suspend_device_irqs: 
presuspend 8 state 00401200
         rtcwake-1332  [000] d..1    64.411112: __irq_disable: 
predisable 8 state 00401200
         rtcwake-1332  [000] d..1    64.411113: __irq_disable: 
postdisable 8 state 00411200
         rtcwake-1332  [000] d..1    64.411114: suspend_device_irqs: 
postsuspend 8 state 00411200
         rtcwake-1332  [000] d..1    64.411115: suspend_device_irqs: 
presuspend 9 state 00403300
         rtcwake-1332  [000] d..1    64.411115: __irq_disable: 
predisable 9 state 00403300
         rtcwake-1332  [000] d..1    64.411116: __irq_disable: 
postdisable 9 state 00413300
         rtcwake-1332  [000] d..1    64.411116: suspend_device_irqs: 
postsuspend 9 state 00413300
         rtcwake-1332  [000] d..1    64.411117: suspend_device_irqs: 
presuspend 10 state 00030000
         rtcwake-1332  [000] d..1    64.411118: suspend_device_irqs: 
postsuspend 10 state 00030000
         rtcwake-1332  [000] d..1    64.411119: suspend_device_irqs: 
presuspend 11 state 00030000
         rtcwake-1332  [000] d..1    64.411119: suspend_device_irqs: 
postsuspend 11 state 00030000
         rtcwake-1332  [000] d..1    64.411120: suspend_device_irqs: 
presuspend 12 state 00030000
         rtcwake-1332  [000] d..1    64.411120: suspend_device_irqs: 
postsuspend 12 state 00030000
         rtcwake-1332  [000] d..1    64.411121: suspend_device_irqs: 
presuspend 13 state 00030000
         rtcwake-1332  [000] d..1    64.411122: suspend_device_irqs: 
postsuspend 13 state 00030000
         rtcwake-1332  [000] d..1    64.411122: suspend_device_irqs: 
presuspend 14 state 00030000
         rtcwake-1332  [000] d..1    64.411123: suspend_device_irqs: 
postsuspend 14 state 00030000
         rtcwake-1332  [000] d..1    64.411124: suspend_device_irqs: 
presuspend 15 state 00030000
         rtcwake-1332  [000] d..1    64.411124: suspend_device_irqs: 
postsuspend 15 state 00030000
         rtcwake-1332  [000] d..1    64.411125: suspend_device_irqs: 
presuspend 16 state 00403200
         rtcwake-1332  [000] d..1    64.411126: __irq_disable: 
predisable 16 state 00403200
         rtcwake-1332  [000] d..1    64.411126: __irq_disable: 
postdisable 16 state 00413200
         rtcwake-1332  [000] d..1    64.411127: suspend_device_irqs: 
postsuspend 16 state 00413200
         rtcwake-1332  [000] d..1    64.411128: suspend_device_irqs: 
presuspend 17 state 00033000
         rtcwake-1332  [000] d..1    64.411128: suspend_device_irqs: 
postsuspend 17 state 00033000
         rtcwake-1332  [000] d..1    64.411129: suspend_device_irqs: 
presuspend 18 state 00032000
         rtcwake-1332  [000] d..1    64.411130: suspend_device_irqs: 
postsuspend 18 state 00032000
         rtcwake-1332  [000] d..1    64.411130: suspend_device_irqs: 
presuspend 19 state 00032000
         rtcwake-1332  [000] d..1    64.411131: suspend_device_irqs: 
postsuspend 19 state 00032000
         rtcwake-1332  [000] d..1    64.411132: suspend_device_irqs: 
presuspend 20 state 00403300
         rtcwake-1332  [000] d..1    64.411132: __irq_disable: 
predisable 20 state 00403300
         rtcwake-1332  [000] d..1    64.411133: __irq_disable: 
postdisable 20 state 00413300
         rtcwake-1332  [000] d..1    64.411133: suspend_device_irqs: 
postsuspend 20 state 00413300
         rtcwake-1332  [000] d..1    64.411134: suspend_device_irqs: 
presuspend 21 state 00403300
         rtcwake-1332  [000] d..1    64.411134: __irq_disable: 
predisable 21 state 00403300
         rtcwake-1332  [000] d..1    64.411135: __irq_disable: 
postdisable 21 state 00413300
         rtcwake-1332  [000] d..1    64.411136: suspend_device_irqs: 
postsuspend 21 state 00413300
         rtcwake-1332  [000] d..1    64.411136: suspend_device_irqs: 
presuspend 22 state 00403300
         rtcwake-1332  [000] d..1    64.411137: __irq_disable: 
predisable 22 state 00403300
         rtcwake-1332  [000] d..1    64.411137: __irq_disable: 
postdisable 22 state 00413300
         rtcwake-1332  [000] d..1    64.411138: suspend_device_irqs: 
postsuspend 22 state 00413300
         rtcwake-1332  [000] d..1    64.411139: suspend_device_irqs: 
presuspend 24 state 00409600
         rtcwake-1332  [000] d..1    64.411139: suspend_device_irqs: 
postsuspend 24 state 00409600
         rtcwake-1332  [000] d..1    64.411140: suspend_device_irqs: 
presuspend 25 state 00409600
         rtcwake-1332  [000] d..1    64.411141: suspend_device_irqs: 
postsuspend 25 state 00409600
         rtcwake-1332  [000] d..1    64.411142: suspend_device_irqs: 
presuspend 26 state 00038000
         rtcwake-1332  [000] d..1    64.411142: suspend_device_irqs: 
postsuspend 26 state 00038000
         rtcwake-1332  [000] d..1    64.411143: suspend_device_irqs: 
presuspend 27 state 00038000
         rtcwake-1332  [000] d..1    64.411143: suspend_device_irqs: 
postsuspend 27 state 00038000
         rtcwake-1332  [000] d..1    64.411144: suspend_device_irqs: 
presuspend 28 state 00401200
         rtcwake-1332  [000] d..1    64.411145: __irq_disable: 
predisable 28 state 00401200
         rtcwake-1332  [000] d..1    64.411145: __irq_disable: 
postdisable 28 state 00411200
         rtcwake-1332  [000] d..1    64.411146: suspend_device_irqs: 
postsuspend 28 state 00411200
         rtcwake-1332  [001] d.H1    64.425561: mask_irq: premask 8 
state 00411200
         rtcwake-1332  [001] d.H1    64.425565: mask_irq: postmask 8 
state 00431200
         rtcwake-1332  [000] dN.1    64.436605: __irq_disable: 
predisable 25 state 00409600
         rtcwake-1332  [000] dN.1    64.436607: mask_irq: premask 25 
state 00419600
         rtcwake-1332  [000] dN.1    64.436608: mask_irq: postmask 25 
state 00439600
         rtcwake-1332  [000] dN.1    64.436609: __irq_disable: 
postdisable 25 state 00439600
         rtcwake-1332  [000] d..1 576460734.868390: __irq_disable: 
predisable 24 state 00409600
         rtcwake-1332  [000] d..1 576460734.868508: __irq_disable: 
postdisable 24 state 00419600
         rtcwake-1332  [000] d..1 576460734.868511: irq_enable: 
preenable 24 state 00419600
         rtcwake-1332  [000] d..1 576460734.868511: unmask_irq: 
preunmask 24 state 00409600
         rtcwake-1332  [000] d..1 576460734.868512: unmask_irq: 
postunmask 24 state 00409600
         rtcwake-1332  [000] d..1 576460734.868512: irq_enable: 
postenable 24 state 00409600
         rtcwake-1332  [000] dNh1 576460734.868533: mask_irq: premask 9 
state 00413200
         rtcwake-1332  [000] dNh1 576460734.868535: mask_irq: postmask 
9 state 00433200
     kworker/1:1-1039  [001] d..1 576460734.869322: irq_enable: 
preenable 25 state 00039600
     kworker/1:1-1039  [001] d..1 576460734.869324: unmask_irq: 
preunmask 25 state 00029600
     kworker/1:1-1039  [001] d..1 576460734.869325: unmask_irq: 
postunmask 25 state 00009600
     kworker/1:1-1039  [001] d..1 576460734.869326: irq_enable: 
postenable 25 state 00009600
     kworker/1:1-1039  [001] d..1 576460734.869329: __irq_disable: 
predisable 25 state 00409600
     kworker/1:1-1039  [001] d..1 576460734.869329: __irq_disable: 
postdisable 25 state 00419600
     kworker/1:1-1039  [001] d..1 576460734.869332: irq_enable: 
preenable 25 state 00419600
     kworker/1:1-1039  [001] d..1 576460734.869332: unmask_irq: 
preunmask 25 state 00409600
     kworker/1:1-1039  [001] d..1 576460734.869333: unmask_irq: 
postunmask 25 state 00409600
     kworker/1:1-1039  [001] d..1 576460734.869333: irq_enable: 
postenable 25 state 00409600
         rtcwake-1332  [000] d..1 576460734.882983: resume_irqs: 
preresume 0 state 00400600
         rtcwake-1332  [000] d..1 18446744056.289114: resume_irqs: 
postresume 0 state 00400600
         rtcwake-1332  [000] d..1 18446744056.289116: resume_irqs: 
preresume 1 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289116: resume_irqs: 
postresume 1 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289117: resume_irqs: 
preresume 2 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289118: resume_irqs: 
postresume 2 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289118: resume_irqs: 
preresume 3 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289119: resume_irqs: 
postresume 3 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289120: resume_irqs: 
preresume 4 state 00031000
         rtcwake-1332  [000] d..1 18446744056.289120: resume_irqs: 
postresume 4 state 00031000
         rtcwake-1332  [000] d..1 18446744056.289121: resume_irqs: 
preresume 5 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289122: resume_irqs: 
postresume 5 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289122: resume_irqs: 
preresume 6 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289123: resume_irqs: 
postresume 6 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289124: resume_irqs: 
preresume 7 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289124: resume_irqs: 
postresume 7 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289125: resume_irqs: 
preresume 8 state 00431200
         rtcwake-1332  [000] d..1 18446744056.289126: irq_enable: 
preenable 8 state 00431200
         rtcwake-1332  [000] d..1 18446744056.289126: unmask_irq: 
preunmask 8 state 00421200
         rtcwake-1332  [000] d..1 18446744056.289128: unmask_irq: 
postunmask 8 state 00401200
         rtcwake-1332  [000] d..1 18446744056.289128: irq_enable: 
postenable 8 state 00401200
         rtcwake-1332  [000] d..1 18446744056.289129: resume_irqs: 
postresume 8 state 00401200
         rtcwake-1332  [000] d..1 18446744056.289570: resume_irqs: 
preresume 9 state 00433200
         rtcwake-1332  [000] d..1 18446744056.289571: irq_enable: 
preenable 9 state 00433200
         rtcwake-1332  [000] d..1 18446744056.289571: unmask_irq: 
preunmask 9 state 00423200
         rtcwake-1332  [000] d..1 18446744056.289572: unmask_irq: 
postunmask 9 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289572: irq_enable: 
postenable 9 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289572: resume_irqs: 
postresume 9 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289573: resume_irqs: 
preresume 10 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289573: resume_irqs: 
postresume 10 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289588: resume_irqs: 
preresume 11 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289588: resume_irqs: 
postresume 11 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289589: resume_irqs: 
preresume 12 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289589: resume_irqs: 
postresume 12 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289589: resume_irqs: 
preresume 13 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289590: resume_irqs: 
postresume 13 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289590: resume_irqs: 
preresume 14 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289591: resume_irqs: 
postresume 14 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289591: resume_irqs: 
preresume 15 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289591: resume_irqs: 
postresume 15 state 00030000
         rtcwake-1332  [000] d..1 18446744056.289592: resume_irqs: 
preresume 16 state 00413200
         rtcwake-1332  [000] d..1 18446744056.289592: irq_enable: 
preenable 16 state 00413200
         rtcwake-1332  [000] d..1 18446744056.289593: unmask_irq: 
preunmask 16 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289593: unmask_irq: 
postunmask 16 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289594: irq_enable: 
postenable 16 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289594: resume_irqs: 
postresume 16 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289594: resume_irqs: 
preresume 17 state 00033000
         rtcwake-1332  [000] d..1 18446744056.289595: resume_irqs: 
postresume 17 state 00033000
         rtcwake-1332  [000] d..1 18446744056.289595: resume_irqs: 
preresume 18 state 00032000
         rtcwake-1332  [000] d..1 18446744056.289596: resume_irqs: 
postresume 18 state 00032000
         rtcwake-1332  [000] d..1 18446744056.289596: resume_irqs: 
preresume 19 state 00032000
         rtcwake-1332  [000] d..1 18446744056.289596: resume_irqs: 
postresume 19 state 00032000
         rtcwake-1332  [000] d..1 18446744056.289597: resume_irqs: 
preresume 20 state 00413200
         rtcwake-1332  [000] d..1 18446744056.289597: irq_enable: 
preenable 20 state 00413200
         rtcwake-1332  [000] d..1 18446744056.289598: unmask_irq: 
preunmask 20 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289598: unmask_irq: 
postunmask 20 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289598: irq_enable: 
postenable 20 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289599: resume_irqs: 
postresume 20 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289599: resume_irqs: 
preresume 21 state 00413200
         rtcwake-1332  [000] d..1 18446744056.289600: irq_enable: 
preenable 21 state 00413200
         rtcwake-1332  [000] d..1 18446744056.289600: unmask_irq: 
preunmask 21 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289600: unmask_irq: 
postunmask 21 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289601: irq_enable: 
postenable 21 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289601: resume_irqs: 
postresume 21 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289602: resume_irqs: 
preresume 22 state 00413200
         rtcwake-1332  [000] d..1 18446744056.289602: irq_enable: 
preenable 22 state 00413200
         rtcwake-1332  [000] d..1 18446744056.289602: unmask_irq: 
preunmask 22 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289603: unmask_irq: 
postunmask 22 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289603: irq_enable: 
postenable 22 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289603: resume_irqs: 
postresume 22 state 00403200
         rtcwake-1332  [000] d..1 18446744056.289604: resume_irqs: 
preresume 24 state 00409600
         rtcwake-1332  [000] d..1 18446744056.289604: resume_irqs: 
postresume 24 state 00409600
         rtcwake-1332  [000] d..1 18446744056.289604: resume_irqs: 
preresume 25 state 00409600
         rtcwake-1332  [000] d..1 18446744056.289605: resume_irqs: 
postresume 25 state 00409600
         rtcwake-1332  [000] d..1 18446744056.289605: resume_irqs: 
preresume 26 state 00038000
         rtcwake-1332  [000] d..1 18446744056.289606: resume_irqs: 
postresume 26 state 00038000
         rtcwake-1332  [000] d..1 18446744056.289606: resume_irqs: 
preresume 27 state 00038000
         rtcwake-1332  [000] d..1 18446744056.289606: resume_irqs: 
postresume 27 state 00038000
         rtcwake-1332  [000] d..1 18446744056.289607: resume_irqs: 
preresume 28 state 00411200
         rtcwake-1332  [000] d..1 18446744056.289607: irq_enable: 
preenable 28 state 00411200
         rtcwake-1332  [000] d..1 18446744056.289608: unmask_irq: 
preunmask 28 state 00401200
         rtcwake-1332  [000] d..1 18446744056.289608: unmask_irq: 
postunmask 28 state 00401200
         rtcwake-1332  [000] d..1 18446744056.289608: irq_enable: 
postenable 28 state 00401200
         rtcwake-1332  [000] d..1 18446744056.289609: resume_irqs: 
postresume 28 state 00401200


Tomi
--
Intel Finland Oy - BIC 0357606-4 - Westendinkatu 7, 02160 Espoo