104750 – [CI] igt@kms_flip@2x-vblank-vs-modeset-suspend-interruptible - incomplete - INFO: task rtcwake:1838 blocked for more than 60 seconds

Bug 104750 - [CI] igt@kms_flip@2x-vblank-vs-modeset-suspend-interruptible - incomplete - INFO: task rtcwake:1838 blocked for more than 60 seconds

Summary: [CI] igt@kms_flip@2x-vblank-vs-modeset-suspend-interruptible - incomplete - I...

Status:	CLOSED WORKSFORME

Alias:	None

Product:	DRI
Classification:	Unclassified
Component:	DRM/Intel (show other bugs)
Version:	DRI git
Hardware:	Other All

Importance:	medium normal
Assignee:	Intel GFX Bugs mailing list
QA Contact:	Intel GFX Bugs mailing list

URL:
Whiteboard:	ReadyForDev
Keywords:

Depends on:
Blocks:

Reported:	2018-01-23 07:43 UTC by Marta Löfstedt
Modified:	2018-02-23 08:13 UTC (History)
CC List:	1 user (show)

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

Attachments

Description Marta Löfstedt 2018-01-23 07:43:21 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3666/shard-hsw5/igt@kms_flip@2x-vblank-vs-modeset-suspend-interruptible.html

from dmesg:
<7>[  180.996833] [IGT] kms_flip: starting subtest 2x-vblank-vs-modeset-suspend-interruptible

