cancel
Showing results for 
Show  only  | Search instead for 
Did you mean: 
hkarimmgc
Observer
Observer
1,727 Views
Registered: ‎05-10-2018

RCU stall on using WiFi PCIe card on ZCU102 with Petalinux 2018.02

  1. Wifi module model is WPEA-121N/W. I enabled required kernel configurations.
    root@xilinx-zcu102-2018_2:~# zcat /proc/config.gz | grep ATH9
    CONFIG_ATH9K_HW=m
    CONFIG_ATH9K_COMMON=m
    CONFIG_ATH9K_COMMON_DEBUG=y
    CONFIG_ATH9K_BTCOEX_SUPPORT=y
    CONFIG_ATH9K=m
    CONFIG_ATH9K_PCI=y
    # CONFIG_ATH9K_AHB is not set
    CONFIG_ATH9K_DEBUGFS=y
    # CONFIG_ATH9K_STATION_STATISTICS is not set
    # CONFIG_ATH9K_TX99 is not set
    # CONFIG_ATH9K_DFS_CERTIFIED is not set
    # CONFIG_ATH9K_DYNACK is not set
    # CONFIG_ATH9K_WOW is not set
    CONFIG_ATH9K_RFKILL=y
    # CONFIG_ATH9K_CHANNEL_CONTEXT is not set
    CONFIG_ATH9K_PCOEM=y
    # CONFIG_ATH9K_HTC is not set
  2. When i go for scanning wifi devices, I get RCU stall on CPU0.
root@xilinx-zcu102-2018_2:~# ./iwlist wlan0 scan
[  446.916350] INFO: rcu_sched detected stalls on CPUs/tasks:
[  446.921764]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=1552 
[  446.929915]  (detected by 2, t=5254 jiffies, g=5116, c=5115, q=4)
[  446.935990] Task dump for CPU 0:
[  446.939200] iwlist          R  running task        0  2325   2295 0x00000002
[  446.946233] Call trace:
[  446.948670] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  446.953786] [<0000000000008b0b>] 0x8b0b
[  509.940345] INFO: rcu_sched detected stalls on CPUs/tasks:
[  509.945753]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=4256 
[  509.953910]  (detected by 2, t=21010 jiffies, g=5116, c=5115, q=5)
[  509.960071] Task dump for CPU 0:
[  509.963282] iwlist          R  running task        0  2325   2295 0x00000002
[  509.970315] Call trace:
[  509.972748] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  509.977868] [<0000000000008b0b>] 0x8b0b
[  572.964343] INFO: rcu_sched detected stalls on CPUs/tasks:
[  572.969748]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=4934 
[  572.977905]  (detected by 2, t=36766 jiffies, g=5116, c=5115, q=5)
[  572.984067] Task dump for CPU 0:
[  572.987277] iwlist          R  running task        0  2325   2295 0x00000002
[  572.994310] Call trace:
[  572.996742] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  573.001863] [<0000000000008b0b>] 0x8b0b
[  635.988342] INFO: rcu_sched detected stalls on CPUs/tasks:
[  635.993743]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=5607 
[  636.001900]  (detected by 2, t=52522 jiffies, g=5116, c=5115, q=5)
[  636.008062] Task dump for CPU 0:
[  636.011272] iwlist          R  running task        0  2325   2295 0x00000002
[  636.018305] Call trace:
[  636.020737] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  636.025858] [<0000000000008b0b>] 0x8b0b
[  699.012341] INFO: rcu_sched detected stalls on CPUs/tasks:
[  699.017748]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=6256 
[  699.025904]  (detected by 1, t=68278 jiffies, g=5116, c=5115, q=15)
[  699.032153] Task dump for CPU 0:
[  699.035363] iwlist          R  running task        0  2325   2295 0x00000002
[  699.042396] Call trace:
[  699.044828] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  699.049950] [<0000000000008b0b>] 0x8b0b
[  762.036340] INFO: rcu_sched detected stalls on CPUs/tasks:
[  762.041742]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=6863 
[  762.049900]  (detected by 1, t=84034 jiffies, g=5116, c=5115, q=15)
[  762.056148] Task dump for CPU 0:
[  762.059359] iwlist          R  running task        0  2325   2295 0x00000002
[  762.066392] Call trace:
[  762.068823] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  762.073945] [<0000000000008b0b>] 0x8b0b
[  825.060339] INFO: rcu_sched detected stalls on CPUs/tasks:
[  825.065738]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=7470 
[  825.073895]  (detected by 1, t=99790 jiffies, g=5116, c=5115, q=15)
[  825.080144] Task dump for CPU 0:
[  825.083354] iwlist          R  running task        0  2325   2295 0x00000002
[  825.090387] Call trace:
[  825.092819] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  825.097940] [<0000000000008b0b>] 0x8b0b
[  888.084338] INFO: rcu_sched detected stalls on CPUs/tasks:
[  888.089742]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=8077 
[  888.097899]  (detected by 1, t=115546 jiffies, g=5116, c=5115, q=15)
[  888.104235] Task dump for CPU 0:
[  888.107445] iwlist          R  running task        0  2325   2295 0x00000002
[  888.114478] Call trace:
[  888.116910] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  888.122031] [<0000000000008b0b>] 0x8b0b
[  951.108337] INFO: rcu_sched detected stalls on CPUs/tasks:
[  951.113737]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=8684 
[  951.121894]  (detected by 1, t=131302 jiffies, g=5116, c=5115, q=15)
[  951.128230] Task dump for CPU 0:
[  951.131440] iwlist          R  running task        0  2325   2295 0x00000002
[  951.138473] Call trace:
[  951.140905] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  951.146026] [<0000000000008b0b>] 0x8b0b
[ 1014.132335] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1014.137741]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=9291 
[ 1014.145898]  (detected by 1, t=147058 jiffies, g=5116, c=5115, q=16)
[ 1014.152234] Task dump for CPU 0:
[ 1014.155444] iwlist          R  running task        0  2325   2295 0x00000002
[ 1014.162477] Call trace:
[ 1014.164909] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1014.170030] [<0000000000008b0b>] 0x8b0b
[ 1077.156334] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1077.161737]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=9897 
[ 1077.169894]  (detected by 2, t=162814 jiffies, g=5116, c=5115, q=16)
[ 1077.176229] Task dump for CPU 0:
[ 1077.179440] iwlist          R  running task        0  2325   2295 0x00000002
[ 1077.186472] Call trace:
[ 1077.188905] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1077.194025] [<0000000000008b0b>] 0x8b0b
[ 1140.180333] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1140.185740]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=10504 
[ 1140.193985]  (detected by 1, t=178570 jiffies, g=5116, c=5115, q=16)
[ 1140.200320] Task dump for CPU 0:
[ 1140.203530] iwlist          R  running task        0  2325   2295 0x00000002
[ 1140.210564] Call trace:
[ 1140.212995] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1140.218116] [<0000000000008b0b>] 0x8b0b
[ 1203.204332] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1203.209736]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=11110 
[ 1203.217980]  (detected by 2, t=194326 jiffies, g=5116, c=5115, q=16)
[ 1203.224316] Task dump for CPU 0:
[ 1203.227526] iwlist          R  running task        0  2325   2295 0x00000002
[ 1203.234559] Call trace:
[ 1203.236991] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1203.242112] [<0000000000008b0b>] 0x8b0b
[ 1266.228331] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1266.233731]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=11716 
[ 1266.241975]  (detected by 2, t=210082 jiffies, g=5116, c=5115, q=16)
[ 1266.248311] Task dump for CPU 0:
[ 1266.251521] iwlist          R  running task        0  2325   2295 0x00000002
[ 1266.258554] Call trace:
[ 1266.260986] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1266.266107] [<0000000000008b0b>] 0x8b0b
[ 1329.252330] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1329.257735]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=12322 
[ 1329.265980]  (detected by 2, t=225838 jiffies, g=5116, c=5115, q=16)
[ 1329.272315] Task dump for CPU 0:
[ 1329.275525] iwlist          R  running task        0  2325   2295 0x00000002
[ 1329.282558] Call trace:
[ 1329.284990] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1329.290111] [<0000000000008b0b>] 0x8b0b

 

