Bug 102365 - [BAT] unchecked MSR access error: (RD|WR)MSR from/to 0x1b0 at rIP: 0xffffffff81030a4f (init_intel_energy_perf)
Summary: [BAT] unchecked MSR access error: (RD|WR)MSR from/to 0x1b0 at rIP: 0xffffffff...
Status: ASSIGNED
Alias: None
Product: DRI
Classification: Unclassified
Component: DRM/Intel (show other bugs)
Version: XOrg git
Hardware: Other All
: medium normal
Assignee: krisman
QA Contact: Intel GFX Bugs mailing list
URL:
Whiteboard: ReadyForDev
Keywords:
: 103073 (view as bug list)
Depends on:
Blocks:
 
Reported: 2017-08-23 09:23 UTC by Martin Peres
Modified: 2018-04-12 08:35 UTC (History)
2 users (show)

See Also:
i915 platform: SNB
i915 features: power/Other


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Martin Peres 2017-08-23 09:23:24 UTC
On CI_DRM_2992, the machine shard-snb3 generaed the following error while running igt@kms_flip@vblank-vs-suspend:

[  280.969486] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff81030a4f (init_intel_energy_perf.part.3+0xf/0xb0)
[  280.969488] Call Trace:
[  280.969492]  intel_bsp_resume+0x1a/0x20
[  280.969494]  bsp_resume+0x1d/0x20
[  280.969498]  syscore_resume+0x62/0x320
[  280.969501]  suspend_devices_and_enter+0xa09/0xcd0
[  280.969506]  pm_suspend+0x574/0xa00
[  280.969509]  state_store+0x82/0xf0
[  280.969514]  kobj_attr_store+0xf/0x20
[  280.969516]  sysfs_kf_write+0x45/0x60
[  280.969519]  kernfs_fop_write+0x124/0x1c0
[  280.969524]  __vfs_write+0x28/0x130
[  280.969526]  ? rcu_read_lock_sched_held+0x7a/0x90
[  280.969528]  ? rcu_sync_lockdep_assert+0x2f/0x60
[  280.969531]  ? __sb_start_write+0x10c/0x200
[  280.969535]  vfs_write+0xc8/0x1c0
[  280.969538]  SyS_write+0x49/0xb0
[  280.969542]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[  280.969544] RIP: 0033:0x7fb6721ce8f0
[  280.969546] RSP: 002b:00007ffc3ac05da8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  280.969549] RAX: ffffffffffffffda RBX: ffffffff81489d83 RCX: 00007fb6721ce8f0
[  280.969551] RDX: 0000000000000004 RSI: 00005634312da060 RDI: 0000000000000007
[  280.969552] RBP: ffffc90001147f88 R08: 00005634312d7de0 R09: 00007fb67269f700
[  280.969554] R10: 00007fb672497b58 R11: 0000000000000246 R12: 00005634312d7d00
[  280.969555] R13: 0000000000000001 R14: 0000000000000004 R15: 0000000000000004
[  280.969559]  ? __this_cpu_preempt_check+0x13/0x20
[  280.969566] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff81030a8e (init_intel_energy_perf.part.3+0x4e/0xb0)
[  280.969567] Call Trace:
[  280.969569]  intel_bsp_resume+0x1a/0x20
[  280.969571]  bsp_resume+0x1d/0x20
[  280.969573]  syscore_resume+0x62/0x320
[  280.969576]  suspend_devices_and_enter+0xa09/0xcd0
[  280.969580]  pm_suspend+0x574/0xa00
[  280.969584]  state_store+0x82/0xf0
[  280.969587]  kobj_attr_store+0xf/0x20
[  280.969589]  sysfs_kf_write+0x45/0x60
[  280.969592]  kernfs_fop_write+0x124/0x1c0
[  280.969595]  __vfs_write+0x28/0x130
[  280.969597]  ? rcu_read_lock_sched_held+0x7a/0x90
[  280.969599]  ? rcu_sync_lockdep_assert+0x2f/0x60
[  280.969601]  ? __sb_start_write+0x10c/0x200
[  280.969605]  vfs_write+0xc8/0x1c0
[  280.969608]  SyS_write+0x49/0xb0
[  280.969612]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[  280.969613] RIP: 0033:0x7fb6721ce8f0
[  280.969614] RSP: 002b:00007ffc3ac05da8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  280.969617] RAX: ffffffffffffffda RBX: ffffffff81489d83 RCX: 00007fb6721ce8f0
[  280.969619] RDX: 0000000000000004 RSI: 00005634312da060 RDI: 0000000000000007
[  280.969620] RBP: ffffc90001147f88 R08: 00005634312d7de0 R09: 00007fb67269f700
[  280.969621] R10: 00007fb672497b58 R11: 0000000000000246 R12: 00005634312d7d00
[  280.969623] R13: 0000000000000001 R14: 0000000000000004 R15: 0000000000000004
[  280.969626]  ? __this_cpu_preempt_check+0x13/0x20

Full logs: https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_2992/shard-snb3/igt@kms_flip@vblank-vs-suspend.html
Comment 3 Marta Löfstedt 2017-10-03 12:00:54 UTC
*** Bug 103073 has been marked as a duplicate of this bug. ***
Comment 4 krisman 2017-10-12 04:33:14 UTC
Submitted this patch to prevent the issue:

