Bug 111464 - [CI][DRMTIP] igt@kms_cursor_legacy@cursora-vs-flipa-atomic-transitions-varying-size - dmesg-warn - watchdog: BUG: soft lockup - CPU#5 stuck for 105s! [dmesg:1072]
Summary: [CI][DRMTIP] igt@kms_cursor_legacy@cursora-vs-flipa-atomic-transitions-varyi...
Status: NEW
Alias: None
Product: DRI
Classification: Unclassified
Component: DRM/Intel (show other bugs)
Version: DRI git
Hardware: Other All
: not set not set
Assignee: Intel GFX Bugs mailing list
QA Contact: Intel GFX Bugs mailing list
URL:
Whiteboard:
Keywords:
Depends on:
Blocks:
 
Reported: 2019-08-22 10:06 UTC by Lakshmi
Modified: 2019-08-29 19:55 UTC (History)
1 user (show)

See Also:
i915 platform: ICL
i915 features: display/Other


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Lakshmi 2019-08-22 10:06:14 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_348/fi-icl-guc/igt@kms_cursor_legacy@cursora-vs-flipa-atomic-transitions-varying-size.html

<0> [311.947734] watchdog: BUG: soft lockup - CPU#5 stuck for 105s! [dmesg:1072]
<4> [200.099667] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic mei_hdcp i915 x86_pkg_temp_thermal coretemp ax88179_178a usbnet mii snd_hda_intel crct10dif_pclmul snd_intel_nhlt snd_hda_codec crc32_pclmul e1000e snd_hwdep snd_hda_core ghash_clmulni_intel snd_pcm ptp pps_core mei_me mei prime_numbers
<4> [200.099686] irq event stamp: 42467984
<4> [200.099693] hardirqs last  enabled at (42467983): [<ffffffff9b1522e2>] ktime_get_coarse_real_ts64+0x92/0xa0
<4> [200.099697] hardirqs last disabled at (42467984): [<ffffffff9b001a8a>] trace_hardirqs_off_thunk+0x1a/0x20
<4> [200.099702] softirqs last  enabled at (42467570): [<ffffffff9bc0033e>] __do_softirq+0x33e/0x4ae
<4> [200.099706] softirqs last disabled at (42467563): [<ffffffff9b0bbf79>] irq_exit+0xa9/0xc0
<4> [200.099709] CPU: 5 PID: 1072 Comm: dmesg Tainted: G     U            5.3.0-rc5-g0db9333be821-drmtip_348+ #1
<4> [200.099712] Hardware name: Intel Corporation Ice Lake Client Platform/IceLake U DDR4 SODIMM PD RVP, BIOS ICLSFWR1.R00.3175.A00.1904261428 04/26/2019
<4> [200.099715] RIP: 0010:ktime_get_coarse_real_ts64+0x94/0xa0
<4> [200.099718] Code: 8b 05 c8 ba 0f 01 48 d3 e8 48 8b 0d 0e bb 0f 01 49 89 45 08 49 89 4d 00 39 13 75 8e 5b 5d 41 5c 41 5d c3 e8 fe e0 04 00 55 9d <8b> 13 f6 c2 01 74 c8 f3 90 eb bd 90 41 57 41 56 41 55 41 54 55 53
<4> [200.099721] RSP: 0018:ffffa57a40dd7ce8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
<4> [200.099727] RAX: ffff9989a7a5c040 RBX: ffffffff9c24dd40 RCX: 0000000000000005
<4> [200.099731] RDX: 00000000000012ca RSI: ffff9989a7a5c918 RDI: ffffffff9c1008a9
<4> [200.099734] RBP: 0000000000000246 R08: ffff9989a7a5c918 R09: 0000000000000000
<4> [200.099736] R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff9c24dd48
<4> [200.099738] R13: ffffa57a40dd7d10 R14: ffff9989b7af83c0 R15: 0000000000000000
<4> [200.099741] FS:  00007f12dba1e240(0000) GS:ffff9989cfe80000(0000) knlGS:0000000000000000
<4> [200.099743] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [200.099745] CR2: 00007f8f983278e0 CR3: 0000000884ea2002 CR4: 0000000000760ee0
<4> [200.099757] PKRU: 55555554
<4> [200.099759] Call Trace:
<4> [200.099779]  current_time+0x26/0x80
<4> [200.099785]  file_update_time+0x5b/0x120
<4> [200.099793]  __generic_file_write_iter+0x94/0x190
<4> [200.099800]  ext4_file_write_iter+0x153/0x3a0
<4> [200.099808]  new_sync_write+0x10d/0x1b0
<4> [200.099817]  vfs_write+0xbd/0x1d0
<4> [200.099821]  ksys_write+0x8f/0xe0
<4> [200.099827]  do_syscall_64+0x55/0x1c0
<4> [200.099830]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [200.099833] RIP: 0033:0x7f12daeff154
<4> [200.099836] Code: 89 02 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 8d 05 b1 07 2e 00 8b 00 85 c0 75 13 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 54 f3 c3 66 90 41 54 55 49 89 d4 53 48 89 f5
<4> [200.099837] RSP: 002b:00007ffcb88d3258 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
<4> [200.099840] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f12daeff154
<4> [200.099842] RDX: 0000000000000001 RSI: 00005589e6f6f0f5 RDI: 0000000000000001
<4> [200.099844] RBP: 00005589e6f6f0f5 R08: 0000000000000001 R09: 0000000000000000
<4> [200.099846] R10: 0000000000000005 R11: 0000000000000246 R12: 00007f12db1db760
<4> [200.099848] R13: 0000000000000001 R14: 00007f12db1d6760 R15: 0000000000000001
<7> [200.118377] [drm:skl_compute_wm [i915]] [PLANE:73:plane 7A] ddb (   0 -  979) -> (   0 -    0), size  979 ->    0
<7> [200.118403] [drm:skl_compute_wm [i915]] [PLANE:73:plane 7A]   level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm ->  wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm
<7> [200.118425] [drm:skl_compute_wm [i915]] [PLANE:73:plane 7A]   lines    1,   4,   4,   4,   5,   8,  13,  13,   0 ->    0,   0,   0,   0,   0,   0,   0,   0,   0
<7> [200.118446] [drm:skl_compute_wm [i915]] [PLANE:73:plane 7A]  blocks   27, 125, 125, 125, 156, 249, 404, 404,  41 ->    0,   0,   0,   0,   0,   0,   0,   0,   0
<7> [200.118467] [drm:skl_compute_wm [i915]] [PLANE:73:plane 7A] min_ddb   31, 139, 139, 139, 173, 275, 446, 446,   0 ->    0,   0,   0,   0,   0,   0,   0,   0,   0
<7> [200.118516] [drm:intel_bw_atomic_check [i915]] pipe A data rate 0 num active planes 0
<6> [200.129024] [IGT] kms_cursor_legacy: exiting, ret=0
<5> [200.129284] Setting dangerous option reset - tainting kernel
<4> [311.947352] clocksource: timekeeping watchdog on CPU2: Marking clocksource 'tsc' as unstable because the skew is too large:
<4> [311.947367] clocksource:                       'hpet' wd_now: 61e96c7d wd_last: e19272ca mask: ffffffff
<4> [311.947369] clocksource:                       'tsc' cs_now: 74fff6db75 cs_last: 4de577147a mask: ffffffffffffffff
<7> [311.947466] [drm:edp_panel_vdd_off_sync [i915]] Turning eDP port A VDD off
<7> [311.947671] [drm:edp_panel_vdd_off_sync [i915]] PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [311.947716] [drm:intel_power_well_disable [i915]] disabling DC off
<6> [311.947718] tsc: Marking TSC unstable due to clocksource watchdog
<7> [311.947764] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [311.947806] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<4> [311.947807] TSC found unstable after boot, most likely due to broken BIOS. Use 'tsc=unstable'.
<6> [311.947813] sched_clock: Marking unstable (311941815763, 5965370)<-(312040333318, -92551794)
<6> [311.948757] clocksource: Switched to clocksource hpet
<7> [311.960637] [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] ddb (   0 -    0) -> (   0 -  979), size    0 ->  979
<7> [311.960661] [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A]   level  wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm
<7> [311.960689] [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A]   lines    0,   0,   0,   0,   0,   0,   0,   0,   0 ->    1,   4,   4,   4,   5,   8,  13,  13,   0
<7> [311.960710] [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A]  blocks    0,   0,   0,   0,   0,   0,   0,   0,   0 ->   27, 125, 125, 125, 156, 249, 404, 404,  41
<7> [311.960731] [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] min_ddb    0,   0,   0,   0,   0,   0,   0,   0,   0 ->   31, 139, 139, 139, 173, 275, 446, 446,   0
<7> [311.960752] [drm:skl_compute_wm [i915]] [PLANE:80:cursor A]   level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm ->  wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm
<7> [311.960773] [drm:skl_compute_wm [i915]] [PLANE:80:cursor A]   lines   13,   4,   4,   4,   5,   8,  13,  13,   0 ->    0,   0,   0,   0,   0,   0,   0,   0,   0
<7> [311.960794] [drm:skl_compute_wm [i915]] [PLANE:80:cursor A]  blocks   27,   9,   9,   9,  11,  17,  27,  27,  41 ->    0,   0,   0,   0,   0,   0,   0,   0,   0
<7> [311.960814] [drm:skl_compute_wm [i915]] [PLANE:80:cursor A] min_ddb   31,  11,  11,  11,  14,  20,  31,  31,   0 ->    0,   0,   0,   0,   0,   0,   0,   0,   0
<7> [311.960861] [drm:intel_bw_atomic_check [i915]] pipe A data rate 2133000 num active planes 1
<6> [311.980904] Console: switching to colour frame buffer device 480x135
Comment 1 CI Bug Log 2019-08-22 10:08:45 UTC
The CI Bug Log issue associated to this bug has been updated.

### New filters associated

* ICL: igt@kms_cursor_legacy@cursora-vs-flipa-atomic-transitions-varying-size - dmesg-warn - watchdog: BUG: soft lockup - CPU#5 stuck for 105s! [dmesg:1072]
  - https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_348/fi-icl-guc/igt@kms_cursor_legacy@cursora-vs-flipa-atomic-transitions-varying-size.html
Comment 2 Chris Wilson 2019-08-29 19:55:06 UTC
https://bugzilla.kernel.org/show_bug.cgi?id=203183

That's for Coffelake and pc10, but might be relevant.


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.