Complete logs:

Xilinx Zynq MP First Stage Boot Loader 
Release 2018.2   Jun 15 2018  -  04:17:37
NOTICE:  ATF running on XCZU9EG/silicon v4/RTL5.1 at 0xfffea000
NOTICE:  BL31: Secure code at 0x0
NOTICE:  BL31: Non secure code at 0x10080000
NOTICE:  BL31: v1.4(release):xilinx-v2018.1-4-g93a69a5a
NOTICE:  BL31: Built : 10:09:42, Jun 15 2018
PMUFW:  v1.0


U-Boot 2018.01 (Dec 10 2018 - 15:46:54 +0500) Xilinx ZynqMP ZCU102 rev1.0

I2C:   ready
DRAM:  4 GiB
EL Level:       EL2
Chip ID:        zu9eg
MMC:   sdhci@ff170000: 0 (SD)
*** Warning - bad CRC, using default environment

In:    serial@ff000000
Out:   serial@ff000000
Err:   serial@ff000000
Model: ZynqMP ZCU102 Rev1.0
Board: Xilinx ZynqMP
Bootmode: LVL_SHFT_SD_MODE1
Net:   ZYNQ GEM: ff0e0000, phyaddr c, interface rgmii-id

Warning: ethernet@ff0e0000 MAC addresses don't match:
Address in ROM is          00:0a:35:04:98:c5
Address in environment is  00:0a:35:00:22:01
eth0: ethernet@ff0e0000
U-BOOT for xilinx-zcu102-2018_2

ethernet@ff0e0000 Waiting for PHY auto negotiation to complete...user interrupt!
Hit any key to stop autoboot:  0 
ZynqMP> 
ZynqMP> run loadbootenv
reading uEnv.txt
457 bytes read in 18 ms (24.4 KiB/s)
ZynqMP> run importbootenv 
Importing environment from SD ...
ZynqMP> run bootkernel 
reading Image
14819840 bytes read in 971 ms (14.6 MiB/s)
reading system.dtb
41527 bytes read in 16 ms (2.5 MiB/s)
## Flattened Device Tree blob at 04000000
   Booting using the fdt blob at 0x4000000
   Loading Device Tree to 0000000007ff2000, end 0000000007fff236 ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.14.0-xilinx-v2018.2 (oe-user@oe-host) (gcc version 7.2.0 (GCC)) #1 SMP Mon Dec 10 19:13:26 PKT 2018