https://lkml.org/lkml/2017/10/12/3
Comment 6 Marta Löfstedt 2017-10-18 06:57:08 UTC
(In reply to krisman from comment #4)
> Submitted this patch to prevent the issue:
> 
> https://lkml.org/lkml/2017/10/12/3

Is there a kernel bug that we could track for this?
Comment 7 Marta Löfstedt 2017-11-13 09:01:48 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3331/shard-snb1/igt@kms_plane@plane-panning-bottom-right-suspend-pipe-a-planes.html

	

[  910.771292] Suspending console(s) (use no_console_suspend to debug)
[  910.772898] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  910.773237] sd 0:0:0:0: [sda] Stopping disk
[  911.006463]  cache: parent cpu1 should not be sleeping
[  911.010897]  cache: parent cpu2 should not be sleeping
[  911.015473]  cache: parent cpu3 should not be sleeping
[  911.020325]  cache: parent cpu4 should not be sleeping
[  911.025284]  cache: parent cpu5 should not be sleeping
[  911.030413]  cache: parent cpu6 should not be sleeping
[  911.035648]  cache: parent cpu7 should not be sleeping
[  911.076933] sd 0:0:0:0: [sda] Starting disk
[  911.496816] Suspending console(s) (use no_console_suspend to debug)
[  911.498479] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  911.498827] sd 0:0:0:0: [sda] Stopping disk
[  911.726397] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff8102f57f (init_intel_energy_perf.part.3+0xf/0xb0)
[  911.726398] Call Trace:
[  911.726403]  intel_bsp_resume+0x1a/0x20
[  911.726405]  bsp_resume+0x1d/0x20
[  911.726408]  syscore_resume+0x62/0x320
[  911.726411]  suspend_devices_and_enter+0xa58/0xcf0
[  911.726416]  pm_suspend+0x4f0/0x9d0
[  911.726419]  state_store+0x82/0xf0
[  911.726424]  kobj_attr_store+0xf/0x20
[  911.726427]  sysfs_kf_write+0x45/0x60
[  911.726430]  kernfs_fop_write+0x124/0x1c0
[  911.726434]  __vfs_write+0x28/0x130
[  911.726437]  ? rcu_read_lock_sched_held+0x7a/0x90
[  911.726439]  ? rcu_sync_lockdep_assert+0x2f/0x60
[  911.726442]  ? __sb_start_write+0x10c/0x200
[  911.726445]  vfs_write+0xc8/0x1c0
[  911.726448]  SyS_write+0x49/0xb0
[  911.726452]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[  911.726454] RIP: 0033:0x7f0183a2d8f0
[  911.726456] RSP: 002b:00007ffe6cdf01b8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  911.726459] RAX: ffffffffffffffda RBX: ffffffff81492083 RCX: 00007f0183a2d8f0
[  911.726461] RDX: 0000000000000004 RSI: 000055fbc61a0060 RDI: 000000000000000a
[  911.726462] RBP: ffffc90000193f88 R08: 000055fbc619dde0 R09: 00007f0183f01700
[  911.726463] R10: 00007f0183cf6b58 R11: 0000000000000246 R12: 000055fbc619dd00
[  911.726465] R13: 0000000000000001 R14: 0000000000000004 R15: 0000000000000004
[  911.726468]  ? __this_cpu_preempt_check+0x13/0x20
[  911.726475] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff8102f5be (init_intel_energy_perf.part.3+0x4e/0xb0)
[  911.726476] Call Trace:
[  911.726478]  intel_bsp_resume+0x1a/0x20
[  911.726480]  bsp_resume+0x1d/0x20
[  911.726483]  syscore_resume+0x62/0x320
[  911.726485]  suspend_devices_and_enter+0xa58/0xcf0
[  911.726490]  pm_suspend+0x4f0/0x9d0
[  911.726493]  state_store+0x82/0xf0
[  911.726497]  kobj_attr_store+0xf/0x20
[  911.726499]  sysfs_kf_write+0x45/0x60
[  911.726502]  kernfs_fop_write+0x124/0x1c0
[  911.726505]  __vfs_write+0x28/0x130
[  911.726507]  ? rcu_read_lock_sched_held+0x7a/0x90
[  911.726509]  ? rcu_sync_lockdep_assert+0x2f/0x60
[  911.726512]  ? __sb_start_write+0x10c/0x200
[  911.726515]  vfs_write+0xc8/0x1c0
[  911.726518]  SyS_write+0x49/0xb0
[  911.726522]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[  911.726523] RIP: 0033:0x7f0183a2d8f0
[  911.726525] RSP: 002b:00007ffe6cdf01b8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  911.726527] RAX: ffffffffffffffda RBX: ffffffff81492083 RCX: 00007f0183a2d8f0
[  911.726529] RDX: 0000000000000004 RSI: 000055fbc61a0060 RDI: 000000000000000a
[  911.726530] RBP: ffffc90000193f88 R08: 000055fbc619dde0 R09: 00007f0183f01700
[  911.726532] R10: 00007f0183cf6b58 R11: 0000000000000246 R12: 000055fbc619dd00
[  911.726533] R13: 0000000000000001 R14: 0000000000000004 R15: 0000000000000004
[  911.726536]  ? __this_cpu_preempt_check+0x13/0x20
[  911.730425]  cache: parent cpu1 should not be sleeping
[  911.734915]  cache: parent cpu2 should not be sleeping
[  911.739511]  cache: parent cpu3 should not be sleeping
[  911.744369]  cache: parent cpu4 should not be sleeping
[  911.749334]  cache: parent cpu5 should not be sleeping
[  911.754512]  cache: parent cpu6 should not be sleeping
[  911.759794]  cache: parent cpu7 should not be sleeping
[  911.792018] mei_me 0000:00:16.0: less data available than length=00000003.
[  911.801258] sd 0:0:0:0: [sda] Starting disk
Comment 8 Marta Löfstedt 2017-11-15 10:08:22 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/IGT_3985/fi-snb-2600/igt@kms_pipe_crc_basic@suspend-read-crc-pipe-b.html

