Bug 103337

Summary: [CI] igt@* - dmesg-warn - *ERROR* Panel is unable to calculate any CRC after 6 vblanks | Unclaimed read from register 0x130090
Product: DRI Reporter: Marta Löfstedt <marta.lofstedt>
Component: DRM/IntelAssignee: Imre Deak <imre.deak>
Status: CLOSED FIXED QA Contact: Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity: normal    
Priority: medium CC: intel-gfx-bugs
Version: DRI git   
Hardware: Other   
OS: All   
Whiteboard: ReadyForDev
i915 platform: CFL i915 features: display/Other
Attachments:
Description Flags
kernel log
none
dmesg log none

Description Marta Löfstedt 2017-10-18 09:41:57 UTC
CI_DRM_3252 GLK-shards:

[  264.131055] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  264.155073] Unclaimed read from register 0x130090
[  264.155190] ------------[ cut here ]------------
[  264.155268] WARNING: CPU: 0 PID: 2655 at drivers/gpu/drm/i915/intel_uncore.c:860 __unclaimed_reg_debug+0x47/0x60 [i915]
[  264.155274] Modules linked in: vgem snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal intel_powerclamp i915 coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm mei_me mei r8169 mii prime_numbers i2c_hid pinctrl_geminilake pinctrl_intel
[  264.155404] CPU: 0 PID: 2655 Comm: cat Tainted: G     U          4.14.0-rc5-CI-CI_DRM_3252+ #1
[  264.155410] Hardware name: Intel Corp. Geminilake/GLK RVP2 LP4SD (07), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[  264.155416] task: ffff880176d6a780 task.stack: ffffc90000e9c000
[  264.155491] RIP: 0010:__unclaimed_reg_debug+0x47/0x60 [i915]
[  264.155497] RSP: 0018:ffffc90000e9fcf8 EFLAGS: 00010092
[  264.155507] RAX: 0000000000000025 RBX: 0000000000000000 RCX: 0000000000000002
[  264.155513] RDX: 0000000080000002 RSI: ffffffff81d183d7 RDI: 00000000ffffffff
[  264.155518] RBP: ffffc90000e9fd10 R08: 0000000000000000 R09: 0000000000000001
[  264.155523] R10: ffffc90000e9fc88 R11: 000000001110455f R12: 0000000000130090
[  264.155529] R13: 0000000000000001 R14: 0000000000000000 R15: ffff88016f230bb0
[  264.155535] FS:  00007f9a86545700(0000) GS:ffff88017fc00000(0000) knlGS:0000000000000000
[  264.155541] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  264.155547] CR2: 000055a41dd4f000 CR3: 000000016fc3e000 CR4: 00000000003406f0
[  264.155552] Call Trace:
[  264.155631]  fwtable_read32+0x243/0x2c0 [i915]
[  264.155704]  i915_drpc_info+0x27d/0x8e0 [i915]
[  264.155720]  seq_read+0xe1/0x3a0
[  264.155738]  full_proxy_read+0x54/0x80
[  264.155750]  __vfs_read+0x28/0x120
[  264.155767]  ? entry_SYSCALL_64_fastpath+0x5/0xb1
[  264.155776]  vfs_read+0xa8/0x150
[  264.155787]  SyS_read+0x49/0xb0
[  264.155799]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[  264.155805] RIP: 0033:0x7f9a86073890
[  264.155810] RSP: 002b:00007fff9bc9dfb8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[  264.155821] RAX: ffffffffffffffda RBX: ffffffff81491f73 RCX: 00007f9a86073890
[  264.155827] RDX: 0000000000020000 RSI: 000055a41dd4c000 RDI: 0000000000000005
[  264.155832] RBP: ffffc90000e9ff88 R08: 00007f9a8633cb88 R09: 0000000000021010
[  264.155838] R10: 00007f9a8633cb58 R11: 0000000000000246 R12: 0000000000020000
[  264.155843] R13: 0000000000000005 R14: 000055a41dd4c000 R15: 0000000000020000
[  264.155855]  ? __this_cpu_preempt_check+0x13/0x20
[  264.155869] Code: 01 75 31 84 db 75 2d 45 84 ed 48 c7 c0 e8 bd 23 a0 48 c7 c6 de bd 23 a0 48 0f 44 f0 44 89 e2 48 c7 c7 f1 bd 23 a0 e8 ba ff f6 e0 <0f> ff 83 2d c0 16 10 00 01 5b 41 5c 41 5d 5d c3 66 0f 1f 84 00 
[  264.156187] ---[ end trace bb371c712040c418 ]---
[  264.274836] atkbd serio0: Failed to enable keyboard on isa0060/serio0

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3252/shard-glkb1/igt@drv_suspend@debugfs-reader.html
	
Also, CI_DRM_3253
Same warning print without the WARNING
[  343.551512] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3253/shard-glkb1/igt@drv_suspend@debugfs-reader.html
Comment 1 Marta Löfstedt 2017-10-19 08:09:28 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3261/shard-glkb2/igt@debugfs_test@read_all_entries.html


