[Macchiato] EDKII grub boot fails with PCIe init
Frederik Lotter
frederik.lotter at netronome.com
Mon Mar 12 16:15:44 GMT 2018
Hi,
I am getting CPU stall warnings when booting up using the EFI route. I
suspect the PCIe interface, as the stall warning sometimes contain the
probe function. Other times is seems to get further than PCIe init, but
still stall interrupt handling.
Here are some facts around my observation:
I have two sdcards for my Machiattobin board. They have identical kernels
(4.16 rc5) with Ubuntu 16.04 rootfs. The one sdcard uses a uboot, DT and
kernel boot. The second sdcard has EDKII, grub kernel boot. The EDKII build
includes the device tree DTB (and DTS which I believe is unused) from the
one used on the uboot sdcard.
EFI stub: Booting Linux Kernel...
EFI stub: Using DTB from configuration table
EFI stub: Exiting boot services and installing virtual address map...
[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd081]
[ 0.000000] Linux version 4.16.0-rc5-mbcin-netronome-2-dirty
(root at mcb1-cpt) (gcc version 5.4.0 20160609 (Ubuntu/Linaro
5.4.0-6ubuntu1~16.04.9)) #2 SMP PREEMPT Mon Mar 12 14:40:25 UTC 2018
[ 0.000000] Machine model: Marvell 8040 MACHIATOBin
[ 0.000000] efi: Getting EFI parameters from FDT:
[ 0.000000] efi: EFI v2.70 by EDK II
[ 0.000000] efi: SMBIOS 3.0=0xbfd00000 ACPI 2.0=0xb6760000
MEMATTR=0xb8973418 RNG=0xbffdbf98
[ 0.000000] random: fast init done
[ 0.000000] efi: seeding entropy pool
:
(I am using the latest EDKII master, the Marvell edk2-open-platform 17.10
banch, with all the latest mv-ddr/ atf /etc....).
The DT data appear there in die EFI boot, but the PCIe interface fails, and
results (I believe) in the CPU stall warnings:
[ 717.453025] INFO: rcu_preempt self-detected stall on CPU
:
:
[ 717.589783] armada8k_pcie_probe+0x140/0x240
:
Other times, the pcie gets further:
[ 3.312127] PCI: OF: host bridge /cp0/pcie at f2600000 ranges:
[ 3.317740] PCI: OF: IO 0xf9000000..0xf900ffff -> 0xf9000000
[ 3.323692] PCI: OF: MEM 0xc0000000..0xdfffffff -> 0xc0000000
[ 3.328915] random: crng init done
[ 4.326158] armada8k-pcie f2600000.pcie: phy link never came up
[ 4.332109] armada8k-pcie f2600000.pcie: Link not up after
reconfiguration
[ 4.339056] armada8k-pcie f2600000.pcie: PCI host bridge to bus 0000:00
[ 4.345705] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 4.351217] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] (bus
address [0xf9000000-0xf900ffff])
[ 4.360741] pci_bus 0000:00: root bus resource [mem
0xc0000000-0xdfffffff]
[ 4.367659] pci 0000:00:00.0: [11ab:0110] type 01 class 0x060400
[ 4.373708] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
[ 4.380562] pci 0000:00:00.0: supports D1 D2
[ 4.384853] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[ 4.390697] pci 0000:00:00.0: bridge configuration invalid ([bus
00-00]), reconfiguring
[ 4.398771] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[ 4.405427] pci 0000:00:00.0: BAR 0: assigned [mem 0xc0000000-0xc00fffff
64bit]
[ 4.412776] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 4.725111] pcieport 0000:00:00.0: Signaling PME with IRQ 56
[ 4.730842] pcieport 0000:00:00.0: AER enabled with IRQ 56
but then CPUs are still stalled on some incoming IRQ
[ 87.352768] dump_backtrace+0x0/0x150
[ 87.356445] show_stack+0x14/0x20
[ 87.359773] sched_show_task+0x14c/0x170
[ 87.363711] dump_cpu_task+0x40/0x50
[ 87.367300] rcu_dump_cpu_stacks+0x94/0xd8
[ 87.371412] rcu_check_callbacks+0x7ac/0x980
[ 87.375700] update_process_times+0x2c/0x58
[ 87.379900] tick_sched_handle.isra.5+0x30/0x50
[ 87.384449] tick_sched_timer+0x40/0x90
[ 87.388301] __hrtimer_run_queues+0x124/0x198
[ 87.392676] hrtimer_interrupt+0xe4/0x240
[ 87.396701] arch_timer_handler_phys+0x30/0x40
[ 87.401163] handle_percpu_devid_irq+0x78/0x130
[ 87.405712] generic_handle_irq+0x24/0x38
[ 87.409738] __handle_domain_irq+0x5c/0xb8
[ 87.413850] gic_handle_irq+0x58/0xb0
[ 87.417526] el1_irq+0xb0/0x128
[ 87.420678] __do_softirq+0xb0/0x228
[ 87.424267] irq_exit+0xbc/0xf0
[ 87.427421] __handle_domain_irq+0x60/0xb8
[ 87.431533] gic_handle_irq+0x58/0xb0
[ 87.435209] el1_irq+0xb0/0x128
Is anyone aware of any issue like this?
Regards,
Fred
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.einval.com/pipermail/macchiato/attachments/20180312/297f05e8/attachment-0001.html>
-------------- next part --------------
EFI stub: Booting Linux Kernel...
EFI stub: Using DTB from configuration table
EFI stub: Exiting boot services and installing virtual address map...
[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd081]
[ 0.000000] Linux version 4.16.0-rc5-mbcin-netronome-1-dirty (root at mcb1-cpt) (gcc version 5.4.0 20160609 (Ubuntu/Linaro 5.4.0-6ubuntu1~16.04.9)) #1 SMP PREEMPT Mon Mar 12 12:41:13 UTC 2018
[ 0.000000] Machine model: Marvell 8040 MACHIATOBin
[ 0.000000] efi: Getting EFI parameters from FDT:
[ 0.000000] efi: EFI v2.70 by EDK II
[ 0.000000] efi: SMBIOS 3.0=0xbfd00000 ACPI 2.0=0xb6760000 MEMATTR=0xb8973418 RNG=0xbffdbf98
[ 0.000000] random: fast init done
[ 0.000000] efi: seeding entropy pool
[ 0.000000] cma: Reserved 64 MiB at 0x00000000bb800000
[ 0.000000] psci: probing for conduit method from DT.
[ 0.000000] psci: PSCIv1.0 detected in firmware.
[ 0.000000] psci: Using standard PSCI v0.2 function IDs
[ 0.000000] psci: MIGRATE_INFO_TYPE not supported.
[ 0.000000] psci: SMC Calling Convention v1.0
[ 0.000000] percpu: Embedded 24 pages/cpu @ (ptrval) s57624 r8192 d32488 u98304
[ 0.000000] Detected PIPT I-cache on CPU0
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1031688
[ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.16.0-rc5-mbcin-netronome-1-dirty root=/dev/mmcblk1p2
[ 0.000000] log_buf_len individual max cpu contribution: 4096 bytes
[ 0.000000] log_buf_len total cpu_extra contributions: 12288 bytes
[ 0.000000] log_buf_len min size: 16384 bytes
[ 0.000000] log_buf_len: 32768 bytes
[ 0.000000] early log buf free: 14452(88%)
[ 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 0xb2700000-0xb6700000] (64MB) mapped at [ (ptrval)- (ptrval)]
[ 0.000000] Memory: 3964292K/4192256K available (9724K kernel code, 632K rwdata, 3020K rodata, 576K init, 303K bss, 162428K reserved, 65536K cma-reserved)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] Preemptible hierarchical RCU implementation.
[ 0.000000] RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4.
[ 0.000000] Tasks RCU enabled.
[ 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 0x00000000f022f000
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] GICv2m: DT overriding V2M MSI_TYPER (base:160, num:32)
[ 0.000000] GICv2m: range[mem 0xf0280000-0xf0280fff], SPI[160:191]
[ 0.000000] GICv2m: DT overriding V2M MSI_TYPER (base:192, num:32)
[ 0.000000] GICv2m: range[mem 0xf0290000-0xf0290fff], SPI[192:223]
[ 0.000000] GICv2m: DT overriding V2M MSI_TYPER (base:224, num:32)
[ 0.000000] GICv2m: range[mem 0xf02a0000-0xf02a0fff], SPI[224:255]
[ 0.000000] GICv2m: DT overriding V2M MSI_TYPER (base:256, num:32)
[ 0.000000] GICv2m: range[mem 0xf02b0000-0xf02b0fff], SPI[256:287]
[ 0.000000] arch_timer: cp15 timer(s) running at 25.00MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns
[ 0.000001] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns
[ 0.000089] Console: colour dummy device 80x25
[ 0.000210] console [tty0] enabled
[ 0.000221] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)
[ 0.000228] pid_max: default: 32768 minimum: 301
[ 0.000255] Security Framework initialized
[ 0.000282] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.000300] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.016006] ASID allocator initialised with 32768 entries
[ 0.024004] Hierarchical SRCU implementation.
[ 0.032092] Remapping and enabling EFI services.
[ 0.032164] EFI remap 0x00000000bf810000 => (ptrval)
[ 0.032218] EFI remap 0x00000000bfc90000 => (ptrval)
[ 0.032223] EFI remap 0x00000000f4284000 => (ptrval)
[ 0.032228] EFI remap 0x00000000f4700000 => (ptrval)
[ 0.032235] EFI remap 0x00000000f93c0000 => (ptrval)
[ 0.040016] smp: Bringing up secondary CPUs ...
[ 0.068175] Detected PIPT I-cache on CPU1
[ 0.068200] CPU1: Booted secondary processor 0x0000000001 [0x410fd081]
[ 0.096190] Detected PIPT I-cache on CPU2
[ 0.096209] CPU2: Booted secondary processor 0x0000000100 [0x410fd081]
[ 0.124211] Detected PIPT I-cache on CPU3
[ 0.124224] CPU3: Booted secondary processor 0x0000000101 [0x410fd081]
[ 0.124255] smp: Brought up 1 node, 4 CPUs
[ 0.124276] SMP: Total of 4 processors activated.
[ 0.124280] CPU features: detected feature: 32-bit EL0 Support
[ 0.124285] CPU features: detected feature: Kernel page table isolation (KPTI)
[ 0.127235] CPU: All CPU(s) started at EL2
[ 0.127247] alternatives: patching kernel code
[ 0.127562] devtmpfs: initialized
[ 0.128703] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.128714] futex hash table entries: 1024 (order: 5, 131072 bytes)
[ 0.129206] xor: measuring software checksum speed
[ 0.168127] 8regs : 8016.000 MB/sec
[ 0.208138] 8regs_prefetch: 7026.000 MB/sec
[ 0.248158] 32regs : 9429.000 MB/sec
[ 0.288178] 32regs_prefetch: 7882.000 MB/sec
[ 0.288183] xor: using function: 32regs (9429.000 MB/sec)
[ 0.288190] pinctrl core: initialized pinctrl subsystem
[ 0.288503] SMBIOS 3.0.0 present.
[ 0.288515] DMI: Marvell Armada 8040 MacchiatoBin/Armada 8040 MacchiatoBin, BIOS EDK II Mar 12 2018
[ 0.288621] NET: Registered protocol family 16
[ 0.288731] audit: initializing netlink subsys (disabled)
[ 0.288769] audit: type=2000 audit(0.288:1): state=initialized audit_enabled=0 res=1
[ 0.292310] cpuidle: using governor ladder
[ 0.292326] cpuidle: using governor menu
[ 0.292379] vdso: 2 pages (1 code @ 000000000482d52a, 1 data @ 00000000e36f362a)
[ 0.292390] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[ 0.292715] DMA: preallocated 256 KiB pool for atomic allocations
[ 0.292750] Serial: AMBA PL011 UART driver
[ 0.298104] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.298509] cryptd: max_cpu_qlen set to 1000
[ 0.364277] raid6: int64x1 gen() 1357 MB/s
[ 0.432311] raid6: int64x1 xor() 1044 MB/s
[ 0.500339] raid6: int64x2 gen() 2226 MB/s
[ 0.568363] raid6: int64x2 xor() 1649 MB/s
[ 0.636407] raid6: int64x4 gen() 3168 MB/s
[ 0.704430] raid6: int64x4 xor() 1842 MB/s
[ 0.772459] raid6: int64x8 gen() 3148 MB/s
[ 0.840509] raid6: int64x8 xor() 1742 MB/s
[ 0.908527] raid6: neonx1 gen() 2603 MB/s
[ 0.976572] raid6: neonx1 xor() 2406 MB/s
[ 1.044601] raid6: neonx2 gen() 4092 MB/s
[ 1.112632] raid6: neonx2 xor() 3561 MB/s
[ 1.180661] raid6: neonx4 gen() 5225 MB/s
[ 1.248697] raid6: neonx4 xor() 4062 MB/s
[ 1.316724] raid6: neonx8 gen() 5133 MB/s
[ 1.384757] raid6: neonx8 xor() 4238 MB/s
[ 1.384761] raid6: using algorithm neonx4 gen() 5225 MB/s
[ 1.384765] raid6: .... xor() 4062 MB/s, rmw enabled
[ 1.384769] raid6: using neon recovery algorithm
[ 1.385105] reg-fixed-voltage regulator-usb3-vbus0: could not find pctldev for node /cp0/config-space at f2000000/system-controller at 440000/pinctrl/xhci0-vbus-pins, deferring probe
[ 1.385209] vgaarb: loaded
[ 1.385323] SCSI subsystem initialized
[ 1.385457] usbcore: registered new interface driver usbfs
[ 1.385475] usbcore: registered new interface driver hub
[ 1.385500] usbcore: registered new device driver usb
[ 1.385663] pps_core: LinuxPPS API ver. 1 registered
[ 1.385669] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti at linux.it>
[ 1.385702] PTP clock support registered
[ 1.385799] Registered efivars operations
[ 1.386039] clocksource: Switched to clocksource arch_sys_counter
[ 1.386076] VFS: Disk quotas dquot_6.6.0
[ 1.386097] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 1.388054] NET: Registered protocol family 2
[ 1.388221] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes)
[ 1.388236] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[ 1.388302] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[ 1.388455] TCP: Hash tables configured (established 32768 bind 32768)
[ 1.388513] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[ 1.388531] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[ 1.388589] NET: Registered protocol family 1
[ 1.388735] RPC: Registered named UNIX socket transport module.
[ 1.388742] RPC: Registered udp transport module.
[ 1.388747] RPC: Registered tcp transport module.
[ 1.388751] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 1.388967] hw perfevents: unable to count PMU IRQs
[ 1.388975] hw perfevents: /ap806/config-space at f0000000/pmu: failed to register PMU devices!
[ 1.389088] kvm [1]: 8-bit VMID
[ 1.389093] kvm [1]: IDMAP page: 9ee000
[ 1.389097] kvm [1]: HYP VA range: 4000000000:7fffffffff
[ 1.389411] kvm [1]: vgic-v2 at f0240000
[ 1.389455] kvm [1]: vgic interrupt IRQ1
[ 1.389475] kvm [1]: virtual timer IRQ4
[ 1.389516] kvm [1]: Hyp mode initialized successfully
[ 1.390835] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[ 1.392925] NFS: Registering the id_resolver key type
[ 1.392943] Key type id_resolver registered
[ 1.392947] Key type id_legacy registered
[ 1.392953] Installing knfsd (copyright (C) 1996 okir at monad.swb.de).
[ 1.393017] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
[ 1.393191] fuse init (API version 7.26)
[ 1.393305] 9p: Installing v9fs 9p2000 file system support
[ 1.393737] async_tx: api initialized (async)
[ 1.393747] io scheduler noop registered
[ 1.393790] io scheduler cfq registered (default)
[ 1.393795] io scheduler mq-deadline registered
[ 1.393799] io scheduler kyber registered
[ 1.394939] armada-ap806-pinctrl f06f4000.system-controller:pinctrl: registered pinctrl driver
[ 1.395176] armada-cp110-pinctrl f2440000.system-controller:pinctrl: registered pinctrl driver
[ 1.395329] armada-cp110-pinctrl f4440000.system-controller:pinctrl: registered pinctrl driver
[ 1.397235] mv_xor_v2 f0400000.xor: Marvell Version 2 XOR driver
[ 1.397419] mv_xor_v2 f0420000.xor: Marvell Version 2 XOR driver
[ 1.397622] mv_xor_v2 f0440000.xor: Marvell Version 2 XOR driver
[ 1.397810] mv_xor_v2 f0460000.xor: Marvell Version 2 XOR driver
[ 1.398068] mv_xor_v2 f26a0000.xor: Marvell Version 2 XOR driver
[ 1.398262] mv_xor_v2 f26c0000.xor: Marvell Version 2 XOR driver
[ 1.398532] mv_xor_v2 f46a0000.xor: Marvell Version 2 XOR driver
[ 1.398729] mv_xor_v2 f46c0000.xor: Marvell Version 2 XOR driver
[ 1.399681] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 1.420516] f0512000.serial: ttyS0 at MMIO 0xf0512000 (irq = 7, base_baud = 12500000) is a 16550A
[ 2.417913] console [ttyS0] enabled
[ 2.422069] omap_rng f2760000.trng: Random Number Generator ver. 203b34c
[ 2.428894] omap_rng f4760000.trng: Random Number Generator ver. 203b34c
[ 2.435801] cacheinfo: Unable to detect cache hierarchy for CPU 0
[ 2.442161] brd: module loaded
[ 2.447077] loop: module loaded
[ 2.450701] ahci f2540000.sata: AHCI 0001.0000 32 slots 2 ports 6 Gbps 0x3 impl platform mode
[ 2.459290] ahci f2540000.sata: flags: 64bit ncq sntf led only pmp fbs pio slum part sxs
[ 2.467921] scsi host0: ahci
[ 2.470981] scsi host1: ahci
[ 2.473927] ata1: SATA max UDMA/133 mmio [mem 0xf2540000-0xf256ffff] port 0x100 irq 28
[ 2.481899] ata2: SATA max UDMA/133 mmio [mem 0xf2540000-0xf256ffff] port 0x180 irq 28
[ 2.490027] ahci f4540000.sata: AHCI 0001.0000 32 slots 2 ports 6 Gbps 0x3 impl platform mode
[ 2.498605] ahci f4540000.sata: flags: 64bit ncq sntf led only pmp fbs pio slum part sxs
[ 2.507222] scsi host2: ahci
[ 2.510222] scsi host3: ahci
[ 2.513158] ata3: SATA max UDMA/133 mmio [mem 0xf4540000-0xf456ffff] port 0x100 irq 29
[ 2.521117] ata4: SATA max UDMA/133 mmio [mem 0xf4540000-0xf456ffff] port 0x180 irq 29
[ 2.529679] m25p80 spi4.0: w25q32 (4096 Kbytes)
[ 2.534508] Ethernet Channel Bonding Driver: v3.7.1 (April 27, 2011)
[ 2.541419] libphy: Fixed MDIO Bus: probed
[ 2.545640] tun: Universal TUN/TAP device driver, 1.6
[ 2.550814] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[ 2.556678] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[ 2.562641] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k
[ 2.569637] igb: Copyright (c) 2007-2014 Intel Corporation.
[ 2.575250] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[ 2.583118] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[ 2.589077] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k
[ 2.596771] ixgbe: Copyright (c) 1999-2016 Intel Corporation.
[ 2.602591] ixgbevf: Intel(R) 10 Gigabit PCI Express Virtual Function Network Driver - version 4.1.0-k
[ 2.611944] ixgbevf: Copyright (c) 2009 - 2015 Intel Corporation.
[ 2.618103] ixgb: Intel(R) PRO/10GbE Network Driver - version 1.0.135-k2-NAPI
[ 2.625273] ixgb: Copyright (c) 1999-2008 Intel Corporation.
[ 2.631104] libphy: orion_mdio_bus: probed
[ 2.635557] libphy: orion_mdio_bus: probed
[ 2.650237] mvpp2 f2000000.ethernet eth0: Using random mac address 22:7e:98:2c:19:d6
[ 2.666281] mvpp2 f4000000.ethernet eth1: Using random mac address 9a:22:ca:31:27:25
[ 2.682123] mvpp2 f4000000.ethernet eth2: Using random mac address 3a:e4:80:f5:ad:d7
[ 2.689992] sky2: driver version 1.30
[ 2.693808] usbcore: registered new interface driver asix
[ 2.699260] usbcore: registered new interface driver ax88179_178a
[ 2.705394] usbcore: registered new interface driver cdc_ether
[ 2.711265] usbcore: registered new interface driver net1080
[ 2.716963] usbcore: registered new interface driver cdc_subset
[ 2.722920] usbcore: registered new interface driver zaurus
[ 2.728533] usbcore: registered new interface driver cdc_ncm
[ 2.734287] VFIO - User Level meta-driver version: 0.3
[ 2.739655] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 2.746218] ehci-pci: EHCI PCI platform driver
[ 2.750697] ehci-platform: EHCI generic platform driver
[ 2.755995] ehci-orion: EHCI orion driver
[ 2.760064] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 2.766279] ohci-pci: OHCI PCI platform driver
[ 2.770758] ohci-platform: OHCI generic platform driver
[ 2.776219] xhci-hcd f2500000.usb3: xHCI Host Controller
[ 2.781571] xhci-hcd f2500000.usb3: new USB bus registered, assigned bus number 1
[ 2.789216] xhci-hcd f2500000.usb3: hcc params 0x0a000990 hci version 0x100 quirks 0x00010010
[ 2.797803] xhci-hcd f2500000.usb3: irq 48, io mem 0xf2500000
[ 2.803807] hub 1-0:1.0: USB hub found
[ 2.804192] ata2: SATA link down (SStatus 0 SControl 300)
[ 2.807588] hub 1-0:1.0: 1 port detected
[ 2.813027] ata1: SATA link down (SStatus 0 SControl 300)
[ 2.817039] xhci-hcd f2500000.usb3: xHCI Host Controller
[ 2.827716] xhci-hcd f2500000.usb3: new USB bus registered, assigned bus number 2
[ 2.835253] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 2.842301] ata4: SATA link down (SStatus 0 SControl 300)
[ 2.843520] hub 2-0:1.0: USB hub found
[ 2.848836] ata3: SATA link down (SStatus 0 SControl 300)
[ 2.852590] hub 2-0:1.0: 1 port detected
[ 2.862145] xhci-hcd f2510000.usb3: xHCI Host Controller
[ 2.867493] xhci-hcd f2510000.usb3: new USB bus registered, assigned bus number 3
[ 2.875129] xhci-hcd f2510000.usb3: hcc params 0x0a000990 hci version 0x100 quirks 0x00010010
[ 2.883710] xhci-hcd f2510000.usb3: irq 49, io mem 0xf2510000
[ 2.889675] hub 3-0:1.0: USB hub found
[ 2.893454] hub 3-0:1.0: 1 port detected
[ 2.897476] xhci-hcd f2510000.usb3: xHCI Host Controller
[ 2.902819] xhci-hcd f2510000.usb3: new USB bus registered, assigned bus number 4
[ 2.910355] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[ 2.918646] hub 4-0:1.0: USB hub found
[ 2.922425] hub 4-0:1.0: 1 port detected
[ 2.926658] usbcore: registered new interface driver usb-storage
[ 2.932823] mousedev: PS/2 mouse device common for all mice
[ 2.938688] armada38x-rtc f4284000.rtc: rtc core: registered f4284000.rtc as rtc0
[ 2.946323] rtc-efi rtc-efi: rtc core: registered rtc-efi as rtc1
[ 2.952461] i2c /dev entries driver
[ 2.962306] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel at redhat.com
[ 2.970933] sdhci: Secure Digital Host Controller Interface driver
[ 2.977144] sdhci: Copyright(c) Pierre Ossman
[ 2.981521] sdhci-pltfm: SDHCI platform and OF driver helper
[ 2.987466] mmc0: Switching to 3.3V signalling voltage failed
[ 3.018359] mmc0: SDHCI controller on f06e0000.sdhci [f06e0000.sdhci] using ADMA 64-bit
[ 3.057163] mmc1: SDHCI controller on f2780000.sdhci [f2780000.sdhci] using ADMA 64-bit
[ 3.060729] mmc0: new high speed MMC card at address 0001
[ 3.070695] ledtrig-cpu: registered to indicate activity on CPUs
[ 3.070988] mmcblk0: mmc0:0001 8GME4R 7.28 GiB
[ 3.077158] usbcore: registered new interface driver usbhid
[ 3.081588] mmcblk0boot0: mmc0:0001 8GME4R partition 1 4.00 MiB
[ 3.086905] usbhid: USB HID core driver
[ 3.093133] mmcblk0boot1: mmc0:0001 8GME4R partition 2 4.00 MiB
[ 3.102749] mmcblk0rpmb: mmc0:0001 8GME4R partition 3 512 KiB, chardev (243:0)
[ 3.102859] NET: Registered protocol family 10
[ 3.114291] mmc0: Switching to 3.3V signalling voltage failed
[ 3.114812] Segment Routing with IPv6
[ 3.124046] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 3.130173] NET: Registered protocol family 17
[ 3.134660] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[ 3.144523] mmc1: new high speed SDHC card at address e624
[ 3.147818] 8021q: 802.1Q VLAN Support v1.8
[ 3.153515] mmcblk1: mmc1:e624 SL16G 14.8 GiB
[ 3.157423] 9pnet: Installing 9P2000 support
[ 3.166194] Key type dns_resolver registered
[ 3.170488] openvswitch: Open vSwitch switching datapath
[ 3.174939] mmcblk1: p1 p2 p3
[ 3.175943] mpls_gso: MPLS GSO support
[ 3.182858] registered taskstats version 1
[ 3.187486] Btrfs loaded, crc32c=crc32c-generic
[ 3.193306] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[ 3.201658] PCI: OF: host bridge /cp0/pcie at f2600000 ranges:
[ 3.207271] PCI: OF: IO 0xf9000000..0xf900ffff -> 0xf9000000
[ 3.213227] PCI: OF: MEM 0xc0000000..0xdfffffff -> 0xc0000000
[ 3.218451] random: crng init done
[ 4.218042] armada8k-pcie f2600000.pcie: phy link never came up
[ 4.223994] armada8k-pcie f2600000.pcie: Link not up after reconfiguration
[ 4.230945] armada8k-pcie f2600000.pcie: PCI host bridge to bus 0000:00
[ 4.237596] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 4.243110] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] (bus address [0xf9000000-0xf900ffff])
[ 4.252635] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xdfffffff]
[ 4.259685] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[ 4.267777] pci 0000:00:00.0: BAR 0: assigned [mem 0xc0000000-0xc00fffff 64bit]
[ 4.275127] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 4.587473] pcieport 0000:00:00.0: Signaling PME with IRQ 56
[ 4.593213] pcieport 0000:00:00.0: AER enabled with IRQ 56
[ 4.598929] xhci-hcd f4500000.usb3: xHCI Host Controller
[ 4.604279] xhci-hcd f4500000.usb3: new USB bus registered, assigned bus number 5
[ 4.611929] xhci-hcd f4500000.usb3: hcc params 0x0a000990 hci version 0x100 quirks 0x00010010
[ 4.620511] xhci-hcd f4500000.usb3: irq 50, io mem 0xf4500000
[ 4.626513] hub 5-0:1.0: USB hub found
[ 4.630294] hub 5-0:1.0: 1 port detected
[ 4.634324] xhci-hcd f4500000.usb3: xHCI Host Controller
[ 4.639667] xhci-hcd f4500000.usb3: new USB bus registered, assigned bus number 6
[ 4.647204] usb usb6: We don't know the algorithms for LPM for this host, disabling LPM.
[ 4.655471] hub 6-0:1.0: USB hub found
[ 4.659251] hub 6-0:1.0: 1 port detected
[ 4.663455] armada38x-rtc f4284000.rtc: setting system clock to 2018-03-12 14:00:20 UTC (1520863220)
[ 4.674894] md: Waiting for all devices to be available before autodetect
[ 4.681722] md: If you don't use raid, use raid=noautodetect
[ 4.687580] md: Autodetecting RAID arrays.
[ 4.691699] md: autorun ...
[ 4.694506] md: ... autorun DONE.
[ 24.198037] INFO: rcu_preempt self-detected stall on CPU
[ 24.203376] 0-...!: (5250 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=0
[ 24.210540]
[ 24.210542] INFO: rcu_preempt detected stalls on CPUs/tasks:
[ 24.212127] (t=5253 jiffies g=-215 c=-216 q=294)
[ 24.217811] 0-...!: (5250 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=0
[ 24.222534] rcu_preempt kthread starved for 5256 jiffies! g18446744073709551401 c18446744073709551400 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x402 ->cpu=0
[ 24.229696]
[ 24.242967] rcu_preempt I
[ 24.242970] (detected by 2, t=5261 jiffies, g=-215, c=-216, q=294)
[ 24.244552] 0 8 2 0x00000000
[ 24.247530] Task dump for CPU 0:
[ 24.253735] Call trace:
[ 24.257761] swapper/0 R
[ 24.261007] __switch_to+0x8c/0xd0
[ 24.261012] __schedule+0x1f8/0x5b0
[ 24.263464] running task
[ 24.266444] schedule+0x38/0xa0
[ 24.269858] 0 0 0 0x00000002
[ 24.273360] schedule_timeout+0x174/0x340
[ 24.276424] Call trace:
[ 24.279578] rcu_gp_kthread+0x4a4/0x750
[ 24.283606] __switch_to+0x8c/0xd0
[ 24.287627] kthread+0xf8/0x128
[ 24.290081] (null)
[ 24.293932] ret_from_fork+0x10/0x18
[ 24.297347] rcu_preempt kthread starved for 5274 jiffies! g18446744073709551401 c18446744073709551400 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x402 ->cpu=0
[ 24.300500] Task dump for CPU 0:
[ 24.303476] rcu_preempt R
[ 24.307064] swapper/0 R
[ 24.320334] running task
[ 24.323573] running task 0 0 0 0x00000002
[ 24.326554] 0 8 2 0x00000000
[ 24.329532] Call trace:
[ 24.332598] Call trace:
[ 24.338193] dump_backtrace+0x0/0x150
[ 24.338196] show_stack+0x14/0x20
[ 24.342220] __switch_to+0x8c/0xd0
[ 24.344676] sched_show_task+0x14c/0x170
[ 24.347131] try_to_del_timer_sync+0x18/0x60
[ 24.350806] dump_cpu_task+0x40/0x50
[ 24.354133] del_timer_sync+0x44/0x58
[ 24.357546] rcu_dump_cpu_stacks+0x94/0xd8
[ 24.357548] rcu_check_callbacks+0x7ac/0x980
[ 24.361487] schedule_timeout+0x17c/0x340
[ 24.365772] update_process_times+0x2c/0x58
[ 24.369360] rcu_gp_kthread+0x4a4/0x750
[ 24.373037] tick_sched_handle.isra.5+0x30/0x50
[ 24.373039] tick_sched_timer+0x40/0x90
[ 24.377151] kthread+0xf8/0x128
[ 24.381439] __hrtimer_run_queues+0x124/0x198
[ 24.385463] ret_from_fork+0x10/0x18
[ 24.389662] hrtimer_interrupt+0xe4/0x240
[ 24.417044] arch_timer_handler_phys+0x30/0x40
[ 24.421505] handle_percpu_devid_irq+0x78/0x130
[ 24.426055] generic_handle_irq+0x24/0x38
[ 24.430080] __handle_domain_irq+0x5c/0xb8
[ 24.434193] gic_handle_irq+0x58/0xb0
[ 24.437868] el1_irq+0xb0/0x128
[ 24.441022] __do_softirq+0xb0/0x228
[ 24.444613] irq_exit+0xbc/0xf0
[ 24.447767] __handle_domain_irq+0x60/0xb8
[ 24.451879] gic_handle_irq+0x58/0xb0
[ 24.455555] el1_irq+0xb0/0x128
[ 24.458708] arch_cpu_idle+0x10/0x18
[ 24.462297] do_idle+0x11c/0x1e0
[ 24.465538] cpu_startup_entry+0x24/0x28
[ 24.469477] rest_init+0xcc/0xd8
[ 24.472719] start_kernel+0x38c/0x3a0
^C[ 87.218037] INFO: rcu_preempt self-detected stall on CPU
[ 87.223374] 0-....: (20937 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=6268
[ 87.230888]
[ 87.230889] INFO: rcu_preempt detected stalls on CPUs/tasks:
[ 87.232474] (t=21008 jiffies g=-215 c=-216 q=294)
[ 87.238158] 0-....: (20937 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=6268
[ 87.250478]
[ 87.250479] Task dump for CPU 0:
[ 87.250481] swapper/0 R
[ 87.252063] (detected by 2, t=21013 jiffies, g=-215, c=-216, q=294)
[ 87.255301] running task 0 0 0 0x00000002
[ 87.270167] Call trace:
[ 87.272623] dump_backtrace+0x0/0x150
[ 87.276300] show_stack+0x14/0x20
[ 87.279628] sched_show_task+0x14c/0x170
[ 87.283566] dump_cpu_task+0x40/0x50
[ 87.287155] rcu_dump_cpu_stacks+0x94/0xd8
[ 87.291267] rcu_check_callbacks+0x7ac/0x980
[ 87.295555] update_process_times+0x2c/0x58
[ 87.299755] tick_sched_handle.isra.5+0x30/0x50
[ 87.304304] tick_sched_timer+0x40/0x90
[ 87.308155] __hrtimer_run_queues+0x124/0x198
[ 87.312531] hrtimer_interrupt+0xe4/0x240
[ 87.316555] arch_timer_handler_phys+0x30/0x40
[ 87.321016] handle_percpu_devid_irq+0x78/0x130
[ 87.325565] generic_handle_irq+0x24/0x38
[ 87.329590] __handle_domain_irq+0x5c/0xb8
[ 87.333702] gic_handle_irq+0x58/0xb0
[ 87.337379] el1_irq+0xb0/0x128
[ 87.340532] __do_softirq+0xb0/0x228
[ 87.344122] irq_exit+0xbc/0xf0
[ 87.347275] __handle_domain_irq+0x60/0xb8
[ 87.351387] gic_handle_irq+0x58/0xb0
[ 87.355064] el1_irq+0xb0/0x128
[ 87.358217] arch_cpu_idle+0x10/0x18
[ 87.361806] do_idle+0x11c/0x1e0
[ 87.365047] cpu_startup_entry+0x24/0x28
[ 87.368985] rest_init+0xcc/0xd8
[ 87.372226] start_kernel+0x38c/0x3a0
[ 87.375902] Task dump for CPU 0:
[ 87.379144] swapper/0 R running task 0 0 0 0x00000002
[ 87.386224] Call trace:
[ 87.388680] __switch_to+0x8c/0xd0
[ 87.392095] (null)
[ 150.238036] INFO: rcu_preempt self-detected stall on CPU
[ 150.243373] 0-....: (36649 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=13270
[ 150.250974]
[ 150.250975] INFO: rcu_preempt detected stalls on CPUs/tasks:
[ 150.252560] (t=36762 jiffies g=-215 c=-216 q=294)
[ 150.258244] 0-....: (36649 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=13270
[ 150.270652]
[ 150.270653] Task dump for CPU 0:
[ 150.270655] swapper/0 R
[ 150.272237] (detected by 3, t=36762 jiffies, g=-215, c=-216, q=294)
[ 150.275474] running task 0 0 0 0x00000002
[ 150.290339] Call trace:
[ 150.292795] dump_backtrace+0x0/0x150
[ 150.296471] show_stack+0x14/0x20
[ 150.299800] sched_show_task+0x14c/0x170
[ 150.303738] dump_cpu_task+0x40/0x50
[ 150.307328] rcu_dump_cpu_stacks+0x94/0xd8
[ 150.311441] rcu_check_callbacks+0x7ac/0x980
[ 150.315729] update_process_times+0x2c/0x58
[ 150.319928] tick_sched_handle.isra.5+0x30/0x50
[ 150.324477] tick_sched_timer+0x40/0x90
[ 150.328329] __hrtimer_run_queues+0x124/0x198
[ 150.332704] hrtimer_interrupt+0xe4/0x240
[ 150.336729] arch_timer_handler_phys+0x30/0x40
[ 150.341190] handle_percpu_devid_irq+0x78/0x130
[ 150.345739] generic_handle_irq+0x24/0x38
[ 150.349765] __handle_domain_irq+0x5c/0xb8
[ 150.353878] gic_handle_irq+0x58/0xb0
[ 150.357554] el1_irq+0xb0/0x128
[ 150.360708] __do_softirq+0xb0/0x228
[ 150.364297] irq_exit+0xbc/0xf0
[ 150.367451] __handle_domain_irq+0x60/0xb8
[ 150.371564] gic_handle_irq+0x58/0xb0
[ 150.375241] el1_irq+0xb0/0x128
[ 150.378395] arch_cpu_idle+0x10/0x18
[ 150.381984] do_idle+0x11c/0x1e0
[ 150.385224] cpu_startup_entry+0x24/0x28
[ 150.389163] rest_init+0xcc/0xd8
[ 150.392403] start_kernel+0x38c/0x3a0
[ 150.396079] Task dump for CPU 0:
[ 150.399321] swapper/0 R running task 0 0 0 0x00000002
[ 150.406402] Call trace:
[ 150.408859] __switch_to+0x8c/0xd0
[ 150.412274] (null)
[ 213.258037] INFO: rcu_preempt self-detected stall on CPU
[ 213.263374] 0-....: (52361 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=21127
[ 213.270975]
[ 213.270976] INFO: rcu_preempt detected stalls on CPUs/tasks:
[ 213.272561] (t=52517 jiffies g=-215 c=-216 q=294)
[ 213.278245] 0-....: (52361 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=21127
[ 213.290653]
[ 213.290654] Task dump for CPU 0:
[ 213.290655] swapper/0 R
[ 213.292238] (detected by 3, t=52517 jiffies, g=-215, c=-216, q=294)
[ 213.295475] running task 0 0 0 0x00000002
[ 213.310340] Call trace:
[ 213.312797] dump_backtrace+0x0/0x150
[ 213.316473] show_stack+0x14/0x20
[ 213.319802] sched_show_task+0x14c/0x170
[ 213.323740] dump_cpu_task+0x40/0x50
[ 213.327329] rcu_dump_cpu_stacks+0x94/0xd8
[ 213.331442] rcu_check_callbacks+0x7ac/0x980
[ 213.335730] update_process_times+0x2c/0x58
[ 213.339930] tick_sched_handle.isra.5+0x30/0x50
[ 213.344479] tick_sched_timer+0x40/0x90
[ 213.348330] __hrtimer_run_queues+0x124/0x198
[ 213.352705] hrtimer_interrupt+0xe4/0x240
[ 213.356731] arch_timer_handler_phys+0x30/0x40
[ 213.361192] handle_percpu_devid_irq+0x78/0x130
[ 213.365742] generic_handle_irq+0x24/0x38
[ 213.369767] __handle_domain_irq+0x5c/0xb8
[ 213.373879] gic_handle_irq+0x58/0xb0
[ 213.377556] el1_irq+0xb0/0x128
[ 213.380709] __do_softirq+0xb0/0x228
[ 213.384298] irq_exit+0xbc/0xf0
[ 213.387452] __handle_domain_irq+0x60/0xb8
[ 213.391564] gic_handle_irq+0x58/0xb0
[ 213.395240] el1_irq+0xb0/0x128
[ 213.398394] arch_cpu_idle+0x10/0x18
[ 213.401983] do_idle+0x11c/0x1e0
[ 213.405224] cpu_startup_entry+0x24/0x28
[ 213.409163] rest_init+0xcc/0xd8
[ 213.412404] start_kernel+0x38c/0x3a0
[ 213.416080] Task dump for CPU 0:
[ 213.419321] swapper/0 R running task 0 0 0 0x00000002
[ 213.426402] Call trace:
[ 213.428858] __switch_to+0x8c/0xd0
[ 213.432272] (null)
[ 242.658041] INFO: task swapper/0:1 blocked for more than 120 seconds.
[ 242.664514] Not tainted 4.16.0-rc5-mbcin-netronome-1-dirty #1
[ 242.670810] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 242.678677] swapper/0 D 0 1 0 0x00000020
[ 242.684189] Call trace:
[ 242.686648] __switch_to+0x8c/0xd0
[ 242.690067] __schedule+0x1f8/0x5b0
[ 242.693569] schedule+0x38/0xa0
[ 242.696727] io_schedule+0x18/0x38
[ 242.700145] bit_wait_io+0x14/0x60
[ 242.703563] __wait_on_bit+0xa4/0xe0
[ 242.707156] out_of_line_wait_on_bit+0x6c/0x78
[ 242.711623] __wait_on_buffer+0x2c/0x38
[ 242.715477] __bread_gfp+0xa8/0xd8
[ 242.718897] ext4_fill_super+0x18c/0x2eb0
[ 242.722926] mount_bdev+0x190/0x1b8
[ 242.726432] ext4_mount+0x14/0x20
[ 242.729760] mount_fs+0x4c/0x168
[ 242.733004] vfs_kern_mount+0x50/0x118
[ 242.736770] do_mount+0x1b8/0xbc0
[ 242.740100] SyS_mount+0x90/0xf8
[ 242.743344] mount_block_root+0x100/0x25c
[ 242.747373] mount_root+0x11c/0x134
[ 242.750879] prepare_namespace+0x174/0x1bc
[ 242.755001] kernel_init_freeable+0x1b0/0x1d4
[ 242.759380] kernel_init+0x10/0x100
[ 242.762885] ret_from_fork+0x10/0x18
[ 242.766478] INFO: task kworker/1:0:16 blocked for more than 120 seconds.
[ 242.773210] Not tainted 4.16.0-rc5-mbcin-netronome-1-dirty #1
[ 242.779505] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 242.787372] kworker/1:0 D 0 16 2 0x00000020
[ 242.792891] Workqueue: events_freezable mmc_rescan
[ 242.797705] Call trace:
[ 242.800164] __switch_to+0x8c/0xd0
[ 242.803582] __schedule+0x1f8/0x5b0
[ 242.807088] schedule+0x38/0xa0
[ 242.810245] schedule_timeout+0x1a4/0x340
[ 242.814274] wait_for_common+0x98/0x140
[ 242.818128] wait_for_completion+0x14/0x20
[ 242.822244] mmc_wait_for_req_done+0x3c/0x148
[ 242.826621] mmc_wait_for_req+0x98/0xd8
[ 242.830475] mmc_wait_for_cmd+0x54/0x70
[ 242.834330] __mmc_send_status+0x54/0x70
[ 242.838272] mmc_send_status+0x10/0x18
[ 242.842039] mmc_sd_alive+0x14/0x20
[ 242.845541] _mmc_detect_card_removed.part.11+0x1c/0x90
[ 242.850791] _mmc_detect_card_removed+0x2c/0x48
[ 242.855343] mmc_sd_detect+0x24/0x78
[ 242.858934] mmc_rescan+0x1c4/0x390
[ 242.862439] process_one_work+0x114/0x320
[ 242.866467] worker_thread+0x130/0x468
[ 242.870234] kthread+0xf8/0x128
[ 242.873388] ret_from_fork+0x10/0x18
[ 242.876981] INFO: task kworker/2:0H:22 blocked for more than 120 seconds.
[ 242.883806] Not tainted 4.16.0-rc5-mbcin-netronome-1-dirty #1
[ 242.890103] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 242.897969] kworker/2:0H D 0 22 2 0x00000000
[ 242.903488] Workqueue: kblockd blk_mq_run_work_fn
[ 242.908214] Call trace:
[ 242.910673] __switch_to+0x8c/0xd0
[ 242.914093] __schedule+0x1f8/0x5b0
[ 242.917595] schedule+0x38/0xa0
[ 242.920752] __mmc_claim_host+0xa0/0x1d8
[ 242.924693] mmc_get_card+0x30/0x40
[ 242.928199] mmc_mq_queue_rq+0x214/0x228
[ 242.932141] blk_mq_dispatch_rq_list+0xf8/0x528
[ 242.936693] blk_mq_do_dispatch_sched+0x64/0xe0
[ 242.941245] blk_mq_sched_dispatch_requests+0x120/0x1b0
[ 242.946495] __blk_mq_run_hw_queue+0x94/0xe8
[ 242.950785] blk_mq_run_work_fn+0x50/0x68
[ 242.954815] process_one_work+0x114/0x320
[ 242.958843] worker_thread+0x130/0x468
[ 242.962609] kthread+0xf8/0x128
[ 242.965763] ret_from_fork+0x10/0x18
[ 276.278037] INFO: rcu_preempt self-detected stall on CPU
[ 276.283374] 0-....: (68073 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=28984
[ 276.290975]
[ 276.290976] INFO: rcu_preempt detected stalls on CPUs/tasks:
[ 276.292560] (t=68272 jiffies g=-215 c=-216 q=294)
[ 276.298244] 0-....: (68073 ticks this GP) idle=dc6/0/3 softirq=185/185 fqs=28984
[ 276.310651]
[ 276.310652] Task dump for CPU 0:
[ 276.310654] swapper/0 R
[ 276.312236] (detected by 3, t=68272 jiffies, g=-215, c=-216, q=294)
[ 276.315475] running task 0 0 0 0x00000002
[ 276.330340] Call trace:
[ 276.332795] dump_backtrace+0x0/0x150
[ 276.336472] show_stack+0x14/0x20
[ 276.339801] sched_show_task+0x14c/0x170
[ 276.343739] dump_cpu_task+0x40/0x50
[ 276.347328] rcu_dump_cpu_stacks+0x94/0xd8
[ 276.351440] rcu_check_callbacks+0x7ac/0x980
[ 276.355728] update_process_times+0x2c/0x58
[ 276.359929] tick_sched_handle.isra.5+0x30/0x50
[ 276.364477] tick_sched_timer+0x40/0x90
[ 276.368329] __hrtimer_run_queues+0x124/0x198
[ 276.372703] hrtimer_interrupt+0xe4/0x240
[ 276.376728] arch_timer_handler_phys+0x30/0x40
[ 276.381189] handle_percpu_devid_irq+0x78/0x130
[ 276.385737] generic_handle_irq+0x24/0x38
[ 276.389762] __handle_domain_irq+0x5c/0xb8
[ 276.393875] gic_handle_irq+0x58/0xb0
[ 276.397552] el1_irq+0xb0/0x128
[ 276.400705] __do_softirq+0xb0/0x228
[ 276.404295] irq_exit+0xbc/0xf0
[ 276.407448] __handle_domain_irq+0x60/0xb8
[ 276.411560] gic_handle_irq+0x58/0xb0
[ 276.415236] el1_irq+0xb0/0x128
[ 276.418389] arch_cpu_idle+0x10/0x18
[ 276.421979] do_idle+0x11c/0x1e0
[ 276.425219] cpu_startup_entry+0x24/0x28
[ 276.429158] rest_init+0xcc/0xd8
[ 276.432399] start_kernel+0x38c/0x3a0
[ 276.436076] Task dump for CPU 0:
[ 276.439317] swapper/0 R running task 0 0 0 0x00000002
[ 276.446397] Call trace:
[ 276.448854] __switch_to+0x8c/0xd0
[ 276.452267] (null)
More information about the Macchiato
mailing list