[    0.000000] Boot CPU: AArch64 Processor [410fd034]
[    0.000000] Machine model: ZynqMP ZCU102 Rev1.0
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] cma: Reserved 256 MiB at 0x000000006fc00000
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.1 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: MIGRATE_INFO_TYPE not supported.
[    0.000000] percpu: Embedded 21 pages/cpu @ffffffc87ff65000 s46488 r8192 d31336 u86016
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: enabling workaround for ARM erratum 845719
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1033987
[    0.000000] Kernel command line: clk_ignore_unused root=/dev/mmcblk0p2 rw rootwait
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.000000] software IO TLB [mem 0x6bc00000-0x6fc00000] (64MB) mapped at [ffffffc06bc00000-ffffffc06fbfffff]
[    0.000000] Memory: 3784340K/4193280K available (10108K kernel code, 648K rwdata, 3176K rodata, 512K init, 2170K bss, 146796K reserved, 262144K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     modules : 0xffffff8000000000 - 0xffffff8008000000   (   128 MB)
[    0.000000]     vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000   (   250 GB)
[    0.000000]       .text : 0xffffff8008080000 - 0xffffff8008a60000   ( 10112 KB)
[    0.000000]     .rodata : 0xffffff8008a60000 - 0xffffff8008d80000   (  3200 KB)
[    0.000000]       .init : 0xffffff8008d80000 - 0xffffff8008e00000   (   512 KB)
[    0.000000]       .data : 0xffffff8008e00000 - 0xffffff8008ea2200   (   649 KB)
[    0.000000]        .bss : 0xffffff8008ea2200 - 0xffffff80090c0d38   (  2171 KB)
[    0.000000]     fixed   : 0xffffffbefe7fd000 - 0xffffffbefec00000   (  4108 KB)
[    0.000000]     PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000   (    16 MB)
[    0.000000]     vmemmap : 0xffffffbf00000000 - 0xffffffc000000000   (     4 GB maximum)
[    0.000000]               0xffffffbf00000000 - 0xffffffbf1dc00000   (   476 MB actual)
[    0.000000]     memory  : 0xffffffc000000000 - 0xffffffc880000000   ( 34816 MB)
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  RCU event tracing is enabled.
[    0.000000]  RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GIC: Adjusting CPU interface base to 0x00000000f902f000
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] arch_timer: cp15 timer(s) running at 99.99MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x170f8dc196, max_idle_ns: 440795203664 ns
[    0.000003] sched_clock: 56 bits at 99MHz, resolution 10ns, wraps every 4398046511099ns
[    0.000326] Console: colour dummy device 80x25
[    0.000600] console [tty0] enabled
[    0.000620] Calibrating delay loop (skipped), value calculated using timer frequency.. 199.98 BogoMIPS (lpj=399960)
[    0.000635] pid_max: default: 32768 minimum: 301
[    0.000738] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.000756] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.001314] ASID allocator initialised with 65536 entries
[    0.001365] Hierarchical SRCU implementation.
[    0.001696] EFI services will not be available.
[    0.001722] zynqmp_plat_init Platform Management API v1.0
[    0.001731] zynqmp_plat_init Trustzone version v1.0
[    0.001826] smp: Bringing up secondary CPUs ...
[    0.002082] Detected VIPT I-cache on CPU1
[    0.002110] CPU1: Booted secondary processor [410fd034]
[    0.002385] Detected VIPT I-cache on CPU2
[    0.002403] CPU2: Booted secondary processor [410fd034]
[    0.002667] Detected VIPT I-cache on CPU3
[    0.002684] CPU3: Booted secondary processor [410fd034]
[    0.002726] smp: Brought up 1 node, 4 CPUs
[    0.002765] SMP: Total of 4 processors activated.
[    0.002774] CPU features: detected feature: 32-bit EL0 Support
[    0.002785] CPU: All CPU(s) started at EL2
[    0.002802] alternatives: patching kernel code
[    0.003531] devtmpfs: initialized
[    0.007834] random: get_random_u32 called from bucket_table_alloc+0x108/0x260 with crng_init=0
[    0.008032] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.008054] futex hash table entries: 1024 (order: 5, 131072 bytes)
[    0.013421] xor: measuring software checksum speed
[    0.052010]    8regs     :  2302.000 MB/sec
[    0.092040]    8regs_prefetch:  2052.000 MB/sec
[    0.132071]    32regs    :  2830.000 MB/sec
[    0.172102]    32regs_prefetch:  2379.000 MB/sec
[    0.172111] xor: using function: 32regs (2830.000 MB/sec)
[    0.172182] pinctrl core: initialized pinctrl subsystem
[    0.172290] random: fast init done
[    0.172677] NET: Registered protocol family 16
[    0.173254] cpuidle: using governor menu
[    0.173755] vdso: 2 pages (1 code @ ffffff8008a66000, 1 data @ ffffff8008e04000)
[    0.173771] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.174229] DMA: preallocated 256 KiB pool for atomic allocations
[    0.203622] reset_zynqmp reset-controller: Xilinx zynqmp reset driver probed
[    0.204176] ARM CCI_400_r1 PMU driver probed
[    0.208971] zynqmp-pinctrl ff180000.pinctrl: zynqmp pinctrl initialized
[    0.216664] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.284371] raid6: int64x1  gen()   405 MB/s
[    0.352283] raid6: int64x1  xor()   444 MB/s
[    0.420392] raid6: int64x2  gen()   690 MB/s
[    0.488382] raid6: int64x2  xor()   602 MB/s
[    0.556413] raid6: int64x4  gen()  1042 MB/s
[    0.624482] raid6: int64x4  xor()   719 MB/s
[    0.692497] raid6: int64x8  gen()   979 MB/s
[    0.760565] raid6: int64x8  xor()   744 MB/s
[    0.828651] raid6: neonx1   gen()   726 MB/s
[    0.896631] raid6: neonx1   xor()   851 MB/s
[    0.964706] raid6: neonx2   gen()  1169 MB/s
[    1.032744] raid6: neonx2   xor()  1206 MB/s
[    1.100776] raid6: neonx4   gen()  1504 MB/s
[    1.168825] raid6: neonx4   xor()  1439 MB/s
[    1.236901] raid6: neonx8   gen()  1651 MB/s
[    1.304930] raid6: neonx8   xor()  1531 MB/s
[    1.304938] raid6: using algorithm neonx8 gen() 1651 MB/s
[    1.304946] raid6: .... xor() 1531 MB/s, rmw enabled
[    1.304953] raid6: using neon recovery algorithm
[    1.306357] SCSI subsystem initialized
[    1.306519] usbcore: registered new interface driver usbfs
[    1.306555] usbcore: registered new interface driver hub
[    1.306592] usbcore: registered new device driver usb
[    1.306656] media: Linux media interface: v0.10
[    1.306682] Linux video capture interface: v2.00
[    1.306720] pps_core: LinuxPPS API ver. 1 registered
[    1.306728] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.306748] PTP clock support registered
[    1.306774] EDAC MC: Ver: 3.0.0
[    1.307113] zynqmp-ipi ff9905c0.mailbox: Probed ZynqMP IPI Mailbox driver.
[    1.307260] FPGA manager framework
[    1.307365] fpga-region fpga-full: FPGA Region probed
[    1.307528] Advanced Linux Sound Architecture Driver Initialized.
[    1.307775] Bluetooth: Core ver 2.22
[    1.307805] NET: Registered protocol family 31
[    1.307813] Bluetooth: HCI device and connection manager initialized
[    1.307824] Bluetooth: HCI socket layer initialized
[    1.307834] Bluetooth: L2CAP socket layer initialized
[    1.307851] Bluetooth: SCO socket layer initialized
[    1.308364] clocksource: Switched to clocksource arch_sys_counter
[    1.308436] VFS: Disk quotas dquot_6.6.0
[    1.308480] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.312388] NET: Registered protocol family 2
[    1.312681] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    1.312892] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    1.313278] TCP: Hash tables configured (established 32768 bind 32768)
[    1.313348] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    1.313424] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    1.313571] NET: Registered protocol family 1
[    1.313771] RPC: Registered named UNIX socket transport module.
[    1.313780] RPC: Registered udp transport module.
[    1.313788] RPC: Registered tcp transport module.
[    1.313795] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.314192] hw perfevents: no interrupt-affinity property for /pmu, guessing.
[    1.314335] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[    1.314955] audit: initializing netlink subsys (disabled)
[    1.315308] audit: type=2000 audit(1.304:1): state=initialized audit_enabled=0 res=1
[    1.315354] workingset: timestamp_bits=62 max_order=20 bucket_order=0
[    1.316012] NFS: Registering the id_resolver key type
[    1.316034] Key type id_resolver registered
[    1.316042] Key type id_legacy registered
[    1.316054] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.316074] jffs2: version 2.2. (NAND) (SUMMARY)  �© 2001-2006 Red Hat, Inc.
[    1.339510] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    1.339527] io scheduler noop registered
[    1.339535] io scheduler deadline registered
[    1.339555] io scheduler cfq registered (default)
[    1.339564] io scheduler mq-deadline registered
[    1.339571] io scheduler kyber registered
[    1.340196] nwl-pcie fd0e0000.pcie: Link is UP
[    1.340239] OF: PCI: host bridge /amba/pcie@fd0e0000 ranges:
[    1.340260] OF: PCI:   MEM 0xe0000000..0xefffffff -> 0xe0000000
[    1.340272] OF: PCI:   MEM 0x600000000..0x7ffffffff -> 0x600000000
[    1.340405] nwl-pcie fd0e0000.pcie: PCI host bridge to bus 0000:00
[    1.340419] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.340430] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xefffffff]
[    1.340441] pci_bus 0000:00: root bus resource [mem 0x600000000-0x7ffffffff pref]
[    1.341020] pci 0000:00:00.0: BAR 8: assigned [mem 0xe0000000-0xe00fffff]
[    1.341035] pci 0000:01:00.0: BAR 0: assigned [mem 0xe0000000-0xe001ffff 64bit]
[    1.341062] pci 0000:01:00.0: BAR 6: assigned [mem 0xe0020000-0xe002ffff pref]
[    1.341075] pci 0000:00:00.0: PCI bridge to [bus 01-0c]
[    1.341087] pci 0000:00:00.0:   bridge window [mem 0xe0000000-0xe00fffff]
[    1.341893] xilinx-dpdma fd4c0000.dma: Xilinx DPDMA engine is probed
[    1.342278] xilinx-zynqmp-dma fd500000.dma: ZynqMP DMA driver Probe success
[    1.342443] xilinx-zynqmp-dma fd510000.dma: ZynqMP DMA driver Probe success
[    1.342600] xilinx-zynqmp-dma fd520000.dma: ZynqMP DMA driver Probe success
[    1.342759] xilinx-zynqmp-dma fd530000.dma: ZynqMP DMA driver Probe success
[    1.342915] xilinx-zynqmp-dma fd540000.dma: ZynqMP DMA driver Probe success
[    1.343074] xilinx-zynqmp-dma fd550000.dma: ZynqMP DMA driver Probe success
[    1.343228] xilinx-zynqmp-dma fd560000.dma: ZynqMP DMA driver Probe success
[    1.343391] xilinx-zynqmp-dma fd570000.dma: ZynqMP DMA driver Probe success
[    1.343611] xilinx-zynqmp-dma ffa80000.dma: ZynqMP DMA driver Probe success
[    1.343763] xilinx-zynqmp-dma ffa90000.dma: ZynqMP DMA driver Probe success
[    1.343916] xilinx-zynqmp-dma ffaa0000.dma: ZynqMP DMA driver Probe success
[    1.344066] xilinx-zynqmp-dma ffab0000.dma: ZynqMP DMA driver Probe success
[    1.344219] xilinx-zynqmp-dma ffac0000.dma: ZynqMP DMA driver Probe success
[    1.344392] xilinx-zynqmp-dma ffad0000.dma: ZynqMP DMA driver Probe success
[    1.344559] xilinx-zynqmp-dma ffae0000.dma: ZynqMP DMA driver Probe success
[    1.344717] xilinx-zynqmp-dma ffaf0000.dma: ZynqMP DMA driver Probe success
[    1.369655] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    1.372534] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    1.376321] brd: module loaded
[    1.379590] loop: module loaded
[    1.380824] ahci-ceva fd0c0000.ahci: couldn't get PHY in node ahci: -517
[    1.380977] mtdoops: mtd device (mtddev=name/number) must be supplied
[    1.381994] m25p80 spi0.0: n25q512a (131072 Kbytes)
[    1.382018] 4 ofpart partitions found on MTD device spi0.0
[    1.382026] Creating 4 MTD partitions on "spi0.0":
[    1.382038] 0x000000000000-0x000001e00000 : "boot"
[    1.382448] 0x000001e00000-0x000001e40000 : "bootenv"
[    1.382827] 0x000001e40000-0x000004240000 : "kernel"
[    1.383192] 0x000004240000-0x000008000000 : "spare"
[    1.384384] libphy: Fixed MDIO Bus: probed
[    1.385343] tun: Universal TUN/TAP device driver, 1.6
[    1.385474] CAN device driver interface
[    1.387448] macb ff0e0000.ethernet: Not enabling partial store and forward
[    1.387824] libphy: MACB_mii_bus: probed
[    1.389470] macb ff0e0000.ethernet eth0: Cadence GEM rev 0x50070106 at 0xff0e0000 irq 31 (00:0a:35:00:22:01)
[    1.389487] TI DP83867 ff0e0000.ethernet-ffffffff:0c: attached PHY driver [TI DP83867] (mii_bus:phy_addr=ff0e0000.ethernet-ffffffff:0c, irq=POLL)
[    1.390017] usbcore: registered new interface driver asix
[    1.390068] usbcore: registered new interface driver ax88179_178a
[    1.390099] usbcore: registered new interface driver cdc_ether
[    1.390128] usbcore: registered new interface driver net1080
[    1.390158] usbcore: registered new interface driver cdc_subset
[    1.390190] usbcore: registered new interface driver zaurus
[    1.390231] usbcore: registered new interface driver cdc_ncm
[    1.390515] xilinx-axipmon ffa00000.perf-monitor: Probed Xilinx APM
[    1.391553] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.391563] ehci-pci: EHCI PCI platform driver
[    1.391809] usbcore: registered new interface driver uas
[    1.391847] usbcore: registered new interface driver usb-storage
[    1.392285] rtc_zynqmp ffa60000.rtc: rtc core: registered ffa60000.rtc as rtc0
[    1.392335] i2c /dev entries driver
[    1.393325] IR NEC protocol handler initialized
[    1.393334] IR RC5(x/sz) protocol handler initialized
[    1.393342] IR RC6 protocol handler initialized
[    1.393349] IR JVC protocol handler initialized
[    1.393356] IR Sony protocol handler initialized
[    1.393362] IR SANYO protocol handler initialized
[    1.393369] IR Sharp protocol handler initialized
[    1.393376] IR MCE Keyboard/mouse protocol handler initialized
[    1.393384] IR XMP protocol handler initialized
[    1.394340] usbcore: registered new interface driver uvcvideo
[    1.394349] USB Video Class driver (1.1.1)
[    1.394805] cdns-wdt fd4d0000.watchdog: Xilinx Watchdog Timer at ffffff8009165000 with timeout 10s
[    1.394974] Bluetooth: HCI UART driver ver 2.3
[    1.394985] Bluetooth: HCI UART protocol H4 registered
[    1.394993] Bluetooth: HCI UART protocol BCSP registered
[    1.395019] Bluetooth: HCI UART protocol LL registered
[    1.395027] Bluetooth: HCI UART protocol ATH3K registered
[    1.395035] Bluetooth: HCI UART protocol Three-wire (H5) registered
[    1.395079] Bluetooth: HCI UART protocol Intel registered
[    1.395088] Bluetooth: HCI UART protocol QCA registered
[    1.395123] usbcore: registered new interface driver bcm203x
[    1.395156] usbcore: registered new interface driver bpa10x
[    1.395189] usbcore: registered new interface driver bfusb
[    1.395224] usbcore: registered new interface driver btusb
[    1.395233] Bluetooth: Generic Bluetooth SDIO driver ver 0.1
[    1.395280] usbcore: registered new interface driver ath3k
[    1.395402] EDAC MC: ECC not enabled
[    1.395569] EDAC DEVICE0: Giving out device to module zynqmp-ocm-edac controller zynqmp_ocm: DEV ff960000.memory-controller (INTERRUPT)
[    1.396049] cpufreq: cpufreq_online: CPU0: Running at unlisted freq: 1199880 KHz
[    1.396103] cpufreq: cpufreq_online: CPU0: Unlisted initial frequency changed to: 1199999 KHz
[    1.396494] sdhci: Secure Digital Host Controller Interface driver
[    1.396504] sdhci: Copyright(c) Pierre Ossman
[    1.396511] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.444503] mmc0: SDHCI controller on ff170000.sdhci [ff170000.sdhci] using ADMA 64-bit
[    1.450500] ledtrig-cpu: registered to indicate activity on CPUs
[    1.450640] usbcore: registered new interface driver usbhid
[    1.450649] usbhid: USB HID core driver
[    1.452403] fpga_manager fpga0: Xilinx ZynqMP FPGA Manager registered
[    1.454151] pktgen: Packet Generator for packet performance testing. Version: 2.75
[    1.456381] Netfilter messages via NETLINK v0.30.
[    1.456495] ip_tables: (C) 2000-2006 Netfilter Core Team
[    1.456635] Initializing XFRM netlink socket
[    1.456695] NET: Registered protocol family 10
[    1.457102] Segment Routing with IPv6
[    1.457144] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    1.457320] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    1.457622] NET: Registered protocol family 17
[    1.457638] NET: Registered protocol family 15
[    1.457655] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    1.457669] Ebtables v2.0 registered
[    1.457767] can: controller area network core (rev 20170425 abi 9)
[    1.457800] NET: Registered protocol family 29
[    1.457815] can: raw protocol (rev 20170425)
[    1.457823] can: broadcast manager protocol (rev 20170425 t)
[    1.457834] can: netlink gateway (rev 20170425) max_hops=1
[    1.458034] Bluetooth: RFCOMM TTY layer initialized
[    1.458049] Bluetooth: RFCOMM socket layer initialized
[    1.458067] Bluetooth: RFCOMM ver 1.11
[    1.458080] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    1.458088] Bluetooth: BNEP filters: protocol multicast
[    1.458098] Bluetooth: BNEP socket layer initialized
[    1.458106] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[    1.458116] Bluetooth: HIDP socket layer initialized
[    1.458224] 9pnet: Installing 9P2000 support
[    1.458245] Key type dns_resolver registered
[    1.458599] registered taskstats version 1
[    1.458916] Btrfs loaded, crc32c=crc32c-generic
[    1.465174] ff000000.serial: ttyPS0 at MMIO 0xff000000 (irq = 47, base_baud = 6249375) is a xuartps
[    1.620815] mmc0: new high speed SDHC card at address aaaa
[    1.628398] mmcblk0: mmc0:aaaa SU08G 7.40 GiB 
[    1.635910]  mmcblk0: p1 p2
[    3.121536] console [ttyPS0] enabled
[    3.125683] ff010000.serial: ttyPS1 at MMIO 0xff010000 (irq = 48, base_baud = 6249375) is a xuartps
[    3.136224] xilinx-psgtr fd400000.zynqmp_phy: Lane:1 type:8 protocol:4 pll_locked:yes
[    3.144460] PLL: shutdown
[    3.147130] PLL: shutdown
[    3.150045] PLL: enable
[    3.152535] PLL: shutdown
[    3.155884] PLL: enable
[    3.158351] xilinx-dp-snd-codec fd4a0000.zynqmp-display:zynqmp_dp_snd_codec0: Xilinx DisplayPort Sound Codec probed
[    3.168924] xilinx-dp-snd-pcm zynqmp_dp_snd_pcm0: Xilinx DisplayPort Sound PCM probed
[    3.176875] xilinx-dp-snd-pcm zynqmp_dp_snd_pcm1: Xilinx DisplayPort Sound PCM probed
[    3.185083] xilinx-dp-snd-card fd4a0000.zynqmp-display:zynqmp_dp_snd_card: xilinx-dp-snd-codec-dai <-> xilinx-dp-snd-codec-dai mapping ok
[    3.197441] xilinx-dp-snd-card fd4a0000.zynqmp-display:zynqmp_dp_snd_card: xilinx-dp-snd-codec-dai <-> xilinx-dp-snd-codec-dai mapping ok
[    3.210058] xilinx-dp-snd-card fd4a0000.zynqmp-display:zynqmp_dp_snd_card: Xilinx DisplayPort Sound Card probed
[    3.220157] OF: graph: no port node found in /amba/zynqmp-display@fd4a0000
[    3.227030] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    3.233566] [drm] No driver support for vblank timestamp query.
[    3.239528] xlnx-drm xlnx-drm.0: bound fd4a0000.zynqmp-display (ops 0xffffff8008b17428)
[    4.324373] [drm] Cannot find any crtc or sizes
[    4.328988] [drm] Initialized xlnx 1.0.0 20130509 for fd4a0000.zynqmp-display on minor 0
[    4.337023] zynqmp-display fd4a0000.zynqmp-display: ZynqMP DisplayPort Subsystem driver probed
[    4.345962] xilinx-psgtr fd400000.zynqmp_phy: Lane:3 type:3 protocol:2 pll_locked:yes
[    4.353771] ahci-ceva fd0c0000.ahci: AHCI 0001.0301 32 slots 2 ports 6 Gbps 0x3 impl platform mode
[    4.362659] ahci-ceva fd0c0000.ahci: flags: 64bit ncq sntf pm clo only pmp fbs pio slum part ccc sds apst 
[    4.372993] scsi host0: ahci-ceva
[    4.376416] scsi host1: ahci-ceva
[    4.379737] ata1: SATA max UDMA/133 mmio [mem 0xfd0c0000-0xfd0c1fff] port 0x100 irq 45
[    4.387581] ata2: SATA max UDMA/133 mmio [mem 0xfd0c0000-0xfd0c1fff] port 0x180 irq 45
[    4.397215] xilinx-psgtr fd400000.zynqmp_phy: Lane:2 type:0 protocol:3 pll_locked:yes
[    4.407122] dwc3 fe200000.dwc3: stop_host(): INFO: Host already stopped
[    4.413983] dwc3 fe200000.dwc3: stop_peripheral(): INFO: Peripheral already stopped
[    4.458713] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[    4.464125] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1
[    4.471965] xhci-hcd xhci-hcd.0.auto: hcc params 0x0238f625 hci version 0x100 quirks 0x22010010
[    4.480609] xhci-hcd xhci-hcd.0.auto: irq 53, io mem 0xfe200000
[    4.486602] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    4.493313] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.500518] usb usb1: Product: xHCI Host Controller
[    4.505374] usb usb1: Manufacturer: Linux 4.14.0-xilinx-v2018.2 xhci-hcd
[    4.512058] usb usb1: SerialNumber: xhci-hcd.0.auto
[    4.517189] hub 1-0:1.0: USB hub found
[    4.520883] hub 1-0:1.0: 1 port detected
[    4.524942] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[    4.530356] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2
[    4.538036] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    4.546140] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    4.552852] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.560056] usb usb2: Product: xHCI Host Controller
[    4.564915] usb usb2: Manufacturer: Linux 4.14.0-xilinx-v2018.2 xhci-hcd
[    4.571600] usb usb2: SerialNumber: xhci-hcd.0.auto
[    4.576689] hub 2-0:1.0: USB hub found
[    4.580379] hub 2-0:1.0: 1 port detected
[    4.585432] pca953x 0-0020: 0-0020 supply vcc not found, using dummy regulator
[    4.595323] pca953x 0-0020: interrupt support not compiled in
[    4.601077] pca953x 0-0021: 0-0021 supply vcc not found, using dummy regulator
[    4.610932] pca953x 0-0021: interrupt support not compiled in
[    4.617366] ina2xx 3-0040: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.624071] ina2xx 3-0041: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.630766] ina2xx 3-0042: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.637454] ina2xx 3-0043: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.644146] ina2xx 3-0044: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.650847] ina2xx 3-0045: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.657543] ina2xx 3-0046: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.664235] ina2xx 3-0047: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.670928] ina2xx 3-004a: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.677632] ina2xx 3-004b: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.683934] i2c i2c-0: Added multiplexed i2c bus 3
[    4.689271] ina2xx 4-0040: power monitor ina226 (Rshunt = 2000 uOhm)
[    4.692369] xhci-hcd xhci-hcd.0.auto: remove, state 4
[    4.692382] usb usb2: USB disconnect, device number 1
[    4.696504] xhci-hcd xhci-hcd.0.auto: USB bus 2 deregistered
[    4.696513] xhci-hcd xhci-hcd.0.auto: remove, state 4
[    4.696522] usb usb1: USB disconnect, device number 1
[    4.696924] xhci-hcd xhci-hcd.0.auto: USB bus 1 deregistered
[    4.696935] dwc3 fe200000.dwc3: stop_peripheral(): INFO: Peripheral already stopped
[    4.714549] ata2: SATA link down (SStatus 0 SControl 330)
[    4.714572] ata1: SATA link down (SStatus 0 SControl 330)
[    4.745795] ina2xx 4-0041: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.752484] ina2xx 4-0042: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.759177] ina2xx 4-0043: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.765864] ina2xx 4-0044: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.772556] ina2xx 4-0045: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.779250] ina2xx 4-0046: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.785940] ina2xx 4-0047: power monitor ina226 (Rshunt = 5000 uOhm)
[    4.792246] i2c i2c-0: Added multiplexed i2c bus 4
[    4.837950] i2c i2c-0: Added multiplexed i2c bus 5
[    4.842785] i2c i2c-0: Added multiplexed i2c bus 6
[    4.847499] pca954x 0-0075: registered 4 multiplexed busses for I2C mux pca9544
[    4.854817] cdns-i2c ff020000.i2c: 400 kHz mmio ff020000 irq 33
[    4.862334] at24 7-0054: 1024 byte 24c08 EEPROM, writable, 1 bytes/write
[    4.868985] i2c i2c-1: Added multiplexed i2c bus 7
[    4.873909] i2c i2c-1: Added multiplexed i2c bus 8
[    4.880605] si570 9-005d: registered, current frequency 300000000 Hz
[    4.886910] i2c i2c-1: Added multiplexed i2c bus 9
[    4.904366] si570 10-005d: registered, current frequency 148500000 Hz
[    4.910756] i2c i2c-1: Added multiplexed i2c bus 10
[    4.915771] i2c i2c-1: Added multiplexed i2c bus 11
[    4.920692] i2c i2c-1: Added multiplexed i2c bus 12
[    4.925605] i2c i2c-1: Added multiplexed i2c bus 13
[    4.930521] i2c i2c-1: Added multiplexed i2c bus 14
[    4.935323] pca954x 1-0074: registered 8 multiplexed busses for I2C switch pca9548
[    4.943173] i2c i2c-1: Added multiplexed i2c bus 15
[    4.948088] i2c i2c-1: Added multiplexed i2c bus 16
[    4.953002] i2c i2c-1: Added multiplexed i2c bus 17
[    4.958211] i2c i2c-1: Added multiplexed i2c bus 18
[    4.963132] i2c i2c-1: Added multiplexed i2c bus 19
[    4.968052] i2c i2c-1: Added multiplexed i2c bus 20
[    4.972973] i2c i2c-1: Added multiplexed i2c bus 21
[    4.977896] i2c i2c-1: Added multiplexed i2c bus 22
[    4.982694] pca954x 1-0075: registered 8 multiplexed busses for I2C switch pca9548
[    4.990268] cdns-i2c ff030000.i2c: 400 kHz mmio ff030000 irq 34
[    4.997327] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[    5.004139] rtc_zynqmp ffa60000.rtc: setting system clock to 2018-12-21 10:14:58 UTC (1545387298)
[    5.013053] clk: Not disabling unused clocks
[    5.017276] ALSA device list:
[    5.020184]   #0: DisplayPort monitor
[    5.029294] EXT4-fs (mmcblk0p2): couldn't mount as ext3 due to feature incompatibilities
[    5.177547] EXT4-fs (mmcblk0p2): recovery complete
[    5.183439] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    5.191478] VFS: Mounted root (ext4 filesystem) on device 179:2.
[    5.197485] devtmpfs: mounted
[    5.200502] Freeing unused kernel memory: 512K
INIT: version 2.88 booting
Starting udev
[    5.512378] [drm] Cannot find any crtc or sizes
[    5.569372] udevd[1907]: starting version 3.2.2
[    5.620791] udevd[1908]: starting eudev-3.2.2
[    5.745065] pci 0000:00:00.0: enabling device (0000 -> 0002)
[    5.750716] ath9k 0000:01:00.0: enabling device (0000 -> 0002)
[    5.770173] ieee80211 phy0: Atheros AR9300 Rev:3 mem=0xffffff800cf80000, irq=57
[    6.112588] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[    6.148412] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered
Starting internet superserver: inetd.
INIT: Entering runlevel: 5
Configuring network interfaces... [    6.459532] pps pps0: new PPS source ptp0
[    6.463489] macb ff0e0000.ethernet: gem-ptp-timer ptp clock registered.
[    6.470122] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
udhcpc (v1.24.1) started
Sending discover...
[    7.460651] macb ff0e0000.ethernet eth0: link up (1000/Full)
[    7.466253] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Sending discover...
Sending select for 137.202.157.22...
Lease of 137.202.157.22 obtained, lease time 1800
/etc/udhcpc.d/50default: Adding DNS 137.202.187.16
/etc/udhcpc.d/50default: Adding DNS 137.202.23.16
/etc/udhcpc.d/50default: Adding DNS 147.34.2.16
done.
Starting system message bus: dbus.
Starting Dropbear SSH server: dropbear.
Starting syslogd/klogd: done
Starting tcf-agent: OK

