Re: Google Chrome cause locks held in system (kernel 4.15 rc2)

["Darrick J. Wong" <darrick.wong@oracle.com>]

On Fri, Dec 08, 2017 at 08:50:38AM +0500, mikhail wrote:
> Hi,
> 
> can anybody said what here happens?
> And which info needed for fixing it?
> Thanks.
> 
> [16712.376081] INFO: task tracker-store:27121 blocked for more than 120
> seconds.
> [16712.376088]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [16712.376092] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [16712.376095] tracker-store   D13400 27121   1843 0x00000000
> [16712.376102] Call Trace:
> [16712.376114]  ? __schedule+0x2e3/0xb90
> [16712.376123]  ? wait_for_completion+0x146/0x1e0
> [16712.376128]  schedule+0x2f/0x90
> [16712.376132]  schedule_timeout+0x236/0x540
> [16712.376143]  ? mark_held_locks+0x4e/0x80
> [16712.376147]  ? _raw_spin_unlock_irq+0x29/0x40
> [16712.376153]  ? wait_for_completion+0x146/0x1e0
> [16712.376158]  wait_for_completion+0x16e/0x1e0
> [16712.376162]  ? wake_up_q+0x70/0x70
> [16712.376204]  ? xfs_buf_read_map+0x134/0x2f0 [xfs]
> [16712.376234]  xfs_buf_submit_wait+0xaf/0x520 [xfs]

Stuck waiting for a directory block to read.  Slow disk?  Bad media?

--D