[ 3203.809549] Unclaimed read from register 0x130090
[ 3203.809592] ------------[ cut here ]------------
[ 3203.809644] WARNING: CPU: 0 PID: 5669 at drivers/gpu/drm/i915/intel_uncore.c:860 __unclaimed_reg_debug+0x47/0x60 [i915]
[ 3203.809647] Modules linked in: vgem snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core r8169 mii snd_pcm mei_me prime_numbers mei i2c_hid pinctrl_geminilake pinctrl_intel
[ 3203.809696] CPU: 0 PID: 5669 Comm: debugfs_test Tainted: G     U          4.14.0-rc5-CI-CI_DRM_3261+ #1
[ 3203.809699] Hardware name: Intel Corp. Geminilake/GLK RVP2 LP4SD (07), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[ 3203.809702] task: ffff88013aab8040 task.stack: ffffc9000049c000
[ 3203.809750] RIP: 0010:__unclaimed_reg_debug+0x47/0x60 [i915]
[ 3203.809754] RSP: 0018:ffffc9000049fcf8 EFLAGS: 00010092
[ 3203.809758] RAX: 0000000000000025 RBX: 0000000000000000 RCX: 0000000000000002
[ 3203.809761] RDX: 0000000080000002 RSI: ffffffff81d1849f RDI: 00000000ffffffff
[ 3203.809764] RBP: ffffc9000049fd10 R08: 0000000000000001 R09: 0000000000000000
[ 3203.809767] R10: ffffc9000049fc88 R11: 0000000000000000 R12: 0000000000130090
[ 3203.809770] R13: 0000000000000001 R14: 0000000000000000 R15: ffff88016e840bb0
[ 3203.809773] FS:  00007fc61d044a40(0000) GS:ffff88017fc00000(0000) knlGS:0000000000000000
[ 3203.809778] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 3203.809781] CR2: 000055eb1ffe3008 CR3: 000000016cc26000 CR4: 00000000003406f0
[ 3203.809783] Call Trace:
[ 3203.809833]  fwtable_read32+0x243/0x2c0 [i915]
[ 3203.809878]  i915_drpc_info+0x27d/0x8e0 [i915]
[ 3203.809886]  seq_read+0xe1/0x3a0
[ 3203.809894]  full_proxy_read+0x54/0x80
[ 3203.809902]  __vfs_read+0x28/0x120
[ 3203.809907]  ? putname+0x54/0x60
[ 3203.809912]  ? kmem_cache_free+0x290/0x330
[ 3203.809915]  ? kmem_cache_free+0x12c/0x330
[ 3203.809920]  vfs_read+0xa8/0x150
[ 3203.809924]  SyS_read+0x49/0xb0
[ 3203.809932]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[ 3203.809935] RIP: 0033:0x7fc61b51e6d0
[ 3203.809938] RSP: 002b:00007fff501a7a18 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[ 3203.809943] RAX: ffffffffffffffda RBX: 000055eb1ffc7f6b RCX: 00007fc61b51e6d0
[ 3203.809946] RDX: 0000000000000200 RSI: 00007fff501a7a30 RDI: 0000000000000006
[ 3203.809948] RBP: 00007fff501a7a30 R08: 000055eb1ff6f300 R09: 0000000000000000
[ 3203.809951] R10: 0000000000000000 R11: 0000000000000246 R12: 000055eb1ffc7a00
[ 3203.809954] R13: 0000000000000005 R14: 0000000000000006 R15: 0000000000000003
[ 3203.809960] Code: 01 75 31 84 db 75 2d 45 84 ed 48 c7 c0 fc 7c 24 a0 48 c7 c6 f2 7c 24 a0 48 0f 44 f0 44 89 e2 48 c7 c7 05 7d 24 a0 e8 4a 40 f6 e0 <0f> ff 83 2d 30 07 10 00 01 5b 41 5c 41 5d 5d c3 66 0f 1f 84 00 
[ 3203.810058] ---[ end trace e04068ecac830ca6 ]---
Comment 2 Marta Löfstedt 2017-10-23 07:38:02 UTC
	

[ 2150.111884] Unclaimed read from register 0x130090
[ 2150.111939] ------------[ cut here ]------------
[ 2150.112008] WARNING: CPU: 3 PID: 8268 at drivers/gpu/drm/i915/intel_uncore.c:860 __unclaimed_reg_debug+0x47/0x60 [i915]
[ 2150.112012] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal intel_powerclamp i915 coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm mei_me prime_numbers r8169 mei mii i2c_hid pinctrl_geminilake pinctrl_intel [last unloaded: vgem]
[ 2150.112081] CPU: 3 PID: 8268 Comm: debugfs_test Tainted: G     U          4.14.0-rc5-CI-CI_DRM_3272+ #1
[ 2150.112086] Hardware name: Intel Corp. Geminilake/GLK RVP1 DDR4 (05), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[ 2150.112091] task: ffff8801781a8040 task.stack: ffffc90000e2c000
[ 2150.112159] RIP: 0010:__unclaimed_reg_debug+0x47/0x60 [i915]
[ 2150.112164] RSP: 0018:ffffc90000e2fcf8 EFLAGS: 00010092
[ 2150.112170] RAX: 0000000000000025 RBX: 0000000000000000 RCX: 0000000000000002
[ 2150.112174] RDX: 0000000080000002 RSI: ffffffff81cb3f8b RDI: 00000000ffffffff
[ 2150.112178] RBP: ffffc90000e2fd10 R08: 0000000000000001 R09: 0000000000000000
[ 2150.112182] R10: ffffc90000e2fc88 R11: 0000000000000000 R12: 0000000000130090
[ 2150.112186] R13: 0000000000000001 R14: 0000000000000000 R15: ffff8801683c0bb0
[ 2150.112191] FS:  00007fb13630aa40(0000) GS:ffff88017fd80000(0000) knlGS:0000000000000000
[ 2150.112195] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 2150.112199] CR2: 00007fdcbdb2c020 CR3: 0000000113915000 CR4: 00000000003406e0
[ 2150.112203] Call Trace:
[ 2150.112271]  fwtable_read32+0x243/0x2c0 [i915]
[ 2150.112333]  i915_drpc_info+0x27d/0x8e0 [i915]
[ 2150.112343]  seq_read+0xe1/0x3a0
[ 2150.112356]  full_proxy_read+0x54/0x80
[ 2150.112364]  __vfs_read+0x28/0x120
[ 2150.112371]  ? putname+0x54/0x60
[ 2150.112377]  ? kmem_cache_free+0x290/0x330
[ 2150.112382]  ? kmem_cache_free+0x12c/0x330
[ 2150.112388]  vfs_read+0xa8/0x150
[ 2150.112395]  SyS_read+0x49/0xb0
[ 2150.112404]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[ 2150.112409] RIP: 0033:0x7fb1347e46d0
[ 2150.112413] RSP: 002b:00007fff5e7af988 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[ 2150.112419] RAX: ffffffffffffffda RBX: 0000561424a4caeb RCX: 00007fb1347e46d0
[ 2150.112423] RDX: 0000000000000200 RSI: 00007fff5e7af9a0 RDI: 0000000000000006
[ 2150.112427] RBP: 00007fff5e7af9a0 R08: 0000561424a47b40 R09: 0000000000000000
[ 2150.112431] R10: 0000000000000000 R11: 0000000000000246 R12: 0000561424a4c580
[ 2150.112435] R13: 0000000000000005 R14: 0000000000000006 R15: 0000000000000000
[ 2150.112444] Code: 01 75 31 84 db 75 2d 45 84 ed 48 c7 c0 c9 6c 22 a0 48 c7 c6 bf 6c 22 a0 48 0f 44 f0 44 89 e2 48 c7 c7 d2 6c 22 a0 e8 2a 50 f8 e0 <0f> ff 83 2d 30 07 10 00 01 5b 41 5c 41 5d 5d c3 66 0f 1f 84 00 
[ 2150.112578] ---[ end trace bd08d0d997cb72dd ]---

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3272/shard-glkb5/igt@debugfs_test@read_all_entries_display_off.html
Comment 3 Marta Löfstedt 2017-11-02 07:20:28 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3306/shard-glkb3/igt@kms_frontbuffer_tracking@fbc-1p-primscrn-pri-indfb-draw-mmap-gtt.html
piglit 
[ 3798.061259] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks

From dmesg it looks like the actual subtest hasn't started yet:
<7>[ 3797.660555] [IGT] kms_frontbuffer_tracking: executing
...
<3>[ 3798.061259] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
<7>[ 3798.078124] [IGT] kms_frontbuffer_tracking: starting subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt

The same thing is going on ealier:
<7>[ 3738.328402] [IGT] kms_frontbuffer_tracking: executing
...
<3>[ 3738.728622] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
...
<7>[ 3738.746617] [IGT] kms_frontbuffer_tracking: starting subtest fbcpsr-suspend

