Re: [powerpc][next-20210625] Kernel warning(arch/powerpc/kernel/interrupt.c:518) during boot

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



> On 27-Jun-2021, at 3:36 PM, Nicholas Piggin <npiggin@xxxxxxxxx> wrote:
>> 
>> So there's definitely IRQMASK=0 and no MSR[EE]=0 in this frame, which is 
>> what the warning was.
>> 
>> I'd say either something hasn't set PACA_IRQ_HARD_DIS properly, so EE 
>> doesn't get enabled when irqs are restored, or maybe the  change to
>> arch_local_irq_restore(). Less likely that the stack got messed up.
>> 
>> Can you try run with CONFIG_PPC_IRQ_SOFT_MASK_DEBUG=y ?
> 
> Nevermind, I think I've found the problem. Some code runs in the
> implicit soft-mask region without expecting to be masked. Working
> on a fix…

:-) . I was able to recreate this after few attempts. It seem the warning isn’t
always triggered during boot. I had to run a kernel compile operation after
boot to trigger this warning again.

In case its helpful here is the additional trace with PPC_IRQ_SOFT_MASK_DEBUG.

[   92.106731] ------------[ cut here ]------------
[   92.106738] WARNING: CPU: 45 PID: 12757 at arch/powerpc/kernel/irq.c:255 arch_local_irq_restore+0x1d0/0x200
[   92.106753] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts vmx_crypto uio_pdrv_genirq uio sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
[   92.106828] CPU: 45 PID: 12757 Comm: sh Kdump: loaded Tainted: G        W         5.13.0-rc7-next-20210625 #1
[   92.106841] NIP:  c0000000000164d0 LR: c000000000cedaa8 CTR: 0000000000000000
[   92.106849] REGS: c00000008dfeb7e0 TRAP: 0700   Tainted: G        W          (5.13.0-rc7-next-20210625)
[   92.106859] MSR:  8000000002823033 <SF,VEC,VSX,FP,ME,IR,DR,RI,LE>  CR: 28004222  XER: 00000000
[   92.106892] CFAR: c00000000001632c IRQMASK: 0 
               GPR00: c000000000ceda98 c00000008dfeba80 c000000002921e00 0000000000000000 
               GPR04: 0000000000000000 0000000000000000 0000000000000000 00000000000000ff 
               GPR08: 0000000000000001 0000000000000000 0000000000000001 0000000000000017 
               GPR12: 0000000024004822 c000000007fb9200 000000012efd81d4 000000012ee50000 
               GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40 
               GPR20: 00003fffa93f8000 0000000000000000 00003fffa93f9300 000000012efb1988 
               GPR24: 000000012ee7fe7c 000000012efccba0 000000012ee50000 c00000008d5d7600 
               GPR28: c0000000314c0bc0 c000000040d9f100 c0000008beb5861c 4b72201a3063fe13 
[   92.107024] NIP [c0000000000164d0] arch_local_irq_restore+0x1d0/0x200
[   92.107035] LR [c000000000cedaa8] _raw_spin_unlock_irqrestore+0x88/0xb0
[   92.107047] Call Trace:
[   92.107052] [c00000008dfeba80] [c00000008dfebb50] 0xc00000008dfebb50 (unreliable)
[   92.107065] [c00000008dfebab0] [238c5bf052df0858] 0x238c5bf052df0858
[   92.107076] [c00000008dfebae0] [c0000000008178e8] get_random_u64+0x88/0x100
[   92.107090] [c00000008dfebb20] [c000000000020134] arch_randomize_brk+0xb4/0xd8
[   92.107105] [c00000008dfebb50] [c0000000005430b0] load_elf_binary+0xe70/0x1220
[   92.107119] [c00000008dfebc40] [c00000000047ded0] bprm_execve+0x410/0x800
[   92.107132] [c00000008dfebd10] [c00000000047e8ec] do_execveat_common.isra.44+0x21c/0x240
[   92.107145] [c00000008dfebd80] [c00000000047e964] sys_execve+0x54/0x70
[   92.107157] [c00000008dfebdb0] [c000000000032334] system_call_exception+0x164/0x2e0
[   92.107169] [c00000008dfebe10] [c00000000000c464] system_call_common+0xf4/0x258
[   92.107185] --- interrupt: c00 at 0x3fff9bb6b8a8
[   92.107193] NIP:  00003fff9bb6b8a8 LR: 00003fff9bb6c240 CTR: 0000000000000000
[   92.107202] REGS: c00000008dfebe80 TRAP: 0c00   Tainted: G        W          (5.13.0-rc7-next-20210625)
[   92.107213] MSR:  800000000000f033 <SF,EE,PR,FP,ME,IR,DR,RI,LE>  CR: 28004224  XER: 00000000
[   92.107243] IRQMASK: 0 
               GPR00: 000000000000000b 00003fffc36a1440 00003fff9bc87300 00000100268a67d0 
               GPR04: 0000010026887e50 0000010026882c50 fefefefefefefeff 7f7f7f7f7f7f7f7f 
               GPR08: 00000100268a67d0 0000000000000000 0000000000000000 0000000000000000 
               GPR12: 0000000000000000 00003fff9bce3780 0000000114200db4 0000000000000000 
               GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40 
               GPR20: 00000001141dd820 0000000000000000 00000001141dd740 0000000114204358 
               GPR24: 0000000114203948 0000010026876454 0000000000000001 0000010026882c50 
               GPR28: 0000010026887e50 0000010026882c50 00000100268a67d0 00003fffc36a1440 