PetaLinux 2018.2 xilinx-zcu102-2018_2 /dev/ttyPS0

xilinx-zcu102-2018_2 login: root
Password: 



Login incorrect
xilinx-zcu102-2018_2 login: root
Password: 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# ls
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# lsmod
Module                  Size  Used by
ath9k                 147456  0
ath9k_common           36864  1 ath9k
ath9k_hw              483328  2 ath9k,ath9k_common
ath                    36864  3 ath9k_hw,ath9k,ath9k_common
uio_pdrv_genirq        16384  0
root@xilinx-zcu102-2018_2:~# echo 1 > /sys/bus/pci/rescan 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# ifconfig -a
can0      Link encap:UNSPEC  HWaddr 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00  
          NOARP  MTU:16  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:10 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)
          Interrupt:11 

eth0      Link encap:Ethernet  HWaddr 00:0A:35:00:22:01  
          inet addr:137.202.157.22  Bcast:137.202.157.255  Mask:255.255.254.0
          inet6 addr: fe80::20a:35ff:fe00:2201%4882584/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:402 errors:0 dropped:0 overruns:0 frame:0
          TX packets:23 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:34243 (33.4 KiB)  TX bytes:3434 (3.3 KiB)
          Interrupt:31 

lo        Link encap:Local Loopback  
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1%4882584/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

