Attachment 522366 Details for Bug 737118 – serial console output with systemd.log_level=debug

serial console output with systemd.log_level=debug

serialconsole_bootlog.txt (text/plain), 34.29 KB, created by Tim Flink on 2011-09-09 16:36:32 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Tim Flink

Created: 2011-09-09 16:36:32 UTC

Size: 34.29 KB

patch

obsolete

>[root@testlap ~]# virsh console f16test
>Connected to domain f16test
>Escape character is ^]
>[    0.000000] Initializing cgroup subsys cpuset
>[    0.000000] Initializing cgroup subsys cpu
>[    0.000000] Linux version 3.1.0-0.rc4.git0.0.fc16.x86_64 (mockbuild@x86-03.phx2.fedoraproject.org) (gcc version 4.6.1 20110804 (Red Hat 4.6.1-7) (GCC) ) #1 SMP Tue Aug 30 00:00:26 UTC 2011
>[    0.000000] Command line: BOOT_IMAGE=/vmlinuz-3.1.0-0.rc4.git0.0.fc16.x86_64 root=/dev/mapper/vg_testhost-lv_root ro rd.md=0 rd.dm=0 KEYTABLE=us rd.lvm.lv=vg_testhost/lv_root rd.lvm.lv=vg_testhost/lv_swap rd.luks=0 SYSFONT=latarcyrheb-sun16 LANG=en_US.UTF-8 systemd.log_level=debug systemd.log_target=kmsg console=ttyS0,19200n8 3
>[    0.000000] BIOS-provided physical RAM map:
>[    0.000000]  BIOS-e820: 0000000000000000 - 000000000009f400 (usable)
>[    0.000000]  BIOS-e820: 000000000009f400 - 00000000000a0000 (reserved)
>[    0.000000]  BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
>[    0.000000]  BIOS-e820: 0000000000100000 - 000000003fffd000 (usable)
>[    0.000000]  BIOS-e820: 000000003fffd000 - 0000000040000000 (reserved)
>[    0.000000]  BIOS-e820: 00000000fffbc000 - 0000000100000000 (reserved)
>[    0.000000] NX (Execute Disable) protection: active
>[    0.000000] DMI 2.4 present.
>[    0.000000] No AGP bridge found
>[    0.000000] last_pfn = 0x3fffd max_arch_pfn = 0x400000000
>[    0.000000] PAT not supported by CPU.
>[    0.000000] found SMP MP-table at [ffff8800000f7b80] f7b80
>[    0.000000] init_memory_mapping: 0000000000000000-000000003fffd000
>[    0.000000] RAMDISK: 35fe4000 - 36fea000
>[    0.000000] ACPI: RSDP 00000000000f7b30 00014 (v00 BOCHS )
>[    0.000000] ACPI: RSDT 000000003fffde30 00034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
>[    0.000000] ACPI: FACP 000000003ffffe70 00074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
>[    0.000000] ACPI: DSDT 000000003fffdfd0 01E22 (v01   BXPC   BXDSDT 00000001 INTL 20090123)
>[    0.000000] ACPI: FACS 000000003ffffe00 00040
>[    0.000000] ACPI: SSDT 000000003fffdf90 00037 (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
>[    0.000000] ACPI: APIC 000000003fffdeb0 00072 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
>[    0.000000] ACPI: HPET 000000003fffde70 00038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
>[    0.000000] No NUMA configuration found
>[    0.000000] Faking a node at 0000000000000000-000000003fffd000
>[    0.000000] Initmem setup node 0 0000000000000000-000000003fffd000
>[    0.000000]   NODE_DATA [000000003ffe5000 - 000000003fff9fff]
>[    0.000000] kvm-clock: Using msrs 12 and 11
>[    0.000000] kvm-clock: cpu 0, msr 0:1d51701, boot clock
>[    0.000000] Zone PFN ranges:
>[    0.000000]   DMA      0x00000010 -> 0x00001000
>[    0.000000]   DMA32    0x00001000 -> 0x00100000
>[    0.000000]   Normal   empty
>[    0.000000] Movable zone start PFN for each node
>[    0.000000] early_node_map[2] active PFN ranges
>[    0.000000]     0: 0x00000010 -> 0x0000009f
>[    0.000000]     0: 0x00000100 -> 0x0003fffd
>[    0.000000] ACPI: PM-Timer IO Port: 0xb008
>[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
>[    0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
>[    0.000000] IOAPIC[0]: apic_id 1, version 17, address 0xfec00000, GSI 0-23
>[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
>[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
>[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
>[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
>[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
>[    0.000000] Using ACPI (MADT) for SMP configuration information
>[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
>[    0.000000] SMP: Allowing 1 CPUs, 0 hotplug CPUs
>[    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 40000000 (gap: 40000000:bffbc000)
>[    0.000000] Booting paravirtualized kernel on KVM
>[    0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1
>[    0.000000] PERCPU: Embedded 476 pages/cpu @ffff88003fc00000 s1918464 r8192 d23040 u2097152
>[    0.000000] kvm-clock: cpu 0, msr 0:3fdd3701, primary cpu clock
>[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 257927
>[    0.000000] Policy zone: DMA32
>[    0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-3.1.0-0.rc4.git0.0.fc16.x86_64 root=/dev/mapper/vg_testhost-lv_root ro rd.md=0 rd.dm=0 KEYTABLE=us rd.lvm.lv=vg_testhost/lv_root rd.lvm.lv=vg_testhost/lv_swap rd.luks=0 SYSFONT=latarcyrheb-sun16 LANG=en_US.UTF-8 systemd.log_level=debug systemd.log_target=kmsg console=ttyS0,19200n8 3
>[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
>[    0.000000] Checking aperture...
>[    0.000000] No AGP bridge found
>[    0.000000] Memory: 986248k/1048564k available (5184k kernel code, 452k absent, 61864k reserved, 6577k data, 2780k init)
>[    0.000000] SLUB: Genslabs=15, 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 lockdep checking is enabled.
>[    0.000000] NR_IRQS:33024 nr_irqs:256 16
>[    0.000000] Console: colour VGA+ 80x25
>[    0.000000] console [ttyS0] enabled
>[    0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
>[    0.000000] ... MAX_LOCKDEP_SUBCLASSES:  8
>[    0.000000] ... MAX_LOCK_DEPTH:          48
>[    0.000000] ... MAX_LOCKDEP_KEYS:        8191
>[    0.000000] ... CLASSHASH_SIZE:          4096
>[    0.000000] ... MAX_LOCKDEP_ENTRIES:     16384
>[    0.000000] ... MAX_LOCKDEP_CHAINS:      32768
>[    0.000000] ... CHAINHASH_SIZE:          16384
>[    0.000000]  memory used by lock dependency info: 6367 kB
>[    0.000000]  per task-struct memory footprint: 2688 bytes
>[    0.000000] allocated 8388608 bytes of page_cgroup
>[    0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
>[    0.000000] Detected 2659.998 MHz processor.
>[    0.002999] Calibrating delay loop (skipped) preset value.. 5319.99 BogoMIPS (lpj=2659998)
>[    0.003757] pid_max: default: 32768 minimum: 301
>[    0.004427] Security Framework initialized
>[    0.005023] SELinux:  Initializing.
>[    0.007659] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
>[    0.009383] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
>[    0.010430] Mount-cache hash table entries: 256
>[    0.013658] Initializing cgroup subsys cpuacct
>[    0.014035] Initializing cgroup subsys memory
>[    0.015287] Initializing cgroup subsys devices
>[    0.016040] Initializing cgroup subsys freezer
>[    0.017022] Initializing cgroup subsys net_cls
>[    0.018019] Initializing cgroup subsys blkio
>[    0.019149] Initializing cgroup subsys perf_event
>[    0.020390] mce: CPU supports 10 MCE banks
>[    0.022177] SMP alternatives: switching to UP code
>[    0.031489] Freeing SMP alternatives: 12k freed
>[    0.032051] ACPI: Core revision 20110623
>[    0.064176] ftrace: allocating 25821 entries in 102 pages
>[    0.069964] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
>[    0.070007] CPU0: Intel QEMU Virtual CPU version 0.14.0 stepping 03
>[    0.072993] Performance Events: unsupported p6 CPU model 2 no PMU driver, software events only.
>[    0.075754] NMI watchdog disabled (cpu0): hardware events not enabled
>[    0.077166] Brought up 1 CPUs
>[    0.078001] Total of 1 processors activated (5319.99 BogoMIPS).
>[    0.081760] devtmpfs: initialized
>[    0.088946] atomic64 test passed for x86-64 platform with CX8 and with SSE
>[    0.090217] RTC time: 21:13:34, date: 09/08/11
>[    0.091545] NET: Registered protocol family 16
>[    0.095356] ACPI: bus type pci registered
>[    0.096640] PCI: Using configuration type 1 for base access
>[    0.118229] bio: create slab <bio-0> at 0
>[    0.119762] ACPI: Added _OSI(Module Device)
>[    0.120006] ACPI: Added _OSI(Processor Device)
>[    0.121015] ACPI: Added _OSI(3.0 _SCP Extensions)
>[    0.122007] ACPI: Added _OSI(Processor Aggregator Device)
>[    0.177947] ACPI: Interpreter enabled
>[    0.177988] ACPI: (supports S0 S3 S4 S5)
>[    0.180004] ACPI: Using IOAPIC for interrupt routing
>[    0.250041] ACPI: No dock devices found.
>[    0.250797] HEST: Table not found.
>[    0.250979] PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug
>[    0.253245] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
>[    0.257343] pci 0000:00:01.3: quirk: [io  0xb000-0xb03f] claimed by PIIX4 ACPI
>[    0.257988] pci 0000:00:01.3: quirk: [io  0xb100-0xb10f] claimed by PIIX4 SMB
>[    0.296631]  pci0000:00: Unable to request _OSC control (_OSC support mask: 0x1e)
>[    0.469644] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
>[    0.472222] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
>[    0.474778] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
>[    0.477319] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
>[    0.480301] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
>[    0.482027] vgaarb: loaded
>[    0.482731] vgaarb: bridge control possible 0000:00:02.0
>[    0.485524] SCSI subsystem initialized
>[    0.487755] usbcore: registered new interface driver usbfs
>[    0.489019] usbcore: registered new interface driver hub
>[    0.490157] usbcore: registered new device driver usb
>[    0.491750] PCI: Using ACPI for IRQ routing
>[    0.494635] NetLabel: Initializing
>[    0.494939] NetLabel:  domain hash size = 128
>[    0.495940] NetLabel:  protocols = UNLABELED CIPSOv4
>[    0.497207] NetLabel:  unlabeled traffic allowed by default
>[    0.497983] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
>[    0.498988] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
>[    0.500164] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
>[    0.505385] Switching to clocksource kvm-clock
>[    0.506651] Switched to NOHz mode on CPU #0
>[    0.656371] pnp: PnP ACPI init
>[    0.657130] ACPI: bus type pnp registered
>[    0.667810] pnp: PnP ACPI: found 6 devices
>[    0.668895] ACPI: ACPI bus type pnp unregistered
>[    0.699645] NET: Registered protocol family 2
>[    0.701850] IP route cache hash table entries: 32768 (order: 6, 262144 bytes)
>[    0.706929] TCP established hash table entries: 131072 (order: 9, 2097152 bytes)
>[    0.710795] TCP bind hash table entries: 65536 (order: 10, 5242880 bytes)
>[    0.720214] TCP: Hash tables configured (established 131072 bind 65536)
>[    0.722425] TCP reno registered
>[    0.723465] UDP hash table entries: 512 (order: 4, 98304 bytes)
>[    0.725468] UDP-Lite hash table entries: 512 (order: 4, 98304 bytes)
>[    0.728260] NET: Registered protocol family 1
>[    0.729703] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
>[    0.731607] pci 0000:00:01.0: PIIX3: Enabling Passive Release
>[    0.733353] pci 0000:00:01.0: Activating ISA DMA hang workarounds
>[    0.736153] Unpacking initramfs...
>[    1.316432] Freeing initrd memory: 16408k freed
>[    1.449335] DMA-API: preallocated 32768 debug entries
>[    1.451067] DMA-API: debugging enabled by kernel config
>[    1.455428] cryptomgr_test used greatest stack depth: 6184 bytes left
>[    1.457362] Intel AES-NI instructions are not detected.
>[    1.460259] audit: initializing netlink socket (disabled)
>[    1.461826] type=2000 audit(1315516416.460:1): initialized
>[    1.490608] HugeTLB registered 2 MB page size, pre-allocated 0 pages
>[    1.534225] VFS: Disk quotas dquot_6.5.2
>[    1.535902] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
>[    1.543872] msgmni has been set to 1958
>[    1.547765] cryptomgr_test used greatest stack depth: 5608 bytes left
>[    1.550545] cryptomgr_test used greatest stack depth: 5464 bytes left
>[    1.552536] alg: No test for stdrng (krng)
>[    1.553815] NET: Registered protocol family 38
>[    1.555651] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
>[    1.557934] io scheduler noop registered
>[    1.559057] io scheduler deadline registered
>[    1.560953] io scheduler cfq registered (default)
>[    1.562234] start plist test
>[    1.564096] end plist test
>[    1.571185] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
>[    1.573207] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
>[    1.575074] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
>[    1.577640] acpiphp: Slot [1] registered
>[    1.579215] acpiphp: Slot [2] registered
>[    1.580674] acpiphp: Slot [3] registered
>[    1.582375] acpiphp: Slot [4] registered
>[    1.583895] acpiphp: Slot [5] registered
>[    1.585434] acpiphp: Slot [6] registered
>[    1.586990] acpiphp: Slot [7] registered
>[    1.588913] acpiphp: Slot [8] registered
>[    1.590730] acpiphp: Slot [9] registered
>[    1.592423] acpiphp: Slot [10] registered
>[    1.593889] acpiphp: Slot [11] registered
>[    1.596064] acpiphp: Slot [12] registered
>[    1.597947] acpiphp: Slot [13] registered
>[    1.599512] acpiphp: Slot [14] registered
>[    1.601284] acpiphp: Slot [15] registered
>[    1.602904] acpiphp: Slot [16] registered
>[    1.604640] acpiphp: Slot [17] registered
>[    1.607064] acpiphp: Slot [18] registered
>[    1.608930] acpiphp: Slot [19] registered
>[    1.610694] acpiphp: Slot [20] registered
>[    1.612512] acpiphp: Slot [21] registered
>[    1.614315] acpiphp: Slot [22] registered
>[    1.616041] acpiphp: Slot [23] registered
>[    1.617882] acpiphp: Slot [24] registered
>[    1.619657] acpiphp: Slot [25] registered
>[    1.621485] acpiphp: Slot [26] registered
>[    1.623447] acpiphp: Slot [27] registered
>[    1.625381] acpiphp: Slot [28] registered
>[    1.627374] acpiphp: Slot [29] registered
>[    1.629216] acpiphp: Slot [30] registered
>[    1.631039] acpiphp: Slot [31] registered
>[    1.636890] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
>[    1.639578] ACPI: Power Button [PWRF]
>[    1.669122] ERST: Table is not found!
>[    1.670671] GHES: HEST is not enabled!
>[    1.674877] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
>[    1.677202] virtio-pci 0000:00:03.0: PCI INT A -> Link[LNKC] -> GSI 11 (level, high) -> IRQ 11
>[    1.682862] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
>[    1.685173] virtio-pci 0000:00:05.0: PCI INT A -> Link[LNKA] -> GSI 10 (level, high) -> IRQ 10
>[    1.691306] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
>[    1.693439] virtio-pci 0000:00:06.0: PCI INT A -> Link[LNKB] -> GSI 10 (level, high) -> IRQ 10
>[    1.696904] virtio-pci 0000:00:07.0: PCI INT A -> Link[LNKC] -> GSI 11 (level, high) -> IRQ 11
>[    1.700806] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
>[    1.724903] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
>[    1.742702] Non-volatile memory driver v1.3
>[    1.744480] Linux agpgart interface v0.103
>[    1.756332] loop: module loaded
>[    1.764623] scsi0 : ata_piix
>[    1.767663] scsi1 : ata_piix
>[    1.769569] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc000 irq 14
>[    1.771934] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc008 irq 15
>[    1.775719] Fixed MDIO Bus: probed
>[    1.777913] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
>[    1.780754] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
>[    1.783115] uhci_hcd: USB Universal Host Controller Interface driver
>[    1.788143] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
>[    1.790255] uhci_hcd 0000:00:01.2: PCI INT D -> Link[LNKD] -> GSI 11 (level, high) -> IRQ 11
>[    1.793223] uhci_hcd 0000:00:01.2: UHCI Host Controller
>[    1.796641] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
>[    1.799517] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c020
>[    1.811967] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
>[    1.851503] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
>[    1.853985] usb usb1: Product: UHCI Host Controller
>[    1.855689] usb usb1: Manufacturer: Linux 3.1.0-0.rc4.git0.0.fc16.x86_64 uhci_hcd
>[    1.858270] usb usb1: SerialNumber: 0000:00:01.2
>[    1.862129] hub 1-0:1.0: USB hub found
>[    1.864043] hub 1-0:1.0: 2 ports detected
>[    1.867841] usbcore: registered new interface driver usbserial
>[    1.870383] USB Serial support registered for generic
>[    1.872660] usbcore: registered new interface driver usbserial_generic
>[    1.875158] usbserial: USB Serial Driver core
>[    1.877292] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
>[    1.881923] serio: i8042 KBD port at 0x60,0x64 irq 1
>[    1.884269] serio: i8042 AUX port at 0x60,0x64 irq 12
>[    1.887810] mousedev: PS/2 mouse device common for all mice
>[    1.892417] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
>[    1.901849] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0
>[    1.904799] rtc0: alarms up to one day, 114 bytes nvram, hpet irqs
>[    1.909339] device-mapper: uevent: version 1.0.3
>[    1.912516] device-mapper: ioctl: 4.21.0-ioctl (2011-07-06) initialised: dm-devel@redhat.com
>[    1.917400] cpuidle: using governor ladder
>[    1.919164] cpuidle: using governor menu
>[    1.921709] EFI Variables Facility v0.08 2004-May-17
>[    1.926801] usbcore: registered new interface driver usbhid
>[    1.929037] usbhid: USB HID core driver
>[    1.932265] ip_tables: (C) 2000-2006 Netfilter Core Team
>[    1.935133] ata2.00: ATAPI: QEMU DVD-ROM, 0.14.0, max UDMA/100
>[    1.938118] ata2.00: configured for MWDMA2
>[    1.941750] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     0.14 PQ: 0 ANSI: 5
>[    1.947336] sr0: scsi3-mmc drive: 4x/4x xa/form2 tray
>[    1.949335] cdrom: Uniform CD-ROM driver Revision: 3.20
>[    1.952594] TCP cubic registered
>[    1.953861] Initializing XFRM netlink socket
>[    1.957899] NET: Registered protocol family 10
>[    1.962790] sr 1:0:0:0: Attached scsi generic sg0 type 5
>[    1.972292] Mobile IPv6
>[    1.973462] NET: Registered protocol family 17
>[    1.975448] Registering the dns_resolver key type
>[    1.978767] registered taskstats version 1
>[    1.986267] IMA: No TPM chip found, activating TPM-bypass!
>[    1.991257]   Magic number: 11:16:247
>[    1.993230] rtc_cmos 00:01: setting system clock to 2011-09-08 21:13:36 UTC (1315516416)
>[    1.997087] Initializing network drop monitor service
>[    2.002380] Freeing unused kernel memory: 2780k freed
>[    2.006857] Write protecting the kernel read-only data: 10240k
>[    2.012949] Freeing unused kernel memory: 940k freed
>[    2.021273] Freeing unused kernel memory: 1424k freed
>[    2.033800] mknod used greatest stack depth: 5120 bytes left
>[    2.051703] mount used greatest stack depth: 4904 bytes left
>[    2.167099] usb 1-1: new full speed USB device number 2 using uhci_hcd
>[    2.332604] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2
>[    2.454329] Refined TSC clocksource calibration: 2659.987 MHz.
>[    2.510305] usb 1-1: New USB device found, idVendor=0627, idProduct=0001
>[    2.513826] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=5
>[    2.516580] usb 1-1: Product: QEMU USB Tablet
>[    2.518276] usb 1-1: Manufacturer: QEMU 0.14.0
>[    2.519991] usb 1-1: SerialNumber: 42
>[    2.560920] input: QEMU 0.14.0 QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/input/input3
>[    2.579526] generic-usb 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Pointer [QEMU 0.14.0 QEMU USB Tablet] on usb-0000:00:01.2-1/input0
>[    2.906832] dracut: dracut-013-8.fc16
>[    2.933841] dracut: rd.luks=0: removing cryptoluks activation
>[    2.972280] udevd[99]: starting version 173
>[    3.305624] console_init used greatest stack depth: 4848 bytes left
>[    3.323929] dracut: Starting plymouth daemon
>[    3.558261] dracut: rd.dm=0: removing DM RAID activation
>[    3.577572] dracut: rd.md=0: removing MD RAID activation
>[    3.726985] modprobe used greatest stack depth: 4704 bytes left
>[    4.039167]  vda: vda1 vda2 vda3
>[    4.054164] cdrom_id used greatest stack depth: 3936 bytes left
>[    4.088806] modprobe used greatest stack depth: 3856 bytes left
>[    4.716433] dracut: Scanning devices vda3  for LVM logical volumes vg_testhost/lv_root vg_testhost/lv_swap
>[    4.747560] dracut: inactive '/dev/vg_testhost/lv_swap' [1.94 GiB] inherit
>[    4.749313] dracut: inactive '/dev/vg_testhost/lv_root' [5.56 GiB] inherit
>[    5.062853] EXT4-fs (dm-1): INFO: recovery required on readonly filesystem
>[    5.064366] EXT4-fs (dm-1): write access will be enabled during recovery
>[    5.258105] EXT4-fs (dm-1): recovery complete
>[    5.277221] EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null)
>[    5.287632] mount used greatest stack depth: 3680 bytes left
>[    5.349817] dracut: Checking ext4: /dev/mapper/vg_testhost-lv_root
>[    5.352244] dracut: issuing e2fsck -a  /dev/mapper/vg_testhost-lv_root
>[    5.397284] dracut: /dev/mapper/vg_testhost-lv_root: clean, 122321/365040 files, 902627/1458176 blocks
>[    5.404262] dracut: Remounting /dev/mapper/vg_testhost-lv_root with -o ro
>[    5.424354] EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null)
>[    5.433823] dracut: Mounted root filesystem /dev/mapper/vg_testhost-lv_root
>[    5.640662] dracut: Switching root
>[    5.726099] type=1404 audit(1315516420.232:2): enforcing=1 old_enforcing=0 auid=4294967295 ses=4294967295
>[    7.700595] type=1403 audit(1315516422.206:3): policy loaded auid=4294967295 ses=4294967295
>[    7.728196] systemd[1]: Successfully loaded SELinux policy in 2s 18ms 518us.
>[    7.747596] systemd[1]: Successfully loaded SELinux database in 16ms 739us, size on heap is 464K.
>[    7.901986] systemd[1]: Relabelled /dev and /run in 121ms 958us.
>[    7.929754] systemd[1]: systemd 35 running in system mode. (+PAM +LIBWRAP +AUDIT +SELINUX +SYSVINIT +LIBCRYPTSETUP; fedora)
>
>Welcome to Fedora release 16 (Verne)!
>
>[    7.943245] systemd[1]: Set hostname to <testhost.localdomain>.
>[    8.291984] systemd[1]: Path /sys/kernel/security is already a mount point, refusing start for sys-kernel-security.automount
>Starting Load legacy module configuration...
>Started Runtime Directory.
>Started File System Check on Root Device.
>Started Lock Directory.
>Starting Remount API VFS...
>Starting Media Directory...
>Starting Remount Root FS...
>Started Set Up Additional Binary Formats.
>Starting Apply Kernel Variables...
>Starting Setup Virtual Console...
>Started Load Kernel Modules.
>Starting RPC Pipe File System...
>Started Load static arp entries.
>Starting udev Coldplug all Devices...
>Starting udev Kernel Device Manager...
>Starting Syslog Kernel Log Buffer Bridge...
>Started Syslog Kernel Log Buffer Bridge.
>Started Remount API VFS.
>Started Media Directory.
>Started Apply Kernel Variables.
>Starting STDOUT Syslog Bridge...
>[    8.802318] EXT4-fs (dm-1): re-mounted. Opts: (null)
>Started STDOUT Syslog Bridge.
>[    8.832495] systemd-cgroups used greatest stack depth: 3552 bytes left
>Started Remount Root FS.
>Starting Configure read-only root support...
>[    8.957691] RPC: Registered named UNIX socket transport module.
>[    8.959881] RPC: Registered udp transport module.
>[    8.961563] RPC: Registered tcp transport module.
>[    8.963275] RPC: Registered tcp NFSv4.1 backchannel transport module.
>Started RPC Pipe File System.
>[    9.011993] systemd[1]: var-lib-nfs-rpc_pipefs.mount mount process exited, code=exited status=32
>[    9.030710] fedora-loadmodu used greatest stack depth: 3504 bytes left
>Started Load legacy module configuration.
>[    9.088652] fedora-readonly[407]: /etc/init.d/functions: line 58: /dev/stderr: No such device or address
>[    9.256340] udevd[397]: starting version 173
>Started udev Kernel Device Manager.
>Started Setup Virtual Console.
>Started udev Coldplug all Devices.
>Starting udev Wait for Complete Device Initialization...
>[   10.093094] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0
>[   10.392304] snd_hda_intel 0000:00:04.0: PCI INT A -> Link[LNKD] -> GSI 11 (level, high) -> IRQ 11
>[   10.657773] type=1400 audit(1315516425.163:4): avc:  denied  { relabelto } for  pid=442 comm="udevd" name="rtc" dev=devtmpfs ino=8269 scontext=system_u:system_r:udev_t:s0-s0:c0.c1023 tcontext=system_u:object_r:default_t:s0 tclass=lnk_file
>[   10.661924] type=1400 audit(1315516425.167:5): avc:  denied  { associate } for  pid=442 comm="udevd" name="rtc" dev=devtmpfs ino=8269 scontext=system_u:object_r:default_t:s0 tcontext=system_u:object_r:device_t:s0 tclass=filesystem
>[   10.716785] mtp-probe[524]: checking bus 1, device 2: "/sys/devices/pci0000:00/0000:00:01.2/usb1/1-1"
>[   10.792720] udevd[442]: setfilecon /dev/rtc failed: Permission denied
>[   10.912473] mtp-probe[524]: bus: 1, device: 2 was not an MTP device
>[   11.039405] microcode: CPU0 sig=0x623, pf=0x0, revision=0x0
>[   11.537934] type=1400 audit(1315516426.043:6): avc:  denied  { associate } for  pid=432 comm="udevd" name="scd0" dev=devtmpfs ino=8422 scontext=system_u:object_r:default_t:s0 tcontext=system_u:object_r:device_t:s0 tclass=filesystem
>[   11.646234] udevd[432]: setfilecon /dev/scd0 failed: Permission denied
>[   11.722498] type=1400 audit(1315516426.228:7): avc:  denied  { create } for  pid=432 comm="udevd" name="cdrom" scontext=system_u:system_r:udev_t:s0-s0:c0.c1023 tcontext=system_u:object_r:default_t:s0 tclass=lnk_file
>[   11.726607] type=1400 audit(1315516426.232:8): avc:  denied  { associate } for  pid=432 comm="udevd" name="cdrom" scontext=system_u:object_r:default_t:s0 tcontext=system_u:object_r:device_t:s0 tclass=filesystem
>[   11.788994] type=1400 audit(1315516426.294:9): avc:  denied  { associate } for  pid=432 comm="udevd" name="cdrom.udev-tmp" scontext=system_u:object_r:default_t:s0 tcontext=system_u:object_r:device_t:s0 tclass=filesystem
>[   11.879383] udevd[432]: symlink 'sr0' '/dev/cdrom.udev-tmp' failed: Permission denied
>[   11.885753] type=1400 audit(1315516426.391:10): avc:  denied  { associate } for  pid=432 comm="udevd" name="dvd" scontext=system_u:object_r:default_t:s0 tcontext=system_u:object_r:device_t:s0 tclass=filesystem
>[   11.988330] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
>[   12.019983] type=1400 audit(1315516426.525:11): avc:  denied  { associate } for  pid=432 comm="udevd" name="dvd.udev-tmp" scontext=system_u:object_r:default_t:s0 tcontext=system_u:object_r:device_t:s0 tclass=filesystem
>[   12.126652] udevd[432]: symlink 'sr0' '/dev/dvd.udev-tmp' failed: Permission denied
>Starting File System Check on /dev/disk/by-uuid/71990d5a-106e-48dc-a2fe-17eb65f554a4...
>[   12.338704] type=1400 audit(1315516426.844:12): avc:  denied  { associate } for  pid=439 comm="udevd" name="root" dev=devtmpfs ino=8704 scontext=system_u:object_r:default_t:s0 tcontext=system_u:object_r:device_t:s0 tclass=filesystem
>Starting /dev/mapper/vg_testhost-lv_swap...
>Started File System Check on /dev/disk/by-uuid/71990d5a-106e-48dc-a2fe-17eb65f554a4.
>Starting /boot...
>[   12.481923] udevd[439]: setfilecon /dev/root failed: Permission denied
>[   12.514254] Adding 2031612k swap on /dev/mapper/vg_testhost-lv_swap.  Priority:0 extents:1 across:2031612k 
>Started /dev/mapper/vg_testhost-lv_swap.
>[   12.613405] EXT4-fs (vda2): mounted filesystem with ordered data mode. Opts: (null)
>Started /boot.
>[   13.449036] ALSA sound/pci/hda/hda_intel.c:748 azx_get_response timeout, switching to polling mode: last cmd=0x000f0000
>[   14.451036] ALSA sound/pci/hda/hda_intel.c:756 No response from codec, disabling MSI: last cmd=0x000f0000
>[   15.455033] ALSA sound/pci/hda/hda_intel.c:787 hda_intel: azx_get_response timeout, switching to single_cmd mode: last cmd=0x000f0000
>Started udev Wait for Complete Device Initialization.
>Starting Wait for storage scan...
>Started Show Plymouth Boot Screen.
>Started Wait for storage scan.
>Starting Initialize storage subsystems (RAID, LVM, etc.)...
>[   15.768550] fedora-storage-init[637]: /etc/init.d/functions: line 58: /dev/stderr: No such device or address
>[   15.945219] fedora-storage-init[637]: Setting up Logical[  OK  ]Management: 
>Started Initialize storage subsystems (RAID, LVM, etc.).
>Starting Initialize storage subsystems (RAID, LVM, etc.)...
>[   15.999213] fedora-storage-init[645]: /etc/init.d/functions: line 58: /dev/stderr: No such device or address
>[   16.121511] fedora-storage-init[645]: Setting up Logical[  OK  ]Management: 
>Started Initialize storage subsystems (RAID, LVM, etc.).
>Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
>Started Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling.
>Started Reconfigure the system on administrator request.
>Started Relabel all filesystems, if necessary.
>Started Mark the need to relabel after reboot.
>Starting Tell Plymouth To Write Out Runtime Data...
>Starting Load Random Seed...
>Starting Recreate Volatile Files and Directories...
>Started Tell Plymouth To Write Out Runtime Data.
>Started Load Random Seed.
>[   16.378314] systemd-tmpfiles[658]: Successfully loaded SELinux database in 10ms 623us, size on heap is 466K.
>[   16.410993] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.431363] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.439096] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.442045] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_run_t:s0 for /run: Permission denied
>[   16.450146] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_run_t:s0 for /run: Permission denied
>[   16.453051] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_run_t:s0 for /run: Permission denied
>[   16.462122] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.465120] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.474114] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.477135] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.485140] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.488112] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.501147] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.504236] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.507073] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.509901] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.512726] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.521118] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.524114] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.533594] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_run_t:s0 for /run: Permission denied
>[   16.545098] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>[   16.548226] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_run_t:s0 for /run: Permission denied
>[   16.557142] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_run_t:s0 for /run: Permission denied
>[   16.566748] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_run_t:s0 for /run: Permission denied
>[   16.575634] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_run_t:s0 for /run: Permission denied
>Started Recreate Volatile Files and Directories.
>Starting Restore Sound Card State...
>[   16.597086] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:var_t:s0 for /var: Permission denied
>Starting Console System Startup Logging...
>[   16.617145] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:tmp_t:s0 for /tmp: Permission denied
>[   16.642673] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:tmp_t:s0 for /tmp: Permission denied
>[   16.672093] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:tmp_t:s0 for /tmp: Permission denied
>[   16.705063] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:tmp_t:s0 for /tmp: Permission denied
>Starting IPv4 firewall with iptables...
>Starting IPv6 firewall with ip6tables...[   16.746202] systemd-tmpfiles[658]: Failed to set security context system_u:object_r:tmp_t:s0 for /tmp: Permission denied
>
>Starting Software RAID Monitor Takeover...
>Starting Machine Check Exception Logging Daemon...
>Starting NFSv4 ID-name mapping daemon...
>Starting Avahi mDNS/DNS-SD Stack...
>Starting ABRT kernel log watcher...
>Started ABRT kernel log watcher.
>Starting ABRT Automated Bug Reporting Tool...
>Starting Network Manager...
>Starting Install ABRT coredump hook...
>Starting irqbalance daemon...
>Starting System Logging Service...
>Starting Command Scheduler...
>Started Command Scheduler.
>Starting Job spooling tools...
>Started Job spooling tools.
>Starting ACPI Event Daemon...
>Starting Device-Mapper Multipath Device Controller...
>Starting CUPS Printing Service...
>Started CUPS Printing Service.
>Started Software RAID monitoring and management.
>Starting SSH server keys generation....
>Starting Login Service...
>Starting firstboot configuration program (text mode)...
>Starting /etc/rc.local Compatibility...
>Starting LSB: Mount and unmount network filesystems....
>Starting D-Bus System Message Bus...
>Started Restore Sound Card State.
>Started Console System Startup Logging.
>Started Software RAID Monitor Takeover.
>Started Machine Check Exception Logging Daemon.
>[   17.479582] systemd[1]: rc-local.service: control process exited, code=exited status=203
>Starting /etc/rc.local Compatibility failed, see 'systemctl status rc-local.service' for details.
>[   17.504729] systemd[1]: Unit rc-local.service entered failed state.
>Starting Wait for Plymouth Boot Screen to Quit...
>Stopping Syslog Kernel Log Buffer Bridge...
>Stopped Syslog Kernel Log Buffer Bridge.
>[   18.521446] ip6_tables: (C) 2000-2006 Netfilter Core Team
>[   18.563636] nf_conntrack version 0.5.0 (7873 buckets, 31492 max)
>[   20.579670] systemd[1]: nfs-lock.service: control process exited, code=exited status=1
>[   20.594264] systemd[1]: Unit nfs-lock.service entered failed state.
>

Actions: View

Attachments on bug 737118: 522366