netdev - Re: [4.10+] sctp lockdep trace

lists.openwall.net		lists / announce owl-users owl-dev john-users john-dev passwdqc-users yescrypt popa3d-users / oss-security kernel-hardening musl sabotage tlsify passwords / crypt-dev xvendor / Bugtraq Full-Disclosure linux-kernel linux-netdev linux-ext4 linux-hardening linux-cve-announce PHC
Open Source and information security mailing list archives
Hash Suite for Android: free password hash cracker in your pocket
[<prev] [next>] [<thread-prev] [thread-next>] [day] [month] [year] [list]
Date:   Mon, 13 Mar 2017 17:11:56 -0300
From:   Marcelo Ricardo Leitner <marcelo.leitner@...il.com>
To:     Dave Jones <davej@...emonkey.org.uk>
Cc:     netdev@...r.kernel.org, linux-sctp@...r.kernel.org,
        Xin Long <lucien.xin@...il.com>
Subject: Re: [4.10+] sctp lockdep trace

On Mon, Mar 13, 2017 at 05:10:45PM -0300, Marcelo Ricardo Leitner wrote:
> On Fri, Feb 24, 2017 at 05:21:10PM -0500, Dave Jones wrote:
> > [  244.251557] ===============================
> > [  244.263321] [ ERR: suspicious RCU usage.  ]
> > [  244.274982] 4.10.0-think+ #7 Not tainted
> > [  244.286511] -------------------------------
> > [  244.298008] ./include/linux/rhashtable.h:602 suspicious rcu_dereference_check() usage!
> > [  244.309665] 
> >                other info that might help us debug this:
> > 
> > [  244.344629] 
> >                rcu_scheduler_active = 2, debug_locks = 1
> > [  244.367839] 1 lock held by trinity-c30/1781:
> > [  244.379481]  #0: 
> > [  244.390848]  (
> > [  244.402372] sk_lock-AF_INET
> > [  244.413825] ){+.+.+.}
> > [  244.425231] , at: [<ffffffffa0302fd0>] sctp_sendmsg+0x330/0xfe0 [sctp]
> > [  244.436774] 
> >                stack backtrace:
> > [  244.459620] CPU: 3 PID: 1781 Comm: trinity-c30 Not tainted 4.10.0-think+ #7 
> > [  244.482790] Call Trace:
> > [  244.494201]  dump_stack+0x68/0x93
> > [  244.505598]  lockdep_rcu_suspicious+0xce/0xf0
> > [  244.516924]  sctp_hash_transport+0x406/0x7e0 [sctp]
> > [  244.528137]  ? sctp_endpoint_bh_rcv+0x171/0x290 [sctp]
> > [  244.539243]  sctp_assoc_add_peer+0x290/0x3c0 [sctp]
> > [  244.550291]  sctp_sendmsg+0x8f7/0xfe0 [sctp]
> > [  244.561258]  ? rw_copy_check_uvector+0x8e/0x190
> > [  244.572308]  ? import_iovec+0x3a/0xe0
> > [  244.583232]  inet_sendmsg+0x49/0x1e0
> > [  244.594150]  ___sys_sendmsg+0x2d4/0x300
> > [  244.605002]  ? debug_smp_processor_id+0x17/0x20
> > [  244.615844]  ? debug_smp_processor_id+0x17/0x20
> > [  244.626533]  ? get_lock_stats+0x19/0x50
> > [  244.637141]  __sys_sendmsg+0x54/0x90
> > [  244.647817]  SyS_sendmsg+0x12/0x20
> > [  244.658400]  do_syscall_64+0x66/0x1d0
> > [  244.668990]  entry_SYSCALL64_slow_path+0x25/0x25
> > [  244.679582] RIP: 0033:0x7fe095fcb0f9
> > [  244.690079] RSP: 002b:00007ffc5601b1d8 EFLAGS: 00000246
> > [  244.700704]  ORIG_RAX: 000000000000002e
> > [  244.711248] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007fe095fcb0f9
> > [  244.721818] RDX: 0000000000000080 RSI: 00005592de12ddc0 RDI: 000000000000012d
> > [  244.732282] RBP: 00007fe0965c8000 R08: 000000000000c000 R09: 00000000000000dc
> > [  244.742576] R10: 0003021200000088 R11: 0000000000000246 R12: 0000000000000002
> > [  244.752804] R13: 00007fe0965c8048 R14: 00007fe0966a1ad8 R15: 00007fe0965c8000
> > 
> > [  244.775549] ===============================
> > [  244.785875] [ ERR: suspicious RCU usage.  ]
> > [  244.796951] 4.10.0-think+ #7 Not tainted
> > [  244.807185] -------------------------------
> > [  244.819213] ./include/linux/rhashtable.h:605 suspicious rcu_dereference_check() usage!
> > [  244.829420] 
> >                other info that might help us debug this:
> > 
> > [  244.859963] 
> >                rcu_scheduler_active = 2, debug_locks = 1
> > [  244.879766] 1 lock held by trinity-c30/1781:
> > [  244.889953]  #0: 
> > [  244.900000]  (
> > [  244.909854] sk_lock-AF_INET
> > [  244.919645] ){+.+.+.}
> > [  244.929238] , at: [<ffffffffa0302fd0>] sctp_sendmsg+0x330/0xfe0 [sctp]
> > [  244.939167] 
> >                stack backtrace:
> > [  244.958506] CPU: 3 PID: 1781 Comm: trinity-c30 Not tainted 4.10.0-think+ #7 
> > [  244.978102] Call Trace:
> > [  244.987735]  dump_stack+0x68/0x93
> > [  244.997112]  lockdep_rcu_suspicious+0xce/0xf0
> > [  245.006588]  sctp_hash_transport+0x4ca/0x7e0 [sctp]
> > [  245.016264]  ? sctp_endpoint_bh_rcv+0x171/0x290 [sctp]
> > [  245.025797]  sctp_assoc_add_peer+0x290/0x3c0 [sctp]
> > [  245.035380]  sctp_sendmsg+0x8f7/0xfe0 [sctp]
> > [  245.044883]  ? rw_copy_check_uvector+0x8e/0x190
> > [  245.054464]  ? import_iovec+0x3a/0xe0
> > [  245.064016]  inet_sendmsg+0x49/0x1e0
> > [  245.073516]  ___sys_sendmsg+0x2d4/0x300
> > [  245.082967]  ? debug_smp_processor_id+0x17/0x20
> > [  245.092448]  ? debug_smp_processor_id+0x17/0x20
> > [  245.101850]  ? get_lock_stats+0x19/0x50
> > [  245.111170]  __sys_sendmsg+0x54/0x90
> > [  245.120451]  SyS_sendmsg+0x12/0x20
> > [  245.129649]  do_syscall_64+0x66/0x1d0
> > [  245.138783]  entry_SYSCALL64_slow_path+0x25/0x25
> > [  245.147678] RIP: 0033:0x7fe095fcb0f9
> > [  245.156588] RSP: 002b:00007ffc5601b1d8 EFLAGS: 00000246
> > [  245.165503]  ORIG_RAX: 000000000000002e
> > [  245.174601] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007fe095fcb0f9
> > [  245.183861] RDX: 0000000000000080 RSI: 00005592de12ddc0 RDI: 000000000000012d
> > [  245.193038] RBP: 00007fe0965c8000 R08: 000000000000c000 R09: 00000000000000dc
> > [  245.202214] R10: 0003021200000088 R11: 0000000000000246 R12: 0000000000000002
> > [  245.211261] R13: 00007fe0965c8048 R14: 00007fe0966a1ad8 R15: 00007fe0965c8000
> > 
> > [  245.308216] ===============================
> > [  245.317295] [ ERR: suspicious RCU usage.  ]
> > [  245.327876] 4.10.0-think+ #7 Not tainted
> > [  245.337065] -------------------------------
> > [  245.345840] ./include/linux/rhashtable.h:616 suspicious rcu_dereference_check() usage!
> > [  245.356501] 
> >                other info that might help us debug this:
> > 
> > [  245.382185] 
> >                rcu_scheduler_active = 2, debug_locks = 1
> > [  245.399415] 1 lock held by trinity-c30/1781:
> > [  245.408138]  #0: 
> > [  245.416594]  (
> > [  245.424928] sk_lock-AF_INET
> > [  245.433279] ){+.+.+.}
> > [  245.441889] , at: [<ffffffffa0302fd0>] sctp_sendmsg+0x330/0xfe0 [sctp]
> > [  245.450167] 
> >                stack backtrace:
> > [  245.466352] CPU: 3 PID: 1781 Comm: trinity-c30 Not tainted 4.10.0-think+ #7 
> > [  245.482894] Call Trace:
> > [  245.491096]  dump_stack+0x68/0x93
> > [  245.499314]  lockdep_rcu_suspicious+0xce/0xf0
> > [  245.507610]  sctp_hash_transport+0x6c0/0x7e0 [sctp]
> > [  245.515972]  ? sctp_endpoint_bh_rcv+0x171/0x290 [sctp]
> > [  245.524366]  sctp_assoc_add_peer+0x290/0x3c0 [sctp]
> > [  245.532736]  sctp_sendmsg+0x8f7/0xfe0 [sctp]
> > [  245.541040]  ? rw_copy_check_uvector+0x8e/0x190
> > [  245.549402]  ? import_iovec+0x3a/0xe0
> > [  245.557679]  inet_sendmsg+0x49/0x1e0
> > [  245.565887]  ___sys_sendmsg+0x2d4/0x300
> > [  245.574092]  ? debug_smp_processor_id+0x17/0x20
> > [  245.582342]  ? debug_smp_processor_id+0x17/0x20
> > [  245.590508]  ? get_lock_stats+0x19/0x50
> > [  245.598641]  __sys_sendmsg+0x54/0x90
> > [  245.606745]  SyS_sendmsg+0x12/0x20
> > [  245.614784]  do_syscall_64+0x66/0x1d0
> > [  245.622828]  entry_SYSCALL64_slow_path+0x25/0x25
> > [  245.630894] RIP: 0033:0x7fe095fcb0f9
> > [  245.638962] RSP: 002b:00007ffc5601b1d8 EFLAGS: 00000246
> > [  245.647071]  ORIG_RAX: 000000000000002e
> > [  245.655186] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007fe095fcb0f9
> > [  245.663435] RDX: 0000000000000080 RSI: 00005592de12ddc0 RDI: 000000000000012d
> > [  245.671776] RBP: 00007fe0965c8000 R08: 000000000000c000 R09: 00000000000000dc
> > [  245.680111] R10: 0003021200000088 R11: 0000000000000246 R12: 0000000000000002
> > [  245.688460] R13: 00007fe0965c8048 R14: 00007fe0966a1ad8 R15: 00007fe0965c8000
> > 
> 
> Cc'ing Xin and linux-sctp@ mailing list.

Seems the same as Andrey Konovalov had reported?