and here:
<7>[ 3786.839316] [IGT] kms_frontbuffer_tracking: executing
...
<3>[ 3787.250869] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
<7>[ 3787.267657] [IGT] kms_frontbuffer_tracking: starting subtest fbc-2p-scndscrn-pri-shrfb-draw-render

The we can look in the previous shard on this machine without reboot:
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3306/shard-glkb3/dmesg28.log
<7>[ 3255.851528] [IGT] kms_frontbuffer_tracking: executing
...
<3>[ 3256.262032] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
...
<7>[ 3256.279360] [IGT] kms_frontbuffer_tracking: starting subtest psr-1p-primscrn-pri-indfb-draw-pwrite

In the run before that one: https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3306/shard-glkb3/dmesg25.log
The issue isn't present.

However, in the on after this run, the last shard: https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3306/shard-glkb3/dmesg34.log

The issue continues:
<7>[ 4388.690460] [IGT] kms_frontbuffer_tracking: executing
...
<3>[ 4389.103319] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
<7>[ 4389.120204] [IGT] kms_frontbuffer_tracking: starting subtest fbc-2p-scndscrn-pri-shrfb-draw-blt
and
<7>[ 4565.469133] [IGT] kms_frontbuffer_tracking: executing
...
<3>[ 4565.874254] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
<7>[ 4565.891824] [IGT] kms_frontbuffer_tracking: starting subtest psr-2p-primscrn-spr-indfb-move
Comment 4 Marta Löfstedt 2017-11-03 06:54:15 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3310/shard-glkb4/igt@perf@rc6-disable.html

[ 4176.043375] Unclaimed read from register 0x130090
[ 4176.043427] ------------[ cut here ]------------
[ 4176.043505] WARNING: CPU: 3 PID: 10053 at drivers/gpu/drm/i915/intel_uncore.c:976 __unclaimed_reg_debug+0x47/0x60 [i915]
[ 4176.043530] Modules linked in: i2c_dev snd_hda_intel i915 vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_codec snd_hwdep snd_hda_core mei_me snd_pcm mei r8169 mii prime_numbers i2c_hid pinctrl_geminilake pinctrl_intel [last unloaded: i915]
[ 4176.043646] CPU: 3 PID: 10053 Comm: perf Tainted: G     U  W       4.14.0-rc7-CI-CI_DRM_3310+ #1
[ 4176.043667] Hardware name: Intel Corp. Geminilake/GLK RVP1 DDR4 (05), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[ 4176.043691] task: ffff8801681d0040 task.stack: ffffc90000130000
[ 4176.043754] RIP: 0010:__unclaimed_reg_debug+0x47/0x60 [i915]
[ 4176.043768] RSP: 0018:ffffc90000133cf8 EFLAGS: 00010092
[ 4176.043783] RAX: 0000000000000025 RBX: 0000000000000000 RCX: 0000000000000002
[ 4176.043800] RDX: 0000000080000002 RSI: ffffffff81cb4dab RDI: 00000000ffffffff
[ 4176.043817] RBP: ffffc90000133d10 R08: 0000000000000001 R09: 0000000000000000
[ 4176.043834] R10: ffffc90000133c88 R11: 0000000000000000 R12: 0000000000130090
[ 4176.043850] R13: 0000000000000001 R14: 0000000000000000 R15: ffff880172ae0bb8
[ 4176.043868] FS:  00007fe1965448c0(0000) GS:ffff88017fd80000(0000) knlGS:0000000000000000
[ 4176.043887] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 4176.043901] CR2: 0000562455bc7248 CR3: 000000017297d000 CR4: 00000000003406e0
[ 4176.043918] Call Trace:
[ 4176.043975]  fwtable_read32+0x243/0x2c0 [i915]
[ 4176.044031]  i915_drpc_info+0x27d/0x8e0 [i915]
[ 4176.044049]  seq_read+0xe1/0x3a0
[ 4176.044063]  full_proxy_read+0x54/0x80
[ 4176.044077]  __vfs_read+0x28/0x120
[ 4176.044091]  ? SyS_newfstat+0x3a/0x50
[ 4176.044103]  vfs_read+0xa8/0x150
[ 4176.044114]  SyS_read+0x49/0xb0
[ 4176.044128]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[ 4176.044140] RIP: 0033:0x7fe194a4b890
[ 4176.044150] RSP: 002b:00007ffc5806c408 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[ 4176.044170] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fe194a4b890
[ 4176.044187] RDX: 0000000000001000 RSI: 0000562455bc6240 RDI: 0000000000000006
[ 4176.044204] RBP: 0000000000000006 R08: 00007fe194d150a8 R09: 0000000000000000
[ 4176.044220] R10: 00007fe194d14b58 R11: 0000000000000246 R12: 0000000000000000
[ 4176.044237] R13: 00007ffc5806ddc0 R14: 0000000000000000 R15: 0000000000000000
[ 4176.044258] Code: 01 75 31 84 db 75 2d 45 84 ed 48 c7 c0 fe 4c 22 a0 48 c7 c6 f4 4c 22 a0 48 0f 44 f0 44 89 e2 48 c7 c7 07 4d 22 a0 e8 3a 85 f8 e0 <0f> ff 83 2d e0 2d 10 00 01 5b 41 5c 41 5d 5d c3 66 0f 1f 84 00 
[ 4176.044396] ---[ end trace b919e89a83d0d7e0 ]---
Comment 5 Armando Antonio 2017-12-07 20:14:50 UTC
This issue is happening on CFL with latest configuration.

Test cases:
igt@drv_suspend@debugfs-reader
igt@drv_suspend@debugfs-reader-hibernate

Hardware configuration:
 intel-gpu-tools-1.20-189-g1db1246

dmesg summary:
[  112.449242] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  112.599206] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  112.749203] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  112.899254] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  113.049197] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  113.199258] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  113.349189] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  113.483122] hpet_rtc_timer_reinit: 20 callbacks suppressed
[  113.483122] hpet1: lost 2 rtc interrupts
Comment 6 Octavio 2017-12-19 18:03:37 UTC
This issue is happening on GLK with latest configuration.

Test cases:
igt@drv_suspend@debugfs-reader
igt@drv_suspend@debugfs-reader-hibernate

configuration
IGT-Version: 1.20-gc0be331 (x86_64) (Linux: 4.15.0-rc4-drm-intel-qa-ww51-commit-bf5cdf9+ x86_64)

[   91.015687] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   91.182245] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   91.348790] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   91.515140] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   91.681693] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   91.848289] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   92.014802] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
Comment 7 Elizabeth 2018-01-02 17:10:18 UTC
CFL with IGT 1.20-g4cd4cc4, kernel 4.15.0-rc5-commit-8cb9e98+, the warn was capture in test igt@debugfs_test@read_all_entries.
	