<6>[  397.728312] ACPI: Preparing to enter system sleep state S3
<6>[  397.739749] PM: Saving platform NVS memory
<6>[  397.741156] Disabling non-boot CPUs ...
<6>[  397.755464] smpboot: CPU 1 is now offline
<6>[  397.768131] smpboot: CPU 2 is now offline
<6>[  397.784517] smpboot: CPU 3 is now offline
<6>[  397.800551] smpboot: CPU 4 is now offline
<6>[  397.816131] smpboot: CPU 5 is now offline
<6>[  397.830475] smpboot: CPU 6 is now offline
<6>[  397.843868] smpboot: CPU 7 is now offline
<6>[  397.845710] ACPI: Low-level resume complete
<6>[  397.845777] PM: Restoring platform NVS memory
<4>[  397.845869] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff8102f58f (init_intel_energy_perf.part.3+0xf/0xb0)
<4>[  397.845871] Call Trace:
<4>[  397.845875]  intel_bsp_resume+0x1a/0x20
<4>[  397.845877]  bsp_resume+0x1d/0x20
<4>[  397.845881]  syscore_resume+0x62/0x320
<4>[  397.845884]  suspend_devices_and_enter+0xa58/0xcf0
<4>[  397.845889]  pm_suspend+0x4f0/0x9d0
<4>[  397.845892]  state_store+0x82/0xf0
<4>[  397.845897]  kobj_attr_store+0xf/0x20
<4>[  397.845900]  sysfs_kf_write+0x45/0x60
<4>[  397.845903]  kernfs_fop_write+0x124/0x1c0
<4>[  397.845907]  __vfs_write+0x28/0x130
<4>[  397.845909]  ? rcu_read_lock_sched_held+0x7a/0x90
<4>[  397.845911]  ? rcu_sync_lockdep_assert+0x2f/0x60
<4>[  397.845914]  ? __sb_start_write+0x10c/0x200
<4>[  397.845917]  vfs_write+0xc8/0x1c0
<4>[  397.845920]  SyS_write+0x49/0xb0
<4>[  397.845925]  entry_SYSCALL_64_fastpath+0x1c/0xb1
<4>[  397.845927] RIP: 0033:0x7fe584325290
<4>[  397.845929] RSP: 002b:00007ffd9e24cae8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
<4>[  397.845932] RAX: ffffffffffffffda RBX: ffffffff814920c3 RCX: 00007fe584325290
<4>[  397.845933] RDX: 0000000000000004 RSI: 000000000217b060 RDI: 000000000000000a
<4>[  397.845935] RBP: ffffc9000160bf88 R08: 0000000002178de0 R09: 00007fe5847fe700
<4>[  397.845936] R10: 0000000000000003 R11: 0000000000000246 R12: 0000000002178d00
<4>[  397.845937] R13: 0000000000000001 R14: 0000000000000004 R15: 0000000000000004
<4>[  397.845941]  ? __this_cpu_preempt_check+0x13/0x20
<4>[  397.845948] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff8102f5ce (init_intel_energy_perf.part.3+0x4e/0xb0)
<4>[  397.845949] Call Trace:
<4>[  397.845951]  intel_bsp_resume+0x1a/0x20
<4>[  397.845953]  bsp_resume+0x1d/0x20
<4>[  397.845955]  syscore_resume+0x62/0x320
<4>[  397.845958]  suspend_devices_and_enter+0xa58/0xcf0
<4>[  397.845963]  pm_suspend+0x4f0/0x9d0
<4>[  397.845966]  state_store+0x82/0xf0
<4>[  397.845969]  kobj_attr_store+0xf/0x20
<4>[  397.845972]  sysfs_kf_write+0x45/0x60
<4>[  397.845975]  kernfs_fop_write+0x124/0x1c0
<4>[  397.845978]  __vfs_write+0x28/0x130
<4>[  397.845980]  ? rcu_read_lock_sched_held+0x7a/0x90
<4>[  397.845981]  ? rcu_sync_lockdep_assert+0x2f/0x60
<4>[  397.845984]  ? __sb_start_write+0x10c/0x200
<4>[  397.845988]  vfs_write+0xc8/0x1c0
<4>[  397.845991]  SyS_write+0x49/0xb0
<4>[  397.845994]  entry_SYSCALL_64_fastpath+0x1c/0xb1
<4>[  397.845996] RIP: 0033:0x7fe584325290
<4>[  397.845997] RSP: 002b:00007ffd9e24cae8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
<4>[  397.846000] RAX: ffffffffffffffda RBX: ffffffff814920c3 RCX: 00007fe584325290
<4>[  397.846001] RDX: 0000000000000004 RSI: 000000000217b060 RDI: 000000000000000a
<4>[  397.846003] RBP: ffffc9000160bf88 R08: 0000000002178de0 R09: 00007fe5847fe700
<4>[  397.846004] R10: 0000000000000003 R11: 0000000000000246 R12: 0000000002178d00
<4>[  397.846006] R13: 0000000000000001 R14: 0000000000000004 R15: 0000000000000004
<4>[  397.846009]  ? __this_cpu_preempt_check+0x13/0x20
<6>[  397.846357] Enabling non-boot CPUs ...
Comment 9 Marta Löfstedt 2017-11-21 08:55:23 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_7198/shard-snb2/igt@kms_flip@vblank-vs-dpms-suspend-interruptible.html