sit0      Link encap:IPv6-in-IPv4  
          NOARP  MTU:1480  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

wlan0     Link encap:Ethernet  HWaddr 00:0E:8E:6A:04:B6  
          BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# rfkill list
0: phy0: wlan
        Soft blocked: no
        Hard blocked: no
root@xilinx-zcu102-2018_2:~# [  203.270941] random: crng init done

root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# ifconfig 
eth0      Link encap:Ethernet  HWaddr 00:0A:35:00:22:01  
          inet addr:137.202.157.22  Bcast:137.202.157.255  Mask:255.255.254.0
          inet6 addr: fe80::20a:35ff:fe00:2201%4882584/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:3339 errors:0 dropped:0 overruns:0 frame:0
          TX packets:121 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:254415 (248.4 KiB)  TX bytes:22470 (21.9 KiB)
          Interrupt:31 

lo        Link encap:Local Loopback  
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1%4882584/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

root@xilinx-zcu102-2018_2:~# 5F
-sh: 5F: command not found
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# ./iwconfig 
sit0      no wireless extensions.

can0      no wireless extensions.

eth0      no wireless extensions.

lo        no wireless extensions.

wlan0     IEEE 802.11  ESSID:off/any  
          Mode:Managed  Access Point: Not-Associated   Tx-Power=0 dBm   
          Retry short limit:7   RTS thr:off   Fragment thr:off
          Encryption key:off
          Power Management:off
          