[   69.501965] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
Comment 8 Marta Löfstedt 2018-01-10 11:29:28 UTC
Note from:
CI_DRM_3611 and all consecutive runs on GLK-shards, so far to CI_DRM_3617

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3611/shard-glkb3/igt@debugfs_test@read_all_entries.html

hits:
[  139.108554] Unclaimed read from register 0x130090
[  139.108666] WARNING: CPU: 1 PID: 1462 at drivers/gpu/drm/i915/intel_uncore.c:986 __unclaimed_reg_debug+0x3b/0x50 [i915]
[  139.108671] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm r8169 mei_me mii mei prime_numbers i2c_hid pinctrl_geminilake pinctrl_intel
[  139.108800] CPU: 1 PID: 1462 Comm: debugfs_test Tainted: G     U           4.15.0-rc7-CI-CI_DRM_3611+ #1
[  139.108806] Hardware name: Intel Corp. Geminilake/GLK RVP1 DDR4 (05), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[  139.108892] RIP: 0010:__unclaimed_reg_debug+0x3b/0x50 [i915]
[  139.108898] RSP: 0018:ffffc9000059bd38 EFLAGS: 00010092
[  139.108908] RAX: 0000000000000025 RBX: 0000000000000000 RCX: 0000000000000002
[  139.108914] RDX: 0000000080000002 RSI: ffffffff820ab3a7 RDI: 00000000ffffffff
[  139.108919] RBP: 0000000000130090 R08: 0000000000000000 R09: 0000000000000001
[  139.108925] R10: ffffc9000059bcd0 R11: 0000000000000002 R12: 0000000000000001
[  139.108930] R13: ffff8801695c0ca0 R14: 0000000000000000 R15: 0000000000000202
[  139.108936] FS:  00007fbb4cd6da40(0000) GS:ffff88017fc80000(0000) knlGS:0000000000000000
[  139.108942] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  139.108948] CR2: 000055921b94d2d8 CR3: 000000016a208000 CR4: 0000000000340ee0
[  139.108953] Call Trace:
[  139.109046]  fwtable_read32+0x214/0x290 [i915]
[  139.109131]  i915_drpc_info+0x267/0x8c0 [i915]
[  139.109145]  ? rcu_read_lock_sched_held+0x6f/0x80
[  139.109162]  seq_read+0xd8/0x3a0
[  139.109176]  ? debug_check_no_obj_freed+0x11f/0x230
[  139.109190]  full_proxy_read+0x4b/0x70
[  139.109204]  __vfs_read+0x1e/0x120
[  139.109215]  ? do_sys_open+0x197/0x1f0
[  139.109226]  ? do_sys_open+0x197/0x1f0
[  139.109237]  ? entry_SYSCALL_64_fastpath+0x5/0x89
[  139.109248]  vfs_read+0x9e/0x150
[  139.109260]  SyS_read+0x40/0xa0
[  139.109274]  entry_SYSCALL_64_fastpath+0x1c/0x89
[  139.109281] RIP: 0033:0x7fbb4b2456d0
[  139.109286] RSP: 002b:00007ffcd34b3888 EFLAGS: 00000246
[  139.109315] Code: 3c 01 75 30 84 db 75 2c 45 84 e4 48 c7 c0 85 35 26 a0 48 c7 c6 7b 35 26 a0 48 0f 44 f0 89 ea 48 c7 c7 8e 35 26 a0 e8 b5 8c ed e0 <0f> ff 83 2d 4c bc 16 00 01 5b 5d 41 5c c3 0f 1f 80 00 00 00 00 
[  139.109629] ---[ end trace 8ffd6c56c54f09e5 ]---

i915_regs.h
#define  FORCEWAKE_ACK				_MMIO(0x130090)

I believe the backtrace is from:
i915_debugfs.c -> 
static int gen6_drpc_info(struct seq_file *m)
...
		while (count++ < 50 && (I915_READ_NOTRACE(FORCEWAKE_ACK) & 1))
Comment 9 Marta Löfstedt 2018-01-10 12:21:10 UTC
Interesting there are no IGT commits between CI_DRM_3611 - CI_DRM_3617, i.e. same test ordering for all the runs.

maybe it isn't a regression. but some other evil thing happening before.

So, before theigt@debugfs_test@read_all_entries there are:
fail: igt/kms_frontbuffer_tracking/fbcpsr-1p-rte
pass: igt/prime_vgem/fence-wait-render
pass: igt/gem_exec_flush/basic-uc-pro-default
skip: igt/kms_plane_lowres/pipe-f-tiling-none
dmesg-warn: igt/pm_rpm/debugfs-read
pass: igt/kms_3d                                  


igt/pm_rpm/debugfs-read
<7>[   98.926697] [drm:sandybridge_pcode_read [i915]] warning: pcode (read from mbox 5) mailbox access failed for i915_drpc_info [i915]: -6

- 6 =>
	case GEN6_PCODE_ILLEGAL_CMD:
		return -ENXIO;
Comment 10 Marta Löfstedt 2018-01-10 14:24:22 UTC
static int gen6_drpc_info(struct seq_file *m)

does:
sandybridge_pcode_read(dev_priv, GEN6_PCODE_READ_RC6VIDS, &rc6vids);

maybe GEN6_PCODE_READ_RC6VIDS is not available on GLK-shards
Comment 11 Octavio 2018-01-11 14:57:11 UTC
This issue is happening on GLK with latest configuration.

Test cases:
igt@drv_suspend@debugfs-reader
igt@drv_suspend@debugfs-reader-hibernate

using IGT-Version: 1.20-g19c6c04 (x86_64) (Linux: 4.15.0-rc7-drm-intel-qa-ww2-commit-17fd162+ x86_64)

[  522.092188] atkbd serio0: Failed to enable keyboard on isa0060/serio0
[  522.270930] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  522.420776] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  522.570663] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  522.720625] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  522.870458] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  523.020311] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  523.170154] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  524.383176] hpet1: lost 7162 rtc interrupts
[  525.579227] hpet1: lost 7162 rtc interrupts
[  526.571006] hpet1: lost 7161 rtc interrupts
[  526.856056]  cache: parent cpu1 should not be sleeping
[  527.036223] usb usb1: root hub lost power or was reset
[  527.036225] usb usb2: root hub lost power or was reset
[  527.046453] sd 0:0:0:0: [sda] Starting disk
[  527.275788] atkbd serio0: Failed to deactivate keyboard on isa0060/serio0
[  527.659785] atkbd serio0: Failed to enable keyboard on isa0060/serio0
[  527.871037] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  527.929151] hpet1: lost 7160 rtc interrupts
[  528.087500] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  528.254138] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  528.453908] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  528.620446] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  528.797602] hpet1: lost 7161 rtc interrupts
[  528.886933] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  529.036662] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  529.203318] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  529.353088] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  529.503077] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  529.652969] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  529.802787] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
Comment 12 Hector Velazquez 2018-01-16 13:49:33 UTC
This tests has a dmesg-warn on CFL QA 