[   92.107369] NIP [00003fff9bb6b8a8] 0x3fff9bb6b8a8
[   92.107378] LR [00003fff9bb6c240] 0x3fff9bb6c240
[   92.107386] --- interrupt: c00
[   92.107393] Instruction dump:
[   92.107400] 7d2000a6 71298000 40820048 39200000 992d0152 39400000 992d0153 614a8002 
[   92.107427] 7d410164 4bfffe6c 60000000 60000000 <0fe00000> 4bfffe5c 60000000 60000000 
[   92.107451] ---[ end trace 5f1d49fb99f3613d ]—

Complete dmesg log attached.

Thanks
-Sachin

[    0.000000] Reserving 512MB of memory at 128MB for crashkernel (System RAM: 35840MB)
[    0.000000] hash-mmu: Page sizes from device-tree:
[    0.000000] hash-mmu: base_shift=12: shift=12, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=0
[    0.000000] hash-mmu: base_shift=12: shift=16, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=7
[    0.000000] hash-mmu: base_shift=12: shift=24, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=56
[    0.000000] hash-mmu: base_shift=16: shift=16, sllp=0x0110, avpnm=0x00000000, tlbiel=1, penc=1
[    0.000000] hash-mmu: base_shift=16: shift=24, sllp=0x0110, avpnm=0x00000000, tlbiel=1, penc=8
[    0.000000] hash-mmu: base_shift=24: shift=24, sllp=0x0100, avpnm=0x00000001, tlbiel=0, penc=0
[    0.000000] hash-mmu: base_shift=34: shift=34, sllp=0x0120, avpnm=0x000007ff, tlbiel=0, penc=3
[    0.000000] Enabling pkeys with max key count 8
[    0.000000] Activating Kernel Userspace Execution Prevention
[    0.000000] Activating Kernel Userspace Access Prevention
[    0.000000] Page orders: linear mapping = 24, virtual = 12, io = 12, vmemmap = 24
[    0.000000] Using 1TB segments
[    0.000000] hash-mmu: Initializing hash mmu with SLB
[    0.000000] Linux version 5.13.0-rc7-next-20210625 (root@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxx) (gcc (GCC) 8.4.1 20200928 (Red Hat 8.4.1-1), GNU ld version 2.30-93.el8) #1 SMP Sun Jun 27 05:45:43 CDT 2021
[    0.000000] Found initrd at 0xc00000000d400000:0xc000000010d118ce
[    0.000000] Using pSeries machine description
[    0.000000] printk: bootconsole [udbg0] enabled
[    0.000000] Partition configured for 96 cpus.
[    0.000000] CPU maps initialized for 8 threads per core
[    0.000000]  (thread shift is 3)
[    0.000000] Allocated 3328 bytes for 96 pacas
[    0.000000] -----------------------------------------------------
[    0.000000] phys_mem_size     = 0x8c0000000
[    0.000000] dcache_bsize      = 0x80
[    0.000000] icache_bsize      = 0x80
[    0.000000] cpu_features      = 0x0001c07b8f5f9187
[    0.000000]   possible        = 0x000ffbfbcf5fb187
[    0.000000]   always          = 0x0000000380008181
[    0.000000] cpu_user_features = 0xdc0065c2 0xeff00000
[    0.000000] mmu_features      = 0x7c006e01
[    0.000000] firmware_features = 0x0000009fc45bfc57
[    0.000000] vmalloc start     = 0xc0003d0000000000
[    0.000000] IO start          = 0xc0003e0000000000
[    0.000000] vmemmap start     = 0xc0003f0000000000
[    0.000000] hash-mmu: ppc64_pft_size    = 0x1c
[    0.000000] hash-mmu: htab_hash_mask    = 0x1fffff
[    0.000000] -----------------------------------------------------
[    0.000000] numa:   NODE_DATA [mem 0x8bfcd6c80-0x8bfcdbfff]
[    0.000000] rfi-flush: fallback displacement flush available
[    0.000000] rfi-flush: mttrig type flush available
[    0.000000] rfi-flush: patched 14 locations (mttrig type flush)
[    0.000000] count-cache-flush: flush disabled.
[    0.000000] link-stack-flush: software flush enabled.
[    0.000000] entry-flush: patched 61 locations (mttrig type flush)
[    0.000000] uaccess-flush: patched 1 locations (mttrig type flush)
[    0.000000] stf-barrier: eieio barrier available
[    0.000000] stf-barrier: patched 61 entry locations (eieio barrier)
[    0.000000] stf-barrier: patched 14 exit locations (eieio barrier)
[    0.000000] lpar: H_BLOCK_REMOVE supports base psize:0 psize:0 block size:8
[    0.000000] lpar: H_BLOCK_REMOVE supports base psize:0 psize:2 block size:8
[    0.000000] lpar: H_BLOCK_REMOVE supports base psize:0 psize:10 block size:8
[    0.000000] lpar: H_BLOCK_REMOVE supports base psize:2 psize:2 block size:8
[    0.000000] lpar: H_BLOCK_REMOVE supports base psize:2 psize:10 block size:8
[    0.000000] PPC64 nvram contains 15360 bytes
[    0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.000000] barrier-nospec: using ORI speculation barrier
[    0.000000] barrier-nospec: patched 389 locations
[    0.000000] Top of RAM: 0x8c0000000, Total RAM: 0x8c0000000
[    0.000000] Memory hole size: 0MB
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x00000008bfffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x00000008bfffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000008bfffffff]
[    0.000000] percpu: Embedded 37 pages/cpu s114456 r0 d37096 u262144
[    0.000000] pcpu-alloc: s114456 r0 d37096 u262144 alloc=1*1048576
[    0.000000] pcpu-alloc: [0] 00 01 02 03 [0] 04 05 06 07 
[    0.000000] pcpu-alloc: [0] 08 09 10 11 [0] 12 13 14 15 
[    0.000000] pcpu-alloc: [0] 16 17 18 19 [0] 20 21 22 23 
[    0.000000] pcpu-alloc: [0] 24 25 26 27 [0] 28 29 30 31 
[    0.000000] pcpu-alloc: [0] 32 33 34 35 [0] 36 37 38 39 
[    0.000000] pcpu-alloc: [0] 40 41 42 43 [0] 44 45 46 47 
[    0.000000] pcpu-alloc: [0] 48 49 50 51 [0] 52 53 54 55 
[    0.000000] pcpu-alloc: [0] 56 57 58 59 [0] 60 61 62 63 
[    0.000000] pcpu-alloc: [0] 64 65 66 67 [0] 68 69 70 71 
[    0.000000] pcpu-alloc: [0] 72 73 74 75 [0] 76 77 78 79 
[    0.000000] pcpu-alloc: [0] 80 81 82 83 [0] 84 85 86 87 
[    0.000000] pcpu-alloc: [0] 88 89 90 91 [0] 92 93 94 95 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 9031680
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-5.13.0-rc7-next-20210625 root=UUID=681ebf25-b7c8-49b9-b247-35a96bc8183f ro crashkernel=384M-2G:64M,2G-:512M biosdevname=0
[    0.000000] Unknown command line parameters: BOOT_IMAGE=/boot/vmlinuz-5.13.0-rc7-next-20210625 crashkernel=384M-2G:64M,2G-:512M biosdevname=0
[    0.000000] Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear)
[    0.000000] Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 35357448K/36700160K available (13252K kernel code, 5244K rwdata, 4064K rodata, 4384K init, 2550K bss, 1342712K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=96, Nodes=2
[    0.000000] ftrace: allocating 33103 entries in 195 pages
[    0.000000] ftrace: allocated 194 pages with 3 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=2048 to nr_cpu_ids=96.
[    0.000000] 	Rude variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=96
[    0.000000] NR_IRQS: 512, nr_irqs: 512, preallocated irqs: 16
[    0.000000] pic: no ISA interrupt controller
[    0.000000] rcu: 	Offload RCU callbacks from CPUs: (none).
[    0.000000] random: get_random_u64 called from start_kernel+0x9b0/0xc10 with crng_init=0
[    0.000000] time_init: decrementer frequency = 512.000000 MHz
[    0.000000] time_init: processor frequency   = 2900.000000 MHz
[    0.000002] time_init: 56 bit decrementer (max: 7fffffffffffff)
[    0.000041] clocksource: timebase: mask: 0xffffffffffffffff max_cycles: 0x761537d007, max_idle_ns: 440795202126 ns
[    0.000108] clocksource: timebase mult[1f40000] shift[24] registered
[    0.000152] clockevent: decrementer mult[83126f] shift[24] cpu[0]
[    0.000242] Console: colour dummy device 80x25
[    0.000274] printk: console [hvc0] enabled
[    0.000304] printk: bootconsole [udbg0] disabled
[    0.000371] pid_max: default: 98304 minimum: 768
[    0.000434] LSM: Security Framework initializing
[    0.000454] Yama: becoming mindful.
[    0.000467] SELinux:  Initializing.
[    0.000602] Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.000678] Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.001696] POWER9 performance monitor hardware support registered
[    0.001737] rcu: Hierarchical SRCU implementation.
[    0.003750] smp: Bringing up secondary CPUs ...
[    0.028999] smp: Brought up 1 node, 64 CPUs
[    0.029008] numa: Node 0 CPUs: 0-63
[    0.029014] Big cores detected but using small core scheduling
[    0.030954] devtmpfs: initialized
[    0.035365] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.035382] futex hash table entries: 32768 (order: 10, 4194304 bytes, linear)
[    0.035882] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.035994] audit: initializing netlink subsys (disabled)
[    0.036095] audit: type=2000 audit(1624792326.030:1): state=initialized audit_enabled=0 res=1
[    0.036166] thermal_sys: Registered thermal governor 'fair_share'
[    0.036167] thermal_sys: Registered thermal governor 'step_wise'
[    0.036339] cpuidle: using governor menu
[    0.036381] RTAS daemon started
[    0.036607] pstore: Registered nvram as persistent store backend
[    0.037251] EEH: pSeries platform initialized
[    0.043241] PCI: Probing PCI hardware
[    0.043248] EEH: No capable adapters found: recovery disabled.
[    0.043252] PCI: Probing PCI hardware done
[    0.043348] pseries-rng: Registering arch random hook.
[    0.044804] Kprobes globally optimized
[    0.045174] HugeTLB registered 16.0 MiB page size, pre-allocated 0 pages
[    0.045181] HugeTLB registered 16.0 GiB page size, pre-allocated 0 pages
[    0.085299] wait_for_initramfs() called before rootfs_initcalls
[    0.089670] iommu: Default domain type: Translated 
[    0.089719] vgaarb: loaded
[    0.089801] SCSI subsystem initialized
[    0.089837] usbcore: registered new interface driver usbfs
[    0.089848] usbcore: registered new interface driver hub
[    0.089879] usbcore: registered new device driver usb
[    0.090040] EDAC MC: Ver: 3.0.0
[    0.090338] NetLabel: Initializing
[    0.090342] NetLabel:  domain hash size = 128
[    0.090345] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.090359] NetLabel:  unlabeled traffic allowed by default
[    0.091140] clocksource: Switched to clocksource timebase
[    0.104582] VFS: Disk quotas dquot_6.6.0
[    0.104616] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.107184] NET: Registered PF_INET protocol family
[    0.107337] IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.110050] tcp_listen_portaddr_hash hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    0.110216] TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.110952] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[    0.111044] TCP: Hash tables configured (established 524288 bind 65536)
[    0.111154] UDP hash table entries: 32768 (order: 8, 1048576 bytes, linear)
[    0.111252] UDP-Lite hash table entries: 32768 (order: 8, 1048576 bytes, linear)
[    0.111477] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.111486] PCI: CLS 0 bytes, default 128
[    0.111552] Trying to unpack rootfs image as initramfs...
[    0.112413] IOMMU table initialized, virtual merging enabled
[    0.128511] hv-24x7: read 1530 catalog entries, created 509 event attrs (0 failures), 275 descs
[    0.136073] Initialise system trusted keyrings
[    0.136166] workingset: timestamp_bits=38 max_order=24 bucket_order=0
[    0.137613] zbud: loaded
[    0.158833] NET: Registered PF_ALG protocol family
[    0.158839] Key type asymmetric registered
[    0.158842] Asymmetric key parser 'x509' registered
[    0.158851] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[    0.158952] io scheduler mq-deadline registered
[    0.158959] io scheduler kyber registered
[    0.161625] atomic64_test: passed
[    0.161667] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    0.162265] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.162635] Non-volatile memory driver v1.3
[    0.162694] Linux agpgart interface v0.103
[    0.162865] tpm_ibmvtpm 30000003: CRQ initialization completed
[    1.192320] rdac: device handler registered
[    1.192440] hp_sw: device handler registered
[    1.192444] emc: device handler registered
[    1.192594] alua: device handler registered
[    1.192733] libphy: Fixed MDIO Bus: probed
[    1.192787] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.192803] ehci-pci: EHCI PCI platform driver
[    1.192816] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.192828] ohci-pci: OHCI PCI platform driver
[    1.192839] uhci_hcd: USB Universal Host Controller Interface driver
[    1.192884] usbcore: registered new interface driver usbserial_generic
[    1.192893] usbserial: USB Serial support registered for generic
[    1.192971] mousedev: PS/2 mouse device common for all mice
[    1.193091] rtc-generic rtc-generic: registered as rtc0
[    1.193232] rtc-generic rtc-generic: setting system clock to 2021-06-27T11:12:08 UTC (1624792328)
[    1.194808] pseries_idle_driver registered
[    1.194820] hid: raw HID events driver (C) Jiri Kosina
[    1.194961] usbcore: registered new interface driver usbhid
[    1.194966] usbhid: USB HID core driver
[    1.195009] drop_monitor: Initializing network drop monitor service
[    1.195083] Initializing XFRM netlink socket
[    1.195207] NET: Registered PF_INET6 protocol family
[    1.195827] Segment Routing with IPv6
[    1.195848] NET: Registered PF_PACKET protocol family
[    1.195854] mpls_gso: MPLS GSO support
[    1.195889] Running MSI bitmap self-tests ...
[    1.198345] registered taskstats version 1
[    1.198365] Loading compiled-in X.509 certificates
[    1.204435] alg: No test for pkcs1pad(rsa,sha1) (pkcs1pad(rsa-generic,sha1))
[    1.205115] Loaded X.509 cert 'Build time autogenerated kernel key: 94d0d11f98fad0e99a9eff7ee03c5e8d9a4666eb'
[    1.205577] zswap: loaded using pool lzo/zbud
[    1.205760] pstore: Using crash dump compression: deflate
[    1.211882] Freeing unused kernel image (initmem) memory: 4384K
[    1.311428] Run /init as init process
[    1.311439]   with arguments:
[    1.311443]     /init
[    1.311446]   with environment:
[    1.311448]     HOME=/
[    1.311451]     TERM=linux
[    1.311454]     BOOT_IMAGE=/boot/vmlinuz-5.13.0-rc7-next-20210625
[    1.311458]     crashkernel=384M-2G:64M,2G-:512M
[    1.311460]     biosdevname=0
[    1.324807] systemd[1]: systemd 239 (239-45.el8) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy)
[    1.325133] systemd[1]: Detected architecture ppc64-le.
[    1.325141] systemd[1]: Running in initial RAM disk.
[    1.401821] systemd[1]: Set hostname to <ltc-zzci-2.aus.stglabs.ibm.com>.
[    1.502967] random: systemd: uninitialized urandom read (16 bytes read)
[    1.503095] systemd[1]: Listening on Journal Socket (/dev/log).
[    1.503295] random: systemd: uninitialized urandom read (16 bytes read)
[    1.503307] systemd[1]: Reached target Swap.
[    1.503428] random: systemd: uninitialized urandom read (16 bytes read)
[    1.503440] systemd[1]: Reached target Local File Systems.
[    1.503656] systemd[1]: Listening on Journal Socket.
[    1.505343] systemd[1]: Starting Journal Service...
[    1.506418] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[    1.579212] fuse: init (API version 7.34)
[    1.934212] synth uevent: /devices/vio: failed to send uevent
[    1.934222] vio vio: uevent: failed to send synthetic uevent
[    1.934308] synth uevent: /devices/vio/4000: failed to send uevent
[    1.934312] vio 4000: uevent: failed to send synthetic uevent
[    1.934328] synth uevent: /devices/vio/4001: failed to send uevent
[    1.934332] vio 4001: uevent: failed to send synthetic uevent
[    1.934347] synth uevent: /devices/vio/4002: failed to send uevent
[    1.934351] vio 4002: uevent: failed to send synthetic uevent
[    1.934366] synth uevent: /devices/vio/4004: failed to send uevent
[    1.934370] vio 4004: uevent: failed to send synthetic uevent
[    1.948417] random: fast init done
[    2.010019] ibmveth: IBM Power Virtual Ethernet Driver 1.06
[    2.015699] ibmveth 30000002 env2: renamed from eth0
[    2.015929] ibmvscsi 30000066: SRP_VERSION: 16.a
[    2.016030] ibmvscsi 30000066: Maximum ID: 64 Maximum LUN: 32 Maximum Channel: 3
[    2.016037] scsi host0: IBM POWER Virtual SCSI Adapter 1.5.9
[    2.016220] ibmvscsi 30000066: partner initialization complete
[    2.016256] ibmvscsi 30000066: host srp version: 16.a, host partition ltc-zzci-vios1 (100), OS 3, max io 1048576
[    2.016302] ibmvscsi 30000066: Client reserve enabled
[    2.016311] ibmvscsi 30000066: sent SRP login
[    2.016337] ibmvscsi 30000066: SRP_LOGIN succeeded
[    2.042065] scsi 0:0:1:0: Direct-Access     AIX      VDASD            0001 PQ: 0 ANSI: 3
[    2.123138] scsi 0:0:1:0: Attached scsi generic sg0 type 0
[    2.140916] sd 0:0:1:0: [sda] 585105408 512-byte logical blocks: (300 GB/279 GiB)
[    2.140958] sd 0:0:1:0: [sda] Write Protect is off
[    2.140965] sd 0:0:1:0: [sda] Mode Sense: 17 00 00 08
[    2.140998] sd 0:0:1:0: [sda] Cache data unavailable
[    2.141005] sd 0:0:1:0: [sda] Assuming drive cache: write through
[    2.251694]  sda: sda1 sda2 sda3 sda4 < sda5 >
[    2.254194] sd 0:0:1:0: [sda] Attached SCSI disk
[    4.185107] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    5.049039] printk: systemd: 15 output lines suppressed due to ratelimiting
[    5.499756] SELinux:  Runtime disable is deprecated, use selinux=0 on the kernel cmdline.
[    5.499767] SELinux:  Disabled at runtime.
[    5.671176] audit: type=1404 audit(1624792332.970:2): enforcing=0 old_enforcing=0 auid=4294967295 ses=4294967295 enabled=0 old-enabled=1 lsm=selinux res=1
[    5.821638] systemd[1]: systemd 239 (239-45.el8) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy)
[    5.821929] systemd[1]: Detected architecture ppc64-le.
[    5.831224] systemd[1]: Set hostname to <ltc-zzci-2.aus.stglabs.ibm.com>.
[    5.885571] random: crng init done
[    5.885579] random: 7 urandom warning(s) missed due to ratelimiting
[    6.980906] systemd[1]: systemd-journald.service: Succeeded.
[    6.987018] systemd[1]: initrd-switch-root.service: Succeeded.
[    6.987363] systemd[1]: Stopped Switch Root.
[    6.988010] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart.
[    6.988108] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
[    6.988207] systemd[1]: Stopped Journal Service.
[    6.989344] systemd[1]: Starting Journal Service...
[    7.148524] EXT4-fs (sda2): re-mounted. Opts: (null). Quota mode: none.
[    7.196100] Unable to find swap-space signature
[    7.310654] synth uevent: /devices/vio: failed to send uevent
[    7.310666] vio vio: uevent: failed to send synthetic uevent
[    7.310933] synth uevent: /devices/vio/4000: failed to send uevent
[    7.310937] vio 4000: uevent: failed to send synthetic uevent
[    7.310953] synth uevent: /devices/vio/4001: failed to send uevent
[    7.310957] vio 4001: uevent: failed to send synthetic uevent
[    7.310971] synth uevent: /devices/vio/4002: failed to send uevent
[    7.310975] vio 4002: uevent: failed to send synthetic uevent
[    7.310989] synth uevent: /devices/vio/4004: failed to send uevent
[    7.310993] vio 4004: uevent: failed to send synthetic uevent
[    8.010469] pseries_rng: Registering IBM pSeries RNG driver
[    8.399599] ibmveth 30000002 net0: renamed from env2
[    8.781995] EXT4-fs (sda3): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    9.351118] ------------[ cut here ]------------
[    9.351132] cfs_rq->avg.load_avg || cfs_rq->avg.util_avg || cfs_rq->avg.runnable_avg
[    9.351138] WARNING: CPU: 48 PID: 0 at kernel/sched/fair.c:3308 update_blocked_averages+0x7c8/0x800
[    9.351151] Modules linked in: pseries_rng xts vmx_crypto uio_pdrv_genirq uio sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
[    9.351178] CPU: 48 PID: 0 Comm: swapper/48 Not tainted 5.13.0-rc7-next-20210625 #1
[    9.351185] NIP:  c0000000001b0a28 LR: c0000000001b0a24 CTR: c000000000721f00
[    9.351190] REGS: c0000000285b74b0 TRAP: 0700   Not tainted  (5.13.0-rc7-next-20210625)
[    9.351195] MSR:  8000000000029033 <SF,EE,ME,IR,DR,RI,LE>  CR: 48000224  XER: 00000005
[    9.351207] CFAR: c000000000145700 IRQMASK: 1 
               GPR00: c0000000001b0a24 c0000000285b7750 c000000002921e00 0000000000000048 
               GPR04: 00000000ffff7fff c0000000285b7410 0000000000000027 c0000008bec07e18 
               GPR08: 0000000000000023 0000000000000001 0000000000000027 c0000000027ddd18 
               GPR12: 0000000000000000 c000000007fb6200 c0000008bec1a580 000000000000b971 
               GPR16: 000000022d5eb98e 00000000000001f7 c00000003ccd0c00 0000000000000000 
               GPR20: 0000000000000000 c000000002944fe0 0000000000000000 00000000000003cc 
               GPR24: 0000000000000000 c0000008bec1af90 0000000000000001 c0000008bec1a600 
               GPR28: 00000000000001f7 c00000003ccd0dc0 c00000003ccd0d00 0000000000000000 
