linux-kernel.vger.kernel.org archive mirror
 help / color / mirror / Atom feed
* Re: 2.6.15-rt4 failure with LATENCY_TRACE on x86_64
       [not found] <1137103652.11354.40.camel@localhost.localdomain>
@ 2006-01-13  3:18 ` Steven Rostedt
  2006-01-13 15:06   ` Clark Williams
  0 siblings, 1 reply; 7+ messages in thread
From: Steven Rostedt @ 2006-01-13  3:18 UTC (permalink / raw)
  To: Clark Williams; +Cc: Ingo Molnar, lkml

On Thu, 2006-01-12 at 16:07 -0600, Clark Williams wrote:
> Ingo/Steve,
> 
> Did I miss a "Don't turn on latency tracing for x86_64" message
> somewhere?
> 
> I'm seeing a failure on my Athlon64 3000+ where, when I turn on
> CONFIG_LATENCY_TRACE, the init program segfaults. Doesn't happen with a
> statically linked shell like sash (e.g. init=/sbin/sash boots ok), but
> if /sbin/init or /bin/sh is used, the init program segfaults. Presumably
> any dynamically linked program will fail. 
> 
> Attached is console output for a boot failure (segfault messages
> truncated after four lines, since they're all the same) as well as the
> config files for both working and failing kernels.
> 
> I'm not sure where to start looking on this one. Barring any advise, I'm
> going to look for occurrences of CONFIG_LATENCY_TRACE, especially in
> proximity to exec.

OK, I'm actually sending you this email on a x86_64 running
2.6.15-rt4-sr2, with latency tracing on.  But unfortunately, I have a
AMD X2 that each core has it's own tsc counter that is not in sync, and
since the latency tracer uses tsc, I get garbage.  But beware, the tsc
does slow down when the cpu idles, so it gives bad results even for non
x2 systems.

I finally was able to boot this with using the PM timer, but the
beginning of my dmesg is still filled with:

read_tsc: ACK! TSC went backward! Unsynced TSCs?

Have you tried booting with idle=poll? I wonder if that would help?

-- Steve



^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: 2.6.15-rt4 failure with LATENCY_TRACE on x86_64
  2006-01-13  3:18 ` 2.6.15-rt4 failure with LATENCY_TRACE on x86_64 Steven Rostedt
@ 2006-01-13 15:06   ` Clark Williams
  2006-01-13 15:54     ` Steven Rostedt
  0 siblings, 1 reply; 7+ messages in thread
From: Clark Williams @ 2006-01-13 15:06 UTC (permalink / raw)
  To: Steven Rostedt; +Cc: lkml, Ingo Molnar

[-- Attachment #1: Type: text/plain, Size: 951 bytes --]

On Thu, 2006-01-12 at 22:18 -0500, Steven Rostedt wrote:
> OK, I'm actually sending you this email on a x86_64 running
> 2.6.15-rt4-sr2, with latency tracing on.  But unfortunately, I have a
> AMD X2 that each core has it's own tsc counter that is not in sync, and
> since the latency tracer uses tsc, I get garbage.  But beware, the tsc
> does slow down when the cpu idles, so it gives bad results even for non
> x2 systems.
> 
Hmm, I didn't realize that (I'm running on a uni-processor system). I
just pulled your rt4-sr2 patch and will apply/rebuild/test. 

> I finally was able to boot this with using the PM timer, but the
> beginning of my dmesg is still filled with:
> 
> read_tsc: ACK! TSC went backward! Unsynced TSCs?
> 
> Have you tried booting with idle=poll? I wonder if that would help?

No, I thought that was strictly an SMP issue. I'll try it as well.

Thanks,
Clark

-- 
Clark Williams <williams@redhat.com>

[-- Attachment #2: This is a digitally signed message part --]
[-- Type: application/pgp-signature, Size: 189 bytes --]

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: 2.6.15-rt4 failure with LATENCY_TRACE on x86_64
  2006-01-13 15:06   ` Clark Williams
@ 2006-01-13 15:54     ` Steven Rostedt
  2006-01-13 22:58       ` Clark Williams
  0 siblings, 1 reply; 7+ messages in thread
From: Steven Rostedt @ 2006-01-13 15:54 UTC (permalink / raw)
  To: Clark Williams; +Cc: lkml, Ingo Molnar

On Fri, 2006-01-13 at 09:06 -0600, Clark Williams wrote:
> On Thu, 2006-01-12 at 22:18 -0500, Steven Rostedt wrote:
> > OK, I'm actually sending you this email on a x86_64 running
> > 2.6.15-rt4-sr2, with latency tracing on.  But unfortunately, I have a
> > AMD X2 that each core has it's own tsc counter that is not in sync, and
> > since the latency tracer uses tsc, I get garbage.  But beware, the tsc
> > does slow down when the cpu idles, so it gives bad results even for non
> > x2 systems.
> > 
> Hmm, I didn't realize that (I'm running on a uni-processor system). I
> just pulled your rt4-sr2 patch and will apply/rebuild/test. 
> 
> > I finally was able to boot this with using the PM timer, but the
> > beginning of my dmesg is still filled with:
> > 
> > read_tsc: ACK! TSC went backward! Unsynced TSCs?
> > 
> > Have you tried booting with idle=poll? I wonder if that would help?
> 
> No, I thought that was strictly an SMP issue. I'll try it as well.