Tests List:

igt@drv_suspend@debugfs-reader
igt@drv_suspend@debugfs-reader-hibernate

using IGT-Version: 1.20-g84a3080 (x86_64) (Linux: 4.15.0-rc8-drm-tip-ww3-commit-37eb04c+ x86_64)

======================================
        dmesg-warn sample
======================================
. . .
[  871.946912] (elapsed 0.176 seconds) done.
[  871.948330] Suspending console(s) (use no_console_suspend to debug)
[  872.292098] sd 4:0:0:0: [sda] Synchronizing SCSI cache
[  872.292489] sd 4:0:0:0: [sda] Stopping disk
[  872.308830] tpm tpm0: A TPM error (325) occurred stopping the TPM
[  872.861846] IRQ 122: no longer affine to CPU8
[  872.885746] IRQ 17: no longer affine to CPU9
[  872.909690] IRQ 125: no longer affine to CPU10
[  872.933554] IRQ 1: no longer affine to CPU11
[  872.933561] IRQ 8: no longer affine to CPU11
[  872.933565] IRQ 9: no longer affine to CPU11
[  872.933572] IRQ 16: no longer affine to CPU11
[  872.933577] IRQ 20: no longer affine to CPU11
[  872.933584] IRQ 123: no longer affine to CPU11
[  872.942497]  cache: parent cpu1 should not be sleeping
[  872.943660]  cache: parent cpu2 should not be sleeping
[  872.944798]  cache: parent cpu3 should not be sleeping
[  872.945950]  cache: parent cpu4 should not be sleeping
[  872.947132]  cache: parent cpu5 should not be sleeping
[  872.948436]  cache: parent cpu6 should not be sleeping
[  872.949682]  cache: parent cpu7 should not be sleeping
[  872.950980]  cache: parent cpu8 should not be sleeping
[  872.952323]  cache: parent cpu9 should not be sleeping
[  872.953731]  cache: parent cpu10 should not be sleeping
[  872.955161]  cache: parent cpu11 should not be sleeping
[  873.023806] ACPI: button: The lid device is not compliant to SW_LID.
[  873.079073] hpet_rtc_timer_reinit: 19 callbacks suppressed
[  873.079074] hpet1: lost 1427 rtc interrupts
[  873.452873] sd 4:0:0:0: [sda] Starting disk
[  873.714075] atkbd serio0: Failed to deactivate keyboard on isa0060/serio0
[  874.090129] atkbd serio0: Failed to enable keyboard on isa0060/serio0
[  874.117353] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  874.300780] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  874.484106] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  874.667559] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  874.850769] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  875.034178] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
. . .
Comment 13 Hector Velazquez 2018-01-16 13:50:37 UTC
Created attachment 136761 [details]
kernel log
Comment 14 Martin Peres 2018-01-18 15:04:00 UTC
The following has also been seen on igt@debugfs_test@read_all_entries_display_off, not just igt@debugfs_test@read_all_entries.

[ 336.785322] ------------[ cut here ]------------
[ 336.785363] Unclaimed read from register 0x130090
[ 336.785431] WARNING: CPU: 3 PID: 1793 at drivers/gpu/drm/i915/intel_uncore.c:986 __unclaimed_reg_debug+0x3b/0x50 [i915]
[ 336.785435] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal i915 intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm r8169 mii mei_me prime_numbers mei i2c_hid pinctrl_geminilake pinctrl_intel
[ 336.785498] CPU: 3 PID: 1793 Comm: debugfs_test Tainted: G U 4.15.0-rc8-CI-CI_DRM_3643+ #1
[ 336.785500] Hardware name: Intel Corp. Geminilake/GLK RVP1 DDR4 (05), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[ 336.785537] RIP: 0010:__unclaimed_reg_debug+0x3b/0x50 [i915]
[ 336.785540] RSP: 0018:ffffc9000194bd38 EFLAGS: 00010092
[ 336.785544] RAX: 0000000000000025 RBX: 0000000000000000 RCX: 0000000000000002
[ 336.785547] RDX: 0000000080000002 RSI: ffffffff820abd07 RDI: 00000000ffffffff
[ 336.785549] RBP: 0000000000130090 R08: 0000000000000000 R09: 0000000000000001
[ 336.785551] R10: ffffc9000194bcd0 R11: 0000000000000002 R12: 0000000000000001
[ 336.785554] R13: ffff880168650ca0 R14: 0000000000000000 R15: 0000000000000202
[ 336.785557] FS: 00007fc0bd595a40(0000) GS:ffff88017fd80000(0000) knlGS:0000000000000000
[ 336.785559] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 336.785562] CR2: 00007f110a6e4030 CR3: 000000017814a000 CR4: 0000000000340ee0
[ 336.785564] Call Trace:
[ 336.785604] fwtable_read32+0x214/0x290 [i915]
[ 336.785640] i915_drpc_info+0x267/0x8c0 [i915]
[ 336.785646] ? rcu_read_lock_sched_held+0x6f/0x80
[ 336.785654] seq_read+0xd8/0x3a0
[ 336.785660] ? debug_check_no_obj_freed+0x11f/0x230
[ 336.785667] full_proxy_read+0x4b/0x70
[ 336.785673] __vfs_read+0x1e/0x120
[ 336.785678] ? do_sys_open+0x197/0x1f0
[ 336.785683] ? do_sys_open+0x197/0x1f0
[ 336.785688] ? entry_SYSCALL_64_fastpath+0x5/0x8f
[ 336.785693] vfs_read+0x9e/0x150
[ 336.785698] SyS_read+0x40/0xa0
[ 336.785705] entry_SYSCALL_64_fastpath+0x22/0x8f
[ 336.785708] RIP: 0033:0x7fc0bba6d6d0
[ 336.785710] RSP: 002b:00007ffdd2380998 EFLAGS: 00000246
[ 336.785723] Code: 3c 01 75 30 84 db 75 2c 45 84 e4 48 c7 c0 98 05 26 a0 48 c7 c6 8e 05 26 a0 48 0f 44 f0 89 ea 48 c7 c7 a1 05 26 a0 e8 55 c3 ed e0 <0f> ff 83 2d 6c b8 16 00 01 5b 5d 41 5c c3 0f 1f 80 00 00 00 00 
[ 336.785854] ---[ end trace f74a4c54edc3891e ]---

