Linux/AM3352: kernel rcu detect lockdep

user5321888

Part Number: AM3352
Other Parts Discussed in Thread: TPS65218

Tool/software: Linux

Hi All,
We would debug a kernel lockup issue in AM335x Kernel 4.4.113, so we selected some Lockups&Hangs Detect options in Kernel menuconfig.
But now the kernel throw out " [ INFO: suspicious RCU usage. ] " when startup.
Will it also affect the stability of the kernel?
How can i fix this firstly.

Below is part of the log, see the appendix in detail.
[ 21.517337] ===============================
[ 21.521759] [ INFO: suspicious RCU usage. ]
[ 21.526095] 4.4.113 #4 Not tainted
[ 21.529546] -------------------------------
[ 21.533874] net/ipv4/cipso_ipv4.c:1940 suspicious rcu_dereference_protected() usage!
[ 21.541766]
[ 21.541766] other info that might help us debug this:
[ 21.541766]
[ 21.549940]
[ 21.549940] rcu_scheduler_active = 1, debug_locks = 0
[ 21.556621] 3 locks held by swapper/0/1:
[ 21.560676] #0: (net_mutex){+.+.+.}, at: [<c0964a14>] register_pernet_subsys+0x30/0x50
[ 21.569181] #1: (slock-AF_INET/1){+.....}, at: [<c0608f1c>] smack_netlabel+0x3c/0xa0
[ 21.577515] #2: (rcu_read_lock){......}, at: [<c0b0ecd4>] netlbl_sock_setattr+0x0/0x17c
[ 21.586187]
[ 21.586187] stack backtrace:
[ 21.590701] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.4.113 #4
[ 21.596769] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 21.602988] [<c001c5bc>] (unwind_backtrace) from [<c00163e8>] (show_stack+0x20/0x24)
[ 21.610833] [<c00163e8>] (show_stack) from [<c0692008>] (dump_stack+0xa0/0xcc)
[ 21.618148] [<c0692008>] (dump_stack) from [<c00a4360>] (lockdep_rcu_suspicious+0xe8/0x11c)
[ 21.626605] [<c00a4360>] (lockdep_rcu_suspicious) from [<c0a5d9c8>] (cipso_v4_sock_setattr+0x188/0x18c)
[ 21.636100] [<c0a5d9c8>] (cipso_v4_sock_setattr) from [<c0b0edd8>] (netlbl_sock_setattr+0x104/0x17c)
[ 21.645327] [<c0b0edd8>] (netlbl_sock_setattr) from [<c0608f70>] (smack_netlabel+0x90/0xa0)
[ 21.653777] [<c0608f70>] (smack_netlabel) from [<c060dfc8>] (smack_socket_post_create+0x68/0x6c)
[ 21.662673] [<c060dfc8>] (smack_socket_post_create) from [<c05e45cc>] (security_socket_post_create+0x70/0x90)
[ 21.672709] [<c05e45cc>] (security_socket_post_create) from [<c094f29c>] (__sock_create+0x320/0x390)
[ 21.681938] [<c094f29c>] (__sock_create) from [<c094f38c>] (sock_create_kern+0x28/0x30)
[ 21.690046] [<c094f38c>] (sock_create_kern) from [<c0a31c6c>] (inet_ctl_sock_create+0x40/0x90)
[ 21.698762] [<c0a31c6c>] (inet_ctl_sock_create) from [<c0aee568>] (sctp_ctrlsock_init+0x7c/0xb0)
[ 21.707636] [<c0aee568>] (sctp_ctrlsock_init) from [<c0963f50>] (ops_init+0xc0/0x174)
[ 21.715551] [<c0963f50>] (ops_init) from [<c0964960>] (register_pernet_operations+0x124/0x1a8)
[ 21.724251] [<c0964960>] (register_pernet_operations) from [<c0964a20>] (register_pernet_subsys+0x3c/0x50)
[ 21.734016] [<c0964a20>] (register_pernet_subsys) from [<c0fcc348>] (sctp_init+0x458/0x500)
[ 21.742465] [<c0fcc348>] (sctp_init) from [<c00098fc>] (do_one_initcall+0xd8/0x22c)
[ 21.750218] [<c00098fc>] (do_one_initcall) from [<c0f76078>] (kernel_init_freeable+0x250/0x2ec)
[ 21.759019] [<c0f76078>] (kernel_init_freeable) from [<c0b18f08>] (kernel_init+0x1c/0xf8)
[ 21.767295] [<c0b18f08>] (kernel_init) from [<c0011024>] (ret_from_fork+0x14/0x30)

Sometime this RCU not come out, but the situation i described below is remained。