> [16712.376263]  xfs_buf_read_map+0x134/0x2f0 [xfs]
> [16712.376293]  ? xfs_trans_read_buf_map+0xc3/0x580 [xfs]
> [16712.376325]  xfs_trans_read_buf_map+0xc3/0x580 [xfs]
> [16712.376353]  xfs_da_read_buf+0xd3/0x120 [xfs]
> [16712.376387]  xfs_dir3_block_read+0x35/0x70 [xfs]
> [16712.376413]  xfs_dir2_block_lookup_int+0x4d/0x220 [xfs]
> [16712.376444]  xfs_dir2_block_replace+0x4e/0x1d0 [xfs]
> [16712.376467]  ? xfs_dir2_isblock+0x2f/0x90 [xfs]
> [16712.376492]  xfs_dir_replace+0x10a/0x180 [xfs]
> [16712.376526]  xfs_rename+0x586/0xbd0 [xfs]
> [16712.376573]  xfs_vn_rename+0xd5/0x140 [xfs]
> [16712.376586]  vfs_rename+0x494/0xa00
> [16712.376601]  SyS_rename+0x338/0x390
> [16712.376618]  entry_SYSCALL_64_fastpath+0x1f/0x96
> [16712.376622] RIP: 0033:0x7f02d4a2c167
> [16712.376624] RSP: 002b:00007ffd0998cb98 EFLAGS: 00000207 ORIG_RAX:
> 0000000000000052
> [16712.376629] RAX: ffffffffffffffda RBX: 0000000000000001 RCX:
> 00007f02d4a2c167
> [16712.376631] RDX: 0000000000000002 RSI: 0000560149d23fd0 RDI:
> 0000560149e737b0
> [16712.376633] RBP: 000000000000000f R08: 0000560149e73710 R09:
> 000000000000002c
> [16712.376635] R10: 0000000000058a8e R11: 0000000000000207 R12:
> 0000000000000000
> [16712.376638] R13: 0000560149e6c360 R14: 0000560149d23fd0 R15:
> 0000000000000000
> [16712.376828] 
>                Showing all locks held in the system:
> [16712.376876] 1 lock held by khungtaskd/67:
> [16712.376886]  #0:  (tasklist_lock){.+.+}, at: [<00000000a615f1dc>]
> debug_show_all_locks+0x37/0x190
> [16712.377113] 3 locks held by kworker/u16:2/18769:
> [16712.377115]  #0:  ((wq_completion)"writeback"){+.+.}, at:
> [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [16712.377128]  #1:  ((work_completion)(&(&wb->dwork)->work)){+.+.},
> at: [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [16712.377138]  #2:  (&type->s_umount_key#63){++++}, at:
> [<00000000ecbba71d>] trylock_super+0x16/0x50
> [16712.377176] 8 locks held by tracker-store/27121:
> [16712.377178]  #0:  (sb_writers#17){.+.+}, at: [<0000000063218e58>]
> mnt_want_write+0x20/0x50
> [16712.377191]  #1:  (&type->i_mutex_dir_key#7/1){+.+.}, at:
> [<0000000026b21526>] lock_rename+0xcf/0xf0
> [16712.377208]  #2:  (&inode->i_rwsem){++++}, at: [<00000000b63ba570>]
> lock_two_nondirectories+0x6d/0x80
> [16712.377219]  #3:  (&inode->i_rwsem/4){+.+.}, at:
> [<00000000d44f800a>] vfs_rename+0x337/0xa00
> [16712.377232]  #4:  (sb_internal){.+.+}, at: [<00000000b5b0ff39>]
> xfs_trans_alloc+0xe2/0x120 [xfs]
> [16712.377269]  #5:  (&xfs_dir_ilock_class){++++}, at:
> [<000000007c7eac55>] xfs_rename+0x45e/0xbd0 [xfs]
> [16712.377306]  #6:  (&xfs_nondir_ilock_class/2){+.+.}, at:
> [<000000007c7eac55>] xfs_rename+0x45e/0xbd0 [xfs]
> [16712.377343]  #7:  (&xfs_nondir_ilock_class/3){+.+.}, at:
> [<000000007c7eac55>] xfs_rename+0x45e/0xbd0 [xfs]
> [16712.377380] 3 locks held by TaskSchedulerFo/27216:
> [16712.377382]  #0:  (sb_writers#17){.+.+}, at: [<0000000054534ce6>]
> do_sys_ftruncate.constprop.17+0xda/0x110
> [16712.377396]  #1:  (&sb->s_type->i_mutex_key#20){++++}, at:
> [<0000000086cbd317>] do_truncate+0x66/0xc0
> [16712.377408]  #2:  (&(&ip->i_mmaplock)->mr_lock){++++}, at:
> [<00000000adf132fd>] xfs_ilock+0x14b/0x200 [xfs]
> [16712.377443] 3 locks held by TaskSchedulerFo/27217:
> [16712.377445]  #0:  (sb_writers#17){.+.+}, at: [<0000000054534ce6>]
> do_sys_ftruncate.constprop.17+0xda/0x110
> [16712.377457]  #1:  (&sb->s_type->i_mutex_key#20){++++}, at:
> [<0000000086cbd317>] do_truncate+0x66/0xc0
> [16712.377471]  #2:  (&(&ip->i_mmaplock)->mr_lock){++++}, at:
> [<00000000adf132fd>] xfs_ilock+0x14b/0x200 [xfs]
> [16712.377504] 1 lock held by TaskSchedulerFo/27219:
> [16712.377506]  #0:  (sb_writers#17){.+.+}, at: [<0000000063218e58>]
> mnt_want_write+0x20/0x50
> [16712.377521] 3 locks held by TaskSchedulerFo/27287:
> [16712.377523]  #0:  (sb_writers#17){.+.+}, at: [<0000000054534ce6>]
> do_sys_ftruncate.constprop.17+0xda/0x110
> [16712.377535]  #1:  (&sb->s_type->i_mutex_key#20){++++}, at:
> [<0000000086cbd317>] do_truncate+0x66/0xc0
> [16712.377547]  #2:  (&(&ip->i_mmaplock)->mr_lock){++++}, at:
> [<00000000adf132fd>] xfs_ilock+0x14b/0x200 [xfs]
> [16712.377581] 3 locks held by TaskSchedulerFo/27289:
> [16712.377583]  #0:  (&f->f_pos_lock){+.+.}, at: [<00000000cb121025>]
> __fdget_pos+0x48/0x60
> [16712.377594]  #1:  (&type->i_mutex_dir_key#7){++++}, at:
> [<00000000a872ed9a>] iterate_dir+0x56/0x180
> [16712.377607]  #2:  (&xfs_dir_ilock_class){++++}, at:
> [<000000002829e721>] xfs_ilock_data_map_shared+0x2c/0x30 [xfs]
> [16712.377641] 3 locks held by TaskSchedulerFo/27292:
> [16712.377642]  #0:  (sb_writers#17){.+.+}, at: [<0000000054534ce6>]
> do_sys_ftruncate.constprop.17+0xda/0x110
> [16712.377655]  #1:  (&inode->i_rwsem){++++}, at: [<0000000086cbd317>]
> do_truncate+0x66/0xc0
> [16712.377667]  #2:  (&(&ip->i_mmaplock)->mr_lock){++++}, at:
> [<00000000adf132fd>] xfs_ilock+0x14b/0x200 [xfs]
> 
> [16712.377873] =============================================
> 
> [18432.706561] INFO: task htop:2690 blocked for more than 120 seconds.
> [18432.706575]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [18432.706581] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [18432.706588] htop            D12280  2690   2565 0x00000000
> [18432.706602] Call Trace:
> [18432.706622]  ? __schedule+0x2e3/0xb90
> [18432.706637]  ? rwsem_down_read_failed+0x147/0x190
> [18432.706648]  schedule+0x2f/0x90
> [18432.706654]  rwsem_down_read_failed+0x118/0x190
> [18432.706662]  ? __lock_acquire+0x2c3/0x1270
> [18432.706688]  ? call_rwsem_down_read_failed+0x14/0x30
> [18432.706695]  call_rwsem_down_read_failed+0x14/0x30
> [18432.706710]  down_read+0x97/0xa0
> [18432.706719]  proc_pid_cmdline_read+0xd2/0x4a0
> [18432.706731]  ? debug_check_no_obj_freed+0x160/0x248
> [18432.706753]  ? __vfs_read+0x33/0x170
> [18432.706759]  __vfs_read+0x33/0x170
> [18432.706781]  vfs_read+0x9e/0x150
> [18432.706792]  SyS_read+0x55/0xc0
> [18432.706807]  entry_SYSCALL_64_fastpath+0x1f/0x96
> [18432.706814] RIP: 0033:0x7fc2d8f4ae01
> [18432.706819] RSP: 002b:00007fffedb1f998 EFLAGS: 00000246 ORIG_RAX:
> 0000000000000000
> [18432.706827] RAX: ffffffffffffffda RBX: 000056139647bc10 RCX:
> 00007fc2d8f4ae01
> [18432.706831] RDX: 0000000000001000 RSI: 00007fffedb1fa60 RDI:
> 0000000000000007
> [18432.706835] RBP: 000056139696f483 R08: 000056139696f483 R09:
> 0000000000000005
> [18432.706839] R10: 0000000000000000 R11: 0000000000000246 R12:
> 0000000000000007
> [18432.706844] R13: 000056139696f3f0 R14: 00005613961bb8a0 R15:
> 00005613961bc5e0
> [18432.707027] INFO: task Chrome_IOThread:27225 blocked for more than
> 120 seconds.
> [18432.707034]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [18432.707039] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [18432.707045] Chrome_IOThread D11304 27225   3654 0x00000000
> [18432.707057] Call Trace:
> [18432.707070]  ? __schedule+0x2e3/0xb90
> [18432.707086]  ? __lock_page+0xa9/0x180
> [18432.707095]  schedule+0x2f/0x90
> [18432.707102]  io_schedule+0x12/0x40
> [18432.707109]  __lock_page+0xe9/0x180
> [18432.707121]  ? page_cache_tree_insert+0x130/0x130
> [18432.707138]  deferred_split_scan+0x2b6/0x300
> [18432.707160]  shrink_slab.part.47+0x1f8/0x590
> [18432.707179]  ? percpu_ref_put_many+0x84/0x100
> [18432.707197]  shrink_node+0x2f4/0x300
> [18432.707219]  do_try_to_free_pages+0xca/0x350
> [18432.707236]  try_to_free_pages+0x140/0x350
> [18432.707259]  __alloc_pages_slowpath+0x43c/0x1080
> [18432.707298]  __alloc_pages_nodemask+0x3ac/0x430
> [18432.707316]  alloc_pages_vma+0x7c/0x200
> [18432.707331]  __handle_mm_fault+0x8a1/0x1230
> [18432.707359]  handle_mm_fault+0x14c/0x310
> [18432.707373]  __do_page_fault+0x28c/0x530
> [18432.707450]  do_page_fault+0x32/0x270
> [18432.707470]  page_fault+0x22/0x30
> [18432.707478] RIP: 0033:0x7f9f336ac4ef
> [18432.707482] RSP: 002b:00007f9f1533c968 EFLAGS: 00010206
> [18432.707491] RAX: 00003d60824b4000 RBX: 00000000000885c8 RCX:
> 0000000000001040
> [18432.707495] RDX: 0000000000001040 RSI: 00003d602692c400 RDI:
> 00003d60824b4000
> [18432.707499] RBP: 00007f9f1533c9a0 R08: 0000000000000089 R09:
> 00003d602692d440
> [18432.707503] R10: 00007f9f1533caf0 R11: 0000000000000000 R12:
> 00003d602c90f3c0
> [18432.707507] R13: 0000000000000010 R14: 00000000000885b8 R15:
> 00003d60824b4000
> [18432.707539] INFO: task TaskSchedulerFo:9369 blocked for more than
> 120 seconds.
> [18432.707546]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [18432.707551] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [18432.707557] TaskSchedulerFo D11224  9369   3654 0x00000000
> [18432.707568] Call Trace:
> [18432.707581]  ? __schedule+0x2e3/0xb90
> [18432.707596]  ? rwsem_down_read_failed+0x147/0x190
> [18432.707605]  schedule+0x2f/0x90
> [18432.707611]  rwsem_down_read_failed+0x118/0x190
> [18432.707618]  ? __lock_acquire+0x2c3/0x1270
> [18432.707643]  ? call_rwsem_down_read_failed+0x14/0x30
> [18432.707650]  call_rwsem_down_read_failed+0x14/0x30
> [18432.707665]  down_read+0x97/0xa0
> [18432.707673]  SyS_madvise+0x859/0x920
> [18432.707682]  ? SyS_rename+0xfc/0x390
> [18432.707695]  ? trace_hardirqs_on_caller+0xed/0x180
> [18432.707704]  ? trace_hardirqs_on_thunk+0x1a/0x1c
> [18432.707720]  ? entry_SYSCALL_64_fastpath+0x1f/0x96
> [18432.707726]  entry_SYSCALL_64_fastpath+0x1f/0x96
> [18432.707731] RIP: 0033:0x7f9f3363c4a7
> [18432.707735] RSP: 002b:00007f9ebe2805c8 EFLAGS: 00000206 ORIG_RAX:
> 000000000000001c
> [18432.707743] RAX: ffffffffffffffda RBX: 00007f9ebe2806d0 RCX:
> 00007f9f3363c4a7
> [18432.707747] RDX: 0000000000000004 RSI: 0000000000041000 RDI:
> 00003d6073c5d000
> [18432.707751] RBP: 00007f9ebe280600 R08: 0000000000000000 R09:
> 000000000000018d
> [18432.707755] R10: 0000000000000000 R11: 0000000000000206 R12:
> 0000000000000000
> [18432.707759] R13: 0000000000021796 R14: 00000000be280601 R15:
> 00007f9ebe2806d0
> [18432.707998] INFO: task kworker/3:1:10525 blocked for more than 120
> seconds.
> [18432.708004]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [18432.708009] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [18432.708015] kworker/3:1     D13784 10525      2 0x80000000
> [18432.708055] Workqueue: events async_pf_execute [kvm]
> [18432.708063] Call Trace:
> [18432.708075]  ? __schedule+0x2e3/0xb90
> [18432.708094]  schedule+0x2f/0x90
> [18432.708100]  io_schedule+0x12/0x40
> [18432.708108]  __lock_page_or_retry+0x2e4/0x350
> [18432.708121]  ? page_cache_tree_insert+0x130/0x130
> [18432.708137]  do_swap_page+0x721/0x9f0
> [18432.708149]  ? __lock_acquire+0x2c3/0x1270
> [18432.708163]  __handle_mm_fault+0xa5c/0x1230
> [18432.708209]  handle_mm_fault+0x14c/0x310
> [18432.708222]  __get_user_pages+0x1b0/0x6e0
> [18432.708244]  get_user_pages_remote+0x13a/0x200
> [18432.708281]  async_pf_execute+0x96/0x280 [kvm]
> [18432.708303]  process_one_work+0x25e/0x6c0
> [18432.708320]  worker_thread+0x3a/0x390
> [18432.708323]  ? process_one_work+0x6c0/0x6c0
> [18432.708325]  kthread+0x15d/0x180
> [18432.708338]  ? kthread_create_worker_on_cpu+0x70/0x70
> [18432.708341]  ret_from_fork+0x24/0x30
> [18432.708353] INFO: task kworker/3:2:13474 blocked for more than 120
> seconds.
> [18432.708355]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [18432.708357] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [18432.708358] kworker/3:2     D14176 13474      2 0x80000000
> [18432.708369] Workqueue: events async_pf_execute [kvm]
> [18432.708371] Call Trace:
> [18432.708384]  ? __schedule+0x2e3/0xb90
> [18432.708395]  schedule+0x2f/0x90
> [18432.708396]  io_schedule+0x12/0x40
> [18432.708399]  __lock_page_or_retry+0x2e4/0x350
> [18432.708403]  ? page_cache_tree_insert+0x130/0x130
> [18432.708407]  do_swap_page+0x721/0x9f0
> [18432.708411]  ? __lock_acquire+0x2c3/0x1270
> [18432.708415]  __handle_mm_fault+0xa5c/0x1230
> [18432.708425]  handle_mm_fault+0x14c/0x310
> [18432.708432]  __get_user_pages+0x1b0/0x6e0
> [18432.708443]  get_user_pages_remote+0x13a/0x200
> [18432.708457]  async_pf_execute+0x96/0x280 [kvm]
> [18432.708464]  process_one_work+0x25e/0x6c0
> [18432.708472]  worker_thread+0x3a/0x390
> [18432.708478]  ? process_one_work+0x6c0/0x6c0
> [18432.708481]  kthread+0x15d/0x180
> [18432.708485]  ? kthread_create_worker_on_cpu+0x70/0x70
> [18432.708491]  ret_from_fork+0x24/0x30
> [18432.708507] 
>                Showing all locks held in the system:
> [18432.708526] 1 lock held by khungtaskd/67:
> [18432.708527]  #0:  (tasklist_lock){.+.+}, at: [<00000000a615f1dc>]
> debug_show_all_locks+0x37/0x190
> [18432.708637] 1 lock held by htop/2690:
> [18432.708638]  #0:  (&mm->mmap_sem){++++}, at: [<000000003ae69604>]
> proc_pid_cmdline_read+0xd2/0x4a0
> [18432.708657] 1 lock held by CPU 0/KVM/3893:
> [18432.708658]  #0:  (&vcpu->mutex){+.+.}, at: [<00000000ff3fb7f4>]
> vcpu_load+0x17/0x60 [kvm]
> [18432.708759] 2 locks held by Chrome_IOThread/27225:
> [18432.708760]  #0:  (&mm->mmap_sem){++++}, at: [<0000000012cb6189>]
> __do_page_fault+0x17a/0x530
> [18432.708766]  #1:  (shrinker_rwsem){++++}, at: [<0000000033d29b77>]
> shrink_slab.part.47+0x5b/0x590
> [18432.708773] 1 lock held by CacheThread_Blo/27264:
> [18432.708774]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18432.708780] 1 lock held by Chrome_HistoryT/27286:
> [18432.708781]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18432.708788] 1 lock held by TaskSchedulerFo/9369:
> [18432.708788]  #0:  (&mm->mmap_sem){++++}, at: [<00000000603ee2cd>]
> SyS_madvise+0x859/0x920
> [18432.708794] 1 lock held by TaskSchedulerFo/12373:
> [18432.708795]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18432.708802] 1 lock held by TaskSchedulerFo/13115:
> [18432.708803]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18432.708809] 1 lock held by TaskSchedulerFo/13125:
> [18432.708810]  #0:  (&mm->mmap_sem){++++}, at: [<000000003933f0be>]
> vm_mmap_pgoff+0xa5/0x120
> [18432.708816] 1 lock held by TaskSchedulerBa/13514:
> [18432.708817]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18432.708977] 1 lock held by tracker-store/5038:
> [18432.708978]  #0:  (&sb->s_type->i_mutex_key#20){++++}, at:
> [<00000000c3f6e04c>] xfs_ilock+0x195/0x200 [xfs]
> [18432.709033] 2 locks held by kworker/3:1/10525:
> [18432.709034]  #0:  ((wq_completion)"events"){+.+.}, at:
> [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [18432.709040]  #1:  ((work_completion)(&work->work)){+.+.}, at:
> [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [18432.709049] 2 locks held by kworker/3:2/13474:
> [18432.709050]  #0:  ((wq_completion)"events"){+.+.}, at:
> [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [18432.709056]  #1:  ((work_completion)(&work->work)){+.+.}, at:
> [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [18432.709064] 2 locks held by cc1/14068:
> [18432.709065]  #0:  (&mm->mmap_sem){++++}, at: [<0000000012cb6189>]
> __do_page_fault+0x17a/0x530
> [18432.709070]  #1:  (shrinker_rwsem){++++}, at: [<0000000033d29b77>]
> shrink_slab.part.47+0x5b/0x590
> 
> [18432.709078] =============================================
> 
> 
> [18555.587276] INFO: task htop:2690 blocked for more than 120 seconds.
> [18555.587281]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [18555.587283] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [18555.587285] htop            D12280  2690   2565 0x00000000
> [18555.587290] Call Trace:
> [18555.587298]  ? __schedule+0x2e3/0xb90
> [18555.587303]  ? rwsem_down_read_failed+0x147/0x190
> [18555.587307]  schedule+0x2f/0x90
> [18555.587309]  rwsem_down_read_failed+0x118/0x190
> [18555.587312]  ? __lock_acquire+0x2c3/0x1270
> [18555.587320]  ? call_rwsem_down_read_failed+0x14/0x30
> [18555.587323]  call_rwsem_down_read_failed+0x14/0x30
> [18555.587328]  down_read+0x97/0xa0
> [18555.587331]  proc_pid_cmdline_read+0xd2/0x4a0
> [18555.587335]  ? debug_check_no_obj_freed+0x160/0x248
> [18555.587343]  ? __vfs_read+0x33/0x170
> [18555.587344]  __vfs_read+0x33/0x170
> [18555.587351]  vfs_read+0x9e/0x150
> [18555.587354]  SyS_read+0x55/0xc0
> [18555.587359]  entry_SYSCALL_64_fastpath+0x1f/0x96
> [18555.587372] RIP: 0033:0x7fc2d8f4ae01
> [18555.587373] RSP: 002b:00007fffedb1f998 EFLAGS: 00000246 ORIG_RAX:
> 0000000000000000
> [18555.587376] RAX: ffffffffffffffda RBX: 000056139647bc10 RCX:
> 00007fc2d8f4ae01
> [18555.587377] RDX: 0000000000001000 RSI: 00007fffedb1fa60 RDI:
> 0000000000000007
> [18555.587378] RBP: 000056139696f483 R08: 000056139696f483 R09:
> 0000000000000005
> [18555.587380] R10: 0000000000000000 R11: 0000000000000246 R12:
> 0000000000000007
> [18555.587381] R13: 000056139696f3f0 R14: 00005613961bb8a0 R15:
> 00005613961bc5e0
> [18555.587516] INFO: task Chrome_IOThread:27225 blocked for more than
> 120 seconds.
> [18555.587519]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [18555.587521] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [18555.587522] Chrome_IOThread D11304 27225   3654 0x00000000
> [18555.587526] Call Trace:
> [18555.587531]  ? __schedule+0x2e3/0xb90
> [18555.587537]  ? __lock_page+0xa9/0x180
> [18555.587539]  schedule+0x2f/0x90
> [18555.587542]  io_schedule+0x12/0x40
> [18555.587544]  __lock_page+0xe9/0x180
> [18555.587548]  ? page_cache_tree_insert+0x130/0x130
> [18555.587553]  deferred_split_scan+0x2b6/0x300
> [18555.587560]  shrink_slab.part.47+0x1f8/0x590
> [18555.587566]  ? percpu_ref_put_many+0x84/0x100
> [18555.587572]  shrink_node+0x2f4/0x300
> [18555.587579]  do_try_to_free_pages+0xca/0x350
> [18555.587584]  try_to_free_pages+0x140/0x350
> [18555.587592]  __alloc_pages_slowpath+0x43c/0x1080
> [18555.587605]  __alloc_pages_nodemask+0x3ac/0x430
> [18555.587611]  alloc_pages_vma+0x7c/0x200
> [18555.587617]  __handle_mm_fault+0x8a1/0x1230
> [18555.587626]  handle_mm_fault+0x14c/0x310
> [18555.587631]  __do_page_fault+0x28c/0x530
> [18555.587637]  do_page_fault+0x32/0x270
> [18555.587641]  page_fault+0x22/0x30
> [18555.587643] RIP: 0033:0x7f9f336ac4ef
> [18555.587644] RSP: 002b:00007f9f1533c968 EFLAGS: 00010206
> [18555.587646] RAX: 00003d60824b4000 RBX: 00000000000885c8 RCX:
> 0000000000001040
> [18555.587648] RDX: 0000000000001040 RSI: 00003d602692c400 RDI:
> 00003d60824b4000
> [18555.587649] RBP: 00007f9f1533c9a0 R08: 0000000000000089 R09:
> 00003d602692d440
> [18555.587650] R10: 00007f9f1533caf0 R11: 0000000000000000 R12:
> 00003d602c90f3c0
> [18555.587651] R13: 0000000000000010 R14: 00000000000885b8 R15:
> 00003d60824b4000
> 
> 
> [18555.587666] INFO: task CacheThread_Blo:27264 blocked for more than
> 120 seconds.
> [18555.587669]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [18555.587672] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [18555.587674] CacheThread_Blo D12488 27264   3654 0x00000000
> [18555.587680] Call Trace:
> [18555.587687]  ? __schedule+0x2e3/0xb90
> [18555.587694]  ? rwsem_down_read_failed+0x147/0x190
> [18555.587699]  schedule+0x2f/0x90
> [18555.587702]  rwsem_down_read_failed+0x118/0x190
> [18555.587713]  ? call_rwsem_down_read_failed+0x14/0x30
> [18555.587715]  call_rwsem_down_read_failed+0x14/0x30
> [18555.587720]  down_read+0x97/0xa0
> [18555.587723]  __do_page_fault+0x493/0x530
> [18555.587727]  ? trace_hardirqs_on_caller+0xed/0x180
> [18555.587732]  do_page_fault+0x32/0x270
> [18555.587735]  page_fault+0x22/0x30
> [18555.587737] RIP: 0033:0x55c9558374c0
> [18555.587738] RSP: 002b:00007f9efed7b648 EFLAGS: 00010206
> [18555.587740] RAX: 0000000000000128 RBX: 0000000000000200 RCX:
> 00007f9efed7b658
> [18555.587741] RDX: 00000000000000b0 RSI: 00000000a1010000 RDI:
> 00003d60252f1088
> [18555.587743] RBP: 00007f9efed7b680 R08: 00007f9f1d5eb520 R09:
> 00000000ffff0001
> [18555.587744] R10: 0000000000000000 R11: 0000000000b10000 R12:
> 00003d6066f02c00
> [18555.587745] R13: 0000000000000000 R14: 0000000000000128 R15:
> 00003d60252f1088
> [18555.587754] INFO: task Chrome_HistoryT:27286 blocked for more than
> 120 seconds.
> [18555.587756]       Not tainted 4.15.0-rc2-amd-vega+ #10
> [18555.587757] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [18555.587759] Chrome_HistoryT D11432 27286   3654 0x00000000
> [18555.587762] Call Trace:
> [18555.587766]  ? __schedule+0x2e3/0xb90
> [18555.587771]  ? rwsem_down_read_failed+0x147/0x190
> [18555.587774]  schedule+0x2f/0x90
> [18555.587776]  rwsem_down_read_failed+0x118/0x190
> [18555.587784]  ? call_rwsem_down_read_failed+0x14/0x30
> [18555.587786]  call_rwsem_down_read_failed+0x14/0x30
> [18555.587790]  down_read+0x97/0xa0
> [18555.587792]  __do_page_fault+0x493/0x530
> [18555.587797]  ? SyS_futex+0x12d/0x180
> [18555.587799]  ? trace_hardirqs_on_caller+0xed/0x180
> [18555.587803]  do_page_fault+0x32/0x270
> [18555.587806]  page_fault+0x22/0x30
> [18555.587808] RIP: 0033:0x55c95555fa7a
> [18555.587809] RSP: 002b:00007f9efdb5ac70 EFLAGS: 00010202
> [18555.587811] RAX: 00007f9f1d5a7498 RBX: 000000000000000a RCX:
> 000000000000000b
> [18555.587812] RDX: 000000000000000a RSI: 0000000000000000 RDI:
> 00003d60258bb200
> [18555.587813] RBP: 00007f9efdb5aca0 R08: 0000000000000002 R09:
> 00003d602c693580
> [18555.587814] R10: 000000000000000d R11: 0000000000000001 R12:
> 000000000000000a
> [18555.587816] R13: 0000000000000001 R14: 00003d60258bb200 R15:
> 0000000000000001
> [18555.588004] 
>                Showing all locks held in the system:
> [18555.588014] 1 lock held by khungtaskd/67:
> [18555.588016]  #0:  (tasklist_lock){.+.+}, at: [<00000000a615f1dc>]
> debug_show_all_locks+0x37/0x190
> [18555.588124] 1 lock held by htop/2690:
> [18555.588125]  #0:  (&mm->mmap_sem){++++}, at: [<000000003ae69604>]
> proc_pid_cmdline_read+0xd2/0x4a0
> [18555.588162] 1 lock held by CPU 0/KVM/3893:
> [18555.588163]  #0:  (&vcpu->mutex){+.+.}, at: [<00000000ff3fb7f4>]
> vcpu_load+0x17/0x60 [kvm]
> [18555.588278] 1 lock held by atop/15452:
> [18555.588279]  #0:  (&mm->mmap_sem){++++}, at: [<000000003ae69604>]
> proc_pid_cmdline_read+0xd2/0x4a0
> [18555.588306] 2 locks held by Chrome_IOThread/27225:
> [18555.588307]  #0:  (&mm->mmap_sem){++++}, at: [<0000000012cb6189>]
> __do_page_fault+0x17a/0x530
> [18555.588318]  #1:  (shrinker_rwsem){++++}, at: [<0000000033d29b77>]
> shrink_slab.part.47+0x5b/0x590
> [18555.588330] 1 lock held by CacheThread_Blo/27264:
> [18555.588332]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18555.588340] 1 lock held by Chrome_HistoryT/27286:
> [18555.588341]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18555.588348] 1 lock held by TaskSchedulerFo/9369:
> [18555.588349]  #0:  (&mm->mmap_sem){++++}, at: [<00000000603ee2cd>]
> SyS_madvise+0x859/0x920
> [18555.588356] 1 lock held by TaskSchedulerFo/12373:
> [18555.588357]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18555.588363] 1 lock held by TaskSchedulerFo/13115:
> [18555.588365]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18555.588371] 1 lock held by TaskSchedulerFo/13125:
> [18555.588372]  #0:  (&mm->mmap_sem){++++}, at: [<000000003933f0be>]
> vm_mmap_pgoff+0xa5/0x120
> [18555.588379] 1 lock held by TaskSchedulerBa/13514:
> [18555.588380]  #0:  (&mm->mmap_sem){++++}, at: [<00000000aa62fc68>]
> __do_page_fault+0x493/0x530
> [18555.588574] 2 locks held by kworker/3:1/10525:
> [18555.588575]  #0:  ((wq_completion)"events"){+.+.}, at:
> [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [18555.588582]  #1:  ((work_completion)(&work->work)){+.+.}, at:
> [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [18555.588592] 2 locks held by kworker/3:2/13474:
> [18555.588593]  #0:  ((wq_completion)"events"){+.+.}, at:
> [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [18555.588599]  #1:  ((work_completion)(&work->work)){+.+.}, at:
> [<000000000f8b6ef4>] process_one_work+0x1d4/0x6c0
> [18555.588606] 2 locks held by cc1/14068:
> [18555.588608]  #0:  (&mm->mmap_sem){++++}, at: [<0000000012cb6189>]
> __do_page_fault+0x17a/0x530
> [18555.588613]  #1:  (shrinker_rwsem){++++}, at: [<0000000033d29b77>]
> shrink_slab.part.47+0x5b/0x590
> 
> [18555.588623] =============================================
> 
> --
> Regards
> Mikhail
> --
> To unsubscribe from this list: send the line "unsubscribe linux-xfs" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html

--
To unsubscribe, send a message with 'unsubscribe linux-mm' in
the body to majordomo@kvack.org.  For more info on Linux MM,
see: http://www.linux-mm.org/ .
Don't email: <a href=mailto:"dont@kvack.org"> email@kvack.org </a>