[    9.351266] NIP [c0000000001b0a28] update_blocked_averages+0x7c8/0x800
[    9.351272] LR [c0000000001b0a24] update_blocked_averages+0x7c4/0x800
[    9.351277] Call Trace:
[    9.351279] [c0000000285b7750] [c0000000001b0a24] update_blocked_averages+0x7c4/0x800 (unreliable)
[    9.351287] [c0000000285b7870] [c0000000001b6aa0] run_rebalance_domains+0xa0/0xd0
[    9.351294] [c0000000285b78a0] [c000000000cee0cc] __do_softirq+0x15c/0x3d4
[    9.351301] [c0000000285b7990] [c000000000150f34] irq_exit+0x1d4/0x1e0
[    9.351308] [c0000000285b79c0] [c000000000029da0] timer_interrupt+0x170/0x360
[    9.351315] [c0000000285b7a20] [c00000000000998c] decrementer_common_virt+0x1ac/0x1b0
[    9.351322] --- interrupt: 900 at plpar_hcall_norets_notrace+0x18/0x2c
[    9.351329] NIP:  c0000000000ef808 LR: c000000000a050a8 CTR: 0000000000000000
[    9.351333] REGS: c0000000285b7a90 TRAP: 0900   Not tainted  (5.13.0-rc7-next-20210625)
[    9.351338] MSR:  800000000280b033 <SF,VEC,VSX,EE,FP,ME,IR,DR,RI,LE>  CR: 22000284  XER: 20040000
[    9.351352] CFAR: 0000000000000c00 IRQMASK: 0 
               GPR00: 0000000000000000 c0000000285b7d30 c000000002921e00 0000000000000000 
               GPR04: 0000000000000010 000000002c000040 0000000000000002 0000000000000040 
               GPR08: 0000000000000000 0000000000000240 0000000000000024 0000000000000000 
               GPR12: 00000000000c7523 c000000007fb6200 0000000000000000 000000001ef29700 
               GPR16: 0000000000000000 0000000000000000 0000000000000000 0000000000000000 
               GPR20: 0000000000000000 0000000000000000 0000000000000000 c000000002865e88 
               GPR24: 0000000000000001 000000022d5bd4e0 0000000000000000 0000000000000001 
               GPR28: c0000008bec18fc8 0000000000000001 c000000002110998 c0000000021109a0 