Source: https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3643/shard-glkb3/igt@debugfs_test@read_all_entries_display_off.html
Comment 15 Jari Tahvanainen 2018-01-30 11:04:07 UTC
Adjusting back to Medium (after discussions), letting CI-Shards to define the priority according the impact over there. Sorry about any inconvenience caused.
Comment 16 Marta Löfstedt 2018-02-08 06:43:38 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4229/shard-glkb1/igt@gem_ctx_create@basic.html
<7>[  234.234820] [IGT] gem_ctx_create: executing
<7>[  234.234890] [drm:intel_power_well_enable [i915]] enabling dpio-common-c
<7>[  234.236312] [drm:intel_power_well_enable [i915]] enabling AUX B
<7>[  234.236349] [drm:intel_power_well_enable [i915]] enabling AUX C
<7>[  234.236387] [drm:intel_power_well_disable [i915]] disabling AUX C
<7>[  234.236423] [drm:intel_power_well_disable [i915]] disabling AUX B
<7>[  234.236459] [drm:intel_power_well_disable [i915]] disabling dpio-common-c
<7>[  234.236496] [drm:intel_power_well_disable [i915]] disabling dpio-common-b
<7>[  234.236532] [drm:intel_power_well_disable [i915]] disabling power well 2
<4>[  234.237101] ------------[ cut here ]------------
<4>[  234.237138] Unclaimed read from register 0x130090
<4>[  234.237206] WARNING: CPU: 2 PID: 1936 at drivers/gpu/drm/i915/intel_uncore.c:986 __unclaimed_reg_debug+0x3b/0x50 [i915]
<4>[  234.237208] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm r8169 mii prime_numbers mei_me mei i2c_hid pinctrl_geminilake pinctrl_intel
<4>[  234.237276] CPU: 2 PID: 1936 Comm: cat Tainted: G     U           4.15.0-CI-CI_DRM_3736+ #1
<4>[  234.237279] Hardware name: Intel Corp. Geminilake/GLK RVP2 LP4SD (07), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
<4>[  234.237315] RIP: 0010:__unclaimed_reg_debug+0x3b/0x50 [i915]
<4>[  234.237320] RSP: 0018:ffffc90000ba3d38 EFLAGS: 00010092
<4>[  234.237327] RAX: 0000000000000025 RBX: 0000000000000000 RCX: 0000000000000002
<4>[  234.237330] RDX: 0000000080000002 RSI: ffffffff820abf17 RDI: 00000000ffffffff
<4>[  234.237332] RBP: 0000000000130090 R08: 0000000000000000 R09: 0000000000000001
<4>[  234.237334] R10: ffffc90000ba3cd0 R11: 0000000000000002 R12: 0000000000000001
<4>[  234.237336] R13: ffff880167780ca0 R14: 0000000000000000 R15: 0000000000000202
<4>[  234.237339] FS:  00007fb4568b5700(0000) GS:ffff88017fd00000(0000) knlGS:0000000000000000
<4>[  234.237341] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[  234.237344] CR2: 000055b3f5415100 CR3: 0000000172902000 CR4: 0000000000340ee0
<4>[  234.237348] Call Trace:
<4>[  234.237391]  fwtable_read32+0x214/0x290 [i915]
<4>[  234.237429]  i915_drpc_info+0x267/0x8c0 [i915]
<4>[  234.237436]  ? rcu_read_lock_sched_held+0x6f/0x80
<4>[  234.237444]  seq_read+0xd8/0x3a0
<4>[  234.237452]  full_proxy_read+0x4b/0x70
<4>[  234.237459]  __vfs_read+0x1e/0x120
<4>[  234.237468]  ? entry_SYSCALL_64_fastpath+0x5/0x8f
<4>[  234.237472]  vfs_read+0x9e/0x150
<4>[  234.237477]  SyS_read+0x40/0xa0
<4>[  234.237483]  entry_SYSCALL_64_fastpath+0x22/0x8f
<4>[  234.237486] RIP: 0033:0x7fb4563e1890
<4>[  234.237488] RSP: 002b:00007fffec12b328 EFLAGS: 00000246
<4>[  234.237501] Code: 3c 01 75 30 84 db 75 2c 45 84 e4 48 c7 c0 aa 46 26 a0 48 c7 c6 a0 46 26 a0 48 0f 44 f0 89 ea 48 c7 c7 b3 46 26 a0 e8 15 b6 ed e0 <0f> ff 83 2d 1c 0c 17 00 01 5b 5d 41 5c c3 0f 1f 80 00 00 00 00 
<4>[  234.237631] ---[ end trace 7b6e6c28539f6d16 ]---
<7>[  234.237803] [drm:sandybridge_pcode_read [i915]] warning: pcode (read from mbox 5) mailbox access failed for i915_drpc_info [i915]: -6
Comment 17 Hector Velazquez 2018-02-09 18:02:31 UTC
This test has a dmesg-warn on GLK QA 

Tests List:

igt@drv_suspend@debugfs-reader-hibernate
igt@drv_suspend@debugfs-reader

IGT-Version: 1.21-g94bd67c (x86_64) (Linux: 4.15.0-drm-tip-ww6-commit-078873d+ x86_64)

======================================
        dmesg-warn sample
======================================
. . .
[  177.388077] (elapsed 0.037 seconds) done.
[  177.389235] Suspending console(s) (use no_console_suspend to debug)
[  178.296465] hpet_rtc_timer_reinit: 14 callbacks suppressed
[  178.296466] hpet1: lost 7161 rtc interrupts
[  179.254906] hpet1: lost 7161 rtc interrupts
[  179.268253] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  179.268930] sd 0:0:0:0: [sda] Stopping disk
[  179.681697] IRQ 124: no longer affine to CPU1
[  179.709678] IRQ fixup: irq 1 move in progress, old vector 33
[  179.709774] IRQ 122: no longer affine to CPU2
[  179.725495] IRQ 1: no longer affine to CPU3
[  179.725513] IRQ 8: no longer affine to CPU3
[  179.725523] IRQ 9: no longer affine to CPU3
[  179.725540] IRQ 27: no longer affine to CPU3
[  179.725562] IRQ 30: no longer affine to CPU3
[  179.725583] IRQ 33: no longer affine to CPU3
[  179.725593] IRQ 34: no longer affine to CPU3
[  179.725604] IRQ 35: no longer affine to CPU3
[  179.736129]  cache: parent cpu1 should not be sleeping
[  179.739679]  cache: parent cpu2 should not be sleeping
[  179.743416]  cache: parent cpu3 should not be sleeping
[  179.845790] ACPI: button: The lid device is not compliant to SW_LID.
[  180.098407] sd 0:0:0:0: [sda] Starting disk
[  180.655249] done.
[  180.790665] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  180.846649] atkbd serio0: Failed to deactivate keyboard on isa0060/serio0
[  180.940567] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  181.090451] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  181.226520] atkbd serio0: Failed to enable keyboard on isa0060/serio0
[  181.240352] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  181.390203] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  181.539998] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  181.689871] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
. . .
Comment 18 Jani Saarinen 2018-02-12 08:43:26 UTC
I suppose fixed already:
"Thanks for the review and testing I pushed the patch to -dinq."
Comment 19 Jani Saarinen 2018-02-12 08:44:18 UTC
Imre Deak <imre.deak@intel.com>	2018-02-08 13:23:31
commit	928f0cd0eb0511e19b416e34a030ebff6785fa52
Comment 20 Marta Löfstedt 2018-02-12 08:57:29 UTC
fix integrated to CI_DRM_3749 no new occurrences I will archive the bug from cibuglog perspective, but since QA also has filed stuff on it I can't close.
Comment 21 Martin Peres 2018-02-12 09:38:10 UTC
The following is still happening. Should I create a new bug?