root@xilinx-zcu102-2018_2:~# ifconfig  wlan0 up
[  367.339554] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# uname -a
Linux xilinx-zcu102-2018_2 4.14.0-xilinx-v2018.2 #1 SMP Mon Dec 10 19:13:26 PKT 2018 aarch64 aarch64 aarch64 GNU/Linux
root@xilinx-zcu102-2018_2:~# 
root@xilinx-zcu102-2018_2:~# ./iwlist wlan0 scan
[  446.916350] INFO: rcu_sched detected stalls on CPUs/tasks:
[  446.921764]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=1552 
[  446.929915]  (detected by 2, t=5254 jiffies, g=5116, c=5115, q=4)
[  446.935990] Task dump for CPU 0:
[  446.939200] iwlist          R  running task        0  2325   2295 0x00000002
[  446.946233] Call trace:
[  446.948670] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  446.953786] [<0000000000008b0b>] 0x8b0b
[  509.940345] INFO: rcu_sched detected stalls on CPUs/tasks:
[  509.945753]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=4256 
[  509.953910]  (detected by 2, t=21010 jiffies, g=5116, c=5115, q=5)
[  509.960071] Task dump for CPU 0:
[  509.963282] iwlist          R  running task        0  2325   2295 0x00000002
[  509.970315] Call trace:
[  509.972748] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  509.977868] [<0000000000008b0b>] 0x8b0b
[  572.964343] INFO: rcu_sched detected stalls on CPUs/tasks:
[  572.969748]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=4934 
[  572.977905]  (detected by 2, t=36766 jiffies, g=5116, c=5115, q=5)
[  572.984067] Task dump for CPU 0:
[  572.987277] iwlist          R  running task        0  2325   2295 0x00000002
[  572.994310] Call trace:
[  572.996742] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  573.001863] [<0000000000008b0b>] 0x8b0b
[  635.988342] INFO: rcu_sched detected stalls on CPUs/tasks:
[  635.993743]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=5607 
[  636.001900]  (detected by 2, t=52522 jiffies, g=5116, c=5115, q=5)
[  636.008062] Task dump for CPU 0:
[  636.011272] iwlist          R  running task        0  2325   2295 0x00000002
[  636.018305] Call trace:
[  636.020737] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  636.025858] [<0000000000008b0b>] 0x8b0b
[  699.012341] INFO: rcu_sched detected stalls on CPUs/tasks:
[  699.017748]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=6256 
[  699.025904]  (detected by 1, t=68278 jiffies, g=5116, c=5115, q=15)
[  699.032153] Task dump for CPU 0:
[  699.035363] iwlist          R  running task        0  2325   2295 0x00000002
[  699.042396] Call trace:
[  699.044828] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  699.049950] [<0000000000008b0b>] 0x8b0b
[  762.036340] INFO: rcu_sched detected stalls on CPUs/tasks:
[  762.041742]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=6863 
[  762.049900]  (detected by 1, t=84034 jiffies, g=5116, c=5115, q=15)
[  762.056148] Task dump for CPU 0:
[  762.059359] iwlist          R  running task        0  2325   2295 0x00000002
[  762.066392] Call trace:
[  762.068823] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  762.073945] [<0000000000008b0b>] 0x8b0b
[  825.060339] INFO: rcu_sched detected stalls on CPUs/tasks:
[  825.065738]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=7470 
[  825.073895]  (detected by 1, t=99790 jiffies, g=5116, c=5115, q=15)
[  825.080144] Task dump for CPU 0:
[  825.083354] iwlist          R  running task        0  2325   2295 0x00000002
[  825.090387] Call trace:
[  825.092819] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  825.097940] [<0000000000008b0b>] 0x8b0b
[  888.084338] INFO: rcu_sched detected stalls on CPUs/tasks:
[  888.089742]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=8077 
[  888.097899]  (detected by 1, t=115546 jiffies, g=5116, c=5115, q=15)
[  888.104235] Task dump for CPU 0:
[  888.107445] iwlist          R  running task        0  2325   2295 0x00000002
[  888.114478] Call trace:
[  888.116910] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  888.122031] [<0000000000008b0b>] 0x8b0b
[  951.108337] INFO: rcu_sched detected stalls on CPUs/tasks:
[  951.113737]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=8684 
[  951.121894]  (detected by 1, t=131302 jiffies, g=5116, c=5115, q=15)
[  951.128230] Task dump for CPU 0:
[  951.131440] iwlist          R  running task        0  2325   2295 0x00000002
[  951.138473] Call trace:
[  951.140905] [<ffffff8008085878>] __switch_to+0x98/0xb0
[  951.146026] [<0000000000008b0b>] 0x8b0b
[ 1014.132335] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1014.137741]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=9291 
[ 1014.145898]  (detected by 1, t=147058 jiffies, g=5116, c=5115, q=16)
[ 1014.152234] Task dump for CPU 0:
[ 1014.155444] iwlist          R  running task        0  2325   2295 0x00000002
[ 1014.162477] Call trace:
[ 1014.164909] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1014.170030] [<0000000000008b0b>] 0x8b0b
[ 1077.156334] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1077.161737]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=9897 
[ 1077.169894]  (detected by 2, t=162814 jiffies, g=5116, c=5115, q=16)
[ 1077.176229] Task dump for CPU 0:
[ 1077.179440] iwlist          R  running task        0  2325   2295 0x00000002
[ 1077.186472] Call trace:
[ 1077.188905] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1077.194025] [<0000000000008b0b>] 0x8b0b
[ 1140.180333] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1140.185740]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=10504 
[ 1140.193985]  (detected by 1, t=178570 jiffies, g=5116, c=5115, q=16)
[ 1140.200320] Task dump for CPU 0:
[ 1140.203530] iwlist          R  running task        0  2325   2295 0x00000002
[ 1140.210564] Call trace:
[ 1140.212995] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1140.218116] [<0000000000008b0b>] 0x8b0b
[ 1203.204332] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1203.209736]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=11110 
[ 1203.217980]  (detected by 2, t=194326 jiffies, g=5116, c=5115, q=16)
[ 1203.224316] Task dump for CPU 0:
[ 1203.227526] iwlist          R  running task        0  2325   2295 0x00000002
[ 1203.234559] Call trace:
[ 1203.236991] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1203.242112] [<0000000000008b0b>] 0x8b0b
[ 1266.228331] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1266.233731]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=11716 
[ 1266.241975]  (detected by 2, t=210082 jiffies, g=5116, c=5115, q=16)
[ 1266.248311] Task dump for CPU 0:
[ 1266.251521] iwlist          R  running task        0  2325   2295 0x00000002
[ 1266.258554] Call trace:
[ 1266.260986] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1266.266107] [<0000000000008b0b>] 0x8b0b
[ 1329.252330] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1329.257735]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=12322 
[ 1329.265980]  (detected by 2, t=225838 jiffies, g=5116, c=5115, q=16)
[ 1329.272315] Task dump for CPU 0:
[ 1329.275525] iwlist          R  running task        0  2325   2295 0x00000002
[ 1329.282558] Call trace:
[ 1329.284990] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1329.290111] [<0000000000008b0b>] 0x8b0b
[ 1392.276329] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1392.281731]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=12929 
[ 1392.289975]  (detected by 1, t=241594 jiffies, g=5116, c=5115, q=16)
[ 1392.296311] Task dump for CPU 0:
[ 1392.299521] iwlist          R  running task        0  2325   2295 0x00000002
[ 1392.306553] Call trace:
[ 1392.308985] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1392.314106] [<0000000000008b0b>] 0x8b0b
[ 1455.300327] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1455.305735]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=13536 
[ 1455.313979]  (detected by 1, t=257350 jiffies, g=5116, c=5115, q=16)
[ 1455.320314] Task dump for CPU 0:
[ 1455.323525] iwlist          R  running task        0  2325   2295 0x00000002
[ 1455.330557] Call trace:
[ 1455.332989] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1455.338110] [<0000000000008b0b>] 0x8b0b
[ 1518.324326] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1518.329730]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=14143 
[ 1518.337974]  (detected by 1, t=273106 jiffies, g=5116, c=5115, q=16)
[ 1518.344310] Task dump for CPU 0:
[ 1518.347520] iwlist          R  running task        0  2325   2295 0x00000002
[ 1518.354553] Call trace:
[ 1518.356985] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1518.362106] [<0000000000008b0b>] 0x8b0b
[ 1581.348325] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1581.353725]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=14750 
[ 1581.361969]  (detected by 1, t=288862 jiffies, g=5116, c=5115, q=16)
[ 1581.368305] Task dump for CPU 0:
[ 1581.371515] iwlist          R  running task        0  2325   2295 0x00000002
[ 1581.378548] Call trace:
[ 1581.380980] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1581.386101] [<0000000000008b0b>] 0x8b0b
[ 1644.372324] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1644.377730]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=15356 
[ 1644.385974]  (detected by 2, t=304618 jiffies, g=5116, c=5115, q=16)
[ 1644.392309] Task dump for CPU 0:
[ 1644.395519] iwlist          R  running task        0  2325   2295 0x00000002
[ 1644.402552] Call trace:
[ 1644.404984] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1644.410105] [<0000000000008b0b>] 0x8b0b
[ 1707.396323] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1707.401725]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=15963 
[ 1707.409969]  (detected by 1, t=320374 jiffies, g=5116, c=5115, q=16)
[ 1707.416304] Task dump for CPU 0:
[ 1707.419515] iwlist          R  running task        0  2325   2295 0x00000002
[ 1707.426548] Call trace:
[ 1707.428979] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1707.434100] [<0000000000008b0b>] 0x8b0b
[ 1770.420322] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1770.425729]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=16569 
[ 1770.433973]  (detected by 2, t=336130 jiffies, g=5116, c=5115, q=16)
[ 1770.440308] Task dump for CPU 0:
[ 1770.443519] iwlist          R  running task        0  2325   2295 0x00000002
[ 1770.450552] Call trace:
[ 1770.452984] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1770.458105] [<0000000000008b0b>] 0x8b0b
[ 1833.444321] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1833.449724]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=17176 
[ 1833.457968]  (detected by 1, t=351886 jiffies, g=5116, c=5115, q=16)
[ 1833.464304] Task dump for CPU 0:
[ 1833.467514] iwlist          R  running task        0  2325   2295 0x00000002
[ 1833.474547] Call trace:
[ 1833.476979] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1833.482100] [<0000000000008b0b>] 0x8b0b
[ 1896.468320] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1896.473719]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=17782 
[ 1896.481964]  (detected by 2, t=367642 jiffies, g=5116, c=5115, q=16)
[ 1896.488299] Task dump for CPU 0:
[ 1896.491509] iwlist          R  running task        0  2325   2295 0x00000002
[ 1896.498542] Call trace:
[ 1896.500974] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1896.506095] [<0000000000008b0b>] 0x8b0b
[ 1959.492318] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 1959.497723]  0-...: (1 GPs behind) idle=1ee/140000000000001/0 softirq=8193/8195 fqs=18389 
[ 1959.505968]  (detected by 1, t=383398 jiffies, g=5116, c=5115, q=17)
[ 1959.512303] Task dump for CPU 0:
[ 1959.515513] iwlist          R  running task        0  2325   2295 0x00000002
[ 1959.522547] Call trace:
[ 1959.524978] [<ffffff8008085878>] __switch_to+0x98/0xb0
[ 1959.530099] [<0000000000008b0b>] 0x8b0b

