On 08.10.19 17:21, Jan Kiszka wrote: > Hi Philippe, > > just a note, probably harmless (instrumentation issue), but it may > prevent debugging real issues. This is ipipe-core-4.19.55-arm64-2 with > lock validation on: > > [ 0.542009] CPU1: Booted secondary processor 0x0000000001 [0x411fd070] > [ 0.542338] ------------[ cut here ]------------ > [ 0.542900] IRQs not enabled as expected > [ 0.543377] WARNING: CPU: 1 PID: 0 at ../kernel/time/tick-sched.c:982 > tick_nohz_idle_enter+0xa0/0xb0 > [ 0.543725] Modules linked in: > [ 0.544086] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.19.55+ #3 > [ 0.544325] Hardware name: linux,dummy-virt (DT) > [ 0.544526] I-pipe domain: Linux > [ 0.544799] pstate: 60000005 (nZCv daif -PAN -UAO) > [ 0.545002] pc : tick_nohz_idle_enter+0xa0/0xb0 > [ 0.545190] lr : tick_nohz_idle_enter+0xa0/0xb0 > [ 0.545373] sp : ffff00000a303f20 > [ 0.545526] x29: ffff00000a303f20 x28: 0000000000000000 > [ 0.545807] x27: 0000000000000000 x26: 0000000000000000 > [ 0.546122] x25: 0000000000000000 x24: 0000000000000000 > [ 0.546497] x23: 0000000000000000 x22: ffff0000092df000 > [ 0.546863] x21: 0000000000000001 x20: 0000000000000001 > [ 0.547225] x19: 0000000000000087 x18: ffffffffffffffff > [ 0.547587] x17: 0000000000001086 x16: 0000000000001086 > [ 0.547948] x15: ffff0000092df808 x14: 0720072007200720 > [ 0.548441] x13: 00000000425b018d x12: 0720072007200720 > [ 0.548826] x11: 0720072007200720 x10: 0000000000000000 > [ 0.549230] x9 : 0000000000000080 x8 : 0000000000000000 > [ 0.549597] x7 : 0000000000000000 x6 : 0000000000000002 > [ 0.549966] x5 : 0000000000000000 x4 : 0000000000000000 > [ 0.550339] x3 : ffff0000092c25e8 x2 : ffff0000092fffc0 > [ 0.550708] x1 : d421eb0c5053da00 x0 : 0000000000000000 > [ 0.551150] Call trace: > [ 0.551348] tick_nohz_idle_enter+0xa0/0xb0 > [ 0.551602] do_idle+0x38/0x2c8 > [ 0.551810] cpu_startup_entry+0x20/0x28 > [ 0.552043] secondary_start_kernel+0x1bc/0x200 > [ 0.552331] irq event stamp: 2 > [ 0.552709] hardirqs last enabled at (1): [<ffff0000080961ac>] > secondary_start_kernel+0x1ac/0x200 > [ 0.553164] hardirqs last disabled at (2): [<ffff000008c35574>] > _raw_spin_lock_irqsave+0x24/0x70 > [ 0.553607] softirqs last enabled at (0): [<ffff0000080e2cb8>] > copy_process.isra.6.part.7+0x4c0/0x1ac8 > [ 0.554091] softirqs last disabled at (0): [<0000000000000000>] > (null) > [ 0.554533] ---[ end trace 838ab3280ac2c8d6 ]--- > > Found while debugging my intret hook. Maybe I can look into that > afterwards, but maybe you already have an idea. > > Jan >
And this is happens when the gdb test is running, so far without any patch of mine: [ 280.116319] ------------[ cut here ]------------ [ 280.118015] DEBUG_LOCKS_WARN_ON(!irqs_disabled() && !hard_irqs_disabled()) [ 280.118832] WARNING: CPU: 2 PID: 1671 at ../kernel/locking/lockdep.c:2862 lockdep_hardirqs_on+0x15c/0x190 [ 280.119665] Modules linked in: crc32_ce crct10dif_ce [ 280.120344] CPU: 2 PID: 1671 Comm: smokey Tainted: G W 4.19.55+ #3 [ 280.120743] Hardware name: linux,dummy-virt (DT) [ 280.121011] I-pipe domain: Linux [ 280.121575] pstate: 40000345 (nZcv DAiF -PAN -UAO) [ 280.121859] pc : lockdep_hardirqs_on+0x15c/0x190 [ 280.122120] lr : lockdep_hardirqs_on+0x15c/0x190 [ 280.122369] sp : ffff00000d043c70 [ 280.122569] x29: ffff00000d043c70 x28: ffff800035d0da00 [ 280.122981] x27: 0000000000000000 x26: 0000000000000000 [ 280.123340] x25: ffff00000949e858 x24: 0000ffffbf6dcee8 [ 280.123716] x23: ffff000008085f00 x22: 0000000000000000 [ 280.124065] x21: ffff000008085e28 x20: ffff000008085f88 [ 280.124407] x19: ffff00000a079000 x18: ffffffffffffffff [ 280.124981] x17: 0000000000000000 x16: 0000000000000000 [ 280.129883] x15: 00000000000003e5 x14: 0000000000000400 [ 280.130501] x13: 0000000000000000 x12: ffff000008c61e68 [ 280.131131] x11: 0000000000000001 x10: ffff00000d043708 [ 280.132029] x9 : 0000000000000000 x8 : ffff0000092df808 [ 280.132751] x7 : ffff000008152f98 x6 : 0000000000000000 [ 280.133746] x5 : 0000000000000000 x4 : 0000000000000001 [ 280.134369] x3 : 0000000000000007 x2 : 0000000000000006 [ 280.134991] x1 : d421eb0c5053da00 x0 : 0000000000000000 [ 280.135709] Call trace: [ 280.136046] lockdep_hardirqs_on+0x15c/0x190 [ 280.136477] trace_hardirqs_on+0xf0/0x208 [ 280.136923] send_user_sigtrap+0x78/0xc0 [ 280.137706] brk_handler+0x118/0x130 [ 280.138087] do_debug_exception+0x114/0x268 [ 280.138500] el0_dbg+0x14/0x1c [ 280.138918] irq event stamp: 2264 [ 280.139341] hardirqs last enabled at (2263): [<ffff0000082e8a4c>] mem_cgroup_commit_charge+0x74/0x458 [ 280.140162] hardirqs last disabled at (2264): [<ffff000008081a2c>] do_debug_exception+0x1cc/0x268 [ 280.141004] softirqs last enabled at (2180): [<ffff00000808212c>] __do_softirq+0x2dc/0x480 [ 280.142263] softirqs last disabled at (2161): [<ffff0000080ed2c4>] irq_exit+0x13c/0x148 [ 280.143035] ---[ end trace 838ab3280ac2c8d8 ]--- No longer sure if this is also harmless. Jan -- Siemens AG, Corporate Technology, CT RDA IOT SES-DE Corporate Competence Center Embedded Linux