pstore:
<3>[  246.873327] INFO: task rtcwake:1838 blocked for more than 60 seconds.
<3>[  246.873353]       Tainted: G     U           4.15.0-rc9-CI-CI_DRM_3666+ #1
<3>[  246.873362] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6>[  246.873370] rtcwake         D    0  1838   1837 0x00000000
<4>[  246.873384] Call Trace:
<4>[  246.873408]  ? __schedule+0x3c3/0xaf0
<4>[  246.873427]  ? SyS_tee+0x3c0/0x3c0
<4>[  246.873438]  schedule+0x37/0x90
<4>[  246.873447]  wb_wait_for_completion+0x47/0x70
<4>[  246.873456]  ? wait_woken+0x90/0x90
<4>[  246.873467]  sync_inodes_sb+0x94/0x400
<4>[  246.873481]  ? lock_acquire+0xaf/0x200
<4>[  246.873502]  ? SyS_tee+0x3c0/0x3c0
<4>[  246.873514]  iterate_supers+0xa3/0xf0
<4>[  246.873527]  sys_sync+0x2b/0x90
<4>[  246.873538]  entry_SYSCALL_64_fastpath+0x22/0x8f
<4>[  246.873546] RIP: 0033:0x7ff06b15cd67
<4>[  246.873551] RSP: 002b:00007ffebd8c7898 EFLAGS: 00000246
<4>[  246.873578] 
<4>[  246.873578] Showing all locks held in the system:
<4>[  246.873593] 2 locks held by khungtaskd/67:
<4>[  246.873604]  #0:  (rcu_read_lock){....}, at: [<00000000b21ad0b5>] watchdog+0x9b/0x5e0
<4>[  246.873637]  #1:  (tasklist_lock){.+.+}, at: [<000000009aca6cd9>] debug_show_all_locks+0x37/0x190
<4>[  246.873667] 1 lock held by khugepaged/72:
<4>[  246.873672]  #0:  (&mm->mmap_sem){++++}, at: [<0000000088861362>] khugepaged+0x2ef/0x2510
<4>[  246.873710] 1 lock held by systemd-udevd/245:
<4>[  246.873719]  #0:  (&type->i_mutex_dir_key){++++}, at: [<00000000fe4a4ffe>] lookup_slow+0xaf/0x1b0
<4>[  246.873771] 1 lock held by in:imklog/630:
<4>[  246.873780]  #0:  (&f->f_pos_lock){+.+.}, at: [<00000000ef747b56>] __fdget_pos+0x3a/0x50
<4>[  246.873833] 1 lock held by NetworkManager/625:
<4>[  246.873841]  #0:  (&type->i_mutex_dir_key){++++}, at: [<00000000fe4a4ffe>] lookup_slow+0xaf/0x1b0
<4>[  246.873886] 1 lock held by java/1543:
<4>[  246.873891]  #0:  (&mm->mmap_sem){++++}, at: [<00000000e00f9d8b>] vm_mmap_pgoff+0x51/0xd0
<4>[  246.873918] 3 locks held by java/1709:
<4>[  246.873923]  #0:  (&mm->mmap_sem){++++}, at: [<000000008c36014c>] __do_page_fault+0x106/0x560
<4>[  246.873950]  #1:  (sb_pagefaults){.+.+}, at: [<000000003ad5b501>] ext4_page_mkwrite+0x46/0x4e0
<4>[  246.873977]  #2:  (jbd2_handle){++++}, at: [<0000000031b4ea2d>] start_this_handle+0xff/0x440
<4>[  246.874006] 1 lock held by dmesg/1084:
<4>[  246.874011]  #0:  (&user->lock){+.+.}, at: [<00000000cd59aa50>] devkmsg_read+0x35/0x2f0
<4>[  246.874043] 4 locks held by kworker/u16:40/1618:
<4>[  246.874048]  #0:  ((wq_completion)"writeback"){+.+.}, at: [<00000000b2e725b7>] process_one_work+0x191/0x640
<4>[  246.874077]  #1:  ((work_completion)(&(&wb->dwork)->work)){+.+.}, at: [<00000000b2e725b7>] process_one_work+0x191/0x640
<4>[  246.874104]  #2:  (&type->s_umount_key#33){++++}, at: [<00000000be83d46c>] trylock_super+0x11/0x50
<4>[  246.874136]  #3:  (&sbi->s_journal_flag_rwsem){.+.+}, at: [<00000000350eb150>] do_writepages+0x1b/0x70
<4>[  246.874168] 1 lock held by rtcwake/1838:
<4>[  246.874172]  #0:  (&type->s_umount_key#33){++++}, at: [<00000000ed612f39>] iterate_supers+0x8d/0xf0
<4>[  246.874292] 
<4>[  246.874297] =============================================
<4>[  246.874297] 
<4>[  246.874304] NMI backtrace for cpu 0
<4>[  246.874313] CPU: 0 PID: 67 Comm: khungtaskd Tainted: G     U           4.15.0-rc9-CI-CI_DRM_3666+ #1
<4>[  246.874325] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[  246.874332] Call Trace:
<4>[  246.874351]  dump_stack+0x5f/0x86
<4>[  246.874365]  nmi_cpu_backtrace+0xb4/0xc0
<4>[  246.874381]  ? lapic_can_unplug_cpu+0x90/0x90
<4>[  246.874394]  nmi_trigger_cpumask_backtrace+0xb8/0xf0
<4>[  246.874411]  watchdog+0x43e/0x5e0
<4>[  246.874429]  kthread+0xfb/0x130
<4>[  246.874442]  ? reset_hung_task_detector+0x10/0x10
<4>[  246.874451]  ? _kthread_create_on_node+0x30/0x30
<4>[  246.874466]  ret_from_fork+0x3a/0x50
<6>[  246.874497] Sending NMI from CPU 0 to CPUs 1-7:
<4>[  246.874668] NMI backtrace for cpu 3
<4>[  246.874675] CPU: 3 PID: 235 Comm: systemd-journal Tainted: G     U           4.15.0-rc9-CI-CI_DRM_3666+ #1
<4>[  246.874677] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[  246.874687] RIP: 0010:__seccomp_filter+0xd1/0x230
<4>[  246.874690] RSP: 0018:ffffc900003efeb0 EFLAGS: 00000292
<4>[  246.874695] RAX: 000000007fff0000 RBX: 0000000000000000 RCX: 000000007fff0000
<4>[  246.874697] NMI backtrace for cpu 6
<4>[  246.874700] RDX: 000000007fff0000 RSI: 0000000000000000 RDI: ffffc900003efd60
<4>[  246.874702] CPU: 6 PID: 630 Comm: in:imklog Tainted: G     U           4.15.0-rc9-CI-CI_DRM_3666+ #1
<4>[  246.874705] RBP: 0000000000000015 R08: 00005570cb37c325 R09: 0000000000000001
<4>[  246.874706] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[  246.874709] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000100
<4>[  246.874710] RIP: 0010:do_raw_spin_lock+0x33/0xb0
<4>[  246.874713] R13: 0000000000000000 R14: 00005570cc9907a0 R15: 0000000000000009
<4>[  246.874714] RSP: 0018:ffffc900003c7dd8 EFLAGS: 00000046
<4>[  246.874719] FS:  00007fbb4d06f8c0(0000) GS:ffff88041fac0000(0000) knlGS:0000000000000000
<4>[  246.874723] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[  246.874725] RAX: 0000000000000000 RBX: ffffffff82244940 RCX: cd73d09800000000
<4>[  246.874728] CR2: 00007fbb49f5c000 CR3: 000000040afe8005 CR4: 00000000001606e0
<4>[  246.874729] RDX: 0000000000000001 RSI: 0000000067470589 RDI: ffffffff82244940
<4>[  246.874731] Call Trace:
<4>[  246.874732] RBP: 0000000000000000 R08: ffff880407098918 R09: 00000000c96130d8
<4>[  246.874740]  syscall_trace_enter+0xb7/0x340
<4>[  246.874741] R10: ffffc900003c7d68 R11: ffff8804070988f0 R12: 00000000000012f6
<4>[  246.874744] R13: ffff88040dbd1a98 R14: 00007f245f893a4a R15: 0000000000000053
<4>[  246.874748]  do_syscall_64+0x134/0x1a0
<4>[  246.874750] FS:  00007f245f8b3700(0000) GS:ffff88041fb80000(0000) knlGS:0000000000000000
<4>[  246.874756]  entry_SYSCALL64_slow_path+0x25/0x25
<4>[  246.874757] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[  246.874761] RIP: 0033:0x7fbb4c345947
<4>[  246.874762] CR2: 00007ffebd91f0a8 CR3: 00000004077d8004 CR4: 00000000001606e0
<4>[  246.874765] RSP: 002b:00007ffcf4e50b78 EFLAGS: 00000246
<4>[  246.874767] Call Trace:
<4>[  246.874769]  ORIG_RAX: 0000000000000015
<4>[  246.874770]  do_syslog+0x352/0x4f0
<4>[  246.874773] RAX: ffffffffffffffda RBX: 00007ffcf4e53b90 RCX: 00007fbb4c345947
<4>[  246.874775]  ? wait_woken+0x90/0x90
<4>[  246.874778] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00005570cb386881
<4>[  246.874779]  kmsg_read+0x39/0x50
<4>[  246.874782] RBP: 00007ffcf4e50cc0 R08: 00005570cb37c325 R09: 0000000000000001
<4>[  246.874783]  proc_reg_read+0x38/0x60
<4>[  246.874786] R10: 00007fbb4c60eb58 R11: 0000000000000246 R12: 0000000000000000
<4>[  246.874787]  __vfs_read+0x1e/0x120
<4>[  246.874790] R13: 0000000000000000 R14: 00005570cc9907a0 R15: 0000000000000009
<4>[  246.874791]  vfs_read+0x9e/0x150
<4>[  246.874793]  SyS_read+0x40/0xa0
<4>[  246.874796]  entry_SYSCALL_64_fastpath+0x22/0x8f
<4>[  246.874797] Code: 
<4>[  246.874799] RIP: 0033:0x7f2461cff6ed
<4>[  246.874801] 00 
<4>[  246.874803] RSP: 002b:00007f245f892580 EFLAGS: 00000293
<4>[  246.874805] 00 
<4>[  246.874806] Code: 
<4>[  246.874808] e8 
<4>[  246.874810] 48 
<4>[  246.874812] 23 
<4>[  246.874813] 89 
<4>[  246.874815] ff 
<4>[  246.874817] fb 
<4>[  246.874819] ff 
<4>[  246.874820] 75 
<4>[  246.874822] ff 
<4>[  246.874823] 43 
<4>[  246.874825] b8 
<4>[  246.874827] 65 
<4>[  246.874829] ff 
<4>[  246.874830] 48 
<4>[  246.874832] ff 
<4>[  246.874833] 8b 
<4>[  246.874835] ff 
<4>[  246.874837] 04 
<4>[  246.874839] ff 
<4>[  246.874840] 25 
<4>[  246.874842] 48 
<4>[  246.874843] 80 
<4>[  246.874845] 81 
<4>[  246.874847] 4e 
<4>[  246.874849] c4 
<4>[  246.874850] 01 
<4>[  246.874852] 88 
<4>[  246.874853] 00 
<4>[  246.874855] 00 
<4>[  246.874856] 48 
<4>[  246.874858] 00 
<4>[  246.874860] 39 
<4>[  246.874862] 00 
<4>[  246.874863] 43 
<4>[  246.874865] 5b 
<4>[  246.874866] 10 
<4>[  246.874868] 5d 
<4>[  246.874870] 74 
<4>[  246.874872] c3 
<4>[  246.874873] 4e 
<4>[  246.874875] 3d 
<4>[  246.874876] 65 
<4>[  246.874878] 00 
<4>[  246.874880] 8b 
<4>[  246.874881] 00 
<4>[  246.874883] 05 
<4>[  246.874885] fc 
<4>[  246.874886] ad 
<4>[  246.874888] 7f 
<4>[  246.874889] 20 
<4>[  246.874891] 74 
<4>[  246.874893] f3 
<4>[  246.874895] 07 
<4>[  246.874896] 7e 
<4>[  246.874898] 3d 
<4>[  246.874899] 39 
<4>[  246.874901] 00 
<4>[  246.874903] 43 
<4>[  246.874904] 00 
<4>[  246.874906] 08 
<4>[  246.874908] ff 
<4>[  246.874909] 74 
<4>[  246.874911] 7f 
<4>[  246.874912] 5c 
<4>[  246.874914] 75 
<4>[  246.874916] 31 
<4>[  246.874917] 0c 
<4>[  246.874919] c0 
<4>[  246.874921] 48 
<4>[  246.874922] ba 
<4>[  246.874924] 81 
<4>[  246.874925] 01 
<4>[  246.874927] c4 
<4>[  246.874929] 00 
<4>[  246.874930] 88 
<4>[  246.874932] 00 
<4>[  246.874934] 00 
<4>[  246.874935] 00 
<4>[  246.874937] 00 
<4>[  246.874938] f0 
<4>[  246.874940] 00 
<4>[  246.874942] 0f 
<4>[  246.874944] <31> 
<4>[  246.874945] b1 
<4>[  246.874947] c0 
<4>[  246.874949] 13 
<4>[  246.874950] 5b 
<4>[  246.874952] <85> 
<4>[  246.874954] 5d 
<4>[  246.874955] c0 
<4>[  246.874957] c3 
<4>[  246.874958] 75 
<4>[  246.874960] 3d 
<4>[  246.874962] 67 
<4>[  246.874964] 00 
<4>[  246.874965] 65 
<4>[  246.874967] 00 
<4>[  246.874968] 8b 
<4>[  246.874970] 00 
<4>[  246.874971] 05 
<4>[  246.874973] 80 
<4>[  246.874975] 92 
<4>[  246.874977] 0f 
<4>[  246.874978] 20 
<4>[  246.874980] 84 
<4>[  246.874981] f3 
<4>[  246.874983] 95 
<4>[  246.874985] 7e 
<4>[  246.874986] 00 
<4>[  246.874988] 89 
<4>[  246.874990] 00 
<4>[  246.874991] 43 
<4>[  246.874993] 00 
<4>[  246.874994] 08 
<4>[  246.874996] 65 
<4>[  246.874998] 65 
<4>[  246.875000] 48 
<4>[  246.875001] 48 
<4>[  246.875003] 8b 
<4>[  246.875004] 8b 
<4>[  246.875006] 04 
<4>[  246.875008] 04 
<4>[  246.875010] 25 
<4>[  246.875011] 25 
<4>[  246.875013] 80 
<4>[  246.875021] NMI backtrace for cpu 2 skipped: idling at intel_idle+0x6f/0x120
<4>[  246.875022] 4e 
<4>[  246.875032] NMI backtrace for cpu 7 skipped: idling at intel_idle+0x6f/0x120
<4>[  246.875044] NMI backtrace for cpu 4 skipped: idling at intel_idle+0x6f/0x120
<4>[  246.875056] NMI backtrace for cpu 5 skipped: idling at intel_idle+0x6f/0x120
<4>[  246.875065] NMI backtrace for cpu 1 skipped: idling at intel_idle+0x6f/0x120
<0>[  246.875533] Kernel panic - not syncing: hung_task: blocked tasks
<4>[  246.875553] CPU: 0 PID: 67 Comm: khungtaskd Tainted: G     U           4.15.0-rc9-CI-CI_DRM_3666+ #1
<4>[  246.875565] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[  246.875577] Call Trace:
<4>[  246.875597]  dump_stack+0x5f/0x86
<4>[  246.875619]  panic+0xcf/0x20d
<4>[  246.875665]  watchdog+0x44a/0x5e0
<4>[  246.875697]  kthread+0xfb/0x130
<4>[  246.875714]  ? reset_hung_task_detector+0x10/0x10
<4>[  246.875729]  ? _kthread_create_on_node+0x30/0x30
<4>[  246.875754]  ret_from_fork+0x3a/0x50
<0>[  246.875854] Dumping ftrace buffer:
<0>[  246.875937]    (ftrace buffer empty)
<0>[  246.875946] Kernel Offset: disabled

Comment 1 Marta Löfstedt 2018-01-23 07:47:22 UTC

Note igt@gem_tiled_swapping@non-threaded was NOT run in this shard so this is NOT related to bug 104218.
Also, we have only had 2X displays for HSW for about a week in the lab...

Comment 2 Marta Löfstedt 2018-02-23 08:13:38 UTC

Last seen: CI_DRM_3666: 2018-01-22 / 20 runs ago

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.