Fullscreen kernel-log.txt Download

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.113 (mgkiller) (gcc version 6.3.0 (crosstool-NG crosstool-ng-1.22.0) ) #4 SMP PREEMPT Wed Mar 27 19:55:01 HKT 2019
[    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: TI AM335x BeagleBone Black
[    0.000000] cma: Reserved 48 MiB at 0x9d000000
[    0.000000] Memory policy: Data cache writeback
[    0.000000] CPU: All CPU(s) started in SVC mode.
[    0.000000] AM335X ES2.1 (neon )
[    0.000000] PERCPU: Embedded 366 pages/cpu @dc9c6000 s1468288 r8192 d22656 u1499136
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 129920
[    0.000000] Kernel command line: console=ttyO0,115200n8 board=eg9041 uboot_ver=V1.0.0
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Memory: 406380K/524288K available (12155K kernel code, 915K rwdata, 3640K rodata, 36204K init, 9009K bss, 68756K reserved, 49152K cma-reserved, 0K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xe0800000 - 0xff800000   ( 496 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0f74ebc   (15796 kB)
[    0.000000]       .init : 0xc0f75000 - 0xc32d0000   (36204 kB)
[    0.000000]       .data : 0xc32d0000 - 0xc33b4dcc   ( 916 kB)
[    0.000000]        .bss : 0xc33b7000 - 0xc3c834ac   (9010 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Running RCU self tests
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  RCU lockdep checking is enabled.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000]  RCU restricting CPUs from NR_CPUS=2 to nr_cpu_ids=1.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=1
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[    0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[    0.000045] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
[    0.000129] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000329] OMAP clocksource: timer1 at 24000000 Hz
[    0.002049] clocksource_probe: no matching clocksources found
[    0.004788] WARNING: Your 'console=ttyO0' has been replaced by 'ttyS0'
[    0.004847] This ensures that you still see kernel messages. Please
[    0.004884] update your kernel commandline.
[    0.004922] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.004960] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.004996] ... MAX_LOCK_DEPTH:          48
[    0.005031] ... MAX_LOCKDEP_KEYS:        8191
[    0.005067] ... CLASSHASH_SIZE:          4096
[    0.005103] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.005139] ... MAX_LOCKDEP_CHAINS:      65536
[    0.005175] ... CHAINHASH_SIZE:          32768
[    0.005211]  memory used by lock dependency info: 5423 kB
[    0.005248]  per task-struct memory footprint: 2304 bytes
[    0.005285] ------------------------
[    0.005319] | Locking API testsuite:
[    0.005355] ----------------------------------------------------------------------------
[    0.005392]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[    0.005427]   --------------------------------------------------------------------------
[    0.005534]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.042017]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.077908]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.115107]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.152535]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.191601]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.230475]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.269422]                     double unlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.304055]                   initialize held:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.338590]                  bad unlock order:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.374502]   --------------------------------------------------------------------------
[    0.374554]               recursive read-lock:             |  ok  |             |  ok  |
[    0.386162]            recursive read-lock #2:             |  ok  |             |  ok  |
[    0.397935]             mixed read-write-lock:             |  ok  |             |  ok  |
[    0.409889]             mixed write-read-lock:             |  ok  |             |  ok  |
[    0.421648]   --------------------------------------------------------------------------
[    0.421776]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.439140]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.456595]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.474025]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.491671]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |
[    0.509236]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |
[    0.526678]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.544040]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.561525]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.579165]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.596633]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.614744]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.632867]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.650967]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.669085]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.687260]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.705613]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.723945]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.741974]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.759750]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.777629]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.795933]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.813935]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.831839]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.850033]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.867856]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.885893]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.903956]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.921999]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.939979]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.957928]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.975920]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.993994]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    1.011987]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    1.029946]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    1.047875]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    1.065953]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    1.083979]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    1.102052]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    1.119953]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    1.138015]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    1.155831]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    1.174017]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    1.191866]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    1.209941]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    1.227942]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    1.245902]       hard-irq read-recursion/123:  ok  |
[    1.251937]       soft-irq read-recursion/123:  ok  |
[    1.258021]       hard-irq read-recursion/132:  ok  |
[    1.264062]       soft-irq read-recursion/132:  ok  |
[    1.270151]       hard-irq read-recursion/213:  ok  |
[    1.276188]       soft-irq read-recursion/213:  ok  |
[    1.282269]       hard-irq read-recursion/231:  ok  |
[    1.288303]       soft-irq read-recursion/231:  ok  |
[    1.294388]       hard-irq read-recursion/312:  ok  |
[    1.300419]       soft-irq read-recursion/312:  ok  |
[    1.306511]       hard-irq read-recursion/321:  ok  |
[    1.312538]       soft-irq read-recursion/321:  ok  |
[    1.318633]   --------------------------------------------------------------------------
[    1.318682]   | Wound/wait tests |
[    1.318719]   ---------------------
[    1.318755]                   ww api failures:  ok  |  ok  |  ok  |
[    1.336710]                ww contexts mixing:  ok  |  ok  |
[    1.348460]              finishing ww context:  ok  |  ok  |  ok  |  ok  |
[    1.371935]                locking mismatches:  ok  |  ok  |  ok  |
[    1.390037]                  EDEADLK handling:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    1.450154]            spinlock nest unlocked:  ok  |
[    1.455970]   -----------------------------------------------------
[    1.456016]                                  |block | try  |context|
[    1.456053]   -----------------------------------------------------
[    1.456090]                           context:  ok  |  ok  |  ok  |
[    1.474097]                               try:  ok  |  ok  |  ok  |
[    1.491848]                             block:  ok  |  ok  |  ok  |
[    1.509570]                          spinlock:  ok  |  ok  |  ok  |
[    1.528328] -------------------------------------------------------
[    1.528378] Good, all 253 testcases passed! |
[    1.528414] ---------------------------------
[    1.528683] Calibrating delay loop... 286.46 BogoMIPS (lpj=572928)
[    1.561895] pid_max: default: 32768 minimum: 301
[    1.564559] Security Framework initialized
[    1.564624] Yama: becoming mindful.
[    1.565162] Smack:  Initializing.
[    1.565206] Smack:  Netfilter enabled.
[    1.565335] AppArmor: AppArmor disabled by boot time parameter
[    1.566747] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    1.566812] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    1.589122] Initializing cgroup subsys io
[    1.589631] Initializing cgroup subsys memory
[    1.590282] Initializing cgroup subsys devices
[    1.590728] Initializing cgroup subsys freezer
[    1.591150] Initializing cgroup subsys net_cls
[    1.591311] Initializing cgroup subsys perf_event
[    1.591468] Initializing cgroup subsys pids
[    1.592418] CPU: Testing write buffer coherency: ok
[    1.593167] ftrace: allocating 33507 entries in 99 pages
[    1.939005] CPU0: thread -1, cpu 0, socket -1, mpidr 0
[    1.939439] Setting up static identity map for 0x80008280 - 0x800082e0
[    2.002314] Brought up 1 CPUs
[    2.002397] SMP: Total of 1 processors activated (286.46 BogoMIPS).
[    2.002435] CPU: All CPU(s) started in SVC mode.
[    2.337527] evm: security.selinux
[    2.337602] evm: security.SMACK64
[    2.337637] evm: security.SMACK64EXEC
[    2.337852] evm: security.SMACK64TRANSMUTE
[    2.337893] evm: security.SMACK64MMAP
[    2.337925] evm: security.capability
[    2.343539] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    2.792178] omap_hwmod: debugss: _wait_target_disable failed
[    2.860333] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    2.860624] futex hash table entries: 256 (order: 2, 16384 bytes)
[    2.883089] xor: measuring software checksum speed
[    2.921900]    arm4regs  :   348.000 MB/sec
[    2.961889]    8regs     :   312.000 MB/sec
[    3.001902]    32regs    :   311.000 MB/sec
[    3.041895]    neon      :   475.000 MB/sec
[    3.041940] xor: using function: neon (475.000 MB/sec)
[    3.042108] pinctrl core: initialized pinctrl subsystem
[    3.073386] NET: Registered protocol family 16
[    3.117468] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    3.138807] cpuidle: using governor ladder
[    3.150789] cpuidle: using governor menu
[    3.248723] OMAP GPIO hardware version 0.1
[    3.463180] No ATAGs?
[    3.463299] hw-breakpoint: debug architecture 0x4 unsupported.
[    3.746410] raid6: neonx2   gen()   537 MB/s
[    3.813891] raid6: neonx2   xor()   333 MB/s
[    3.813938] raid6: using algorithm neonx2 gen() 537 MB/s
[    3.813974] raid6: .... xor() 333 MB/s, rmw enabled
[    3.814010] raid6: using intx1 recovery algorithm
[    3.988547] edma 49000000.edma: TI EDMA DMA engine driver
[    4.005517] SCSI subsystem initialized
[    4.011907] usbcore: registered new interface driver usbfs
[    4.013063] usbcore: registered new interface driver hub
[    4.014949] usbcore: registered new device driver usb
[    4.030779] i2c-gpio ocp:i2c@3: using pins 0 (SDA) and 1 (SCL)
[    4.034294] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/i2c0_pins, deferring probe
[    4.034923] omap_i2c 4819c000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/i2c2_pins, deferring probe
[    4.036721] pps_core: LinuxPPS API ver. 1 registered
[    4.036768] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    4.037158] PTP clock support registered
[    4.051940] Advanced Linux Sound Architecture Driver Initialized.
[    4.069660] NetLabel: Initializing
[    4.070073] NetLabel:  domain hash size = 128
[    4.070107] NetLabel:  protocols = UNLABELED CIPSOv4
[    4.071146] NetLabel:  unlabeled traffic allowed by default
[    4.074478] clocksource: Switched to clocksource timer1
[    7.002232] NET: Registered protocol family 2
[    7.016255] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    7.016628] TCP bind hash table entries: 4096 (order: 5, 180224 bytes)
[    7.023884] TCP: Hash tables configured (established 4096 bind 4096)
[    7.024678] UDP hash table entries: 256 (order: 2, 24576 bytes)
[    7.025609] UDP-Lite hash table entries: 256 (order: 2, 24576 bytes)
[    7.031189] NET: Registered protocol family 1
[    7.045624] RPC: Registered named UNIX socket transport module.
[    7.045943] RPC: Registered udp transport module.
[    7.045999] RPC: Registered tcp transport module.
[    7.046046] RPC: Registered tcp NFSv4.1 backchannel transport module.
[   11.655006] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
[   11.696999] audit: initializing netlink subsys (disabled)
[   11.698556] audit: type=2000 audit(11.100:1): initialized
[   11.937283] zbud: loaded
[   11.951542] VFS: Disk quotas dquot_6.6.0
[   11.956815] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[   12.032546] NFS: Registering the id_resolver key type
[   12.034629] Key type id_resolver registered
[   12.034707] Key type id_legacy registered
[   12.034938] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[   12.038725] fuse init (API version 7.23)
[   12.045640] SGI XFS with ACLs, security attributes, realtime, no debug enabled
[   12.271893] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[   12.280684] io scheduler noop registered
[   12.280816] io scheduler deadline registered
[   12.283280] io scheduler cfq registered (default)
[   12.294226] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[   12.312435] Serial: 8250/16550 driver, 6 ports, IRQ sharing disabled
[   12.385178] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 158, base_baud = 3000000) is a 8250
[   13.968015] console [ttyS0] enabled
[   13.987361] 48022000.serial: ttyS1 at MMIO 0x48022000 (irq = 159, base_baud = 3000000) is a 8250
[   14.011715] 481a8000.serial: ttyS4 at MMIO 0x481a8000 (irq = 160, base_baud = 3000000) is a 8250
[   14.047033] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[   14.246854] brd: module loaded
[   14.342350] vcan: Virtual CAN interface driver
[   14.347119] CAN device driver interface
[   14.368582] c_can_platform 481cc000.can: c_can_platform device registered (regs=fa1cc000, irq=165)
[   15.608567] dummy phy addr is 0x3, we always return 0x221430!
[   19.315944] libphy: 4a101000.mdio: probed
[   19.321220] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver unknown
[   19.330597] davinci_mdio 4a101000.mdio: phy[1]: device 4a101000.mdio:01, driver unknown
[   19.339338] davinci_mdio 4a101000.mdio: phy[2]: device 4a101000.mdio:02, driver unknown
[   19.348044] davinci_mdio 4a101000.mdio: phy[3]: device 4a101000.mdio:03, driver Micrel KSZ886X or KSZ8895
[   19.357829] davinci_mdio 4a101000.mdio: phy[4]: device 4a101000.mdio:04, driver unknown
[   19.366022] davinci_mdio 4a101000.mdio: phy[5]: device 4a101000.mdio:05, driver unknown
[   19.374211] davinci_mdio 4a101000.mdio: phy[6]: device 4a101000.mdio:06, driver unknown
[   19.382399] davinci_mdio 4a101000.mdio: phy[7]: device 4a101000.mdio:07, driver unknown
[   19.390586] davinci_mdio 4a101000.mdio: phy[8]: device 4a101000.mdio:08, driver unknown
[   19.398772] davinci_mdio 4a101000.mdio: phy[9]: device 4a101000.mdio:09, driver unknown
[   19.406958] davinci_mdio 4a101000.mdio: phy[10]: device 4a101000.mdio:0a, driver unknown
[   19.415717] davinci_mdio 4a101000.mdio: phy[11]: device 4a101000.mdio:0b, driver unknown
[   19.424038] davinci_mdio 4a101000.mdio: phy[12]: device 4a101000.mdio:0c, driver unknown
[   19.432321] davinci_mdio 4a101000.mdio: phy[13]: device 4a101000.mdio:0d, driver unknown
[   19.440597] davinci_mdio 4a101000.mdio: phy[14]: device 4a101000.mdio:0e, driver unknown
[   19.448873] davinci_mdio 4a101000.mdio: phy[15]: device 4a101000.mdio:0f, driver unknown
[   19.457148] davinci_mdio 4a101000.mdio: phy[16]: device 4a101000.mdio:10, driver unknown
[   19.465423] davinci_mdio 4a101000.mdio: phy[17]: device 4a101000.mdio:11, driver unknown
[   19.473991] davinci_mdio 4a101000.mdio: phy[18]: device 4a101000.mdio:12, driver unknown
[   19.482296] davinci_mdio 4a101000.mdio: phy[19]: device 4a101000.mdio:13, driver unknown
[   19.490576] davinci_mdio 4a101000.mdio: phy[20]: device 4a101000.mdio:14, driver unknown
[   19.498852] davinci_mdio 4a101000.mdio: phy[21]: device 4a101000.mdio:15, driver unknown
[   19.507129] davinci_mdio 4a101000.mdio: phy[22]: device 4a101000.mdio:16, driver unknown
[   19.515405] davinci_mdio 4a101000.mdio: phy[23]: device 4a101000.mdio:17, driver unknown
[   19.523681] davinci_mdio 4a101000.mdio: phy[24]: device 4a101000.mdio:18, driver unknown
[   19.531955] davinci_mdio 4a101000.mdio: phy[25]: device 4a101000.mdio:19, driver unknown
[   19.540229] davinci_mdio 4a101000.mdio: phy[26]: device 4a101000.mdio:1a, driver unknown
[   19.548504] davinci_mdio 4a101000.mdio: phy[27]: device 4a101000.mdio:1b, driver unknown
[   19.556779] davinci_mdio 4a101000.mdio: phy[28]: device 4a101000.mdio:1c, driver unknown
[   19.565054] davinci_mdio 4a101000.mdio: phy[29]: device 4a101000.mdio:1d, driver unknown
[   19.573331] davinci_mdio 4a101000.mdio: phy[30]: device 4a101000.mdio:1e, driver unknown
[   19.581604] davinci_mdio 4a101000.mdio: phy[31]: device 4a101000.mdio:1f, driver unknown
[   19.616755] cpsw 4a100000.ethernet: Detected MACID = 58:7a:62:c6:f6:4b
[   19.642921] cpsw 4a100000.ethernet: cpts: overflow check period 5250
[   19.665027] PPP generic driver version 2.4.2
[   19.673570] PPP BSD Compression module registered
[   19.678666] PPP Deflate Compression module registered
[   19.684059] PPP MPPE Compression module registered
[   19.689126] NET: Registered protocol family 24
[   19.695068] usbcore: registered new interface driver cdc_ether
[   19.702273] usbcore: registered new interface driver cdc_eem
[   19.708940] usbcore: registered new interface driver rndis_host
[   19.716419] usbcore: registered new interface driver cdc_ncm
[   19.723184] usbcore: registered new interface driver qmi_wwan
[   19.730247] usbcore: registered new interface driver cdc_mbim
[   19.742564] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   19.749677] ehci-pci: EHCI PCI platform driver
[   19.755475] ehci-platform: EHCI generic platform driver
[   19.763532] ehci-omap: OMAP-EHCI Host Controller driver
[   19.771347] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   19.778654] ohci-pci: OHCI PCI platform driver
[   19.784281] ohci-platform: OHCI generic platform driver
[   19.796635] usbcore: registered new interface driver cdc_wdm
[   19.803741] usbcore: registered new interface driver usb-storage
[   19.811688] usbcore: registered new interface driver usbserial
[   19.818395] usbcore: registered new interface driver usbserial_generic
[   19.827879] usbserial: USB Serial support registered for generic
[   19.834919] usbcore: registered new interface driver ftdi_sio
[   19.841597] usbserial: USB Serial support registered for FTDI USB Serial Device
[   19.850081] usbcore: registered new interface driver ipw
[   19.856278] usbserial: USB Serial support registered for IPWireless converter
[   19.864342] usbcore: registered new interface driver sierra
[   19.870877] usbserial: USB Serial support registered for Sierra USB modem
[   19.900276] 47401300.usb-phy supply vcc not found, using dummy regulator
[   19.928553] ### usb_create_hcd: musb_hc_driver musb=dc2fe010
[   19.937166] musb-hdrc musb-hdrc.0.auto: use_dma=1.
[   19.943146] musb-hdrc musb-hdrc.0.auto: Failed to request rx1.
[   19.951515] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517
[   19.972378] 47401b00.usb-phy supply vcc not found, using dummy regulator
[   19.994807] ### usb_create_hcd: musb_hc_nodma_driver musb=dc2fe010
[   20.003529] musb-hdrc musb-hdrc.1.auto: use_dma=0.
[   20.012478] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
[   20.022582] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 1
[   20.039177] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[   20.046231] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   20.053643] usb usb1: Product: MUSB HDRC host driver
[   20.058778] usb usb1: Manufacturer: Linux 4.4.113 musb-hcd-nodma
[   20.064955] usb usb1: SerialNumber: musb-hdrc.1.auto
[   20.091602] hub 1-0:1.0: USB hub found
[   20.097331] hub 1-0:1.0: 1 port detected
[   20.323109] omap_rtc 44e3e000.rtc: already running
[   20.334868] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
[   20.346464] i2c /dev entries driver
[   20.409405] i2c i2c-3: sendbytes: NAK bailout.
[   20.442139] i2c i2c-3: sendbytes: NAK bailout.
[   20.472327] i2c i2c-3: sendbytes: NAK bailout.
[   20.526352] usb 1-1: new high-speed USB device number 2 using musb-hdrc
[   20.582118] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[   20.600643] cpuidle: enable-method property 'ti,am3352' found operations
[   20.664220] mmc0: mmc_rescan_try_freq: trying to init card at 400000 Hz
[   20.678820] usb 1-1: New USB device found, idVendor=1a40, idProduct=0201
[   20.693137] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[   20.714388] usb 1-1: Product: USB 2.0 Hub [MTT]
[   20.763174] hub 1-1:1.0: USB hub found
[   20.772988] mmc0: MAN_BKOPS_EN bit is not set
[   20.783059] hub 1-1:1.0: 7 ports detected
[   20.795074] mmc0: new high speed MMC card at address 0001
[   20.880676] mmcblk0: mmc0:0001 Q2J54A 3.59 GiB 
[   20.886916] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[   20.921108] mmcblk0boot0: mmc0:0001 Q2J54A partition 1 16.0 MiB
[   20.967629] mmcblk0boot1: mmc0:0001 Q2J54A partition 2 16.0 MiB
[   20.974104] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[   21.083319]  mmcblk0: p1 p2 p3
[   21.148108] ksz886x: probe dev ok.
[   21.202848] Dummypcm probe...
[   21.206327] usb 1-1.2: new full-speed USB device number 3 using musb-hdrc
[   21.260818] davinci_evm sound: dummypcm-hifi <-> 4803c000.mcasp mapping ok
[   21.296930] Netfilter messages via NETLINK v0.30.
[   21.302645] nf_conntrack version 0.5.0 (7117 buckets, 28468 max)
[   21.319800] ctnetlink v0.93: registering with nfnetlink.
[   21.333370] nf_tables: (c) 2007-2009 Patrick McHardy <kaber@trash.net>
[   21.341521] usb 1-1.2: New USB device found, idVendor=1a86, idProduct=7523
[   21.348662] usb 1-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[   21.356748] nf_tables_compat: (c) 2012 Pablo Neira Ayuso <pablo@netfilter.org>
[   21.364259] usb 1-1.2: Product: USB2.0-Serial
[   21.380604] ip_tables: (C) 2000-2006 Netfilter Core Team
[   21.395850] arp_tables: (C) 2002 David S. Miller
[   21.412147] Initializing XFRM netlink socket
[   21.423602] NET: Registered protocol family 17
[   21.431174] NET: Registered protocol family 15
[   21.437683] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[   21.452420] can: controller area network core (rev 20120528 abi 9)
[   21.460129] NET: Registered protocol family 29
[   21.465157] can: raw protocol (rev 20120528)
[   21.470661] can: broadcast manager protocol (rev 20120528 t)
[   21.476748] can: netlink gateway (rev 20130117) max_hops=1
[   21.488836] 8021q: 802.1Q VLAN Support v1.8
[   21.495471] sctp: Hash tables configured (established 85 bind 64)
[   21.506465] usb 1-1.3: new full-speed USB device number 4 using musb-hdrc
[   21.515785] 
[   21.517337] ===============================
[   21.521759] [ INFO: suspicious RCU usage. ]
[   21.526095] 4.4.113 #4 Not tainted
[   21.529546] -------------------------------
[   21.533874] net/ipv4/cipso_ipv4.c:1940 suspicious rcu_dereference_protected() usage!
[   21.541766] 
[   21.541766] other info that might help us debug this:
[   21.541766] 
[   21.549940] 
[   21.549940] rcu_scheduler_active = 1, debug_locks = 0
[   21.556621] 3 locks held by swapper/0/1:
[   21.560676]  #0:  (net_mutex){+.+.+.}, at: [<c0964a14>] register_pernet_subsys+0x30/0x50
[   21.569181]  #1:  (slock-AF_INET/1){+.....}, at: [<c0608f1c>] smack_netlabel+0x3c/0xa0
[   21.577515]  #2:  (rcu_read_lock){......}, at: [<c0b0ecd4>] netlbl_sock_setattr+0x0/0x17c
[   21.586187] 
[   21.586187] stack backtrace:
[   21.590701] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.4.113 #4
[   21.596769] Hardware name: Generic AM33XX (Flattened Device Tree)
[   21.602988] [<c001c5bc>] (unwind_backtrace) from [<c00163e8>] (show_stack+0x20/0x24)
[   21.610833] [<c00163e8>] (show_stack) from [<c0692008>] (dump_stack+0xa0/0xcc)
[   21.618148] [<c0692008>] (dump_stack) from [<c00a4360>] (lockdep_rcu_suspicious+0xe8/0x11c)
[   21.626605] [<c00a4360>] (lockdep_rcu_suspicious) from [<c0a5d9c8>] (cipso_v4_sock_setattr+0x188/0x18c)
[   21.636100] [<c0a5d9c8>] (cipso_v4_sock_setattr) from [<c0b0edd8>] (netlbl_sock_setattr+0x104/0x17c)
[   21.645327] [<c0b0edd8>] (netlbl_sock_setattr) from [<c0608f70>] (smack_netlabel+0x90/0xa0)
[   21.653777] [<c0608f70>] (smack_netlabel) from [<c060dfc8>] (smack_socket_post_create+0x68/0x6c)
[   21.662673] [<c060dfc8>] (smack_socket_post_create) from [<c05e45cc>] (security_socket_post_create+0x70/0x90)
[   21.672709] [<c05e45cc>] (security_socket_post_create) from [<c094f29c>] (__sock_create+0x320/0x390)
[   21.681938] [<c094f29c>] (__sock_create) from [<c094f38c>] (sock_create_kern+0x28/0x30)
[   21.690046] [<c094f38c>] (sock_create_kern) from [<c0a31c6c>] (inet_ctl_sock_create+0x40/0x90)
[   21.698762] [<c0a31c6c>] (inet_ctl_sock_create) from [<c0aee568>] (sctp_ctrlsock_init+0x7c/0xb0)
[   21.707636] [<c0aee568>] (sctp_ctrlsock_init) from [<c0963f50>] (ops_init+0xc0/0x174)
[   21.715551] [<c0963f50>] (ops_init) from [<c0964960>] (register_pernet_operations+0x124/0x1a8)
[   21.724251] [<c0964960>] (register_pernet_operations) from [<c0964a20>] (register_pernet_subsys+0x3c/0x50)
[   21.734016] [<c0964a20>] (register_pernet_subsys) from [<c0fcc348>] (sctp_init+0x458/0x500)
[   21.742465] [<c0fcc348>] (sctp_init) from [<c00098fc>] (do_one_initcall+0xd8/0x22c)
[   21.750218] [<c00098fc>] (do_one_initcall) from [<c0f76078>] (kernel_init_freeable+0x250/0x2ec)
[   21.759019] [<c0f76078>] (kernel_init_freeable) from [<c0b18f08>] (kernel_init+0x1c/0xf8)
[   21.767295] [<c0b18f08>] (kernel_init) from [<c0011024>] (ret_from_fork+0x14/0x30)
[   21.776264] Key type dns_resolver registered
[   21.780814] mpls_gso: MPLS GSO support
[   21.785051] omap_voltage_late_init: Voltage driver support not added
[   21.798797] ThumbEE CPU extension supported.
[   21.803404] Registering SWP/SWPB emulation handler
[   21.814481] registered taskstats version 1
[   21.819490] zswap: loaded using pool lzo/zbud
[   21.852961] Btrfs loaded
[   21.880769] usb 1-1.3: New USB device found, idVendor=1a86, idProduct=7523
[   21.894084] usb 1-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[   21.910041] usb 1-1.3: Product: USB2.0-Serial
[   22.014486] usb 1-1.4: new full-speed USB device number 5 using musb-hdrc
[   22.124954] Key type encrypted registered
[   22.130597] evm: HMAC attrs: 0x1
[   22.136814] usb 1-1.4: New USB device found, idVendor=1a86, idProduct=7523
[   22.146726] usb 1-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[   22.161982] usb 1-1.4: Product: USB2.0-Serial
[   22.247248] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 100 kHz
[   22.262157] usb 1-1.5: new full-speed USB device number 6 using musb-hdrc
[   22.330981] lm75 2-0049: hwmon0: sensor 'lm75a'
[   22.337357] omap_i2c 4819c000.i2c: bus 2 rev0.11 at 100 kHz
[   22.344313] ### usb_create_hcd: musb_hc_driver musb=dc756010
[   22.351752] musb-hdrc musb-hdrc.0.auto: use_dma=1.
[   22.367862] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
[   22.379831] musb-hdrc: MHDRC RTL version 2.0 
[   22.384815] musb-hdrc: setup fifo_mode 4
[   22.389465] usb 1-1.5: New USB device found, idVendor=1a86, idProduct=7523
[   22.396590] musb-hdrc: 28/31 max ep, 16384/16384 memory
[   22.402026] usb 1-1.5: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[   22.410528] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver
[   22.416572] usb 1-1.5: Product: USB2.0-Serial
[   22.421332] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 2
[   22.439149] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
[   22.448482] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   22.455957] usb usb2: Product: MUSB HDRC host driver
[   22.461090] usb usb2: Manufacturer: Linux 4.4.113 musb-hcd
[   22.467145] usb usb2: SerialNumber: musb-hdrc.0.auto
[   22.476921] hub 2-0:1.0: USB hub found
[   22.481141] hub 2-0:1.0: 1 port detected
[   22.498602] core: _opp_supported_by_regulators: OPP minuV: 1176000 maxuV: 1224000, not supported by regulator
[   22.509100] cpu cpu0: _opp_add: OPP not supported by regulators (720000000)
[   22.518398] core: _opp_supported_by_regulators: OPP minuV: 1234800 maxuV: 1285200, not supported by regulator
[   22.528774] cpu cpu0: _opp_add: OPP not supported by regulators (800000000)
[   22.537479] core: _opp_supported_by_regulators: OPP minuV: 1298500 maxuV: 1351500, not supported by regulator
[   22.547783] usb 1-1.6: new full-speed USB device number 7 using musb-hdrc
[   22.554806] cpu cpu0: _opp_add: OPP not supported by regulators (1000000000)
[   22.571617] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[   22.580457] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 00:06:31 UTC (946685191)
[   22.590186] of_cfs_init
[   22.593088] of_cfs_init: OK
[   22.626860] PM: Hibernation image not present or could not be loaded.
[   22.633949] ALSA device list:
[   22.636963]   #0: TI DUMMYPCM
[   22.667384] usb 1-1.6: New USB device found, idVendor=1a86, idProduct=7523
[   22.681779] usb 1-1.6: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[   22.693768] usb 1-1.6: Product: USB2.0-Serial
[   22.772873] Freeing unused kernel memory: 36204K
[   22.802313] usb 1-1.7: new full-speed USB device number 8 using musb-hdrc
[   22.874033] usb 2-1: new high-speed USB device number 2 using musb-hdrc
ROS system init ...
[   22.923937] usb 1-1.7: New USB device found, idVendor=1a86, idProduct=7523
[   22.938271] usb 1-1.7: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[   22.954056] usb 1-1.7: Product: USB2.0-Serial
[   23.075053] usb 2-1: New USB device found, idVendor=1a40, idProduct=0201
[   23.090225] usb 2-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[   23.102141] usb 2-1: Product: USB 2.0 Hub [MTT]
[   23.116686] hub 2-1:1.0: USB hub found
[   23.126236] hub 2-1:1.0: 7 ports detected
mkdir: can't create directory '/etc/ppp': File exists
[   30.955246] EXT4-fs (mmcblk0p2): recovery complete
[   30.961284] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[   31.214771] random: nonblocking pool is initialized
[   31.239919] EXT4-fs (mmcblk0p3): recovery complete
[   31.246133] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
[   32.157128] zl380tw: disagrees about version of symbol module_layout
[   32.165241] zl380tw: disagrees about version of symbol module_layout
modprobe: 'zl380tw.ko': invalid module format
[   32.216516] usbcore: registered new interface driver cdc_acm
[   32.238136] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters

over 6 years ago

0 Pavel Botev over 6 years ago

TI__Guru**** 170625 points

Hi user5321888,

Do you use AM335x TI PSDK LInux? If yes, which version?

Do you use AM335x TI board (EVM, SK, ICE, BBB) or custom board?

Regards,
Pavel

0 user5321888 over 6 years ago in reply to Pavel Botev

Intellectual 330 points

hi Pavel，sorry for my delay reply。the kernel i use maybe from kernel.org, not AM335x TI PSDK LInux, and use in a custom board.

This problem has been bothering me for a week. I guess i would encounter a lockup issue in that kernel, but not really sure.

I found 3 strange points, always come up after i let the kernel run 1 or 2 days.

~ # uptime
06:34:22 up 2 days, 5:53, 1 users, load average: 0.04, 0.43, 0.64

~ # free   // ddr memory seem ok
             total       used       free     shared    buffers     cached
Mem:        503196      94996     408200      37688        628      37744
-/+ buffers/cache:      56624     446572
Swap:            0          0          0

strange point 1: ----------------- cpu load for tasks are all 0.0% int top cmd, not change again. Load average is also no change.

user app "kone" loged a debug message to console in every one second , but at this time none output.

[rcu_sched] / [rcuc/0] and my user apps kone & /usr/bin/kbattery have run last for hours as below 'ps' cmd,