[  599.342144] Suspending console(s) (use no_console_suspend to debug)
[  599.343700] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  599.343806] sd 0:0:0:0: [sda] Stopping disk
[  599.544447] IRQ 28: no longer affine to CPU4
[  599.564396] IRQ 16: no longer affine to CPU5
[  599.585989] IRQ 26: no longer affine to CPU6
[  599.599881] IRQ 8: no longer affine to CPU7
[  599.599898] IRQ 23: no longer affine to CPU7
[  599.599908] IRQ 30: no longer affine to CPU7
[  599.607338]  cache: parent cpu1 should not be sleeping
[  599.612004]  cache: parent cpu2 should not be sleeping
[  599.616788]  cache: parent cpu3 should not be sleeping
[  599.621973]  cache: parent cpu4 should not be sleeping
[  599.627315]  cache: parent cpu5 should not be sleeping
[  599.632732]  cache: parent cpu6 should not be sleeping
[  599.638307]  cache: parent cpu7 should not be sleeping
[  599.671164] hpet1: lost 2226 rtc interrupts
[  599.682159] sd 0:0:0:0: [sda] Starting disk
[  600.050794] Suspending console(s) (use no_console_suspend to debug)
[  600.052244] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  600.052360] sd 0:0:0:0: [sda] Stopping disk
[  600.298215] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff8102d6bb (init_intel_energy_perf.part.3+0xb/0xa0)
[  600.298217] Call Trace:
[  600.298221]  syscore_resume+0x5d/0x320
[  600.298224]  suspend_devices_and_enter+0xa43/0xcd0
[  600.298229]  pm_suspend+0x4e8/0x9d0
[  600.298232]  state_store+0x72/0xd0
[  600.298237]  kernfs_fop_write+0x110/0x1b0
[  600.298240]  __vfs_write+0x23/0x130
[  600.298243]  ? rcu_read_lock_sched_held+0x74/0x80
[  600.298245]  ? rcu_sync_lockdep_assert+0x2a/0x50
[  600.298247]  ? __sb_start_write+0xde/0x1f0
[  600.298249]  ? __sb_start_write+0xf8/0x1f0
[  600.298252]  vfs_write+0xc2/0x1b0
[  600.298255]  SyS_write+0x45/0xb0
[  600.298259]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[  600.298261] RIP: 0033:0x7f0f234e58f0
[  600.298263] RSP: 002b:00007ffcb586a3a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  600.298266] RAX: ffffffffffffffda RBX: ffffc90000c27ff0 RCX: 00007f0f234e58f0
[  600.298267] RDX: 0000000000000004 RSI: 0000556989270060 RDI: 0000000000000008
[  600.298268] RBP: 0000000000000004 R08: 000055698926dde0 R09: 00007f0f239b9700
[  600.298270] R10: 00007f0f237aeb58 R11: 0000000000000246 R12: 0000000000000046
[  600.298271] R13: 00007f0f237aeb58 R14: 000000000000270f R15: 0000000000001010
[  600.298279] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff8102d6fa (init_intel_energy_perf.part.3+0x4a/0xa0)
[  600.298280] Call Trace:
[  600.298283]  syscore_resume+0x5d/0x320
[  600.298285]  suspend_devices_and_enter+0xa43/0xcd0
[  600.298289]  pm_suspend+0x4e8/0x9d0
[  600.298292]  state_store+0x72/0xd0
[  600.298296]  kernfs_fop_write+0x110/0x1b0
[  600.298299]  __vfs_write+0x23/0x130
[  600.298301]  ? rcu_read_lock_sched_held+0x74/0x80
[  600.298302]  ? rcu_sync_lockdep_assert+0x2a/0x50
[  600.298304]  ? __sb_start_write+0xde/0x1f0
[  600.298306]  ? __sb_start_write+0xf8/0x1f0
[  600.298309]  vfs_write+0xc2/0x1b0
[  600.298312]  SyS_write+0x45/0xb0
[  600.298315]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[  600.298317] RIP: 0033:0x7f0f234e58f0
[  600.298318] RSP: 002b:00007ffcb586a3a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  600.298321] RAX: ffffffffffffffda RBX: ffffc90000c27ff0 RCX: 00007f0f234e58f0
[  600.298322] RDX: 0000000000000004 RSI: 0000556989270060 RDI: 0000000000000008
[  600.298323] RBP: 0000000000000004 R08: 000055698926dde0 R09: 00007f0f239b9700
[  600.298325] R10: 00007f0f237aeb58 R11: 0000000000000246 R12: 0000000000000046
[  600.298326] R13: 00007f0f237aeb58 R14: 000000000000270f R15: 0000000000001010
[  600.302152]  cache: parent cpu1 should not be sleeping
[  600.306859]  cache: parent cpu2 should not be sleeping
[  600.311725]  cache: parent cpu3 should not be sleeping
[  600.316866]  cache: parent cpu4 should not be sleeping
[  600.322191]  cache: parent cpu5 should not be sleeping
[  600.327620]  cache: parent cpu6 should not be sleeping
[  600.333180]  cache: parent cpu7 should not be sleeping
[  600.375675] sd 0:0:0:0: [sda] Starting disk
[  600.735596] Suspending console(s) (use no_console_suspend to debug)
[  600.737162] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  600.737278] sd 0:0:0:0: [sda] Stopping disk
[  600.976800]  cache: parent cpu1 should not be sleeping
[  600.981465]  cache: parent cpu2 should not be sleeping
[  600.986277]  cache: parent cpu3 should not be sleeping
[  600.991423]  cache: parent cpu4 should not be sleeping
[  600.996664]  cache: parent cpu5 should not be sleeping
[  601.002122]  cache: parent cpu6 should not be sleeping
[  601.007667]  cache: parent cpu7 should not be sleeping
[  601.051670] sd 0:0:0:0: [sda] Starting disk
[  601.404288] Suspending console(s) (use no_console_suspend to debug)
[  601.405979] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  601.406174] sd 0:0:0:0: [sda] Stopping disk
[  601.643639]  cache: parent cpu1 should not be sleeping
[  601.648402]  cache: parent cpu2 should not be sleeping
[  601.653790]  cache: parent cpu3 should not be sleeping
[  601.659241]  cache: parent cpu4 should not be sleeping
[  601.664602]  cache: parent cpu5 should not be sleeping
[  601.670021]  cache: parent cpu6 should not be sleeping
[  601.675606]  cache: parent cpu7 should not be sleeping
[  601.720095] sd 0:0:0:0: [sda] Starting disk
Comment 10 Marta Löfstedt 2017-12-04 07:08:38 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3431/shard-snb2/igt@kms_cursor_crc@cursor-64x64-suspend.html

[  838.719211] Suspending console(s) (use no_console_suspend to debug)
[  838.729293] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  838.729518] sd 0:0:0:0: [sda] Stopping disk
[  838.991445] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff8102c4a6 (init_intel_energy_perf.part.3+0x6/0xa0)
[  838.991446] Call Trace:
[  838.991450]  syscore_resume+0x58/0x310
[  838.991454]  suspend_devices_and_enter+0xa37/0xcc0
[  838.991461]  pm_suspend+0x4e3/0x9d0
[  838.991465]  state_store+0x6d/0xd0
[  838.991470]  kernfs_fop_write+0x10b/0x1a0
[  838.991476]  __vfs_write+0x1e/0x130
[  838.991479]  ? rcu_read_lock_sched_held+0x6f/0x80
[  838.991481]  ? rcu_sync_lockdep_assert+0x25/0x50
[  838.991483]  ? __sb_start_write+0xd5/0x1f0
[  838.991485]  ? __sb_start_write+0xef/0x1f0
[  838.991490]  vfs_write+0xc0/0x1b0
[  838.991494]  SyS_write+0x40/0xa0
[  838.991499]  entry_SYSCALL_64_fastpath+0x1c/0x89
[  838.991501] RIP: 0033:0x7f8b6bdbf8f0
[  838.991502] RSP: 002b:00007ffe3c486fb8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  838.991505] RAX: ffffffffffffffda RBX: 0000556b1369f050 RCX: 00007f8b6bdbf8f0
[  838.991507] RDX: 0000000000000004 RSI: 0000556b1369f060 RDI: 000000000000000a
[  838.991508] RBP: 00007f8b6c088b00 R08: 0000556b1369cde0 R09: 00007f8b6c293700
[  838.991510] R10: 00007f8b6c088b58 R11: 0000000000000246 R12: 00007f8b6c088b58
[  838.991511] R13: 00007f8b6c088b58 R14: 000000000000270f R15: 0000000000001010
[  838.991523] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff8102c4e5 (init_intel_energy_perf.part.3+0x45/0xa0)
[  838.991524] Call Trace:
[  838.991526]  syscore_resume+0x58/0x310
[  838.991529]  suspend_devices_and_enter+0xa37/0xcc0
[  838.991535]  pm_suspend+0x4e3/0x9d0
[  838.991540]  state_store+0x6d/0xd0
[  838.991544]  kernfs_fop_write+0x10b/0x1a0
[  838.991549]  __vfs_write+0x1e/0x130
[  838.991551]  ? rcu_read_lock_sched_held+0x6f/0x80
[  838.991553]  ? rcu_sync_lockdep_assert+0x25/0x50
[  838.991555]  ? __sb_start_write+0xd5/0x1f0
[  838.991556]  ? __sb_start_write+0xef/0x1f0
[  838.991561]  vfs_write+0xc0/0x1b0
[  838.991565]  SyS_write+0x40/0xa0
[  838.991569]  entry_SYSCALL_64_fastpath+0x1c/0x89
[  838.991571] RIP: 0033:0x7f8b6bdbf8f0
[  838.991572] RSP: 002b:00007ffe3c486fb8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  838.991575] RAX: ffffffffffffffda RBX: 0000556b1369f050 RCX: 00007f8b6bdbf8f0
[  838.991577] RDX: 0000000000000004 RSI: 0000556b1369f060 RDI: 000000000000000a
[  838.991578] RBP: 00007f8b6c088b00 R08: 0000556b1369cde0 R09: 00007f8b6c293700
[  838.991579] R10: 00007f8b6c088b58 R11: 0000000000000246 R12: 00007f8b6c088b58
[  838.991581] R13: 00007f8b6c088b58 R14: 000000000000270f R15: 0000000000001010
[  838.995744]  cache: parent cpu1 should not be sleeping
[  839.000469]  cache: parent cpu2 should not be sleeping
[  839.005338]  cache: parent cpu3 should not be sleeping
[  839.010527]  cache: parent cpu4 should not be sleeping
[  839.015875]  cache: parent cpu5 should not be sleeping
[  839.021373]  cache: parent cpu6 should not be sleeping
[  839.027001]  cache: parent cpu7 should not be sleeping
[  839.071500] sd 0:0:0:0: [sda] Starting disk
[  839.435290] Suspending console(s) (use no_console_suspend to debug)
[  839.455154] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  839.455376] sd 0:0:0:0: [sda] Stopping disk
[  839.752308]  cache: parent cpu1 should not be sleeping
[  839.757091]  cache: parent cpu2 should not be sleeping
[  839.761962]  cache: parent cpu3 should not be sleeping
[  839.767115]  cache: parent cpu4 should not be sleeping
[  839.772364]  cache: parent cpu5 should not be sleeping
[  839.777800]  cache: parent cpu6 should not be sleeping
[  839.783264]  cache: parent cpu7 should not be sleeping
[  839.820082] mei_me 0000:00:16.0: less data available than length=00000000.
[  839.829315] sd 0:0:0:0: [sda] Starting disk
Comment 11 Marta Löfstedt 2017-12-04 11:54:00 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_577/fi-snb-2600/igt@kms_pipe_crc_basic@suspend-read-crc-pipe-a.html

