Fixed
Created: Nov 8, 2015
Updated: Dec 3, 2018
Resolved Date: Jan 4, 2016
Found In Version: 8.0,8.0.0.0.LB06
Fix Version: 8.0.0.1
Severity: Standard
Applicable for: Wind River Linux 8
Component/s: BSP
intel-x86-64 default preempt-rt config project.
VLM boards.(22412 and 22603) .
22412’s reproduce frequency is more than 22603’s.
Repeat do A-C on VLM board ,system boot hung sometimes.
A VLM power off
B Wait one minute .
C VLM Power on.
Log location(detail log is attached.)
======================
Key type dns_resolver registered
Btrfs loaded
console [netcon0] enabled
netconsole: network logging started
rtc_cmos 00:00: setting system clock to 2015-11-08 03:21:01 UTC (1446952861)
usb 1-9: new full-speed USB device number 3 using xhci_hcd
IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
usb 1-9: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes
input: American Megatrends Inc. Virtual Keyboard and Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9:1.0/0003:046B:FF10.0002/input/input3
hid-generic 0003:046B:FF10.0002: input: USB HID v1.10 Keyboard [American Megatrends Inc. Virtual Keyboard and Mouse] on usb-0000:00:14.0-9/input0
input: American Megatrends Inc. Virtual Keyboard and Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9:1.1/0003:046B:FF10.0003/input/input4
hid-generic 0003:046B:FF10.0003: input: USB HID v1.10 Mouse [American Megatrends Inc. Virtual Keyboard and Mouse] on usb-0000:00:14.0-9/input1
ixgbe 0000:02:00.0: registered PHC device on eth2
IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
ixgbe 0000:02:00.1: registered PHC device on eth3
IPv6: ADDRCONF(NETDEV_UP): eth3: link is not ready
igb 0000:04:00.0 eth0: igb: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
random: nonblocking pool is initialized
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (47 GPs behind) idle=dcc/0/0 softirq=0/0 fqs=1
(detected by 7, t=21002 jiffies, g=-249, c=-250, q=157)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 20999 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=e22/0/0 softirq=0/0 fqs=0
(detected by 56, t=21002 jiffies, g=-248, c=-249, q=164)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 21002 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=f2a/0/0 softirq=0/0 fqs=1
(detected by 26, t=84007 jiffies, g=-248, c=-249, q=184)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 63005 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=fa0/0/0 softirq=0/0 fqs=0
(detected by 7, t=21002 jiffies, g=-247, c=-248, q=197)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 21002 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=0e8/0/0 softirq=0/0 fqs=1
(detected by 7, t=84007 jiffies, g=-247, c=-248, q=218)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 63005 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=156/0/0 softirq=0/0 fqs=0
(detected by 20, t=21002 jiffies, g=-246, c=-247, q=71)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 21002 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=29a/0/0 softirq=0/0 fqs=1
(detected by 7, t=84007 jiffies, g=-246, c=-247, q=92)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 63005 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=30a/0/0 softirq=0/0 fqs=0
(detected by 7, t=21002 jiffies, g=-245, c=-246, q=76)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 21002 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=45a/0/0 softirq=0/0 fqs=1
(detected by 19, t=84007 jiffies, g=-245, c=-246, q=118)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 63005 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=4ca/0/0 softirq=0/0 fqs=0
(detected by 7, t=21002 jiffies, g=-244, c=-245, q=100)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 21002 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=616/0/0 softirq=0/0 fqs=1
(detected by 19, t=84007 jiffies, g=-244, c=-245, q=129)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 63005 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=688/0/0 softirq=0/0 fqs=0
(detected by 62, t=21002 jiffies, g=-243, c=-244, q=114)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 21002 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=7d2/0/0 softirq=0/0 fqs=1
(detected by 56, t=84007 jiffies, g=-243, c=-244, q=135)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 63005 jiffies!
INFO: rcu_preempt detected stalls on CPUs/tasks:
43: (0 ticks this GP) idle=840/0/0 softirq=0/0 fqs=0
(detected by 55, t=21002 jiffies, g=-242, c=-243, q=107)
Task dump for CPU 43:
swapper/43 R running task 0 0 1 0x00200000
ffffffff8145cd77 0000000000000004 0000000000000004 ffffe8f7ffa41f90
ffffffff820b8240 ffff880859147ea8 ffffffff817fecb5 0000000000000000
0000000000000000 0000000000000046 ffffffff8213b120 ffffe8f7ffa41f90
Call Trace:
[<ffffffff8145cd77>] ? intel_idle+0x137/0x140
[<ffffffff817fecb5>] ? cpuidle_enter_state+0x65/0x280
[<ffffffff817fef07>] ? cpuidle_enter+0x17/0x20
[<ffffffff81096b2d>] ? cpu_startup_entry+0x30d/0x4a0
[<ffffffff8103a5b9>] ? start_secondary+0x129/0x130
rcu_preempt kthread starved for 21002 jiffies!
wrlinux-8/wrlinux/configure --enable-board=intel-x86-64 --enable-kernel=preempt-rt --enable-rootfs=glibc-std --enable-jobs=24 --enable-parallel-pkgbuilds=24 --enable-reconfig=yes --enable-internet-download=yes
make fs
Target (barcode : 22412 or 22603)
22412’s reproduce frequency is more than 22603’s.
Repeat do A-C, sometimes can boot hung.
A VLM power off
B Wait one minute .
C VLM Power on.