It effects SMP mostly.  I doubt that it will effect UP, but the tsc is
still not consistent.

-- Steve



^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: 2.6.15-rt4 failure with LATENCY_TRACE on x86_64
  2006-01-13 15:54     ` Steven Rostedt
@ 2006-01-13 22:58       ` Clark Williams
  2006-01-13 23:08         ` Steven Rostedt
  0 siblings, 1 reply; 7+ messages in thread
From: Clark Williams @ 2006-01-13 22:58 UTC (permalink / raw)
  To: Steven Rostedt; +Cc: lkml, Ingo Molnar


[-- Attachment #1.1: Type: text/plain, Size: 1014 bytes --]

On Fri, 2006-01-13 at 10:54 -0500, Steven Rostedt wrote:
>  
> > > Have you tried booting with idle=poll? I wonder if that would help?
> > 
> > No, I thought that was strictly an SMP issue. I'll try it as well.
> 
> It effects SMP mostly.  I doubt that it will effect UP, but the tsc is
> still not consistent.

I tried rt4-sr2 and got the same results (also with idle=poll for
completeness :). 

I'm beginning to think that it's some sort of interaction with the BIOS
(either that or just weird hardware). When I compared the two sets of
boot outputs, the boot that succeeded (dmesg-rt4-sr2.good.txt, with
WAKEUP_TIMING, LATENCY_TIMING and LATENCY_TRACE turned off) shows APIC
probes and PCI probes that don't have corresponding prints in the failed
boot (dmesg-rt4-sr2.fail.txt). 

I tried booting variously with:
	pci=routeirq
	acpi=noirq
	lapic
but saw no change in behavior.

Have you tried booting your system with a up kernel?

Clark

-- 
Clark Williams <williams@redhat.com>

[-- Attachment #1.2: dmesg-rt4-sr2.fail.txt --]
[-- Type: text/plain, Size: 9285 bytes --]

Bootdata ok (command line is ro root=/dev/sda2 console=ttyS0,115200 console=tty0)
Linux version 2.6.15-rt4-sr2 (williams@rhel4-64-1.farm.hsv.redhat.com) (gcc version 3.4.4 20050721 (Red Hat 3.4.4-2)) #4 PREEMPT Fri Jan 13 12:43:09 CST 2006
BIOS-provided physical RAM map:
 BIOS-e820: 0000000000000000 - 000000000009f800 (usable)
 BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
 BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
 BIOS-e820: 0000000000100000 - 000000001fff0000 (usable)
 BIOS-e820: 000000001fff0000 - 000000001fff3000 (ACPI NVS)
 BIOS-e820: 000000001fff3000 - 0000000020000000 (ACPI data)
 BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved)
 BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
 BIOS-e820: 00000000ffff0000 - 0000000100000000 (reserved)
ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
Processor #0 15:12 APIC version 16
ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
IOAPIC[0]: apic_id 2, version 20, address 0xfec00000, GSI 0-23
ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 dfl dfl)
Setting APIC routing to flat
Using ACPI (MADT) for SMP configuration information
Allocating PCI resources starting at 30000000 (gap: 20000000:dec00000)
Checking aperture...
CPU 0: aperture @ e0000000 size 128 MB
Real-Time Preemption Support (C) 2004-2005 Ingo Molnar
Built 1 zonelists
Kernel command line: ro root=/dev/sda2 console=ttyS0,115200 console=tty0
Initializing CPU#0
WARNING: experimental RCU implementation.
PID hash table entries: 2048 (order: 11, 65536 bytes)
time.c: Using 1.193182 MHz PIT timer.
time.c: Detected 1999.480 MHz processor.
Console: colour VGA+ 80x25
Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
Memory: 491540k/524224k available (2228k kernel code, 32296k reserved, 1327k data, 184k init)
Calibrating delay using timer specific routine.. 3999.67 BogoMIPS (lpj=1999839)
Security Framework v1.0.0 initialized
SELinux:  Initializing.
SELinux:  Starting in permissive mode
selinux_register_security:  Registering secondary module capability
Capability LSM initialized as secondary
Mount-cache hash table entries: 256
CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
CPU: L2 Cache: 512K (64 bytes/line)
mtrr: v2.0 (20020519)
CPU: AMD Athlon(tm) 64 Processor 3000+ stepping 00
Using local APIC timer interrupts.
Detected 12.496 MHz APIC timer.
testing NMI watchdog ... OK.
checking if image is initramfs... it is
NET: Registered protocol family 16
ACPI: bus type pci registered
PCI: Using configuration type 1
ACPI: Subsystem revision 20050902
ACPI: Interpreter enabled
ACPI: Using IOAPIC for interrupt routing
ACPI: PCI Root Bridge [PCI0] (0000:00)
ACPI: Assume root bridge [\_SB_.PCI0] bus is 0
ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 9 10 11 12 *14 15)
ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 9 10 *11 12 14 15)
ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 *5 6 7 9 10 11 12 14 15)
ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 9 *10 11 12 14 15)
ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 9 *10 11 12 14 15)
ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 9 10 *11 12 14 15)
ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 *9 10 11 12 14 15)
ACPI: PCI Interrupt Link [LNKH] (IRQs *3 4 5 6 7 9 10 11 12 14 15)
usbcore: registered new driver usbfs
usbcore: registered new driver hub
PCI: Using ACPI for IRQ routing
PCI: If a device doesn't work, try "pci=routeirq".  If it helps, post a report
agpgart: Detected AGP bridge 0
agpgart: AGP aperture is 128M @ 0xe0000000
PCI-DMA: Disabling IOMMU.
PCI: Bridge: 0000:00:01.0
  IO window: disabled.
  MEM window: e8000000-e9ffffff
  PREFETCH window: d0000000-dfffffff
IA32 emulation $Id: sys_ia32.c,v 1.32 2002/03/24 13:02:28 ak Exp $
audit: initializing netlink socket (disabled)
audit(1137180272.734:1): initialized
Total HugeTLB memory allocated, 0
Time: tsc clocksource has been installed.
VFS: Disk quotas dquot_6.5.1
Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
SELinux:  Registering netfilter hooks
Initializing Cryptographic API
io scheduler noop registered
io scheduler anticipatory registered
io scheduler deadline registered
io scheduler cfq registered
pci_hotplug: PCI Hot Plug PCI Core version: 0.5
ACPI: Fan [FAN] (on)
ACPI: Thermal Zone [THRM] (40 C)
Real Time Clock Driver v1.12
Linux agpgart interface v0.101 (c) Dave Jones
[drm] Initialized drm 1.0.0 20040925
serio: i8042 AUX port at 0x60,0x64 irq 12
serio: i8042 KBD port at 0x60,0x64 irq 1
Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
GSI 16 sharing vector 0xA9 and IRQ 16
ACPI: PCI Interrupt 0000:00:02.6[C] -> GSI 18 (level, low) -> IRQ 169
ACPI: PCI interrupt for device 0000:00:02.6 disabled
RAMDISK driver initialized: 16 RAM disks of 16384K size 1024 blocksize
Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
SIS5513: IDE controller at PCI slot 0000:00:02.5
SIS5513: chipset revision 1
SIS5513: not 100% native mode: will probe irqs later
SIS5513: SiS 962/963 MuTIOL IDE UDMA133 controller
    ide0: BM-DMA at 0x4000-0x4007, BIOS settings: hda:DMA, hdb:pio
    ide1: BM-DMA at 0x4008-0x400f, BIOS settings: hdc:pio, hdd:pio
hdc: AOPEN COM5232/AAH PRO, ATAPI CD/DVD-ROM drive
ide1 at 0x170-0x177,0x376 on irq 15
hdc: ATAPI 52X DVD-ROM CD-R/RW drive, 2048kB Cache, UDMA(33)
Uniform CD-ROM driver Revision: 3.20
ide-floppy driver 0.99.newide
usbcore: registered new driver hiddev
usbcore: registered new driver usbhid
drivers/usb/input/hid-core.c: v2.6:USB HID core driver
mice: PS/2 mouse device common for all mice
md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27
md: bitmap version 4.39
NET: Registered protocol family 2
IP route cache hash table entries: 4096 (order: 3, 32768 bytes)
TCP established hash table entries: 16384 (order: 8, 1179648 bytes)
input: AT Translated Set 2 keyboard as /class/input/input0
TCP bind hash table entries: 16384 (order: 8, 1048576 bytes)
TCP: Hash tables configured (established 16384 bind 16384)
TCP reno registered
TCP bic registered
Initializing IPsec netlink socket
NET: Registered protocol family 1
NET: Registered protocol family 17
powernow-k8: Found 1 AMD Athlon 64 / Opteron processors (version 1.50.4)
powernow-k8:    0 : fid 0xc (2000 MHz), vid 0x2 (1500 mV)
powernow-k8:    1 : fid 0xa (1800 MHz), vid 0x6 (1400 mV)
powernow-k8:    2 : fid 0xa (1800 MHz), vid 0x6 (1400 mV)
cpu_init done, current fid 0xc, vid 0x2
ACPI wakeup devices:
FUTS PCI0 USB0 USB1 USB2 USB3 MAC0 AMR0 UAR1 PS2M PS2K
ACPI: (supports S0 S1 S4 S5)
*****************************************************************************
*                                                                           *
*  REMINDER, the following debugging option is turned on in your .config:   *
*                                                                           *
*        CONFIG_LATENCY_TRACE                                               *
*                                                                           *
*  it may increase runtime overhead and latencies.                          *
*                                                                           *
*****************************************************************************
Freeing unused kernel memory: 184k freed
logips2pp: Detected unknown logitech mouse model 1
SCSI subsystem initialized
sata_sis 0000:00:05.0: version 0.5
GSI 17 sharing vector 0xB1 and IRQ 17
ACPI: PCI Interrupt 0000:00:05.0[A] -> GSI 17 (level, low) -> IRQ 177
sata_sis 0000:00:05.0: Detected SiS 180/181 chipset in SATA mode
ata1: SATA max UDMA/133 cmd 0xE500 ctl 0xE602 bmdma 0xE900 irq 177
ata2: SATA max UDMA/133 cmd 0xE700 ctl 0xE802 bmdma 0xE908 irq 177
input: ImPS/2 Logitech Wheel Mouse as /class/input/input1
ata1: dev 0 ATA-7, max UDMA/133, 160836480 sectors: LBA48
ata1: dev 0 configured for UDMA/133
scsi0 : sata_sis
ata2: no device found (phy stat 00000000)
scsi1 : sata_sis
  Vendor: ATA       Model: HDS728080PLA380   Rev: PF2O
  Type:   Direct-Access                      ANSI SCSI revision: 05
SCSI device sda: 160836480 512-byte hdwr sectors (82348 MB)
SCSI device sda: drive cache: write back
SCSI device sda: 160836480 512-byte hdwr sectors (82348 MB)
SCSI device sda: drive cache: write back
 sda: sda1 sda2 sda3
sd 0:0:0:0: Attached scsi disk sda
EXT3-fs: mounted filesystem with ordered data mode.
kjournald starting.  Commit interval 5 seconds
init[1]: segfault at ffffffff8010fadc rip ffffffff8010fadc rsp 00007fffffd03f08 error 15
init[1]: segfault at ffffffff8010fadc rip ffffffff8010fadc rsp 00007fffffd03f08 error 15
init[1]: segfault at ffffffff8010fadc rip ffffffff8010fadc rsp 00007fffffd03f08 error 15
init[1]: segfault at ffffffff8010fadc rip ffffffff8010fadc rsp 00007fffffd03f08 error 15
init[1]: segfault at ffffffff8010fadc rip ffffffff8010fadc rsp 00007fffffd03f08 error 15


[-- Attachment #1.3: dmesg-rt4-sr2.good.txt --]
[-- Type: text/plain, Size: 13967 bytes --]

Bootdata ok (command line is ro root=/dev/sda2 console=ttyS0,115200 console=tty0)
Linux version 2.6.15-rt4-sr2 (williams@rhel4-64-1.farm.hsv.redhat.com) (gcc version 3.4.4 20050721 (Red Hat 3.4.4-2)) #3 PREEMPT Fri Jan 13 12:12:19 CST 2006
BIOS-provided physical RAM map:
 BIOS-e820: 0000000000000000 - 000000000009f800 (usable)
 BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
 BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
 BIOS-e820: 0000000000100000 - 000000001fff0000 (usable)
 BIOS-e820: 000000001fff0000 - 000000001fff3000 (ACPI NVS)
 BIOS-e820: 000000001fff3000 - 0000000020000000 (ACPI data)
 BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved)
 BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
 BIOS-e820: 00000000ffff0000 - 0000000100000000 (reserved)
ACPI: RSDP (v000 AWARD                                 ) @ 0x00000000000f6eb0
ACPI: RSDT (v001 AWARD  AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x000000001fff3000
ACPI: FADT (v001 AWARD  AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x000000001fff3040
ACPI: MADT (v001 AWARD  AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x000000001fff6b80
ACPI: DSDT (v001 AWARD  AWRDACPI 0x00001000 MSFT 0x0100000e) @ 0x0000000000000000
On node 0 totalpages: 128075
  DMA zone: 2850 pages, LIFO batch:0
  DMA32 zone: 125225 pages, LIFO batch:31
  Normal zone: 0 pages, LIFO batch:0
  HighMem zone: 0 pages, LIFO batch:0
ACPI: Local APIC address 0xfee00000
ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
Processor #0 15:12 APIC version 16
ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
IOAPIC[0]: apic_id 2, version 20, address 0xfec00000, GSI 0-23
ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 dfl dfl)
ACPI: IRQ0 used by override.
ACPI: IRQ2 used by override.
ACPI: IRQ9 used by override.
Setting APIC routing to flat
Using ACPI (MADT) for SMP configuration information
Allocating PCI resources starting at 30000000 (gap: 20000000:dec00000)
Checking aperture...
CPU 0: aperture @ e0000000 size 128 MB
Real-Time Preemption Support (C) 2004-2005 Ingo Molnar
Built 1 zonelists
Kernel command line: ro root=/dev/sda2 console=ttyS0,115200 console=tty0
Initializing CPU#0
WARNING: experimental RCU implementation.
PID hash table entries: 2048 (order: 11, 65536 bytes)
time.c: Using 1.193182 MHz PIT timer.
time.c: Detected 1999.481 MHz processor.
Console: colour VGA+ 80x25
Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
Memory: 510156k/524224k available (2169k kernel code, 13680k reserved, 1326k data, 184k init)
Calibrating delay using timer specific routine.. 3999.43 BogoMIPS (lpj=1999719)
Security Framework v1.0.0 initialized
SELinux:  Initializing.
SELinux:  Starting in permissive mode
selinux_register_security:  Registering secondary module capability
Capability LSM initialized as secondary
Mount-cache hash table entries: 256
CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
CPU: L2 Cache: 512K (64 bytes/line)
mtrr: v2.0 (20020519)
CPU: AMD Athlon(tm) 64 Processor 3000+ stepping 00
Using local APIC timer interrupts.
Detected 12.496 MHz APIC timer.
testing NMI watchdog ... OK.
checking if image is initramfs... it is
NET: Registered protocol family 16
ACPI: bus type pci registered
PCI: Using configuration type 1
ACPI: Subsystem revision 20050902
ACPI: Interpreter enabled
ACPI: Using IOAPIC for interrupt routing
ACPI: PCI Root Bridge [PCI0] (0000:00)
PCI: Probing PCI hardware (bus 00)
ACPI: Assume root bridge [\_SB_.PCI0] bus is 0
Boot video device is 0000:01:00.0
ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 9 10 11 12 *14 15)
ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 9 10 *11 12 14 15)
ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 *5 6 7 9 10 11 12 14 15)
ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 9 *10 11 12 14 15)
ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 9 *10 11 12 14 15)
ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 9 10 *11 12 14 15)
ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 *9 10 11 12 14 15)
ACPI: PCI Interrupt Link [LNKH] (IRQs *3 4 5 6 7 9 10 11 12 14 15)
usbcore: registered new driver usbfs
usbcore: registered new driver hub
PCI: Using ACPI for IRQ routing
PCI: If a device doesn't work, try "pci=routeirq".  If it helps, post a report
agpgart: Detected AGP bridge 0
agpgart: AGP aperture is 128M @ 0xe0000000
PCI-DMA: Disabling IOMMU.
PCI: Bridge: 0000:00:01.0
  IO window: disabled.
  MEM window: e8000000-e9ffffff
  PREFETCH window: d0000000-dfffffff
IA32 emulation $Id: sys_ia32.c,v 1.32 2002/03/24 13:02:28 ak Exp $
audit: initializing netlink socket (disabled)
audit(1137177211.587:1): initialized
Total HugeTLB memory allocated, 0
VFS: Disk quotas dquot_6.5.1
Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Time: tsc clocksource has been installed.
SELinux:  Registering netfilter hooks
Initializing Cryptographic API
io scheduler noop registered
io scheduler anticipatory registered
io scheduler deadline registered
io scheduler cfq registered
pci_hotplug: PCI Hot Plug PCI Core version: 0.5
ACPI: Fan [FAN] (on)
ACPI: Thermal Zone [THRM] (40 C)
Real Time Clock Driver v1.12
Linux agpgart interface v0.101 (c) Dave Jones
[drm] Initialized drm 1.0.0 20040925
serio: i8042 AUX port at 0x60,0x64 irq 12
serio: i8042 KBD port at 0x60,0x64 irq 1
Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
GSI 16 sharing vector 0xA9 and IRQ 16
ACPI: PCI Interrupt 0000:00:02.6[C] -> GSI 18 (level, low) -> IRQ 169
ACPI: PCI interrupt for device 0000:00:02.6 disabled
RAMDISK driver initialized: 16 RAM disks of 16384K size 1024 blocksize
Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
SIS5513: IDE controller at PCI slot 0000:00:02.5
SIS5513: chipset revision 1
SIS5513: not 100% native mode: will probe irqs later
SIS5513: SiS 962/963 MuTIOL IDE UDMA133 controller
    ide0: BM-DMA at 0x4000-0x4007, BIOS settings: hda:DMA, hdb:pio
    ide1: BM-DMA at 0x4008-0x400f, BIOS settings: hdc:pio, hdd:pio
Probing IDE interface ide0...
Probing IDE interface ide1...
hdc: AOPEN COM5232/AAH PRO, ATAPI CD/DVD-ROM drive
ide1 at 0x170-0x177,0x376 on irq 15
Probing IDE interface ide0...
hdc: ATAPI 52X DVD-ROM CD-R/RW drive, 2048kB Cache, UDMA(33)
Uniform CD-ROM driver Revision: 3.20
ide-floppy driver 0.99.newide
usbcore: registered new driver hiddev
usbcore: registered new driver usbhid
drivers/usb/input/hid-core.c: v2.6:USB HID core driver
mice: PS/2 mouse device common for all mice
md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27
md: bitmap version 4.39
NET: Registered protocol family 2
IP route cache hash table entries: 4096 (order: 3, 32768 bytes)
input: AT Translated Set 2 keyboard as /class/input/input0
TCP established hash table entries: 16384 (order: 8, 1179648 bytes)
TCP bind hash table entries: 16384 (order: 8, 1048576 bytes)
TCP: Hash tables configured (established 16384 bind 16384)
TCP reno registered
TCP bic registered
Initializing IPsec netlink socket
NET: Registered protocol family 1
NET: Registered protocol family 17
powernow-k8: Found 1 AMD Athlon 64 / Opteron processors (version 1.50.4)
powernow-k8:    0 : fid 0xc (2000 MHz), vid 0x2 (1500 mV)
powernow-k8:    1 : fid 0xa (1800 MHz), vid 0x6 (1400 mV)
powernow-k8:    2 : fid 0xa (1800 MHz), vid 0x6 (1400 mV)
cpu_init done, current fid 0xc, vid 0x2
ACPI wakeup devices: 
FUTS PCI0 USB0 USB1 USB2 USB3 MAC0 AMR0 UAR1 PS2M PS2K 
ACPI: (supports S0 S1 S4 S5)
Freeing unused kernel memory: 184k freed
SCSI subsystem initialized
libata version 1.20 loaded.
sata_sis 0000:00:05.0: version 0.5
GSI 17 sharing vector 0xB1 and IRQ 17
ACPI: PCI Interrupt 0000:00:05.0[A] -> GSI 17 (level, low) -> IRQ 177
sata_sis 0000:00:05.0: Detected SiS 180/181 chipset in SATA mode
ata1: SATA max UDMA/133 cmd 0xE500 ctl 0xE602 bmdma 0xE900 irq 177
ata2: SATA max UDMA/133 cmd 0xE700 ctl 0xE802 bmdma 0xE908 irq 177
logips2pp: Detected unknown logitech mouse model 1
input: ImPS/2 Logitech Wheel Mouse as /class/input/input1
ata1: dev 0 cfg 49:2f00 82:346b 83:7fe9 84:4773 85:3469 86:3c01 87:4763 88:407f
ata1: dev 0 ATA-7, max UDMA/133, 160836480 sectors: LBA48
ata1: dev 0 configured for UDMA/133
scsi0 : sata_sis
ata2: no device found (phy stat 00000000)
scsi1 : sata_sis
  Vendor: ATA       Model: HDS728080PLA380   Rev: PF2O
  Type:   Direct-Access                      ANSI SCSI revision: 05
SCSI device sda: 160836480 512-byte hdwr sectors (82348 MB)
SCSI device sda: drive cache: write back
SCSI device sda: 160836480 512-byte hdwr sectors (82348 MB)
SCSI device sda: drive cache: write back
 sda: sda1 sda2 sda3
sd 0:0:0:0: Attached scsi disk sda
EXT3-fs: mounted filesystem with ordered data mode.
kjournald starting.  Commit interval 5 seconds
security:  3 users, 6 roles, 888 types, 109 bools
security:  55 classes, 244070 rules
SELinux:  Completing initialization.
SELinux:  Setting up existing superblocks.
SELinux: initialized (dev sda2, type ext3), uses xattr
SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
SELinux: initialized (dev debugfs, type debugfs), uses genfs_contexts
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 eventpollfs, type eventpollfs), uses genfs_contexts
SELinux: initialized (dev inotifyfs, type inotifyfs), uses genfs_contexts
SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
SELinux: initialized (dev futexfs, type futexfs), uses genfs_contexts
SELinux: initialized (dev pipefs, type pipefs), uses task SIDs
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
SELinux: initialized (dev usbfs, type usbfs), uses genfs_contexts
Floppy drive(s): fd0 is 1.44M
FDC 0 is a post-1991 82077
e100: Intel(R) PRO/100 Network Driver, 3.4.14-k4-NAPI
e100: Copyright(c) 1999-2005 Intel Corporation
ACPI: PCI Interrupt 0000:00:0b.0[A] -> GSI 17 (level, low) -> IRQ 177
e100: eth0: e100_probe: addr 0xea124000, irq 177, MAC addr 00:90:27:DE:09:73
sis900.c: v1.08.08 Jan. 22 2005
GSI 18 sharing vector 0xB9 and IRQ 18
ACPI: PCI Interrupt 0000:00:04.0[A] -> GSI 19 (level, low) -> IRQ 185
0000:00:04.0: Realtek RTL8201 PHY transceiver found at address 1.
0000:00:04.0: Using transceiver found at address 1 as default
eth1: SiS 900 PCI Fast Ethernet at 0xe400, IRQ 185, 00:11:5b:8e:b9:8a.
shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
GSI 19 sharing vector 0xC1 and IRQ 19
ACPI: PCI Interrupt 0000:00:03.3[D] -> GSI 23 (level, low) -> IRQ 193
ehci_hcd 0000:00:03.3: EHCI Host Controller
PCI: cache line size of 64 is not supported by device 0000:00:03.3
ehci_hcd 0000:00:03.3: new USB bus registered, assigned bus number 1
ehci_hcd 0000:00:03.3: irq 193, io mem 0xea121000
ehci_hcd 0000:00:03.3: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 8 ports detected
ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
GSI 20 sharing vector 0xC9 and IRQ 20
ACPI: PCI Interrupt 0000:00:03.0[A] -> GSI 20 (level, low) -> IRQ 201
ohci_hcd 0000:00:03.0: OHCI Host Controller
ohci_hcd 0000:00:03.0: new USB bus registered, assigned bus number 2
ohci_hcd 0000:00:03.0: irq 201, io mem 0xea123000
hub 2-0:1.0: USB hub found
hub 2-0:1.0: 3 ports detected
GSI 21 sharing vector 0xD1 and IRQ 21
ACPI: PCI Interrupt 0000:00:03.1[B] -> GSI 21 (level, low) -> IRQ 209
ohci_hcd 0000:00:03.1: OHCI Host Controller
ohci_hcd 0000:00:03.1: new USB bus registered, assigned bus number 3
ohci_hcd 0000:00:03.1: irq 209, io mem 0xea125000
hub 3-0:1.0: USB hub found
hub 3-0:1.0: 3 ports detected
GSI 22 sharing vector 0xD9 and IRQ 22
ACPI: PCI Interrupt 0000:00:03.2[C] -> GSI 22 (level, low) -> IRQ 217
ohci_hcd 0000:00:03.2: OHCI Host Controller
ohci_hcd 0000:00:03.2: new USB bus registered, assigned bus number 4
ohci_hcd 0000:00:03.2: irq 217, io mem 0xea120000
hub 4-0:1.0: USB hub found
hub 4-0:1.0: 2 ports detected
ACPI: Power Button (FF) [PWRF]
ACPI: Power Button (CM) [PWRB]
ACPI: Sleep Button (CM) [FUTS]
md: Autodetecting RAID arrays.
md: autorun ...
md: ... autorun DONE.
device-mapper: 4.4.0-ioctl (2005-01-12) initialised: dm-devel@redhat.com
cdrom: open failed.
cdrom: open failed.
EXT3 FS on sda2, internal journal
kjournald starting.  Commit interval 5 seconds
EXT3 FS on sda1, internal journal
EXT3-fs: mounted filesystem with ordered data mode.
SELinux: initialized (dev sda1, type ext3), uses xattr
SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
Adding 2096472k swap on /dev/sda3.  Priority:-1 extents:1 across:2096472k
SELinux: initialized (dev binfmt_misc, type binfmt_misc), uses genfs_contexts
e100: eth0: e100_watchdog: link up, 100Mbps, full-duplex
eth1: Media Link On 100mbps half-duplex 
sed[1377] general protection rip:30ba7041b1 rsp:7fffffffe620 error:0
SELinux: initialized (dev rpc_pipefs, type rpc_pipefs), uses genfs_contexts
SELinux: initialized (dev 0:14, type nfs), uses genfs_contexts
SELinux: initialized (dev 0:15, type nfs), uses genfs_contexts

[-- Attachment #2: This is a digitally signed message part --]
[-- Type: application/pgp-signature, Size: 189 bytes --]

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: 2.6.15-rt4 failure with LATENCY_TRACE on x86_64
  2006-01-13 22:58       ` Clark Williams