[  383.360064] IRQ 23: no longer affine to CPU7
[  383.363199] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff8102c4a6 (init_intel_energy_perf.part.3+0x6/0xa0)
[  383.363201] Call Trace:
[  383.363205]  syscore_resume+0x58/0x310
[  383.363209]  suspend_devices_and_enter+0xa37/0xcc0
[  383.363215]  pm_suspend+0x4e3/0x9d0
[  383.363220]  state_store+0x6d/0xd0
[  383.363225]  kernfs_fop_write+0x10b/0x1a0
[  383.363230]  __vfs_write+0x1e/0x130
[  383.363234]  ? rcu_read_lock_sched_held+0x6f/0x80
[  383.363235]  ? rcu_sync_lockdep_assert+0x25/0x50
[  383.363238]  ? __sb_start_write+0xd5/0x1f0
[  383.363239]  ? __sb_start_write+0xef/0x1f0
[  383.363244]  vfs_write+0xc0/0x1b0
[  383.363248]  SyS_write+0x40/0xa0
[  383.363253]  entry_SYSCALL_64_fastpath+0x1c/0x89
[  383.363255] RIP: 0033:0x7f8c86637290
[  383.363257] RSP: 002b:00007ffd77508b38 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  383.363260] RAX: ffffffffffffffda RBX: 00007f8c86904b20 RCX: 00007f8c86637290
[  383.363261] RDX: 0000000000000004 RSI: 0000000001922060 RDI: 000000000000000a
[  383.363263] RBP: 0000000000001011 R08: 000000000191fde0 R09: 00007f8c86b10700
[  383.363264] R10: 0000000000000003 R11: 0000000000000246 R12: 00007f8c86904b78
[  383.363266] R13: 00007f8c86904b78 R14: 000000000000270f R15: 00007f8c86904b78
[  383.363277] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff8102c4e5 (init_intel_energy_perf.part.3+0x45/0xa0)
[  383.363278] Call Trace:
[  383.363280]  syscore_resume+0x58/0x310
[  383.363283]  suspend_devices_and_enter+0xa37/0xcc0
[  383.363290]  pm_suspend+0x4e3/0x9d0
[  383.363294]  state_store+0x6d/0xd0
[  383.363298]  kernfs_fop_write+0x10b/0x1a0
[  383.363303]  __vfs_write+0x1e/0x130
[  383.363306]  ? rcu_read_lock_sched_held+0x6f/0x80
[  383.363307]  ? rcu_sync_lockdep_assert+0x25/0x50
[  383.363309]  ? __sb_start_write+0xd5/0x1f0
[  383.363311]  ? __sb_start_write+0xef/0x1f0
[  383.363316]  vfs_write+0xc0/0x1b0
[  383.363320]  SyS_write+0x40/0xa0
[  383.363324]  entry_SYSCALL_64_fastpath+0x1c/0x89
[  383.363325] RIP: 0033:0x7f8c86637290
[  383.363326] RSP: 002b:00007ffd77508b38 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  383.363329] RAX: ffffffffffffffda RBX: 00007f8c86904b20 RCX: 00007f8c86637290
[  383.363331] RDX: 0000000000000004 RSI: 0000000001922060 RDI: 000000000000000a
[  383.363332] RBP: 0000000000001011 R08: 000000000191fde0 R09: 00007f8c86b10700
[  383.363333] R10: 0000000000000003 R11: 0000000000000246 R12: 00007f8c86904b78
[  383.363335] R13: 00007f8c86904b78 R14: 000000000000270f R15: 00007f8c86904b78
[  383.367323]  cache: parent cpu1 should not be sleeping
[  383.372081]  cache: parent cpu2 should not be sleeping
[  383.377028]  cache: parent cpu3 should not be sleeping
[  383.382281]  cache: parent cpu4 should not be sleeping
[  383.387633]  cache: parent cpu5 should not be sleeping
[  383.393155]  cache: parent cpu6 should not be sleeping
[  383.398855]  cache: parent cpu7 should not be sleeping
[  383.437815] hpet1: lost 8368 rtc interrupts
[  383.450747] sd 0:0:0:0: [sda] Starting disk
Comment 12 Marta Löfstedt 2017-12-05 07:13:06 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4036/shard-snb1/igt@kms_flip@vblank-vs-modeset-suspend.html