[    9.351412] NIP [c0000000000ef808] plpar_hcall_norets_notrace+0x18/0x2c
[    9.351417] LR [c000000000a050a8] check_and_cede_processor+0x48/0x60
[    9.351424] --- interrupt: 900
[    9.351426] [c0000000285b7d30] [c0000000285b7db0] 0xc0000000285b7db0 (unreliable)
[    9.351433] [c0000000285b7d90] [c000000000a05368] shared_cede_loop+0x78/0x170
[    9.351440] [c0000000285b7dd0] [c000000000a01c34] cpuidle_enter_state+0x2e4/0x4e0
[    9.351446] [c0000000285b7e30] [c000000000a01ed0] cpuidle_enter+0x50/0x70
[    9.351452] [c0000000285b7e70] [c0000000001a318c] call_cpuidle+0x4c/0x80
[    9.351460] [c0000000285b7e90] [c0000000001a3810] do_idle+0x380/0x3e0
[    9.351465] [c0000000285b7f10] [c0000000001a3aec] cpu_startup_entry+0x3c/0x50
[    9.351471] [c0000000285b7f40] [c0000000000654d0] start_secondary+0x280/0x2a0
[    9.351479] [c0000000285b7f90] [c00000000000d054] start_secondary_prolog+0x10/0x14
[    9.351485] Instruction dump:
[    9.351488] 38635a78 9be9fda5 4bf94c99 60000000 0fe00000 4bfff910 e9210070 e8610088 
[    9.351499] 39400001 99490009 4bf94c79 60000000 <0fe00000> e95201ba 2faa0000 4bfffbd0 
[    9.351509] ---[ end trace 5f1d49fb99f3613b ]---
[    9.427038] RPC: Registered named UNIX socket transport module.
[    9.427049] RPC: Registered udp transport module.
[    9.427052] RPC: Registered tcp transport module.
[    9.427056] RPC: Registered tcp NFSv4.1 backchannel transport module.
[   18.411772] sysrq: Changing Loglevel
[   18.411785] sysrq: Loglevel set to 9
[   19.952501] device-mapper: uevent: version 1.0.3
[   19.952630] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@xxxxxxxxxx
[   92.105621] ------------[ cut here ]------------
[   92.105650] WARNING: CPU: 45 PID: 12757 at arch/powerpc/kernel/interrupt.c:518 interrupt_exit_kernel_prepare+0x280/0x2a0
[   92.105673] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts vmx_crypto uio_pdrv_genirq uio sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
[   92.105774] CPU: 45 PID: 12757 Comm: sh Kdump: loaded Tainted: G        W         5.13.0-rc7-next-20210625 #1
[   92.105789] NIP:  c000000000032a00 LR: c00000000000c958 CTR: 000000000049f42c
[   92.105800] REGS: c00000008dfeb460 TRAP: 0700   Tainted: G        W          (5.13.0-rc7-next-20210625)
[   92.105812] MSR:  8000000000021033 <SF,ME,IR,DR,RI,LE>  CR: 28088288  XER: 00000000
[   92.105846] CFAR: c000000000032814 IRQMASK: 1 
               GPR00: c00000000000c958 c00000008dfeb700 c000000002921e00 c00000008dfeb7c0 
               GPR04: 8000000002803033 00000000000064cf 00000000000000d0 ffffffffffffffbf 
               GPR08: 0000000000000040 0000000000000000 0000000000000003 000000000000000f 
               GPR12: 0000000000008000 c000000007fb9200 000000012efd81d4 000000012ee50000 
               GPR16: 0000000000000007 0000400000000008 0000400000000008 c00000002c464948 
               GPR20: fcffffffffffffff 000000000001ffff 5455555555555555 00003fffffffffff 
               GPR24: 00003ffff69ff790 0000000000000000 00003ffff69ff400 0000000000000220 
               GPR28: 00003ffff69ff3f8 fcffffffffffffff 0000000000000000 c00000008dfeb7c0 
