Bug 105087 - [CI] igt@kms_fbcon_fbt@fbc-suspend - incomplete - INFO: task rs:main Q:Reg:632 blocked for more than 60 seconds.
Summary: [CI] igt@kms_fbcon_fbt@fbc-suspend - incomplete - INFO: task rs:main Q:Reg:63...
Status: CLOSED DUPLICATE of bug 104874
Alias: None
Product: DRI
Classification: Unclassified
Component: DRM/Intel (show other bugs)
Version: DRI git
Hardware: Other All
: medium normal
Assignee: Intel GFX Bugs mailing list
QA Contact: Intel GFX Bugs mailing list
URL:
Whiteboard: ReadyForDev
Keywords:
Depends on:
Blocks:
 
Reported: 2018-02-14 07:39 UTC by Marta Löfstedt
Modified: 2018-03-08 07:03 UTC (History)
1 user (show)

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


Attachments

Description Marta Löfstedt 2018-02-14 07:39:34 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3767/shard-hsw7/igt@kms_fbcon_fbt@fbc-suspend.html

<6>[   63.384468] r8169 0000:02:00.0 enp2s0: link up
<3>[  185.400926] INFO: task rs:main Q:Reg:632 blocked for more than 60 seconds.
<3>[  185.401048]       Tainted: G     U  W        4.16.0-rc1-CI-CI_DRM_3767+ #1
<3>[  185.401101] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6>[  185.401162] rs:main Q:Reg   D    0   632      1 0x00000000
<4>[  185.401174] Call Trace:
<4>[  185.401194]  ? __schedule+0x3c3/0xaf0
<4>[  185.401207]  ? bit_wait+0x50/0x50
<4>[  185.401216]  schedule+0x37/0x90
<4>[  185.401227]  io_schedule+0xd/0x30
<4>[  185.401235]  bit_wait_io+0x8/0x50
<4>[  185.401242]  __wait_on_bit+0x4e/0x80
<4>[  185.401254]  out_of_line_wait_on_bit+0x78/0x80
<4>[  185.401265]  ? bit_waitqueue+0x30/0x30
<4>[  185.401278]  do_get_write_access+0x1fe/0x670
<4>[  185.401296]  ? ext4_dirty_inode+0x3e/0x60
<4>[  185.401305]  jbd2_journal_get_write_access+0x27/0x50
<4>[  185.401317]  __ext4_journal_get_write_access+0x5e/0xb0
<4>[  185.401329]  ext4_reserve_inode_write+0x88/0xb0
<4>[  185.401340]  ext4_mark_inode_dirty+0x63/0x270
<4>[  185.401350]  ? rcu_read_lock_sched_held+0x6f/0x80
<4>[  185.401365]  ext4_dirty_inode+0x3e/0x60
<4>[  185.401375]  __mark_inode_dirty+0x67/0x690
<4>[  185.401391]  generic_update_time+0x62/0xc0
<4>[  185.401400]  file_update_time+0x9f/0xf0
<4>[  185.401418]  __generic_file_write_iter+0x94/0x1b0
<4>[  185.401431]  ext4_file_write_iter+0x10f/0x3a0
<4>[  185.401442]  ? lock_acquire+0xaf/0x200
<4>[  185.401457]  __vfs_write+0xc5/0x130
<4>[  185.401476]  vfs_write+0xbd/0x1b0
<4>[  185.401486]  SyS_write+0x40/0xa0
<4>[  185.401499]  do_syscall_64+0x68/0x1a0
<4>[  185.401512]  entry_SYSCALL_64_after_hwframe+0x26/0x9b
<4>[  185.401518] RIP: 0033:0x7ff381efd68d
<4>[  185.401524] RSP: 002b:00007ff37f2af5c0 EFLAGS: 00000293 ORIG_RAX: 0000000000000001
<4>[  185.401533] RAX: ffffffffffffffda RBX: 00007ff3740031e0 RCX: 00007ff381efd68d
<4>[  185.401538] RDX: 00000000000000c5 RSI: 00007ff3740031e0 RDI: 0000000000000005
<4>[  185.401543] RBP: 0000000000000000 R08: 000055b2b17cce30 R09: 0000000000000003
<4>[  185.401548] R10: 000055b2b17cbdb0 R11: 0000000000000293 R12: 00007ff374002f20
<4>[  185.401552] R13: 00007ff37f2af5e0 R14: 000055b2b17cbce0 R15: 00000000000000c5
<4>[  185.401575] 
<4>[  185.401575] Showing all locks held in the system:
<4>[  185.401588] 2 locks held by khungtaskd/61:
<4>[  185.401599]  #0:  (rcu_read_lock){....}, at: [<000000001016f9fc>] watchdog+0x9b/0x5e0
<4>[  185.401629]  #1:  (tasklist_lock){.+.+}, at: [<00000000af845daa>] debug_show_all_locks+0x37/0x1a0
<4>[  185.401666] 1 lock held by in:imklog/631:
<4>[  185.401670]  #0:  (&f->f_pos_lock){+.+.}, at: [<0000000054bd892e>] __fdget_pos+0x3a/0x50
<4>[  185.401694] 4 locks held by rs:main Q:Reg/632:
<4>[  185.401699]  #0:  (&f->f_pos_lock){+.+.}, at: [<0000000054bd892e>] __fdget_pos+0x3a/0x50
<4>[  185.401720]  #1:  (sb_writers#3){.+.+}, at: [<00000000d089c9f9>] vfs_write+0x158/0x1b0
<4>[  185.401748]  #2:  (&sb->s_type->i_mutex_key#14){+.+.}, at: [<000000001fc17a8d>] ext4_file_write_iter+0x64/0x3a0
<4>[  185.401775]  #3:  (jbd2_handle){++++}, at: [<00000000c53823c8>] start_this_handle+0xff/0x440
<4>[  185.401916] 1 lock held by java/1018:
<4>[  185.401927]  #0:  (&mm->mmap_sem){++++}, at: [<00000000e8a6aa2f>] do_mprotect_pkey+0x9f/0x2f0
<4>[  185.401988] 3 locks held by java/1022:
<4>[  185.401998]  #0:  (&mm->mmap_sem){++++}, at: [<00000000c2466a9d>] __do_page_fault+0x106/0x560
<4>[  185.402052]  #1:  (sb_pagefaults){.+.+}, at: [<0000000093d9c80d>] ext4_page_mkwrite+0x46/0x4e0
<4>[  185.402107]  #2:  (jbd2_handle){++++}, at: [<00000000c53823c8>] start_this_handle+0xff/0x440
<4>[  185.402166] 3 locks held by java/1748:
<4>[  185.402176]  #0:  (&mm->mmap_sem){++++}, at: [<00000000c2466a9d>] __do_page_fault+0x106/0x560
<4>[  185.402218]  #1:  (sb_pagefaults){.+.+}, at: [<0000000093d9c80d>] ext4_page_mkwrite+0x46/0x4e0
<4>[  185.402256]  #2:  (jbd2_handle){++++}, at: [<00000000c53823c8>] start_this_handle+0xff/0x440
<4>[  185.402305] 3 locks held by dmesg/1139:
<4>[  185.402313]  #0:  (sb_writers#3){.+.+}, at: [<00000000d089c9f9>] vfs_write+0x158/0x1b0
<4>[  185.402364]  #1:  (&sb->s_type->i_mutex_key#14){+.+.}, at: [<000000001fc17a8d>] ext4_file_write_iter+0x64/0x3a0
<4>[  185.402414]  #2:  (jbd2_handle){++++}, at: [<00000000c53823c8>] start_this_handle+0xff/0x440
<4>[  185.402469] 3 locks held by kworker/u16:7/1471:
<4>[  185.402478]  #0:  ((wq_completion)"writeback"){+.+.}, at: [<00000000675f3421>] process_one_work+0x191/0x620
<4>[  185.402531]  #1:  ((work_completion)(&(&wb->dwork)->work)){+.+.}, at: [<00000000675f3421>] process_one_work+0x191/0x620
<4>[  185.402577]  #2:  (&sbi->s_journal_flag_rwsem){.+.+}, at: [<0000000065472f34>] do_writepages+0x1b/0x70
<4>[  185.402645] 3 locks held by dhclient/1814:
<4>[  185.402654]  #0:  (sb_writers#3){.+.+}, at: [<00000000d089c9f9>] vfs_write+0x158/0x1b0
<4>[  185.402707]  #1:  (&sb->s_type->i_mutex_key#14){+.+.}, at: [<000000001fc17a8d>] ext4_file_write_iter+0x64/0x3a0
<4>[  185.402757]  #2:  (jbd2_handle){++++}, at: [<00000000c53823c8>] start_this_handle+0xff/0x440
<4>[  185.402857] 1 lock held by rtcwake/1829:
<4>[  185.402870]  #0:  (&type->s_umount_key#33){++++}, at: [<000000001a3b39e0>] iterate_supers+0x8d/0xf0
<4>[  185.402930] 
<4>[  185.402940] =============================================
<4>[  185.402940] 
<4>[  185.402953] NMI backtrace for cpu 1
<4>[  185.402974] CPU: 1 PID: 61 Comm: khungtaskd Tainted: G     U  W        4.16.0-rc1-CI-CI_DRM_3767+ #1
<4>[  185.402984] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[  185.402993] Call Trace:
<4>[  185.403012]  dump_stack+0x5f/0x86
<4>[  185.403031]  nmi_cpu_backtrace+0xb4/0xc0
<4>[  185.403053]  ? lapic_can_unplug_cpu+0x90/0x90
<4>[  185.403075]  nmi_trigger_cpumask_backtrace+0xb8/0xf0
<4>[  185.403098]  watchdog+0x43e/0x5e0
<4>[  185.403126]  kthread+0xfb/0x130
<4>[  185.403141]  ? reset_hung_task_detector+0x10/0x10
<4>[  185.403155]  ? _kthread_create_on_node+0x30/0x30
<4>[  185.403181]  ret_from_fork+0x3a/0x50
<6>[  185.403236] Sending NMI from CPU 1 to CPUs 0,2-7:
<4>[  185.403255] NMI backtrace for cpu 5
<4>[  185.403261] CPU: 5 PID: 631 Comm: in:imklog Tainted: G     U  W        4.16.0-rc1-CI-CI_DRM_3767+ #1
<4>[  185.403263] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[  185.403273] RIP: 0010:alloc_debug_processing+0x166/0x1a0
<4>[  185.403276] RSP: 0018:ffffc90000607c40 EFLAGS: 00000006
<4>[  185.403280] RAX: 0000000000000013 RBX: ffff88040e00eb80 RCX: ffff88039662ff80
<4>[  185.403283] RDX: 0000000000000000 RSI: 0000000000000550 RDI: ffff880396630000
<4>[  185.403286] RBP: ffffea000e598a00 R08: 000000000000005a R09: 0101010101010101
<4>[  185.403289] R10: ffffc90000607c70 R11: ffffea000e598a20 R12: ffff88039662e4f8
<4>[  185.403291] R13: ffffffff810eb6b4 R14: ffff88041fb64e70 R15: ffff88040e00eb80
<4>[  185.403296] FS:  00007ff37fab1700(0000) GS:ffff88041fb40000(0000) knlGS:0000000000000000
<4>[  185.403299] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[  185.403302] CR2: 000055ad371bc038 CR3: 00000003ffe36002 CR4: 00000000001606e0
<4>[  185.403304] Call Trace:
<4>[  185.403313]  ___slab_alloc.constprop.30+0x3a8/0x3d0
<4>[  185.403319]  ? do_syslog+0x2a4/0x4f0
<4>[  185.403324]  ? __slab_free+0x3ae/0x580
<4>[  185.403331]  ? __lock_acquire+0x4ab/0x1b60
<4>[  185.403337]  ? lock_acquire+0xaf/0x200
<4>[  185.403342]  ? do_syslog+0x2a4/0x4f0
<4>[  185.403349]  ? __slab_alloc.isra.24.constprop.29+0x3d/0x70
<4>[  185.403355]  __slab_alloc.isra.24.constprop.29+0x3d/0x70
<4>[  185.403360]  ? do_syslog+0x2a4/0x4f0
<4>[  185.403365]  kmem_cache_alloc_trace+0x215/0x2b0
<4>[  185.403371]  do_syslog+0x2a4/0x4f0
<4>[  185.403382]  kmsg_read+0x39/0x50
<4>[  185.403390]  proc_reg_read+0x34/0x60
<4>[  185.403396]  __vfs_read+0x1e/0x120
<4>[  185.403405]  vfs_read+0x9e/0x150
<4>[  185.403411]  SyS_read+0x40/0xa0
<4>[  185.403418]  do_syscall_64+0x68/0x1a0
<4>[  185.403426]  entry_SYSCALL_64_after_hwframe+0x26/0x9b
<4>[  185.403430] RIP: 0033:0x7ff381efd6ed
<4>[  185.403432] RSP: 002b:00007ff37fa90580 EFLAGS: 00000293 ORIG_RAX: 0000000000000000
<4>[  185.403436] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007ff381efd6ed
<4>[  185.403439] RDX: 0000000000001fa0 RSI: 00007ff37fa90da0 RDI: 0000000000000004
<4>[  185.403441] RBP: 000055b2b17c9430 R08: 0000000000000000 R09: 0000000000000000
<4>[  185.403444] R10: 2ce33e6c02ce33e7 R11: 0000000000000293 R12: 00007ff37fa90da0
<4>[  185.403447] R13: 0000000000001fa0 R14: 0000000000001f9f R15: 00007ff37fa90dcb
<4>[  185.403454] Code: ff 7f 66 89 55 18 e9 27 ff ff ff 4c 89 e9 31 d2 4c 89 e6 48 89 df e8 4a c8 ff ff 8b 43 08 e9 cb fe ff ff 48 29 d0 48 99 48 f7 fe <48> 85 d2 0f 85 74 ff ff ff b9 bb 00 00 00 4c 89 e2 48 89 ee 48 
<4>[  185.403535] NMI backtrace for cpu 4
<4>[  185.403542] CPU: 4 PID: 236 Comm: systemd-journal Tainted: G     U  W        4.16.0-rc1-CI-CI_DRM_3767+ #1
<4>[  185.403544] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[  185.403555] RIP: 0010:debug_lockdep_rcu_enabled.part.0+0x17/0x20
<4>[  185.403562] RSP: 0018:ffffc900003afbe8 EFLAGS: 00000246
<4>[  185.403569] RAX: 0000000000000001 RBX: ffff880409cbee88 RCX: 000000000000000b
<4>[  185.403575] RDX: 0000000000000002 RSI: ffffc900003afdd0 RDI: ffff88040c4f5fb8
<4>[  185.403581] RBP: ffff88040f45a460 R08: 2f2f2f2f2f2f2f2f R09: 0000000385a46667
<4>[  185.403587] R10: 0000000000000000 R11: 8080808080808080 R12: ffff88040c4f5fb8
<4>[  185.403593] R13: ffff8803f9c7475d R14: ffffc900003afdd0 R15: 0000000385a46667
<4>[  185.403600] FS:  00007ffab85568c0(0000) GS:ffff88041fb00000(0000) knlGS:0000000000000000
<4>[  185.403606] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[  185.403612] CR2: 000055e047902d80 CR3: 000000040a744005 CR4: 00000000001606e0
<4>[  185.403617] Call Trace:
<4>[  185.403628]  __d_lookup_rcu+0x43/0x1e0
<4>[  185.403638]  lookup_fast+0x3d/0x440
<4>[  185.403647]  walk_component+0x33/0x2c0
<4>[  185.403656]  link_path_walk+0x185/0x570
<4>[  185.403664]  ? path_init+0x4ae/0x6c0
<4>[  185.403673]  path_openat+0x74/0x9d0
<4>[  185.403682]  do_filp_open+0x85/0xf0
<4>[  185.403692]  ? __alloc_fd+0xe9/0x200
<4>[  185.403704]  ? do_sys_open+0x12b/0x1f0
<4>[  185.403710]  do_sys_open+0x12b/0x1f0
<4>[  185.403718]  do_syscall_64+0x68/0x1a0
<4>[  185.403729]  entry_SYSCALL_64_after_hwframe+0x26/0x9b
<4>[  185.403735] RIP: 0033:0x7ffab7b0bd70
<4>[  185.403741] RSP: 002b:00007ffd8f07bc38 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
<4>[  185.403748] RAX: ffffffffffffffda RBX: 00007ffd8f07bf40 RCX: 00007ffab7b0bd70
<4>[  185.403754] RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 00005634002283a0
<4>[  185.403760] RBP: 000000000000000d R08: 000000000000ffc0 R09: 00000000ffffffff
<4>[  185.403766] R10: 00007ffab7af5b58 R11: 0000000000000246 R12: 00000000ffffffff
<4>[  185.403771] R13: 0000563400213040 R14: 00007ffd8f07bf00 R15: 0000563400221cc0
<4>[  185.403780] Code: 82 8d 70 01 89 35 62 3d 5c 02 e9 0a 4a ff ff 0f 1f 44 00 00 65 48 8b 04 25 80 4e 01 00 8b 80 ac 08 00 00 85 c0 0f 94 c0 0f b6 c0 <c3> 0f 1f 84 00 00 00 00 00 8b 15 ee 79 24 01 85 d2 74 0c 8b 05 
<4>[  185.403873] NMI backtrace for cpu 0 skipped: idling at intel_idle+0x6f/0x120
<4>[  185.403887] NMI backtrace for cpu 7 skipped: idling at intel_idle+0x6f/0x120
<4>[  185.403899] NMI backtrace for cpu 3 skipped: idling at intel_idle+0x6f/0x120
<4>[  185.403914] NMI backtrace for cpu 2 skipped: idling at intel_idle+0x6f/0x120
<4>[  185.403927] NMI backtrace for cpu 6 skipped: idling at intel_idle+0x6f/0x120
<0>[  185.404275] Kernel panic - not syncing: hung_task: blocked tasks
<4>[  185.404381] CPU: 1 PID: 61 Comm: khungtaskd Tainted: G     U  W        4.16.0-rc1-CI-CI_DRM_3767+ #1
<4>[  185.404517] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[  185.404580] Call Trace:
<4>[  185.404614]  dump_stack+0x5f/0x86
<4>[  185.404653]  panic+0xcf/0x20d
<4>[  185.404701]  watchdog+0x44a/0x5e0
<4>[  185.404744]  kthread+0xfb/0x130
<4>[  185.404779]  ? reset_hung_task_detector+0x10/0x10
<4>[  185.404859]  ? _kthread_create_on_node+0x30/0x30
<4>[  185.404952]  ret_from_fork+0x3a/0x50
<0>[  185.405116] Dumping ftrace buffer:
<0>[  185.405300]    (ftrace buffer empty)
<0>[  185.405338] Kernel Offset: disabled
Comment 1 Marta Löfstedt 2018-03-08 07:02:14 UTC

*** This bug has been marked as a duplicate of bug 104874 ***


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.