[  101.147381] IRQ 30: no longer affine to CPU7
[  101.150605] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff8102c4a6 (init_intel_energy_perf.part.3+0x6/0xa0)
[  101.150606] Call Trace:
[  101.150610]  syscore_resume+0x58/0x310
[  101.150614]  suspend_devices_and_enter+0xa37/0xcc0
[  101.150621]  pm_suspend+0x4e3/0x9d0
[  101.150625]  state_store+0x6d/0xd0
[  101.150630]  kernfs_fop_write+0x10b/0x1a0
[  101.150636]  __vfs_write+0x1e/0x130
[  101.150639]  ? rcu_read_lock_sched_held+0x6f/0x80
[  101.150641]  ? rcu_sync_lockdep_assert+0x25/0x50
[  101.150643]  ? __sb_start_write+0xd5/0x1f0
[  101.150645]  ? __sb_start_write+0xef/0x1f0
[  101.150649]  vfs_write+0xc0/0x1b0
[  101.150654]  SyS_write+0x40/0xa0
[  101.150658]  entry_SYSCALL_64_fastpath+0x1c/0x89
[  101.150661] RIP: 0033:0x7f40ef8868f0
[  101.150662] RSP: 002b:00007fff40738268 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  101.150665] RAX: ffffffffffffffda RBX: 0000558484060050 RCX: 00007f40ef8868f0
[  101.150667] RDX: 0000000000000004 RSI: 0000558484060060 RDI: 0000000000000008
[  101.150668] RBP: 00007f40efb4fb00 R08: 000055848405dde0 R09: 00007f40efd5a700
[  101.150669] R10: 00007f40efb4fb58 R11: 0000000000000246 R12: 00007f40efb4fb58
[  101.150671] R13: 00007f40efb4fb58 R14: 000000000000270f R15: 0000000000001010
[  101.150682] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff8102c4e5 (init_intel_energy_perf.part.3+0x45/0xa0)
[  101.150684] Call Trace:
[  101.150686]  syscore_resume+0x58/0x310
[  101.150689]  suspend_devices_and_enter+0xa37/0xcc0
[  101.150695]  pm_suspend+0x4e3/0x9d0
[  101.150699]  state_store+0x6d/0xd0
[  101.150704]  kernfs_fop_write+0x10b/0x1a0
[  101.150708]  __vfs_write+0x1e/0x130
[  101.150711]  ? rcu_read_lock_sched_held+0x6f/0x80
[  101.150713]  ? rcu_sync_lockdep_assert+0x25/0x50
[  101.150715]  ? __sb_start_write+0xd5/0x1f0
[  101.150716]  ? __sb_start_write+0xef/0x1f0
[  101.150721]  vfs_write+0xc0/0x1b0
[  101.150725]  SyS_write+0x40/0xa0
[  101.150729]  entry_SYSCALL_64_fastpath+0x1c/0x89
[  101.150731] RIP: 0033:0x7f40ef8868f0
[  101.150732] RSP: 002b:00007fff40738268 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  101.150735] RAX: ffffffffffffffda RBX: 0000558484060050 RCX: 00007f40ef8868f0
[  101.150736] RDX: 0000000000000004 RSI: 0000558484060060 RDI: 0000000000000008
[  101.150737] RBP: 00007f40efb4fb00 R08: 000055848405dde0 R09: 00007f40efd5a700
[  101.150739] R10: 00007f40efb4fb58 R11: 0000000000000246 R12: 00007f40efb4fb58
[  101.150740] R13: 00007f40efb4fb58 R14: 000000000000270f R15: 0000000000001010
[  101.155864]  cache: parent cpu1 should not be sleeping
Comment 14 Marta Löfstedt 2018-02-06 06:33:30 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4221/shard-snb3/igt@drv_suspend@debugfs-reader.html

	

[  151.478226] Suspending console(s) (use no_console_suspend to debug)
[  151.490025] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  151.499070] sd 0:0:0:0: [sda] Stopping disk
[  151.663093] IRQ 25: no longer affine to CPU4
[  151.663101] IRQ 28: no longer affine to CPU4
[  151.683370] IRQ 16: no longer affine to CPU5
[  151.683379] IRQ 24: no longer affine to CPU5
[  151.702812] IRQ 26: no longer affine to CPU6
[  151.714970] IRQ 8: no longer affine to CPU7
[  151.714980] IRQ 9: no longer affine to CPU7
[  151.714993] IRQ 23: no longer affine to CPU7
[  151.715002] IRQ 30: no longer affine to CPU7
[  151.718586] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff8102e236 (init_intel_energy_perf.part.3+0x6/0xa0)
[  151.718588] Call Trace:
[  151.718592]  syscore_resume+0x58/0x310
[  151.718596]  suspend_devices_and_enter+0xa37/0xcc0
[  151.718603]  pm_suspend+0x4e3/0x9d0
[  151.718607]  state_store+0x6d/0xd0
[  151.718612]  kernfs_fop_write+0x10b/0x1a0
[  151.718617]  __vfs_write+0x1e/0x130
[  151.718620]  ? rcu_read_lock_sched_held+0x6f/0x80
[  151.718622]  ? rcu_sync_lockdep_assert+0x25/0x50
[  151.718624]  ? __sb_start_write+0xd9/0x1f0
[  151.718626]  ? __sb_start_write+0xf3/0x1f0
[  151.718630]  vfs_write+0xbd/0x1b0
[  151.718634]  SyS_write+0x40/0xa0
[  151.718639]  entry_SYSCALL_64_fastpath+0x22/0x8f
[  151.718641] RIP: 0033:0x7f2bda7da8f0
[  151.718643] RSP: 002b:00007ffce2542e88 EFLAGS: 00000246
[  151.718654] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff8102e275 (init_intel_energy_perf.part.3+0x45/0xa0)
[  151.718656] Call Trace:
[  151.718658]  syscore_resume+0x58/0x310
[  151.718661]  suspend_devices_and_enter+0xa37/0xcc0
[  151.718667]  pm_suspend+0x4e3/0x9d0
[  151.718672]  state_store+0x6d/0xd0
[  151.718676]  kernfs_fop_write+0x10b/0x1a0
[  151.718680]  __vfs_write+0x1e/0x130
[  151.718683]  ? rcu_read_lock_sched_held+0x6f/0x80
[  151.718684]  ? rcu_sync_lockdep_assert+0x25/0x50
[  151.718687]  ? __sb_start_write+0xd9/0x1f0
[  151.718688]  ? __sb_start_write+0xf3/0x1f0
[  151.718693]  vfs_write+0xbd/0x1b0
[  151.718696]  SyS_write+0x40/0xa0
[  151.718700]  entry_SYSCALL_64_fastpath+0x22/0x8f
[  151.718702] RIP: 0033:0x7f2bda7da8f0
[  151.718703] RSP: 002b:00007ffce2542e88 EFLAGS: 00000246
[  151.723616]  cache: parent cpu1 should not be sleeping
[  151.728928]  cache: parent cpu2 should not be sleeping
[  151.733745]  cache: parent cpu3 should not be sleeping
[  151.738708]  cache: parent cpu4 should not be sleeping
[  151.743899]  cache: parent cpu5 should not be sleeping
[  151.749264]  cache: parent cpu6 should not be sleeping
[  151.754852]  cache: parent cpu7 should not be sleeping
[  151.793387] hpet1: lost 9499 rtc interrupts
[  151.806201] sd 0:0:0:0: [sda] Starting disk
Comment 15 Marta Löfstedt 2018-03-06 06:37:54 UTC
back on cibuglog due to:
https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4328/fi-snb-2600/igt@gem_exec_suspend@basic-s3.html

	