[   92.106004] NIP [c000000000032a00] interrupt_exit_kernel_prepare+0x280/0x2a0
[   92.106017] LR [c00000000000c958] interrupt_return_srr_user_restart+0x2c/0x110
[   92.106030] Call Trace:
[   92.106036] [c00000008dfeb700] [00003ffff69ff400] 0x3ffff69ff400 (unreliable)
[   92.106049] [c00000008dfeb750] [c00000000000c958] interrupt_return_srr_user_restart+0x2c/0x110
[   92.106065] --- interrupt: 300 at strnlen_user+0xe0/0x240
[   92.106076] NIP:  c0000000006ff820 LR: c000000000541fc4 CTR: 0000000000003fc3
[   92.106085] REGS: c00000008dfeb7c0 TRAP: 0300   Tainted: G        W          (5.13.0-rc7-next-20210625)
[   92.106095] MSR:  8000000002803033 <SF,VEC,VSX,FP,ME,IR,DR,RI,LE>  CR: 44088222  XER: 00000000
[   92.106127] CFAR: c0000000006ff844 DAR: 00003ffff6a00000 DSISR: 40000000 IRQMASK: 0 
               GPR00: c000000000541fc4 c00000008dfeba60 c000000002921e00 0000000000000000 
               GPR04: 0000000000020000 0000000000020008 00000000000001e8 00003ffff69ffe10 
               GPR08: 00000000000001f0 0000000000000000 0000000000000000 0000000000000000 
               GPR12: 0000000000000006 c000000007fb9200 000000012efd81d4 000000012ee50000 
               GPR16: 0000000000000007 0000400000000008 0000400000000008 c00000002c464948 
               GPR20: fcffffffffffffff 000000000001ffff 5455555555555555 00003fffffffffff 
               GPR24: 00003ffff69ff790 0000000000000000 00003ffff69ff400 0000000000000220 
               GPR28: 00003ffff69ff3f8 00003ffff69ffe16 00003ffff69ff400 c00000002c464800 