Time in "ps" cmd , would not increase, no change any more.

i guess some tasks in top are suspend or cpu in a low power state , but as this, my uart console work well still.

network ping is also ok, and my web_server can access from explorer normally.

Mem: 92584K used, 410612K free, 37688K shrd, 628K buff, 37744K cached
CPU: 0.0% usr 0.0% sys 0.0% nic 100% idle 0.0% io 0.0% irq 0.0% sirq
Load average: 0.05 0.43 0.64 1/92 26227
PID PPID USER     STAT   VSZ %VSZ CPU %CPU COMMAND
701     1 100      S    21076 4.1   0 0.0 /usr/sbin/tcsd
1119 1083 root     S    18772 3.7   0 0.0 /usr/bin/kbattery
1117 1083 root     S    18772 3.7   0 0.0 /usr/bin/kanyescalator
26159 26055 root     S    13980 2.7   0 0.0 /usr/bin/dtulceuio /dev/ttyCOM1 96
24671 1083 root     S    10236 2.0   0 0.0 /usr/bin/kdaemon
1118 1083 root     S    10200 2.0   0 0.0 /usr/bin/kvoice
1112 1083 root     S     9952 1.9   0 0.0 /usr/bin/ksp
26055 1083 root     S     9904 1.9   0 0.0 /usr/bin/kdetect 1
26189 1083 root     S     9904 1.9   0 0.0 /usr/bin/kdetect 3
1083     1 root     S     9556 1.9   0 0.0 kone
26196 1083 root     S     9120 1.8   0 0.0 /usr/bin/kdetect 2
    1     0 root     S     8140 1.6   0 0.0 /init
