While booting 2.6.30-rc6-git5 kernel came across this badness message.
ftrace: allocating 18134 entries in 107 pages ------------[ cut here ]------------ Badness at kernel/trace/ftrace.c:444 NIP: c00000000011e754 LR: c0000000001210fc CTR: 0000000000000000 REGS: c000000000edba40 TRAP: 0700 Not tainted (2.6.30-rc6-git5) MSR: 8000000000021032 <ME,CE,IR,DR> CR: 28000082 XER: 00000000 TASK = c000000000e2d650[0] 'swapper' THREAD: c000000000ed8000 CPU: 0GPR00: 0000000000000001 c000000000edbcc0 c000000000ed8830 ffffffffffffffea GPR04: c000000000500000 0000000000000004 0000000000000000 0000000000000080 GPR08: 0000000000000000 c000000000fe6c80 f0000000013bc5c0 001bd7a20f0eb79e GPR12: 0000000048000088 c000000000f1f380 0000000000382400 0000000000000000 GPR16: 0000000001100000 0000000001550a00 0000000001550898 0000000008000000 GPR20: 00000000031cae90 c0000000008f3d88 0000000000000000 c000000000500000 GPR24: 0000000000000000 0000000000000000 0000000004a47270 0000000000000001 GPR28: c000000000fe6c80 c000000000500000 c000000000e670f0 c000000000edbcc0 NIP [c00000000011e754] .ftrace_bug+0x98/0x218
LR [c0000000001210fc] .ftrace_convert_nops+0x20c/0x308 Call Trace: [c000000000edbcc0] [600000000012bf5c] 0x600000000012bf5c (unreliable) [c000000000edbd60] [c0000000001210fc] .ftrace_convert_nops+0x20c/0x308 [c000000000edbe30] [c000000000929b8c] .ftrace_init+0x158/0x17c [c000000000edbee0] [c0000000008fdca4] .start_kernel+0x47c/0x4a8 [c000000000edbf90] [c000000000008408] .start_here_common+0x2c/0xa4 Instruction dump: 41be0010 e93e8010 38000001 90090018 e93e8000 e81e8008 e87e80b0 48000138 8009001c 7c000034 5400d97e 78000020 <0b000000> 2fa00000 41be0010 e93e8010 ftrace failed to modify [<c000000000500000>] .uhci_activate_qh+0x34/0x220 actual: 00:00:10:10 irq: irq 2 on host null mapped to virtual irq 16Observed similar problem with git4 as well. This doesn't seem to be a widespread problem
and currently observing this on one out of 3 power6 machines. Complete dmesg and .config(gzipped) attached. Thanks -Sachin -- --------------------------------- Sachin Sant IBM Linux Technology Center India Systems and Technology Labs Bangalore, India ---------------------------------
Phyp-dump disabled at boot time
Using pSeries machine description
Page orders: linear mapping = 24, virtual = 12, io = 12, vmemmap = 24
Using 1TB segments
Found initrd at 0xc000000001e00000:0xc000000002182400
console [udbg0] enabled
Partition configured for 8 cpus.
CPU maps initialized for 2 threads per core
(thread shift is 1)
Starting Linux PPC64 #1 SMP Wed May 20 11:51:20 IST 2009
-----------------------------------------------------
ppc64_pft_size = 0x1a
physicalMemorySize = 0xa0000000
htab_hash_mask = 0x7ffff
-----------------------------------------------------
Initializing cgroup subsys cpuset
Initializing cgroup subsys cpu
Linux version 2.6.30-rc6-git5 (r...@mjs22lp1) (gcc version 4.4.0 20090307 (Red
Hat 4.4.0-0.23) (GCC) ) #1 SMP Wed May 20 11:51:20 IST 2009
[boot]0012 Setup Arch
Node 0 Memory: 0x8000000-0x5c000000
Node 1 Memory: 0x0-0x8000000 0x5c000000-0xa0000000
EEH: No capable adapters found
PPC64 nvram contains 15360 bytes
Using shared processor idle loop
Zone PFN ranges:
DMA 0x00000000 -> 0x000a0000
Normal 0x000a0000 -> 0x000a0000
Movable zone start PFN for each node
early_node_map[3] active PFN ranges
1: 0x00000000 -> 0x00008000
0: 0x00008000 -> 0x0005c000
1: 0x0005c000 -> 0x000a0000
On node 0 totalpages: 344064
DMA zone: 4704 pages used for memmap
DMA zone: 0 pages reserved
DMA zone: 339360 pages, LIFO batch:31
On node 1 totalpages: 311296
DMA zone: 8960 pages used for memmap
DMA zone: 0 pages reserved
DMA zone: 302336 pages, LIFO batch:31
[boot]0015 Setup Done
Built 2 zonelists in Node order, mobility grouping on. Total pages: 641696
Policy zone: DMA
Kernel command line: ro console=hvc0 rhgb quiet
root=UUID=9448b379-7842-486c-8bc8-22a1427c4462
NR_IRQS:512
[boot]0020 XICS Init
[boot]0021 XICS Done
pic: no ISA interrupt controller
PID hash table entries: 4096 (order: 12, 32768 bytes)
time_init: decrementer frequency = 512.000000 MHz
time_init: processor frequency = 4005.000000 MHz
clocksource: timebase mult[7d0000] shift[22] registered
clockevent: decrementer mult[8312] shift[16] cpu[0]
Console: colour dummy device 80x25
console handover: boot [udbg0] -> real [hvc0]
allocated 26214400 bytes of page_cgroup
please try cgroup_disable=memory option if you don't want
freeing bootmem node 0
freeing bootmem node 1
Memory: 2514952k/2621440k available (14120k kernel code, 137064k reserved,
1076k data, 1310k bss, 4916k init)
SLUB: Genslabs=14, HWalign=128, Order=0-3, MinObjects=0, CPUs=8, Nodes=16
Calibrating delay loop... 1021.95 BogoMIPS (lpj=510976)
Security Framework initialized
SELinux: Initializing.
SELinux: Starting in permissive mode
Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
Mount-cache hash table entries: 256
Initializing cgroup subsys ns
Initializing cgroup subsys cpuacct
Initializing cgroup subsys memory
Initializing cgroup subsys devices
Initializing cgroup subsys freezer
Initializing cgroup subsys net_cls
ftrace: allocating 18134 entries in 107 pages
------------[ cut here ]------------
Badness at kernel/trace/ftrace.c:444
NIP: c00000000011e754 LR: c0000000001210fc CTR: 0000000000000000
REGS: c000000000edba40 TRAP: 0700 Not tainted (2.6.30-rc6-git5)
MSR: 8000000000021032 <ME,CE,IR,DR> CR: 28000082 XER: 00000000
TASK = c000000000e2d650[0] 'swapper' THREAD: c000000000ed8000 CPU: 0
GPR00: 0000000000000001 c000000000edbcc0 c000000000ed8830 ffffffffffffffea
GPR04: c000000000500000 0000000000000004 0000000000000000 0000000000000080
GPR08: 0000000000000000 c000000000fe6c80 f0000000013bc5c0 001bd7a20f0eb79e
GPR12: 0000000048000088 c000000000f1f380 0000000000382400 0000000000000000
GPR16: 0000000001100000 0000000001550a00 0000000001550898 0000000008000000
GPR20: 00000000031cae90 c0000000008f3d88 0000000000000000 c000000000500000
GPR24: 0000000000000000 0000000000000000 0000000004a47270 0000000000000001
GPR28: c000000000fe6c80 c000000000500000 c000000000e670f0 c000000000edbcc0
NIP [c00000000011e754] .ftrace_bug+0x98/0x218
LR [c0000000001210fc] .ftrace_convert_nops+0x20c/0x308
Call Trace:
[c000000000edbcc0] [600000000012bf5c] 0x600000000012bf5c (unreliable)
[c000000000edbd60] [c0000000001210fc] .ftrace_convert_nops+0x20c/0x308
[c000000000edbe30] [c000000000929b8c] .ftrace_init+0x158/0x17c
[c000000000edbee0] [c0000000008fdca4] .start_kernel+0x47c/0x4a8
[c000000000edbf90] [c000000000008408] .start_here_common+0x2c/0xa4
Instruction dump:
41be0010 e93e8010 38000001 90090018 e93e8000 e81e8008 e87e80b0 48000138
8009001c 7c000034 5400d97e 78000020 <0b000000> 2fa00000 41be0010 e93e8010
ftrace failed to modify [<c000000000500000>] .uhci_activate_qh+0x34/0x220
actual: 00:00:10:10
irq: irq 2 on host null mapped to virtual irq 16
clockevent: decrementer mult[8312] shift[16] cpu[1]
Processor 1 found.
clockevent: decrementer mult[8312] shift[16] cpu[2]
Processor 2 found.
clockevent: decrementer mult[8312] shift[16] cpu[3]
Processor 3 found.
clockevent: decrementer mult[8312] shift[16] cpu[4]
Processor 4 found.
clockevent: decrementer mult[8312] shift[16] cpu[5]
Processor 5 found.
clockevent: decrementer mult[8312] shift[16] cpu[6]
Processor 6 found.
clockevent: decrementer mult[8312] shift[16] cpu[7]
Processor 7 found.
Brought up 8 CPUs
Node 0 CPUs: 0-7
Node 1 CPUs:
CPU0 attaching sched-domain:
domain 0: span 0-1 level SIBLING
groups: 0 1
domain 1: span 0-7 level CPU
groups: 0-1 2-3 4-5 6-7
domain 2: span 0-7 level NODE
groups: 0-7 (__cpu_power = 4096)
CPU1 attaching sched-domain:
domain 0: span 0-1 level SIBLING
groups: 1 0
domain 1: span 0-7 level CPU
groups: 0-1 2-3 4-5 6-7
domain 2: span 0-7 level NODE
groups: 0-7 (__cpu_power = 4096)
CPU2 attaching sched-domain:
domain 0: span 2-3 level SIBLING
groups: 2 3
domain 1: span 0-7 level CPU
groups: 2-3 4-5 6-7 0-1
domain 2: span 0-7 level NODE
groups: 0-7 (__cpu_power = 4096)
CPU3 attaching sched-domain:
domain 0: span 2-3 level SIBLING
groups: 3 2
domain 1: span 0-7 level CPU
groups: 2-3 4-5 6-7 0-1
domain 2: span 0-7 level NODE
groups: 0-7 (__cpu_power = 4096)
CPU4 attaching sched-domain:
domain 0: span 4-5 level SIBLING
groups: 4 5
domain 1: span 0-7 level CPU
groups: 4-5 6-7 0-1 2-3
domain 2: span 0-7 level NODE
groups: 0-7 (__cpu_power = 4096)
CPU5 attaching sched-domain:
domain 0: span 4-5 level SIBLING
groups: 5 4
domain 1: span 0-7 level CPU
groups: 4-5 6-7 0-1 2-3
domain 2: span 0-7 level NODE
groups: 0-7 (__cpu_power = 4096)
CPU6 attaching sched-domain:
domain 0: span 6-7 level SIBLING
groups: 6 7
domain 1: span 0-7 level CPU
groups: 6-7 0-1 2-3 4-5
domain 2: span 0-7 level NODE
groups: 0-7 (__cpu_power = 4096)
CPU7 attaching sched-domain:
domain 0: span 6-7 level SIBLING
groups: 7 6
domain 1: span 0-7 level CPU
groups: 6-7 0-1 2-3 4-5
domain 2: span 0-7 level NODE
groups: 0-7 (__cpu_power = 4096)
net_namespace: 1952 bytes
regulator: core version 0.5
NET: Registered protocol family 16
IBM eBus Device Driver
PCI: Probing PCI hardware
PCI: Probing PCI hardware done
bio: create slab <bio-0> at 0
SCSI subsystem initialized
libata version 3.00 loaded.
usbcore: registered new interface driver usbfs
usbcore: registered new interface driver hub
usbcore: registered new device driver usb
NetLabel: Initializing
NetLabel: domain hash size = 128
NetLabel: protocols = UNLABELED CIPSOv4
NetLabel: unlabeled traffic allowed by default
NET: Registered protocol family 2
Switched to high resolution mode on CPU 0
Switched to high resolution mode on CPU 3
Switched to high resolution mode on CPU 4
Switched to high resolution mode on CPU 5
Switched to high resolution mode on CPU 1
Switched to high resolution mode on CPU 6
Switched to high resolution mode on CPU 2
Switched to high resolution mode on CPU 7
IP route cache hash table entries: 131072 (order: 8, 1048576 bytes)
TCP established hash table entries: 524288 (order: 11, 8388608 bytes)
TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
TCP: Hash tables configured (established 524288 bind 65536)
TCP reno registered
NET: Registered protocol family 1
Trying to unpack rootfs image as initramfs...
Freeing initrd memory: 3593k freed
irq: irq 655360 on host null mapped to virtual irq 17
irq: irq 655362 on host null mapped to virtual irq 18
IOMMU table initialized, virtual merging enabled
irq: irq 655364 on host null mapped to virtual irq 19
irq: irq 655365 on host null mapped to virtual irq 20
irq: irq 589825 on host null mapped to virtual irq 21
RTAS daemon started
audit: initializing netlink socket (disabled)
type=2000 audit(1242803832.218:1): initialized
RTAS: event: 20, Type: Platform Error, Severity: 2
HugeTLB registered 16 MB page size, pre-allocated 0 pages
HugeTLB registered 16 GB page size, pre-allocated 0 pages
VFS: Disk quotas dquot_6.5.2
Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
msgmni has been set to 4919
SELinux: Registering netfilter hooks
alg: No test for stdrng (krng)
Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
io scheduler noop registered
io scheduler anticipatory registered
io scheduler deadline registered
io scheduler cfq registered (default)
pci_hotplug: PCI Hot Plug PCI Core version: 0.5
pciehp: PCI Express Hot Plug Controller Driver version: 0.4
vio_register_driver: driver hvc_console registering
HVSI: registered 0 devices
Linux agpgart interface v0.103
Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
TX39/49 Serial driver version 1.11
brd: module loaded
loop: module loaded
input: Macintosh mouse button emulation as /devices/virtual/input/input0
Uniform Multi-Platform E-IDE driver
ide-gd driver 1.18
Driver 'sd' needs updating - please use bus_type methods
Driver 'sr' needs updating - please use bus_type methods
Fixed MDIO Bus: probed
ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
uhci_hcd: USB Universal Host Controller Interface driver
mice: PS/2 mouse device common for all mice
device-mapper: uevent: version 1.0.3
device-mapper: ioctl: 4.14.0-ioctl (2008-04-23) initialised: dm-de...@redhat.com
usbcore: registered new interface driver hiddev
usbcore: registered new interface driver usbhid
usbhid: v2.6:USB HID core driver
nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
CONFIG_NF_CT_ACCT is deprecated and will be removed soon. Please use
nf_conntrack.acct=1 kernel paramater, acct=1 nf_conntrack module option or
sysctl net.netfilter.nf_conntrack_acct=1 to enable it.
ip_tables: (C) 2000-2006 Netfilter Core Team
TCP cubic registered
Initializing XFRM netlink socket
NET: Registered protocol family 17
Running MSI bitmap self-tests ...
PM: Resume from disk failed.
registered taskstats version 1
Initalizing network drop monitor service
Freeing unused kernel memory: 4916k freed
vio_register_driver: driver ibmvscsi registering
ibmvscsi 30000002: SRP_VERSION: 16.a
scsi0 : IBM POWER Virtual SCSI Adapter 1.5.8
ibmvscsi 30000002: partner initialization complete
ibmvscsi 30000002: sent SRP login
ibmvscsi 30000002: SRP_LOGIN succeeded
ibmvscsi 30000002: host srp version: 16.a, host partition 06-1C12A (1), OS 3,
max io 1048576
scsi 0:0:1:0: Direct-Access AIX VDASD 0001 PQ: 0 ANSI: 3
scsi 0:0:2:0: CD-ROM AIX VOPTA PQ: 0 ANSI: 4
scsi: waiting for bus probes to complete ...
sd 0:0:1:0: Attached scsi generic sg0 type 0
sd 0:0:1:0: [sda] 147324928 512-byte hardware sectors: (75.4 GB/70.2 GiB)
sd 0:0:1:0: [sda] Write Protect is off
sd 0:0:1:0: [sda] Mode Sense: 17 00 00 08
sd 0:0:1:0: [sda] Cache data unavailable
sd 0:0:1:0: [sda] Assuming drive cache: write through
sr0: scsi-1 drive
Uniform CD-ROM driver Revision: 3.20
sd 0:0:1:0: [sda] Cache data unavailable
sd 0:0:1:0: [sda] Assuming drive cache: write through
sda:<7>sr 0:0:2:0: Attached scsi CD-ROM sr0
sr 0:0:2:0: Attached scsi generic sg1 type 5
sda1 sda2 sda3 sda4 < sda5 sda6 sda7 sda8 >
sd 0:0:1:0: [sda] Attached SCSI disk
EXT4-fs: barriers enabled
kjournald2 starting: pid 129, dev sda3:8, commit interval 5 seconds
EXT4-fs: delayed allocation enabled
EXT4-fs: file extents enabled
EXT4-fs: mballoc enabled
EXT4-fs: mounted filesystem sda3 with ordered data mode
type=1404 audit(1242803833.068:2): enforcing=1 old_enforcing=0 auid=4294967295
ses=4294967295
SELinux: 8192 avtab hash slots, 117789 rules.
SELinux: 8192 avtab hash slots, 117789 rules.
SELinux: 8 users, 13 roles, 2653 types, 121 bools, 1 sens, 1024 cats
SELinux: 74 classes, 117789 rules
SELinux: Completing initialization.
SELinux: Setting up existing superblocks.
SELinux: initialized (dev sda3, type ext4), uses xattr
SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
SELinux: initialized (dev selinuxfs, type selinuxfs), uses genfs_contexts
SELinux: initialized (dev mqueue, type mqueue), uses transition SIDs
SELinux: initialized (dev hugetlbfs, type hugetlbfs), uses genfs_contexts
SELinux: initialized (dev devpts, type devpts), uses transition SIDs
SELinux: initialized (dev inotifyfs, type inotifyfs), uses genfs_contexts
SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
SELinux: initialized (dev anon_inodefs, type anon_inodefs), uses genfs_contexts
SELinux: initialized (dev pipefs, type pipefs), uses task SIDs
SELinux: initialized (dev debugfs, type debugfs), uses genfs_contexts
SELinux: initialized (dev sockfs, type sockfs), uses task SIDs
SELinux: initialized (dev proc, type proc), uses genfs_contexts
SELinux: initialized (dev bdev, type bdev), uses genfs_contexts
SELinux: initialized (dev rootfs, type rootfs), uses genfs_contexts
SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts
type=1403 audit(1242803833.425:3): policy loaded auid=4294967295 ses=4294967295
SELinux: initialized (dev usbfs, type usbfs), uses genfs_contexts
udev: starting version 139
drivers/net/ibmveth.c: ibmveth: IBM i/pSeries Virtual Ethernet Driver 1.03
vio_register_driver: driver ibmveth registering
eth0 (ibmveth): not using net_device_ops yet
eth1 (ibmveth): not using net_device_ops yet
IBM eHEA ethernet device driver (Release EHEA_0101)
irq: irq 590080 on host null mapped to virtual irq 256
ehea: eth0: Jumbo frames are enabled
ehea: eth0 -> logical port id #3
ehea: eth2: Jumbo frames are enabled
ehea: eth2 -> logical port id #4
udev: renamed network interface eth1 to eth3
udev: renamed network interface eth0_rename to eth1
device-mapper: multipath: version 1.0.5 loaded
EXT4 FS on sda3, internal journal on sda3:8
kjournald starting. Commit interval 5 seconds
EXT3 FS on sda2, internal journal
EXT3-fs: mounted filesystem with writeback data mode.
SELinux: initialized (dev sda2, type ext3), uses xattr
SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
Adding 3068372k swap on /dev/sda8. Priority:-1 extents:1 across:3068372k
SELinux: initialized (dev binfmt_misc, type binfmt_misc), uses genfs_contexts
NET: Registered protocol family 10
lo: Disabled Privacy Extensions
RPC: Registered udp transport module.
RPC: Registered tcp transport module.
SELinux: initialized (dev rpc_pipefs, type rpc_pipefs), uses genfs_contexts
Bluetooth: Core ver 2.15
NET: Registered protocol family 31
Bluetooth: HCI device and connection manager initialized
Bluetooth: HCI socket layer initialized
Bluetooth: L2CAP ver 2.13
Bluetooth: L2CAP socket layer initialized
Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Bluetooth: BNEP filters: protocol multicast
Bridge firewalling registered
Bluetooth: SCO (Voice Link) ver 0.6
Bluetooth: SCO socket layer initialized
Installing knfsd (copyright (C) 1996 o...@monad.swb.de).
SELinux: initialized (dev nfsd, type nfsd), uses genfs_contexts
NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
NFSD: starting 90-second grace period
virbr0: starting userspace STP failed, starting kernel STP
SELinux: initialized (dev mqueue, type mqueue), uses transition SIDs
SELinux: initialized (dev proc, type proc), uses genfs_contexts
SELinux: initialized (dev mqueue, type mqueue), uses transition SIDs
lo: Disabled Privacy Extensions
SELinux: initialized (dev proc, type proc), uses genfs_contexts
eth3: no IPv6 routers present
eth1: no IPv6 routers present
virbr0: no IPv6 routers present
ehea: eth0: Physical port up
ehea: External switch port is backup port
irq: irq 777 on host null mapped to virtual irq 265
irq: irq 778 on host null mapped to virtual irq 266
eth0: no IPv6 routers present
config_ftrace.gz
Description: GNU Zip compressed data
_______________________________________________ Linuxppc-dev mailing list Linuxppc-dev@ozlabs.org https://ozlabs.org/mailman/listinfo/linuxppc-dev