[   92.106256] NIP [c0000000006ff820] strnlen_user+0xe0/0x240
[   92.106267] LR [c000000000541fc4] create_elf_tables.isra.22+0xa04/0xc80
[   92.106279] --- interrupt: 300
[   92.106285] [c00000008dfeba60] [c000000000ce91b8] down_read_killable+0x28/0x130 (unreliable)
[   92.106302] [c00000008dfeba90] [c000000000541fc4] create_elf_tables.isra.22+0xa04/0xc80
[   92.106315] [c00000008dfebb50] [c000000000543050] load_elf_binary+0xe10/0x1220
[   92.106329] [c00000008dfebc40] [c00000000047ded0] bprm_execve+0x410/0x800
[   92.106343] [c00000008dfebd10] [c00000000047e8ec] do_execveat_common.isra.44+0x21c/0x240
[   92.106356] [c00000008dfebd80] [c00000000047e964] sys_execve+0x54/0x70
[   92.106368] [c00000008dfebdb0] [c000000000032334] system_call_exception+0x164/0x2e0
[   92.106382] [c00000008dfebe10] [c00000000000c464] system_call_common+0xf4/0x258
[   92.106396] --- interrupt: c00 at 0x3fff9bb6b8a8
[   92.106405] NIP:  00003fff9bb6b8a8 LR: 00003fff9bb6c240 CTR: 0000000000000000
[   92.106414] REGS: c00000008dfebe80 TRAP: 0c00   Tainted: G        W          (5.13.0-rc7-next-20210625)
[   92.106424] MSR:  800000000000f033 <SF,EE,PR,FP,ME,IR,DR,RI,LE>  CR: 28004224  XER: 00000000
[   92.106454] IRQMASK: 0 
               GPR00: 000000000000000b 00003fffc36a1440 00003fff9bc87300 00000100268a67d0 
               GPR04: 0000010026887e50 0000010026882c50 fefefefefefefeff 7f7f7f7f7f7f7f7f 
               GPR08: 00000100268a67d0 0000000000000000 0000000000000000 0000000000000000 
               GPR12: 0000000000000000 00003fff9bce3780 0000000114200db4 0000000000000000 
               GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40 
               GPR20: 00000001141dd820 0000000000000000 00000001141dd740 0000000114204358 
               GPR24: 0000000114203948 0000010026876454 0000000000000001 0000010026882c50 
               GPR28: 0000010026887e50 0000010026882c50 00000100268a67d0 00003fffc36a1440 