[  234.237101] ------------[ cut here ]------------
[  234.237138] Unclaimed read from register 0x130090
[  234.237206] WARNING: CPU: 2 PID: 1936 at drivers/gpu/drm/i915/intel_uncore.c:986 __unclaimed_reg_debug+0x3b/0x50 [i915]
[  234.237208] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm r8169 mii prime_numbers mei_me mei i2c_hid pinctrl_geminilake pinctrl_intel
[  234.237276] CPU: 2 PID: 1936 Comm: cat Tainted: G     U           4.15.0-CI-CI_DRM_3736+ #1
[  234.237279] Hardware name: Intel Corp. Geminilake/GLK RVP2 LP4SD (07), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[  234.237315] RIP: 0010:__unclaimed_reg_debug+0x3b/0x50 [i915]
[  234.237320] RSP: 0018:ffffc90000ba3d38 EFLAGS: 00010092
[  234.237327] RAX: 0000000000000025 RBX: 0000000000000000 RCX: 0000000000000002
[  234.237330] RDX: 0000000080000002 RSI: ffffffff820abf17 RDI: 00000000ffffffff
[  234.237332] RBP: 0000000000130090 R08: 0000000000000000 R09: 0000000000000001
[  234.237334] R10: ffffc90000ba3cd0 R11: 0000000000000002 R12: 0000000000000001
[  234.237336] R13: ffff880167780ca0 R14: 0000000000000000 R15: 0000000000000202
[  234.237339] FS:  00007fb4568b5700(0000) GS:ffff88017fd00000(0000) knlGS:0000000000000000
[  234.237341] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  234.237344] CR2: 000055b3f5415100 CR3: 0000000172902000 CR4: 0000000000340ee0
[  234.237348] Call Trace:
[  234.237391]  fwtable_read32+0x214/0x290 [i915]
[  234.237429]  i915_drpc_info+0x267/0x8c0 [i915]
[  234.237436]  ? rcu_read_lock_sched_held+0x6f/0x80
[  234.237444]  seq_read+0xd8/0x3a0
[  234.237452]  full_proxy_read+0x4b/0x70
[  234.237459]  __vfs_read+0x1e/0x120
[  234.237468]  ? entry_SYSCALL_64_fastpath+0x5/0x8f
[  234.237472]  vfs_read+0x9e/0x150
[  234.237477]  SyS_read+0x40/0xa0
[  234.237483]  entry_SYSCALL_64_fastpath+0x22/0x8f
[  234.237486] RIP: 0033:0x7fb4563e1890
[  234.237488] RSP: 002b:00007fffec12b328 EFLAGS: 00000246
[  234.237501] Code: 3c 01 75 30 84 db 75 2c 45 84 e4 48 c7 c0 aa 46 26 a0 48 c7 c6 a0 46 26 a0 48 0f 44 f0 89 ea 48 c7 c7 b3 46 26 a0 e8 15 b6 ed e0 <0f> ff 83 2d 1c 0c 17 00 01 5b 5d 41 5c c3 0f 1f 80 00 00 00 00 
[  234.237631] ---[ end trace 7b6e6c28539f6d16 ]---
[  234.311753] Setting dangerous option reset - tainting kernel