@ 2006-01-13 23:08         ` Steven Rostedt
  2006-01-14  5:12           ` Mikael Andersson
  0 siblings, 1 reply; 7+ messages in thread
From: Steven Rostedt @ 2006-01-13 23:08 UTC (permalink / raw)
  To: Clark Williams; +Cc: lkml, Ingo Molnar


On Fri, 13 Jan 2006, Clark Williams wrote:

>
> Have you tried booting your system with a up kernel?
>

Not a x86_64 up.  But serveral up i386 boxes.

-- Steve


^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: 2.6.15-rt4 failure with LATENCY_TRACE on x86_64
  2006-01-13 23:08         ` Steven Rostedt
@ 2006-01-14  5:12           ` Mikael Andersson
  2006-01-15 18:59             ` Clark Williams
  0 siblings, 1 reply; 7+ messages in thread
From: Mikael Andersson @ 2006-01-14  5:12 UTC (permalink / raw)
  To: Steven Rostedt; +Cc: Clark Williams, lkml, Ingo Molnar

Steven Rostedt wrote:
> On Fri, 13 Jan 2006, Clark Williams wrote:
>>Have you tried booting your system with a up kernel?
>>
> Not a x86_64 up.  But serveral up i386 boxes.
> 

I had a very similar problem on a x86_64 up. I got a segfault in init 
with LATENCY_TRACE enabled on 2.6.15-rt2.
I get it at ffffffff8010fe30, which should be mcount according to my 
System.map [1]. It seems a bit weird because i have tried to alter 
mcount somewhat. Initially by removing the initial comparison, but later
i tried a few other things also. Nothing had any effect at all.

AFAIK glibc also has a mcount symbol, and it's almost as if ld.so would 
have linked the glibc mcount symbol to the kernel symbol mcount. That
would naturally lead to a pagefault :)
  And it would be consistent with the fact that statically linked shells
works.

It's probably something completely different, bevause that would be 
really weird. OTOH, it was really weird that i could change the asm for 
mcount in entry.S without any effect whatsoever.

I'll verify that it hasn't gone away in lates 2.6.15-rt tomorrow.

[1]
ffffffff8010fd68 T machine_check
ffffffff8010fdf0 T call_debug
ffffffff8010fe00 T call_softirq
ffffffff8010fe30 T mcount
ffffffff8010fe65 t skiptrace
ffffffff8010fe7c t out

/Mikael

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: 2.6.15-rt4 failure with LATENCY_TRACE on x86_64
  2006-01-14  5:12           ` Mikael Andersson