Complete kernel configrations are attached.

0 Kudos
3 Replies
shabbirk
Moderator
Moderator
1,642 Views
Registered: ‎12-04-2016

Hi

Just for debugging purpose, can you try disabling CPU IDLE from kernel config and see if stall issue goes away?

 

Best Regards

Shabbir

0 Kudos
hkarimmgc
Observer
Observer
1,600 Views
Registered: ‎05-10-2018

I Disable CPU_IDLE config .

root@zcu102-zynqmp-mel:~# zcat /proc/config.gz | grep IDLE
CONFIG_NO_HZ_IDLE=y
CONFIG_GENERIC_SMP_IDLE_THREAD=y
CONFIG_GENERIC_IDLE_POLL_SETUP=y
# CONFIG_IDLE_PAGE_TRACKING is not set
# CONFIG_CPU_IDLE is not set
# CONFIG_ARCH_NEEDS_CPU_IDLE_COUPLED is not set
# CONFIG_NETFILTER_XT_TARGET_IDLETIMER is not set
root@zcu102-zynqmp-mel:~# 

But got the same response.

root@zcu102-zynqmp-mel:~# echo 1 > /sys/bus/pci/rescan 
[  179.737513] pci 0000:00:00.0: BAR 8: assigned [mem 0xe0000000-0xe00fffff]
[  179.744259] pci 0000:01:00.0: BAR 0: assigned [mem 0xe0000000-0xe001ffff 64bit]
[  179.751549] pci 0000:01:00.0: BAR 6: assigned [mem 0xe0020000-0xe002ffff pref]
[  179.758734] pci 0000:00:00.0: PCI bridge to [bus 01-0c]
[  179.763933] pci 0000:00:00.0:   bridge window [mem 0xe0000000-0xe00fffff]
[  179.771143] pci 0000:00:00.0: enabling device (0000 -> 0002)
[  179.776738] ath9k 0000:01:00.0: enabling device (0000 -> 0002)
[  179.795797] ieee80211 phy0: Atheros AR9300 Rev:3 mem=0xffffff800fc80000, irq=49
[  179.803202] ath9k 0000:01:00.0 wlp1s0: renamed from wlan0
root@zcu102-zynqmp-mel:~# iwconfig 
wlp1s0    IEEE 802.11  ESSID:off/any  
          Mode:Managed  Access Point: Not-Associated   Tx-Power=off   
          Retry short limit:7   RTS thr:off   Fragment thr:off
          Encryption key:off
          Power Management:off
          