[   92.106579] NIP [00003fff9bb6b8a8] 0x3fff9bb6b8a8
[   92.106588] LR [00003fff9bb6c240] 0x3fff9bb6c240
[   92.106596] --- interrupt: c00
[   92.106602] Instruction dump:
[   92.106611] 71290001 892d0153 61290001 992d0153 4082000c 392d0138 7c20492a 4bfe362d 
[   92.106631] 60000000 4bfffe34 60000000 60000000 <0fe00000> 4bfffe14 60000000 60000000 
[   92.106654] ---[ end trace 5f1d49fb99f3613c ]---
[   92.106731] ------------[ cut here ]------------
[   92.106738] WARNING: CPU: 45 PID: 12757 at arch/powerpc/kernel/irq.c:255 arch_local_irq_restore+0x1d0/0x200
[   92.106753] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts vmx_crypto uio_pdrv_genirq uio sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
[   92.106828] CPU: 45 PID: 12757 Comm: sh Kdump: loaded Tainted: G        W         5.13.0-rc7-next-20210625 #1
[   92.106841] NIP:  c0000000000164d0 LR: c000000000cedaa8 CTR: 0000000000000000
[   92.106849] REGS: c00000008dfeb7e0 TRAP: 0700   Tainted: G        W          (5.13.0-rc7-next-20210625)
[   92.106859] MSR:  8000000002823033 <SF,VEC,VSX,FP,ME,IR,DR,RI,LE>  CR: 28004222  XER: 00000000
[   92.106892] CFAR: c00000000001632c IRQMASK: 0 
               GPR00: c000000000ceda98 c00000008dfeba80 c000000002921e00 0000000000000000 
               GPR04: 0000000000000000 0000000000000000 0000000000000000 00000000000000ff 
               GPR08: 0000000000000001 0000000000000000 0000000000000001 0000000000000017 
               GPR12: 0000000024004822 c000000007fb9200 000000012efd81d4 000000012ee50000 
               GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40 
               GPR20: 00003fffa93f8000 0000000000000000 00003fffa93f9300 000000012efb1988 
               GPR24: 000000012ee7fe7c 000000012efccba0 000000012ee50000 c00000008d5d7600 
               GPR28: c0000000314c0bc0 c000000040d9f100 c0000008beb5861c 4b72201a3063fe13 
