* rps and pvdrivers
@ 2010-09-03 19:49 Chetan Loke
2010-09-03 21:05 ` [Pv-drivers] " Bhavesh Davda
0 siblings, 1 reply; 10+ messages in thread
From: Chetan Loke @ 2010-09-03 19:49 UTC (permalink / raw)
To: netdev, pv-drivers, therbert
Hello,
I just tried kernel-2.6.35-0.57.rc6.git1.fc14.x86_64.rpm because
ksoftirqd was consuming 100% cpu under high packet load(on a 10G
vNIC). Now with the above combo the vNIC on the monitoring interface
virtually receives 'zero' interrupts.
Do I have to configure anything manually? Things work fine with 2.6.33
and I can capture 250K+ packets but I can't use it on a 10G NIC. I'm
going to try the stock 2.6.35.4 to see if I get the same results.
Is anyone else seeing similar issues?
Chetan Loke
^ permalink raw reply [flat|nested] 10+ messages in thread
* RE: [Pv-drivers] rps and pvdrivers
2010-09-03 19:49 rps and pvdrivers Chetan Loke
@ 2010-09-03 21:05 ` Bhavesh Davda
2010-09-03 22:39 ` Chetan Loke
0 siblings, 1 reply; 10+ messages in thread
From: Bhavesh Davda @ 2010-09-03 21:05 UTC (permalink / raw)
To: Chetan Loke, netdev, pv-drivers, therbert
Hi Chetan,
I presume you're talking about the vmxnet3 vNIC here? What version of the VMware hypervisor are you observing this with? Could you share the vmware.log (and potentially the vmkernel log) file from the VM where you're seeing this?
Thanks.
- Bhavesh
Bhavesh P. Davda
> -----Original Message-----
> From: pv-drivers-bounces@vmware.com [mailto:pv-drivers-
> bounces@vmware.com] On Behalf Of Chetan Loke
> Sent: Friday, September 03, 2010 12:49 PM
> To: netdev@vger.kernel.org; pv-drivers@vmware.com; therbert@google.com
> Subject: [Pv-drivers] rps and pvdrivers
>
> Hello,
>
> I just tried kernel-2.6.35-0.57.rc6.git1.fc14.x86_64.rpm because
> ksoftirqd was consuming 100% cpu under high packet load(on a 10G
> vNIC). Now with the above combo the vNIC on the monitoring interface
> virtually receives 'zero' interrupts.
> Do I have to configure anything manually? Things work fine with 2.6.33
> and I can capture 250K+ packets but I can't use it on a 10G NIC. I'm
> going to try the stock 2.6.35.4 to see if I get the same results.
>
> Is anyone else seeing similar issues?
>
>
> Chetan Loke
> _______________________________________________
> Pv-drivers mailing list
> Pv-drivers@vmware.com
> http://mailman2.vmware.com/mailman/listinfo/pv-drivers
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: [Pv-drivers] rps and pvdrivers
2010-09-03 21:05 ` [Pv-drivers] " Bhavesh Davda
@ 2010-09-03 22:39 ` Chetan Loke
2010-09-04 6:51 ` Eric Dumazet
0 siblings, 1 reply; 10+ messages in thread
From: Chetan Loke @ 2010-09-03 22:39 UTC (permalink / raw)
To: Bhavesh Davda; +Cc: netdev, pv-drivers, therbert
[-- Attachment #1: Type: text/plain, Size: 469 bytes --]
Hi Bhavesh,
On Fri, Sep 3, 2010 at 5:05 PM, Bhavesh Davda <bhavesh@vmware.com> wrote:
>
> I presume you're talking about the vmxnet3 vNIC here? What version of the VMware hypervisor are you observing this with? Could you share the vmware.log (and potentially the vmkernel log) file from the VM where you're seeing this?
>
Yes its vmxnet3 on esx 4.1. I've attached the vm-log file. Its easy to
reproduce - A simple 'rcvfrom' loop on a promiscuous interface.
Chetan
[-- Attachment #2: vm-var_log_messages.txt --]
[-- Type: text/plain, Size: 97205 bytes --]
Sep 3 01:38:55 (none) init: Disconnected from system bus
Sep 3 01:38:55 (none) rpcbind: rpcbind terminating on signal. Restart with "rpcbind -w"
Sep 3 01:38:56 (none) auditd[941]: The audit daemon is exiting.
Sep 3 01:38:56 (none) kernel: type=1305 audit(1283492336.118:26): audit_pid=0 old=941 auid=4294967295 ses=4294967295 subj=system_u:system_r:auditd_t:s0 res=1
Sep 3 01:38:56 (none) kernel: Kernel logging (proc) stopped.
Sep 3 01:38:56 (none) rsyslogd: [origin software="rsyslogd" swVersion="4.4.2" x-pid="957" x-info="http://www.rsyslog.com"] exiting on signal 15.
Sep 3 01:39:18 (none) kernel: imklog 4.4.2, log source = /proc/kmsg started.
Sep 3 01:39:18 (none) rsyslogd: [origin software="rsyslogd" swVersion="4.4.2" x-pid="908" x-info="http://www.rsyslog.com"] (re)start
Sep 3 01:39:18 (none) kernel: Initializing cgroup subsys cpuset
Sep 3 01:39:18 (none) kernel: Initializing cgroup subsys cpu
Sep 3 01:39:18 (none) kernel: Linux version 2.6.35-0.57.rc6.git1.fc14.x86_64 (mockbuild@x86-17.phx2.fedoraproject.org) (gcc version 4.5.0 20100716 (Red Hat 4.5.0-3) (GCC) ) #1 SMP Mon Jul 26 22:43:02 UTC 2010
Sep 3 01:39:18 (none) kernel: Command line: ro root=UUID=2a5dd2a0-cf59-4e25-9e95-23ac45b5c81f rd_NO_LUKS rd_NO_LVM rd_NO_MD rd_NO_DM LANG=en_US.UTF-8 SYSFONT=latarcyrheb-sun16 KEYTABLE=us rhgb quiet
Sep 3 01:39:18 (none) kernel: BIOS-provided physical RAM map:
Sep 3 01:39:18 (none) kernel: BIOS-e820: 0000000000000000 - 000000000009f800 (usable)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 00000000000dc000 - 00000000000e4000 (reserved)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 00000000000e8000 - 0000000000100000 (reserved)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 0000000000100000 - 00000000bfef0000 (usable)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 00000000bfef0000 - 00000000bfeff000 (ACPI data)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 00000000bfeff000 - 00000000bff00000 (ACPI NVS)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 00000000bff00000 - 00000000c0000000 (usable)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 00000000fec00000 - 00000000fec10000 (reserved)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 00000000fffe0000 - 0000000100000000 (reserved)
Sep 3 01:39:18 (none) kernel: BIOS-e820: 0000000100000000 - 0000000140000000 (usable)
Sep 3 01:39:18 (none) kernel: NX (Execute Disable) protection: active
Sep 3 01:39:18 (none) kernel: DMI present.
Sep 3 01:39:18 (none) kernel: Phoenix BIOS detected: BIOS may corrupt low RAM, working around it.
Sep 3 01:39:18 (none) kernel: Hypervisor detected: VMware
Sep 3 01:39:18 (none) kernel: No AGP bridge found
Sep 3 01:39:18 (none) kernel: last_pfn = 0x140000 max_arch_pfn = 0x400000000
Sep 3 01:39:18 (none) kernel: x86 PAT enabled: cpu 0, old 0x0, new 0x7010600070106
Sep 3 01:39:18 (none) kernel: total RAM covered: 4096M
Sep 3 01:39:18 (none) kernel: Found optimal setting for mtrr clean up
Sep 3 01:39:18 (none) kernel: gran_size: 64K chunk_size: 64K num_reg: 3 lose cover RAM: 0G
Sep 3 01:39:18 (none) kernel: x86 PAT enabled: cpu 0, old 0x0, new 0x7010600070106
Sep 3 01:39:18 (none) kernel: last_pfn = 0xc0000 max_arch_pfn = 0x400000000
Sep 3 01:39:18 (none) kernel: found SMP MP-table at [ffff8800000f69b0] f69b0
Sep 3 01:39:18 (none) kernel: init_memory_mapping: 0000000000000000-00000000c0000000
Sep 3 01:39:18 (none) kernel: init_memory_mapping: 0000000100000000-0000000140000000
Sep 3 01:39:18 (none) kernel: RAMDISK: 3747f000 - 37ff0000
Sep 3 01:39:18 (none) kernel: ACPI: RSDP 00000000000f6940 00024 (v02 PTLTD )
Sep 3 01:39:18 (none) kernel: ACPI: XSDT 00000000bfef07f3 0004C (v01 INTEL 440BX 06040000 VMW 01324272)
Sep 3 01:39:18 (none) kernel: ACPI: FACP 00000000bfefee98 000F4 (v04 INTEL 440BX 06040000 PTL 000F4240)
Sep 3 01:39:18 (none) kernel: ACPI: DSDT 00000000bfef09e1 0E4B7 (v01 PTLTD Custom 06040000 MSFT 03000001)
Sep 3 01:39:18 (none) kernel: ACPI: FACS 00000000bfefffc0 00040
Sep 3 01:39:18 (none) kernel: ACPI: BOOT 00000000bfef09b9 00028 (v01 PTLTD $SBFTBL$ 06040000 LTP 00000001)
Sep 3 01:39:18 (none) kernel: ACPI: APIC 00000000bfef095b 0005E (v01 PTLTD ? APIC 06040000 LTP 00000000)
Sep 3 01:39:18 (none) kernel: ACPI: MCFG 00000000bfef091f 0003C (v01 PTLTD $PCITBL$ 06040000 LTP 00000001)
Sep 3 01:39:18 (none) kernel: ACPI: SRAT 00000000bfef0877 000A8 (v02 VMWARE MEMPLUG 06040000 VMW 00000001)
Sep 3 01:39:18 (none) kernel: SRAT: Node 0 PXM 0 0-a0000
Sep 3 01:39:18 (none) kernel: SRAT: Node 0 PXM 0 100000-c0000000
Sep 3 01:39:18 (none) kernel: SRAT: Node 0 PXM 0 100000000-140000000
Sep 3 01:39:18 (none) kernel: No NUMA configuration found
Sep 3 01:39:18 (none) kernel: Faking a node at 0000000000000000-0000000140000000
Sep 3 01:39:18 (none) kernel: Initmem setup node 0 0000000000000000-0000000140000000
Sep 3 01:39:18 (none) kernel: NODE_DATA [0000000100000000 - 0000000100014fff]
Sep 3 01:39:18 (none) kernel: bootmap [0000000100015000 - 000000010003cfff] pages 28
Sep 3 01:39:18 (none) kernel: (14/32 early reservations) ==> bootmem [0000000000 - 0140000000]
Sep 3 01:39:18 (none) kernel: #0 [0001000000 - 0002a388c0] TEXT DATA BSS ==> [0001000000 - 0002a388c0]
Sep 3 01:39:18 (none) kernel: #1 [003747f000 - 0037ff0000] RAMDISK ==> [003747f000 - 0037ff0000]
Sep 3 01:39:18 (none) kernel: #2 [0002a39000 - 0002a391e8] BRK ==> [0002a39000 - 0002a391e8]
Sep 3 01:39:18 (none) kernel: #3 [00000f69c0 - 0000100000] BIOS reserved ==> [00000f69c0 - 0000100000]
Sep 3 01:39:18 (none) kernel: #4 [00000f69b0 - 00000f69c0] MP-table mpf ==> [00000f69b0 - 00000f69c0]
Sep 3 01:39:18 (none) kernel: #5 [000009f800 - 000009fd70] BIOS reserved ==> [000009f800 - 000009fd70]
Sep 3 01:39:18 (none) kernel: #6 [000009febc - 00000f69b0] BIOS reserved ==> [000009febc - 00000f69b0]
Sep 3 01:39:18 (none) kernel: #7 [000009fd70 - 000009febc] MP-table mpc ==> [000009fd70 - 000009febc]
Sep 3 01:39:18 (none) kernel: #8 [0000010000 - 0000012000] TRAMPOLINE ==> [0000010000 - 0000012000]
Sep 3 01:39:18 (none) kernel: #9 [0000012000 - 0000016000] ACPI WAKEUP ==> [0000012000 - 0000016000]
Sep 3 01:39:18 (none) kernel: #10 [0000016000 - 0000018000] PGTABLE ==> [0000016000 - 0000018000]
Sep 3 01:39:18 (none) kernel: #11 [0000018000 - 0000019000] PGTABLE ==> [0000018000 - 0000019000]
Sep 3 01:39:18 (none) kernel: #12 [0100000000 - 0100015000] NODE_DATA ==> [0100000000 - 0100015000]
Sep 3 01:39:18 (none) kernel: #13 [0100015000 - 010003d000] BOOTMAP ==> [0100015000 - 010003d000]
Sep 3 01:39:18 (none) kernel: Zone PFN ranges:
Sep 3 01:39:18 (none) kernel: DMA 0x00000010 -> 0x00001000
Sep 3 01:39:18 (none) kernel: DMA32 0x00001000 -> 0x00100000
Sep 3 01:39:18 (none) kernel: Normal 0x00100000 -> 0x00140000
Sep 3 01:39:18 (none) kernel: Movable zone start PFN for each node
Sep 3 01:39:18 (none) kernel: early_node_map[4] active PFN ranges
Sep 3 01:39:18 (none) kernel: 0: 0x00000010 -> 0x0000009f
Sep 3 01:39:18 (none) kernel: 0: 0x00000100 -> 0x000bfef0
Sep 3 01:39:18 (none) kernel: 0: 0x000bff00 -> 0x000c0000
Sep 3 01:39:18 (none) kernel: 0: 0x00100000 -> 0x00140000
Sep 3 01:39:18 (none) kernel: ACPI: PM-Timer IO Port: 0x1008
Sep 3 01:39:18 (none) kernel: ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
Sep 3 01:39:18 (none) kernel: ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
Sep 3 01:39:18 (none) kernel: ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
Sep 3 01:39:18 (none) kernel: ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
Sep 3 01:39:18 (none) kernel: ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
Sep 3 01:39:18 (none) kernel: IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23
Sep 3 01:39:18 (none) kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 high edge)
Sep 3 01:39:18 (none) kernel: Using ACPI (MADT) for SMP configuration information
Sep 3 01:39:18 (none) kernel: SMP: Allowing 2 CPUs, 0 hotplug CPUs
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 000000000009f000 - 00000000000a0000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000000a0000 - 00000000000dc000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000000dc000 - 00000000000e4000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000000e4000 - 00000000000e8000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000000e8000 - 0000000000100000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000bfef0000 - 00000000bfeff000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000bfeff000 - 00000000bff00000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000c0000000 - 00000000e0000000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000e0000000 - 00000000f0000000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000f0000000 - 00000000fec00000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000fec00000 - 00000000fec10000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000fec10000 - 00000000fee00000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000fee00000 - 00000000fee01000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000fee01000 - 00000000fffe0000
Sep 3 01:39:18 (none) kernel: PM: Registered nosave memory: 00000000fffe0000 - 0000000100000000
Sep 3 01:39:18 (none) kernel: Allocating PCI resources starting at c0000000 (gap: c0000000:20000000)
Sep 3 01:39:18 (none) kernel: Booting paravirtualized kernel on bare hardware
Sep 3 01:39:18 (none) kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:2 nr_node_ids:1
Sep 3 01:39:18 (none) kernel: PERCPU: Embedded 478 pages/cpu @ffff880002c00000 s1927360 r8192 d22336 u2097152
Sep 3 01:39:18 (none) kernel: pcpu-alloc: s1927360 r8192 d22336 u2097152 alloc=1*2097152
Sep 3 01:39:18 (none) kernel: pcpu-alloc: [0] 0 [0] 1
Sep 3 01:39:18 (none) kernel: Built 1 zonelists in Node order, mobility grouping on. Total pages: 1030423
Sep 3 01:39:18 (none) kernel: Policy zone: Normal
Sep 3 01:39:18 (none) kernel: Kernel command line: ro root=UUID=2a5dd2a0-cf59-4e25-9e95-23ac45b5c81f rd_NO_LUKS rd_NO_LVM rd_NO_MD rd_NO_DM LANG=en_US.UTF-8 SYSFONT=latarcyrheb-sun16 KEYTABLE=us rhgb quiet
Sep 3 01:39:18 (none) kernel: PID hash table entries: 4096 (order: 3, 32768 bytes)
Sep 3 01:39:18 (none) kernel: Checking aperture...
Sep 3 01:39:18 (none) kernel: No AGP bridge found
Sep 3 01:39:18 (none) kernel: Memory: 4026944k/5242880k available (4740k kernel code, 1049092k absent, 166844k reserved, 7161k data, 2756k init)
Sep 3 01:39:18 (none) kernel: SLUB: Genslabs=14, HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Sep 3 01:39:18 (none) kernel: Hierarchical RCU implementation.
Sep 3 01:39:18 (none) kernel: RCU dyntick-idle grace-period acceleration is enabled.
Sep 3 01:39:18 (none) kernel: RCU lockdep checking is enabled.
Sep 3 01:39:18 (none) kernel: RCU-based detection of stalled CPUs is disabled.
Sep 3 01:39:18 (none) kernel: Verbose stalled-CPUs detection is disabled.
Sep 3 01:39:18 (none) kernel: NR_IRQS:33024 nr_irqs:512
Sep 3 01:39:18 (none) kernel: Extended CMOS year: 2000
Sep 3 01:39:18 (none) kernel: Console: colour VGA+ 80x25
Sep 3 01:39:18 (none) kernel: console [tty0] enabled
Sep 3 01:39:18 (none) kernel: Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
Sep 3 01:39:18 (none) kernel: ... MAX_LOCKDEP_SUBCLASSES: 8
Sep 3 01:39:18 (none) kernel: ... MAX_LOCK_DEPTH: 48
Sep 3 01:39:18 (none) kernel: ... MAX_LOCKDEP_KEYS: 8191
Sep 3 01:39:18 (none) kernel: ... CLASSHASH_SIZE: 4096
Sep 3 01:39:18 (none) kernel: ... MAX_LOCKDEP_ENTRIES: 16384
Sep 3 01:39:18 (none) kernel: ... MAX_LOCKDEP_CHAINS: 32768
Sep 3 01:39:18 (none) kernel: ... CHAINHASH_SIZE: 16384
Sep 3 01:39:18 (none) kernel: memory used by lock dependency info: 6367 kB
Sep 3 01:39:18 (none) kernel: per task-struct memory footprint: 2688 bytes
Sep 3 01:39:18 (none) kernel: allocated 41943040 bytes of page_cgroup
Sep 3 01:39:18 (none) kernel: please try 'cgroup_disable=memory' option if you don't want memory cgroups
Sep 3 01:39:18 (none) kernel: TSC freq read from hypervisor : 2526.999 MHz
Sep 3 01:39:18 (none) kernel: Detected 2526.999 MHz processor.
Sep 3 01:39:18 (none) kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5053.99 BogoMIPS (lpj=2526999)
Sep 3 01:39:18 (none) kernel: pid_max: default: 32768 minimum: 301
Sep 3 01:39:18 (none) kernel: Security Framework initialized
Sep 3 01:39:18 (none) kernel: SELinux: Initializing.
Sep 3 01:39:18 (none) kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
Sep 3 01:39:18 (none) kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
Sep 3 01:39:18 (none) kernel: Mount-cache hash table entries: 256
Sep 3 01:39:18 (none) kernel: Initializing cgroup subsys ns
Sep 3 01:39:18 (none) kernel: Initializing cgroup subsys cpuacct
Sep 3 01:39:18 (none) kernel: Initializing cgroup subsys memory
Sep 3 01:39:18 (none) kernel: Initializing cgroup subsys devices
Sep 3 01:39:18 (none) kernel: Initializing cgroup subsys freezer
Sep 3 01:39:18 (none) kernel: Initializing cgroup subsys net_cls
Sep 3 01:39:18 (none) kernel: Initializing cgroup subsys blkio
Sep 3 01:39:18 (none) kernel: CPU: Physical Processor ID: 0
Sep 3 01:39:18 (none) kernel: mce: CPU supports 0 MCE banks
Sep 3 01:39:18 (none) kernel: Performance Events: Nehalem events, Intel PMU driver.
Sep 3 01:39:18 (none) kernel: ... version: 3
Sep 3 01:39:18 (none) kernel: ... bit width: 48
Sep 3 01:39:18 (none) kernel: ... generic registers: 4
Sep 3 01:39:18 (none) kernel: ... value mask: 0000ffffffffffff
Sep 3 01:39:18 (none) kernel: ... max period: 000000007fffffff
Sep 3 01:39:18 (none) kernel: ... fixed-purpose events: 3
Sep 3 01:39:18 (none) kernel: ... event mask: 000000070000000f
Sep 3 01:39:18 (none) kernel: ACPI: Core revision 20100428
Sep 3 01:39:18 (none) kernel: ftrace: converting mcount calls to 0f 1f 44 00 00
Sep 3 01:39:18 (none) kernel: ftrace: allocating 23954 entries in 94 pages
Sep 3 01:39:18 (none) kernel: Setting APIC routing to flat
Sep 3 01:39:18 (none) kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Sep 3 01:39:18 (none) kernel: CPU0: Intel(R) Xeon(R) CPU E5540 @ 2.53GHz stepping 05
Sep 3 01:39:18 (none) kernel: lockdep: fixing up alternatives.
Sep 3 01:39:18 (none) kernel:
Sep 3 01:39:18 (none) kernel: ===================================================
Sep 3 01:39:18 (none) kernel: [ INFO: suspicious rcu_dereference_check() usage. ]
Sep 3 01:39:18 (none) kernel: ---------------------------------------------------
Sep 3 01:39:18 (none) kernel: kernel/sched.c:616 invoked rcu_dereference_check() without protection!
Sep 3 01:39:18 (none) kernel:
Sep 3 01:39:18 (none) kernel: other info that might help us debug this:
Sep 3 01:39:18 (none) kernel:
Sep 3 01:39:18 (none) kernel:
Sep 3 01:39:18 (none) kernel: rcu_scheduler_active = 1, debug_locks = 0
Sep 3 01:39:18 (none) kernel: 3 locks held by swapper/1:
Sep 3 01:39:18 (none) kernel: #0: (cpu_add_remove_lock){+.+.+.}, at: [<ffffffff81052b57>] cpu_maps_update_begin+0x17/0x19
Sep 3 01:39:18 (none) kernel: #1: (cpu_hotplug.lock){+.+.+.}, at: [<ffffffff81052a6a>] cpu_hotplug_begin+0x2c/0x53
Sep 3 01:39:18 (none) kernel: #2: (&rq->lock){-.....}, at: [<ffffffff814919e9>] init_idle+0x30/0x131
Sep 3 01:39:18 (none) kernel:
Sep 3 01:39:18 (none) kernel: stack backtrace:
Sep 3 01:39:18 (none) kernel: Pid: 1, comm: swapper Not tainted 2.6.35-0.57.rc6.git1.fc14.x86_64 #1
Sep 3 01:39:18 (none) kernel: Call Trace:
Sep 3 01:39:18 (none) kernel: [<ffffffff8107bc56>] lockdep_rcu_dereference+0xaa/0xb3
Sep 3 01:39:18 (none) kernel: [<ffffffff8103fceb>] task_group+0x80/0x8f
Sep 3 01:39:18 (none) kernel: [<ffffffff8103fd11>] set_task_rq+0x17/0x73
Sep 3 01:39:18 (none) kernel: [<ffffffff81491aa3>] init_idle+0xea/0x131
Sep 3 01:39:18 (none) kernel: [<ffffffff81491e73>] fork_idle+0x92/0xa3
Sep 3 01:39:18 (none) kernel: [<ffffffff8107e73c>] ? mark_held_locks+0x50/0x72
Sep 3 01:39:18 (none) kernel: [<ffffffff8148f919>] do_fork_idle+0x1c/0x2d
Sep 3 01:39:18 (none) kernel: [<ffffffff8148fa61>] do_boot_cpu+0x137/0x9ac
Sep 3 01:39:18 (none) kernel: [<ffffffff8148f8fd>] ? do_fork_idle+0x0/0x2d
Sep 3 01:39:18 (none) kernel: [<ffffffff81490afa>] native_cpu_up+0x100/0x1c2
Sep 3 01:39:18 (none) kernel: [<ffffffff81491f4c>] _cpu_up+0x9d/0xf9
Sep 3 01:39:18 (none) kernel: [<ffffffff8149207b>] cpu_up+0xd3/0xe5
Sep 3 01:39:18 (none) kernel: [<ffffffff81d78d86>] kernel_init+0x105/0x2c9
Sep 3 01:39:18 (none) kernel: [<ffffffff8100aae4>] kernel_thread_helper+0x4/0x10
Sep 3 01:39:18 (none) kernel: [<ffffffff81499250>] ? restore_args+0x0/0x30
Sep 3 01:39:18 (none) kernel: [<ffffffff81d78c81>] ? kernel_init+0x0/0x2c9
Sep 3 01:39:18 (none) kernel: [<ffffffff8100aae0>] ? kernel_thread_helper+0x0/0x10
Sep 3 01:39:18 (none) kernel: Booting Node 0, Processors #1 Ok.
Sep 3 01:39:18 (none) kernel: mce: CPU supports 0 MCE banks
Sep 3 01:39:18 (none) kernel: Skipped synchronization checks as TSC is reliable.
Sep 3 01:39:18 (none) kernel: Brought up 2 CPUs
Sep 3 01:39:18 (none) kernel: Total of 2 processors activated (10106.81 BogoMIPS).
Sep 3 01:39:18 (none) kernel: x86 PAT enabled: cpu 1, old 0x0, new 0x7010600070106
Sep 3 01:39:18 (none) kernel: devtmpfs: initialized
Sep 3 01:39:18 (none) kernel: atomic64 test passed for x86-64 platform with CX8 and with SSE
Sep 3 01:39:18 (none) kernel: Time: 5:39:05 Date: 09/03/10
Sep 3 01:39:18 (none) kernel: NET: Registered protocol family 16
Sep 3 01:39:18 (none) kernel: ACPI: bus type pci registered
Sep 3 01:39:18 (none) kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
Sep 3 01:39:18 (none) kernel: PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in E820
Sep 3 01:39:18 (none) kernel: PCI: Using configuration type 1 for base access
Sep 3 01:39:18 (none) kernel: bio: create slab <bio-0> at 0
Sep 3 01:39:18 (none) kernel: ACPI: BIOS _OSI(Linux) query ignored
Sep 3 01:39:18 (none) kernel: ACPI: Interpreter enabled
Sep 3 01:39:18 (none) kernel: ACPI: (supports S0 S1 S4 S5)
Sep 3 01:39:18 (none) kernel: ACPI: Using IOAPIC for interrupt routing
Sep 3 01:39:18 (none) kernel: ACPI: No dock devices found.
Sep 3 01:39:18 (none) kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Sep 3 01:39:18 (none) kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Sep 3 01:39:18 (none) kernel: pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff]
Sep 3 01:39:18 (none) kernel: pci_root PNP0A03:00: host bridge window [mem 0x000d0000-0x000d3fff]
Sep 3 01:39:18 (none) kernel: pci_root PNP0A03:00: host bridge window [mem 0x000d4000-0x000d7fff]
Sep 3 01:39:18 (none) kernel: pci_root PNP0A03:00: host bridge window [mem 0x000d8000-0x000dbfff]
Sep 3 01:39:18 (none) kernel: pci_root PNP0A03:00: host bridge window [mem 0x000e4000-0x000e7fff]
Sep 3 01:39:18 (none) kernel: pci_root PNP0A03:00: host bridge window [mem 0xc0000000-0xfebfffff]
Sep 3 01:39:18 (none) kernel: pci_root PNP0A03:00: host bridge window [io 0x0000-0x0cf7]
Sep 3 01:39:18 (none) kernel: pci_root PNP0A03:00: host bridge window [io 0x0d00-0xfeff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:07.3: quirk: [io 0x1000-0x103f] claimed by PIIX4 ACPI
Sep 3 01:39:18 (none) kernel: pci 0000:00:07.3: quirk: [io 0x1040-0x104f] claimed by PIIX4 SMB
Sep 3 01:39:18 (none) kernel: pci 0000:00:01.0: PCI bridge to [bus 01-01]
Sep 3 01:39:18 (none) kernel: pci 0000:00:11.0: PCI bridge to [bus 02-02] (subtractive decode)
Sep 3 01:39:18 (none) kernel: pci 0000:03:00.0: disabling ASPM on pre-1.1 PCIe device. You can enable it with 'pcie_aspm=force'
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.0: PCI bridge to [bus 03-03]
Sep 3 01:39:18 (none) kernel: pci 0000:04:00.0: disabling ASPM on pre-1.1 PCIe device. You can enable it with 'pcie_aspm=force'
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.1: PCI bridge to [bus 04-04]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.2: PCI bridge to [bus 05-05]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.3: PCI bridge to [bus 06-06]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.4: PCI bridge to [bus 07-07]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.5: PCI bridge to [bus 08-08]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.6: PCI bridge to [bus 09-09]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.7: PCI bridge to [bus 0a-0a]
Sep 3 01:39:18 (none) kernel: pci 0000:0b:00.0: disabling ASPM on pre-1.1 PCIe device. You can enable it with 'pcie_aspm=force'
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.0: PCI bridge to [bus 0b-0b]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.1: PCI bridge to [bus 0c-0c]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.2: PCI bridge to [bus 0d-0d]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.3: PCI bridge to [bus 0e-0e]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.4: PCI bridge to [bus 0f-0f]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.5: PCI bridge to [bus 10-10]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.6: PCI bridge to [bus 11-11]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.7: PCI bridge to [bus 12-12]
Sep 3 01:39:18 (none) kernel: pci 0000:13:00.0: disabling ASPM on pre-1.1 PCIe device. You can enable it with 'pcie_aspm=force'
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.0: PCI bridge to [bus 13-13]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.1: PCI bridge to [bus 14-14]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.2: PCI bridge to [bus 15-15]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.3: PCI bridge to [bus 16-16]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.4: PCI bridge to [bus 17-17]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.5: PCI bridge to [bus 18-18]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.6: PCI bridge to [bus 19-19]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.7: PCI bridge to [bus 1a-1a]
Sep 3 01:39:18 (none) kernel: pci 0000:1b:00.0: disabling ASPM on pre-1.1 PCIe device. You can enable it with 'pcie_aspm=force'
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.0: PCI bridge to [bus 1b-1b]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.1: PCI bridge to [bus 1c-1c]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.2: PCI bridge to [bus 1d-1d]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.3: PCI bridge to [bus 1e-1e]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.4: PCI bridge to [bus 1f-1f]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.5: PCI bridge to [bus 20-20]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.6: PCI bridge to [bus 21-21]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.7: PCI bridge to [bus 22-22]
Sep 3 01:39:18 (none) kernel: ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 *9 10 11 14 15)
Sep 3 01:39:18 (none) kernel: ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 *5 6 7 9 10 11 14 15)
Sep 3 01:39:18 (none) kernel: ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 9 10 *11 14 15)
Sep 3 01:39:18 (none) kernel: ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 9 *10 11 14 15)
Sep 3 01:39:18 (none) kernel: HEST: Table is not found!
Sep 3 01:39:18 (none) kernel: vgaarb: device added: PCI:0000:00:0f.0,decodes=io+mem,owns=io+mem,locks=none
Sep 3 01:39:18 (none) kernel: vgaarb: loaded
Sep 3 01:39:18 (none) kernel: SCSI subsystem initialized
Sep 3 01:39:18 (none) kernel: usbcore: registered new interface driver usbfs
Sep 3 01:39:18 (none) kernel: usbcore: registered new interface driver hub
Sep 3 01:39:18 (none) kernel: usbcore: registered new device driver usb
Sep 3 01:39:18 (none) kernel: PCI: Using ACPI for IRQ routing
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.2: no compatible bridge window for [io 0xf000-0xffff]
Sep 3 01:39:18 (none) kernel: NetLabel: Initializing
Sep 3 01:39:18 (none) kernel: NetLabel: domain hash size = 128
Sep 3 01:39:18 (none) kernel: NetLabel: protocols = UNLABELED CIPSOv4
Sep 3 01:39:18 (none) kernel: NetLabel: unlabeled traffic allowed by default
Sep 3 01:39:18 (none) kernel: Switching to clocksource tsc
Sep 3 01:39:18 (none) kernel: pnp: PnP ACPI init
Sep 3 01:39:18 (none) kernel: ACPI: bus type pnp registered
Sep 3 01:39:18 (none) kernel: pnp: PnP ACPI: found 13 devices
Sep 3 01:39:18 (none) kernel: ACPI: ACPI bus type pnp unregistered
Sep 3 01:39:18 (none) kernel: system 00:01: [io 0x1000-0x103f] has been reserved
Sep 3 01:39:18 (none) kernel: system 00:01: [io 0x1040-0x104f] has been reserved
Sep 3 01:39:18 (none) kernel: system 00:01: [io 0x0cf0-0x0cf1] has been reserved
Sep 3 01:39:18 (none) kernel: system 00:0c: [io 0x1060-0x107f] has been reserved
Sep 3 01:39:18 (none) kernel: system 00:0c: [mem 0xe0000000-0xefffffff] has been reserved
Sep 3 01:39:18 (none) kernel: system 00:0c: [mem 0xdbc00000-0xdbdfffff] has been reserved
Sep 3 01:39:18 (none) kernel: pci 0000:00:0f.0: BAR 6: assigned [mem 0xc0000000-0xc0007fff pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.3: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.4: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.5: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.6: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.7: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.3: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.4: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.5: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.6: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.7: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.3: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.4: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.5: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.6: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.7: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.2: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.3: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.4: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.5: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.6: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.7: BAR 13: can't assign io (size 0x1000)
Sep 3 01:39:18 (none) kernel: pci 0000:00:01.0: PCI bridge to [bus 01-01]
Sep 3 01:39:18 (none) kernel: pci 0000:00:01.0: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:01.0: bridge window [mem disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:01.0: bridge window [mem pref disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:02:00.0: BAR 6: assigned [mem 0xdc200000-0xdc20ffff pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:11.0: PCI bridge to [bus 02-02]
Sep 3 01:39:18 (none) kernel: pci 0000:00:11.0: bridge window [io 0x2000-0x3fff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:11.0: bridge window [mem 0xd8800000-0xd9bfffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:11.0: bridge window [mem 0xdc200000-0xdc7fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:03:00.0: BAR 6: assigned [mem 0xdc800000-0xdc803fff pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.0: PCI bridge to [bus 03-03]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.0: bridge window [io 0x4000-0x4fff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.0: bridge window [mem 0xd9c00000-0xd9cfffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.0: bridge window [mem 0xdc800000-0xdc8fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:04:00.0: BAR 6: assigned [mem 0xdcc00000-0xdcc0ffff pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.1: PCI bridge to [bus 04-04]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.1: bridge window [io 0x8000-0x8fff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.1: bridge window [mem 0xda000000-0xda0fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.1: bridge window [mem 0xdcc00000-0xdccfffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.2: PCI bridge to [bus 05-05]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.2: bridge window [io 0xc000-0xcfff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.2: bridge window [mem 0xda400000-0xda4fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.2: bridge window [mem 0xdd000000-0xdd0fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.3: PCI bridge to [bus 06-06]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.3: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.3: bridge window [mem 0xda800000-0xda8fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.3: bridge window [mem 0xdd400000-0xdd4fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.4: PCI bridge to [bus 07-07]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.4: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.4: bridge window [mem 0xdac00000-0xdacfffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.4: bridge window [mem 0xdd800000-0xdd8fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.5: PCI bridge to [bus 08-08]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.5: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.5: bridge window [mem 0xdb000000-0xdb0fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.5: bridge window [mem 0xddc00000-0xddcfffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.6: PCI bridge to [bus 09-09]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.6: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.6: bridge window [mem 0xdb400000-0xdb4fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.6: bridge window [mem 0xde000000-0xde0fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.7: PCI bridge to [bus 0a-0a]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.7: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.7: bridge window [mem 0xdb800000-0xdb8fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:15.7: bridge window [mem 0xde400000-0xde4fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:0b:00.0: BAR 6: assigned [mem 0xdc900000-0xdc90ffff pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.0: PCI bridge to [bus 0b-0b]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.0: bridge window [io 0x5000-0x5fff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.0: bridge window [mem 0xd9d00000-0xd9dfffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.0: bridge window [mem 0xdc900000-0xdc9fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.1: PCI bridge to [bus 0c-0c]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.1: bridge window [io 0x9000-0x9fff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.1: bridge window [mem 0xda100000-0xda1fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.1: bridge window [mem 0xdcd00000-0xdcdfffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.2: PCI bridge to [bus 0d-0d]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.2: bridge window [io 0xd000-0xdfff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.2: bridge window [mem 0xda500000-0xda5fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.2: bridge window [mem 0xdd100000-0xdd1fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.3: PCI bridge to [bus 0e-0e]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.3: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.3: bridge window [mem 0xda900000-0xda9fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.3: bridge window [mem 0xdd500000-0xdd5fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.4: PCI bridge to [bus 0f-0f]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.4: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.4: bridge window [mem 0xdad00000-0xdadfffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.4: bridge window [mem 0xdd900000-0xdd9fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.5: PCI bridge to [bus 10-10]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.5: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.5: bridge window [mem 0xdb100000-0xdb1fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.5: bridge window [mem 0xddd00000-0xdddfffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.6: PCI bridge to [bus 11-11]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.6: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.6: bridge window [mem 0xdb500000-0xdb5fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.6: bridge window [mem 0xde100000-0xde1fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.7: PCI bridge to [bus 12-12]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.7: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.7: bridge window [mem 0xdb900000-0xdb9fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:16.7: bridge window [mem 0xde500000-0xde5fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:13:00.0: BAR 6: assigned [mem 0xdca00000-0xdca0ffff pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.0: PCI bridge to [bus 13-13]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.0: bridge window [io 0x6000-0x6fff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.0: bridge window [mem 0xd9e00000-0xd9efffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.0: bridge window [mem 0xdca00000-0xdcafffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.1: PCI bridge to [bus 14-14]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.1: bridge window [io 0xa000-0xafff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.1: bridge window [mem 0xda200000-0xda2fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.1: bridge window [mem 0xdce00000-0xdcefffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.2: PCI bridge to [bus 15-15]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.2: bridge window [io 0xe000-0xefff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.2: bridge window [mem 0xda600000-0xda6fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.2: bridge window [mem 0xdd200000-0xdd2fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.3: PCI bridge to [bus 16-16]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.3: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.3: bridge window [mem 0xdaa00000-0xdaafffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.3: bridge window [mem 0xdd600000-0xdd6fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.4: PCI bridge to [bus 17-17]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.4: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.4: bridge window [mem 0xdae00000-0xdaefffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.4: bridge window [mem 0xdda00000-0xddafffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.5: PCI bridge to [bus 18-18]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.5: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.5: bridge window [mem 0xdb200000-0xdb2fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.5: bridge window [mem 0xdde00000-0xddefffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.6: PCI bridge to [bus 19-19]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.6: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.6: bridge window [mem 0xdb600000-0xdb6fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.6: bridge window [mem 0xde200000-0xde2fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.7: PCI bridge to [bus 1a-1a]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.7: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.7: bridge window [mem 0xdba00000-0xdbafffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:17.7: bridge window [mem 0xde600000-0xde6fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:1b:00.0: BAR 6: assigned [mem 0xdcb00000-0xdcb0ffff pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.0: PCI bridge to [bus 1b-1b]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.0: bridge window [io 0x7000-0x7fff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.0: bridge window [mem 0xd9f00000-0xd9ffffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.0: bridge window [mem 0xdcb00000-0xdcbfffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.1: PCI bridge to [bus 1c-1c]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.1: bridge window [io 0xb000-0xbfff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.1: bridge window [mem 0xda300000-0xda3fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.1: bridge window [mem 0xdcf00000-0xdcffffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.2: PCI bridge to [bus 1d-1d]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.2: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.2: bridge window [mem 0xda700000-0xda7fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.2: bridge window [mem 0xdd300000-0xdd3fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.3: PCI bridge to [bus 1e-1e]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.3: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.3: bridge window [mem 0xdab00000-0xdabfffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.3: bridge window [mem 0xdd700000-0xdd7fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.4: PCI bridge to [bus 1f-1f]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.4: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.4: bridge window [mem 0xdaf00000-0xdaffffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.4: bridge window [mem 0xddb00000-0xddbfffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.5: PCI bridge to [bus 20-20]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.5: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.5: bridge window [mem 0xdb300000-0xdb3fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.5: bridge window [mem 0xddf00000-0xddffffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.6: PCI bridge to [bus 21-21]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.6: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.6: bridge window [mem 0xdb700000-0xdb7fffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.6: bridge window [mem 0xde300000-0xde3fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.7: PCI bridge to [bus 22-22]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.7: bridge window [io disabled]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.7: bridge window [mem 0xdbb00000-0xdbbfffff]
Sep 3 01:39:18 (none) kernel: pci 0000:00:18.7: bridge window [mem 0xde700000-0xde7fffff 64bit pref]
Sep 3 01:39:18 (none) kernel: NET: Registered protocol family 2
Sep 3 01:39:18 (none) kernel: IP route cache hash table entries: 131072 (order: 8, 1048576 bytes)
Sep 3 01:39:18 (none) kernel: TCP established hash table entries: 524288 (order: 11, 8388608 bytes)
Sep 3 01:39:18 (none) kernel: TCP bind hash table entries: 65536 (order: 10, 4718592 bytes)
Sep 3 01:39:18 (none) kernel: TCP: Hash tables configured (established 524288 bind 65536)
Sep 3 01:39:18 (none) kernel: TCP reno registered
Sep 3 01:39:18 (none) kernel: UDP hash table entries: 2048 (order: 6, 327680 bytes)
Sep 3 01:39:18 (none) kernel: UDP-Lite hash table entries: 2048 (order: 6, 327680 bytes)
Sep 3 01:39:18 (none) kernel: NET: Registered protocol family 1
Sep 3 01:39:18 (none) kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers
Sep 3 01:39:18 (none) kernel: Trying to unpack rootfs image as initramfs...
Sep 3 01:39:18 (none) kernel: Freeing initrd memory: 11716k freed
Sep 3 01:39:18 (none) kernel: DMA-API: preallocated 32768 debug entries
Sep 3 01:39:18 (none) kernel: DMA-API: debugging enabled by kernel config
Sep 3 01:39:18 (none) kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
Sep 3 01:39:18 (none) kernel: Placing 64MB software IO TLB between ffff880002fde000 - ffff880006fde000
Sep 3 01:39:18 (none) kernel: software IO TLB at phys 0x2fde000 - 0x6fde000
Sep 3 01:39:18 (none) kernel: Simple Boot Flag at 0x36 set to 0x80
Sep 3 01:39:18 (none) kernel: audit: initializing netlink socket (disabled)
Sep 3 01:39:18 (none) kernel: type=2000 audit(1283492346.741:1): initialized
Sep 3 01:39:18 (none) kernel: HugeTLB registered 2 MB page size, pre-allocated 0 pages
Sep 3 01:39:18 (none) kernel: VFS: Disk quotas dquot_6.5.2
Sep 3 01:39:18 (none) kernel: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Sep 3 01:39:18 (none) kernel: msgmni has been set to 7888
Sep 3 01:39:18 (none) kernel: cryptomgr_test used greatest stack depth: 5968 bytes left
Sep 3 01:39:18 (none) kernel: cryptomgr_test used greatest stack depth: 5944 bytes left
Sep 3 01:39:18 (none) kernel: cryptomgr_test used greatest stack depth: 5664 bytes left
Sep 3 01:39:18 (none) kernel: alg: No test for stdrng (krng)
Sep 3 01:39:18 (none) kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
Sep 3 01:39:18 (none) kernel: io scheduler noop registered
Sep 3 01:39:18 (none) kernel: io scheduler deadline registered
Sep 3 01:39:18 (none) kernel: io scheduler cfq registered (default)
Sep 3 01:39:18 (none) kernel: pci_hotplug: PCI Hot Plug PCI Core version: 0.5
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:15.0:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:15.1:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:15.2:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:15.3:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:15.4:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:15.5:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:15.6:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:15.7:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:16.0:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:16.1:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:16.2:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:16.3:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:16.4:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:16.5:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:16.6:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:16.7:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:17.0:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:17.1:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:17.2:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:17.3:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:17.4:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:17.5:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:17.6:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:17.7:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:18.0:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:18.1:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:18.2:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:18.3:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:18.4:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:18.5:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:18.6:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp 0000:00:18.7:pcie04: HPC vendor_id 15ad device_id 7a0 ss_vid 15ad ss_did 7a0
Sep 3 01:39:18 (none) kernel: pciehp: PCI Express Hot Plug Controller Driver version: 0.4
Sep 3 01:39:18 (none) kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [32] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [33] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [34] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [35] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [36] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [37] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [38] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [39] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [40] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [41] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [42] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [43] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [44] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [45] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [46] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [47] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [48] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [49] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [50] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [51] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [52] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [53] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [54] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [55] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [56] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [57] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [58] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [59] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [60] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [61] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [62] registered
Sep 3 01:39:18 (none) kernel: acpiphp: Slot [63] registered
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 160 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 192 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 224 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 256 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 161 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 162 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 163 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 164 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 165 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 166 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 167 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 193 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 194 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 195 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 196 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 197 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 198 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 199 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 225 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 226 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 227 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 228 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 229 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 230 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 231 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 257 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 258 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 259 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 260 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 261 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 262 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: acpiphp_glue: Slot 263 already registered by another hotplug driver
Sep 3 01:39:18 (none) kernel: pci-stub: invalid id string ""
Sep 3 01:39:18 (none) kernel: ACPI: AC Adapter [ACAD] (on-line)
Sep 3 01:39:18 (none) kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
Sep 3 01:39:18 (none) kernel: ACPI: Power Button [PWRF]
Sep 3 01:39:18 (none) kernel: ERST: Table is not found!
Sep 3 01:39:18 (none) kernel: Non-volatile memory driver v1.3
Sep 3 01:39:18 (none) kernel: Linux agpgart interface v0.103
Sep 3 01:39:18 (none) kernel: agpgart-intel 0000:00:00.0: Intel 440BX Chipset
Sep 3 01:39:18 (none) kernel: agpgart-intel 0000:00:00.0: AGP aperture is 256M @ 0x0
Sep 3 01:39:18 (none) kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Sep 3 01:39:18 (none) kernel: serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Sep 3 01:39:18 (none) kernel: serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
Sep 3 01:39:18 (none) kernel: 00:09: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Sep 3 01:39:18 (none) kernel: 00:0a: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
Sep 3 01:39:18 (none) kernel: brd: module loaded
Sep 3 01:39:18 (none) kernel: loop: module loaded
Sep 3 01:39:18 (none) kernel: scsi0 : ata_piix
Sep 3 01:39:18 (none) kernel: scsi1 : ata_piix
Sep 3 01:39:18 (none) kernel: ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0x10c0 irq 14
Sep 3 01:39:18 (none) kernel: ata2: PATA max UDMA/33 cmd 0x170 ctl 0x376 bmdma 0x10c8 irq 15
Sep 3 01:39:18 (none) kernel: Fixed MDIO Bus: probed
Sep 3 01:39:18 (none) kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Sep 3 01:39:18 (none) kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
Sep 3 01:39:18 (none) kernel: uhci_hcd: USB Universal Host Controller Interface driver
Sep 3 01:39:18 (none) kernel: PNP: PS/2 Controller [PNP0303:KBC,PNP0f13:MOUS] at 0x60,0x64 irq 1,12
Sep 3 01:39:18 (none) kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Sep 3 01:39:18 (none) kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Sep 3 01:39:18 (none) kernel: mice: PS/2 mouse device common for all mice
Sep 3 01:39:18 (none) kernel: rtc_cmos 00:04: rtc core: registered rtc_cmos as rtc0
Sep 3 01:39:18 (none) kernel: rtc0: alarms up to one month, y3k, 114 bytes nvram
Sep 3 01:39:18 (none) kernel: device-mapper: uevent: version 1.0.3
Sep 3 01:39:18 (none) kernel: device-mapper: ioctl: 4.17.0-ioctl (2010-03-05) initialised: dm-devel@redhat.com
Sep 3 01:39:18 (none) kernel: cpuidle: using governor ladder
Sep 3 01:39:18 (none) kernel: cpuidle: using governor menu
Sep 3 01:39:18 (none) kernel: usbcore: registered new interface driver hiddev
Sep 3 01:39:18 (none) kernel: usbcore: registered new interface driver usbhid
Sep 3 01:39:18 (none) kernel: usbhid: USB HID core driver
Sep 3 01:39:18 (none) kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
Sep 3 01:39:18 (none) kernel: CONFIG_NF_CT_ACCT is deprecated and will be removed soon. Please use
Sep 3 01:39:18 (none) kernel: nf_conntrack.acct=1 kernel parameter, acct=1 nf_conntrack module option or
Sep 3 01:39:18 (none) kernel: sysctl net.netfilter.nf_conntrack_acct=1 to enable it.
Sep 3 01:39:18 (none) kernel: ip_tables: (C) 2000-2006 Netfilter Core Team
Sep 3 01:39:18 (none) kernel: TCP cubic registered
Sep 3 01:39:18 (none) kernel: Initializing XFRM netlink socket
Sep 3 01:39:18 (none) kernel: NET: Registered protocol family 17
Sep 3 01:39:18 (none) kernel: registered taskstats version 1
Sep 3 01:39:18 (none) kernel: IMA: No TPM chip found, activating TPM-bypass!
Sep 3 01:39:18 (none) kernel: Magic number: 10:901:667
Sep 3 01:39:18 (none) kernel: rtc_cmos 00:04: setting system clock to 2010-09-03 05:39:08 UTC (1283492348)
Sep 3 01:39:18 (none) kernel: Initalizing network drop monitor service
Sep 3 01:39:18 (none) kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
Sep 3 01:39:18 (none) kernel: input: ImPS/2 Generic Wheel Mouse as /devices/platform/i8042/serio1/input/input2
Sep 3 01:39:18 (none) kernel: ata2.00: ATAPI: VMware Virtual IDE CDROM Drive, 00000001, max UDMA/33
Sep 3 01:39:18 (none) kernel: ata2.00: configured for UDMA/33
Sep 3 01:39:18 (none) kernel: scsi 1:0:0:0: CD-ROM NECVMWar VMware IDE CDR10 1.00 PQ: 0 ANSI: 5
Sep 3 01:39:18 (none) kernel: sr0: scsi3-mmc drive: 1x/1x writer dvd-ram cd/rw xa/form2 cdda tray
Sep 3 01:39:18 (none) kernel: Uniform CD-ROM driver Revision: 3.20
Sep 3 01:39:18 (none) kernel: sr 1:0:0:0: Attached scsi generic sg0 type 5
Sep 3 01:39:18 (none) kernel: Freeing unused kernel memory: 2756k freed
Sep 3 01:39:18 (none) kernel: Write protecting the kernel read-only data: 10240k
Sep 3 01:39:18 (none) kernel: Freeing unused kernel memory: 1384k freed
Sep 3 01:39:18 (none) kernel: Freeing unused kernel memory: 1716k freed
Sep 3 01:39:18 (none) kernel: dracut: dracut-005-3.fc13
Sep 3 01:39:18 (none) kernel: dracut: rd_NO_LUKS: removing cryptoluks activation
Sep 3 01:39:18 (none) kernel: dracut: rd_NO_LVM: removing LVM activation
Sep 3 01:39:18 (none) kernel: udev: starting version 151
Sep 3 01:39:18 (none) kernel: udevadm used greatest stack depth: 5648 bytes left
Sep 3 01:39:18 (none) kernel: udevadm used greatest stack depth: 5472 bytes left
Sep 3 01:39:18 (none) kernel: dracut: Starting plymouth daemon
Sep 3 01:39:18 (none) kernel: dracut: rd_NO_DM: removing DM RAID activation
Sep 3 01:39:18 (none) kernel: dracut: rd_NO_MD: removing MD RAID activation
Sep 3 01:39:18 (none) kernel: cdrom_id used greatest stack depth: 4168 bytes left
Sep 3 01:39:18 (none) kernel: Fusion MPT base driver 3.04.15
Sep 3 01:39:18 (none) kernel: Copyright (c) 1999-2008 LSI Corporation
Sep 3 01:39:18 (none) kernel: Fusion MPT SAS Host driver 3.04.15
Sep 3 01:39:18 (none) kernel: mptsas 0000:03:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Sep 3 01:39:18 (none) kernel: mptbase: ioc0: Initiating bringup
Sep 3 01:39:18 (none) kernel: ioc0: LSISAS1068 B0: Capabilities={Initiator}
Sep 3 01:39:18 (none) kernel: scsi2 : ioc0: LSISAS1068 B0, FwRev=01032920h, Ports=1, MaxQ=128, IRQ=18
Sep 3 01:39:18 (none) kernel: mptsas: ioc0: attaching ssp device: fw_channel 0, fw_id 0, phy 0, sas_addr 0x5000c29ff90fe52c
Sep 3 01:39:18 (none) kernel: scsi 2:0:0:0: Direct-Access VMware Virtual disk 1.0 PQ: 0 ANSI: 2
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: [sda] 12582912 512-byte logical blocks: (6.44 GB/6.00 GiB)
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: [sda] Write Protect is off
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: [sda] Cache data unavailable
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: [sda] Assuming drive cache: write through
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: [sda] Cache data unavailable
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: [sda] Assuming drive cache: write through
Sep 3 01:39:18 (none) kernel: sda:
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0
Sep 3 01:39:18 (none) kernel: mptsas: ioc0: attaching ssp device: fw_channel 0, fw_id 1, phy 1, sas_addr 0x5000c29e9f5b8975
Sep 3 01:39:18 (none) kernel: sda1 sda2 sda3
Sep 3 01:39:18 (none) kernel: scsi 2:0:1:0: Direct-Access VMware Virtual disk 1.0 PQ: 0 ANSI: 2
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: [sdb] 33554432 512-byte logical blocks: (17.1 GB/16.0 GiB)
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: [sdb] Write Protect is off
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: [sdb] Cache data unavailable
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: [sdb] Assuming drive cache: write through
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: [sdb] Cache data unavailable
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: [sdb] Assuming drive cache: write through
Sep 3 01:39:18 (none) kernel: sdb:
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: Attached scsi generic sg2 type 0
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: [sda] Cache data unavailable
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: [sda] Assuming drive cache: write through
Sep 3 01:39:18 (none) kernel: sd 2:0:0:0: [sda] Attached SCSI disk
Sep 3 01:39:18 (none) kernel: sdb1
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: [sdb] Cache data unavailable
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: [sdb] Assuming drive cache: write through
Sep 3 01:39:18 (none) kernel: sd 2:0:1:0: [sdb] Attached SCSI disk
Sep 3 01:39:18 (none) kernel: modprobe used greatest stack depth: 3976 bytes left
Sep 3 01:39:18 (none) kernel: SGI XFS with ACLs, security attributes, large block/inode numbers, no debug enabled
Sep 3 01:39:18 (none) kernel: SGI XFS Quota Management subsystem
Sep 3 01:39:18 (none) kernel: XFS mounting filesystem sda3
Sep 3 01:39:18 (none) kernel: dracut: Mounted root filesystem /dev/sda3
Sep 3 01:39:18 (none) kernel: dracut: Loading SELinux policy
Sep 3 01:39:18 (none) kernel: type=1404 audit(1283492350.159:2): enforcing=1 old_enforcing=0 auid=4294967295 ses=4294967295
Sep 3 01:39:18 (none) kernel: type=1403 audit(1283492351.001:3): policy loaded auid=4294967295 ses=4294967295
Sep 3 01:39:18 (none) kernel: dracut: Switching root
Sep 3 01:39:18 (none) kernel: readahead: starting
Sep 3 01:39:18 (none) kernel: udev: starting version 151
Sep 3 01:39:18 (none) kernel: VMware vmxnet3 virtual NIC driver - version 1.0.5.0-k-NAPI
Sep 3 01:39:18 (none) kernel: vmxnet3 0000:04:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Sep 3 01:39:18 (none) kernel: microcode: CPU0 sig=0x106a5, pf=0x1, revision=0x11
Sep 3 01:39:18 (none) kernel: features: sg csum vlan jf tso tsoIPv6 lro highDMA
Sep 3 01:39:18 (none) kernel: vmxnet3 0000:0b:00.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
Sep 3 01:39:18 (none) kernel: microcode: CPU1 sig=0x106a5, pf=0x1, revision=0x11
Sep 3 01:39:18 (none) kernel: microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
Sep 3 01:39:18 (none) kernel: features: sg csum vlan jf tso tsoIPv6 lro highDMA
Sep 3 01:39:18 (none) kernel: vmxnet3 0000:13:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Sep 3 01:39:18 (none) kernel: features: sg csum vlan jf tso tsoIPv6 lro highDMA
Sep 3 01:39:18 (none) kernel: vmxnet3 0000:1b:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
Sep 3 01:39:18 (none) kernel: features: sg csum vlan jf tso tsoIPv6 lro highDMA
Sep 3 01:39:18 (none) kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
Sep 3 01:39:18 (none) kernel: udev: renamed network interface eth0 to eth4
Sep 3 01:39:18 (none) kernel: parport_pc 00:08: reported by Plug and Play ACPI
Sep 3 01:39:18 (none) kernel: parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
Sep 3 01:39:18 (none) kernel: e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k6-NAPI
Sep 3 01:39:18 (none) kernel: e1000: Copyright (c) 1999-2006 Intel Corporation.
Sep 3 01:39:18 (none) kernel: e1000 0000:02:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Sep 3 01:39:18 (none) kernel: e1000 0000:02:00.0: eth0: (PCI:66MHz:32-bit) 00:0c:29:b3:1f:90
Sep 3 01:39:18 (none) kernel: e1000 0000:02:00.0: eth0: Intel(R) PRO/1000 Network Connection
Sep 3 01:39:18 (none) kernel: ppdev: user-space parallel port driver
Sep 3 01:39:18 (none) kernel: ACPI: resource piix4_smbus [io 0x1040-0x1047] conflicts with ACPI region SMB_ [io 0x1040-0x104b 64bit disabled]
Sep 3 01:39:18 (none) kernel: ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
Sep 3 01:39:18 (none) kernel: type=1400 audit(1283492355.204:4): avc: denied { mmap_zero } for pid=664 comm="vbetool" scontext=system_u:system_r:vbetool_t:s0-s0:c0.c1023 tcontext=system_u:system_r:vbetool_t:s0-s0:c0.c1023 tclass=memprotect
Sep 3 01:39:18 (none) kernel: EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
Sep 3 01:39:18 (none) kernel: XFS mounting filesystem sdb1
Sep 3 01:39:18 (none) kernel: Adding 1048572k swap on /dev/sda2. Priority:-1 extents:1 across:1048572k
Sep 3 01:39:18 (none) kernel: telinit used greatest stack depth: 3792 bytes left
Sep 3 01:39:18 (none) kernel: NET: Registered protocol family 10
Sep 3 01:39:18 (none) kernel: lo: Disabled Privacy Extensions
Sep 3 01:39:19 (none) NetworkManager[975]: <info> NetworkManager (version 0.8.0.997) is starting...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> trying to start the modem manager...
Sep 3 01:39:19 (none) NetworkManager[975]: ifcfg-rh: Acquired D-Bus service com.redhat.ifcfgrh1
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Loaded plugin ifcfg-rh: (c) 2007 - 2008 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
Sep 3 01:39:19 (none) modem-manager: Loaded plugin AnyData
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Generic
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Gobi
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Option High-Speed
Sep 3 01:39:19 (none) NetworkManager[975]: ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-eth0 ...
Sep 3 01:39:19 (none) NetworkManager[975]: ifcfg-rh: read connection 'System eth0'
Sep 3 01:39:19 (none) NetworkManager[975]: ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-lo ...
Sep 3 01:39:19 (none) NetworkManager[975]: ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-eth5 ...
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Huawei
Sep 3 01:39:19 (none) NetworkManager[975]: ifcfg-rh: error: addresses
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Longcheer
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Ericsson MBM
Sep 3 01:39:19 (none) NetworkManager[975]: <info> WiFi enabled by radio killswitch; enabled by state file
Sep 3 01:39:19 (none) NetworkManager[975]: <info> WWAN enabled by radio killswitch; enabled by state file
Sep 3 01:39:19 (none) NetworkManager[975]: <info> WiMAX enabled by radio killswitch; enabled by state file
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Networking is enabled by state file
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Setting system hostname to 'vm3' (from system configuration)
Sep 3 01:39:19 (none) modem-manager: Loaded plugin MotoC
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Nokia
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Novatel
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Option
Sep 3 01:39:19 (none) modem-manager: Loaded plugin Sierra
Sep 3 01:39:19 (none) modem-manager: Loaded plugin ZTE
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): carrier is OFF
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): new Ethernet device (driver: 'e1000' ifindex: 6)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
Sep 3 01:39:19 (none) avahi-daemon[986]: Found user 'avahi' (UID 498) and group 'avahi' (GID 495).
Sep 3 01:39:19 (none) avahi-daemon[986]: Successfully dropped root privileges.
Sep 3 01:39:19 (none) avahi-daemon[986]: avahi-daemon 0.6.25 starting up.
Sep 3 01:39:19 (none) avahi-daemon[986]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
Sep 3 01:39:19 (none) avahi-daemon[986]: Successfully called chroot().
Sep 3 01:39:19 (none) avahi-daemon[986]: Successfully dropped remaining capabilities.
Sep 3 01:39:19 (none) avahi-daemon[986]: Loading service file /services/ssh.service.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): now managed
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): device state change: 1 -> 2 (reason 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): bringing up device.
Sep 3 01:39:19 (none) kernel: e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None
Sep 3 01:39:19 (none) avahi-daemon[986]: Network interface enumeration completed.
Sep 3 01:39:19 (none) avahi-daemon[986]: Registering new address record for fe80::20c:29ff:feb3:1f90 on eth0.*.
Sep 3 01:39:19 (none) avahi-daemon[986]: Server startup complete. Host name is vm3.local. Local service cookie is 2511285796.
Sep 3 01:39:19 (none) avahi-daemon[986]: Service "vm3" (/services/ssh.service) successfully established.
Sep 3 01:39:19 (none) avahi-daemon[986]: Registering HINFO record with values 'X86_64'/'LINUX'.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): preparing device.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): deactivating device (reason: 2).
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): carrier is OFF
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): new Ethernet device (driver: 'vmxnet3' ifindex: 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): exported as /org/freedesktop/NetworkManager/Devices/1
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): now managed
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): device state change: 1 -> 2 (reason 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): bringing up device.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): preparing device.
Sep 3 01:39:19 (none) kernel: eth4: intr type 3, mode 0, 1 vectors allocated
Sep 3 01:39:19 (none) kernel: eth4: NIC Link is Up 10000 Mbps
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): deactivating device (reason: 2).
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Added default wired connection 'Auto eth4' for /sys/devices/pci0000:00/0000:00:15.1/0000:04:00.0/net/eth4
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): carrier is OFF
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): new Ethernet device (driver: 'vmxnet3' ifindex: 3)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): exported as /org/freedesktop/NetworkManager/Devices/2
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): now managed
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): device state change: 1 -> 2 (reason 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): bringing up device.
Sep 3 01:39:19 (none) kernel: eth1: intr type 3, mode 0, 1 vectors allocated
Sep 3 01:39:19 (none) kernel: eth1: NIC Link is Up 10000 Mbps
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): preparing device.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): deactivating device (reason: 2).
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Added default wired connection 'Auto eth1' for /sys/devices/pci0000:00/0000:00:16.0/0000:0b:00.0/net/eth1
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): carrier is OFF
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): new Ethernet device (driver: 'vmxnet3' ifindex: 4)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): exported as /org/freedesktop/NetworkManager/Devices/3
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): now managed
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): device state change: 1 -> 2 (reason 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): bringing up device.
Sep 3 01:39:19 (none) kernel: eth2: intr type 3, mode 0, 1 vectors allocated
Sep 3 01:39:19 (none) kernel: eth2: NIC Link is Up 10000 Mbps
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): preparing device.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): deactivating device (reason: 2).
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Added default wired connection 'Auto eth2' for /sys/devices/pci0000:00/0000:00:17.0/0000:13:00.0/net/eth2
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): carrier is OFF
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): new Ethernet device (driver: 'vmxnet3' ifindex: 5)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): exported as /org/freedesktop/NetworkManager/Devices/4
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): now managed
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): device state change: 1 -> 2 (reason 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): bringing up device.
Sep 3 01:39:19 (none) kernel: eth3: intr type 3, mode 0, 1 vectors allocated
Sep 3 01:39:19 (none) kernel: eth3: NIC Link is Up 10000 Mbps
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): preparing device.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): deactivating device (reason: 2).
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Added default wired connection 'Auto eth3' for /sys/devices/pci0000:00/0000:00:18.0/0000:1b:00.0/net/eth3
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): carrier now ON (device state 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): device state change: 2 -> 3 (reason 40)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): carrier now ON (device state 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): device state change: 2 -> 3 (reason 40)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): carrier now ON (device state 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): device state change: 2 -> 3 (reason 40)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): carrier now ON (device state 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): device state change: 2 -> 3 (reason 40)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): carrier now ON (device state 2)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): device state change: 2 -> 3 (reason 40)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> modem-manager is now available
Sep 3 01:39:19 (none) NetworkManager[975]: <warn> bluez error getting default adapter: The name org.bluez was not provided by any .service files
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Trying to start the supplicant...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) starting connection 'System eth0'
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): device state change: 3 -> 4 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) starting connection 'Auto eth4'
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): device state change: 3 -> 4 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 1 of 5 (Device Prepare) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) starting connection 'Auto eth1'
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): device state change: 3 -> 4 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) starting connection 'Auto eth2'
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): device state change: 3 -> 4 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 1 of 5 (Device Prepare) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) starting connection 'Auto eth3'
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): device state change: 3 -> 4 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 1 of 5 (Device Prepare) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 1 of 5 (Device Prepare) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 2 of 5 (Device Configure) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 1 of 5 (Device Prepare) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 1 of 5 (Device Prepare) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 2 of 5 (Device Configure) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 1 of 5 (Device Prepare) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 1 of 5 (Device Prepare) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 2 of 5 (Device Configure) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 1 of 5 (Device Prepare) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 1 of 5 (Device Prepare) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 2 of 5 (Device Configure) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 1 of 5 (Device Prepare) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): device state change: 4 -> 5 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 2 of 5 (Device Configure) starting...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): device state change: 4 -> 5 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 2 of 5 (Device Configure) successful.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 2 of 5 (Device Configure) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 2 of 5 (Device Configure) starting...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): device state change: 4 -> 5 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 2 of 5 (Device Configure) successful.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 2 of 5 (Device Configure) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 2 of 5 (Device Configure) starting...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): device state change: 4 -> 5 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 2 of 5 (Device Configure) successful.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 2 of 5 (Device Configure) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 2 of 5 (Device Configure) starting...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): device state change: 4 -> 5 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 2 of 5 (Device Configure) successful.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 2 of 5 (Device Configure) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth0): device state change: 5 -> 7 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 3 of 5 (IP Configure Start) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth4): device state change: 5 -> 7 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Beginning DHCPv4 transaction (timeout in 45 seconds)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> dhclient started with pid 998
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth4) Stage 3 of 5 (IP Configure Start) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 3 of 5 (IP Configure Start) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth1): device state change: 5 -> 7 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Beginning DHCPv4 transaction (timeout in 45 seconds)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> dhclient started with pid 1000
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth1) Stage 3 of 5 (IP Configure Start) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 3 of 5 (IP Configure Start) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth2): device state change: 5 -> 7 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Beginning DHCPv4 transaction (timeout in 45 seconds)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> dhclient started with pid 1002
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth2) Stage 3 of 5 (IP Configure Start) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 3 of 5 (IP Configure Start) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> (eth3): device state change: 5 -> 7 (reason 0)
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Beginning DHCPv4 transaction (timeout in 45 seconds)
Sep 3 01:39:19 (none) dhclient[998]: Internet Systems Consortium DHCP Client 4.1.1
Sep 3 01:39:19 (none) dhclient[998]: Copyright 2004-2010 Internet Systems Consortium.
Sep 3 01:39:19 (none) dhclient[998]: All rights reserved.
Sep 3 01:39:19 (none) dhclient[998]: For info, please visit https://www.isc.org/software/dhcp/
Sep 3 01:39:19 (none) dhclient[998]:
Sep 3 01:39:19 (none) dhclient[1000]: Internet Systems Consortium DHCP Client 4.1.1
Sep 3 01:39:19 (none) dhclient[1000]: Copyright 2004-2010 Internet Systems Consortium.
Sep 3 01:39:19 (none) dhclient[1000]: All rights reserved.
Sep 3 01:39:19 (none) dhclient[1000]: For info, please visit https://www.isc.org/software/dhcp/
Sep 3 01:39:19 (none) dhclient[1000]:
Sep 3 01:39:19 (none) dhclient[1002]: Internet Systems Consortium DHCP Client 4.1.1
Sep 3 01:39:19 (none) dhclient[1002]: Copyright 2004-2010 Internet Systems Consortium.
Sep 3 01:39:19 (none) dhclient[1002]: All rights reserved.
Sep 3 01:39:19 (none) dhclient[1002]: For info, please visit https://www.isc.org/software/dhcp/
Sep 3 01:39:19 (none) dhclient[1002]:
Sep 3 01:39:19 (none) NetworkManager[975]: <info> dhclient started with pid 1005
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth3) Stage 3 of 5 (IP Configure Start) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) started...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) complete.
Sep 3 01:39:19 (none) NetworkManager[975]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
Sep 3 01:39:19 (none) avahi-daemon[986]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.20.232.19.
Sep 3 01:39:19 (none) avahi-daemon[986]: New relevant interface eth0.IPv4 for mDNS.
Sep 3 01:39:19 (none) avahi-daemon[986]: Registering new address record for 10.20.232.19 on eth0.IPv4.
Sep 3 01:39:19 (none) dhclient[1005]: Internet Systems Consortium DHCP Client 4.1.1
Sep 3 01:39:19 (none) dhclient[1005]: Copyright 2004-2010 Internet Systems Consortium.
Sep 3 01:39:19 (none) dhclient[1005]: All rights reserved.
Sep 3 01:39:19 (none) dhclient[1005]: For info, please visit https://www.isc.org/software/dhcp/
Sep 3 01:39:19 (none) dhclient[1005]:
Sep 3 01:39:19 (none) dhclient[998]: Listening on LPF/eth4/00:0c:29:b3:1f:b8
Sep 3 01:39:19 (none) dhclient[998]: Sending on LPF/eth4/00:0c:29:b3:1f:b8
Sep 3 01:39:19 (none) dhclient[998]: Sending on Socket/fallback
Sep 3 01:39:19 (none) dhclient[1002]: Listening on LPF/eth2/00:0c:29:b3:1f:a4
Sep 3 01:39:19 (none) dhclient[1002]: Sending on LPF/eth2/00:0c:29:b3:1f:a4
Sep 3 01:39:19 (none) dhclient[1002]: Sending on Socket/fallback
Sep 3 01:39:19 (none) rpc.statd[1012]: Version 1.2.2 starting
Sep 3 01:39:19 (none) dhclient[1005]: Listening on LPF/eth3/00:0c:29:b3:1f:ae
Sep 3 01:39:19 (none) dhclient[1005]: Sending on LPF/eth3/00:0c:29:b3:1f:ae
Sep 3 01:39:19 (none) dhclient[1005]: Sending on Socket/fallback
Sep 3 01:39:19 (none) sm-notify[1013]: Version 1.2.2 starting
Sep 3 01:39:19 (none) dhclient[1000]: Listening on LPF/eth1/00:0c:29:b3:1f:9a
Sep 3 01:39:19 (none) dhclient[1000]: Sending on LPF/eth1/00:0c:29:b3:1f:9a
Sep 3 01:39:19 (none) dhclient[1000]: Sending on Socket/fallback
Sep 3 01:39:19 (none) kernel: RPC: Registered udp transport module.
Sep 3 01:39:19 (none) kernel: RPC: Registered tcp transport module.
Sep 3 01:39:19 (none) kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Sep 3 01:39:20 (none) dhclient[1000]: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 8
Sep 3 01:39:20 (none) avahi-daemon[986]: Registering new address record for fe80::20c:29ff:feb3:1fae on eth3.*.
Sep 3 01:39:20 (none) NetworkManager[975]: <info> (eth0): device state change: 7 -> 8 (reason 0)
Sep 3 01:39:20 (none) NetworkManager[975]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep 3 01:39:20 (none) NetworkManager[975]: <info> Activation (eth0) successful, device activated.
Sep 3 01:39:20 (none) NetworkManager[975]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
Sep 3 01:39:20 (none) NetworkManager[975]: <info> (eth4): DHCPv4 state changed nbi -> preinit
Sep 3 01:39:20 (none) NetworkManager[975]: <info> (eth3): DHCPv4 state changed nbi -> preinit
Sep 3 01:39:20 (none) NetworkManager[975]: <info> (eth1): DHCPv4 state changed nbi -> preinit
Sep 3 01:39:20 (none) avahi-daemon[986]: Registering new address record for fe80::20c:29ff:feb3:1fb8 on eth4.*.
Sep 3 01:39:20 (none) /usr/sbin/gpm[1170]: *** info [daemon/startup.c(136)]:
Sep 3 01:39:20 (none) /usr/sbin/gpm[1170]: Started gpm successfully. Entered daemon mode.
Sep 3 01:39:21 (none) dhclient[1005]: DHCPDISCOVER on eth3 to 255.255.255.255 port 67 interval 6
Sep 3 01:39:21 (none) dhclient[1002]: DHCPDISCOVER on eth2 to 255.255.255.255 port 67 interval 4
Sep 3 01:39:21 (none) avahi-daemon[986]: Registering new address record for fe80::20c:29ff:feb3:1f9a on eth1.*.
Sep 3 01:39:21 (none) avahi-daemon[986]: Registering new address record for fe80::20c:29ff:feb3:1fa4 on eth2.*.
Sep 3 01:39:22 (none) dhclient[998]: DHCPDISCOVER on eth4 to 255.255.255.255 port 67 interval 3
Sep 3 01:39:25 (none) dhclient[1002]: DHCPDISCOVER on eth2 to 255.255.255.255 port 67 interval 5
Sep 3 01:39:25 (none) dhclient[998]: DHCPDISCOVER on eth4 to 255.255.255.255 port 67 interval 8
Sep 3 01:39:27 (none) dhclient[1005]: DHCPDISCOVER on eth3 to 255.255.255.255 port 67 interval 9
Sep 3 01:39:28 (none) dhclient[1000]: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 13
Sep 3 01:39:30 (none) dhclient[1002]: DHCPDISCOVER on eth2 to 255.255.255.255 port 67 interval 6
Sep 3 01:39:33 (none) dhclient[998]: DHCPDISCOVER on eth4 to 255.255.255.255 port 67 interval 18
Sep 3 01:39:36 (none) dhclient[1005]: DHCPDISCOVER on eth3 to 255.255.255.255 port 67 interval 12
Sep 3 01:39:36 (none) dhclient[1002]: DHCPDISCOVER on eth2 to 255.255.255.255 port 67 interval 14
Sep 3 01:39:41 (none) dhclient[1000]: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 21
Sep 3 01:39:48 (none) dhclient[1005]: DHCPDISCOVER on eth3 to 255.255.255.255 port 67 interval 12
Sep 3 01:39:50 (none) dhclient[1002]: DHCPDISCOVER on eth2 to 255.255.255.255 port 67 interval 21
Sep 3 01:39:51 (none) dhclient[998]: DHCPDISCOVER on eth4 to 255.255.255.255 port 67 interval 13
Sep 3 01:40:00 (none) dhclient[1005]: DHCPDISCOVER on eth3 to 255.255.255.255 port 67 interval 20
Sep 3 01:40:02 (none) dhclient[1000]: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 12
Sep 3 01:40:04 (none) dhclient[998]: DHCPDISCOVER on eth4 to 255.255.255.255 port 67 interval 8
Sep 3 01:40:05 (none) NetworkManager[975]: <warn> (eth4): DHCPv4 request timed out.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth4): canceled DHCP transaction, DHCP client pid 998
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth4) Stage 4 of 5 (IP4 Configure Timeout) scheduled...
Sep 3 01:40:05 (none) NetworkManager[975]: <warn> (eth1): DHCPv4 request timed out.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth1): canceled DHCP transaction, DHCP client pid 1000
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth1) Stage 4 of 5 (IP4 Configure Timeout) scheduled...
Sep 3 01:40:05 (none) NetworkManager[975]: <warn> (eth2): DHCPv4 request timed out.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth2): canceled DHCP transaction, DHCP client pid 1002
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth2) Stage 4 of 5 (IP4 Configure Timeout) scheduled...
Sep 3 01:40:05 (none) NetworkManager[975]: <warn> (eth3): DHCPv4 request timed out.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth3): canceled DHCP transaction, DHCP client pid 1005
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth3) Stage 4 of 5 (IP4 Configure Timeout) scheduled...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth4) Stage 4 of 5 (IP4 Configure Timeout) started...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth4) Stage 5 of 5 (IP Configure Commit) scheduled...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth4) Stage 4 of 5 (IP4 Configure Timeout) complete.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth1) Stage 4 of 5 (IP4 Configure Timeout) started...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth1) Stage 5 of 5 (IP Configure Commit) scheduled...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth1) Stage 4 of 5 (IP4 Configure Timeout) complete.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth2) Stage 4 of 5 (IP4 Configure Timeout) started...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth2) Stage 5 of 5 (IP Configure Commit) scheduled...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth2) Stage 4 of 5 (IP4 Configure Timeout) complete.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth3) Stage 4 of 5 (IP4 Configure Timeout) started...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth3) Stage 5 of 5 (IP Configure Commit) scheduled...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth3) Stage 4 of 5 (IP4 Configure Timeout) complete.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth4) Stage 5 of 5 (IP Configure Commit) started...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth4) Stage 5 of 5 (IP Configure Commit) failed (no IP configuration found)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth4): device state change: 7 -> 9 (reason 5)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Marking connection 'Auto eth4' invalid.
Sep 3 01:40:05 (none) NetworkManager[975]: <warn> Activation (eth4) failed.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth4) Stage 5 of 5 (IP Configure Commit) complete.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth1) Stage 5 of 5 (IP Configure Commit) started...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth1) Stage 5 of 5 (IP Configure Commit) failed (no IP configuration found)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth1): device state change: 7 -> 9 (reason 5)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Marking connection 'Auto eth1' invalid.
Sep 3 01:40:05 (none) NetworkManager[975]: <warn> Activation (eth1) failed.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth1) Stage 5 of 5 (IP Configure Commit) complete.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth2) Stage 5 of 5 (IP Configure Commit) started...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth2) Stage 5 of 5 (IP Configure Commit) failed (no IP configuration found)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth2): device state change: 7 -> 9 (reason 5)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Marking connection 'Auto eth2' invalid.
Sep 3 01:40:05 (none) NetworkManager[975]: <warn> Activation (eth2) failed.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth2) Stage 5 of 5 (IP Configure Commit) complete.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth3) Stage 5 of 5 (IP Configure Commit) started...
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth3) Stage 5 of 5 (IP Configure Commit) failed (no IP configuration found)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth3): device state change: 7 -> 9 (reason 5)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Marking connection 'Auto eth3' invalid.
Sep 3 01:40:05 (none) NetworkManager[975]: <warn> Activation (eth3) failed.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Activation (eth3) Stage 5 of 5 (IP Configure Commit) complete.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth4): device state change: 9 -> 3 (reason 0)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth4): deactivating device (reason: 0).
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth1): device state change: 9 -> 3 (reason 0)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth1): deactivating device (reason: 0).
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth2): device state change: 9 -> 3 (reason 0)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth2): deactivating device (reason: 0).
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth3): device state change: 9 -> 3 (reason 0)
Sep 3 01:40:05 (none) NetworkManager[975]: <info> (eth3): deactivating device (reason: 0).
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep 3 01:40:05 (none) NetworkManager[975]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep 3 01:43:10 (none) kernel: eth2: intr type 3, mode 0, 1 vectors allocated
Sep 3 01:43:10 (none) kernel: eth2: NIC Link is Up 10000 Mbps
Sep 3 01:43:10 (none) kernel: device eth2 entered promiscuous mode
Sep 3 01:43:11 (none) kernel: eth2: intr type 3, mode 0, 1 vectors allocated
Sep 3 01:43:11 (none) kernel: eth2: NIC Link is Up 10000 Mbps
Sep 3 01:43:15 (none) kernel: eth3: intr type 3, mode 0, 1 vectors allocated
Sep 3 01:43:15 (none) kernel: eth3: NIC Link is Up 10000 Mbps
Sep 3 01:43:15 (none) kernel: eth3: intr type 3, mode 0, 1 vectors allocated
Sep 3 01:43:15 (none) kernel: eth3: NIC Link is Up 10000 Mbps
Sep 3 01:43:15 (none) kernel: eth3: intr type 3, mode 0, 1 vectors allocated
Sep 3 01:43:15 (none) kernel: eth3: NIC Link is Up 10000 Mbps
Sep 3 01:49:45 (none) kernel: flush-8:0 used greatest stack depth: 3192 bytes left
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: [Pv-drivers] rps and pvdrivers
2010-09-03 22:39 ` Chetan Loke
@ 2010-09-04 6:51 ` Eric Dumazet
2010-09-04 7:00 ` Eric Dumazet
2010-09-04 13:38 ` Chetan Loke
0 siblings, 2 replies; 10+ messages in thread
From: Eric Dumazet @ 2010-09-04 6:51 UTC (permalink / raw)
To: Chetan Loke; +Cc: Bhavesh Davda, netdev, pv-drivers, therbert
Le vendredi 03 septembre 2010 à 18:39 -0400, Chetan Loke a écrit :
> Hi Bhavesh,
>
> On Fri, Sep 3, 2010 at 5:05 PM, Bhavesh Davda <bhavesh@vmware.com> wrote:
>
> >
> > I presume you're talking about the vmxnet3 vNIC here? What version of the VMware hypervisor are you observing this with? Could you share the vmware.log (and potentially the vmkernel log) file from the VM where you're seeing this?
> >
>
> Yes its vmxnet3 on esx 4.1. I've attached the vm-log file. Its easy to
> reproduce - A simple 'rcvfrom' loop on a promiscuous interface.
>
>
Reproduce what exactly ?
I dont understand what the problem is, reading your description.
RPS is not automatically switched on, you have to configure it.
echo ffff >/sys/class/net/eth0/queues/rx-0/rps_cpus
Same for RFS if you prefer to use RFS
echo 16384 >/sys/class/net/eth0/queues/rx-0/rps_flow_cnt
If you receive a flood, your cpu stay in NAPI mode, and no hardware
interrupt is received while you process xxx.xxx packets per second.
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: [Pv-drivers] rps and pvdrivers
2010-09-04 6:51 ` Eric Dumazet
@ 2010-09-04 7:00 ` Eric Dumazet
2010-09-04 13:38 ` Chetan Loke
1 sibling, 0 replies; 10+ messages in thread
From: Eric Dumazet @ 2010-09-04 7:00 UTC (permalink / raw)
To: Chetan Loke, Tom Herbert; +Cc: Bhavesh Davda, netdev, pv-drivers
Le samedi 04 septembre 2010 à 08:51 +0200, Eric Dumazet a écrit :
> >
>
> Reproduce what exactly ?
>
> I dont understand what the problem is, reading your description.
>
> RPS is not automatically switched on, you have to configure it.
>
> echo ffff >/sys/class/net/eth0/queues/rx-0/rps_cpus
>
> Same for RFS if you prefer to use RFS
>
> echo 16384 >/sys/class/net/eth0/queues/rx-0/rps_flow_cnt
>
And, before you try this, you probably also need
echo 16384 >/proc/sys/net/core/rps_sock_flow_entries
Tom, I believe you were about to add RPS documentation in
Documentation/ ?
> If you receive a flood, your cpu stay in NAPI mode, and no hardware
> interrupt is received while you process xxx.xxx packets per second.
>
>
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: [Pv-drivers] rps and pvdrivers
2010-09-04 6:51 ` Eric Dumazet
2010-09-04 7:00 ` Eric Dumazet
@ 2010-09-04 13:38 ` Chetan Loke
2010-09-04 13:51 ` Eric Dumazet
1 sibling, 1 reply; 10+ messages in thread
From: Chetan Loke @ 2010-09-04 13:38 UTC (permalink / raw)
To: Eric Dumazet; +Cc: Bhavesh Davda, netdev, pv-drivers, therbert
Hi Eric,
On Sat, Sep 4, 2010 at 2:51 AM, Eric Dumazet <eric.dumazet@gmail.com> wrote:
> Reproduce what exactly ?
>
> I dont understand what the problem is, reading your description.
With 2.6.35 the rx-path performance on a 10G vNIC is way too low. If
you setup a simple 'recvfrom' loop on a promiscuous interface you can
see this easily.
My use-case is to achieve high speed pkt capturing.
> RPS is not automatically switched on, you have to configure it.
>
> echo ffff >/sys/class/net/eth0/queues/rx-0/rps_cpus
>
> Same for RFS if you prefer to use RFS
>
> echo 16384 >/sys/class/net/eth0/queues/rx-0/rps_flow_cnt
>
Ok, thanks for sharing this. I tried this and still doesn't help.
> If you receive a flood, your cpu stay in NAPI mode, and no hardware
> interrupt is received while you process xxx.xxx packets per second.
>
I see. Ok then that's whats happening. I guess I will have to look at
the napi/ksoftirq/rps block in detail to understand this. But I would
think that even w/o the rps settings I should still get the same
numbers as compared to non-rps case, correct?
On a VM(virtual machine) using a 1G vNIC I can capture ~250K
pkts/sec(even higher in some cases). But I can't go beyond 100K
pkts/sec on a 10G vNIC because ksoftirqd consumes 1-cpu 100% of the
time. That's why I thought of switching to the 2.6.35 kernel to see if
I could scale on 10G.
It's possible that a VM cannot handle that much load. So I tried
sending only 10% of line-rate(10G) which is 1G. It still doesn't work.
I still can't capture that many pkts.
Chetan Loke
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: [Pv-drivers] rps and pvdrivers
2010-09-04 13:38 ` Chetan Loke
@ 2010-09-04 13:51 ` Eric Dumazet
2010-09-04 14:02 ` Chetan Loke
0 siblings, 1 reply; 10+ messages in thread
From: Eric Dumazet @ 2010-09-04 13:51 UTC (permalink / raw)
To: Chetan Loke; +Cc: Bhavesh Davda, netdev, pv-drivers, therbert
Le samedi 04 septembre 2010 à 09:38 -0400, Chetan Loke a écrit :
> I see. Ok then that's whats happening. I guess I will have to look at
> the napi/ksoftirq/rps block in detail to understand this. But I would
> think that even w/o the rps settings I should still get the same
> numbers as compared to non-rps case, correct?
>
> On a VM(virtual machine) using a 1G vNIC I can capture ~250K
> pkts/sec(even higher in some cases). But I can't go beyond 100K
> pkts/sec on a 10G vNIC because ksoftirqd consumes 1-cpu 100% of the
> time. That's why I thought of switching to the 2.6.35 kernel to see if
> I could scale on 10G.
>
> It's possible that a VM cannot handle that much load. So I tried
> sending only 10% of line-rate(10G) which is 1G. It still doesn't work.
> I still can't capture that many pkts.
If packets have same rxhash, (same src IP, dst IP, src port, dst port),
they are directed on a single CPU, and this might explain you cannot
handle the load, RPS or not.
cat /proc/net/softnet_stat
RPS is good to handle multiple flows, because it can distribute load on
several cpus. But with a single flow, I guess it might be not that
useful.
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: [Pv-drivers] rps and pvdrivers
2010-09-04 13:51 ` Eric Dumazet
@ 2010-09-04 14:02 ` Chetan Loke
2010-09-04 14:31 ` Eric Dumazet
0 siblings, 1 reply; 10+ messages in thread
From: Chetan Loke @ 2010-09-04 14:02 UTC (permalink / raw)
To: Eric Dumazet; +Cc: Bhavesh Davda, netdev, pv-drivers, therbert
On Sat, Sep 4, 2010 at 9:51 AM, Eric Dumazet <eric.dumazet@gmail.com> wrote:
>
> If packets have same rxhash, (same src IP, dst IP, src port, dst port),
> they are directed on a single CPU, and this might explain you cannot
> handle the load, RPS or not.
I tried incrementing the src-macs from the load-generator. Or only the
above 4-tuple entries are used?
>
> cat /proc/net/softnet_stat
>
cat /proc/net/softnet_stat
0655cb87 006dd31b 00096a78 00000000 00000000 00000000 00000000
00000000 00000000 00000dbe
0042feec 00000000 0008ae33 00000000 00000000 00000000 00000000
00000000 00000000 00000000
cat /proc/net/softnet_stat
065d444e 006e669d 00097565 00000000 00000000 00000000 00000000
00000000 00000000 00000dbe
0042ff8c 00000000 0008b83f 00000000 00000000 00000000 00000000
00000000 00000000 00000000
cat /proc/net/softnet_stat
066a6fcf 006fefd9 000988c9 00000000 00000000 00000000 00000000
00000000 00000000 00000dbe
004300bd 00000000 0008c945 00000000 00000000 00000000 00000000
00000000 00000000 00000000
> RPS is good to handle multiple flows, because it can distribute load on
> several cpus. But with a single flow, I guess it might be not that
> useful.
For single flow if it doesn't improve performance that's fine. But it
should atleast work as good as N-2(2.6.33).
Chetan Loke
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: [Pv-drivers] rps and pvdrivers
2010-09-04 14:02 ` Chetan Loke
@ 2010-09-04 14:31 ` Eric Dumazet
2010-09-04 15:27 ` Chetan Loke
0 siblings, 1 reply; 10+ messages in thread
From: Eric Dumazet @ 2010-09-04 14:31 UTC (permalink / raw)
To: Chetan Loke; +Cc: Bhavesh Davda, netdev, pv-drivers, therbert
Le samedi 04 septembre 2010 à 10:02 -0400, Chetan Loke a écrit :
> On Sat, Sep 4, 2010 at 9:51 AM, Eric Dumazet <eric.dumazet@gmail.com> wrote:
> >
> > If packets have same rxhash, (same src IP, dst IP, src port, dst port),
> > they are directed on a single CPU, and this might explain you cannot
> > handle the load, RPS or not.
>
> I tried incrementing the src-macs from the load-generator. Or only the
> above 4-tuple entries are used?
>
MAC addresses are not used to compute rxhash
>
> >
> > cat /proc/net/softnet_stat
> >
>
> cat /proc/net/softnet_stat
> 0655cb87 006dd31b 00096a78 00000000 00000000 00000000 00000000
> 00000000 00000000 00000dbe
> 0042feec 00000000 0008ae33 00000000 00000000 00000000 00000000
> 00000000 00000000 00000000
>
> cat /proc/net/softnet_stat
> 065d444e 006e669d 00097565 00000000 00000000 00000000 00000000
> 00000000 00000000 00000dbe
> 0042ff8c 00000000 0008b83f 00000000 00000000 00000000 00000000
> 00000000 00000000 00000000
>
> cat /proc/net/softnet_stat
> 066a6fcf 006fefd9 000988c9 00000000 00000000 00000000 00000000
> 00000000 00000000 00000dbe
> 004300bd 00000000 0008c945 00000000 00000000 00000000 00000000
> 00000000 00000000 00000000
>
>
>
> > RPS is good to handle multiple flows, because it can distribute load on
> > several cpus. But with a single flow, I guess it might be not that
> > useful.
>
> For single flow if it doesn't improve performance that's fine. But it
> should atleast work as good as N-2(2.6.33).
>
Nope. Because if each packet goes through two cpus instead of one before
being queued to socket queue, you pay overhead and memory trafic between
these cpus.
This is the reason why RPS is not automatically switched on. It might be
slower on some workloads.
For UDP trafic, single flow, RPS is not a win, because UDP stack is
quite small.
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: [Pv-drivers] rps and pvdrivers
2010-09-04 14:31 ` Eric Dumazet
@ 2010-09-04 15:27 ` Chetan Loke
0 siblings, 0 replies; 10+ messages in thread
From: Chetan Loke @ 2010-09-04 15:27 UTC (permalink / raw)
To: Eric Dumazet; +Cc: Bhavesh Davda, netdev, pv-drivers, therbert
On Sat, Sep 4, 2010 at 10:31 AM, Eric Dumazet <eric.dumazet@gmail.com> wrote:
> Nope. Because if each packet goes through two cpus instead of one before
> being queued to socket queue, you pay overhead and memory trafic between
> these cpus.
But if an interface is set in promisc-mode then why split the flows?
And if rps is not enabled(which by default it isn't) then everything
should just work as before.
>
> This is the reason why RPS is not automatically switched on. It might be
> slower on some workloads.
>
I'm now generating multi-flow traffic pattern and still the same.
Chetan
^ permalink raw reply [flat|nested] 10+ messages in thread
end of thread, other threads:[~2010-09-04 15:27 UTC | newest]
Thread overview: 10+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2010-09-03 19:49 rps and pvdrivers Chetan Loke
2010-09-03 21:05 ` [Pv-drivers] " Bhavesh Davda
2010-09-03 22:39 ` Chetan Loke
2010-09-04 6:51 ` Eric Dumazet
2010-09-04 7:00 ` Eric Dumazet
2010-09-04 13:38 ` Chetan Loke
2010-09-04 13:51 ` Eric Dumazet
2010-09-04 14:02 ` Chetan Loke
2010-09-04 14:31 ` Eric Dumazet
2010-09-04 15:27 ` Chetan Loke
This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.