lo        no wireless extensions.

eth0      no wireless extensions.

sit0      no wireless extensions.

can0      no wireless extensions.

root@zcu102-zynqmp-mel:~# rfkill list
0: phy0: wlan
        Soft blocked: yes
        Hard blocked: no
root@zcu102-zynqmp-mel:~# rfkill unblock all
root@zcu102-zynqmp-mel:~# [  237.348196] IPv6: ADDRCONF(NETDEV_UP): wlp1s0: link is not ready
[  237.836890] NOHZ: local_softirq_pending 80
[  258.416884] INFO: rcu_sched detected stalls on CPUs/tasks:
[  258.422294]  0-...: (1 ticks this GP) idle=6c6/140000000000001/0 softirq=5410/5410 fqs=0 
[  258.430449]  (detected by 2, t=5255 jiffies, g=529, c=528, q=33)
[  258.436438] Task dump for CPU 0:
[  258.439651] kworker/u8:0    R  running task        0     5      2 0x00000000
[  258.446691] Workqueue: phy0 ieee80211_scan_work
[  258.451195] Call trace:
[  258.453630] [<ffffff8008085e54>] __switch_to+0x94/0xf0
[  258.458749] [<ffffffc87b8a2a00>] 0xffffffc87b8a2a00
[  258.463612] rcu_sched kthread starved for 5263 jiffies! g529 c528 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x402 ->cpu=0
[  258.473682] rcu_sched       I    0     8      2 0x00000000
[  258.479147] Call trace:
[  258.481579] [<ffffff8008085e54>] __switch_to+0x94/0xf0
[  258.486704] [<ffffff8008af37d0>] __schedule+0x1c8/0x6a0
[  258.491911] [<ffffff8008af3ccc>] schedule+0x24/0x80
[  258.496774] [<ffffff8008af6f20>] schedule_timeout+0x78/0x3e0
[  258.502416] [<ffffff80080fa8e4>] rcu_gp_kthread+0x614/0xdc8
[  258.507972] [<ffffff80080bff98>] kthread+0x130/0x138
[  258.512918] [<ffffff8008084f2c>] ret_from_fork+0x10/0x1c
[  321.436882] INFO: rcu_sched detected stalls on CPUs/tasks:
[  321.442287]  0-...: (1 ticks this GP) idle=6c6/140000000000001/0 softirq=5410/5410 fqs=0 
[  321.450444]  (detected by 2, t=21010 jiffies, g=529, c=528, q=211)
[  321.456606] Task dump for CPU 0:
[  321.459816] kworker/u8:0    R  running task        0     5      2 0x00000000
[  321.466854] Workqueue: phy0 ieee80211_scan_work
[  321.471361] Call trace:
[  321.473795] [<ffffff8008085e54>] __switch_to+0x94/0xf0
[  321.478916] [<ffffffc87b8a2a00>] 0xffffffc87b8a2a00
[  321.483779] rcu_sched kthread starved for 21018 jiffies! g529 c528 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x200 ->cpu=0
[  321.493936] rcu_sched       R    0     8      2 0x00000000
[  321.499401] Call trace:
[  321.501833] [<ffffff8008085e54>] __switch_to+0x94/0xf0
[  321.506957] [<ffffff8008af37d0>] __schedule+0x1c8/0x6a0
[  321.512165] [<ffffff8008af3ccc>] schedule+0x24/0x80
[  321.517027] [<ffffff8008af6f20>] schedule_timeout+0x78/0x3e0
[  321.522670] [<ffffff80080fa8e4>] rcu_gp_kthread+0x614/0xdc8
[  321.528225] [<ffffff80080bff98>] kthread+0x130/0x138
[  321.533172] [<ffffff8008084f2c>] ret_from_fork+0x10/0x1c
[  384.456881] INFO: rcu_sched detected stalls on CPUs/tasks:
[  384.462289]  0-...: (1 ticks this GP) idle=6c6/140000000000001/0 softirq=5410/5410 fqs=0 
[  384.470446]  (detected by 2, t=36765 jiffies, g=529, c=528, q=375)
[  384.476608] Task dump for CPU 0:
[  384.479818] kworker/u8:0    R  running task        0     5      2 0x00000000
[  384.486854] Workqueue: phy0 ieee80211_scan_work
[  384.491364] Call trace:
[  384.493797] [<ffffff8008085e54>] __switch_to+0x94/0xf0
[  384.498918] [<ffffffc87b8a2a00>] 0xffffffc87b8a2a00
[  384.503781] rcu_sched kthread starved for 36773 jiffies! g529 c528 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x200 ->cpu=0
[  384.513938] rcu_sched       R    0     8      2 0x00000000
[  384.519403] Call trace:
[  384.521835] [<ffffff8008085e54>] __switch_to+0x94/0xf0
[  384.526959] [<ffffff8008af37d0>] __schedule+0x1c8/0x6a0
[  384.532167] [<ffffff8008af3ccc>] schedule+0x24/0x80
[  384.537029] [<ffffff8008af6f20>] schedule_timeout+0x78/0x3e0
[  384.542671] [<ffffff80080fa8e4>] rcu_gp_kthread+0x614/0xdc8
[  384.548227] [<ffffff80080bff98>] kthread+0x130/0x138
[  384.553175] [<ffffff8008084f2c>] ret_from_fork+0x10/0x1c
[  447.476880] INFO: rcu_sched detected stalls on CPUs/tasks:
[  447.482282]  0-...: (1 ticks this GP) idle=6c6/140000000000001/0 softirq=5410/5410 fqs=0 
[  447.490439]  (detected by 2, t=52520 jiffies, g=529, c=528, q=529)
[  447.496601] Task dump for CPU 0:
[  447.499811] kworker/u8:0    R  running task        0     5      2 0x00000000
[  447.506848] Workqueue: phy0 ieee80211_scan_work
[  447.511357] Call trace:
[  447.513790] [<ffffff8008085e54>] __switch_to+0x94/0xf0
[  447.518911] [<ffffffc87b8a2a00>] 0xffffffc87b8a2a00
[  447.523775] rcu_sched kthread starved for 52528 jiffies! g529 c528 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x200 ->cpu=0
[  447.533932] rcu_sched       R    0     8      2 0x00000000
[  447.539397] Call trace:
[  447.541829] [<ffffff8008085e54>] __switch_to+0x94/0xf0
[  447.546952] [<ffffff8008af37d0>] __schedule+0x1c8/0x6a0
[  447.552160] [<ffffff8008af3ccc>] schedule+0x24/0x80
[  447.557022] [<ffffff8008af6f20>] schedule_timeout+0x78/0x3e0
[  447.562665] [<ffffff80080fa8e4>] rcu_gp_kthread+0x614/0xdc8
[  447.568220] [<ffffff80080bff98>] kthread+0x130/0x138
[  447.573168] [<ffffff8008084f2c>] ret_from_fork+0x10/0x1c
0 Kudos
HenryYu
Visitor
Visitor
746 Views
Registered: ‎05-12-2020

Hi, 

I met the same problem using XDMA on PCIe card

Is this problem solved?

0 Kudos