852     1 root     S     7764 1.5   0 0.0 /usr/bin/monitord
853     1 root     S     7764 1.5   0 0.0 /usr/bin/eventd
967     1 root     S     7592 1.5   0 0.0 /usr/bin/link_manager
1676     1 root     S     7592 1.5   0 0.0 /usr/bin/modemd2
1000     1 root     S     7072 1.4   0 0.0 /usr/bin/rdd
976     1 root     S     6704 1.3   0 0.0 /usr/bin/rollback_checkd
1065     1 root     S     6516 1.2   0 0.0 web_server --home /www --backgroun
1420     1 root     S     4384 0.8   0 0.0 /usr/sbin/sshd

~ # ps
PID   USER     TIME   COMMAND
    1 root       0:08 /init
    2 root       0:00 [kthreadd]
    3 root       0:00 [ksoftirqd/0]
    5 root       0:00 [kworker/0:0H]
    7 root       6:50 [rcu_sched]
    8 root       0:00 [rcu_bh]
    9 root      13:20 [rcuc/0]
   10 root       0:00 [migration/0]
   11 root       0:03 [watchdog/0]
   13 root       0:00 [netns]
   14 root       0:00 [kswork]
   15 root       0:00 [perf]
   16 root       0:00 [khungtaskd]
   17 root       0:00 [writeback]
   18 root       0:00 [ksmd]
   19 root       0:00 [crypto]
   20 root       0:00 [kintegrityd]
   21 root       0:00 [bioset]
   22 root       0:00 [kblockd]
   23 root       0:00 [devfreq_wq]
   24 root       0:00 [rpciod]
   25 root       0:00 [kswapd0]
   26 root       0:00 [vmstat]
   27 root       0:00 [fsnotify_mark]
   28 root       0:00 [nfsiod]
   29 root       0:00 [xfsalloc]
   30 root       0:00 [xfs_mru_cache]
   60 root       0:00 [kthrotld]
   61 root       0:00 [bioset]
   62 root       0:00 [bioset]
   63 root       0:00 [bioset]
   64 root       0:00 [bioset]
   65 root       0:00 [bioset]
   66 root       0:00 [bioset]
   67 root       0:00 [bioset]
   68 root       0:00 [bioset]
   69 root       0:00 [spi1]
   70 root       0:00 [kworker/u2:1]
