Re: Kernel hangs in cifs_reconnect

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



On October 30, 2019 7:17:25 PM UTC, Martijn de Gouw <martijn.de.gouw@xxxxxxxxxxxxxxxxxxxxxxxxx> wrote:
>Hi Paulo,
>
>On 29-10-2019 15:49, Paulo Alcantara wrote:
>> Hi Martijn,
>> 
>> Martijn de Gouw <martijn.de.gouw@xxxxxxxxxxxxxxxxxxxxxxxxx> writes:
>> 
>>> Anybody any idea on what goes wrong here?
>> 
>> Looks like an use-after-free bug in cifs_reconnect(). cifs superblock
>> gets freed due to automount expiration and then we dereference it in
>> dfs_cache_noreq_find().
>> 
>>> Is any of the recently posted patches related to my issue, because
>I'm
>>> more that willing to test out patches if needed.
>> 
>> Could you please test it again with below patch?
>
>Again cifs stopped working, it looks more like a live/dead lock to me
>than a use-after-free.
>I cut the dmesg from the moment the echo to DC02 starts failing.
>
>[70939.980125] fs/cifs/smb2pdu.c: In echo request
>[70939.980137] fs/cifs/smb2pdu.c: Echo request failed: -11
>[70939.980143] fs/cifs/connect.c: Unable to send echo request to
>server: DC02
>[70960.106599] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13353
>with uid: 999
>[70960.106697] fs/cifs/transport.c: Sending smb: smb_len=372
>[70960.116162] fs/cifs/connect.c: RFC1002 header 0x1b8
>[70960.116170] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[70960.116171] fs/cifs/smb2misc.c: SMB2 len 208
>[70960.116185] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[70960.116186] fs/cifs/smb2misc.c: SMB2 len 104
>[70960.116196] fs/cifs/smb2misc.c: SMB2 len 124
>[70960.116197] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 4066
>[70960.116200] fs/cifs/smb2ops.c: add 3 credits total=512
>[70960.116218] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5
>mid=4064 state=4
>[70960.116227] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16
>mid=4065 state=4
>[70960.116230] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6
>mid=4066 state=4
>[70960.116234] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[70960.116242] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[70960.116247] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[70960.116290] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13353) rc = 0
>[70960.116319] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13354
>with uid: 999
>[70960.116429] fs/cifs/transport.c: Sending smb: smb_len=348
>[70960.116829] fs/cifs/connect.c: RFC1002 header 0x1b8
>[70960.116834] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[70960.116835] fs/cifs/smb2misc.c: SMB2 len 208
>[70960.116842] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[70960.116843] fs/cifs/smb2misc.c: SMB2 len 104
>[70960.116856] fs/cifs/smb2misc.c: SMB2 len 124
>[70960.116857] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 74
>[70960.116859] fs/cifs/smb2ops.c: add 30 credits total=897
>[70960.116872] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=72
>state=4
>[70960.116874] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=73
>state=4
>[70960.116876] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=74
>state=4
>[70960.116877] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[70960.116882] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[70960.116887] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[70960.116954] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13354) rc = 0
>[71001.418549] fs/cifs/smb2pdu.c: In echo request
>[71001.418565] fs/cifs/smb2pdu.c: Echo request failed: -11
>[71001.418570] fs/cifs/connect.c: Unable to send echo request to
>server: DC02
>[71020.097059] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13355
>with uid: 999
>[71020.097165] fs/cifs/transport.c: Sending smb: smb_len=372
>[71020.097760] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71020.097767] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71020.097768] fs/cifs/smb2misc.c: SMB2 len 208
>[71020.097777] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71020.097778] fs/cifs/smb2misc.c: SMB2 len 104
>[71020.097788] fs/cifs/smb2misc.c: SMB2 len 124
>[71020.097789] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 4069
>[71020.097796] fs/cifs/smb2ops.c: add 3 credits total=512
>[71020.098387] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5
>mid=4067 state=4
>[71020.098395] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16
>mid=4068 state=4
>[71020.098398] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6
>mid=4069 state=4
>[71020.098405] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71020.098414] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71020.098419] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71020.098461] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13355) rc = 0
>[71020.098488] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13356
>with uid: 999
>[71020.098556] fs/cifs/transport.c: Sending smb: smb_len=348
>[71020.098915] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71020.098921] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71020.098922] fs/cifs/smb2misc.c: SMB2 len 208
>[71020.098932] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71020.098932] fs/cifs/smb2misc.c: SMB2 len 104
>[71020.098942] fs/cifs/smb2misc.c: SMB2 len 124
>[71020.098944] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 77
>[71020.098946] fs/cifs/smb2ops.c: add 30 credits total=924
>[71020.098956] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=75
>state=4
>[71020.098958] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=76
>state=4
>[71020.098959] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=77
>state=4
>[71020.098961] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71020.098968] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71020.098973] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71020.099011] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13356) rc = 0
>[71044.418723] INFO: task node_exporter:7214 blocked for more than 120
>seconds.
>[71044.420037]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71044.421491] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71044.422762] node_exporter   D    0  7214      1 0x00000000
>[71044.422781] Call Trace:
>[71044.422806]  ? __schedule+0x540/0xac0
>[71044.422813]  ? firmware_map_remove+0xe9/0xe9
>[71044.422821]  ? vsnprintf+0x32c/0x870
>[71044.422826]  ? _raw_spin_lock+0x7a/0xd0
>[71044.422829]  schedule+0x5e/0x100
>[71044.422837]  schedule_preempt_disabled+0xa/0x10
>[71044.422840]  __mutex_lock.isra.4+0x484/0x820
>[71044.422844]  ? mutex_trylock+0x90/0x90
>[71044.422848]  ? string_nocheck+0xb0/0xd0
>[71044.422854]  ? pointer+0x387/0x460
>[71044.422859]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71044.422863]  ? _raw_write_lock_bh+0xe0/0xe0
>[71044.422868]  ? mutex_lock+0xce/0xe0
>[71044.422871]  mutex_lock+0xce/0xe0
>[71044.422875]  ? __mutex_lock_slowpath+0x10/0x10
>[71044.422892]  ? find_nls+0x7d/0xa0
>[71044.422976]  smb2_reconnect.part.21+0x218/0xbd0 [cifs]
>[71044.422992]  ? deref_stack_reg+0x88/0xd0
>[71044.422995]  ? 0xffffffffa1e00000
>[71044.423065]  ? SMB2_tcon+0xab0/0xab0 [cifs]
>[71044.423067]  ? unwind_next_frame+0x90a/0x980
>[71044.423070]  ? entry_SYSCALL_64_after_hwframe+0x44/0xa9
>[71044.423081]  ? __module_text_address+0x11/0xa0
>[71044.423087]  ? __is_insn_slot_addr+0x1b/0x70
>[71044.423094]  ? is_bpf_text_address+0xa/0x20
>[71044.423101]  ? kernel_text_address+0xe2/0xf0
>[71044.423104]  ? __kernel_text_address+0xe/0x30
>[71044.423108]  ? unwind_get_return_address+0x2f/0x50
>[71044.423114]  ? create_prof_cpu_mask+0x20/0x20
>[71044.423120]  ? arch_stack_walk+0x92/0xe0
>[71044.423125]  ? stack_trace_save+0x8a/0xb0
>[71044.423195]  smb2_plain_req_init+0x2fe/0x3d0 [cifs]
>[71044.423272]  SMB2_open_init+0x143/0x12b0 [cifs]
>[71044.423339]  ? cifs_statfs+0x13a/0x420 [cifs]
>[71044.423352]  ? statfs_by_dentry+0xa5/0xf0
>[71044.423354]  ? vfs_statfs+0x28/0x110
>[71044.423357]  ? __do_sys_statfs+0x64/0xc0
>[71044.423413]  ? smb2_parse_contexts+0x270/0x270 [cifs]
>[71044.423416]  ? _raw_write_trylock+0xe0/0xe0
>[71044.423419]  ? _raw_spin_lock+0x7a/0xd0
>[71044.423421]  ? _raw_write_trylock+0xe0/0xe0
>[71044.423429]  ? memset+0x1f/0x40
>[71044.423433]  ? stack_access_ok+0x35/0x90
>[71044.423437]  ? deref_stack_reg+0x88/0xd0
>[71044.423439]  ? stack_access_ok+0x35/0x90
>[71044.423442]  ? deref_stack_reg+0x88/0xd0
>[71044.423446]  ? __read_once_size_nocheck.constprop.6+0x10/0x10
>[71044.423450]  ? put_dec_trunc8+0x73/0x110
>[71044.423453]  ? number+0x356/0x4b0
>[71044.423456]  ? widen_string+0x23/0xf0
>[71044.423517]  ? smb2_query_info_compound+0x242/0x4d0 [cifs]
>[71044.423558]  smb2_query_info_compound+0x242/0x4d0 [cifs]
>[71044.423601]  ? smb2_query_symlink+0xc70/0xc70 [cifs]
>[71044.423604]  ? pointer+0x460/0x460
>[71044.423606]  ? kernel_text_address+0xe2/0xf0
>[71044.423608]  ? va_format.isra.12+0xee/0x100
>[71044.423612]  ? vsnprintf+0x870/0x870
>[71044.423614]  ? string_nocheck+0xb0/0xd0
>[71044.423616]  ? pointer+0x387/0x460
>[71044.423619]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71044.423620]  ? _raw_write_lock_bh+0xe0/0xe0
>[71044.423622]  ? _raw_spin_lock+0x7a/0xd0
>[71044.423624]  ? _raw_write_trylock+0xe0/0xe0
>[71044.423626]  ? up+0x32/0x70
>[71044.423630]  ? __switch_to_asm+0x34/0x70
>[71044.423636]  ? dynamic_emit_prefix+0x29/0x220
>[71044.423684]  ? smb2_queryfs+0xd9/0x1c0 [cifs]
>[71044.423735]  smb2_queryfs+0xd9/0x1c0 [cifs]
>[71044.423776]  ? smb2_query_eas+0x4f0/0x4f0 [cifs]
>[71044.423780]  ? vfs_mknod+0xc0/0x320
>[71044.423785]  ? map_id_up+0x12f/0x1d0
>[71044.423787]  ? make_kprojid+0x20/0x20
>[71044.423789]  ? _raw_spin_lock+0x7a/0xd0
>[71044.423837]  cifs_statfs+0x13a/0x420 [cifs]
>[71044.423844]  statfs_by_dentry+0xa5/0xf0
>[71044.423848]  vfs_statfs+0x28/0x110
>[71044.423851]  user_statfs+0x91/0xf0
>[71044.423853]  ? vfs_statfs+0x110/0x110
>[71044.423855]  ? __schedule+0x562/0xac0
>[71044.423857]  __do_sys_statfs+0x64/0xc0
>[71044.423859]  ? user_statfs+0xf0/0xf0
>[71044.423865]  do_syscall_64+0x73/0x190
>[71044.423868]  entry_SYSCALL_64_after_hwframe+0x44/0xa9
>[71044.423875] RIP: 0033:0x4a5c20
>[71044.423880] Code: Bad RIP value.
>[71044.423882] RSP: 002b:000000c000341490 EFLAGS: 00000206 ORIG_RAX:
>0000000000000089
>[71044.423888] RAX: ffffffffffffffda RBX: 000000c00002ea00 RCX:
>00000000004a5c20
>[71044.423890] RDX: 0000000000000000 RSI: 000000c0003415c0 RDI:
>000000c0001c43c0
>[71044.423892] RBP: 000000c0003414f0 R08: 0000000000000000 R09:
>0000000000000000
>[71044.423893] R10: 0000000000000000 R11: 0000000000000206 R12:
>ffffffffffffffff
>[71044.423894] R13: 000000000000001f R14: 000000000000001e R15:
>0000000000000100
>[71044.423926] INFO: task cifsd:9136 blocked for more than 120 seconds.
>[71044.425076]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71044.426421] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71044.427718] cifsd           D    0  9136      2 0x80004000
>[71044.427721] Call Trace:
>[71044.427738]  ? __schedule+0x540/0xac0
>[71044.427745]  ? firmware_map_remove+0xe9/0xe9
>[71044.427752]  ? _raw_spin_lock+0x7a/0xd0
>[71044.427757]  schedule+0x5e/0x100
>[71044.427765]  schedule_preempt_disabled+0xa/0x10
>[71044.427775]  __mutex_lock.isra.4+0x484/0x820
>[71044.427781]  ? mutex_trylock+0x90/0x90
>[71044.427787]  ? irq_work_claim+0x2e/0x50
>[71044.427792]  ? vprintk_emit+0x11d/0x2e0
>[71044.427795]  ? mutex_lock+0xce/0xe0
>[71044.427797]  mutex_lock+0xce/0xe0
>[71044.427800]  ? __mutex_lock_slowpath+0x10/0x10
>[71044.427877]  dfs_cache_noreq_find+0xa7/0x190 [cifs]
>[71044.427951]  cifs_reconnect+0x18c/0x1510 [cifs]
>[71044.427970]  ? ___ratelimit+0x106/0x190
>[71044.428050]  ? smb2_calc_size+0x15c/0x250 [cifs]
>[71044.428107]  ? extract_hostname+0xa0/0xa0 [cifs]
>[71044.428121]  ? _raw_spin_trylock+0x91/0xe0
>[71044.428129]  ? _raw_spin_trylock_bh+0x100/0x100
>[71044.428134]  ? ___ratelimit+0x106/0x190
>[71044.428179]  cifs_handle_standard+0x252/0x270 [cifs]
>[71044.428224]  cifs_demultiplex_thread+0x124a/0x13e0 [cifs]
>[71044.428267]  ? cifs_handle_standard+0x270/0x270 [cifs]
>[71044.428272]  ? __switch_to_asm+0x40/0x70
>[71044.428276]  ? __switch_to_asm+0x34/0x70
>[71044.428278]  ? __switch_to_asm+0x40/0x70
>[71044.428280]  ? __switch_to_asm+0x34/0x70
>[71044.428281]  ? __switch_to_asm+0x40/0x70
>[71044.428283]  ? __switch_to_asm+0x34/0x70
>[71044.428284]  ? __switch_to_asm+0x40/0x70
>[71044.428286]  ? __switch_to_asm+0x34/0x70
>[71044.428287]  ? __switch_to_asm+0x40/0x70
>[71044.428289]  ? __switch_to_asm+0x34/0x70
>[71044.428290]  ? __switch_to_asm+0x40/0x70
>[71044.428292]  ? __switch_to_asm+0x34/0x70
>[71044.428293]  ? __switch_to_asm+0x40/0x70
>[71044.428295]  ? __switch_to_asm+0x34/0x70
>[71044.428296]  ? __switch_to_asm+0x40/0x70
>[71044.428298]  ? __switch_to_asm+0x34/0x70
>[71044.428299]  ? __switch_to_asm+0x40/0x70
>[71044.428301]  ? __switch_to_asm+0x34/0x70
>[71044.428303]  ? __switch_to_asm+0x40/0x70
>[71044.428304]  ? __switch_to_asm+0x34/0x70
>[71044.428306]  ? __switch_to_asm+0x40/0x70
>[71044.428307]  ? __switch_to_asm+0x34/0x70
>[71044.428309]  ? __switch_to_asm+0x40/0x70
>[71044.428310]  ? __switch_to_asm+0x34/0x70
>[71044.428312]  ? __switch_to_asm+0x40/0x70
>[71044.428313]  ? __switch_to_asm+0x34/0x70
>[71044.428317]  ? finish_task_switch+0xf6/0x370
>[71044.428319]  ? __switch_to+0x2ec/0x5e0
>[71044.428321]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71044.428323]  ? _raw_write_lock_bh+0xe0/0xe0
>[71044.428375]  ? cifs_handle_standard+0x270/0x270 [cifs]
>[71044.428378]  kthread+0x192/0x1e0
>[71044.428381]  ? kthread_create_worker_on_cpu+0xc0/0xc0
>[71044.428384]  ret_from_fork+0x35/0x40
>[71062.848999] fs/cifs/smb2pdu.c: In echo request
>[71062.849014] fs/cifs/smb2pdu.c: Echo request failed: -11
>[71062.849025] fs/cifs/connect.c: Unable to send echo request to
>server: DC02
>[71080.092803] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13357
>with uid: 999
>[71080.092915] fs/cifs/transport.c: Sending smb: smb_len=372
>[71080.097568] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71080.097580] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71080.097581] fs/cifs/smb2misc.c: SMB2 len 208
>[71080.097591] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71080.097592] fs/cifs/smb2misc.c: SMB2 len 104
>[71080.097601] fs/cifs/smb2misc.c: SMB2 len 124
>[71080.097603] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 4072
>[71080.097605] fs/cifs/smb2ops.c: add 3 credits total=512
>[71080.097741] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5
>mid=4070 state=4
>[71080.097749] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16
>mid=4071 state=4
>[71080.097752] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6
>mid=4072 state=4
>[71080.097758] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71080.097767] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71080.097771] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71080.097812] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13357) rc = 0
>[71080.097992] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13358
>with uid: 999
>[71080.098070] fs/cifs/transport.c: Sending smb: smb_len=348
>[71080.098487] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71080.098492] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71080.098493] fs/cifs/smb2misc.c: SMB2 len 208
>[71080.098500] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71080.098501] fs/cifs/smb2misc.c: SMB2 len 104
>[71080.098510] fs/cifs/smb2misc.c: SMB2 len 124
>[71080.098512] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 80
>[71080.098514] fs/cifs/smb2ops.c: add 30 credits total=951
>[71080.098609] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=78
>state=4
>[71080.098610] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=79
>state=4
>[71080.098612] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=80
>state=4
>[71080.098613] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71080.098619] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71080.098624] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71080.098655] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13358) rc = 0
>[71105.853107] fs/cifs/connect.c: Existing tcp session with server
>found
>[71105.853114] fs/cifs/dfs_cache.c: CIFS VFS: in do_refresh_tcon as
>Xid: 13359 with uid: 0
>[71116.587766] fs/cifs/inode.c: CIFS VFS: in
>cifs_revalidate_dentry_attr as Xid: 13360 with uid: 11025
>[71116.587834] fs/cifs/dir.c: name: \SOMEDIRX
>[71116.587842] fs/cifs/inode.c: Update attributes: \SOMEDIRX inode
>0x000000009f726d03 count 1 dentry: 0x00000000d22e461a d_time 4312598279
>jiffies 4312672891
>[71116.587847] fs/cifs/inode.c: Getting info on \SOMEDIRX
>[71116.588001] fs/cifs/transport.c: Sending smb: smb_len=388
>[71124.283416] fs/cifs/smb2pdu.c: In echo request
>[71124.283434] fs/cifs/smb2pdu.c: Echo request failed: -11
>[71124.283442] fs/cifs/connect.c: Unable to send echo request to
>server: DC02
>[71140.087648] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13361
>with uid: 999
>[71140.087743] fs/cifs/transport.c: Sending smb: smb_len=372
>[71140.088088] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71140.088095] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71140.088096] fs/cifs/smb2misc.c: SMB2 len 208
>[71140.088106] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71140.088107] fs/cifs/smb2misc.c: SMB2 len 104
>[71140.088116] fs/cifs/smb2misc.c: SMB2 len 124
>[71140.088118] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 4075
>[71140.088120] fs/cifs/smb2ops.c: add 3 credits total=512
>[71140.088137] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5
>mid=4073 state=4
>[71140.088141] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16
>mid=4074 state=4
>[71140.088144] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6
>mid=4075 state=4
>[71140.088147] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71140.088153] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71140.088158] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71140.088190] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13361) rc = 0
>[71140.088218] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13362
>with uid: 999
>[71140.088304] fs/cifs/transport.c: Sending smb: smb_len=348
>[71140.088643] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71140.088647] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71140.088648] fs/cifs/smb2misc.c: SMB2 len 208
>[71140.088655] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71140.088656] fs/cifs/smb2misc.c: SMB2 len 104
>[71140.088664] fs/cifs/smb2misc.c: SMB2 len 124
>[71140.088665] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 83
>[71140.088667] fs/cifs/smb2ops.c: add 30 credits total=978
>[71140.088680] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=81
>state=4
>[71140.088682] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=82
>state=4
>[71140.088684] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=83
>state=4
>[71140.088686] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71140.088692] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71140.088696] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71140.088728] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13362) rc = 0
>[71165.239724] INFO: task node_exporter:7214 blocked for more than 241
>seconds.
>[71165.240866]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71165.241935] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71165.243018] node_exporter   D    0  7214      1 0x00000000
>[71165.243022] Call Trace:
>[71165.243028]  ? __schedule+0x540/0xac0
>[71165.243031]  ? firmware_map_remove+0xe9/0xe9
>[71165.243035]  ? vsnprintf+0x32c/0x870
>[71165.243038]  ? _raw_spin_lock+0x7a/0xd0
>[71165.243040]  schedule+0x5e/0x100
>[71165.243042]  schedule_preempt_disabled+0xa/0x10
>[71165.243044]  __mutex_lock.isra.4+0x484/0x820
>[71165.243047]  ? mutex_trylock+0x90/0x90
>[71165.243049]  ? string_nocheck+0xb0/0xd0
>[71165.243051]  ? pointer+0x387/0x460
>[71165.243054]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71165.243056]  ? _raw_write_lock_bh+0xe0/0xe0
>[71165.243059]  ? mutex_lock+0xce/0xe0
>[71165.243060]  mutex_lock+0xce/0xe0
>[71165.243062]  ? __mutex_lock_slowpath+0x10/0x10
>[71165.243065]  ? find_nls+0x7d/0xa0
>[71165.243133]  smb2_reconnect.part.21+0x218/0xbd0 [cifs]
>[71165.243137]  ? deref_stack_reg+0x88/0xd0
>[71165.243139]  ? 0xffffffffa1e00000
>[71165.243180]  ? SMB2_tcon+0xab0/0xab0 [cifs]
>[71165.243181]  ? unwind_next_frame+0x90a/0x980
>[71165.243183]  ? entry_SYSCALL_64_after_hwframe+0x44/0xa9
>[71165.243187]  ? __module_text_address+0x11/0xa0
>[71165.243189]  ? __is_insn_slot_addr+0x1b/0x70
>[71165.243193]  ? is_bpf_text_address+0xa/0x20
>[71165.243195]  ? kernel_text_address+0xe2/0xf0
>[71165.243197]  ? __kernel_text_address+0xe/0x30
>[71165.243200]  ? unwind_get_return_address+0x2f/0x50
>[71165.243203]  ? create_prof_cpu_mask+0x20/0x20
>[71165.243207]  ? arch_stack_walk+0x92/0xe0
>[71165.243210]  ? stack_trace_save+0x8a/0xb0
>[71165.243251]  smb2_plain_req_init+0x2fe/0x3d0 [cifs]
>[71165.243294]  SMB2_open_init+0x143/0x12b0 [cifs]
>[71165.243332]  ? cifs_statfs+0x13a/0x420 [cifs]
>[71165.243336]  ? statfs_by_dentry+0xa5/0xf0
>[71165.243338]  ? vfs_statfs+0x28/0x110
>[71165.243340]  ? __do_sys_statfs+0x64/0xc0
>[71165.243382]  ? smb2_parse_contexts+0x270/0x270 [cifs]
>[71165.243384]  ? _raw_write_trylock+0xe0/0xe0
>[71165.243385]  ? _raw_spin_lock+0x7a/0xd0
>[71165.243387]  ? _raw_write_trylock+0xe0/0xe0
>[71165.243389]  ? memset+0x1f/0x40
>[71165.243391]  ? stack_access_ok+0x35/0x90
>[71165.243393]  ? deref_stack_reg+0x88/0xd0
>[71165.243396]  ? stack_access_ok+0x35/0x90
>[71165.243399]  ? deref_stack_reg+0x88/0xd0
>[71165.243402]  ? __read_once_size_nocheck.constprop.6+0x10/0x10
>[71165.243404]  ? put_dec_trunc8+0x73/0x110
>[71165.243406]  ? number+0x356/0x4b0
>[71165.243408]  ? widen_string+0x23/0xf0
>[71165.243449]  ? smb2_query_info_compound+0x242/0x4d0 [cifs]
>[71165.243490]  smb2_query_info_compound+0x242/0x4d0 [cifs]
>[71165.243533]  ? smb2_query_symlink+0xc70/0xc70 [cifs]
>[71165.243535]  ? pointer+0x460/0x460
>[71165.243537]  ? kernel_text_address+0xe2/0xf0
>[71165.243539]  ? va_format.isra.12+0xee/0x100
>[71165.243541]  ? vsnprintf+0x870/0x870
>[71165.243543]  ? string_nocheck+0xb0/0xd0
>[71165.243545]  ? pointer+0x387/0x460
>[71165.243547]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71165.243549]  ? _raw_write_lock_bh+0xe0/0xe0
>[71165.243551]  ? _raw_spin_lock+0x7a/0xd0
>[71165.243552]  ? _raw_write_trylock+0xe0/0xe0
>[71165.243555]  ? up+0x32/0x70
>[71165.243558]  ? __switch_to_asm+0x34/0x70
>[71165.243561]  ? dynamic_emit_prefix+0x29/0x220
>[71165.243602]  ? smb2_queryfs+0xd9/0x1c0 [cifs]
>[71165.243644]  smb2_queryfs+0xd9/0x1c0 [cifs]
>[71165.243688]  ? smb2_query_eas+0x4f0/0x4f0 [cifs]
>[71165.243764]  ? vfs_mknod+0xc0/0x320
>[71165.243770]  ? map_id_up+0x12f/0x1d0
>[71165.243774]  ? make_kprojid+0x20/0x20
>[71165.243777]  ? _raw_spin_lock+0x7a/0xd0
>[71165.243818]  cifs_statfs+0x13a/0x420 [cifs]
>[71165.243825]  statfs_by_dentry+0xa5/0xf0
>[71165.243829]  vfs_statfs+0x28/0x110
>[71165.243835]  user_statfs+0x91/0xf0
>[71165.243838]  ? vfs_statfs+0x110/0x110
>[71165.243844]  ? __schedule+0x562/0xac0
>[71165.243847]  __do_sys_statfs+0x64/0xc0
>[71165.243854]  ? user_statfs+0xf0/0xf0
>[71165.243859]  do_syscall_64+0x73/0x190
>[71165.243863]  entry_SYSCALL_64_after_hwframe+0x44/0xa9
>[71165.243866] RIP: 0033:0x4a5c20
>[71165.243873] Code: Bad RIP value.
>[71165.243876] RSP: 002b:000000c000341490 EFLAGS: 00000206 ORIG_RAX:
>0000000000000089
>[71165.243881] RAX: ffffffffffffffda RBX: 000000c00002ea00 RCX:
>00000000004a5c20
>[71165.243883] RDX: 0000000000000000 RSI: 000000c0003415c0 RDI:
>000000c0001c43c0
>[71165.243885] RBP: 000000c0003414f0 R08: 0000000000000000 R09:
>0000000000000000
>[71165.243886] R10: 0000000000000000 R11: 0000000000000206 R12:
>ffffffffffffffff
>[71165.243887] R13: 000000000000001f R14: 000000000000001e R15:
>0000000000000100
>[71165.243905] INFO: task cifsd:9136 blocked for more than 241 seconds.
>[71165.245022]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71165.246156] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71165.247272] cifsd           D    0  9136      2 0x80004000
>[71165.247274] Call Trace:
>[71165.247281]  ? __schedule+0x540/0xac0
>[71165.247284]  ? firmware_map_remove+0xe9/0xe9
>[71165.247287]  ? _raw_spin_lock+0x7a/0xd0
>[71165.247289]  schedule+0x5e/0x100
>[71165.247291]  schedule_preempt_disabled+0xa/0x10
>[71165.247293]  __mutex_lock.isra.4+0x484/0x820
>[71165.247296]  ? mutex_trylock+0x90/0x90
>[71165.247300]  ? irq_work_claim+0x2e/0x50
>[71165.247303]  ? vprintk_emit+0x11d/0x2e0
>[71165.247305]  ? mutex_lock+0xce/0xe0
>[71165.247306]  mutex_lock+0xce/0xe0
>[71165.247308]  ? __mutex_lock_slowpath+0x10/0x10
>[71165.247358]  dfs_cache_noreq_find+0xa7/0x190 [cifs]
>[71165.247398]  cifs_reconnect+0x18c/0x1510 [cifs]
>[71165.247402]  ? ___ratelimit+0x106/0x190
>[71165.247444]  ? smb2_calc_size+0x15c/0x250 [cifs]
>[71165.247484]  ? extract_hostname+0xa0/0xa0 [cifs]
>[71165.247486]  ? _raw_spin_trylock+0x91/0xe0
>[71165.247487]  ? _raw_spin_trylock_bh+0x100/0x100
>[71165.247490]  ? ___ratelimit+0x106/0x190
>[71165.247533]  cifs_handle_standard+0x252/0x270 [cifs]
>[71165.247575]  cifs_demultiplex_thread+0x124a/0x13e0 [cifs]
>[71165.247616]  ? cifs_handle_standard+0x270/0x270 [cifs]
>[71165.247618]  ? __switch_to_asm+0x40/0x70
>[71165.247620]  ? __switch_to_asm+0x34/0x70
>[71165.247621]  ? __switch_to_asm+0x40/0x70
>[71165.247623]  ? __switch_to_asm+0x34/0x70
>[71165.247624]  ? __switch_to_asm+0x40/0x70
>[71165.247626]  ? __switch_to_asm+0x34/0x70
>[71165.247627]  ? __switch_to_asm+0x40/0x70
>[71165.247629]  ? __switch_to_asm+0x34/0x70
>[71165.247630]  ? __switch_to_asm+0x40/0x70
>[71165.247632]  ? __switch_to_asm+0x34/0x70
>[71165.247633]  ? __switch_to_asm+0x40/0x70
>[71165.247635]  ? __switch_to_asm+0x34/0x70
>[71165.247637]  ? __switch_to_asm+0x40/0x70
>[71165.247638]  ? __switch_to_asm+0x34/0x70
>[71165.247640]  ? __switch_to_asm+0x40/0x70
>[71165.247641]  ? __switch_to_asm+0x34/0x70
>[71165.247643]  ? __switch_to_asm+0x40/0x70
>[71165.247644]  ? __switch_to_asm+0x34/0x70
>[71165.247646]  ? __switch_to_asm+0x40/0x70
>[71165.247647]  ? __switch_to_asm+0x34/0x70
>[71165.247649]  ? __switch_to_asm+0x40/0x70
>[71165.247651]  ? __switch_to_asm+0x34/0x70
>[71165.247653]  ? __switch_to_asm+0x40/0x70
>[71165.247659]  ? __switch_to_asm+0x34/0x70
>[71165.247678]  ? __switch_to_asm+0x40/0x70
>[71165.247680]  ? __switch_to_asm+0x34/0x70
>[71165.247683]  ? finish_task_switch+0xf6/0x370
>[71165.247685]  ? __switch_to+0x2ec/0x5e0
>[71165.247688]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71165.247690]  ? _raw_write_lock_bh+0xe0/0xe0
>[71165.247731]  ? cifs_handle_standard+0x270/0x270 [cifs]
>[71165.247735]  kthread+0x192/0x1e0
>[71165.247737]  ? kthread_create_worker_on_cpu+0xc0/0xc0
>[71165.247739]  ret_from_fork+0x35/0x40
>[71185.717885] fs/cifs/smb2pdu.c: In echo request
>[71185.717900] fs/cifs/smb2pdu.c: Echo request failed: -11
>[71185.717906] fs/cifs/connect.c: Unable to send echo request to
>server: DC02
>[71200.086057] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13363
>with uid: 999
>[71200.086149] fs/cifs/transport.c: Sending smb: smb_len=372
>[71200.086716] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71200.086725] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71200.086726] fs/cifs/smb2misc.c: SMB2 len 208
>[71200.086740] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71200.086741] fs/cifs/smb2misc.c: SMB2 len 104
>[71200.086751] fs/cifs/smb2misc.c: SMB2 len 124
>[71200.086752] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 4078
>[71200.086755] fs/cifs/smb2ops.c: add 3 credits total=512
>[71200.086770] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5
>mid=4076 state=4
>[71200.086775] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16
>mid=4077 state=4
>[71200.086777] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6
>mid=4078 state=4
>[71200.086781] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71200.086786] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71200.086791] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71200.086824] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13363) rc = 0
>[71200.086841] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13364
>with uid: 999
>[71200.086938] fs/cifs/transport.c: Sending smb: smb_len=348
>[71200.087336] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71200.087342] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71200.087343] fs/cifs/smb2misc.c: SMB2 len 208
>[71200.087352] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71200.087353] fs/cifs/smb2misc.c: SMB2 len 104
>[71200.087362] fs/cifs/smb2misc.c: SMB2 len 124
>[71200.087364] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 86
>[71200.087366] fs/cifs/smb2ops.c: add 30 credits total=1005
>[71200.087379] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=84
>state=4
>[71200.087381] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=85
>state=4
>[71200.087383] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=86
>state=4
>[71200.087384] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71200.087391] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71200.087395] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71200.087428] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13364) rc = 0
>[71247.152290] fs/cifs/smb2pdu.c: In echo request
>[71247.152306] fs/cifs/smb2pdu.c: Echo request failed: -11
>[71247.152313] fs/cifs/connect.c: Unable to send echo request to
>server: DC02
>[71260.080476] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13365
>with uid: 999
>[71260.080564] fs/cifs/transport.c: Sending smb: smb_len=372
>[71260.080923] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71260.080931] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71260.080932] fs/cifs/smb2misc.c: SMB2 len 208
>[71260.080946] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71260.080947] fs/cifs/smb2misc.c: SMB2 len 104
>[71260.080958] fs/cifs/smb2misc.c: SMB2 len 124
>[71260.080959] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 4081
>[71260.080962] fs/cifs/smb2ops.c: add 3 credits total=512
>[71260.080975] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5
>mid=4079 state=4
>[71260.080979] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16
>mid=4080 state=4
>[71260.080982] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6
>mid=4081 state=4
>[71260.080985] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71260.080991] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71260.080996] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71260.081032] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13365) rc = 0
>[71260.081163] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13366
>with uid: 999
>[71260.081228] fs/cifs/transport.c: Sending smb: smb_len=348
>[71260.081798] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71260.081804] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71260.081805] fs/cifs/smb2misc.c: SMB2 len 208
>[71260.081814] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71260.081814] fs/cifs/smb2misc.c: SMB2 len 104
>[71260.081824] fs/cifs/smb2misc.c: SMB2 len 124
>[71260.081826] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 89
>[71260.081828] fs/cifs/smb2ops.c: add 30 credits total=1032
>[71260.081841] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=87
>state=4
>[71260.081843] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=88
>state=4
>[71260.081845] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=89
>state=4
>[71260.081847] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71260.081854] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71260.081904] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71260.081936] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13366) rc = 0
>[71286.060785] INFO: task node_exporter:7214 blocked for more than 362
>seconds.
>[71286.061989]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71286.063131] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71286.064284] node_exporter   D    0  7214      1 0x00000000
>[71286.064287] Call Trace:
>[71286.064294]  ? __schedule+0x540/0xac0
>[71286.064297]  ? firmware_map_remove+0xe9/0xe9
>[71286.064301]  ? vsnprintf+0x32c/0x870
>[71286.064304]  ? _raw_spin_lock+0x7a/0xd0
>[71286.064306]  schedule+0x5e/0x100
>[71286.064308]  schedule_preempt_disabled+0xa/0x10
>[71286.064310]  __mutex_lock.isra.4+0x484/0x820
>[71286.064312]  ? mutex_trylock+0x90/0x90
>[71286.064315]  ? string_nocheck+0xb0/0xd0
>[71286.064317]  ? pointer+0x387/0x460
>[71286.064319]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71286.064321]  ? _raw_write_lock_bh+0xe0/0xe0
>[71286.064324]  ? mutex_lock+0xce/0xe0
>[71286.064325]  mutex_lock+0xce/0xe0
>[71286.064327]  ? __mutex_lock_slowpath+0x10/0x10
>[71286.064331]  ? find_nls+0x7d/0xa0
>[71286.064381]  smb2_reconnect.part.21+0x218/0xbd0 [cifs]
>[71286.064386]  ? deref_stack_reg+0x88/0xd0
>[71286.064392]  ? 0xffffffffa1e00000
>[71286.064434]  ? SMB2_tcon+0xab0/0xab0 [cifs]
>[71286.064436]  ? unwind_next_frame+0x90a/0x980
>[71286.064438]  ? entry_SYSCALL_64_after_hwframe+0x44/0xa9
>[71286.064442]  ? __module_text_address+0x11/0xa0
>[71286.064444]  ? __is_insn_slot_addr+0x1b/0x70
>[71286.064447]  ? is_bpf_text_address+0xa/0x20
>[71286.064450]  ? kernel_text_address+0xe2/0xf0
>[71286.064451]  ? __kernel_text_address+0xe/0x30
>[71286.064454]  ? unwind_get_return_address+0x2f/0x50
>[71286.064457]  ? create_prof_cpu_mask+0x20/0x20
>[71286.064460]  ? arch_stack_walk+0x92/0xe0
>[71286.064463]  ? stack_trace_save+0x8a/0xb0
>[71286.064505]  smb2_plain_req_init+0x2fe/0x3d0 [cifs]
>[71286.064548]  SMB2_open_init+0x143/0x12b0 [cifs]
>[71286.064586]  ? cifs_statfs+0x13a/0x420 [cifs]
>[71286.064590]  ? statfs_by_dentry+0xa5/0xf0
>[71286.064592]  ? vfs_statfs+0x28/0x110
>[71286.064594]  ? __do_sys_statfs+0x64/0xc0
>[71286.064636]  ? smb2_parse_contexts+0x270/0x270 [cifs]
>[71286.064638]  ? _raw_write_trylock+0xe0/0xe0
>[71286.064640]  ? _raw_spin_lock+0x7a/0xd0
>[71286.064641]  ? _raw_write_trylock+0xe0/0xe0
>[71286.064643]  ? memset+0x1f/0x40
>[71286.064646]  ? stack_access_ok+0x35/0x90
>[71286.064648]  ? deref_stack_reg+0x88/0xd0
>[71286.064650]  ? stack_access_ok+0x35/0x90
>[71286.064652]  ? deref_stack_reg+0x88/0xd0
>[71286.064654]  ? __read_once_size_nocheck.constprop.6+0x10/0x10
>[71286.064656]  ? put_dec_trunc8+0x73/0x110
>[71286.064658]  ? number+0x356/0x4b0
>[71286.064660]  ? widen_string+0x23/0xf0
>[71286.064705]  ? smb2_query_info_compound+0x242/0x4d0 [cifs]
>[71286.064770]  smb2_query_info_compound+0x242/0x4d0 [cifs]
>[71286.064817]  ? smb2_query_symlink+0xc70/0xc70 [cifs]
>[71286.064821]  ? pointer+0x460/0x460
>[71286.064827]  ? kernel_text_address+0xe2/0xf0
>[71286.064835]  ? va_format.isra.12+0xee/0x100
>[71286.064841]  ? vsnprintf+0x870/0x870
>[71286.064854]  ? string_nocheck+0xb0/0xd0
>[71286.064869]  ? pointer+0x387/0x460
>[71286.064875]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71286.064886]  ? _raw_write_lock_bh+0xe0/0xe0
>[71286.064888]  ? _raw_spin_lock+0x7a/0xd0
>[71286.064889]  ? _raw_write_trylock+0xe0/0xe0
>[71286.064892]  ? up+0x32/0x70
>[71286.064896]  ? __switch_to_asm+0x34/0x70
>[71286.064898]  ? dynamic_emit_prefix+0x29/0x220
>[71286.064940]  ? smb2_queryfs+0xd9/0x1c0 [cifs]
>[71286.064982]  smb2_queryfs+0xd9/0x1c0 [cifs]
>[71286.065029]  ? smb2_query_eas+0x4f0/0x4f0 [cifs]
>[71286.065035]  ? vfs_mknod+0xc0/0x320
>[71286.065038]  ? map_id_up+0x12f/0x1d0
>[71286.065040]  ? make_kprojid+0x20/0x20
>[71286.065042]  ? _raw_spin_lock+0x7a/0xd0
>[71286.065081]  cifs_statfs+0x13a/0x420 [cifs]
>[71286.065088]  statfs_by_dentry+0xa5/0xf0
>[71286.065091]  vfs_statfs+0x28/0x110
>[71286.065093]  user_statfs+0x91/0xf0
>[71286.065095]  ? vfs_statfs+0x110/0x110
>[71286.065099]  ? __schedule+0x562/0xac0
>[71286.065103]  __do_sys_statfs+0x64/0xc0
>[71286.065105]  ? user_statfs+0xf0/0xf0
>[71286.065109]  do_syscall_64+0x73/0x190
>[71286.065112]  entry_SYSCALL_64_after_hwframe+0x44/0xa9
>[71286.065116] RIP: 0033:0x4a5c20
>[71286.065121] Code: Bad RIP value.
>[71286.065122] RSP: 002b:000000c000341490 EFLAGS: 00000206 ORIG_RAX:
>0000000000000089
>[71286.065125] RAX: ffffffffffffffda RBX: 000000c00002ea00 RCX:
>00000000004a5c20
>[71286.065127] RDX: 0000000000000000 RSI: 000000c0003415c0 RDI:
>000000c0001c43c0
>[71286.065131] RBP: 000000c0003414f0 R08: 0000000000000000 R09:
>0000000000000000
>[71286.065133] R10: 0000000000000000 R11: 0000000000000206 R12:
>ffffffffffffffff
>[71286.065134] R13: 000000000000001f R14: 000000000000001e R15:
>0000000000000100
>[71286.065156] INFO: task cifsd:9136 blocked for more than 362 seconds.
>[71286.066326]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71286.068159] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71286.069395] cifsd           D    0  9136      2 0x80004000
>[71286.069399] Call Trace:
>[71286.069405]  ? __schedule+0x540/0xac0
>[71286.069408]  ? firmware_map_remove+0xe9/0xe9
>[71286.069411]  ? _raw_spin_lock+0x7a/0xd0
>[71286.069413]  schedule+0x5e/0x100
>[71286.069416]  schedule_preempt_disabled+0xa/0x10
>[71286.069417]  __mutex_lock.isra.4+0x484/0x820
>[71286.069420]  ? mutex_trylock+0x90/0x90
>[71286.069424]  ? irq_work_claim+0x2e/0x50
>[71286.069427]  ? vprintk_emit+0x11d/0x2e0
>[71286.069430]  ? mutex_lock+0xce/0xe0
>[71286.069431]  mutex_lock+0xce/0xe0
>[71286.069433]  ? __mutex_lock_slowpath+0x10/0x10
>[71286.069485]  dfs_cache_noreq_find+0xa7/0x190 [cifs]
>[71286.069526]  cifs_reconnect+0x18c/0x1510 [cifs]
>[71286.069530]  ? ___ratelimit+0x106/0x190
>[71286.069572]  ? smb2_calc_size+0x15c/0x250 [cifs]
>[71286.069611]  ? extract_hostname+0xa0/0xa0 [cifs]
>[71286.069613]  ? _raw_spin_trylock+0x91/0xe0
>[71286.069615]  ? _raw_spin_trylock_bh+0x100/0x100
>[71286.069617]  ? ___ratelimit+0x106/0x190
>[71286.069657]  cifs_handle_standard+0x252/0x270 [cifs]
>[71286.069697]  cifs_demultiplex_thread+0x124a/0x13e0 [cifs]
>[71286.069737]  ? cifs_handle_standard+0x270/0x270 [cifs]
>[71286.069739]  ? __switch_to_asm+0x40/0x70
>[71286.069741]  ? __switch_to_asm+0x34/0x70
>[71286.069742]  ? __switch_to_asm+0x40/0x70
>[71286.069744]  ? __switch_to_asm+0x34/0x70
>[71286.069746]  ? __switch_to_asm+0x40/0x70
>[71286.069748]  ? __switch_to_asm+0x34/0x70
>[71286.069750]  ? __switch_to_asm+0x40/0x70
>[71286.069752]  ? __switch_to_asm+0x34/0x70
>[71286.069753]  ? __switch_to_asm+0x40/0x70
>[71286.069755]  ? __switch_to_asm+0x34/0x70
>[71286.069756]  ? __switch_to_asm+0x40/0x70
>[71286.069758]  ? __switch_to_asm+0x34/0x70
>[71286.069759]  ? __switch_to_asm+0x40/0x70
>[71286.069761]  ? __switch_to_asm+0x34/0x70
>[71286.069762]  ? __switch_to_asm+0x40/0x70
>[71286.069764]  ? __switch_to_asm+0x34/0x70
>[71286.069765]  ? __switch_to_asm+0x40/0x70
>[71286.069767]  ? __switch_to_asm+0x34/0x70
>[71286.069768]  ? __switch_to_asm+0x40/0x70
>[71286.069770]  ? __switch_to_asm+0x34/0x70
>[71286.069772]  ? __switch_to_asm+0x40/0x70
>[71286.069773]  ? __switch_to_asm+0x34/0x70
>[71286.069775]  ? __switch_to_asm+0x40/0x70
>[71286.069776]  ? __switch_to_asm+0x34/0x70
>[71286.069778]  ? __switch_to_asm+0x40/0x70
>[71286.069779]  ? __switch_to_asm+0x34/0x70
>[71286.069782]  ? finish_task_switch+0xf6/0x370
>[71286.069784]  ? __switch_to+0x2ec/0x5e0
>[71286.069786]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71286.069788]  ? _raw_write_lock_bh+0xe0/0xe0
>[71286.069828]  ? cifs_handle_standard+0x270/0x270 [cifs]
>[71286.069830]  kthread+0x192/0x1e0
>[71286.069832]  ? kthread_create_worker_on_cpu+0xc0/0xc0
>[71286.069834]  ret_from_fork+0x35/0x40
>[71286.069842] INFO: task kworker/0:0:16069 blocked for more than 120
>seconds.
>[71286.071059]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71286.072279] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71286.073534] kworker/0:0     D    0 16069      2 0x80004000
>[71286.073585] Workqueue: cifsiod refresh_cache_worker [cifs]
>[71286.073586] Call Trace:
>[71286.073590]  ? __schedule+0x540/0xac0
>[71286.073592]  ? firmware_map_remove+0xe9/0xe9
>[71286.073595]  ? _raw_read_lock_irq+0x40/0x40
>[71286.073597]  ? _raw_spin_lock+0x7a/0xd0
>[71286.073599]  schedule+0x5e/0x100
>[71286.073601]  schedule_preempt_disabled+0xa/0x10
>[71286.073603]  __mutex_lock.isra.4+0x484/0x820
>[71286.073606]  ? mutex_trylock+0x90/0x90
>[71286.073609]  ? dynamic_emit_prefix+0x29/0x220
>[71286.073610]  ? __dynamic_pr_debug+0xf8/0x140
>[71286.073612]  ? dynamic_emit_prefix+0x220/0x220
>[71286.073618]  ? update_dl_rq_load_avg+0x2c3/0x4d0
>[71286.073620]  ? mutex_lock+0xce/0xe0
>[71286.073621]  mutex_lock+0xce/0xe0
>[71286.073623]  ? __mutex_lock_slowpath+0x10/0x10
>[71286.073665]  refresh_cache_worker+0x48f/0x14a0 [cifs]
>[71286.073668]  ? __switch_to_asm+0x40/0x70
>[71286.073670]  ? __switch_to_asm+0x40/0x70
>[71286.073672]  ? __switch_to_asm+0x34/0x70
>[71286.073673]  ? __switch_to_asm+0x40/0x70
>[71286.073675]  ? __switch_to_asm+0x34/0x70
>[71286.073676]  ? __switch_to_asm+0x40/0x70
>[71286.073718]  ? find_root_ses.isra.9+0x320/0x320 [cifs]
>[71286.073720]  ? __switch_to_asm+0x40/0x70
>[71286.073722]  ? __switch_to_asm+0x40/0x70
>[71286.073723]  ? __switch_to_asm+0x34/0x70
>[71286.073725]  ? __switch_to_asm+0x40/0x70
>[71286.073728]  ? __switch_to_asm+0x34/0x70
>[71286.073729]  ? __switch_to_asm+0x40/0x70
>[71286.073731]  ? __switch_to_asm+0x34/0x70
>[71286.073732]  ? __switch_to_asm+0x40/0x70
>[71286.073734]  ? __switch_to_asm+0x40/0x70
>[71286.073736]  ? __switch_to_asm+0x34/0x70
>[71286.073738]  ? finish_task_switch+0xf6/0x370
>[71286.073741]  ? __switch_to+0x2ec/0x5e0
>[71286.073742]  ? __schedule+0x562/0xac0
>[71286.073745]  ? read_word_at_a_time+0xe/0x20
>[71286.073747]  ? strscpy+0xca/0x1d0
>[71286.073753]  process_one_work+0x373/0x6e0
>[71286.073756]  worker_thread+0x78/0x5b0
>[71286.073759]  ? rescuer_thread+0x5e0/0x5e0
>[71286.073760]  kthread+0x192/0x1e0
>[71286.073762]  ? kthread_create_worker_on_cpu+0xc0/0xc0
>[71286.073764]  ret_from_fork+0x35/0x40
>[71308.590706] fs/cifs/smb2pdu.c: In echo request
>[71308.590730] fs/cifs/smb2pdu.c: Echo request failed: -11
>[71308.590743] fs/cifs/connect.c: Unable to send echo request to
>server: DC02
>[71320.544886] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13367
>with uid: 999
>[71320.544989] fs/cifs/transport.c: Sending smb: smb_len=372
>[71320.545462] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71320.545477] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71320.545479] fs/cifs/smb2misc.c: SMB2 len 208
>[71320.545495] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71320.545496] fs/cifs/smb2misc.c: SMB2 len 104
>[71320.545512] fs/cifs/smb2misc.c: SMB2 len 124
>[71320.545515] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 4084
>[71320.545517] fs/cifs/smb2ops.c: add 3 credits total=512
>[71320.545533] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5
>mid=4082 state=4
>[71320.545539] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16
>mid=4083 state=4
>[71320.545542] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6
>mid=4084 state=4
>[71320.545548] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71320.545555] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71320.545559] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71320.545620] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13367) rc = 0
>[71320.548354] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13368
>with uid: 999
>[71320.548525] fs/cifs/transport.c: Sending smb: smb_len=348
>[71320.548816] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71320.548826] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71320.548828] fs/cifs/smb2misc.c: SMB2 len 208
>[71320.548844] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71320.548846] fs/cifs/smb2misc.c: SMB2 len 104
>[71320.548864] fs/cifs/smb2misc.c: SMB2 len 124
>[71320.548867] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 92
>[71320.548870] fs/cifs/smb2ops.c: add 30 credits total=1059
>[71320.548887] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=90
>state=4
>[71320.548890] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=91
>state=4
>[71320.548891] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=92
>state=4
>[71320.548893] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71320.548900] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71320.548904] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71320.548937] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13368) rc = 0
>[71370.021141] fs/cifs/smb2pdu.c: In echo request
>[71370.021164] fs/cifs/smb2pdu.c: Echo request failed: -11
>[71370.021172] fs/cifs/connect.c: Unable to send echo request to
>server: DC02
>[71380.065094] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13369
>with uid: 999
>[71380.065186] fs/cifs/transport.c: Sending smb: smb_len=372
>[71380.065747] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71380.065757] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71380.065758] fs/cifs/smb2misc.c: SMB2 len 208
>[71380.065771] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71380.065773] fs/cifs/smb2misc.c: SMB2 len 104
>[71380.065786] fs/cifs/smb2misc.c: SMB2 len 124
>[71380.065789] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 4087
>[71380.065792] fs/cifs/smb2ops.c: add 3 credits total=512
>[71380.065826] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5
>mid=4085 state=4
>[71380.065831] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16
>mid=4086 state=4
>[71380.065834] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6
>mid=4087 state=4
>[71380.065837] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71380.065843] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71380.065848] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71380.065881] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13369) rc = 0
>[71380.065908] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 13370
>with uid: 999
>[71380.065966] fs/cifs/transport.c: Sending smb: smb_len=348
>[71380.066494] fs/cifs/connect.c: RFC1002 header 0x1b8
>[71380.066501] fs/cifs/smb2misc.c: SMB2 data length 56 offset 152
>[71380.066503] fs/cifs/smb2misc.c: SMB2 len 208
>[71380.066516] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72
>[71380.066517] fs/cifs/smb2misc.c: SMB2 len 104
>[71380.066532] fs/cifs/smb2misc.c: SMB2 len 124
>[71380.066534] fs/cifs/smb2misc.c: Calculated size 124 length 128
>mismatch mid 95
>[71380.066536] fs/cifs/smb2ops.c: add 30 credits total=1086
>[71380.067649] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=93
>state=4
>[71380.067652] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=94
>state=4
>[71380.067656] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=95
>state=4
>[71380.067658] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71380.067667] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71380.067672] fs/cifs/misc.c: Null buffer passed to
>cifs_small_buf_release
>[71380.067707] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid =
>13370) rc = 0
>[71406.881866] INFO: task node_exporter:7214 blocked for more than 483
>seconds.
>[71406.883327]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71406.884700] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71406.886064] node_exporter   D    0  7214      1 0x00000000
>[71406.886068] Call Trace:
>[71406.886075]  ? __schedule+0x540/0xac0
>[71406.886080]  ? firmware_map_remove+0xe9/0xe9
>[71406.886087]  ? vsnprintf+0x32c/0x870
>[71406.886091]  ? _raw_spin_lock+0x7a/0xd0
>[71406.886095]  schedule+0x5e/0x100
>[71406.886098]  schedule_preempt_disabled+0xa/0x10
>[71406.886104]  __mutex_lock.isra.4+0x484/0x820
>[71406.886106]  ? mutex_trylock+0x90/0x90
>[71406.886108]  ? string_nocheck+0xb0/0xd0
>[71406.886111]  ? pointer+0x387/0x460
>[71406.886113]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71406.886115]  ? _raw_write_lock_bh+0xe0/0xe0
>[71406.886121]  ? mutex_lock+0xce/0xe0
>[71406.886122]  mutex_lock+0xce/0xe0
>[71406.886124]  ? __mutex_lock_slowpath+0x10/0x10
>[71406.886126]  ? find_nls+0x7d/0xa0
>[71406.886194]  smb2_reconnect.part.21+0x218/0xbd0 [cifs]
>[71406.886206]  ? deref_stack_reg+0x88/0xd0
>[71406.886210]  ? 0xffffffffa1e00000
>[71406.886254]  ? SMB2_tcon+0xab0/0xab0 [cifs]
>[71406.886258]  ? unwind_next_frame+0x90a/0x980
>[71406.886260]  ? entry_SYSCALL_64_after_hwframe+0x44/0xa9
>[71406.886263]  ? __module_text_address+0x11/0xa0
>[71406.886265]  ? __is_insn_slot_addr+0x1b/0x70
>[71406.886272]  ? is_bpf_text_address+0xa/0x20
>[71406.886274]  ? kernel_text_address+0xe2/0xf0
>[71406.886275]  ? __kernel_text_address+0xe/0x30
>[71406.886278]  ? unwind_get_return_address+0x2f/0x50
>[71406.886280]  ? create_prof_cpu_mask+0x20/0x20
>[71406.886288]  ? arch_stack_walk+0x92/0xe0
>[71406.886291]  ? stack_trace_save+0x8a/0xb0
>[71406.886337]  smb2_plain_req_init+0x2fe/0x3d0 [cifs]
>[71406.886381]  SMB2_open_init+0x143/0x12b0 [cifs]
>[71406.886420]  ? cifs_statfs+0x13a/0x420 [cifs]
>[71406.886424]  ? statfs_by_dentry+0xa5/0xf0
>[71406.886426]  ? vfs_statfs+0x28/0x110
>[71406.886428]  ? __do_sys_statfs+0x64/0xc0
>[71406.886470]  ? smb2_parse_contexts+0x270/0x270 [cifs]
>[71406.886475]  ? _raw_write_trylock+0xe0/0xe0
>[71406.886476]  ? _raw_spin_lock+0x7a/0xd0
>[71406.886478]  ? _raw_write_trylock+0xe0/0xe0
>[71406.886480]  ? memset+0x1f/0x40
>[71406.886482]  ? stack_access_ok+0x35/0x90
>[71406.886484]  ? deref_stack_reg+0x88/0xd0
>[71406.886486]  ? stack_access_ok+0x35/0x90
>[71406.886488]  ? deref_stack_reg+0x88/0xd0
>[71406.886491]  ? __read_once_size_nocheck.constprop.6+0x10/0x10
>[71406.886493]  ? put_dec_trunc8+0x73/0x110
>[71406.886495]  ? number+0x356/0x4b0
>[71406.886497]  ? widen_string+0x23/0xf0
>[71406.886538]  ? smb2_query_info_compound+0x242/0x4d0 [cifs]
>[71406.886580]  smb2_query_info_compound+0x242/0x4d0 [cifs]
>[71406.886622]  ? smb2_query_symlink+0xc70/0xc70 [cifs]
>[71406.886624]  ? pointer+0x460/0x460
>[71406.886626]  ? kernel_text_address+0xe2/0xf0
>[71406.886628]  ? va_format.isra.12+0xee/0x100
>[71406.886630]  ? vsnprintf+0x870/0x870
>[71406.886632]  ? string_nocheck+0xb0/0xd0
>[71406.886634]  ? pointer+0x387/0x460
>[71406.886636]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71406.886638]  ? _raw_write_lock_bh+0xe0/0xe0
>[71406.886640]  ? _raw_spin_lock+0x7a/0xd0
>[71406.886641]  ? _raw_write_trylock+0xe0/0xe0
>[71406.886644]  ? up+0x32/0x70
>[71406.886647]  ? __switch_to_asm+0x34/0x70
>[71406.886649]  ? dynamic_emit_prefix+0x29/0x220
>[71406.886691]  ? smb2_queryfs+0xd9/0x1c0 [cifs]
>[71406.886744]  smb2_queryfs+0xd9/0x1c0 [cifs]
>[71406.886789]  ? smb2_query_eas+0x4f0/0x4f0 [cifs]
>[71406.886792]  ? vfs_mknod+0xc0/0x320
>[71406.886795]  ? map_id_up+0x12f/0x1d0
>[71406.886797]  ? make_kprojid+0x20/0x20
>[71406.886799]  ? _raw_spin_lock+0x7a/0xd0
>[71406.886836]  cifs_statfs+0x13a/0x420 [cifs]
>[71406.886839]  statfs_by_dentry+0xa5/0xf0
>[71406.886842]  vfs_statfs+0x28/0x110
>[71406.886844]  user_statfs+0x91/0xf0
>[71406.886846]  ? vfs_statfs+0x110/0x110
>[71406.886848]  ? __schedule+0x562/0xac0
>[71406.886850]  __do_sys_statfs+0x64/0xc0
>[71406.886852]  ? user_statfs+0xf0/0xf0
>[71406.886856]  do_syscall_64+0x73/0x190
>[71406.886858]  entry_SYSCALL_64_after_hwframe+0x44/0xa9
>[71406.886860] RIP: 0033:0x4a5c20
>[71406.886864] Code: Bad RIP value.
>[71406.886865] RSP: 002b:000000c000341490 EFLAGS: 00000206 ORIG_RAX:
>0000000000000089
>[71406.886867] RAX: ffffffffffffffda RBX: 000000c00002ea00 RCX:
>00000000004a5c20
>[71406.886868] RDX: 0000000000000000 RSI: 000000c0003415c0 RDI:
>000000c0001c43c0
>[71406.886869] RBP: 000000c0003414f0 R08: 0000000000000000 R09:
>0000000000000000
>[71406.886871] R10: 0000000000000000 R11: 0000000000000206 R12:
>ffffffffffffffff
>[71406.886871] R13: 000000000000001f R14: 000000000000001e R15:
>0000000000000100
>[71406.886897] INFO: task cifsd:9136 blocked for more than 483 seconds.
>[71406.888219]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71406.889666] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71406.891125] cifsd           D    0  9136      2 0x80004000
>[71406.891130] Call Trace:
>[71406.891138]  ? __schedule+0x540/0xac0
>[71406.891147]  ? firmware_map_remove+0xe9/0xe9
>[71406.891156]  ? _raw_spin_lock+0x7a/0xd0
>[71406.891161]  schedule+0x5e/0x100
>[71406.891168]  schedule_preempt_disabled+0xa/0x10
>[71406.891173]  __mutex_lock.isra.4+0x484/0x820
>[71406.891179]  ? mutex_trylock+0x90/0x90
>[71406.891192]  ? irq_work_claim+0x2e/0x50
>[71406.891207]  ? vprintk_emit+0x11d/0x2e0
>[71406.891216]  ? mutex_lock+0xce/0xe0
>[71406.891228]  mutex_lock+0xce/0xe0
>[71406.891232]  ? __mutex_lock_slowpath+0x10/0x10
>[71406.891317]  dfs_cache_noreq_find+0xa7/0x190 [cifs]
>[71406.891378]  cifs_reconnect+0x18c/0x1510 [cifs]
>[71406.891383]  ? ___ratelimit+0x106/0x190
>[71406.891439]  ? smb2_calc_size+0x15c/0x250 [cifs]
>[71406.891505]  ? extract_hostname+0xa0/0xa0 [cifs]
>[71406.891510]  ? _raw_spin_trylock+0x91/0xe0
>[71406.891513]  ? _raw_spin_trylock_bh+0x100/0x100
>[71406.891516]  ? ___ratelimit+0x106/0x190
>[71406.891574]  cifs_handle_standard+0x252/0x270 [cifs]
>[71406.891631]  cifs_demultiplex_thread+0x124a/0x13e0 [cifs]
>[71406.891692]  ? cifs_handle_standard+0x270/0x270 [cifs]
>[71406.891696]  ? __switch_to_asm+0x40/0x70
>[71406.891698]  ? __switch_to_asm+0x34/0x70
>[71406.891701]  ? __switch_to_asm+0x40/0x70
>[71406.891703]  ? __switch_to_asm+0x34/0x70
>[71406.891706]  ? __switch_to_asm+0x40/0x70
>[71406.891709]  ? __switch_to_asm+0x34/0x70
>[71406.891712]  ? __switch_to_asm+0x40/0x70
>[71406.891715]  ? __switch_to_asm+0x34/0x70
>[71406.891718]  ? __switch_to_asm+0x40/0x70
>[71406.891720]  ? __switch_to_asm+0x34/0x70
>[71406.891723]  ? __switch_to_asm+0x40/0x70
>[71406.891725]  ? __switch_to_asm+0x34/0x70
>[71406.891727]  ? __switch_to_asm+0x40/0x70
>[71406.891729]  ? __switch_to_asm+0x34/0x70
>[71406.891732]  ? __switch_to_asm+0x40/0x70
>[71406.891734]  ? __switch_to_asm+0x34/0x70
>[71406.891737]  ? __switch_to_asm+0x40/0x70
>[71406.891739]  ? __switch_to_asm+0x34/0x70
>[71406.891741]  ? __switch_to_asm+0x40/0x70
>[71406.891744]  ? __switch_to_asm+0x34/0x70
>[71406.891746]  ? __switch_to_asm+0x40/0x70
>[71406.891749]  ? __switch_to_asm+0x34/0x70
>[71406.891751]  ? __switch_to_asm+0x40/0x70
>[71406.891753]  ? __switch_to_asm+0x34/0x70
>[71406.891756]  ? __switch_to_asm+0x40/0x70
>[71406.891758]  ? __switch_to_asm+0x34/0x70
>[71406.891763]  ? finish_task_switch+0xf6/0x370
>[71406.891767]  ? __switch_to+0x2ec/0x5e0
>[71406.891772]  ? _raw_spin_lock_irqsave+0x8d/0xf0
>[71406.891774]  ? _raw_write_lock_bh+0xe0/0xe0
>[71406.891946]  ? cifs_handle_standard+0x270/0x270 [cifs]
>[71406.891951]  kthread+0x192/0x1e0
>[71406.891955]  ? kthread_create_worker_on_cpu+0xc0/0xc0
>[71406.891958]  ret_from_fork+0x35/0x40
>[71406.891970] INFO: task kworker/0:0:16069 blocked for more than 241
>seconds.
>[71406.894262]       Tainted: G            E     5.3.11-pd-5.3.y
>#20191029
>[71406.896328] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
>disables this message.
>[71406.898113] kworker/0:0     D    0 16069      2 0x80004000
>[71406.898203] Workqueue: cifsiod refresh_cache_worker [cifs]
>[71406.898211] Call Trace:
>[71406.898220]  ? __schedule+0x540/0xac0
>[71406.898224]  ? firmware_map_remove+0xe9/0xe9
>[71406.898228]  ? _raw_read_lock_irq+0x40/0x40
>[71406.898231]  ? _raw_spin_lock+0x7a/0xd0
>[71406.898234]  schedule+0x5e/0x100
>[71406.898239]  schedule_preempt_disabled+0xa/0x10
>[71406.898242]  __mutex_lock.isra.4+0x484/0x820
>[71406.898247]  ? mutex_trylock+0x90/0x90
>[71406.898251]  ? dynamic_emit_prefix+0x29/0x220
>[71406.898255]  ? __dynamic_pr_debug+0xf8/0x140
>[71406.898260]  ? dynamic_emit_prefix+0x220/0x220
>[71406.898271]  ? update_dl_rq_load_avg+0x2c3/0x4d0
>[71406.898277]  ? mutex_lock+0xce/0xe0
>[71406.898280]  mutex_lock+0xce/0xe0
>[71406.898283]  ? __mutex_lock_slowpath+0x10/0x10
>[71406.898362]  refresh_cache_worker+0x48f/0x14a0 [cifs]
>[71406.898370]  ? __switch_to_asm+0x40/0x70
>[71406.898373]  ? __switch_to_asm+0x40/0x70
>[71406.898376]  ? __switch_to_asm+0x34/0x70
>[71406.898378]  ? __switch_to_asm+0x40/0x70
>[71406.898383]  ? __switch_to_asm+0x34/0x70
>[71406.898389]  ? __switch_to_asm+0x40/0x70
>[71406.898467]  ? find_root_ses.isra.9+0x320/0x320 [cifs]
>[71406.898472]  ? __switch_to_asm+0x40/0x70
>[71406.898476]  ? __switch_to_asm+0x40/0x70
>[71406.898479]  ? __switch_to_asm+0x34/0x70
>[71406.898484]  ? __switch_to_asm+0x40/0x70
>[71406.898488]  ? __switch_to_asm+0x34/0x70
>[71406.898490]  ? __switch_to_asm+0x40/0x70
>[71406.898499]  ? __switch_to_asm+0x34/0x70
>[71406.898502]  ? __switch_to_asm+0x40/0x70
>[71406.898505]  ? __switch_to_asm+0x40/0x70
>[71406.898508]  ? __switch_to_asm+0x34/0x70
>[71406.898516]  ? finish_task_switch+0xf6/0x370
>[71406.898519]  ? __switch_to+0x2ec/0x5e0
>[71406.898523]  ? __schedule+0x562/0xac0
>[71406.898530]  ? read_word_at_a_time+0xe/0x20
>[71406.898533]  ? strscpy+0xca/0x1d0
>[71406.898539]  process_one_work+0x373/0x6e0
>[71406.898547]  worker_thread+0x78/0x5b0
>[71406.898552]  ? rescuer_thread+0x5e0/0x5e0
>[71406.898555]  kthread+0x192/0x1e0
>[71406.898559]  ? kthread_create_worker_on_cpu+0xc0/0xc0
>[71406.898563]  ret_from_fork+0x35/0x40
>[71417.962152] fs/cifs/inode.c: CIFS VFS: in
>cifs_revalidate_dentry_attr as Xid: 13371 with uid: 11025
>[71417.962171] fs/cifs/dir.c: name: \SOMEDIRX
>[71417.962177] fs/cifs/inode.c: Update attributes: \SOMEDIRX inode
>0x000000009f726d03 count 1 dentry: 0x00000000d22e461a d_time 4312598279
>jiffies 4312748242
>[71417.962180] fs/cifs/inode.c: Getting info on \SOMEDIRX
>[71417.962367] fs/cifs/transport.c: Sending smb: smb_len=388 
>
>Gr, Martijn

Yeah, thats another issue indeed. Thanks for the report.

I will look into it and let you know.

Paulo





[Linux USB Devel]     [Video for Linux]     [Linux Audio Users]     [Yosemite News]     [Linux Kernel]     [Linux SCSI]

  Powered by Linux