[   92.107024] NIP [c0000000000164d0] arch_local_irq_restore+0x1d0/0x200
[   92.107035] LR [c000000000cedaa8] _raw_spin_unlock_irqrestore+0x88/0xb0
[   92.107047] Call Trace:
[   92.107052] [c00000008dfeba80] [c00000008dfebb50] 0xc00000008dfebb50 (unreliable)
[   92.107065] [c00000008dfebab0] [238c5bf052df0858] 0x238c5bf052df0858
[   92.107076] [c00000008dfebae0] [c0000000008178e8] get_random_u64+0x88/0x100
[   92.107090] [c00000008dfebb20] [c000000000020134] arch_randomize_brk+0xb4/0xd8
[   92.107105] [c00000008dfebb50] [c0000000005430b0] load_elf_binary+0xe70/0x1220
[   92.107119] [c00000008dfebc40] [c00000000047ded0] bprm_execve+0x410/0x800
[   92.107132] [c00000008dfebd10] [c00000000047e8ec] do_execveat_common.isra.44+0x21c/0x240
[   92.107145] [c00000008dfebd80] [c00000000047e964] sys_execve+0x54/0x70
[   92.107157] [c00000008dfebdb0] [c000000000032334] system_call_exception+0x164/0x2e0
[   92.107169] [c00000008dfebe10] [c00000000000c464] system_call_common+0xf4/0x258
[   92.107185] --- interrupt: c00 at 0x3fff9bb6b8a8
[   92.107193] NIP:  00003fff9bb6b8a8 LR: 00003fff9bb6c240 CTR: 0000000000000000
[   92.107202] REGS: c00000008dfebe80 TRAP: 0c00   Tainted: G        W          (5.13.0-rc7-next-20210625)
[   92.107213] MSR:  800000000000f033 <SF,EE,PR,FP,ME,IR,DR,RI,LE>  CR: 28004224  XER: 00000000
[   92.107243] IRQMASK: 0 
               GPR00: 000000000000000b 00003fffc36a1440 00003fff9bc87300 00000100268a67d0 
               GPR04: 0000010026887e50 0000010026882c50 fefefefefefefeff 7f7f7f7f7f7f7f7f 
               GPR08: 00000100268a67d0 0000000000000000 0000000000000000 0000000000000000 
               GPR12: 0000000000000000 00003fff9bce3780 0000000114200db4 0000000000000000 
               GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40 
               GPR20: 00000001141dd820 0000000000000000 00000001141dd740 0000000114204358 
               GPR24: 0000000114203948 0000010026876454 0000000000000001 0000010026882c50 
               GPR28: 0000010026887e50 0000010026882c50 00000100268a67d0 00003fffc36a1440 
[   92.107369] NIP [00003fff9bb6b8a8] 0x3fff9bb6b8a8
[   92.107378] LR [00003fff9bb6c240] 0x3fff9bb6c240
[   92.107386] --- interrupt: c00
[   92.107393] Instruction dump:
[   92.107400] 7d2000a6 71298000 40820048 39200000 992d0152 39400000 992d0153 614a8002 
[   92.107427] 7d410164 4bfffe6c 60000000 60000000 <0fe00000> 4bfffe5c 60000000 60000000 
[   92.107451] ---[ end trace 5f1d49fb99f3613d ]---

[Index of Archives]     [Linux Kernel]     [Linux USB Development]     [Yosemite News]     [Linux SCSI]

  Powered by Linux