117 root       0:00 [irq/179-TI-am33]
118 root       0:00 [irq/183-4803c00]
119 root       0:00 [irq/182-4803c00]
120 root       0:00 [kworker/u2:2]
121 root       0:00 [bioset]
122 root       0:00 [mmcqd/0]
123 root       0:00 [bioset]
124 root       0:00 [mmcqd/0boot0]
125 root       0:00 [bioset]
126 root       0:00 [mmcqd/0boot1]
127 root       0:00 [bioset]
136 root       0:00 [deferwq]
137 root       0:00 [irq/161-44e0b00]
138 root       0:00 [irq/162-4819c00]
139 root       0:00 [irq/189-tps6521]
378 root       0:00 [kworker/0:1H]
379 root       0:00 [jbd2/mmcblk0p2-]
380 root       0:00 [ext4-rsv-conver]
390 root       0:00 [jbd2/mmcblk0p3-]
391 root       0:00 [ext4-rsv-conver]
701 100        0:00 /usr/sbin/tcsd
843 root       0:22 syslogd -L -O /dev/console -l 8 -f /var/etc/syslog.conf
852 root       1:04 /usr/bin/monitord
853 root       0:00 /usr/bin/eventd
857 nobody     0:00 /usr/sbin/dnsmasq --conf-file=/var/etc/dnsmasq_lan0.conf
861 nobody     0:00 /usr/sbin/dnsmasq --conf-file=/var/etc/dnsmasq_lan1.conf
950 root       0:00 /usr/sbin/telnetd -p 23
967 root       0:00 /usr/bin/link_manager
976 root       0:00 /usr/bin/rollback_checkd
1000 root       0:00 /usr/bin/rdd
1065 root       0:02 web_server --home /www --background /www/web http://*:80
1083 root      16:52 kone
1112 root       0:00 /usr/bin/ksp
1117 root       0:50 /usr/bin/kanyescalator
1118 root       0:24 /usr/bin/kvoice
1119 root       8:31 /usr/bin/kbattery
1420 root       0:00 /usr/sbin/sshd
1550 root       0:00 -sh
1676 root       0:00 /usr/bin/modemd2
21350 root       0:00 [kworker/0:2]
24153 root       0:00 [kworker/0:1]
24671 root       0:00 /usr/bin/kdaemon
25415 root       0:00 [kworker/0:0]
26055 root       0:00 /usr/bin/kdetect 1
26159 root       0:00 /usr/bin/dtulceuio /dev/ttyCOM1 9600
26189 root       0:00 /usr/bin/kdetect 3
26196 root       0:00 /usr/bin/kdetect 2
26203 root       0:00 [kdetect]
26204 root       0:00 [kdetect]
26205 root       0:00 [kdetect]
26226 root       0:00 ps
~ #

