Fixed
Created: Jul 4, 2018
Updated: Feb 11, 2019
Resolved Date: Jul 15, 2018
Found In Version: 9.0.0.17
Fix Version: 9.0.0.17
Severity: Standard
Applicable for: Wind River Linux 9
Component/s: Kernel
ffffffff82203e88 0000000000000018 ffffffff8198db87 ffffe8ffe1e02380\r\nCall Trace:\r\n [<ffffffff8198db92>] ? cpuidle_enter_state+0x142/0x390\r\n [<ffffffff8198db87>] ? cpuidle_enter_state+0x137/0x390\r\n [<ffffffff8198de17>] ? cpuidle_enter+0x17/0x20\r\n [<ffffffff810b4f83>] ? call_cpuidle+0x23/0x40\r\n [<ffffffff810b5379>] ? cpu_startup_entry+0x2a9/0x3d0\r\n [<ffffffff81bd86c0>] ? rest_init+0x230/0x240\r\n [<ffffffff8255af7f>] ? start_kernel+0x406/0x413\r\n [<ffffffff8255a29c>] ? x86_64_start_reservations+0x38/0x3a\r\n [<ffffffff8255a416>] ? x86_64_start_kernel+0x178/0x18b\r\nrcu_preempt kthread starved for 1877669 jiffies! g18446744073709551574 c18446744073709551573 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0\r\nrcu_preempt R running task 0 7 2 0x00000000\r\n ffff8800444e0000 ffff88005a42d200 0000000000000000 ffff88005a870000\r\n ffff8800623d8d58 ffffc90000067cf8 ffffffff81bdae27 0000000000000082\r\n 00000000001d8d40 ffff88005a870600 ffff8800623d8d58 ffff8800623d8d40\r\nCall Trace:\r\n [<ffffffff81bdae27>] ? __schedule+0x317/0xaf0\r\n [<ffffffff81bdb640>] schedule+0x40/0x90\r\n [<ffffffff81bdff2d>] schedule_timeout+0x1cd/0x480\r\n [<ffffffff810ebe90>] ? del_timer_sync+0xc0/0xc0\r\n [<ffffffff810e6387>] rcu_gp_kthread+0x677/0x9e0\r\n [<ffffffff810e5d10>] ? force_qs_rnp+0x1d0/0x1d0\r\n [<ffffffff8108c061>] kthread+0x101/0x120\r\n [<ffffffff8108bf60>] ? kthread_create_on_node+0x230/0x230\r\n [<ffffffff81be1a69>] ret_from_fork+0x59/0x70\r\nINFO: rcu_sched detected stalls on CPUs/tasks:\r\n\t0-...: (1 GPs behind) idle=a01/1/0 softirq=343/346 fqs=504351 \r\n\t(detected by 41, t=2054143 jiffies, g=-234, c=-235, q=1)\r\nTask dump for CPU 0:\r\nswapper/0 R running task 0 0 0 0x00000008\r\n 0000000000000001 20c49ba5e353f7cf ffffffff81f4c2e6 ffffffff81f2f21b\r\n ffffffffffffff5d ffffffff8198db92 0000000000000010 0000000000000202\r\n ffffffff82203e88 0000000000000018 ffffffff8198db87 ffffe8ffe1e02380\r\nCall Trace:\r\n [<ffffffff8198db92>] ? cpuidle_enter_state+0x142/0x390\r\n [<ffffffff8198db87>] ? cpuidle_enter_state+0x137/0x390\r\n [<ffffffff8198de17>] ? cpuidle_enter+0x17/0x20\r\n [<ffffffff810b4f83>] ? call_cpuidle+0x23/0x40\r\n [<ffffffff810b5379>] ? cpu_startup_entry+0x2a9/0x3d0\r\n [<ffffffff81bd86c0>] ? rest_init+0x230/0x240\r\n [<ffffffff8255af7f>] ? start_kernel+0x406/0x413\r\n [<ffffffff8255a29c>] ? x86_64_start_reservations+0x38/0x3a\r\n [<ffffffff8255a416>] ? x86_64_start_kernel+0x178/0x18b\r\nINFO: rcu_preempt detected stalls on CPUs/tasks:\r\n\t0-...: (1 GPs behind) idle=a01/1/0 softirq=345/346 fqs=24605 \r\n\t(detected by 21, t=2054222 jiffies, g=-42, c=-43, q=97)\r\nTask dump for CPU 0:\r\nswapper/0 R running task 0 0 0 0x00000008\r\n 0000000000000001 20c49ba5e353f7cf ffffffff81f4c2e6 ffffffff81f2f21b\r\n ffffffffffffff5d ffffffff8198db92 0000000000000010 0000000000000202\r\n ffffffff82203e88 0000000000000018 ffffffff8198db87 ffffe8ffe1e02380\r\nCall Trace:\r\n [<ffffffff8198db92>] ? cpuidle_enter_state+0x142/0x390\r\n [<ffffffff8198db87>] ? cpuidle_enter_state+0x137/0x390\r\n [<ffffffff8198de17>] ? cpuidle_enter+0x17/0x20\r\n [<ffffffff810b4f83>] ? call_cpuidle+0x23/0x40\r\n [<ffffffff810b5379>] ? cpu_startup_entry+0x2a9/0x3d0\r\n [<ffffffff81bd86c0>] ? rest_init+0x230/0x240\r\n [<ffffffff8255af7f>] ? start_kernel+0x406/0x413\r\n [<ffffffff8255a29c>] ? x86_64_start_reservations+0x38/0x3a\r\n [<ffffffff8255a416>] ? x86_64_start_kernel+0x178/0x18b\r\nrcu_preempt kthread starved for 1955674 jiffies! g18446744073709551574 c18446744073709551573 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0\r\nrcu_preempt R running task 0 7 2 0x00000000\r\n ffff8800444e0000 ffff88005a42d200 0000000000000000 ffff88005a870000\r\n ffff8800623d8d58 ffffc90000067cf8 ffffffff81bdae27 0000000000000082\r\n 00000000001d8d40 ffff88005a870600 ffff8800623d8d58 ffff8800623d8d40\r\nCall Trace:\r\n [<ffffffff81bdae27>] ? __schedule+0x317/0xaf0\r\n [<ffffffff81bdb640>] schedule+0x40/0x90\r\n [<ffffffff81bdff2d>] schedule_timeout+0x1cd/0x480\r\n [<ffffffff810ebe90>] ? del_timer_sync+0xc0/0xc0\r\n [<ffffffff810e6387>] rcu_gp_kthread+0x677/0x9e0\r\n [<ffffffff810e5d10>] ? force_qs_rnp+0x1d0/0x1d0\r\n [<ffffffff8108c061>] kthread+0x101/0x120\r\n [<ffffffff8108bf60>] ? kthread_create_on_node+0x230/0x230\r\n [<ffffffff81be1a69>] ret_from_fork+0x59/0x70\r\nINFO: rcu_sched detected stalls on CPUs/tasks:\r\n\t0-...: (1 GPs behind) idle=a01/1/0 softirq=343/346 fqs=523787 \r\n\t(detected by 21, t=2132137 jiffies, g=-234, c=-235, q=1)\r\nTask dump for CPU 0:\r\nswapper/0 R running task 0 0 0 0x00000008\r\n 0000000000000001 20c49ba5e353f7cf ffffffff81f4c2e6 ffffffff81f2f21b\r\n ffffffffffffff5d ffffffff8198db92 0000000000000010 0000000000000202\r\n ffffffff82203e88 0000000000000018 ffffffff8198db87 ffffe8ffe1e02380\r\nCall Trace:\r\n [<ffffffff8198db92>] ? cpuidle_enter_state+0x142/0x390\r\n [<ffffffff8198db87>] ? cpuidle_enter_state+0x137/0x390\r\n [<ffffffff8198de17>] ? cpuidle_enter+0x17/0x20\r\n [<ffffffff810b4f83>] ? call_cpuidle+0x23/0x40\r\n [<ffffffff810b5379>] ? cpu_startup_entry+0x2a9/0x3d0\r\n [<ffffffff81bd86c0>] ? rest_init+0x230/0x240\r\n [<ffffffff8255af7f>] ? start_kernel+0x406/0x413\r\n [<ffffffff8255a29c>] ? x86_64_start_reservations+0x38/0x3a\r\n [<ffffffff8255a416>] ? x86_64_start_kernel+0x178/0x18b\r\nINFO: rcu_preempt detected stalls on CPUs/tasks:\r\n\t0-...: (1 GPs behind) idle=a01/1/0 softirq=345/346 fqs=24605 \r\n\t(detected by 21, t=2132227 jiffies, g=-42, c=-43, q=98)\r\nTask dump for CPU 0:\r\nswapper/0 R running task 0 0 0 0x00000008\r\n 0000000000000001 20c49ba5e353f7cf ffffffff81f4c2e6 ffffffff81f2f21b\r\n ffffffffffffff5d ffffffff8198db92 0000000000000010 0000000000000202\r\n ffffffff82203e88 0000000000000018 ffffffff8198db87 ffffe8ffe1e02380\r\nCall Trace:\r\n [<ffffffff8198db92>] ? cpuidle_enter_state+0x142/0x390\r\n [<ffffffff8198db87>] ? cpuidle_enter_state+0x137/0x390\r\n [<ffffffff8198de17>] ? cpuidle_enter+0x17/0x20\r\n [<ffffffff810b4f83>] ? call_cpuidle+0x23/0x40\r\n [<ffffffff810b5379>] ? cpu_startup_entry+0x2a9/0x3d0\r\n [<ffffffff81bd86c0>] ? rest_init+0x230/0x240\r\n [<ffffffff8255af7f>] ? start_kernel+0x406/0x413\r\n [<ffffffff8255a29c>] ? x86_64_start_reservations+0x38/0x3a\r\n [<ffffffff8255a416>] ? x86_64_start_kernel+0x178/0x18b\r\nrcu_preempt kthread starved for 2033679 jiffies! g18446744073709551574 c18446744073709551573 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0\r\nrcu_preempt R running task 0 7 2 0x00000000\r\n ffff8800444e0000 ffff88005a42d200 0000000000000000 ffff88005a870000\r\n ffff8800623d8d58 ffffc90000067cf8 ffffffff81bdae27 0000000000000082\r\n 00000000001d8d40 ffff88005a870600 ffff8800623d8d58 ffff8800623d8d40\r\nCall Trace:\r\n [<ffffffff81bdae27>] ? __schedule+0x317/0xaf0\r\n [<ffffffff81bdb640>] schedule+0x40/0x90\r\n [<ffffffff81bdff2d>] schedule_timeout+0x1cd/0x480\r\n [<ffffffff810ebe90>] ? del_timer_sync+0xc0/0xc0\r\n [<ffffffff810e6387>] rcu_gp_kthread+0x677/0x9e0\r\n [<ffffffff810e5d10>] ? force_qs_rnp+0x1d0/0x1d0\r\n [<ffffffff8108c061>] kthread+0x101/0x120\r\n [<ffffffff8108bf60>] ? kthread_create_on_node+0x230/0x230\r\n [<ffffffff81be1a69>] ret_from_fork+0x59/0x70\r\n'
INFO: EXPECT : Total time spent expecting data: 2400.03 seconds
INFO:
INFO: All channels ids: dict_keys([b'00'])
INFO: All channels data:
INFO: Channel b'00'
:b''
INFO: fragRun results: {'name': '/lpg-build/cdc/WASSP_LINUX_90/testcases/wrlinux/utils/frags/login.frag', 'status': 'TIMEOUT', 'reason': ''}
INFO: -------------------------------------------------------------------------------
ERROR: Node platform level 0 runtime configuration result: FAIL
Traceback (most recent call last):
File "/lpg-build/cdc/WASSP_LINUX_90/wassp/host/htee/utils/runtimeUtils.py", line 146, in runtimeConfig
if runtimeConfigNode(testRunObj, nodeID, targetObjects, rtCfgLevel):
File "/lpg-build/cdc/WASSP_LINUX_90/wassp/host/htee/utils/runtimeUtils.py", line 107, in runtimeConfigNode
rtCfgFile)
Exception: ('Runtime config fragment failed %s', '/lpg-build/cdc/WASSP_LINUX_90/testcases/wrlinux/utils/frags/login.frag')
ERROR: FATAL - Failed runtime configuration.
INFO: DONE
INFO: Cleaning up
INFO: logs can be found in : /WRL9/log_dir/GIT_20180704/kernel/acquire_cpu_resource/intel-x86-64_platform/Canoe_Pass.standard.true.glibcstd.lpgbuildcdcWASSP_LINUX_90testcaseswrlinuxutilsfrags
INFO: cleanup: Working Directory contents retained (test did not PASS): /WRL9/build_dir//kernel/GIT_201807/acquire_cpu_resource/intel-x86-64_platform/Canoe_Pass.standard.true.glibcstd.lpgbuildcdcWASSP_LINUX_90testcaseswrlinuxutilsfrags
INFO: Main exited (31)
INFO: UTC time stamp: 2018-07-03 21:26:42
INFO: local time stamp: 2018-07-04 05:26:42
/WRL9/product_install/wrlinux-9/setup.sh --machines=intel-x86-64 --kernel=standard --machines=intel-x86-64 --kernel=standard --dl-layers --accept-eula=yes --dl-layers && source environment-setup-x86_64-wrlinuxsdk-linux && source oe-init-build-env build
bitbake-layers add-layer /lpg-build/cdc/WASSP_LINUX_90/testcases/wrlinux/wr-testing/kts-dev && echo require templates/feature/wr-kts/template.conf >> conf/local.conf && echo IMAGE_FSTYPES += \"tar.bz2\" >> conf/local.conf && echo BB_NUMBER_THREADS ?= \"24\" >> conf/local.conf && echo PARALLEL_MAKE ?= \"-j 24\" >> conf/local.conf && echo BB_NO_NETWORK_pn-lmbench = \"0\" >> conf/local.conf
bitbake wrlinux-image-glibc-std
