Bug 112158

Summary:	[CI][SHARD]igt@i915_selftest@mock_requests - dmesg-warn - WARNING: CPU: 1 PID: 16 at kernel/workqueue.c:1415 __queue_work+0x547/0x660
Product:	DRI	Reporter:	Lakshmi <lakshminarayana.vudum>
Component:	DRM/Intel	Assignee:	Intel GFX Bugs mailing list <intel-gfx-bugs>
Status:	RESOLVED FIXED	QA Contact:	Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity:	not set
Priority:	not set	CC:	intel-gfx-bugs
Version:	DRI git
Hardware:	Other
OS:	All
Whiteboard:
i915 platform:	GLK	i915 features:

Description Lakshmi 2019-10-28 16:47:42 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_7194/shard-glk3/igt@i915_selftest@mock_requests.html
<6> [1921.044124] [IGT] i915_selftest: executing
<6> [1921.051813] [IGT] i915_selftest: starting subtest mock_requests
<5> [1921.162892] Setting dangerous option mock_selftests - tainting kernel
<6> [1921.223109] i915: Performing mock selftests with st_random_seed=0xcd0fc2d st_timeout=500
<6> [1921.223114] i915: Running requests
<7> [1921.227519]  mock: [drm:intel_uc_init_early [i915]] enable_guc=0 (guc:no submission:no huc:no)
<7> [1921.231342] [drm:intel_engines_driver_register [i915]] renamed mock to rcs0
<6> [1921.231363] i915: Running i915_request_mock_selftests/igt_add_request
<6> [1921.231970] i915: Running i915_request_mock_selftests/igt_wait_request
<6> [1921.744648] i915: Running i915_request_mock_selftests/igt_fence_wait
<6> [1922.255083] i915: Running i915_request_mock_selftests/igt_request_rewind
<6> [1922.259109] i915: Running i915_request_mock_selftests/mock_breadcrumbs_smoketest
<6> [1922.852986] Completed 29 waits for 14557 fence across 2 cpus
<4> [1922.934719] ------------[ cut here ]------------
<4> [1922.934744] WARNING: CPU: 1 PID: 16 at kernel/workqueue.c:1415 __queue_work+0x547/0x660
<4> [1922.934746] Modules linked in: i915(+) vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic mei_hdcp x86_pkg_temp_thermal btusb coretemp btrtl btbcm btintel crct10dif_pclmul crc32_pclmul bluetooth ghash_clmulni_intel ecdh_generic snd_intel_nhlt ecc snd_hda_codec snd_hwdep snd_hda_core snd_pcm r8169 realtek mei_me mei prime_numbers i2c_hid pinctrl_geminilake pinctrl_intel [last unloaded: i915]
<4> [1922.934778] CPU: 1 PID: 16 Comm: ksoftirqd/1 Tainted: G     U            5.4.0-rc4-CI-CI_DRM_7194+ #1
<4> [1922.934781] Hardware name: Intel Corporation NUC7CJYH/NUC7JYB, BIOS JYGLKCPX.86A.0027.2018.0125.1347 01/25/2018
<4> [1922.934785] RIP: 0010:__queue_work+0x547/0x660
<4> [1922.934789] Code: 00 75 27 65 48 8b 3c 25 00 5f 01 00 f6 47 24 20 74 18 e8 2c 87 00 00 48 85 c0 74 0e 48 8b 40 20 4c 3b 60 08 0f 84 15 fb ff ff <0f> 0b 48 8d 65 d8 5b 41 5c 41 5d 41 5e 41 5f 5d c3 0f 0b e9 34 fc
<4> [1922.934792] RSP: 0018:ffffc900000c7da8 EFLAGS: 00010006
<4> [1922.934795] RAX: 0000000080000100 RBX: ffff88823e600808 RCX: 0000000000000000
<4> [1922.934797] RDX: 0000000080000101 RSI: 0000000000000000 RDI: 00000000ffffffff
<4> [1922.934800] RBP: ffffc900000c7de0 R08: 0000000000000000 R09: 0000000000000001
<4> [1922.934802] R10: ffffc900000c7da8 R11: 00000000ffd37363 R12: ffff88827051e800
<4> [1922.934804] R13: 0000000000000010 R14: 0000000000000010 R15: ffff88823e600848
<4> [1922.934807] FS:  0000000000000000(0000) GS:ffff888277e80000(0000) knlGS:0000000000000000
<4> [1922.934810] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [1922.934812] CR2: 00007f79a1395100 CR3: 0000000219464000 CR4: 0000000000340ee0
<4> [1922.934814] Call Trace:
<4> [1922.934822]  ? delayed_work_timer_fn+0x10/0x10
<4> [1922.934827]  ? rcu_core+0x420/0x750
<4> [1922.934831]  rcu_work_rcufn+0x1c/0x30
<4> [1922.934835]  rcu_core+0x480/0x750
<4> [1922.934847]  __do_softirq+0xdf/0x47f
<4> [1922.934856]  ? smpboot_thread_fn+0x23/0x280
<4> [1922.934859]  ? smpboot_thread_fn+0x6b/0x280
<4> [1922.934864]  run_ksoftirqd+0x2b/0x50
<4> [1922.934867]  smpboot_thread_fn+0x1d3/0x280
<4> [1922.934873]  ? sort_range+0x20/0x20
<4> [1922.934877]  kthread+0x119/0x130
<4> [1922.934880]  ? kthread_park+0x80/0x80
<4> [1922.934887]  ret_from_fork+0x24/0x50
<4> [1922.934900] irq event stamp: 10637771
<4> [1922.934904] hardirqs last  enabled at (10637770): [<ffffffff819e30ac>] _raw_spin_unlock_irqrestore+0x4c/0x60
<4> [1922.934908] hardirqs last disabled at (10637771): [<ffffffff810d4baa>] rcu_work_rcufn+0xa/0x30
<4> [1922.934911] softirqs last  enabled at (10637714): [<ffffffff81c00385>] __do_softirq+0x385/0x47f
<4> [1922.934914] softirqs last disabled at (10637719): [<ffffffff810b7d9b>] run_ksoftirqd+0x2b/0x50
<4> [1922.934916] ---[ end trace 63f3542ef3b6ed51 ]---
<6> [1923.025832] [IGT] i915_selftest: exiting, ret=0