strange point 2: ----------------- all the irq counter are not increase between two "cat /proc/interrupts", except 44e09000.serial (console) and 4a100000.ethernet

if i use "sleep 1" between two "cat /proc/interrupts" , gp_timer will increase 1. "sleep 10" is also only increase 1

~ # cat /proc/interrupts
           CPU0
16:   29449074      INTC 68 Level     gp_timer
20:        146      INTC 12 Level     49000000.edma_ccint
22:          1      INTC 14 Level     49000000.edma_ccerrint
26:          0      INTC 96 Level     44e07000.gpio
59:          0      INTC 98 Level     4804c000.gpio
92:          1      INTC 32 Level     481ac000.gpio
95:          1 481ac000.gpio   2 Edge      power_reset
125:          0      INTC 62 Level     481ae000.gpio
158:     981439      INTC 72 Level     44e09000.serial
161:        435      INTC 70 Level     44e0b000.i2c
162:        102      INTC 30 Level     4819c000.i2c
163:        623      INTC 64 Level     mmc0
172:          0      INTC 75 Level     rtc0
173:          0      INTC 76 Level     rtc0
176:      85728      INTC 41 Level     4a100000.ethernet
177:         76      INTC 42 Level     4a100000.ethernet
179:          0      INTC 16 Level     TI-am335x-adc
180:          0      INTC 109 Level     53100000.sham
182:          0      INTC 82 Level     4803c000.mcasp_tx
183:          0      INTC 83 Level     4803c000.mcasp_rx
184:          0      INTC 111 Level     48310000.rng
186:        531      INTC 18 Level     musb-hdrc.0.auto
187:    2439519      INTC 19 Level     musb-hdrc.1.auto
188:        255      INTC 17 Level     47400000.dma-controller
189:          0      INTC   0 Level     tps65218
IPI0:          0 CPU wakeup interrupts
IPI1:          0 Timer broadcast interrupts
IPI2:          0 Rescheduling interrupts
IPI3:          0 Function call interrupts
IPI4:          0 Single function call interrupts
IPI5:          0 CPU stop interrupts
IPI6:          0 IRQ work interrupts
IPI7:          0 completion interrupts
Err:          0

~ #

~ # //after some seconds

~ #
~ # cat /proc/interrupts
           CPU0