@ 2006-01-15 18:59             ` Clark Williams
  0 siblings, 0 replies; 7+ messages in thread
From: Clark Williams @ 2006-01-15 18:59 UTC (permalink / raw)
  To: Mikael Andersson; +Cc: Steven Rostedt, lkml, Ingo Molnar

[-- Attachment #1: Type: text/plain, Size: 1750 bytes --]

On Sat, 2006-01-14 at 06:12 +0100, Mikael Andersson wrote:
> Steven Rostedt wrote:
> > On Fri, 13 Jan 2006, Clark Williams wrote:
> >>Have you tried booting your system with a up kernel?
> >>
> > Not a x86_64 up.  But serveral up i386 boxes.
> > 
> 
> I had a very similar problem on a x86_64 up. I got a segfault in init 
> with LATENCY_TRACE enabled on 2.6.15-rt2.
> I get it at ffffffff8010fe30, which should be mcount according to my 
> System.map [1]. It seems a bit weird because i have tried to alter 
> mcount somewhat. Initially by removing the initial comparison, but later
> i tried a few other things also. Nothing had any effect at all.

Glad to see that I'm not completely insane :).

> 
> AFAIK glibc also has a mcount symbol, and it's almost as if ld.so would 
> have linked the glibc mcount symbol to the kernel symbol mcount. That
> would naturally lead to a pagefault :)
>   And it would be consistent with the fact that statically linked shells
> works.
> 
> It's probably something completely different, bevause that would be 
> really weird. OTOH, it was really weird that i could change the asm for 
> mcount in entry.S without any effect whatsoever.
> 

Yeah, I don't really see how ld.so would know about the kernel mcount.
AFAIK it only knows about symbols exported from ELF libraries that it
has loaded in user space. 

I'm still trying to figure out if LATENCY_TRACE could effect
do_execve(), since that's the routine that starts the init program. I'm
wondering if do_execve() is doing something that aggrevates ld.so, so
that it loads the program and/or libraries incorrectly.

Guess that's what I'll be doing Monday morning...

Clark

-- 
Clark Williams <williams@redhat.com>

[-- Attachment #2: This is a digitally signed message part --]
[-- Type: application/pgp-signature, Size: 189 bytes --]

^ permalink raw reply	[flat|nested] 7+ messages in thread

end of thread, other threads:[~2006-01-15 18:59 UTC | newest]

Thread overview: 7+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
     [not found] <1137103652.11354.40.camel@localhost.localdomain>
2006-01-13  3:18 ` 2.6.15-rt4 failure with LATENCY_TRACE on x86_64 Steven Rostedt
2006-01-13 15:06   ` Clark Williams
2006-01-13 15:54     ` Steven Rostedt
2006-01-13 22:58       ` Clark Williams
2006-01-13 23:08         ` Steven Rostedt
2006-01-14  5:12           ` Mikael Andersson
2006-01-15 18:59             ` Clark Williams

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).