Comment 1 CI Bug Log 2019-10-28 16:48:42 UTC

The CI Bug Log issue associated to this bug has been updated.

### New filters associated

* GLK:  igt@i915_selftest@mock_requests - dmesg-warn - WARNING: CPU: 1 PID: 16 at kernel/workqueue.c:1415 __queue_work+0x547/0x660
  - https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_3618/shard-glk9/igt@i915_selftest@mock_requests.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_7194/shard-glk3/igt@i915_selftest@mock_requests.html

Comment 2 Chris Wilson 2019-10-28 20:47:28 UTC

Hmm, this is the race between draining the wq on shutdown and something queuing to the wq. Usually a missing sync in the test (i.e. shutting down before finishing all threads).

Comment 3 Lakshmi 2019-11-12 06:43:31 UTC

*** Bug 112247 has been marked as a duplicate of this bug. ***

Comment 4 CI Bug Log 2019-11-12 07:31:19 UTC

A CI Bug Log filter associated to this bug has been updated:

{- GLK:  igt@i915_selftest@mock_requests - dmesg-warn - WARNING: CPU: 1 PID: 16 at kernel/workqueue.c:1415 __queue_work+0x547/0x660 -}
{+ GLK:  igt@i915_selftest@mock_requests - dmesg-warn - WARNING: CPU: 1 PID: 16 at kernel/workqueue.c:1415 __queue_work+0x547/0x660 +}

New failures caught by the filter:

  * https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_7291/shard-glk1/igt@i915_selftest@mock_requests.html
  * https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_7306/shard-glk9/igt@i915_selftest@mock_requests.html
  * https://intel-gfx-ci.01.org/tree/drm-tip/IGT_5271/shard-glk7/igt@i915_selftest@mock_requests.html
  * https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_7309/shard-glk6/igt@i915_selftest@mock_requests.html

Comment 5 Chris Wilson 2019-11-20 17:25:43 UTC

Should be fixed by the intel_gt_wait_for_idle updates I believe.

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.