16:   29449074      INTC 68 Level     gp_timer
20:        146      INTC 12 Level     49000000.edma_ccint
22:          1      INTC 14 Level     49000000.edma_ccerrint
26:          0      INTC 96 Level     44e07000.gpio
59:          0      INTC 98 Level     4804c000.gpio
92:          1      INTC 32 Level     481ac000.gpio
95:          1 481ac000.gpio   2 Edge      power_reset
125:          0      INTC 62 Level     481ae000.gpio
158:     981512      INTC 72 Level     44e09000.serial
161:        435      INTC 70 Level     44e0b000.i2c
162:        102      INTC 30 Level     4819c000.i2c
163:        623      INTC 64 Level     mmc0
172:          0      INTC 75 Level     rtc0
173:          0      INTC 76 Level     rtc0
176:      85744      INTC 41 Level     4a100000.ethernet
177:         76      INTC 42 Level     4a100000.ethernet
179:          0      INTC 16 Level     TI-am335x-adc
180:          0      INTC 109 Level     53100000.sham
182:          0      INTC 82 Level     4803c000.mcasp_tx
183:          0      INTC 83 Level     4803c000.mcasp_rx
184:          0      INTC 111 Level     48310000.rng
186:        531      INTC 18 Level     musb-hdrc.0.auto
187:    2439519      INTC 19 Level     musb-hdrc.1.auto
188:        255      INTC 17 Level     47400000.dma-controller
189:          0      INTC   0 Level     tps65218
IPI0:          0 CPU wakeup interrupts
IPI1:          0 Timer broadcast interrupts
IPI2:          0 Rescheduling interrupts
IPI3:          0 Function call interrupts
IPI4:          0 Single function call interrupts
IPI5:          0 CPU stop interrupts
IPI6:          0 IRQ work interrupts
IPI7:          0 completion interrupts
Err:          0

//use sleep between two 'cat /proc/interrupts'

~ # cat /proc/interrupts
           CPU0
16:   29449081      INTC 68 Level     gp_timer
20:        146      INTC 12 Level     49000000.edma_ccint
22:          1      INTC 14 Level     49000000.edma_ccerrint
26:          0      INTC 96 Level     44e07000.gpio
59:          0      INTC 98 Level     4804c000.gpio
92:          1      INTC 32 Level     481ac000.gpio
95:          1 481ac000.gpio   2 Edge      power_reset
125:          0      INTC 62 Level     481ae000.gpio
158:     983004      INTC 72 Level     44e09000.serial
161:        435      INTC 70 Level     44e0b000.i2c
162:        102      INTC 30 Level     4819c000.i2c
163:        623      INTC 64 Level     mmc0
172:          0      INTC 75 Level     rtc0
173:          0      INTC 76 Level     rtc0
176:      86052      INTC 41 Level     4a100000.ethernet
177:         76      INTC 42 Level     4a100000.ethernet
179:          0      INTC 16 Level     TI-am335x-adc
180:          0      INTC 109 Level     53100000.sham
182:          0      INTC 82 Level     4803c000.mcasp_tx
183:          0      INTC 83 Level     4803c000.mcasp_rx
184:          0      INTC 111 Level     48310000.rng
186:        531      INTC 18 Level     musb-hdrc.0.auto
187:    2439519      INTC 19 Level     musb-hdrc.1.auto
188:        255      INTC 17 Level     47400000.dma-controller
189:          0      INTC   0 Level     tps65218
IPI0:          0 CPU wakeup interrupts
IPI1:          0 Timer broadcast interrupts
IPI2:          0 Rescheduling interrupts
IPI3:          0 Function call interrupts
IPI4:          0 Single function call interrupts
IPI5:          0 CPU stop interrupts
IPI6:          0 IRQ work interrupts
IPI7:          0 completion interrupts
Err:          0
~ # sleep 1
~ # cat /proc/interrupts
           CPU0
16:   29449082      INTC 68 Level     gp_timer
20:        146      INTC 12 Level     49000000.edma_ccint
22:          1      INTC 14 Level     49000000.edma_ccerrint
26:          0      INTC 96 Level     44e07000.gpio
59:          0      INTC 98 Level     4804c000.gpio
92:          1      INTC 32 Level     481ac000.gpio
95:          1 481ac000.gpio   2 Edge      power_reset
125:          0      INTC 62 Level     481ae000.gpio
158:     983061      INTC 72 Level     44e09000.serial
161:        435      INTC 70 Level     44e0b000.i2c
162:        102      INTC 30 Level     4819c000.i2c
163:        623      INTC 64 Level     mmc0
172:          0      INTC 75 Level     rtc0
173:          0      INTC 76 Level     rtc0
176:      86052      INTC 41 Level     4a100000.ethernet
177:         76      INTC 42 Level     4a100000.ethernet
179:          0      INTC 16 Level     TI-am335x-adc
180:          0      INTC 109 Level     53100000.sham
182:          0      INTC 82 Level     4803c000.mcasp_tx
183:          0      INTC 83 Level     4803c000.mcasp_rx
184:          0      INTC 111 Level     48310000.rng
186:        531      INTC 18 Level     musb-hdrc.0.auto
187:    2439519      INTC 19 Level     musb-hdrc.1.auto
188:        255      INTC 17 Level     47400000.dma-controller
189:          0      INTC   0 Level     tps65218
IPI0:          0 CPU wakeup interrupts
IPI1:          0 Timer broadcast interrupts
IPI2:          0 Rescheduling interrupts
IPI3:          0 Function call interrupts
IPI4:          0 Single function call interrupts
IPI5:          0 CPU stop interrupts
IPI6:          0 IRQ work interrupts
IPI7:          0 completion interrupts
Err:          0
~ #

strange point 3: ----------------- time reversed in cmd date

~ # date
Mon Jan 3 06:35:22 UTC 2000
~ #

~ # after some mintes
~ #
~ # date
Mon Jan 3 06:34:32 UTC 2000
~ #

if reboot , those three strange point will not come out, but if run 1 or 2 days they come again.

what is wrong with my Am335x Kernel? and how can't i fix them?

if need more info, pls let me konw. i will reply as soon as possible. thanks!

0 Pavel Botev over 6 years ago in reply to user5321888

TI__Guru**** 170625 points

Hello,

This kernel 4.4.113 is not tested and supported by TI. I would suggest you to try with kernel that comes with AM335x TI PSDK Linux.

You can also check if below e2e threads will be in help regarding RCU issue:

e2e.ti.com/.../694048
e2e.ti.com/.../322368
e2e.ti.com/.../630021

Regards,
Pavel

Processors

Processors forum

Linux/AM3352: kernel rcu detect lockdep