https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4229/shard-glkb1/igt@gem_ctx_create@basic.html
Comment 22 Marta Löfstedt 2018-02-12 09:41:46 UTC
(In reply to Martin Peres from comment #21)
> The following is still happening. Should I create a new bug?
> 
> [  234.237101] ------------[ cut here ]------------
> [  234.237138] Unclaimed read from register 0x130090
> [  234.237206] WARNING: CPU: 2 PID: 1936 at
> drivers/gpu/drm/i915/intel_uncore.c:986 __unclaimed_reg_debug+0x3b/0x50
> [i915]
> [  234.237208] Modules linked in: vgem snd_hda_codec_hdmi
> snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal
> intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel
> snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm r8169 mii
> prime_numbers mei_me mei i2c_hid pinctrl_geminilake pinctrl_intel
> [  234.237276] CPU: 2 PID: 1936 Comm: cat Tainted: G     U          
> 4.15.0-CI-CI_DRM_3736+ #1
> [  234.237279] Hardware name: Intel Corp. Geminilake/GLK RVP2 LP4SD (07),
> BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
> [  234.237315] RIP: 0010:__unclaimed_reg_debug+0x3b/0x50 [i915]
> [  234.237320] RSP: 0018:ffffc90000ba3d38 EFLAGS: 00010092
> [  234.237327] RAX: 0000000000000025 RBX: 0000000000000000 RCX:
> 0000000000000002
> [  234.237330] RDX: 0000000080000002 RSI: ffffffff820abf17 RDI:
> 00000000ffffffff
> [  234.237332] RBP: 0000000000130090 R08: 0000000000000000 R09:
> 0000000000000001
> [  234.237334] R10: ffffc90000ba3cd0 R11: 0000000000000002 R12:
> 0000000000000001
> [  234.237336] R13: ffff880167780ca0 R14: 0000000000000000 R15:
> 0000000000000202
> [  234.237339] FS:  00007fb4568b5700(0000) GS:ffff88017fd00000(0000)
> knlGS:0000000000000000
> [  234.237341] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [  234.237344] CR2: 000055b3f5415100 CR3: 0000000172902000 CR4:
> 0000000000340ee0
> [  234.237348] Call Trace:
> [  234.237391]  fwtable_read32+0x214/0x290 [i915]
> [  234.237429]  i915_drpc_info+0x267/0x8c0 [i915]
> [  234.237436]  ? rcu_read_lock_sched_held+0x6f/0x80
> [  234.237444]  seq_read+0xd8/0x3a0
> [  234.237452]  full_proxy_read+0x4b/0x70
> [  234.237459]  __vfs_read+0x1e/0x120
> [  234.237468]  ? entry_SYSCALL_64_fastpath+0x5/0x8f
> [  234.237472]  vfs_read+0x9e/0x150
> [  234.237477]  SyS_read+0x40/0xa0
> [  234.237483]  entry_SYSCALL_64_fastpath+0x22/0x8f
> [  234.237486] RIP: 0033:0x7fb4563e1890
> [  234.237488] RSP: 002b:00007fffec12b328 EFLAGS: 00000246
> [  234.237501] Code: 3c 01 75 30 84 db 75 2c 45 84 e4 48 c7 c0 aa 46 26 a0
> 48 c7 c6 a0 46 26 a0 48 0f 44 f0 89 ea 48 c7 c7 b3 46 26 a0 e8 15 b6 ed e0
> <0f> ff 83 2d 1c 0c 17 00 01 5b 5d 41 5c c3 0f 1f 80 00 00 00 00 
> [  234.237631] ---[ end trace 7b6e6c28539f6d16 ]---
> [  234.311753] Setting dangerous option reset - tainting kernel
> 
> https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4229/shard-glkb1/
> igt@gem_ctx_create@basic.html

Martin,IGT_4229 is based on CI_DRM_3736 the fix was integrated to CI_DRM_3749 which is after your occurrence.
Comment 23 Imre Deak 2018-02-12 11:47:26 UTC
There are 2 issues handled in this report:

1. [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks

This is still unsolved I think.

2. Unclaimed read from register 0x130090

Fix for this already merged, per comment#19.
Comment 24 Imre Deak 2018-02-12 11:57:41 UTC
(In reply to Imre Deak from comment #23)
> There are 2 issues handled in this report:
> 
> 1. [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any
> CRC after 6 vblanks
> 
> This is still unsolved I think.
> 
> 2. Unclaimed read from register 0x130090
> 
> Fix for this already merged, per comment#19.

Hm, actually there is also a third one:

[drm:sandybridge_pcode_read [i915]] warning: pcode (read from mbox 5) mailbox access failed for i915_drpc_info [i915]: -6

for which there is a fix, but it's not merged yet.
Comment 25 Marta Löfstedt 2018-02-12 12:04:40 UTC
If QA want to reopen I am fine with that. However, for CI the only issues seen in a long time is the "Unclaimed read from register 0x130090" on GLK-shards. This issue appear to be fixed, therefore I have archived the bug from cibuglog perspective.
Comment 26 Octavio 2018-02-27 21:30:59 UTC
The following test cases have dmesg-warn on CFL QA

igt@drv_suspend@debugfs-reader
igt@drv_suspend@debugfs-reader-hibernate

IGT-Version: 1.21-ga2664f8 (x86_64) (Linux: 4.16.0-rc2-drm-intel-qa-ww9-commit-01a067a+ x86_64)
[cmd] rtcwake: wakeup from "disk" using /dev/rtc0 at Sun Feb 25 06:09:08 2018
Subtest debugfs-reader-hibernate: SUCCESS (4.441s)

[  197.561673] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  197.695111] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  197.861730] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  198.045035] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  198.228417] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  198.411760] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  198.595041] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  198.922071] Suspending console(s) (use no_console_suspend to debug)
[  199.610452]  cache: parent cpu1 should not be sleeping
[  199.611550]  cache: parent cpu2 should not be sleeping
[  199.612653]  cache: parent cpu3 should not be sleeping
[  199.613793]  cache: parent cpu4 should not be sleeping
[  199.614976]  cache: parent cpu5 should not be sleeping
[  199.616228]  cache: parent cpu6 should not be sleeping
[  199.617477]  cache: parent cpu7 should not be sleeping
[  199.618766]  cache: parent cpu8 should not be sleeping
[  199.620095]  cache: parent cpu9 should not be sleeping
[  199.621467]  cache: parent cpu10 should not be sleeping
[  199.622932]  cache: parent cpu11 should not be sleeping
[  199.672137] i2c_designware i2c_designware.0: Unknown Synopsys component type: 0xffffffff
[  199.672148] i2c_designware i2c_designware.1: Unknown Synopsys component type: 0xffffffff
[  199.705783] usb usb1: root hub lost power or was reset
[  199.705784] usb usb2: root hub lost power or was reset
[  199.938908] atkbd serio0: Failed to deactivate keyboard on isa0060/serio0
[  200.310988] atkbd serio0: Failed to enable keyboard on isa0060/serio0
[  200.937637] i2c_designware i2c_designware.0: timeout in disabling adapter
[  200.937659] i2c_designware i2c_designware.1: timeout in disabling adapter
[  201.140997] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  201.324108] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  201.507592] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  201.690756] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  201.840742] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[  201.990994] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
Comment 27 Octavio 2018-02-27 21:31:17 UTC
Created attachment 137669 [details]
dmesg log
Comment 28 Octavio 2018-03-06 17:08:02 UTC
The following test cases have dmesg-warn on CFL QA

igt@drv_suspend@debugfs-reader
igt@drv_suspend@debugfs-reader-hibernat

IGT-Version: 1.21-g68fb759 (x86_64) (Linux: 4.16.0-rc4-drm-intel-qa-ww10-commit-a994c52+ x86_64)

[   46.538739] (elapsed 0.152 seconds) done.
[   46.540187] Suspending console(s) (use no_console_suspend to debug)
[   47.054541] IRQ 142: no longer affine to CPU1
[   47.078452] IRQ 144: no longer affine to CPU2
[   47.078457] IRQ 146: no longer affine to CPU2
[   47.102558] IRQ 134: no longer affine to CPU3
[   47.102566] IRQ 141: no longer affine to CPU3
[   47.126764] IRQ 136: no longer affine to CPU4
[   47.126770] IRQ 138: no longer affine to CPU4
[   47.150357] IRQ 140: no longer affine to CPU5
[   47.178020] IRQ 124: no longer affine to CPU6
[   47.178031] IRQ 139: no longer affine to CPU6
[   47.301317]  cache: parent cpu1 should not be sleeping
[   47.302405]  cache: parent cpu2 should not be sleeping
[   47.303471]  cache: parent cpu3 should not be sleeping
[   47.304564]  cache: parent cpu4 should not be sleeping
[   47.305695]  cache: parent cpu5 should not be sleeping
[   47.306930]  cache: parent cpu6 should not be sleeping
[   47.308105]  cache: parent cpu7 should not be sleeping
[   47.309330]  cache: parent cpu8 should not be sleeping
[   47.310598]  cache: parent cpu9 should not be sleeping
[   47.311930]  cache: parent cpu10 should not be sleeping
[   47.313283]  cache: parent cpu11 should not be sleeping
[   47.993649] atkbd serio0: Failed to deactivate keyboard on isa0060/serio0
[   48.369679] atkbd serio0: Failed to enable keyboard on isa0060/serio0
[   48.596880] done.
[   48.851449] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   49.034770] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   49.218033] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   49.401431] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   49.568111] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
[   49.751431] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
Comment 29 Marta Löfstedt 2018-03-12 12:02:04 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3903/fi-cfl-u/igt@drv_suspend@debugfs-reader.html

[  330.361752] [drm:intel_dp_sink_crc [i915]] *ERROR* Panel is unable to calculate any CRC after 6 vblanks
Comment 30 Marta Löfstedt 2018-03-16 08:48:29 UTC
This was reopend due to CFL-u. However I just discovered this maybe new thing with the:
<7>[   39.903153] [drm:intel_dp_sink_crc_stop [i915]] TIMEOUT: Sink CRC counter is not zeroed after calculation is stopped

ralated to this issue so I will close this again and use bug 105542

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.