[  193.911275] Setting dangerous option reset - tainting kernel
[  194.285020] Suspending console(s) (use no_console_suspend to debug)
[  194.296204] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  194.296424] sd 0:0:0:0: [sda] Stopping disk
[  194.462046] IRQ 16: no longer affine to CPU4
[  194.475834] IRQ 26: no longer affine to CPU5
[  194.492047] IRQ 23: no longer affine to CPU6
[  194.499894] IRQ 8: no longer affine to CPU7
[  194.499900] IRQ 9: no longer affine to CPU7
[  194.499908] IRQ 24: no longer affine to CPU7
[  194.502714] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff8102e166 (init_intel_energy_perf.part.3+0x6/0xa0)
[  194.502716] Call Trace:
[  194.502721]  syscore_resume+0x58/0x310
[  194.502726]  suspend_devices_and_enter+0xa37/0xcc0
[  194.502733]  pm_suspend+0x4e3/0x9d0
[  194.502738]  state_store+0x6d/0xd0
[  194.502743]  kernfs_fop_write+0xfe/0x180
[  194.502748]  __vfs_write+0x1e/0x130
[  194.502752]  ? rcu_read_lock_sched_held+0x6f/0x80
[  194.502754]  ? rcu_sync_lockdep_assert+0x25/0x50
[  194.502757]  ? __sb_start_write+0xd9/0x1f0
[  194.502759]  ? __sb_start_write+0xf3/0x1f0
[  194.502764]  vfs_write+0xbd/0x1b0
[  194.502768]  SyS_write+0x40/0xa0
[  194.502773]  do_syscall_64+0x65/0x1a0
[  194.502778]  entry_SYSCALL_64_after_hwframe+0x42/0xb7
[  194.502780] RIP: 0033:0x7fd859de9290
[  194.502782] RSP: 002b:00007ffd8f6656d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  194.502786] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fd859de9290
[  194.502787] RDX: 0000000000000004 RSI: 0000000001b78060 RDI: 0000000000000008
[  194.502789] RBP: 0000000001b78060 R08: 0000000001b75df0 R09: 00007fd85a2c2700
[  194.502790] R10: 0000000000000003 R11: 0000000000000246 R12: 0000000000000004
[  194.502792] R13: 0000000000000001 R14: 0000000001b75d10 R15: 00007ffd8f667c11
[  194.502804] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff8102e1a5 (init_intel_energy_perf.part.3+0x45/0xa0)
[  194.502805] Call Trace:
[  194.502808]  syscore_resume+0x58/0x310
[  194.502812]  suspend_devices_and_enter+0xa37/0xcc0
[  194.502819]  pm_suspend+0x4e3/0x9d0
[  194.502823]  state_store+0x6d/0xd0
[  194.502827]  kernfs_fop_write+0xfe/0x180
[  194.502832]  __vfs_write+0x1e/0x130
[  194.502835]  ? rcu_read_lock_sched_held+0x6f/0x80
[  194.502837]  ? rcu_sync_lockdep_assert+0x25/0x50
[  194.502840]  ? __sb_start_write+0xd9/0x1f0
[  194.502842]  ? __sb_start_write+0xf3/0x1f0
[  194.502847]  vfs_write+0xbd/0x1b0
[  194.502851]  SyS_write+0x40/0xa0
[  194.502855]  do_syscall_64+0x65/0x1a0
[  194.502859]  entry_SYSCALL_64_after_hwframe+0x42/0xb7
[  194.502860] RIP: 0033:0x7fd859de9290
[  194.502862] RSP: 002b:00007ffd8f6656d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  194.502865] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fd859de9290
[  194.502867] RDX: 0000000000000004 RSI: 0000000001b78060 RDI: 0000000000000008
[  194.502868] RBP: 0000000001b78060 R08: 0000000001b75df0 R09: 00007fd85a2c2700
[  194.502870] R10: 0000000000000003 R11: 0000000000000246 R12: 0000000000000004
[  194.502871] R13: 0000000000000001 R14: 0000000001b75d10 R15: 00007ffd8f667c11
[  194.507649]  cache: parent cpu1 should not be sleeping
[  194.512962]  cache: parent cpu2 should not be sleeping
[  194.518015]  cache: parent cpu3 should not be sleeping
[  194.523122]  cache: parent cpu4 should not be sleeping
[  194.528219]  cache: parent cpu5 should not be sleeping
[  194.533532]  cache: parent cpu6 should not be sleeping
[  194.539032]  cache: parent cpu7 should not be sleeping
[  194.576750] hpet1: lost 6759 rtc interrupts
[  194.588392] sd 0:0:0:0: [sda] Starting disk
[  195.154420] Setting dangerous option reset - tainting kernel
Comment 17 Marta Löfstedt 2018-03-28 05:53:08 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3992/shard-snb5/igt@kms_cursor_crc@cursor-128x128-suspend.html

