Hello
I was using the LTTng agent to control LTTng Kernel tracing. Everything
worked fine until I tried to disable certain channels (command
setChannelEnable()). Interestingly, this command doesn't fail, however
the sub-subsequent command startTrace() fails. I did some debugging of
the LTTng agent to investigate the problem. I'm able to pin-point the
last line of execution, which is in the library liblttctrl. There is a
crash and the tcf-agent becomes a zombie process (defunc). When I
display kernel messages with the shell command dmesg I see a kernel
oops. After the error occurred, the remote machine has to be rebooted.
I'm not able to debug further and need some support.
Could someone please look into the problem? Please let me know if you
have any questions.
Steps to reproduce:
- Enable all markers (use ltt-armall)
- Start tcf-client
- connect <remote>
- tcf ltt_control setupTrace "kernel" "0" "h2"
- tcf ltt_control setChannelEnable "kernel" "0" "h2" false
- tcf ltt_control allocTrace "kernel" "0" "h2"
- lttctl_client ltt_control writeTraceLocal "kernel" "0" "h2"
"/tmp/h2" 2 false false false
- tcf ltt_control startTrace "kernel" "0" "h2"
Now, the command startTrace won't return. It hangs on line "if
(write(fd, op, strlen(op)) == -1)" of method "lttctl_sendop()" which was
called by method "lttctl_start()" (see file liblttctrl.c). Please see
attached file "dmesg_start.log" for the dmesg output.
My target node (running in virtualbox) has the following versions installed:
- Ubuntu 10.10
- Instrumented kernel 2.6.35-24 installed from the PPA repository at
https://launchpad.net/~lttng/+archive/ppa
- manually upgraded UST 0.12
- manually upgraded lttng-agent from the git repository
(git.dorsal.polymtl.ca/git/lttng-agent.git), master branch of 23 March 2011
Thank you very much in advance!
Best Regards
Bernd
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 2.6.35-24-lttng (buildd@nannyberry) (gcc version 4.4.5 (Ubuntu/Linaro 4.4.4-14ubuntu5) ) #37~lttng1-Ubuntu SMP Sun Oct 31 05:21:09 UTC 2010 (Ubuntu 2.6.35-24.37~lttng1-lttng 2.6.35.7)
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
[ 0.000000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 000000001fff0000 (usable)
[ 0.000000] BIOS-e820: 000000001fff0000 - 0000000020000000 (ACPI data)
[ 0.000000] BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserved)
[ 0.000000] NX (Execute Disable) protection: approximated by x86 segment limits
[ 0.000000] DMI 2.5 present.
[ 0.000000] e820 update range: 0000000000000000 - 0000000000001000 (usable) ==> (reserved)
[ 0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (usable)
[ 0.000000] last_pfn = 0x1fff0 max_arch_pfn = 0x100000
[ 0.000000] MTRR default type: uncachable
[ 0.000000] MTRR variable ranges disabled:
[ 0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[ 0.000000] CPU MTRRs all blank - virtualized system.
[ 0.000000] e820 update range: 0000000000002000 - 0000000000010000 (usable) ==> (reserved)
[ 0.000000] Scanning 1 areas for low memory corruption
[ 0.000000] modified physical RAM map:
[ 0.000000] modified: 0000000000000000 - 0000000000001000 (reserved)
[ 0.000000] modified: 0000000000001000 - 0000000000002000 (usable)
[ 0.000000] modified: 0000000000002000 - 0000000000010000 (reserved)
[ 0.000000] modified: 0000000000010000 - 000000000009fc00 (usable)
[ 0.000000] modified: 000000000009fc00 - 00000000000a0000 (reserved)
[ 0.000000] modified: 00000000000f0000 - 0000000000100000 (reserved)
[ 0.000000] modified: 0000000000100000 - 000000001fff0000 (usable)
[ 0.000000] modified: 000000001fff0000 - 0000000020000000 (ACPI data)
[ 0.000000] modified: 00000000fffc0000 - 0000000100000000 (reserved)
[ 0.000000] initial memory mapped : 0 - 00c00000
[ 0.000000] init_memory_mapping: 0000000000000000-000000001fff0000
[ 0.000000] 0000000000 - 0000400000 page 4k
[ 0.000000] 0000400000 - 001fc00000 page 2M
[ 0.000000] 001fc00000 - 001fff0000 page 4k
[ 0.000000] kernel direct mapping tables up to 1fff0000 @ 15000-1a000
[ 0.000000] RAMDISK: 175ed000 - 18034000
[ 0.000000] ACPI: RSDP 000e0000 00024 (v02 VBOX )
[ 0.000000] ACPI: XSDT 1fff0030 00034 (v01 VBOX VBOXXSDT 00000001 ASL 00000061)
[ 0.000000] ACPI: FACP 1fff00f0 000F4 (v04 VBOX VBOXFACP 00000001 ASL 00000061)
[ 0.000000] ACPI: DSDT 1fff0410 01A63 (v01 VBOX VBOXBIOS 00000002 INTL 20100528)
[ 0.000000] ACPI: FACS 1fff0200 00040
[ 0.000000] ACPI: SSDT 1fff0240 001CC (v01 VBOX VBOXCPUT 00000002 INTL 20100528)
[ 0.000000] 0MB HIGHMEM available.
[ 0.000000] 511MB LOWMEM available.
[ 0.000000] mapped low ram: 0 - 1fff0000
[ 0.000000] low ram: 0 - 1fff0000
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0x00000001 -> 0x00001000
[ 0.000000] Normal 0x00001000 -> 0x0001fff0
[ 0.000000] HighMem empty
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[3] active PFN ranges
[ 0.000000] 0: 0x00000001 -> 0x00000002
[ 0.000000] 0: 0x00000010 -> 0x0000009f
[ 0.000000] 0: 0x00000100 -> 0x0001fff0
[ 0.000000] On node 0 totalpages: 130944
[ 0.000000] free_area_init_node: node 0, pgdat c0807e40, node_mem_map c1001020
[ 0.000000] DMA zone: 32 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 3952 pages, LIFO batch:0
[ 0.000000] Normal zone: 992 pages used for memmap
[ 0.000000] Normal zone: 125968 pages, LIFO batch:31
[ 0.000000] Using APIC driver default
[ 0.000000] ACPI: PM-Timer IO Port: 0x4008
[ 0.000000] SMP: Allowing 1 CPUs, 0 hotplug CPUs
[ 0.000000] Found and enabled local APIC!
[ 0.000000] nr_irqs_gsi: 16
[ 0.000000] PM: Registered nosave memory: 0000000000002000 - 0000000000010000
[ 0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000
[ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000
[ 0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000
[ 0.000000] Allocating PCI resources starting at 20000000 (gap: 20000000:dffc0000)
[ 0.000000] Booting paravirtualized kernel on bare hardware
[ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:1 nr_node_ids:1
[ 0.000000] PERCPU: Embedded 14 pages/cpu @c1800000 s36480 r0 d20864 u4194304
[ 0.000000] early_res array is doubled to 64 at [16000 - 167ff]
[ 0.000000] pcpu-alloc: s36480 r0 d20864 u4194304 alloc=1*4194304
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 129920
[ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-2.6.35-24-lttng root=UUID=955d701b-409c-4db5-9be9-1244964e7067 ro quiet splash
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Enabling fast FPU save and restore... done.
[ 0.000000] Enabling unmasked SIMD FPU exception support... done.
[ 0.000000] Initializing CPU#0
[ 0.000000] allocated 2621100 bytes of page_cgroup
[ 0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[ 0.000000] Subtract (37 early reservations)
[ 0.000000] #1 [0000001000 - 0000002000] EX TRAMPOLINE
[ 0.000000] #2 [0000100000 - 00009accdc] TEXT DATA BSS
[ 0.000000] #3 [00175ed000 - 0018034000] RAMDISK
[ 0.000000] #4 [000009fc00 - 0000100000] BIOS reserved
[ 0.000000] #5 [00009ad000 - 00009b0071] BRK
[ 0.000000] #6 [0000010000 - 0000011000] TRAMPOLINE
[ 0.000000] #7 [0000011000 - 0000015000] ACPI WAKEUP
[ 0.000000] #8 [0000015000 - 0000016000] PGTABLE
[ 0.000000] #9 [0001000000 - 0001001000] BOOTMEM
[ 0.000000] #10 [0001001000 - 0001401000] BOOTMEM
[ 0.000000] #11 [0001401000 - 0001401004] BOOTMEM
[ 0.000000] #12 [0001401040 - 0001401100] BOOTMEM
[ 0.000000] #13 [0001401100 - 0001401130] BOOTMEM
[ 0.000000] #14 [0001401140 - 0001402940] BOOTMEM
[ 0.000000] #15 [0001402940 - 0001402a3c] BOOTMEM
[ 0.000000] #16 [0001402a40 - 0001402a80] BOOTMEM
[ 0.000000] #17 [0001402a80 - 0001402ac0] BOOTMEM
[ 0.000000] #18 [0001402ac0 - 0001402b00] BOOTMEM
[ 0.000000] #19 [0001402b00 - 0001402b40] BOOTMEM
[ 0.000000] #20 [0001402b40 - 0001402b80] BOOTMEM
[ 0.000000] #21 [0001402b80 - 0001402bc0] BOOTMEM
[ 0.000000] #22 [0001402bc0 - 0001402bd0] BOOTMEM
[ 0.000000] #23 [0001402c00 - 0001402c10] BOOTMEM
[ 0.000000] #24 [0001402c40 - 0001402ca8] BOOTMEM
[ 0.000000] #25 [0001402cc0 - 0001402d28] BOOTMEM
[ 0.000000] #26 [0001800000 - 000180e000] BOOTMEM
[ 0.000000] #27 [0001404d40 - 0001404d44] BOOTMEM
[ 0.000000] #28 [0001404d80 - 0001404d84] BOOTMEM
[ 0.000000] #29 [0001404dc0 - 0001404dc4] BOOTMEM
[ 0.000000] #30 [0001404e00 - 0001404e04] BOOTMEM
[ 0.000000] #31 [0001404e40 - 0001404ef0] BOOTMEM
[ 0.000000] #32 [0001404f00 - 0001404fa8] BOOTMEM
[ 0.000000] #33 [0001402d40 - 0001404d40] BOOTMEM
[ 0.000000] #34 [0001404fc0 - 0001444fc0] BOOTMEM
[ 0.000000] #35 [0001444fc0 - 0001464fc0] BOOTMEM
[ 0.000000] #36 [0001465000 - 00016e4eac] BOOTMEM
[ 0.000000] Initializing HighMem for node 0 (00000000:00000000)
[ 0.000000] Memory: 497208k/524224k available (4949k kernel code, 26568k reserved, 2362k data, 684k init, 0k highmem)
[ 0.000000] virtual kernel memory layout:
[ 0.000000] fixmap : 0xfff16000 - 0xfffff000 ( 932 kB)
[ 0.000000] pkmap : 0xff800000 - 0xffc00000 (4096 kB)
[ 0.000000] vmalloc : 0xe07f0000 - 0xff7fe000 ( 496 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xdfff0000 ( 511 MB)
[ 0.000000] .init : 0xc0825000 - 0xc08d0000 ( 684 kB)
[ 0.000000] .data : 0xc05d5792 - 0xc0824368 (2362 kB)
[ 0.000000] .text : 0xc0100000 - 0xc05d5792 (4949 kB)
[ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[ 0.000000] SLUB: Genslabs=13, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] RCU dyntick-idle grace-period acceleration is enabled.
[ 0.000000] RCU-based detection of stalled CPUs is disabled.
[ 0.000000] Verbose stalled-CPUs detection is disabled.
[ 0.000000] NR_IRQS:2304 nr_irqs:256
[ 0.000000] Console: colour VGA+ 80x25
[ 0.000000] console [tty0] enabled
[ 0.000000] Fast TSC calibration failed
[ 0.000000] TSC: Unable to calibrate against PIT
[ 0.000000] TSC: using PMTIMER reference calibration
[ 0.000000] Detected 2661.016 MHz processor.
[ 0.024234] Calibrating delay loop (skipped), value calculated using timer frequency.. 5322.03 BogoMIPS (lpj=10644064)
[ 0.024559] pid_max: default: 32768 minimum: 301
[ 0.025052] Security Framework initialized
[ 0.029403] AppArmor: AppArmor initialized
[ 0.029475] Yama: becoming mindful.
[ 0.033231] Mount-cache hash table entries: 512
[ 0.039596] Initializing cgroup subsys ns
[ 0.039677] Initializing cgroup subsys cpuacct
[ 0.039890] Initializing cgroup subsys memory
[ 0.040950] Initializing cgroup subsys devices
[ 0.041043] Initializing cgroup subsys freezer
[ 0.041131] Initializing cgroup subsys net_cls
[ 0.042054] mce: CPU supports 0 MCE banks
[ 0.042347] using mwait in idle threads.
[ 0.042455] Performance Events: unsupported p6 CPU model 23 no PMU driver, software events only.
[ 0.089802] SMP alternatives: switching to UP code
[ 0.544000] Freeing SMP alternatives: 24k freed
[ 0.548452] ACPI: Core revision 20100428
[ 0.557298] ACPI: setting ELCR to 0200 (from 0e20)
[ 0.559658] ftrace: converting mcount calls to 0f 1f 44 00 00
[ 0.560000] ftrace: allocating 21862 entries in 43 pages
[ 0.622328] Enabling APIC mode: Flat. Using 0 I/O APICs
[ 0.624441] weird, boot CPU (#0) not listed by the BIOS.
[ 0.624536] SMP motherboard not detected.
[ 0.628000] SMP disabled
[ 0.665538] Brought up 1 CPUs
[ 0.668111] Total of 1 processors activated (5322.03 BogoMIPS).
[ 0.676673] devtmpfs: initialized
[ 0.681559] regulator: core version 0.5
[ 0.685491] Time: 19:33:50 Date: 04/15/11
[ 0.685880] NET: Registered protocol family 16
[ 0.691019] EISA bus registered
[ 0.692339] ACPI: bus type pci registered
[ 0.773090] PCI: PCI BIOS revision 2.10 entry at 0xfc090, last bus=0
[ 0.773255] PCI: Using configuration type 1 for base access
[ 0.777686] bio: create slab <bio-0> at 0
[ 0.786856] ACPI: EC: Look up EC in DSDT
[ 0.788284] ACPI: Executed 1 blocks of module-level executable AML code
[ 0.796531] ACPI: Interpreter enabled
[ 0.796574] ACPI: (supports S0 S5)
[ 0.796665] ACPI: Using PIC for interrupt routing
[ 0.801376] ACPI: No dock devices found.
[ 0.801429] PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug
[ 0.807068] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.809031] pci_root PNP0A03:00: host bridge window [io 0x0000-0x0cf7] (ignored)
[ 0.809244] pci_root PNP0A03:00: host bridge window [io 0x0d00-0xffff] (ignored)
[ 0.809438] pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff] (ignored)
[ 0.809613] pci_root PNP0A03:00: host bridge window [mem 0x20000000-0xffdfffff] (ignored)
[ 0.816733] pci 0000:00:01.1: reg 20: [io 0xd000-0xd00f]
[ 0.818460] pci 0000:00:02.0: reg 10: [mem 0xe0000000-0xe0ffffff pref]
[ 0.819731] pci 0000:00:03.0: reg 10: [mem 0xf0000000-0xf001ffff]
[ 0.819888] pci 0000:00:03.0: reg 18: [io 0xd010-0xd017]
[ 0.820565] pci 0000:00:04.0: reg 10: [io 0xd020-0xd03f]
[ 0.821287] pci 0000:00:04.0: reg 14: [mem 0xf0400000-0xf07fffff]
[ 0.821969] pci 0000:00:04.0: reg 18: [mem 0xf0800000-0xf0803fff pref]
[ 0.822377] pci 0000:00:05.0: reg 10: [io 0xd100-0xd1ff]
[ 0.822439] pci 0000:00:05.0: reg 14: [io 0xd200-0xd23f]
[ 0.823394] pci 0000:00:06.0: reg 10: [mem 0xf0804000-0xf0804fff]
[ 0.824872] pci 0000:00:0d.0: reg 10: [io 0xd240-0xd247]
[ 0.825043] pci 0000:00:0d.0: reg 18: [io 0xd250-0xd257]
[ 0.825169] pci 0000:00:0d.0: reg 20: [io 0xd260-0xd26f]
[ 0.825918] pci 0000:00:0d.0: reg 24: [mem 0xf0806000-0xf0807fff]
[ 0.826281] pci_bus 0000:00: on NUMA node 0
[ 0.826338] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[ 0.832827] ACPI: PCI Interrupt Link [LNKA] (IRQs *5 9 10 11)
[ 0.833260] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 9 10 *11)
[ 0.833487] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 9 *10 11)
[ 0.833708] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 *9 10 11)
[ 0.833789] HEST: Table is not found!
[ 0.833789] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[ 0.836173] vgaarb: loaded
[ 0.837160] SCSI subsystem initialized
[ 0.837752] libata version 3.00 loaded.
[ 0.837889] usbcore: registered new interface driver usbfs
[ 0.837924] usbcore: registered new interface driver hub
[ 0.838072] usbcore: registered new device driver usb
[ 0.849132] ACPI: WMI: Mapper loaded
[ 0.849250] PCI: Using ACPI for IRQ routing
[ 0.849340] PCI: pci_cache_line_size set to 64 bytes
[ 0.850046] reserve RAM buffer: 0000000000002000 - 000000000000ffff
[ 0.850119] reserve RAM buffer: 000000000009fc00 - 000000000009ffff
[ 0.850180] reserve RAM buffer: 000000001fff0000 - 000000001fffffff
[ 0.852683] NetLabel: Initializing
[ 0.852783] NetLabel: domain hash size = 128
[ 0.852845] NetLabel: protocols = UNLABELED CIPSOv4
[ 0.852988] NetLabel: unlabeled traffic allowed by default
[ 0.853710] Switching to clocksource tsc
[ 0.860200] AppArmor: AppArmor Filesystem Enabled
[ 0.860322] pnp: PnP ACPI init
[ 0.860368] ACPI: bus type pnp registered
[ 0.860841] ERROR: Unable to locate IOAPIC for GSI 1
[ 0.861005] ERROR: Unable to locate IOAPIC for GSI 12
[ 0.861077] ERROR: Unable to locate IOAPIC for GSI 7
[ 0.861870] pnp: PnP ACPI: found 5 devices
[ 0.861893] ACPI: ACPI bus type pnp unregistered
[ 0.863853] PnPBIOS: Disabled by ACPI PNP
[ 0.901022] pci_bus 0000:00: resource 0 [io 0x0000-0xffff]
[ 0.901053] pci_bus 0000:00: resource 1 [mem 0x00000000-0xffffffff]
[ 0.901134] NET: Registered protocol family 2
[ 0.902858] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.903191] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[ 0.903295] TCP bind hash table entries: 16384 (order: 5, 131072 bytes)
[ 0.903404] TCP: Hash tables configured (established 16384 bind 16384)
[ 0.903429] TCP reno registered
[ 0.903453] UDP hash table entries: 256 (order: 1, 8192 bytes)
[ 0.903483] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[ 0.903611] NET: Registered protocol family 1
[ 0.903663] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 0.904360] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 0.904422] pci 0000:00:02.0: Boot video device
[ 0.904579] PCI: CLS 0 bytes, default 64
[ 0.905866] platform rtc_cmos: registered platform RTC device (no PNP device found)
[ 0.908047] cpufreq-nforce2: No nForce2 chipset.
[ 0.908133] Scanning for low memory corruption every 60 seconds
[ 0.928069] Trying to unpack rootfs image as initramfs...
[ 0.944238] audit: initializing netlink socket (disabled)
[ 0.945653] type=2000 audit(1302896029.940:1): initialized
[ 0.969307] HugeTLB registered 4 MB page size, pre-allocated 0 pages
[ 0.978154] VFS: Disk quotas dquot_6.5.2
[ 0.978242] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.979524] fuse init (API version 7.14)
[ 0.979953] msgmni has been set to 971
[ 1.024625] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[ 1.024671] io scheduler noop registered
[ 1.024700] io scheduler deadline registered
[ 1.024734] io scheduler cfq registered (default)
[ 1.024907] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[ 1.024955] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[ 1.026713] ACPI: AC Adapter [AC] (on-line)
[ 1.028018] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[ 1.028071] ACPI: Power Button [PWRF]
[ 1.028179] input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input1
[ 1.028205] ACPI: Sleep Button [SLPF]
[ 1.028601] ACPI: acpi_idle registered with cpuidle
[ 1.030140] ERST: Table is not found!
[ 1.030577] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 1.037987] ACPI: Battery Slot [BAT0] (battery present)
[ 1.038289] isapnp: Scanning for PnP cards...
[ 1.045559] brd: module loaded
[ 1.045727] loop: module loaded
[ 1.046548] ata_piix 0000:00:01.1: version 2.13
[ 1.060467] scsi0 : ata_piix
[ 1.105208] scsi1 : ata_piix
[ 1.105564] ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xd000 irq 14
[ 1.105591] ata2: PATA max UDMA/33 cmd 0x170 ctl 0x376 bmdma 0xd008 irq 15
[ 1.115666] Fixed MDIO Bus: probed
[ 1.115750] PPP generic driver version 2.4.2
[ 1.115902] tun: Universal TUN/TAP device driver, 1.6
[ 1.115924] tun: (C) 1999-2004 Max Krasnyansky <[email protected]>
[ 1.116071] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.116110] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 1.116638] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
[ 1.116667] PCI: setting IRQ 11 as level-triggered
[ 1.116698] ohci_hcd 0000:00:06.0: PCI INT A -> Link[LNKB] -> GSI 11 (level, low) -> IRQ 11
[ 1.116783] ohci_hcd 0000:00:06.0: OHCI Host Controller
[ 1.116857] ohci_hcd 0000:00:06.0: new USB bus registered, assigned bus number 1
[ 1.116941] ohci_hcd 0000:00:06.0: irq 11, io mem 0xf0804000
[ 1.181659] hub 1-0:1.0: USB hub found
[ 1.182265] hub 1-0:1.0: 8 ports detected
[ 1.238944] uhci_hcd: USB Universal Host Controller Interface driver
[ 1.239353] PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
[ 1.250961] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 1.251440] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 1.251827] mice: PS/2 mouse device common for all mice
[ 1.259300] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
[ 1.262084] rtc0: alarms up to one day, 114 bytes nvram
[ 1.262360] device-mapper: uevent: version 1.0.3
[ 1.269007] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
[ 1.271499] device-mapper: ioctl: 4.17.0-ioctl (2010-03-05) initialised: [email protected]
[ 1.274893] device-mapper: multipath: version 1.1.1 loaded
[ 1.274920] device-mapper: multipath round-robin: version 1.0.0 loaded
[ 1.281339] EISA: Probing bus 0 at eisa.0
[ 1.281405] Cannot allocate resource for EISA slot 4
[ 1.281440] EISA: Detected 0 cards.
[ 1.286801] cpuidle: using governor ladder
[ 1.286843] cpuidle: using governor menu
[ 1.287211] TCP cubic registered
[ 1.287351] NET: Registered protocol family 10
[ 1.287721] lo: Disabled Privacy Extensions
[ 1.287915] NET: Registered protocol family 17
[ 1.301373] ata2.00: ATAPI: VBOX CD-ROM, 1.0, max UDMA/133
[ 1.302399] ata2.00: configured for UDMA/33
[ 1.306514] Using IPI No-Shortcut mode
[ 1.330649] PM: Resume from disk failed.
[ 1.330761] registered taskstats version 1
[ 1.331168] Magic number: 7:160:597
[ 1.332121] rtc_cmos rtc_cmos: setting system clock to 2011-04-15 19:33:50 UTC (1302896030)
[ 1.332151] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[ 1.332173] EDD information not available.
[ 1.606418] Freeing initrd memory: 10524k freed
[ 1.662571] usb 1-1: new full speed USB device using ohci_hcd and address 2
[ 1.763264] isapnp: No Plug & Play device found
[ 1.806932] scsi 1:0:0:0: CD-ROM VBOX CD-ROM 1.0 PQ: 0 ANSI: 5
[ 1.830311] sr0: scsi3-mmc drive: 32x/32x xa/form2 tray
[ 1.830351] Uniform CD-ROM driver Revision: 3.20
[ 1.830636] sr 1:0:0:0: Attached scsi CD-ROM sr0
[ 1.831485] sr 1:0:0:0: Attached scsi generic sg0 type 5
[ 1.832259] Freeing unused kernel memory: 684k freed
[ 1.845832] Write protecting the kernel text: 4952k
[ 1.847831] Write protecting the kernel read-only data: 1988k
[ 1.963589] udev[67]: starting version 163
[ 2.505857] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k6-NAPI
[ 2.505899] e1000: Copyright (c) 1999-2006 Intel Corporation.
[ 2.506548] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
[ 2.506578] PCI: setting IRQ 10 as level-triggered
[ 2.506609] e1000 0000:00:03.0: PCI INT A -> Link[LNKC] -> GSI 10 (level, low) -> IRQ 10
[ 2.522530] usbcore: registered new interface driver hiddev
[ 2.906637] input: VirtualBox USB Tablet as /devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/input/input3
[ 2.906901] generic-usb 0003:80EE:0021.0001: input,hidraw0: USB HID v1.10 Mouse [VirtualBox USB Tablet] on usb-0000:00:06.0-1/input0
[ 2.908298] usbcore: registered new interface driver usbhid
[ 2.908341] usbhid: USB HID core driver
[ 2.952019] e1000 0000:00:03.0: eth0: (PCI:33MHz:32-bit) 08:00:27:91:e6:87
[ 2.954489] e1000 0000:00:03.0: eth0: Intel(R) PRO/1000 Network Connection
[ 2.956773] ahci 0000:00:0d.0: version 3.0
[ 2.957802] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 5
[ 2.957831] PCI: setting IRQ 5 as level-triggered
[ 2.957861] ahci 0000:00:0d.0: PCI INT A -> Link[LNKA] -> GSI 5 (level, low) -> IRQ 5
[ 2.959096] ahci: SSS flag set, parallel bus scan disabled
[ 2.959189] ahci 0000:00:0d.0: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl SATA mode
[ 2.959218] ahci 0000:00:0d.0: flags: 64bit ncq stag only ccc
[ 2.964436] scsi2 : ahci
[ 2.964841] ata3: SATA max UDMA/133 abar m8192@0xf0806000 port 0xf0806100 irq 5
[ 3.287367] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 3.291830] ata3.00: ATA-6: VBOX HARDDISK, 1.0, max UDMA/133
[ 3.291985] ata3.00: 41963520 sectors, multi 128: LBA48 NCQ (depth 31/32)
[ 3.299280] ata3.00: configured for UDMA/133
[ 3.302880] scsi 2:0:0:0: Direct-Access ATA VBOX HARDDISK 1.0 PQ: 0 ANSI: 5
[ 3.304257] sd 2:0:0:0: Attached scsi generic sg1 type 0
[ 3.305295] sd 2:0:0:0: [sda] 41963520 512-byte logical blocks: (21.4 GB/20.0 GiB)
[ 3.307327] sd 2:0:0:0: [sda] Write Protect is off
[ 3.307353] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 3.307539] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 3.312655] sda: sda1 sda2 < sda5 >
[ 3.329856] sd 2:0:0:0: [sda] Attached SCSI disk
[ 3.484223] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[ 4.006480] Adding 916476k swap on /dev/sda5. Priority:-1 extents:1 across:916476k
[ 4.197399] udev[232]: starting version 163
[ 4.271030] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro
[ 4.625000] lp: driver loaded but no devices found
[ 4.808124] piix4_smbus 0000:00:07.0: SMBus base address uninitialized - upgrade BIOS or use force_addr=0xaddr
[ 5.313985] psmouse serio1: ID: 10 00 64
[ 5.378806] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4
[ 5.531065] LTT : ltt-relay init
[ 5.584950] parport_pc 00:04: reported by Plug and Play ACPI
[ 5.676419] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 9
[ 5.676434] PCI: setting IRQ 9 as level-triggered
[ 5.676449] pci 0000:00:04.0: PCI INT A -> Link[LNKD] -> GSI 9 (level, low) -> IRQ 9
[ 5.747723] type=1400 audit(1302896034.911:2): apparmor="STATUS" operation="profile_load" name="/sbin/dhclient3" pid=409 comm="apparmor_parser"
[ 5.748352] type=1400 audit(1302896034.915:3): apparmor="STATUS" operation="profile_load" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=409 comm="apparmor_parser"
[ 5.748736] type=1400 audit(1302896034.915:4): apparmor="STATUS" operation="profile_load" name="/usr/lib/connman/scripts/dhclient-script" pid=409 comm="apparmor_parser"
[ 5.751245] type=1400 audit(1302896034.915:5): apparmor="STATUS" operation="profile_replace" name="/sbin/dhclient3" pid=371 comm="apparmor_parser"
[ 5.751830] type=1400 audit(1302896034.915:6): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=371 comm="apparmor_parser"
[ 5.752168] type=1400 audit(1302896034.919:7): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=371 comm="apparmor_parser"
[ 5.855415] ppdev: user-space parallel port driver
[ 6.016027] vboxguest: major 0, IRQ 9, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
[ 6.029043] vboxguest: Successfully loaded version 3.2.8_OSE (interface 0x00010004)
[ 6.814418] LTT : State dump init
[ 6.902258] Intel ICH 0000:00:05.0: PCI INT A -> Link[LNKA] -> GSI 5 (level, low) -> IRQ 5
[ 6.907690] LTT : ltt-kprobes init
[ 7.417804] intel8x0_measure_ac97_clock: measured 133888 usecs (17336 samples)
[ 7.417831] intel8x0: measured clock 129481 rejected
[ 7.674109] type=1400 audit(1302896036.839:8): apparmor="STATUS" operation="profile_load" name="/usr/share/gdm/guest-session/Xsession" pid=646 comm="apparmor_parser"
[ 7.719708] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[ 7.729542] type=1400 audit(1302896036.895:9): apparmor="STATUS" operation="profile_replace" name="/sbin/dhclient3" pid=649 comm="apparmor_parser"
[ 7.730339] type=1400 audit(1302896036.895:10): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=649 comm="apparmor_parser"
[ 7.730694] type=1400 audit(1302896036.895:11): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=649 comm="apparmor_parser"
[ 7.776202] intel8x0_measure_ac97_clock: measured 54226 usecs (12600 samples)
[ 7.776263] intel8x0: measured clock 232360 rejected
[ 7.859251] type=1400 audit(1302896037.023:12): apparmor="STATUS" operation="profile_load" name="/usr/bin/evince" pid=651 comm="apparmor_parser"
[ 7.894822] type=1400 audit(1302896037.059:13): apparmor="STATUS" operation="profile_load" name="/usr/bin/evince-previewer" pid=651 comm="apparmor_parser"
[ 7.930185] type=1400 audit(1302896037.095:14): apparmor="STATUS" operation="profile_load" name="/usr/bin/evince-thumbnailer" pid=651 comm="apparmor_parser"
[ 8.007608] type=1400 audit(1302896037.171:15): apparmor="STATUS" operation="profile_load" name="/usr/lib/cups/backend/cups-pdf" pid=667 comm="apparmor_parser"
[ 8.007982] type=1400 audit(1302896037.171:16): apparmor="STATUS" operation="profile_load" name="/usr/sbin/cupsd" pid=667 comm="apparmor_parser"
[ 8.035110] type=1400 audit(1302896037.199:17): apparmor="STATUS" operation="profile_load" name="/usr/sbin/tcpdump" pid=670 comm="apparmor_parser"
[ 8.137071] intel8x0_measure_ac97_clock: measured 56743 usecs (13697 samples)
[ 8.137131] intel8x0: measured clock 241386 rejected
[ 8.137154] intel8x0: clocking to 48000
[ 10.563708] vboxsf: Successfully loaded version 3.2.8_OSE (interface 0x00010004)
[ 10.654672] Linux agpgart interface v0.103
[ 10.737113] [drm] Initialized drm 1.1.0 20060810
[ 10.747982] pci 0000:00:02.0: PCI INT A -> Link[LNKB] -> GSI 11 (level, low) -> IRQ 11
[ 10.750798] [drm] Initialized vboxvideo 1.0.0 20090303 for 0000:00:02.0 on minor 0
[ 12.476491] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
[ 15.158470] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
[ 18.272584] eth0: no IPv6 routers present
[ 416.740678] LTT : Tracing not active for trace h5
[ 416.740908] LTT state dump begin
[ 416.740931] LTT state dump thread start
[ 416.743693] LTT state dump end
[ 447.956279] LTT: 36 events written in channel metadata (cpu 0, index 0)
[ 447.956424] LTT: 54278 events written in channel fs (cpu 0, index 0)
[ 447.956468] LTT: 40989 events written in channel fs (cpu 0, index 1)
[ 618.382904] LTT : Tracing not active for trace h6
[ 618.383350] BUG: unable to handle kernel NULL pointer dereference at 00000014
[ 618.383983] IP: [<e080828f>] ltt_trace_start+0x7f/0x190 [ltt_tracer]
[ 618.384608] *pde = 1ea70067 *pte = 00000000
[ 618.384806] Oops: 0000 [#1] SMP
[ 618.384869] last sysfs file: /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0C0A:00/power_supply/BAT0/voltage_now
[ 618.385050] Modules linked in: lp ltt_tracer serio_raw snd_seq_device joydev snd_page_alloc ltt_core net_trace hid soundcore vboxvideo ltt_marker_control i2c_piix4 vboxsf snd_rawmidi agpgart fs_trace e1000 libahci snd_timer ltt_trace_control binfmt_misc ipc_trace ahci psmouse snd_seq_midi usbhid ltt_filter jbd2_trace syscall_trace snd parport snd_seq kernel_trace trap_trace mm_trace ac97_bus snd_seq_midi_event ltt_statedump vboxguest ltt_relay parport_pc block_trace ppdev snd_pcm snd_ac97_codec snd_intel8x0 ltt_kprobes ltt_userspace_event rcu_trace drm
[ 618.386077]
[ 618.386077] Pid: 1629, comm: tcf-agent Not tainted 2.6.35-24-lttng #37~lttng1-Ubuntu /VirtualBox
[ 618.386077] EIP: 0060:[<e080828f>] EFLAGS: 00010202 CPU: 0
[ 618.386077] EIP is at ltt_trace_start+0x7f/0x190 [ltt_tracer]
[ 618.386077] EAX: de1ec938 EBX: 00000002 ECX: 000000fc EDX: 00000000
[ 618.386077] ESI: 00000002 EDI: de1ec800 EBP: dea85f28 ESP: dea85f10
[ 618.386077] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
[ 618.386077] Process tcf-agent (pid: 1629, ti=dea84000 task=dfbdcc20 task.ti=dea84000)
[ 618.386077] Stack:
[ 618.386077] 00000fff 00000001 df2a2400 00000001 00000000 dde23000 dea85f54 e088e87b
[ 618.386077] <0> dde23000 e088f0b9 de1ea000 def18f00 de1ea000 00000001 def18f00 00000001
[ 618.386077] <0> 006759a4 dea85f7c c021ce22 dea85f94 00000002 dea85f7c e088e7a0 00000008
[ 618.386077] Call Trace:
[ 618.386077] [<e088e87b>] ? enabled_write+0xdb/0x164 [ltt_trace_control]
[ 618.386077] [<c021ce22>] ? vfs_write+0xa2/0x190
[ 618.386077] [<e088e7a0>] ? enabled_write+0x0/0x164 [ltt_trace_control]
[ 618.386077] [<c021d7db>] ? sys_write+0x4b/0xc0
[ 618.386077] [<c05cdf7c>] ? syscall_call+0x7/0xb
[ 618.386077] Code: 00 84 c0 0f 85 cd 00 00 00 8b 45 f0 8b 70 0c 8b 78 08 85 f6 74 21 31 c0 31 db 66 90 69 c0 38 01 00 00 83 c3 01 8d 04 07 8b 50 28 <8b> 52 14 ff 52 24 39 f3 89 d8 72 e5 8b 45 f0 c7 40 10 01 00 00
[ 618.386077] EIP: [<e080828f>] ltt_trace_start+0x7f/0x190 [ltt_tracer] SS:ESP 0068:dea85f10
[ 618.386077] CR2: 0000000000000014
[ 618.400464] ---[ end trace 3bdb19f1a660263a ]---
_______________________________________________
ltt-dev mailing list
[email protected]
http://lists.casi.polymtl.ca/cgi-bin/mailman/listinfo/ltt-dev
