I had this hang in haproxy after trying out kernel 4.16.0-041600rc1
after starting haproxy for some minutes. Now I am back on kernel
4.15.0-10-generic and everything seems ok so far.

Adrian Veith

[ 9063.536247] INFO: task haproxy:1234 blocked for more than 120 seconds.
[ 9063.536334]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[ 9063.536456] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 9063.536582] haproxy         D    0  1234   1232 0x00000000
[ 9063.536586] Call Trace:
[ 9063.536595]  __schedule+0x297/0x870
[ 9063.536598]  schedule+0x2c/0x80
[ 9063.536601]  __lock_sock+0x7d/0xc0
[ 9063.536605]  ? wait_woken+0x80/0x80
[ 9063.536607]  lock_sock_nested+0x64/0x70
[ 9063.536613]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[ 9063.536617]  nf_getsockopt+0x4f/0x80
[ 9063.536619]  ip_getsockopt+0x81/0xc0
[ 9063.536622]  tcp_getsockopt+0x28/0x40
[ 9063.536624]  sock_common_getsockopt+0x1a/0x20
[ 9063.536625]  SyS_getsockopt+0x7d/0xe0
[ 9063.536629]  do_syscall_64+0x76/0x130
[ 9063.536631]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[ 9063.536633] RIP: 0033:0x7fbca66f7a0a
[ 9063.536635] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[ 9063.536637] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[ 9063.536638] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[ 9063.536639] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[ 9063.536640] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[ 9063.536641] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[ 9184.370248] INFO: task haproxy:1234 blocked for more than 120 seconds.
[ 9184.370336]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[ 9184.370457] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 9184.370583] haproxy         D    0  1234   1232 0x00000000
[ 9184.370586] Call Trace:
[ 9184.370595]  __schedule+0x297/0x870
[ 9184.370598]  schedule+0x2c/0x80
[ 9184.370601]  __lock_sock+0x7d/0xc0
[ 9184.370605]  ? wait_woken+0x80/0x80
[ 9184.370607]  lock_sock_nested+0x64/0x70
[ 9184.370614]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[ 9184.370617]  nf_getsockopt+0x4f/0x80
[ 9184.370620]  ip_getsockopt+0x81/0xc0
[ 9184.370622]  tcp_getsockopt+0x28/0x40
[ 9184.370624]  sock_common_getsockopt+0x1a/0x20
[ 9184.370626]  SyS_getsockopt+0x7d/0xe0
[ 9184.370629]  do_syscall_64+0x76/0x130
[ 9184.370632]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[ 9184.370634] RIP: 0033:0x7fbca66f7a0a
[ 9184.370635] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[ 9184.370637] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[ 9184.370638] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[ 9184.370639] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[ 9184.370640] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[ 9184.370641] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[ 9305.204082] INFO: task haproxy:1234 blocked for more than 120 seconds.
[ 9305.204170]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[ 9305.204291] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 9305.204417] haproxy         D    0  1234   1232 0x00000000
[ 9305.204421] Call Trace:
[ 9305.204430]  __schedule+0x297/0x870
[ 9305.204433]  schedule+0x2c/0x80
[ 9305.204437]  __lock_sock+0x7d/0xc0
[ 9305.204441]  ? wait_woken+0x80/0x80
[ 9305.204443]  lock_sock_nested+0x64/0x70
[ 9305.204449]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[ 9305.204452]  nf_getsockopt+0x4f/0x80
[ 9305.204455]  ip_getsockopt+0x81/0xc0
[ 9305.204457]  tcp_getsockopt+0x28/0x40
[ 9305.204459]  sock_common_getsockopt+0x1a/0x20
[ 9305.204461]  SyS_getsockopt+0x7d/0xe0
[ 9305.204464]  do_syscall_64+0x76/0x130
[ 9305.204467]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[ 9305.204469] RIP: 0033:0x7fbca66f7a0a
[ 9305.204470] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[ 9305.204473] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[ 9305.204474] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[ 9305.204475] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[ 9305.204475] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[ 9305.204476] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[ 9426.037943] INFO: task haproxy:1234 blocked for more than 120 seconds.
[ 9426.038031]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[ 9426.038151] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 9426.038277] haproxy         D    0  1234   1232 0x00000000
[ 9426.038281] Call Trace:
[ 9426.038291]  __schedule+0x297/0x870
[ 9426.038294]  schedule+0x2c/0x80
[ 9426.038298]  __lock_sock+0x7d/0xc0
[ 9426.038301]  ? wait_woken+0x80/0x80
[ 9426.038303]  lock_sock_nested+0x64/0x70
[ 9426.038309]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[ 9426.038313]  nf_getsockopt+0x4f/0x80
[ 9426.038316]  ip_getsockopt+0x81/0xc0
[ 9426.038318]  tcp_getsockopt+0x28/0x40
[ 9426.038320]  sock_common_getsockopt+0x1a/0x20
[ 9426.038322]  SyS_getsockopt+0x7d/0xe0
[ 9426.038325]  do_syscall_64+0x76/0x130
[ 9426.038328]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[ 9426.038330] RIP: 0033:0x7fbca66f7a0a
[ 9426.038331] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[ 9426.038333] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[ 9426.038334] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[ 9426.038335] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[ 9426.038336] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[ 9426.038337] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[ 9546.872473] INFO: task haproxy:1234 blocked for more than 120 seconds.
[ 9546.872561]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[ 9546.872681] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 9546.872807] haproxy         D    0  1234   1232 0x00000000
[ 9546.872811] Call Trace:
[ 9546.872821]  __schedule+0x297/0x870
[ 9546.872824]  schedule+0x2c/0x80
[ 9546.872828]  __lock_sock+0x7d/0xc0
[ 9546.872831]  ? wait_woken+0x80/0x80
[ 9546.872833]  lock_sock_nested+0x64/0x70
[ 9546.872839]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[ 9546.872843]  nf_getsockopt+0x4f/0x80
[ 9546.872846]  ip_getsockopt+0x81/0xc0
[ 9546.872848]  tcp_getsockopt+0x28/0x40
[ 9546.872850]  sock_common_getsockopt+0x1a/0x20
[ 9546.872852]  SyS_getsockopt+0x7d/0xe0
[ 9546.872855]  do_syscall_64+0x76/0x130
[ 9546.872858]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[ 9546.872860] RIP: 0033:0x7fbca66f7a0a
[ 9546.872861] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[ 9546.872864] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[ 9546.872865] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[ 9546.872866] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[ 9546.872867] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[ 9546.872868] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[ 9667.707144] INFO: task haproxy:1234 blocked for more than 120 seconds.
[ 9667.707231]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[ 9667.707352] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 9667.707478] haproxy         D    0  1234   1232 0x00000000
[ 9667.707482] Call Trace:
[ 9667.707491]  __schedule+0x297/0x870
[ 9667.707494]  schedule+0x2c/0x80
[ 9667.707497]  __lock_sock+0x7d/0xc0
[ 9667.707501]  ? wait_woken+0x80/0x80
[ 9667.707503]  lock_sock_nested+0x64/0x70
[ 9667.707509]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[ 9667.707513]  nf_getsockopt+0x4f/0x80
[ 9667.707516]  ip_getsockopt+0x81/0xc0
[ 9667.707518]  tcp_getsockopt+0x28/0x40
[ 9667.707520]  sock_common_getsockopt+0x1a/0x20
[ 9667.707522]  SyS_getsockopt+0x7d/0xe0
[ 9667.707526]  do_syscall_64+0x76/0x130
[ 9667.707528]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[ 9667.707530] RIP: 0033:0x7fbca66f7a0a
[ 9667.707531] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[ 9667.707533] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[ 9667.707534] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[ 9667.707535] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[ 9667.707536] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[ 9667.707537] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[ 9788.541727] INFO: task haproxy:1234 blocked for more than 120 seconds.
[ 9788.541815]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[ 9788.541936] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 9788.542062] haproxy         D    0  1234   1232 0x00000000
[ 9788.542065] Call Trace:
[ 9788.542074]  __schedule+0x297/0x870
[ 9788.542077]  schedule+0x2c/0x80
[ 9788.542081]  __lock_sock+0x7d/0xc0
[ 9788.542085]  ? wait_woken+0x80/0x80
[ 9788.542087]  lock_sock_nested+0x64/0x70
[ 9788.542092]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[ 9788.542096]  nf_getsockopt+0x4f/0x80
[ 9788.542099]  ip_getsockopt+0x81/0xc0
[ 9788.542101]  tcp_getsockopt+0x28/0x40
[ 9788.542103]  sock_common_getsockopt+0x1a/0x20
[ 9788.542105]  SyS_getsockopt+0x7d/0xe0
[ 9788.542109]  do_syscall_64+0x76/0x130
[ 9788.542111]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[ 9788.542113] RIP: 0033:0x7fbca66f7a0a
[ 9788.542115] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[ 9788.542117] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[ 9788.542118] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[ 9788.542119] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[ 9788.542120] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[ 9788.542121] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[ 9909.376116] INFO: task haproxy:1234 blocked for more than 120 seconds.
[ 9909.376205]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[ 9909.376326] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 9909.376452] haproxy         D    0  1234   1232 0x00000000
[ 9909.376456] Call Trace:
[ 9909.376465]  __schedule+0x297/0x870
[ 9909.376468]  schedule+0x2c/0x80
[ 9909.376471]  __lock_sock+0x7d/0xc0
[ 9909.376475]  ? wait_woken+0x80/0x80
[ 9909.376477]  lock_sock_nested+0x64/0x70
[ 9909.376483]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[ 9909.376486]  nf_getsockopt+0x4f/0x80
[ 9909.376489]  ip_getsockopt+0x81/0xc0
[ 9909.376491]  tcp_getsockopt+0x28/0x40
[ 9909.376493]  sock_common_getsockopt+0x1a/0x20
[ 9909.376495]  SyS_getsockopt+0x7d/0xe0
[ 9909.376499]  do_syscall_64+0x76/0x130
[ 9909.376501]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[ 9909.376504] RIP: 0033:0x7fbca66f7a0a
[ 9909.376505] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[ 9909.376507] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[ 9909.376508] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[ 9909.376509] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[ 9909.376510] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[ 9909.376511] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[10030.210357] INFO: task haproxy:1234 blocked for more than 120 seconds.
[10030.210445]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[10030.210566] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[10030.210692] haproxy         D    0  1234   1232 0x00000000
[10030.210696] Call Trace:
[10030.210705]  __schedule+0x297/0x870
[10030.210708]  schedule+0x2c/0x80
[10030.210711]  __lock_sock+0x7d/0xc0
[10030.210715]  ? wait_woken+0x80/0x80
[10030.210717]  lock_sock_nested+0x64/0x70
[10030.210723]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[10030.210727]  nf_getsockopt+0x4f/0x80
[10030.210730]  ip_getsockopt+0x81/0xc0
[10030.210732]  tcp_getsockopt+0x28/0x40
[10030.210734]  sock_common_getsockopt+0x1a/0x20
[10030.210736]  SyS_getsockopt+0x7d/0xe0
[10030.210739]  do_syscall_64+0x76/0x130
[10030.210742]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[10030.210744] RIP: 0033:0x7fbca66f7a0a
[10030.210745] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[10030.210747] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[10030.210748] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[10030.210749] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[10030.210750] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[10030.210751] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[10151.044481] INFO: task haproxy:1234 blocked for more than 120 seconds.
[10151.044569]       Tainted: G         C       4.16.0-041600rc1-generic
#201802120030
[10151.044690] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[10151.044815] haproxy         D    0  1234   1232 0x00000000
[10151.044820] Call Trace:
[10151.044829]  __schedule+0x297/0x870
[10151.044832]  schedule+0x2c/0x80
[10151.044836]  __lock_sock+0x7d/0xc0
[10151.044840]  ? wait_woken+0x80/0x80
[10151.044842]  lock_sock_nested+0x64/0x70
[10151.044848]  getorigdst+0x59/0x230 [nf_conntrack_ipv4]
[10151.044852]  nf_getsockopt+0x4f/0x80
[10151.044854]  ip_getsockopt+0x81/0xc0
[10151.044856]  tcp_getsockopt+0x28/0x40
[10151.044858]  sock_common_getsockopt+0x1a/0x20
[10151.044860]  SyS_getsockopt+0x7d/0xe0
[10151.044864]  do_syscall_64+0x76/0x130
[10151.044867]  entry_SYSCALL_64_after_hwframe+0x21/0x86
[10151.044869] RIP: 0033:0x7fbca66f7a0a
[10151.044870] RSP: 002b:00007ffccfe718b8 EFLAGS: 00000246 ORIG_RAX:
0000000000000037
[10151.044872] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fbca66f7a0a
[10151.044873] RDX: 0000000000000050 RSI: 0000000000000000 RDI:
0000000000000001
[10151.044874] RBP: 000055c721ace048 R08: 00007ffccfe718cc R09:
0000000000000001
[10151.044875] R10: 000055c721ace048 R11: 0000000000000246 R12:
0000000000000000
[10151.044876] R13: 0000000000000001 R14: 00007ffccfe718cc R15:
0000000000908002
[48739.429757] TCP: request_sock_TCP: Possible SYN flooding on port 25.
Sending cookies.  Check SNMP counters.
[48739.469994] TCP: request_sock_TCP: Possible SYN flooding on port 80.
Sending cookies.  Check SNMP counters.


Reply via email to