[  134.511579] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffff8102e236 (init_intel_energy_perf.part.3+0x6/0xa0)
[  134.511580] Call Trace:
[  134.511584]  syscore_resume+0x58/0x310
[  134.511589]  suspend_devices_and_enter+0xa37/0xcc0
[  134.511596]  pm_suspend+0x4e3/0x9d0
[  134.511601]  state_store+0x6d/0xd0
[  134.511606]  kernfs_fop_write+0xfe/0x180
[  134.511611]  __vfs_write+0x1e/0x130
[  134.511615]  ? rcu_read_lock_sched_held+0x6f/0x80
[  134.511617]  ? rcu_sync_lockdep_assert+0x25/0x50
[  134.511620]  ? __sb_start_write+0xd9/0x1f0
[  134.511622]  ? __sb_start_write+0xf3/0x1f0
[  134.511627]  vfs_write+0xbd/0x1b0
[  134.511631]  SyS_write+0x40/0xa0
[  134.511636]  do_syscall_64+0x65/0x1a0
[  134.511639]  entry_SYSCALL_64_after_hwframe+0x42/0xb7
[  134.511642] RIP: 0033:0x7fb049bdf154
[  134.511643] RSP: 002b:00007ffd579d9dc8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  134.511647] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fb049bdf154
[  134.511648] RDX: 0000000000000004 RSI: 00005567d7ca75b0 RDI: 000000000000000a
[  134.511650] RBP: 00005567d7ca75b0 R08: 00005567d7ca45e0 R09: 00007fb04a0ce540
[  134.511652] R10: 00005567d7ca2010 R11: 0000000000000246 R12: 00005567d7ca4500
[  134.511653] R13: 0000000000000004 R14: 00007fb049eb72a0 R15: 00007fb049eb6760
[  134.511665] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffff8102e275 (init_intel_energy_perf.part.3+0x45/0xa0)
[  134.511666] Call Trace:
[  134.511669]  syscore_resume+0x58/0x310
[  134.511672]  suspend_devices_and_enter+0xa37/0xcc0
[  134.511679]  pm_suspend+0x4e3/0x9d0
[  134.511683]  state_store+0x6d/0xd0
[  134.511688]  kernfs_fop_write+0xfe/0x180
[  134.511692]  __vfs_write+0x1e/0x130
[  134.511695]  ? rcu_read_lock_sched_held+0x6f/0x80
[  134.511697]  ? rcu_sync_lockdep_assert+0x25/0x50
[  134.511700]  ? __sb_start_write+0xd9/0x1f0
[  134.511702]  ? __sb_start_write+0xf3/0x1f0
[  134.511707]  vfs_write+0xbd/0x1b0
[  134.511711]  SyS_write+0x40/0xa0
[  134.511715]  do_syscall_64+0x65/0x1a0
[  134.511718]  entry_SYSCALL_64_after_hwframe+0x42/0xb7
[  134.511720] RIP: 0033:0x7fb049bdf154
[  134.511721] RSP: 002b:00007ffd579d9dc8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  134.511724] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fb049bdf154
[  134.511726] RDX: 0000000000000004 RSI: 00005567d7ca75b0 RDI: 000000000000000a
[  134.511727] RBP: 00005567d7ca75b0 R08: 00005567d7ca45e0 R09: 00007fb04a0ce540
[  134.511729] R10: 00005567d7ca2010 R11: 0000000000000246 R12: 00005567d7ca4500
[  134.511731] R13: 0000000000000004 R14: 00007fb049eb72a0 R15: 00007fb049eb6760
Comment 19 Marta Löfstedt 2018-04-12 08:35:32 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_17/fi-snb-2600/igt@gem_softpin@noreloc-s3.html

<6>[   62.328024] PM: Restoring platform NVS memory
<4>[   62.328111] unchecked MSR access error: RDMSR from 0x1b0 at rIP: 0xffffffffb202f986 (init_intel_energy_perf.part.3+0x6/0xa0)
<4>[   62.328113] Call Trace:
<4>[   62.328117]  syscore_resume+0x58/0x310
<4>[   62.328121]  suspend_devices_and_enter+0xa6b/0xcf0
<4>[   62.328128]  pm_suspend+0x4fc/0x9e0
<4>[   62.328134]  state_store+0x6d/0xd0
<4>[   62.328140]  kernfs_fop_write+0xfe/0x180
<4>[   62.328145]  __vfs_write+0x2e/0x150
<4>[   62.328150]  ? rcu_read_lock_sched_held+0x6f/0x80
<4>[   62.328152]  ? rcu_sync_lockdep_assert+0x25/0x50
<4>[   62.328155]  ? __sb_start_write+0xd9/0x1f0
<4>[   62.328157]  ? __sb_start_write+0xf3/0x1f0
<4>[   62.328162]  vfs_write+0xbd/0x1b0
<4>[   62.328166]  SyS_write+0x50/0xc0
<4>[   62.328168]  ? do_syscall_64+0x19/0x1a0
<4>[   62.328172]  do_syscall_64+0x65/0x1a0
<4>[   62.328177]  entry_SYSCALL_64_after_hwframe+0x42/0xb7
<4>[   62.328179] RIP: 0033:0x7f70d8eeb154
<4>[   62.328181] RSP: 002b:00007ffd18d5e9d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
<4>[   62.328185] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f70d8eeb154
<4>[   62.328186] RDX: 0000000000000004 RSI: 00005640bab8e5b0 RDI: 0000000000000008
<4>[   62.328188] RBP: 00005640bab8e5b0 R08: 00005640bab8b5e0 R09: 00007f70d93d3540
<4>[   62.328190] R10: 00005640bab89010 R11: 0000000000000246 R12: 00005640bab8b500
<4>[   62.328191] R13: 0000000000000004 R14: 00007f70d91c32a0 R15: 00007f70d91c2760
<4>[   62.328203] unchecked MSR access error: WRMSR to 0x1b0 (tried to write 0x0000000000000006) at rIP: 0xffffffffb202f9c5 (init_intel_energy_perf.part.3+0x45/0xa0)
<4>[   62.328204] Call Trace:
<4>[   62.328207]  syscore_resume+0x58/0x310
<4>[   62.328210]  suspend_devices_and_enter+0xa6b/0xcf0
<4>[   62.328217]  pm_suspend+0x4fc/0x9e0
<4>[   62.328222]  state_store+0x6d/0xd0
<4>[   62.328227]  kernfs_fop_write+0xfe/0x180
<4>[   62.328231]  __vfs_write+0x2e/0x150
<4>[   62.328235]  ? rcu_read_lock_sched_held+0x6f/0x80
<4>[   62.328237]  ? rcu_sync_lockdep_assert+0x25/0x50
<4>[   62.328240]  ? __sb_start_write+0xd9/0x1f0
<4>[   62.328241]  ? __sb_start_write+0xf3/0x1f0
<4>[   62.328247]  vfs_write+0xbd/0x1b0
<4>[   62.328251]  SyS_write+0x50/0xc0
<4>[   62.328253]  ? do_syscall_64+0x19/0x1a0
<4>[   62.328257]  do_syscall_64+0x65/0x1a0
<4>[   62.328261]  entry_SYSCALL_64_after_hwframe+0x42/0xb7
<4>[   62.328262] RIP: 0033:0x7f70d8eeb154
<4>[   62.328264] RSP: 002b:00007ffd18d5e9d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
<4>[   62.328267] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f70d8eeb154
<4>[   62.328268] RDX: 0000000000000004 RSI: 00005640bab8e5b0 RDI: 0000000000000008
<4>[   62.328270] RBP: 00005640bab8e5b0 R08: 00005640bab8b5e0 R09: 00007f70d93d3540
<4>[   62.328272] R10: 00005640bab89010 R11: 0000000000000246 R12: 00005640bab8b500
<4>[   62.328273] R13: 0000000000000004 R14: 00007f70d91c32a0 R15: 00007f70d91c2760


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.