Bug#637725: Logs
xend/xm are used.
The attached logs represent the following actions:
-----------
Host freshly booted.
init.d/xendomains auto-creates the first domu, which is a firewall
application (endian 2.2 with custom paravirtualized 2.6.21 kernel) with
pygrub. The firewall uses 3 bridged interfaces (intern, extern, dmz) and
comes up correctly on all of them.
Config: vif = [ 'bridge=intern','bridge=extern', 'bridge=dmz' ]
The test domu is created and has normal connectivity over its single
bridged interface.
Config: vif = [ 'bridge=intern' ]
test domu is saved.
test domu is restored.
bridge name bridge id STP interfaces
dmz 8000.001b21a93ef9 no eth3
vif1.2
extern 8000.001b21a93c70 no eth2
vif1.1
intern 8000.0025902c9e65 no eth0
vif1.0
vif3.0
Looks as expected, but the domu has no connectivity. Using the console
to the domu, ifconfig looks normal, but only the interface itself pings,
no other hosts.
test domu is saved again.
The firewall domu is shutdown.
test domu is restored again, now the connectivity is restored as well!
-----------------
At this moment, dmesg and xen logs are saved.
Some more remarks:
The restored and network-wise nonfunctional domu live-migrates
successfully to a 2.6.32-5-amd64 4.1 xen debian-stable host and regains
normal connectivity there instantly.
When domus are only created and shutdown (no save/restore/migrate),
everything works normally.
If I let init.d/xendomains save the firewall domu on shutdown, it will
restore it on reboot; this very first domu is already nonfunctional
network-wise.
rdware events not enabled
[ 0.026867] installing Xen timer for CPU 7
[ 0.027043] NMI watchdog disabled (cpu7): hardware events not enabled
[ 0.027073] Brought up 8 CPUs
[ 0.027320] devtmpfs: initialized
[ 0.035339] PM: Registering ACPI NVS region at dfeb2000 (188416 bytes)
[ 0.035339] Grant table initialized
[ 0.035339] print_constraints: dummy:
[ 0.035339] NET: Registered protocol family 16
[ 0.036104] node 0 link 0: io port [1000, ffffff]
[ 0.036113] TOM: 00000000e0000000 aka 3584M
[ 0.036116] Fam 10h mmconf [e0000000, efffffff]
[ 0.036125] node 0 link 0: mmio [e0000000, efffffff] ==> none
[ 0.036131] node 0 link 0: mmio [f0000000, ffffffff]
[ 0.036138] node 0 link 0: mmio [a0000, bffff]
[ 0.036144] TOM2: 0000000420000000 aka 16896M
[ 0.036148] bus: [00, 1f] on node 0 link 0
[ 0.036152] bus: 00 index 0 [io 0x0000-0xffff]
[ 0.036156] bus: 00 index 1 [mem 0xf0000000-0xffffffff]
[ 0.036160] bus: 00 index 2 [mem 0x000a0000-0x000bffff]
[ 0.036163] bus: 00 index 3 [mem 0x420000000-0xfcffffffff]
[ 0.036182] Extended Config Space enabled on 2 nodes
[ 0.036341] ACPI: bus type pci registered
[ 0.036459] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
[ 0.036464] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in E820
[ 0.121415] PCI: Using configuration type 1 for base access
[ 0.122251] bio: create slab <bio-0> at 0
[ 0.124156] ACPI: EC: Look up EC in DSDT
[ 0.124642] \_SB_:_OSC evaluation returned wrong type
[ 0.124646] _OSC request data:1 7
[ 0.127925] ACPI: Executed 2 blocks of module-level executable AML code
[ 0.153493] ACPI: Interpreter enabled
[ 0.153499] ACPI: (supports S0 S1 S4 S5)
[ 0.153552] ACPI: Using IOAPIC for interrupt routing
[ 0.262446] ACPI: No dock devices found.
[ 0.262516] HEST: Table parsing has been initialized.
[ 0.262522] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.262744] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.263192] pci_root PNP0A08:00: host bridge window [io 0x0000-0x0cf7]
[ 0.263197] pci_root PNP0A08:00: host bridge window [io 0x0d00-0xffff]
[ 0.263202] pci_root PNP0A08:00: host bridge window [mem 0x000a0000-0x000bffff]
[ 0.263206] pci_root PNP0A08:00: host bridge window [mem 0x000d0000-0x000dffff]
[ 0.263210] pci_root PNP0A08:00: host bridge window [mem 0xf0000000-0xfebfffff]
[ 0.263250] pci 0000:00:00.0: [1002:5a13] type 0 class 0x000600
[ 0.263416] pci 0000:00:03.0: [1002:5a17] type 1 class 0x000604
[ 0.263502] pci 0000:00:03.0: PME# supported from D0 D3hot D3cold
[ 0.263508] pci 0000:00:03.0: PME# disabled
[ 0.263549] pci 0000:00:04.0: [1002:5a18] type 1 class 0x000604
[ 0.263633] pci 0000:00:04.0: PME# supported from D0 D3hot D3cold
[ 0.263639] pci 0000:00:04.0: PME# disabled
[ 0.263688] pci 0000:00:09.0: [1002:5a1c] type 1 class 0x000604
[ 0.263771] pci 0000:00:09.0: PME# supported from D0 D3hot D3cold
[ 0.263777] pci 0000:00:09.0: PME# disabled
[ 0.263818] pci 0000:00:0a.0: [1002:5a1d] type 1 class 0x000604
[ 0.263901] pci 0000:00:0a.0: PME# supported from D0 D3hot D3cold
[ 0.263907] pci 0000:00:0a.0: PME# disabled
[ 0.263967] pci 0000:00:11.0: [1002:4390] type 0 class 0x000101
[ 0.264003] pci 0000:00:11.0: reg 10: [io 0x9000-0x9007]
[ 0.264015] pci 0000:00:11.0: reg 14: [io 0x8000-0x8003]
[ 0.264015] pci 0000:00:11.0: reg 18: [io 0x7000-0x7007]
[ 0.264015] pci 0000:00:11.0: reg 1c: [io 0x6000-0x6003]
[ 0.264015] pci 0000:00:11.0: reg 20: [io 0x5000-0x500f]
[ 0.264015] pci 0000:00:11.0: reg 24: [mem 0xfdaffc00-0xfdafffff]
[ 0.264015] pci 0000:00:11.0: set SATA to AHCI mode
[ 0.264057] pci 0000:00:12.0: [1002:4397] type 0 class 0x000c03
[ 0.264082] pci 0000:00:12.0: reg 10: [mem 0xfdafd000-0xfdafdfff]
[ 0.264200] pci 0000:00:12.1: [1002:4398] type 0 class 0x000c03
[ 0.264226] pci 0000:00:12.1: reg 10: [mem 0xfdafe000-0xfdafefff]
[ 0.264354] pci 0000:00:12.2: [1002:4396] type 0 class 0x000c03
[ 0.264389] pci 0000:00:12.2: reg 10: [mem 0xfdaff800-0xfdaff8ff]
[ 0.264517] pci 0000:00:12.2: supports D1 D2
[ 0.264521] pci 0000:00:12.2: PME# supported from D0 D1 D2 D3hot
[ 0.264528] pci 0000:00:12.2: PME# disabled
[ 0.264569] pci 0000:00:13.0: [1002:4397] type 0 class 0x000c03
[ 0.264594] pci 0000:00:13.0: reg 10: [mem 0xfdafb000-0xfdafbfff]
[ 0.264711] pci 0000:00:13.1: [1002:4398] type 0 class 0x000c03
[ 0.264736] pci 0000:00:13.1: reg 10: [mem 0xfdafc000-0xfdafcfff]
[ 0.264864] pci 0000:00:13.2: [1002:4396] type 0 class 0x000c03
[ 0.264899] pci 0000:00:13.2: reg 10: [mem 0xfdaff400-0xfdaff4ff]
[ 0.265027] pci 0000:00:13.2: supports D1 D2
[ 0.265031] pci 0000:00:13.2: PME# supported from D0 D1 D2 D3hot
[ 0.265038] pci 0000:00:13.2: PME# disabled
[ 0.265085] pci 0000:00:14.0: [1002:4385] type 0 class 0x000c05
[ 0.265258] pci 0000:00:14.1: [1002:439c] type 0 class 0x000101
[ 0.265289] pci 0000:00:14.1: reg 10: [io 0x0000-0x0007]
[ 0.265307] pci 0000:00:14.1: reg 14: [io 0x0000-0x0003]
[ 0.265325] pci 0000:00:14.1: reg 18: [io 0x0000-0x0007]
[ 0.265343] pci 0000:00:14.1: reg 1c: [io 0x0000-0x0003]
[ 0.265361] pci 0000:00:14.1: reg 20: [io 0xff00-0xff0f]
[ 0.265449] pci 0000:00:14.3: [1002:439d] type 0 class 0x000601
[ 0.265586] pci 0000:00:14.4: [1002:4384] type 1 class 0x000604
[ 0.265662] pci 0000:00:14.5: [1002:4399] type 0 class 0x000c03
[ 0.265687] pci 0000:00:14.5: reg 10: [mem 0xfdafa000-0xfdafafff]
[ 0.265823] pci 0000:00:18.0: [1022:1200] type 0 class 0x000600
[ 0.265925] pci 0000:00:18.1: [1022:1201] type 0 class 0x000600
[ 0.265984] pci 0000:00:18.2: [1022:1202] type 0 class 0x000600
[ 0.266046] pci 0000:00:18.3: [1022:1203] type 0 class 0x000600
[ 0.266125] pci 0000:00:18.4: [1022:1204] type 0 class 0x000600
[ 0.266220] pci 0000:00:19.0: [1022:1200] type 0 class 0x000600
[ 0.266317] pci 0000:00:19.1: [1022:1201] type 0 class 0x000600
[ 0.266377] pci 0000:00:19.2: [1022:1202] type 0 class 0x000600
[ 0.266443] pci 0000:00:19.3: [1022:1203] type 0 class 0x000600
[ 0.266524] pci 0000:00:19.4: [1022:1204] type 0 class 0x000600
[ 0.266722] pci 0000:01:00.0: [8086:10d3] type 0 class 0x000200
[ 0.266753] pci 0000:01:00.0: reg 10: [mem 0xfdb20000-0xfdb3ffff]
[ 0.266776] pci 0000:01:00.0: reg 14: [mem 0xfdb80000-0xfdbfffff]
[ 0.266799] pci 0000:01:00.0: reg 18: [io 0xa800-0xa81f]
[ 0.266822] pci 0000:01:00.0: reg 1c: [mem 0xfdb1c000-0xfdb1ffff]
[ 0.266883] pci 0000:01:00.0: reg 30: [mem 0xfdb40000-0xfdb7ffff pref]
[ 0.266951] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold
[ 0.266960] pci 0000:01:00.0: PME# disabled
[ 0.272056] pci 0000:00:03.0: PCI bridge to [bus 01-01]
[ 0.272068] pci 0000:00:03.0: bridge window [io 0xa000-0xafff]
[ 0.272075] pci 0000:00:03.0: bridge window [mem 0xfdb00000-0xfdbfffff]
[ 0.272085] pci 0000:00:03.0: bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[ 0.272199] pci 0000:02:00.0: [8086:10d3] type 0 class 0x000200
[ 0.272231] pci 0000:02:00.0: reg 10: [mem 0xfdc20000-0xfdc3ffff]
[ 0.272254] pci 0000:02:00.0: reg 14: [mem 0xfdc80000-0xfdcfffff]
[ 0.272277] pci 0000:02:00.0: reg 18: [io 0xb800-0xb81f]
[ 0.272300] pci 0000:02:00.0: reg 1c: [mem 0xfdc1c000-0xfdc1ffff]
[ 0.272361] pci 0000:02:00.0: reg 30: [mem 0xfdc40000-0xfdc7ffff pref]
[ 0.272432] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold
[ 0.272441] pci 0000:02:00.0: PME# disabled
[ 0.280056] pci 0000:00:04.0: PCI bridge to [bus 02-02]
[ 0.280067] pci 0000:00:04.0: bridge window [io 0xb000-0xbfff]
[ 0.280074] pci 0000:00:04.0: bridge window [mem 0xfdc00000-0xfdcfffff]
[ 0.280083] pci 0000:00:04.0: bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[ 0.280194] pci 0000:03:00.0: [8086:10d3] type 0 class 0x000200
[ 0.280226] pci 0000:03:00.0: reg 10: [mem 0xfdde0000-0xfddfffff]
[ 0.280268] pci 0000:03:00.0: reg 18: [io 0xc800-0xc81f]
[ 0.280290] pci 0000:03:00.0: reg 1c: [mem 0xfdddc000-0xfdddffff]
[ 0.280417] pci 0000:03:00.0: PME# supported from D0 D3hot D3cold
[ 0.280427] pci 0000:03:00.0: PME# disabled
[ 0.288055] pci 0000:00:09.0: PCI bridge to [bus 03-03]
[ 0.288065] pci 0000:00:09.0: bridge window [io 0xc000-0xcfff]
[ 0.288072] pci 0000:00:09.0: bridge window [mem 0xfdd00000-0xfddfffff]
[ 0.288082] pci 0000:00:09.0: bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[ 0.288193] pci 0000:04:00.0: [8086:10d3] type 0 class 0x000200
[ 0.288224] pci 0000:04:00.0: reg 10: [mem 0xfdee0000-0xfdefffff]
[ 0.288266] pci 0000:04:00.0: reg 18: [io 0xd800-0xd81f]
[ 0.288289] pci 0000:04:00.0: reg 1c: [mem 0xfdedc000-0xfdedffff]
[ 0.288417] pci 0000:04:00.0: PME# supported from D0 D3hot D3cold
[ 0.288426] pci 0000:04:00.0: PME# disabled
[ 0.296056] pci 0000:00:0a.0: PCI bridge to [bus 04-04]
[ 0.296067] pci 0000:00:0a.0: bridge window [io 0xd000-0xdfff]
[ 0.296074] pci 0000:00:0a.0: bridge window [mem 0xfde00000-0xfdefffff]
[ 0.296083] pci 0000:00:0a.0: bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[ 0.296139] pci 0000:05:02.0: [1244:0e00] type 0 class 0x000280
[ 0.296176] pci 0000:05:02.0: reg 10: [mem 0xfebffc00-0xfebffc1f]
[ 0.296197] pci 0000:05:02.0: reg 14: [io 0xe800-0xe81f]
[ 0.296323] pci 0000:05:02.0: supports D2
[ 0.296326] pci 0000:05:02.0: PME# supported from D2 D3hot D3cold
[ 0.296335] pci 0000:05:02.0: PME# disabled
[ 0.296373] pci 0000:05:04.0: [102b:0532] type 0 class 0x000300
[ 0.296409] pci 0000:05:04.0: reg 10: [mem 0xfc000000-0xfcffffff pref]
[ 0.296430] pci 0000:05:04.0: reg 14: [mem 0xfdffc000-0xfdffffff]
[ 0.296451] pci 0000:05:04.0: reg 18: [mem 0xfe000000-0xfe7fffff]
[ 0.296632] pci 0000:00:14.4: PCI bridge to [bus 05-05] (subtractive decode)
[ 0.296640] pci 0000:00:14.4: bridge window [io 0xe000-0xefff]
[ 0.296648] pci 0000:00:14.4: bridge window [mem 0xfdf00000-0xfebfffff]
[ 0.296657] pci 0000:00:14.4: bridge window [mem 0xfc000000-0xfcffffff pref]
[ 0.296661] pci 0000:00:14.4: bridge window [io 0x0000-0x0cf7] (subtractive decode)
[ 0.296666] pci 0000:00:14.4: bridge window [io 0x0d00-0xffff] (subtractive decode)
[ 0.296670] pci 0000:00:14.4: bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
[ 0.296675] pci 0000:00:14.4: bridge window [mem 0x000d0000-0x000dffff] (subtractive decode)
[ 0.296679] pci 0000:00:14.4: bridge window [mem 0xf0000000-0xfebfffff] (subtractive decode)
[ 0.296721] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[ 0.297480] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PC03._PRT]
[ 0.297574] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PC04._PRT]
[ 0.297684] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PC09._PRT]
[ 0.297776] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PC0A._PRT]
[ 0.297915] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0PC._PRT]
[ 0.298185] pci0000:00: Requesting ACPI _OSC control (0x1d)
[ 0.298191] pci0000:00: ACPI _OSC request failed (AE_NOT_FOUND), returned control mask: 0x1d
[ 0.298195] ACPI _OSC control for PCIe not granted, disabling ASPM
[ 0.320616] ACPI: PCI Interrupt Link [LNKA] (IRQs 4 7 10 *11 12 14 15)
[ 0.320728] ACPI: PCI Interrupt Link [LNKB] (IRQs 4 *7 10 11 12 14 15)
[ 0.320842] ACPI: PCI Interrupt Link [LNKC] (IRQs 4 7 *10 11 12 14 15)
[ 0.320960] ACPI: PCI Interrupt Link [LNKD] (IRQs 4 7 *10 11 12 14 15)
[ 0.321079] ACPI: PCI Interrupt Link [LNKE] (IRQs 4 7 10 *11 12 14 15)
[ 0.321188] ACPI: PCI Interrupt Link [LNKF] (IRQs 4 7 10 11 12 14 15) *0, disabled.
[ 0.321307] ACPI: PCI Interrupt Link [LNKG] (IRQs 4 7 *10 11 12 14 15)
[ 0.321424] ACPI: PCI Interrupt Link [LNKH] (IRQs 4 7 10 11 12 14 15) *0, disabled.
[ 0.321488] xen/balloon: Initialising balloon driver.
[ 0.321493] last_pfn = 0x220000 max_arch_pfn = 0x400000000
[ 0.335658] xen-balloon: Initialising balloon driver.
[ 0.335849] vgaarb: device added: PCI:0000:05:04.0,decodes=io+mem,owns=io+mem,locks=none
[ 0.335854] vgaarb: loaded
[ 0.335857] vgaarb: bridge control possible 0000:05:04.0
[ 0.335929] PCI: Using ACPI for IRQ routing
[ 0.336020] PCI: pci_cache_line_size set to 64 bytes
[ 0.336020] reserve RAM buffer: 000000000009a000 - 000000000009ffff
[ 0.336311] Switching to clocksource xen
[ 0.339138] pnp: PnP ACPI init
[ 0.339160] ACPI: bus type pnp registered
[ 0.339443] pnp 00:00: [bus 00-ff]
[ 0.339448] pnp 00:00: [io 0x0cf8-0x0cff]
[ 0.339452] pnp 00:00: [io 0x0000-0x0cf7 window]
[ 0.339456] pnp 00:00: [io 0x0d00-0xffff window]
[ 0.339460] pnp 00:00: [mem 0x000a0000-0x000bffff window]
[ 0.339464] pnp 00:00: [mem 0x000d0000-0x000dffff window]
[ 0.339468] pnp 00:00: [mem 0xe0000000-0xdfffffff window disabled]
[ 0.339472] pnp 00:00: [mem 0xf0000000-0xfebfffff window]
[ 0.339599] pnp 00:00: Plug and Play ACPI device, IDs PNP0a08 PNP0a03 (active)
[ 0.339825] pnp 00:01: [mem 0x00000000-0xffffffffffffffff disabled]
[ 0.339830] pnp 00:01: [mem 0x00000000-0xffffffffffffffff disabled]
[ 0.339923] system 00:01: Plug and Play ACPI device, IDs PNP0c02 (active)
[ 0.340020] pnp 00:02: [mem 0x00000000-0xffffffffffffffff disabled]
[ 0.340020] Switched to NOHz mode on CPU #0
[ 0.340020] system 00:02: Plug and Play ACPI device, IDs PNP0c02 (active)
[ 0.340333] pnp 00:03: [dma 4]
[ 0.340337] pnp 00:03: [io 0x0000-0x000f]
[ 0.340341] pnp 00:03: [io 0x0081-0x0083]
[ 0.340345] pnp 00:03: [io 0x0087]
[ 0.340348] pnp 00:03: [io 0x0089-0x008b]
[ 0.340352] pnp 00:03: [io 0x008f]
[ 0.340356] pnp 00:03: [io 0x00c0-0x00df]
[ 0.340416] pnp 00:03: Plug and Play ACPI device, IDs PNP0200 (active)
[ 0.340441] pnp 00:04: [io 0x0070-0x0071]
[ 0.340447] xen: registering gsi 8 triggering 1 polarity 0
[ 0.340451] xen_map_pirq_gsi: returning irq 8 for gsi 8
[ 0.340455] xen: --> pirq=8 -> irq=8 (gsi=8)
[ 0.340470] pnp 00:04: [irq 8]
[ 0.340528] pnp 00:04: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 0.340633] pnp 00:05: [io 0x0061]
[ 0.340694] pnp 00:05: Plug and Play ACPI device, IDs PNP0800 (active)
[ 0.340714] pnp 00:06: [io 0x00f0-0x00ff]
[ 0.340718] xen: registering gsi 13 triggering 1 polarity 0
[ 0.340721] xen_map_pirq_gsi: returning irq 13 for gsi 13
[ 0.340724] xen: --> pirq=13 -> irq=13 (gsi=13)
[ 0.340734] pnp 00:06: [irq 13]
[ 0.340793] pnp 00:06: Plug and Play ACPI device, IDs PNP0c04 (active)
[ 0.340952] pnp 00:07: [io 0x0000-0xffffffffffffffff disabled]
[ 0.340957] pnp 00:07: [io 0x0000-0xffffffffffffffff disabled]
[ 0.340961] pnp 00:07: [io 0x0a10-0x0a1f]
[ 0.341064] system 00:07: [io 0x0a10-0x0a1f] has been reserved
[ 0.341069] system 00:07: Plug and Play ACPI device, IDs PNP0c02 (active)
[ 0.341140] pnp 00:08: [mem 0xfed00000-0xfed003ff]
[ 0.341203] pnp 00:08: Plug and Play ACPI device, IDs PNP0103 (active)
[ 0.341446] pnp 00:09: [io 0x0060]
[ 0.341450] pnp 00:09: [io 0x0064]
[ 0.341454] pnp 00:09: [mem 0xfec00000-0xfec00fff]
[ 0.341458] pnp 00:09: [mem 0xfee00000-0xfee00fff]
[ 0.341562] system 00:09: [mem 0xfec00000-0xfec00fff] could not be reserved
[ 0.341567] system 00:09: [mem 0xfee00000-0xfee00fff] has been reserved
[ 0.341575] system 00:09: Plug and Play ACPI device, IDs PNP0c02 (active)
[ 0.341974] pnp 00:0a: [io 0x0010-0x001f]
[ 0.341978] pnp 00:0a: [io 0x0022-0x003f]
[ 0.341983] pnp 00:0a: [io 0x0062-0x0063]
[ 0.341987] pnp 00:0a: [io 0x0065-0x006f]
[ 0.341991] pnp 00:0a: [io 0x0072-0x007f]
[ 0.341997] Switched to NOHz mode on CPU #1
[ 0.342004] pnp 00:0a: [io 0x0080]
[ 0.342008] pnp 00:0a: [io 0x0084-0x0086]
[ 0.342012] pnp 00:0a: [io 0x0088]
[ 0.342016] pnp 00:0a: [io 0x008c-0x008e]
[ 0.342020] pnp 00:0a: [io 0x0090-0x009f]
[ 0.342024] pnp 00:0a: [io 0x00a2-0x00bf]
[ 0.342027] pnp 00:0a: [io 0x00b1]
[ 0.342031] pnp 00:0a: [io 0x00e0-0x00ef]
[ 0.342035] pnp 00:0a: [io 0x0ca2-0x0ca3]
[ 0.342038] pnp 00:0a: [io 0x0550-0x0551]
[ 0.342042] pnp 00:0a: [io 0x04d0-0x04d1]
[ 0.342046] pnp 00:0a: [io 0x040b]
[ 0.342049] pnp 00:0a: [io 0x04d6]
[ 0.342053] pnp 00:0a: [io 0x0c00-0x0c01]
[ 0.342056] pnp 00:0a: [io 0x0c14]
[ 0.342060] pnp 00:0a: [io 0x0c50-0x0c51]
[ 0.342064] pnp 00:0a: [io 0x0c52]
[ 0.342067] pnp 00:0a: [io 0x0c6c]
[ 0.342071] pnp 00:0a: [io 0x0c6f]
[ 0.342074] pnp 00:0a: [io 0x0cd0-0x0cd1]
[ 0.342080] pnp 00:0a: [io 0x0cd2-0x0cd3]
[ 0.342084] pnp 00:0a: [io 0x0cd4-0x0cd5]
[ 0.342088] pnp 00:0a: [io 0x0cd6-0x0cd7]
[ 0.342091] pnp 00:0a: [io 0x0cd8-0x0cdf]
[ 0.342095] pnp 00:0a: [io 0x0800-0x089f]
[ 0.342099] pnp 00:0a: [io 0x0000-0xffffffffffffffff disabled]
[ 0.342103] pnp 00:0a: [io 0x0b00-0x0b0f]
[ 0.342107] pnp 00:0a: [io 0x0b20-0x0b3f]
[ 0.342111] pnp 00:0a: [io 0x0900-0x090f]
[ 0.342114] pnp 00:0a: [io 0x0910-0x091f]
[ 0.342118] pnp 00:0a: [io 0xfe00-0xfefe]
[ 0.342122] pnp 00:0a: [io 0x0060]
[ 0.342126] pnp 00:0a: [io 0x0064]
[ 0.342129] pnp 00:0a: [mem 0xffb80000-0xffbfffff]
[ 0.342133] pnp 00:0a: [mem 0xfec10000-0xfec1001f]
[ 0.342305] system 00:0a: [io 0x0ca2-0x0ca3] has been reserved
[ 0.342310] system 00:0a: [io 0x0550-0x0551] has been reserved
[ 0.342315] system 00:0a: [io 0x04d0-0x04d1] has been reserved
[ 0.342320] system 00:0a: [io 0x040b] has been reserved
[ 0.342325] system 00:0a: [io 0x04d6] has been reserved
[ 0.342330] system 00:0a: [io 0x0c00-0x0c01] has been reserved
[ 0.342334] system 00:0a: [io 0x0c14] has been reserved
[ 0.342339] system 00:0a: [io 0x0c50-0x0c51] has been reserved
[ 0.342344] system 00:0a: [io 0x0c52] has been reserved
[ 0.342348] system 00:0a: [io 0x0c6c] has been reserved
[ 0.342353] system 00:0a: [io 0x0c6f] has been reserved
[ 0.342357] system 00:0a: [io 0x0cd0-0x0cd1] has been reserved
[ 0.342362] system 00:0a: [io 0x0cd2-0x0cd3] has been reserved
[ 0.342367] system 00:0a: [io 0x0cd4-0x0cd5] has been reserved
[ 0.342372] system 00:0a: [io 0x0cd6-0x0cd7] has been reserved
[ 0.342377] system 00:0a: [io 0x0cd8-0x0cdf] has been reserved
[ 0.342381] system 00:0a: [io 0x0800-0x089f] has been reserved
[ 0.342386] system 00:0a: [io 0x0b00-0x0b0f] has been reserved
[ 0.342391] system 00:0a: [io 0x0b20-0x0b3f] has been reserved
[ 0.342396] system 00:0a: [io 0x0900-0x090f] has been reserved
[ 0.342400] system 00:0a: [io 0x0910-0x091f] has been reserved
[ 0.342406] system 00:0a: [io 0xfe00-0xfefe] has been reserved
[ 0.342411] system 00:0a: [mem 0xffb80000-0xffbfffff] has been reserved
[ 0.342416] system 00:0a: [mem 0xfec10000-0xfec1001f] has been reserved
[ 0.342421] system 00:0a: Plug and Play ACPI device, IDs PNP0c02 (active)
[ 0.342629] Switched to NOHz mode on CPU #2
[ 0.342989] Switched to NOHz mode on CPU #3
[ 0.343021] pnp 00:0b: [io 0x03f8-0x03ff]
[ 0.343025] xen: registering gsi 4 triggering 1 polarity 0
[ 0.343029] xen_map_pirq_gsi: returning irq 4 for gsi 4
[ 0.343032] xen: --> pirq=4 -> irq=4 (gsi=4)
[ 0.343038] pnp 00:0b: [irq 4]
[ 0.343042] pnp 00:0b: [dma 0 disabled]
[ 0.343160] pnp 00:0b: Plug and Play ACPI device, IDs PNP0501 (active)
[ 0.343736] pnp 00:0c: [io 0x02f8-0x02ff]
[ 0.343740] xen: registering gsi 3 triggering 1 polarity 0
[ 0.343744] xen_map_pirq_gsi: returning irq 3 for gsi 3
[ 0.343747] xen: --> pirq=3 -> irq=3 (gsi=3)
[ 0.343756] pnp 00:0c: [irq 3]
[ 0.343762] pnp 00:0c: [dma 0 disabled]
[ 0.343803] Switched to NOHz mode on CPU #4
[ 0.343878] pnp 00:0c: Plug and Play ACPI device, IDs PNP0501 (active)
[ 0.343993] pnp 00:0d: [mem 0xe0000000-0xefffffff]
[ 0.344122] system 00:0d: [mem 0xe0000000-0xefffffff] has been reserved
[ 0.344127] system 00:0d: Plug and Play ACPI device, IDs PNP0c02 (active)
[ 0.344181] Switched to NOHz mode on CPU #5
[ 0.344576] Switched to NOHz mode on CPU #6
[ 0.344930] pnp 00:0e: [mem 0x00000000-0x0009ffff]
[ 0.344934] pnp 00:0e: [mem 0x000c0000-0x000cffff]
[ 0.344939] pnp 00:0e: [mem 0x000e0000-0x000fffff]
[ 0.344943] pnp 00:0e: [mem 0x00100000-0xdfffffff]
[ 0.344947] pnp 00:0e: [mem 0xfec00000-0xffffffff]
[ 0.344957] Switched to NOHz mode on CPU #7
[ 0.345065] system 00:0e: [mem 0x00000000-0x0009ffff] could not be reserved
[ 0.345071] system 00:0e: [mem 0x000c0000-0x000cffff] could not be reserved
[ 0.345077] system 00:0e: [mem 0x000e0000-0x000fffff] could not be reserved
[ 0.345082] system 00:0e: [mem 0x00100000-0xdfffffff] could not be reserved
[ 0.345088] system 00:0e: [mem 0xfec00000-0xffffffff] could not be reserved
[ 0.345093] system 00:0e: Plug and Play ACPI device, IDs PNP0c01 (active)
[ 0.345365] pnp: PnP ACPI: found 15 devices
[ 0.345368] ACPI: ACPI bus type pnp unregistered
[ 0.351447] PM-Timer failed consistency check (0x0xffffff) - aborting.
[ 0.351486] PCI: max bus depth: 1 pci_try_num: 2
[ 0.351551] pci 0000:00:03.0: PCI bridge to [bus 01-01]
[ 0.351557] pci 0000:00:03.0: bridge window [io 0xa000-0xafff]
[ 0.351565] pci 0000:00:03.0: bridge window [mem 0xfdb00000-0xfdbfffff]
[ 0.351572] pci 0000:00:03.0: bridge window [mem pref disabled]
[ 0.351581] pci 0000:00:04.0: PCI bridge to [bus 02-02]
[ 0.351586] pci 0000:00:04.0: bridge window [io 0xb000-0xbfff]
[ 0.351594] pci 0000:00:04.0: bridge window [mem 0xfdc00000-0xfdcfffff]
[ 0.351600] pci 0000:00:04.0: bridge window [mem pref disabled]
[ 0.351610] pci 0000:00:09.0: PCI bridge to [bus 03-03]
[ 0.351615] pci 0000:00:09.0: bridge window [io 0xc000-0xcfff]
[ 0.351625] pci 0000:00:09.0: bridge window [mem 0xfdd00000-0xfddfffff]
[ 0.351632] pci 0000:00:09.0: bridge window [mem pref disabled]
[ 0.351641] pci 0000:00:0a.0: PCI bridge to [bus 04-04]
[ 0.351646] pci 0000:00:0a.0: bridge window [io 0xd000-0xdfff]
[ 0.351654] pci 0000:00:0a.0: bridge window [mem 0xfde00000-0xfdefffff]
[ 0.351661] pci 0000:00:0a.0: bridge window [mem pref disabled]
[ 0.351670] pci 0000:00:14.4: PCI bridge to [bus 05-05]
[ 0.351676] pci 0000:00:14.4: bridge window [io 0xe000-0xefff]
[ 0.351686] pci 0000:00:14.4: bridge window [mem 0xfdf00000-0xfebfffff]
[ 0.351694] pci 0000:00:14.4: bridge window [mem 0xfc000000-0xfcffffff pref]
[ 0.351715] xen: registering gsi 19 triggering 0 polarity 1
[ 0.351732] xen: --> pirq=19 -> irq=19 (gsi=19)
[ 0.351740] pci 0000:00:03.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
[ 0.351749] pci 0000:00:03.0: setting latency timer to 64
[ 0.351759] xen: registering gsi 16 triggering 0 polarity 1
[ 0.351767] xen: --> pirq=16 -> irq=16 (gsi=16)
[ 0.351773] pci 0000:00:04.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 0.351779] pci 0000:00:04.0: setting latency timer to 64
[ 0.351789] xen: registering gsi 17 triggering 0 polarity 1
[ 0.351797] xen: --> pirq=17 -> irq=17 (gsi=17)
[ 0.351802] pci 0000:00:09.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[ 0.351808] pci 0000:00:09.0: setting latency timer to 64
[ 0.351817] xen: registering gsi 18 triggering 0 polarity 1
[ 0.351825] xen: --> pirq=18 -> irq=18 (gsi=18)
[ 0.351830] pci 0000:00:0a.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 0.351837] pci 0000:00:0a.0: setting latency timer to 64
[ 0.351851] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7]
[ 0.351855] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff]
[ 0.351858] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff]
[ 0.351862] pci_bus 0000:00: resource 7 [mem 0x000d0000-0x000dffff]
[ 0.351866] pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff]
[ 0.351870] pci_bus 0000:01: resource 0 [io 0xa000-0xafff]
[ 0.351874] pci_bus 0000:01: resource 1 [mem 0xfdb00000-0xfdbfffff]
[ 0.351878] pci_bus 0000:02: resource 0 [io 0xb000-0xbfff]
[ 0.351882] pci_bus 0000:02: resource 1 [mem 0xfdc00000-0xfdcfffff]
[ 0.351886] pci_bus 0000:03: resource 0 [io 0xc000-0xcfff]
[ 0.351890] pci_bus 0000:03: resource 1 [mem 0xfdd00000-0xfddfffff]
[ 0.351894] pci_bus 0000:04: resource 0 [io 0xd000-0xdfff]
[ 0.351897] pci_bus 0000:04: resource 1 [mem 0xfde00000-0xfdefffff]
[ 0.351902] pci_bus 0000:05: resource 0 [io 0xe000-0xefff]
[ 0.351905] pci_bus 0000:05: resource 1 [mem 0xfdf00000-0xfebfffff]
[ 0.351909] pci_bus 0000:05: resource 2 [mem 0xfc000000-0xfcffffff pref]
[ 0.351913] pci_bus 0000:05: resource 4 [io 0x0000-0x0cf7]
[ 0.351917] pci_bus 0000:05: resource 5 [io 0x0d00-0xffff]
[ 0.351921] pci_bus 0000:05: resource 6 [mem 0x000a0000-0x000bffff]
[ 0.351924] pci_bus 0000:05: resource 7 [mem 0x000d0000-0x000dffff]
[ 0.351928] pci_bus 0000:05: resource 8 [mem 0xf0000000-0xfebfffff]
[ 0.352263] NET: Registered protocol family 2
[ 0.352699] IP route cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.355365] TCP established hash table entries: 262144 (order: 10, 4194304 bytes)
[ 0.357357] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[ 0.357734] TCP: Hash tables configured (established 262144 bind 65536)
[ 0.357739] TCP reno registered
[ 0.357811] UDP hash table entries: 4096 (order: 5, 131072 bytes)
[ 0.357937] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes)
[ 0.358393] NET: Registered protocol family 1
[ 1.124297] pci 0000:05:04.0: Boot video device
[ 1.124308] PCI: CLS 64 bytes, default 64
[ 1.124404] Unpacking initramfs...
[ 1.138726] Freeing initrd memory: 7944k freed
[ 1.143573] audit: initializing netlink socket (disabled)
[ 1.143595] type=2000 audit(1313452742.547:1): initialized
[ 1.162773] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[ 1.163683] VFS: Disk quotas dquot_6.5.2
[ 1.163789] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 1.163968] msgmni has been set to 712
[ 1.164405] alg: No test for stdrng (krng)
[ 1.164572] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[ 1.164579] io scheduler noop registered
[ 1.164582] io scheduler deadline registered
[ 1.164703] io scheduler cfq registered (default)
[ 1.165735] APEI: Can not request iomem region <00000000dfec60ea-00000000dfec60ec> for GARs.
[ 1.166303] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 1.169987] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 1.244106] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[ 1.368241] 00:0b: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 1.392123] 00:0c: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[ 1.412472] hpet_acpi_add: no address or irqs in _CRS
[ 1.412502] Linux agpgart interface v0.103
[ 1.415507] i8042: PNP: No PS/2 controller found. Probing ports directly.
[ 1.418415] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 1.418427] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 1.418550] mousedev: PS/2 mouse device common for all mice
[ 1.418613] rtc_cmos 00:04: RTC can wake from S4
[ 1.418783] rtc_cmos 00:04: rtc core: registered rtc_cmos as rtc0
[ 1.418827] rtc0: alarms up to one month, y3k, 114 bytes nvram
[ 1.418845] cpuidle: using governor ladder
[ 1.418848] cpuidle: using governor menu
[ 1.419223] TCP cubic registered
[ 1.419535] NET: Registered protocol family 10
[ 1.420619] Mobile IPv6
[ 1.420624] NET: Registered protocol family 17
[ 1.420631] Registering the dns_resolver key type
[ 1.420789] PM: Hibernation image not present or could not be loaded.
[ 1.420803] registered taskstats version 1
[ 1.421721] rtc_cmos 00:04: setting system clock to 2011-08-15 23:59:02 UTC (1313452742)
[ 1.421780] Initializing network drop monitor service
[ 1.422249] Freeing unused kernel memory: 560k freed
[ 1.422483] Write protecting the kernel read-only data: 6144k
[ 1.426463] Freeing unused kernel memory: 756k freed
[ 1.427241] Freeing unused kernel memory: 740k freed
[ 1.477063] udev[109]: starting version 164
[ 1.546166] SCSI subsystem initialized
[ 1.549589] libata version 3.00 loaded.
[ 1.552789] ahci 0000:00:11.0: version 3.0
[ 1.552821] xen: registering gsi 22 triggering 0 polarity 1
[ 1.552847] xen: --> pirq=22 -> irq=22 (gsi=22)
[ 1.552859] ahci 0000:00:11.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
[ 1.553046] ahci 0000:00:11.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf impl SATA mode
[ 1.553055] ahci 0000:00:11.0: flags: 64bit ncq sntf ilck pm led clo pmp pio slum part ccc
[ 1.554473] scsi0 : ahci
[ 1.554841] scsi1 : ahci
[ 1.554985] scsi2 : ahci
[ 1.555160] scsi3 : ahci
[ 1.555923] ata1: SATA max UDMA/133 abar m1024@0xfdaffc00 port 0xfdaffd00 irq 22
[ 1.555937] ata2: SATA max UDMA/133 abar m1024@0xfdaffc00 port 0xfdaffd80 irq 22
[ 1.555943] ata3: SATA max UDMA/133 abar m1024@0xfdaffc00 port 0xfdaffe00 irq 22
[ 1.555951] ata4: SATA max UDMA/133 abar m1024@0xfdaffc00 port 0xfdaffe80 irq 22
[ 2.048165] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 2.048197] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 2.048220] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 2.048243] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 2.050534] ata2.00: ATA-8: WDC WD1003FBYX-01Y7B0, 01.01V01, max UDMA/133
[ 2.050540] ata2.00: 1953525168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[ 2.050639] ata1.00: ATA-8: WDC WD1003FBYX-01Y7B0, 01.01V01, max UDMA/133
[ 2.050644] ata1.00: 1953525168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[ 2.052339] ata2.00: configured for UDMA/133
[ 2.052391] ata1.00: configured for UDMA/133
[ 2.052576] scsi 0:0:0:0: Direct-Access ATA WDC WD1003FBYX-0 01.0 PQ: 0 ANSI: 5
[ 2.052973] scsi 1:0:0:0: Direct-Access ATA WDC WD1003FBYX-0 01.0 PQ: 0 ANSI: 5
[ 2.053657] ata4.00: ATA-8: Corsair CSSD-F120GB2, 2.0, max UDMA/133
[ 2.053662] ata4.00: 234441648 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[ 2.057407] ata3.00: ATA-8: Corsair CSSD-F120GB2, 2.0, max UDMA/133
[ 2.057412] ata3.00: 234441648 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[ 2.063627] ata4.00: configured for UDMA/133
[ 2.067403] ata3.00: configured for UDMA/133
[ 2.067560] scsi 2:0:0:0: Direct-Access ATA Corsair CSSD-F12 2.0 PQ: 0 ANSI: 5
[ 2.067818] scsi 3:0:0:0: Direct-Access ATA Corsair CSSD-F12 2.0 PQ: 0 ANSI: 5
[ 2.078668] sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
[ 2.078780] sd 0:0:0:0: [sda] Write Protect is off
[ 2.078784] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 2.078829] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 2.079316] sd 1:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
[ 2.079422] sd 1:0:0:0: [sdb] Write Protect is off
[ 2.079426] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 2.079470] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 2.080266] sd 2:0:0:0: [sdc] 234441648 512-byte logical blocks: (120 GB/111 GiB)
[ 2.080407] sd 2:0:0:0: [sdc] Write Protect is off
[ 2.080411] sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
[ 2.080454] sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 2.081272] sd 3:0:0:0: [sdd] 234441648 512-byte logical blocks: (120 GB/111 GiB)
[ 2.081414] sd 3:0:0:0: [sdd] Write Protect is off
[ 2.081419] sd 3:0:0:0: [sdd] Mode Sense: 00 3a 00 00
[ 2.081423] sdc: sdc1
[ 2.081462] sd 3:0:0:0: [sdd] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 2.082053] sd 2:0:0:0: [sdc] Attached SCSI disk
[ 2.082301] sdd: sdd1
[ 2.082811] sd 3:0:0:0: [sdd] Attached SCSI disk
[ 2.090355] sdb: sdb1 sdb2 sdb3 sdb4
[ 2.091052] sd 1:0:0:0: [sdb] Attached SCSI disk
[ 2.091600] sda: sda1 sda2 sda3 sda4
[ 2.092306] sd 0:0:0:0: [sda] Attached SCSI disk
[ 2.316790] md: raid1 personality registered for level 1
[ 2.336984] md: md4 stopped.
[ 2.339553] md: bind<sdb1>
[ 2.339783] md: bind<sda1>
[ 2.344118] bio: create slab <bio-1> at 1
[ 2.344366] md/raid1:md4: active with 2 out of 2 mirrors
[ 2.344403] md4: detected capacity change from 0 to 98553856
[ 2.347715] md4: unknown partition table
[ 2.558035] md: md3 stopped.
[ 2.560369] md: bind<sdb2>
[ 2.560662] md: bind<sda2>
[ 2.565039] md/raid1:md3: active with 2 out of 2 mirrors
[ 2.565071] md3: detected capacity change from 0 to 9933160448
[ 2.570586] md3: unknown partition table
[ 2.790024] md: md2 stopped.
[ 2.791191] md: bind<sda3>
[ 2.791470] md: bind<sdb3>
[ 2.794751] md/raid1:md2: active with 2 out of 2 mirrors
[ 2.794781] md2: detected capacity change from 0 to 1003356160
[ 2.797961] md2: unknown partition table
[ 2.834041] md: md1 stopped.
[ 2.835994] md: bind<sda4>
[ 2.836203] md: bind<sdb4>
[ 2.839461] md/raid1:md1: active with 2 out of 2 mirrors
[ 2.839492] md1: detected capacity change from 0 to 989155622912
[ 2.847006] md1: unknown partition table
[ 2.888289] md: md0 stopped.
[ 2.889395] md: bind<sdd1>
[ 2.889556] md: bind<sdc1>
[ 2.893089] md/raid1:md0: active with 2 out of 2 mirrors
[ 2.893124] md0: detected capacity change from 0 to 120030356480
[ 2.896532] md0: unknown partition table
[ 2.902935] device-mapper: uevent: version 1.0.3
[ 2.903228] device-mapper: ioctl: 4.20.0-ioctl (2011-02-02) initialised: dm-devel@redhat.com
[ 2.993927] PM: Starting manual resume from disk
[ 2.993933] PM: Hibernation image partition 9:2 present
[ 2.993936] PM: Looking for hibernation image.
[ 2.994172] PM: Image not found (code -22)
[ 2.994176] PM: Hibernation image not present or could not be loaded.
[ 3.024960] EXT4-fs (md3): mounted filesystem with ordered data mode. Opts: (null)
[ 3.741478] udev[391]: starting version 164
[ 3.916251] input: Power Button as /devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input0
[ 3.916260] ACPI: Power Button [PWRB]
[ 3.916348] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[ 3.916353] ACPI: Power Button [PWRF]
[ 3.924417] ACPI: acpi_idle registered with cpuidle
[ 3.932717] e1000e: Intel(R) PRO/1000 Network Driver - 1.3.10-k2
[ 3.932723] e1000e: Copyright(c) 1999 - 2011 Intel Corporation.
[ 3.932877] e1000e 0000:01:00.0: Disabling ASPM L0s
[ 3.932905] xen: registering gsi 19 triggering 0 polarity 1
[ 3.932916] xen_map_pirq_gsi: returning irq 19 for gsi 19
[ 3.932920] xen: --> pirq=19 -> irq=19 (gsi=19)
[ 3.932926] Already setup the GSI :19
[ 3.932931] e1000e 0000:01:00.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
[ 3.932966] e1000e 0000:01:00.0: setting latency timer to 64
[ 3.987942] usbcore: registered new interface driver usbfs
[ 3.987974] usbcore: registered new interface driver hub
[ 3.988063] usbcore: registered new device driver usb
[ 3.989112] [Firmware Warn]: GHES: Poll interval is 0 for generic hardware error source: 1, disabled.
[ 4.007485] input: PC Speaker as /devices/platform/pcspkr/input/input2
[ 4.013368] piix4_smbus 0000:00:14.0: SMBus Host Controller at 0xb00, revision 0
[ 4.015066] xen: registering gsi 16 triggering 0 polarity 1
[ 4.015075] xen_map_pirq_gsi: returning irq 16 for gsi 16
[ 4.015079] xen: --> pirq=16 -> irq=16 (gsi=16)
[ 4.015085] Already setup the GSI :16
[ 4.015090] pata_atiixp 0000:00:14.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 4.015145] pata_atiixp 0000:00:14.1: setting latency timer to 64
[ 4.015740] scsi4 : pata_atiixp
[ 4.015910] scsi5 : pata_atiixp
[ 4.017941] ata5: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0xff00 irq 14
[ 4.017946] ata6: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0xff08 irq 15
[ 4.047811] e1000e 0000:01:00.0: eth0: (PCI Express:2.5GT/s:Width x1) 00:1b:21:a9:3c:70
[ 4.047817] e1000e 0000:01:00.0: eth0: Intel(R) PRO/1000 Network Connection
[ 4.047831] e1000e 0000:01:00.0: eth0: MAC: 3, PHY: 8, PBA No: E46981-005
[ 4.047983] e1000e 0000:02:00.0: Disabling ASPM L0s
[ 4.048004] xen: registering gsi 16 triggering 0 polarity 1
[ 4.048011] xen_map_pirq_gsi: returning irq 16 for gsi 16
[ 4.048014] xen: --> pirq=16 -> irq=16 (gsi=16)
[ 4.048020] Already setup the GSI :16
[ 4.048025] e1000e 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 4.048082] e1000e 0000:02:00.0: setting latency timer to 64
[ 4.078641] SP5100 TCO timer: SP5100 TCO WatchDog Timer Driver v0.01
[ 4.078763] SP5100 TCO timer: mmio address 0xfec000f0 already in use
[ 4.095660] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 4.095897] xen: registering gsi 17 triggering 0 polarity 1
[ 4.095903] xen_map_pirq_gsi: returning irq 17 for gsi 17
[ 4.095907] xen: --> pirq=17 -> irq=17 (gsi=17)
[ 4.095912] Already setup the GSI :17
[ 4.095917] ehci_hcd 0000:00:12.2: PCI INT B -> GSI 17 (level, low) -> IRQ 17
[ 4.095957] ehci_hcd 0000:00:12.2: EHCI Host Controller
[ 4.095999] ehci_hcd 0000:00:12.2: new USB bus registered, assigned bus number 1
[ 4.096018] ehci_hcd 0000:00:12.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[ 4.096101] ehci_hcd 0000:00:12.2: debug port 1
[ 4.096150] ehci_hcd 0000:00:12.2: irq 17, io mem 0xfdaff800
[ 4.104756] MCE: In-kernel MCE decoding enabled.
[ 4.108149] ehci_hcd 0000:00:12.2: USB 2.0 started, EHCI 1.00
[ 4.108196] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 4.108200] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.108204] usb usb1: Product: EHCI Host Controller
[ 4.108207] usb usb1: Manufacturer: Linux 3.0.0-1-amd64 ehci_hcd
[ 4.108210] usb usb1: SerialNumber: 0000:00:12.2
[ 4.108520] hub 1-0:1.0: USB hub found
[ 4.108530] hub 1-0:1.0: 6 ports detected
[ 4.108861] xen: registering gsi 19 triggering 0 polarity 1
[ 4.108867] xen_map_pirq_gsi: returning irq 19 for gsi 19
[ 4.108870] xen: --> pirq=19 -> irq=19 (gsi=19)
[ 4.108875] Already setup the GSI :19
[ 4.108880] ehci_hcd 0000:00:13.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[ 4.108918] ehci_hcd 0000:00:13.2: EHCI Host Controller
[ 4.108938] ehci_hcd 0000:00:13.2: new USB bus registered, assigned bus number 2
[ 4.108951] ehci_hcd 0000:00:13.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[ 4.109039] ehci_hcd 0000:00:13.2: debug port 1
[ 4.109083] ehci_hcd 0000:00:13.2: irq 19, io mem 0xfdaff400
[ 4.120154] ehci_hcd 0000:00:13.2: USB 2.0 started, EHCI 1.00
[ 4.120220] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
[ 4.120225] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.120228] usb usb2: Product: EHCI Host Controller
[ 4.120231] usb usb2: Manufacturer: Linux 3.0.0-1-amd64 ehci_hcd
[ 4.120235] usb usb2: SerialNumber: 0000:00:13.2
[ 4.120504] hub 2-0:1.0: USB hub found
[ 4.120513] hub 2-0:1.0: 6 ports detected
[ 4.127044] Modular ISDN core version 1.1.21
[ 4.127153] NET: Registered protocol family 34
[ 4.145281] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 4.145421] xen: registering gsi 16 triggering 0 polarity 1
[ 4.145429] xen_map_pirq_gsi: returning irq 16 for gsi 16
[ 4.145432] xen: --> pirq=16 -> irq=16 (gsi=16)
[ 4.145437] Already setup the GSI :16
[ 4.145443] ohci_hcd 0000:00:12.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 4.145797] ohci_hcd 0000:00:12.0: OHCI Host Controller
[ 4.145820] ohci_hcd 0000:00:12.0: new USB bus registered, assigned bus number 3
[ 4.145911] ohci_hcd 0000:00:12.0: irq 16, io mem 0xfdafd000
[ 4.156675] e1000e 0000:02:00.0: eth1: (PCI Express:2.5GT/s:Width x1) 00:1b:21:a9:3e:f9
[ 4.156681] e1000e 0000:02:00.0: eth1: Intel(R) PRO/1000 Network Connection
[ 4.156695] e1000e 0000:02:00.0: eth1: MAC: 3, PHY: 8, PBA No: E46981-005
[ 4.156884] e1000e 0000:03:00.0: Disabling ASPM L0s
[ 4.156900] xen: registering gsi 17 triggering 0 polarity 1
[ 4.156906] xen_map_pirq_gsi: returning irq 17 for gsi 17
[ 4.156909] xen: --> pirq=17 -> irq=17 (gsi=17)
[ 4.156914] Already setup the GSI :17
[ 4.156918] e1000e 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[ 4.156945] e1000e 0000:03:00.0: setting latency timer to 64
[ 4.184433] ata6.00: ATAPI: HL-DT-STDVD-ROM GDR8164B, 0L06, max UDMA/33
[ 4.200573] ata6.00: configured for UDMA/33
[ 4.204198] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001
[ 4.204204] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.204208] usb usb3: Product: OHCI Host Controller
[ 4.204211] usb usb3: Manufacturer: Linux 3.0.0-1-amd64 ohci_hcd
[ 4.204214] usb usb3: SerialNumber: 0000:00:12.0
[ 4.204554] hub 3-0:1.0: USB hub found
[ 4.204566] hub 3-0:1.0: 3 ports detected
[ 4.204913] xen: registering gsi 16 triggering 0 polarity 1
[ 4.204920] scsi 5:0:0:0: CD-ROM HL-DT-ST DVD-ROM GDR8164B 0L06 PQ: 0 ANSI: 5
[ 4.204928] xen_map_pirq_gsi: returning irq 16 for gsi 16
[ 4.204932] xen: --> pirq=16 -> irq=16 (gsi=16)
[ 4.204936] Already setup the GSI :16
[ 4.204941] ohci_hcd 0000:00:12.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 4.205038] ohci_hcd 0000:00:12.1: OHCI Host Controller
[ 4.205059] ohci_hcd 0000:00:12.1: new USB bus registered, assigned bus number 4
[ 4.205130] ohci_hcd 0000:00:12.1: irq 16, io mem 0xfdafe000
[ 4.264185] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001
[ 4.264190] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.264194] usb usb4: Product: OHCI Host Controller
[ 4.264197] usb usb4: Manufacturer: Linux 3.0.0-1-amd64 ohci_hcd
[ 4.264201] usb usb4: SerialNumber: 0000:00:12.1
[ 4.264510] hub 4-0:1.0: USB hub found
[ 4.264524] hub 4-0:1.0: 3 ports detected
[ 4.264866] xen: registering gsi 18 triggering 0 polarity 1
[ 4.264872] xen_map_pirq_gsi: returning irq 18 for gsi 18
[ 4.264875] xen: --> pirq=18 -> irq=18 (gsi=18)
[ 4.264880] Already setup the GSI :18
[ 4.264885] ohci_hcd 0000:00:13.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 4.264993] ohci_hcd 0000:00:13.0: OHCI Host Controller
[ 4.265014] ohci_hcd 0000:00:13.0: new USB bus registered, assigned bus number 5
[ 4.265125] ohci_hcd 0000:00:13.0: irq 18, io mem 0xfdafb000
[ 4.298193] EDAC MC: Ver: 2.1.0
[ 4.300733] udev[412]: renamed network interface eth1 to eth3
[ 4.301665] e1000e 0000:03:00.0: eth1: (PCI Express:2.5GT/s:Width x1) 00:25:90:2c:9e:65
[ 4.301670] e1000e 0000:03:00.0: eth1: Intel(R) PRO/1000 Network Connection
[ 4.301754] e1000e 0000:03:00.0: eth1: MAC: 3, PHY: 8, PBA No: 0101FF-0FF
[ 4.301940] e1000e 0000:04:00.0: Disabling ASPM L0s
[ 4.301963] xen: registering gsi 18 triggering 0 polarity 1
[ 4.301971] xen_map_pirq_gsi: returning irq 18 for gsi 18
[ 4.301975] xen: --> pirq=18 -> irq=18 (gsi=18)
[ 4.301982] Already setup the GSI :18
[ 4.301987] e1000e 0000:04:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 4.302017] e1000e 0000:04:00.0: setting latency timer to 64
[ 4.307285] mISDNipac module version 2.0
[ 4.328178] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001
[ 4.328183] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.328188] usb usb5: Product: OHCI Host Controller
[ 4.328191] usb usb5: Manufacturer: Linux 3.0.0-1-amd64 ohci_hcd
[ 4.328194] usb usb5: SerialNumber: 0000:00:13.0
[ 4.328507] hub 5-0:1.0: USB hub found
[ 4.328522] hub 5-0:1.0: 3 ports detected
[ 4.328746] udev[434]: renamed network interface eth1 to eth1-eth0
[ 4.328862] xen: registering gsi 18 triggering 0 polarity 1
[ 4.328868] xen_map_pirq_gsi: returning irq 18 for gsi 18
[ 4.328871] xen: --> pirq=18 -> irq=18 (gsi=18)
[ 4.328876] Already setup the GSI :18
[ 4.328880] ohci_hcd 0000:00:13.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 4.328981] ohci_hcd 0000:00:13.1: OHCI Host Controller
[ 4.328999] ohci_hcd 0000:00:13.1: new USB bus registered, assigned bus number 6
[ 4.329073] ohci_hcd 0000:00:13.1: irq 18, io mem 0xfdafc000
[ 4.342407] AVM Fritz PCI driver Rev. 2.1
[ 4.342576] xen: registering gsi 22 triggering 0 polarity 1
[ 4.342582] xen_map_pirq_gsi: returning irq 22 for gsi 22
[ 4.342586] xen: --> pirq=22 -> irq=22 (gsi=22)
[ 4.342590] Already setup the GSI :22
[ 4.342595] fcpci 0000:05:02.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
[ 4.342599] mISDN: found adapter Fritz!Card PCI v2 at 0000:05:02.0
[ 4.342612] AVM.1: AVM Fritz!CARD PCIv2 config irq:22 base:0xE800
[ 4.364161] AVM 1 cards installed DEBUG
[ 4.388172] usb usb6: New USB device found, idVendor=1d6b, idProduct=0001
[ 4.388177] usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.388181] usb usb6: Product: OHCI Host Controller
[ 4.388185] usb usb6: Manufacturer: Linux 3.0.0-1-amd64 ohci_hcd
[ 4.388188] usb usb6: SerialNumber: 0000:00:13.1
[ 4.388531] hub 6-0:1.0: USB hub found
[ 4.388545] hub 6-0:1.0: 3 ports detected
[ 4.388927] xen: registering gsi 18 triggering 0 polarity 1
[ 4.388933] xen_map_pirq_gsi: returning irq 18 for gsi 18
[ 4.388936] xen: --> pirq=18 -> irq=18 (gsi=18)
[ 4.388941] Already setup the GSI :18
[ 4.388945] ohci_hcd 0000:00:14.5: PCI INT C -> GSI 18 (level, low) -> IRQ 18
[ 4.389035] ohci_hcd 0000:00:14.5: OHCI Host Controller
[ 4.389057] ohci_hcd 0000:00:14.5: new USB bus registered, assigned bus number 7
[ 4.389101] ohci_hcd 0000:00:14.5: irq 18, io mem 0xfdafa000
[ 4.398575] AMD64 EDAC driver v3.4.0
[ 4.408002] e1000e 0000:04:00.0: eth1: (PCI Express:2.5GT/s:Width x1) 00:25:90:2c:9e:64
[ 4.408010] e1000e 0000:04:00.0: eth1: Intel(R) PRO/1000 Network Connection
[ 4.408146] e1000e 0000:04:00.0: eth1: MAC: 3, PHY: 8, PBA No: 0101FF-0FF
[ 4.444766] udev[410]: renamed network interface eth0 to eth2
[ 4.449568] usb usb7: New USB device found, idVendor=1d6b, idProduct=0001
[ 4.449577] usb usb7: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.449581] usb usb7: Product: OHCI Host Controller
[ 4.449585] usb usb7: Manufacturer: Linux 3.0.0-1-amd64 ohci_hcd
[ 4.449588] usb usb7: SerialNumber: 0000:00:14.5
[ 4.450047] hub 7-0:1.0: USB hub found
[ 4.450067] hub 7-0:1.0: 2 ports detected
[ 4.451078] EDAC amd64: DRAM ECC enabled.
[ 4.451105] EDAC amd64: F10h detected (node 0).
[ 4.451169] EDAC MC: DCT0 chip selects:
[ 4.451173] EDAC amd64: MC: 0: 2048MB 1: 2048MB
[ 4.451177] EDAC amd64: MC: 2: 0MB 3: 0MB
[ 4.451180] EDAC amd64: MC: 4: 0MB 5: 0MB
[ 4.451183] EDAC amd64: MC: 6: 0MB 7: 0MB
[ 4.451186] EDAC MC: DCT1 chip selects:
[ 4.451189] EDAC amd64: MC: 0: 2048MB 1: 2048MB
[ 4.451193] EDAC amd64: MC: 2: 0MB 3: 0MB
[ 4.451196] EDAC amd64: MC: 4: 0MB 5: 0MB
[ 4.451199] EDAC amd64: MC: 6: 0MB 7: 0MB
[ 4.451202] EDAC amd64: using x8 syndromes.
[ 4.451206] EDAC amd64: MCT channel count: 2
[ 4.451243] EDAC amd64: CS0: Unbuffered DDR3 RAM
[ 4.451246] EDAC amd64: CS1: Unbuffered DDR3 RAM
[ 4.451590] EDAC MC0: Giving out device to 'amd64_edac' 'F10h': DEV 0000:00:18.2
[ 4.454442] EDAC amd64: DRAM ECC enabled.
[ 4.454449] EDAC amd64: F10h detected (node 1).
[ 4.454521] EDAC MC: DCT0 chip selects:
[ 4.454525] EDAC amd64: MC: 0: 2048MB 1: 2048MB
[ 4.454528] EDAC amd64: MC: 2: 0MB 3: 0MB
[ 4.454531] EDAC amd64: MC: 4: 0MB 5: 0MB
[ 4.454535] EDAC amd64: MC: 6: 0MB 7: 0MB
[ 4.454538] EDAC MC: DCT1 chip selects:
[ 4.454541] EDAC amd64: MC: 0: 2048MB 1: 2048MB
[ 4.454544] EDAC amd64: MC: 2: 0MB 3: 0MB
[ 4.454547] EDAC amd64: MC: 4: 0MB 5: 0MB
[ 4.454550] EDAC amd64: MC: 6: 0MB 7: 0MB
[ 4.454553] EDAC amd64: using x8 syndromes.
[ 4.454556] EDAC amd64: MCT channel count: 2
[ 4.454600] EDAC amd64: CS0: Unbuffered DDR3 RAM
[ 4.454603] EDAC amd64: CS1: Unbuffered DDR3 RAM
[ 4.454809] EDAC MC1: Giving out device to 'amd64_edac' 'F10h': DEV 0000:00:19.2
[ 4.454982] EDAC PCI0: Giving out device to module 'amd64_edac' controller 'EDAC PCI controller': DEV '0000:00:18.2' (POLLED)
[ 4.472783] udev[434]: renamed network interface eth1-eth0 to eth0
[ 4.476115] sr0: scsi3-mmc drive: 52x/52x cd/rw xa/form2 cdda tray
[ 4.476123] cdrom: Uniform CD-ROM driver Revision: 3.20
[ 4.476727] sr 5:0:0:0: Attached scsi CD-ROM sr0
[ 4.512364] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 4.512444] sd 1:0:0:0: Attached scsi generic sg1 type 0
[ 4.512494] sd 2:0:0:0: Attached scsi generic sg2 type 0
[ 4.512540] sd 3:0:0:0: Attached scsi generic sg3 type 0
[ 4.512591] sr 5:0:0:0: Attached scsi generic sg4 type 5
[ 4.523018] Error: Driver 'pcspkr' is already registered, aborting...
[ 4.692128] usb 5-3: new full speed USB device number 2 using ohci_hcd
[ 4.867144] usb 5-3: New USB device found, idVendor=0557, idProduct=2221
[ 4.867150] usb 5-3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 4.867154] usb 5-3: Product: Hermon USB hidmouse Device
[ 4.867158] usb 5-3: Manufacturer: Winbond Electronics Corp
[ 4.916558] input: Winbond Electronics Corp Hermon USB hidmouse Device as /devices/pci0000:00/0000:00:13.0/usb5/5-3/5-3:1.0/input/input3
[ 4.916848] generic-usb 0003:0557:2221.0001: input,hidraw0: USB HID v1.00 Mouse [Winbond Electronics Corp Hermon USB hidmouse Device] on usb-0000:00:13.0-3/input0
[ 4.920489] input: Winbond Electronics Corp Hermon USB hidmouse Device as /devices/pci0000:00/0000:00:13.0/usb5/5-3/5-3:1.1/input/input4
[ 4.920693] generic-usb 0003:0557:2221.0002: input,hidraw1: USB HID v1.00 Keyboard [Winbond Electronics Corp Hermon USB hidmouse Device] on usb-0000:00:13.0-3/input1
[ 4.920804] usbcore: registered new interface driver usbhid
[ 4.920808] usbhid: USB HID core driver
[ 4.982167] Adding 979836k swap on /dev/md2. Priority:-1 extents:1 across:979836k
[ 4.994637] EXT4-fs (md3): re-mounted. Opts: (null)
[ 5.137596] EXT4-fs (md3): re-mounted. Opts: errors=remount-ro
[ 5.197199] loop: module loaded
[ 5.232723] ipmi message handler version 39.2
[ 5.237754] IPMI System Interface driver.
[ 5.237820] ipmi_si: probing via SMBIOS
[ 5.237825] ipmi_si: SMBIOS: io 0xca2 regsize 1 spacing 1 irq 0
[ 5.237829] ipmi_si: Adding SMBIOS-specified kcs state machine
[ 5.237837] ipmi_si: Trying SMBIOS-specified kcs state machine at i/o address 0xca2, slave address 0x0, irq 0
[ 5.356128] ipmi_si: Invalid return from get global enables command, cannot enable the event buffer.
[ 5.359889] ipmi_si ipmi_si.0: Found new BMC (man_id: 0x00b980, prod_id: 0xa711, dev_id: 0x20)
[ 5.359906] ipmi_si ipmi_si.0: IPMI kcs interface initialized
[ 5.367344] ipmi device interface
[ 7.849938] EXT3-fs: barriers not enabled
[ 7.855723] kjournald starting. Commit interval 5 seconds
[ 7.860378] EXT3-fs (md4): using internal journal
[ 7.860387] EXT3-fs (md4): mounted filesystem with ordered data mode
[ 8.789960] Bridge firewalling registered
[ 8.797186] device eth0 entered promiscuous mode
[ 8.877303] ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 8.889082] ADDRCONF(NETDEV_UP): intern: link is not ready
[ 11.369117] e1000e: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx
[ 11.369599] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 11.369762] intern: port 1(eth0) entering forwarding state
[ 11.369767] intern: port 1(eth0) entering forwarding state
[ 11.370267] ADDRCONF(NETDEV_CHANGE): intern: link becomes ready
[ 12.197367] ADDRCONF(NETDEV_UP): eth1: link is not ready
[ 12.198154] e1000e 0000:04:00.0: Disabling ASPM L1
[ 12.198161] e1000e 0000:04:00.0: eth1: changing MTU from 1500 to 9000
[ 12.368097] intern: port 1(eth0) entering forwarding state
[ 12.408109] device eth2 entered promiscuous mode
[ 12.490974] ADDRCONF(NETDEV_UP): eth2: link is not ready
[ 12.502494] ADDRCONF(NETDEV_UP): extern: link is not ready
[ 14.733085] e1000e: eth2 NIC Link is Up 10 Mbps Half Duplex, Flow Control: None
[ 14.733150] e1000e 0000:01:00.0: eth2: Autonegotiated half duplex but link partner cannot autoneg. Try forcing full duplex if link gets many collisions.
[ 14.733155] e1000e 0000:01:00.0: eth2: 10/100 speed: disabling TSO
[ 14.733658] ADDRCONF(NETDEV_CHANGE): eth2: link becomes ready
[ 14.733817] extern: port 1(eth2) entering forwarding state
[ 14.733822] extern: port 1(eth2) entering forwarding state
[ 14.734237] ADDRCONF(NETDEV_CHANGE): extern: link becomes ready
[ 15.345113] e1000e: eth1 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx
[ 15.345547] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[ 15.655876] ADDRCONF(NETDEV_UP): wlan: link is not ready
[ 15.655890] device wlan entered promiscuous mode
[ 15.732165] extern: port 1(eth2) entering forwarding state
[ 15.743947] device eth3 entered promiscuous mode
[ 15.821315] ADDRCONF(NETDEV_UP): eth3: link is not ready
[ 15.833064] ADDRCONF(NETDEV_UP): dmz: link is not ready
[ 18.521082] e1000e: eth3 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx
[ 18.521575] ADDRCONF(NETDEV_CHANGE): eth3: link becomes ready
[ 18.521738] dmz: port 1(eth3) entering forwarding state
[ 18.521743] dmz: port 1(eth3) entering forwarding state
[ 18.522243] ADDRCONF(NETDEV_CHANGE): dmz: link becomes ready
[ 19.085779] RPC: Registered named UNIX socket transport module.
[ 19.085784] RPC: Registered udp transport module.
[ 19.085787] RPC: Registered tcp transport module.
[ 19.085790] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 19.123193] FS-Cache: Loaded
[ 19.153985] FS-Cache: Netfs 'nfs' registered for caching
[ 19.304924] svc: failed to register lockdv1 RPC service (errno 97).
[ 19.515239] fuse init (API version 7.16)
[ 19.520105] dmz: port 1(eth3) entering forwarding state
[ 20.061947] fcpci 0000:05:02.0: PCI INT A disabled
[ 20.062595] pci 0000:05:02.0: [1244:0e00] type 0 class 0x000280
[ 20.062638] pci 0000:05:02.0: reg 10: [mem 0xfebffc00-0xfebffc1f]
[ 20.062664] pci 0000:05:02.0: reg 14: [io 0xe800-0xe81f]
[ 20.062767] pci 0000:05:02.0: Disabling memory decoding and releasing memory resources.
[ 20.062777] pci 0000:05:02.0: Rounding up size of resource #0 to 0x1000.
[ 20.062816] pci 0000:05:02.0: supports D2
[ 20.062819] pci 0000:05:02.0: PME# supported from D2 D3hot D3cold
[ 20.062829] pci 0000:05:02.0: PME# disabled
[ 20.063025] pci 0000:05:04.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[ 20.063033] pci 0000:05:02.0: BAR 0: assigned [mem 0xfdf00000-0xfdf00fff]
[ 20.063046] pci 0000:05:02.0: BAR 0: set to [mem 0xfdf00000-0xfdf00fff] (PCI address [0xfdf00000-0xfdf00fff])
[ 20.063051] pci 0000:05:02.0: BAR 1: assigned [io 0xe000-0xe01f]
[ 20.063061] pci 0000:05:02.0: BAR 1: set to [io 0xe000-0xe01f] (PCI address [0xe000-0xe01f])
[ 20.064712] fcpci 0000:05:02.0: enabling device (0101 -> 0103)
[ 20.064727] xen: registering gsi 22 triggering 0 polarity 1
[ 20.064735] xen_map_pirq_gsi: returning irq 22 for gsi 22
[ 20.064739] xen: --> pirq=22 -> irq=22 (gsi=22)
[ 20.064745] Already setup the GSI :22
[ 20.064750] fcpci 0000:05:02.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
[ 20.064754] mISDN: found adapter Fritz!Card PCI v2 at 0000:05:02.0
[ 20.064778] AVM.1: AVM Fritz!CARD PCIv2 config irq:22 base:0xE000
[ 20.084116] AVM 1 cards installed DEBUG
[ 20.218213] Event-channel device installed.
[ 20.308430] XENBUS: Unable to read cpu state
[ 20.308653] XENBUS: Unable to read cpu state
[ 20.308848] XENBUS: Unable to read cpu state
[ 20.309069] XENBUS: Unable to read cpu state
[ 20.309232] XENBUS: Unable to read cpu state
[ 20.309406] XENBUS: Unable to read cpu state
[ 20.309563] XENBUS: Unable to read cpu state
[ 20.309714] XENBUS: Unable to read cpu state
[ 20.824955] sshd (1725): /proc/1725/oom_adj is deprecated, please use /proc/1725/oom_score_adj instead.
[ 21.122266] drbd: initialized. Version: 8.3.11 (api:88/proto:86-96)
[ 21.122272] drbd: srcversion: DA5A13F16DE6553FC7CE9B2
[ 21.122276] drbd: registered as block device major 147
[ 21.122279] drbd: minor_table @ 0xffff880001fcc600
[ 21.218661] block drbd4: Starting worker thread (from drbdsetup [1788])
[ 21.220309] block drbd4: disk( Diskless -> Attaching )
[ 21.263182] block drbd4: Found 4 transactions (106 active extents) in activity log.
[ 21.263190] block drbd4: Method to ensure write ordering: flush
[ 21.263195] block drbd4: max BIO size = 4096
[ 21.263203] block drbd4: drbd_bm_resize called with capacity == 4194104
[ 21.263250] block drbd4: resync bitmap: bits=524263 words=8192 pages=16
[ 21.263255] block drbd4: size = 2048 MB (2097052 KB)
[ 21.264269] block drbd4: bitmap READ of 16 pages took 1 jiffies
[ 21.264312] block drbd4: recounting of set bits took additional 0 jiffies
[ 21.264316] block drbd4: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.264327] block drbd4: disk( Attaching -> UpToDate )
[ 21.264331] block drbd4: attached to UUIDs ADADE964A3BCBA36:0000000000000000:77A99C1BED0834FC:98F285E3706F6779
[ 21.350730] block drbd5: Starting worker thread (from drbdsetup [1796])
[ 21.350961] block drbd5: disk( Diskless -> Attaching )
[ 21.365919] block drbd5: Found 4 transactions (127 active extents) in activity log.
[ 21.365924] block drbd5: Method to ensure write ordering: flush
[ 21.365929] block drbd5: max BIO size = 4096
[ 21.365936] block drbd5: drbd_bm_resize called with capacity == 4194104
[ 21.365974] block drbd5: resync bitmap: bits=524263 words=8192 pages=16
[ 21.365979] block drbd5: size = 2048 MB (2097052 KB)
[ 21.366975] block drbd5: bitmap READ of 16 pages took 0 jiffies
[ 21.367013] block drbd5: recounting of set bits took additional 0 jiffies
[ 21.367017] block drbd5: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.367024] block drbd5: disk( Attaching -> UpToDate )
[ 21.367029] block drbd5: attached to UUIDs 1A85EE39384808A6:0000000000000000:DF27E1F047DE30CC:99A3CB023638CB95
[ 21.397158] block drbd12: Starting worker thread (from drbdsetup [1805])
[ 21.400144] block drbd12: disk( Diskless -> Attaching )
[ 21.421351] block drbd12: Found 4 transactions (40 active extents) in activity log.
[ 21.421357] block drbd12: Method to ensure write ordering: flush
[ 21.421362] block drbd12: max BIO size = 4096
[ 21.421369] block drbd12: drbd_bm_resize called with capacity == 2506600
[ 21.421402] block drbd12: resync bitmap: bits=313325 words=4896 pages=10
[ 21.421406] block drbd12: size = 1224 MB (1253300 KB)
[ 21.422089] block drbd12: bitmap READ of 10 pages took 0 jiffies
[ 21.422116] block drbd12: recounting of set bits took additional 0 jiffies
[ 21.422121] block drbd12: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.422130] block drbd12: disk( Attaching -> UpToDate )
[ 21.422135] block drbd12: attached to UUIDs 4C9C7BF4E072F39C:0000000000000000:7971F16F42442182:289CCB228BFC0B9B
[ 21.463940] block drbd13: Starting worker thread (from drbdsetup [1813])
[ 21.464276] block drbd13: disk( Diskless -> Attaching )
[ 21.485063] block drbd13: Found 4 transactions (6 active extents) in activity log.
[ 21.485069] block drbd13: Method to ensure write ordering: flush
[ 21.485074] block drbd13: max BIO size = 4096
[ 21.485081] block drbd13: drbd_bm_resize called with capacity == 2097016
[ 21.485105] block drbd13: resync bitmap: bits=262127 words=4096 pages=8
[ 21.485109] block drbd13: size = 1024 MB (1048508 KB)
[ 21.485662] block drbd13: bitmap READ of 8 pages took 0 jiffies
[ 21.485683] block drbd13: recounting of set bits took additional 0 jiffies
[ 21.485688] block drbd13: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.485695] block drbd13: disk( Attaching -> UpToDate )
[ 21.485699] block drbd13: attached to UUIDs 100EFC9CBE465CC2:0000000000000000:638984EE5A0908EC:9F877D200E9B5DED
[ 21.521838] block drbd9: Starting worker thread (from drbdsetup [1821])
[ 21.522047] block drbd9: disk( Diskless -> Attaching )
[ 21.544761] block drbd9: Found 4 transactions (38 active extents) in activity log.
[ 21.544767] block drbd9: Method to ensure write ordering: flush
[ 21.544771] block drbd9: max BIO size = 4096
[ 21.544777] block drbd9: drbd_bm_resize called with capacity == 2097016
[ 21.544800] block drbd9: resync bitmap: bits=262127 words=4096 pages=8
[ 21.544805] block drbd9: size = 1024 MB (1048508 KB)
[ 21.545353] block drbd9: bitmap READ of 8 pages took 0 jiffies
[ 21.545374] block drbd9: recounting of set bits took additional 0 jiffies
[ 21.545379] block drbd9: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.545386] block drbd9: disk( Attaching -> UpToDate )
[ 21.545390] block drbd9: attached to UUIDs 5AD5CACAB5DAA6C2:0000000000000000:F079E875D41E2B0C:2DF98C6109EDD521
[ 21.567820] block drbd10: Starting worker thread (from drbdsetup [1829])
[ 21.568023] block drbd10: disk( Diskless -> Attaching )
[ 21.586216] block drbd10: Found 4 transactions (192 active extents) in activity log.
[ 21.586221] block drbd10: Method to ensure write ordering: flush
[ 21.586225] block drbd10: max BIO size = 4096
[ 21.586231] block drbd10: drbd_bm_resize called with capacity == 524271928
[ 21.590216] block drbd10: resync bitmap: bits=65533991 words=1023969 pages=2000
[ 21.590222] block drbd10: size = 250 GB (262135964 KB)
[ 21.669536] block drbd10: bitmap READ of 2000 pages took 20 jiffies
[ 21.673846] block drbd10: recounting of set bits took additional 1 jiffies
[ 21.673852] block drbd10: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.673862] block drbd10: disk( Attaching -> UpToDate )
[ 21.673867] block drbd10: attached to UUIDs 36679E9D7F7A4574:0000000000000000:99A9DF3596FA0214:F4D830BAF1EB5C27
[ 21.693425] block drbd16: Starting worker thread (from drbdsetup [1837])
[ 21.693667] block drbd16: disk( Diskless -> Attaching )
[ 21.709610] block drbd16: Found 4 transactions (192 active extents) in activity log.
[ 21.709616] block drbd16: Method to ensure write ordering: flush
[ 21.709620] block drbd16: max BIO size = 4096
[ 21.709627] block drbd16: drbd_bm_resize called with capacity == 33553336
[ 21.709869] block drbd16: resync bitmap: bits=4194167 words=65534 pages=128
[ 21.709874] block drbd16: size = 16 GB (16776668 KB)
[ 21.726685] block drbd16: bitmap READ of 128 pages took 4 jiffies
[ 21.726956] block drbd16: recounting of set bits took additional 0 jiffies
[ 21.726960] block drbd16: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.726968] block drbd16: disk( Attaching -> UpToDate )
[ 21.726973] block drbd16: attached to UUIDs CAAFA6B198486FB4:0000000000000000:2E231EFCB41EC6E2:1E09897FACAB4BAB
[ 21.746710] block drbd17: Starting worker thread (from drbdsetup [1845])
[ 21.746973] block drbd17: disk( Diskless -> Attaching )
[ 21.748618] block drbd17: Found 4 transactions (192 active extents) in activity log.
[ 21.748625] block drbd17: Method to ensure write ordering: flush
[ 21.748629] block drbd17: max BIO size = 4096
[ 21.748638] block drbd17: drbd_bm_resize called with capacity == 52427128
[ 21.749053] block drbd17: resync bitmap: bits=6553391 words=102397 pages=200
[ 21.749059] block drbd17: size = 25 GB (26213564 KB)
[ 21.753629] block drbd17: bitmap READ of 200 pages took 1 jiffies
[ 21.754066] block drbd17: recounting of set bits took additional 0 jiffies
[ 21.754070] block drbd17: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.754078] block drbd17: disk( Attaching -> UpToDate )
[ 21.754083] block drbd17: attached to UUIDs 00EDCA9391ADF4F2:0000000000000000:CD43868BBC7882A6:A5A7A1EF83B6D435
[ 21.760044] block drbd18: Starting worker thread (from drbdsetup [1853])
[ 21.760267] block drbd18: disk( Diskless -> Attaching )
[ 21.761686] block drbd18: Found 4 transactions (192 active extents) in activity log.
[ 21.761694] block drbd18: Method to ensure write ordering: flush
[ 21.761700] block drbd18: max BIO size = 4096
[ 21.761707] block drbd18: drbd_bm_resize called with capacity == 52427128
[ 21.762245] block drbd18: resync bitmap: bits=6553391 words=102397 pages=200
[ 21.762252] block drbd18: size = 25 GB (26213564 KB)
[ 21.767014] block drbd18: bitmap READ of 200 pages took 1 jiffies
[ 21.767504] block drbd18: recounting of set bits took additional 0 jiffies
[ 21.767509] block drbd18: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.767520] block drbd18: disk( Attaching -> UpToDate )
[ 21.767524] block drbd18: attached to UUIDs 72E92035FE61A1BC:0000000000000000:6C6F66F993CA5B02:072D080C57399B43
[ 21.773976] block drbd19: Starting worker thread (from drbdsetup [1860])
[ 21.774274] block drbd19: disk( Diskless -> Attaching )
[ 21.781470] block drbd19: Found 4 transactions (192 active extents) in activity log.
[ 21.781475] block drbd19: Method to ensure write ordering: flush
[ 21.781480] block drbd19: max BIO size = 4096
[ 21.781486] block drbd19: drbd_bm_resize called with capacity == 127922296
[ 21.782422] block drbd19: resync bitmap: bits=15990287 words=249849 pages=488
[ 21.782427] block drbd19: size = 61 GB (63961148 KB)
[ 21.806602] block drbd19: bitmap READ of 488 pages took 6 jiffies
[ 21.807621] block drbd19: recounting of set bits took additional 0 jiffies
[ 21.807627] block drbd19: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.807635] block drbd19: disk( Attaching -> UpToDate )
[ 21.807640] block drbd19: attached to UUIDs 11127A9B01124BC8:0000000000000000:6D96B71C3E9EB464:795D49BD2D2AD713
[ 21.844318] block drbd20: Starting worker thread (from drbdsetup [1871])
[ 21.844518] block drbd20: disk( Diskless -> Attaching )
[ 21.861683] block drbd20: Found 4 transactions (16 active extents) in activity log.
[ 21.861689] block drbd20: Method to ensure write ordering: flush
[ 21.861693] block drbd20: max BIO size = 4096
[ 21.861701] block drbd20: drbd_bm_resize called with capacity == 106951416
[ 21.862481] block drbd20: resync bitmap: bits=13368927 words=208890 pages=408
[ 21.862486] block drbd20: size = 51 GB (53475708 KB)
[ 21.887982] block drbd20: bitmap READ of 408 pages took 6 jiffies
[ 21.888920] block drbd20: recounting of set bits took additional 1 jiffies
[ 21.888926] block drbd20: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.888936] block drbd20: disk( Attaching -> UpToDate )
[ 21.888941] block drbd20: attached to UUIDs 9094066D5E87D9E2:0000000000000000:49986507DCBDA2B4:30CBF9BDE7924DFD
[ 21.904085] eth0: no IPv6 routers present
[ 21.932686] block drbd0: Starting worker thread (from drbdsetup [1892])
[ 21.932905] block drbd0: disk( Diskless -> Attaching )
[ 21.971695] block drbd0: Found 4 transactions (140 active extents) in activity log.
[ 21.971704] block drbd0: Method to ensure write ordering: flush
[ 21.971711] block drbd0: max BIO size = 4096
[ 21.971723] block drbd0: drbd_bm_resize called with capacity == 2097016
[ 21.971752] block drbd0: resync bitmap: bits=262127 words=4096 pages=8
[ 21.971759] block drbd0: size = 1024 MB (1048508 KB)
[ 21.972304] block drbd0: bitmap READ of 8 pages took 1 jiffies
[ 21.972325] block drbd0: recounting of set bits took additional 0 jiffies
[ 21.972329] block drbd0: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 21.972337] block drbd0: disk( Attaching -> UpToDate )
[ 21.972342] block drbd0: attached to UUIDs 1D6F7FBCF234A076:0000000000000000:8A4291061DC2C84C:540AB1380157C68D
[ 22.012614] block drbd11: Starting worker thread (from drbdsetup [1910])
[ 22.012811] block drbd11: disk( Diskless -> Attaching )
[ 22.037242] block drbd11: Found 4 transactions (192 active extents) in activity log.
[ 22.037249] block drbd11: Method to ensure write ordering: flush
[ 22.037253] block drbd11: max BIO size = 4096
[ 22.037261] block drbd11: drbd_bm_resize called with capacity == 35650424
[ 22.037528] block drbd11: resync bitmap: bits=4456303 words=69630 pages=136
[ 22.037533] block drbd11: size = 17 GB (17825212 KB)
[ 22.047163] block drbd11: bitmap READ of 136 pages took 2 jiffies
[ 22.047449] block drbd11: recounting of set bits took additional 0 jiffies
[ 22.047454] block drbd11: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 22.047463] block drbd11: disk( Attaching -> UpToDate )
[ 22.047468] block drbd11: attached to UUIDs 0E57951FC273CAA6:0000000000000000:89884E7EBF8A9884:4A94D90E7AE7C94B
[ 22.099790] block drbd6: Starting worker thread (from drbdsetup [1936])
[ 22.100004] block drbd6: disk( Diskless -> Attaching )
[ 22.139762] block drbd6: Found 4 transactions (49 active extents) in activity log.
[ 22.139768] block drbd6: Method to ensure write ordering: flush
[ 22.139774] block drbd6: max BIO size = 4096
[ 22.139781] block drbd6: drbd_bm_resize called with capacity == 4194104
[ 22.139831] block drbd6: resync bitmap: bits=524263 words=8192 pages=16
[ 22.139836] block drbd6: size = 2048 MB (2097052 KB)
[ 22.140611] block drbd6: bitmap READ of 16 pages took 1 jiffies
[ 22.140655] block drbd6: recounting of set bits took additional 0 jiffies
[ 22.140660] block drbd6: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 22.140670] block drbd6: disk( Attaching -> UpToDate )
[ 22.140675] block drbd6: attached to UUIDs 4D53F128241921BE:0000000000000000:DC0204DD03AFC92C:843C04A477CC443B
[ 22.177238] block drbd7: Starting worker thread (from drbdsetup [1961])
[ 22.177839] block drbd7: disk( Diskless -> Attaching )
[ 22.219166] block drbd7: Found 4 transactions (188 active extents) in activity log.
[ 22.219172] block drbd7: Method to ensure write ordering: flush
[ 22.219177] block drbd7: max BIO size = 4096
[ 22.219185] block drbd7: drbd_bm_resize called with capacity == 6291192
[ 22.219245] block drbd7: resync bitmap: bits=786399 words=12288 pages=24
[ 22.219250] block drbd7: size = 3072 MB (3145596 KB)
[ 22.225750] block drbd7: bitmap READ of 24 pages took 2 jiffies
[ 22.225806] block drbd7: recounting of set bits took additional 0 jiffies
[ 22.225810] block drbd7: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 22.225819] block drbd7: disk( Attaching -> UpToDate )
[ 22.225823] block drbd7: attached to UUIDs C32B547729275370:0000000000000000:F399F87DA26B521E:91CE13D3661CD893
[ 22.272121] intern: no IPv6 routers present
[ 22.278839] block drbd8: Starting worker thread (from drbdsetup [1996])
[ 22.279027] block drbd8: disk( Diskless -> Attaching )
[ 22.335089] block drbd8: Found 4 transactions (192 active extents) in activity log.
[ 22.335095] block drbd8: Method to ensure write ordering: flush
[ 22.335100] block drbd8: max BIO size = 4096
[ 22.335106] block drbd8: drbd_bm_resize called with capacity == 83883448
[ 22.335861] block drbd8: resync bitmap: bits=10485431 words=163835 pages=320
[ 22.335867] block drbd8: size = 40 GB (41941724 KB)
[ 22.351261] block drbd8: bitmap READ of 320 pages took 4 jiffies
[ 22.352129] block drbd8: recounting of set bits took additional 1 jiffies
[ 22.352134] block drbd8: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 22.352144] block drbd8: disk( Attaching -> UpToDate )
[ 22.352153] block drbd8: attached to UUIDs 816BEF9EF6DDA02C:0000000000000000:480A7A5CAD15A434:CFE0E1F2C4D5F299
[ 22.368427] block drbd15: Starting worker thread (from drbdsetup [2039])
[ 22.368644] block drbd15: disk( Diskless -> Attaching )
[ 22.477191] block drbd15: Found 4 transactions (23 active extents) in activity log.
[ 22.477197] block drbd15: Method to ensure write ordering: flush
[ 22.477203] block drbd15: max BIO size = 4096
[ 22.477212] block drbd15: drbd_bm_resize called with capacity == 3145560
[ 22.477250] block drbd15: resync bitmap: bits=393195 words=6144 pages=12
[ 22.477255] block drbd15: size = 1536 MB (1572780 KB)
[ 22.484559] block drbd15: bitmap READ of 12 pages took 2 jiffies
[ 22.484593] block drbd15: recounting of set bits took additional 0 jiffies
[ 22.484598] block drbd15: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 22.484609] block drbd15: disk( Attaching -> UpToDate )
[ 22.484613] block drbd15: attached to UUIDs 35D4F70B985F6624:0000000000000000:0F608BC467E3DFB0:CCBA03DE261F464D
[ 22.532289] block drbd1: Starting worker thread (from drbdsetup [2179])
[ 22.532511] block drbd1: disk( Diskless -> Attaching )
[ 22.577308] block drbd1: Found 4 transactions (76 active extents) in activity log.
[ 22.577315] block drbd1: Method to ensure write ordering: flush
[ 22.577319] block drbd1: max BIO size = 4096
[ 22.577327] block drbd1: drbd_bm_resize called with capacity == 2621288
[ 22.577356] block drbd1: resync bitmap: bits=327661 words=5120 pages=10
[ 22.577361] block drbd1: size = 1280 MB (1310644 KB)
[ 22.577967] block drbd1: bitmap READ of 10 pages took 0 jiffies
[ 22.577992] block drbd1: recounting of set bits took additional 0 jiffies
[ 22.577996] block drbd1: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 22.578004] block drbd1: disk( Attaching -> UpToDate )
[ 22.578009] block drbd1: attached to UUIDs 3855BCD58C4E52C8:0000000000000000:0410B8A74E50F3FE:14C4D04A89475403
[ 22.594079] block drbd2: Starting worker thread (from drbdsetup [2231])
[ 22.594356] block drbd2: disk( Diskless -> Attaching )
[ 22.618604] block drbd2: Found 4 transactions (85 active extents) in activity log.
[ 22.618610] block drbd2: Method to ensure write ordering: flush
[ 22.618615] block drbd2: max BIO size = 4096
[ 22.618622] block drbd2: drbd_bm_resize called with capacity == 1048472
[ 22.618642] block drbd2: resync bitmap: bits=131059 words=2048 pages=4
[ 22.618647] block drbd2: size = 512 MB (524236 KB)
[ 22.619015] block drbd2: bitmap READ of 4 pages took 0 jiffies
[ 22.619030] block drbd2: recounting of set bits took additional 0 jiffies
[ 22.619034] block drbd2: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 22.619043] block drbd2: disk( Attaching -> UpToDate )
[ 22.619048] block drbd2: attached to UUIDs 8CCF7E298C8F2D3E:0000000000000000:FDC151B8FDC2C738:7370109581D43D3F
[ 22.652825] block drbd3: Starting worker thread (from drbdsetup [2245])
[ 22.653019] block drbd3: disk( Diskless -> Attaching )
[ 22.716291] block drbd3: Found 1 transactions (1 active extents) in activity log.
[ 22.716298] block drbd3: Method to ensure write ordering: flush
[ 22.716303] block drbd3: max BIO size = 4096
[ 22.716311] block drbd3: drbd_bm_resize called with capacity == 1048472
[ 22.716329] block drbd3: resync bitmap: bits=131059 words=2048 pages=4
[ 22.716334] block drbd3: size = 512 MB (524236 KB)
[ 22.716634] block drbd3: bitmap READ of 4 pages took 0 jiffies
[ 22.716647] block drbd3: recounting of set bits took additional 0 jiffies
[ 22.716651] block drbd3: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 22.716660] block drbd3: disk( Attaching -> UpToDate )
[ 22.716664] block drbd3: attached to UUIDs 6F689B2C6C924FBE:0000000000000000:03EED0C0647E3E0C:9D1360B3C2C580F3
[ 22.767723] block drbd30: Starting worker thread (from drbdsetup [2259])
[ 22.767916] block drbd30: disk( Diskless -> Attaching )
[ 22.813453] block drbd30: Found 4 transactions (133 active extents) in activity log.
[ 22.813458] block drbd30: Method to ensure write ordering: flush
[ 22.813463] block drbd30: max BIO size = 4096
[ 22.813469] block drbd30: drbd_bm_resize called with capacity == 20995376
[ 22.813661] block drbd30: resync bitmap: bits=2624422 words=41007 pages=81
[ 22.813665] block drbd30: size = 10 GB (10497688 KB)
[ 22.821703] block drbd30: bitmap READ of 81 pages took 2 jiffies
[ 22.821875] block drbd30: recounting of set bits took additional 0 jiffies
[ 22.821880] block drbd30: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 22.821887] block drbd30: disk( Attaching -> UpToDate )
[ 22.821892] block drbd30: attached to UUIDs F9EEE74785722580:0000000000000000:D13FFF3979601F43:0000000000000004
[ 22.950025] block drbd4: conn( StandAlone -> Unconnected )
[ 22.950050] block drbd4: Starting receiver thread (from drbd4_worker [1790])
[ 22.950307] block drbd4: receiver (re)started
[ 22.950319] block drbd4: conn( Unconnected -> WFConnection )
[ 22.955845] block drbd5: conn( StandAlone -> Unconnected )
[ 22.955904] block drbd5: Starting receiver thread (from drbd5_worker [1799])
[ 22.956001] block drbd5: receiver (re)started
[ 22.956012] block drbd5: conn( Unconnected -> WFConnection )
[ 22.961173] block drbd12: conn( StandAlone -> Unconnected )
[ 22.961267] block drbd12: Starting receiver thread (from drbd12_worker [1808])
[ 22.962029] block drbd12: receiver (re)started
[ 22.962041] block drbd12: conn( Unconnected -> WFConnection )
[ 22.966359] block drbd13: conn( StandAlone -> Unconnected )
[ 22.966375] block drbd13: Starting receiver thread (from drbd13_worker [1815])
[ 22.966494] block drbd13: receiver (re)started
[ 22.966507] block drbd13: conn( Unconnected -> WFConnection )
[ 22.972006] block drbd9: conn( StandAlone -> Unconnected )
[ 22.972038] block drbd9: Starting receiver thread (from drbd9_worker [1823])
[ 22.972807] block drbd9: receiver (re)started
[ 22.972822] block drbd9: conn( Unconnected -> WFConnection )
[ 22.976626] block drbd10: conn( StandAlone -> Unconnected )
[ 22.976668] block drbd10: Starting receiver thread (from drbd10_worker [1831])
[ 22.976919] block drbd10: receiver (re)started
[ 22.976931] block drbd10: conn( Unconnected -> WFConnection )
[ 22.982369] block drbd16: conn( StandAlone -> Unconnected )
[ 22.982400] block drbd16: Starting receiver thread (from drbd16_worker [1839])
[ 22.982562] block drbd16: receiver (re)started
[ 22.982573] block drbd16: conn( Unconnected -> WFConnection )
[ 22.987724] block drbd17: conn( StandAlone -> Unconnected )
[ 22.987766] block drbd17: Starting receiver thread (from drbd17_worker [1847])
[ 22.987850] block drbd17: receiver (re)started
[ 22.987860] block drbd17: conn( Unconnected -> WFConnection )
[ 22.992269] block drbd18: conn( StandAlone -> Unconnected )
[ 22.992308] block drbd18: Starting receiver thread (from drbd18_worker [1854])
[ 22.992611] block drbd18: receiver (re)started
[ 22.992623] block drbd18: conn( Unconnected -> WFConnection )
[ 22.996585] block drbd19: conn( StandAlone -> Unconnected )
[ 22.996616] block drbd19: Starting receiver thread (from drbd19_worker [1863])
[ 22.996719] block drbd19: receiver (re)started
[ 22.996729] block drbd19: conn( Unconnected -> WFConnection )
[ 23.001291] block drbd20: conn( StandAlone -> Unconnected )
[ 23.001323] block drbd20: Starting receiver thread (from drbd20_worker [1873])
[ 23.001518] block drbd20: receiver (re)started
[ 23.001535] block drbd20: conn( Unconnected -> WFConnection )
[ 23.006563] block drbd0: conn( StandAlone -> Unconnected )
[ 23.006594] block drbd0: Starting receiver thread (from drbd0_worker [1894])
[ 23.006698] block drbd0: receiver (re)started
[ 23.006708] block drbd0: conn( Unconnected -> WFConnection )
[ 23.011141] block drbd11: conn( StandAlone -> Unconnected )
[ 23.011167] block drbd11: Starting receiver thread (from drbd11_worker [1912])
[ 23.011308] block drbd11: receiver (re)started
[ 23.011320] block drbd11: conn( Unconnected -> WFConnection )
[ 23.015579] block drbd6: conn( StandAlone -> Unconnected )
[ 23.015599] block drbd6: Starting receiver thread (from drbd6_worker [1938])
[ 23.015722] block drbd6: receiver (re)started
[ 23.015732] block drbd6: conn( Unconnected -> WFConnection )
[ 23.021174] block drbd7: conn( StandAlone -> Unconnected )
[ 23.021220] block drbd7: Starting receiver thread (from drbd7_worker [1963])
[ 23.022505] block drbd7: receiver (re)started
[ 23.022523] block drbd7: conn( Unconnected -> WFConnection )
[ 23.025592] block drbd8: conn( StandAlone -> Unconnected )
[ 23.025623] block drbd8: Starting receiver thread (from drbd8_worker [1998])
[ 23.026006] block drbd8: receiver (re)started
[ 23.026019] block drbd8: conn( Unconnected -> WFConnection )
[ 23.030963] block drbd15: conn( StandAlone -> Unconnected )
[ 23.030995] block drbd15: Starting receiver thread (from drbd15_worker [2041])
[ 23.032279] block drbd15: receiver (re)started
[ 23.032288] block drbd15: conn( Unconnected -> WFConnection )
[ 23.035361] block drbd1: conn( StandAlone -> Unconnected )
[ 23.035392] block drbd1: Starting receiver thread (from drbd1_worker [2184])
[ 23.035593] block drbd1: receiver (re)started
[ 23.035603] block drbd1: conn( Unconnected -> WFConnection )
[ 23.040900] block drbd2: conn( StandAlone -> Unconnected )
[ 23.040935] block drbd2: Starting receiver thread (from drbd2_worker [2236])
[ 23.041056] block drbd2: receiver (re)started
[ 23.041068] block drbd2: conn( Unconnected -> WFConnection )
[ 23.048193] block drbd3: conn( StandAlone -> Unconnected )
[ 23.048228] block drbd3: Starting receiver thread (from drbd3_worker [2247])
[ 23.048322] block drbd3: receiver (re)started
[ 23.048332] block drbd3: conn( Unconnected -> WFConnection )
[ 23.049557] block drbd4: Handshake successful: Agreed network protocol version 91
[ 23.049568] block drbd4: conn( WFConnection -> WFReportParams )
[ 23.049627] block drbd4: Starting asender thread (from drbd4_receiver [2365])
[ 23.050016] block drbd4: data-integrity-alg: <not-used>
[ 23.050160] block drbd4: drbd_sync_handshake:
[ 23.050168] block drbd4: self ADADE964A3BCBA36:0000000000000000:77A99C1BED0834FC:98F285E3706F6779 bits:0 flags:0
[ 23.050175] block drbd4: peer 4CD4000D22AE680F:ADADE964A3BCBA37:77A99C1BED0834FD:98F285E3706F6779 bits:238 flags:0
[ 23.050182] block drbd4: uuid_compare()=-1 by rule 50
[ 23.050190] block drbd4: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.052375] block drbd30: conn( StandAlone -> Unconnected )
[ 23.052393] block drbd30: Starting receiver thread (from drbd30_worker [2261])
[ 23.052498] block drbd30: receiver (re)started
[ 23.052513] block drbd30: conn( Unconnected -> WFConnection )
[ 23.061561] block drbd5: Handshake successful: Agreed network protocol version 91
[ 23.061574] block drbd5: conn( WFConnection -> WFReportParams )
[ 23.061641] block drbd5: Starting asender thread (from drbd5_receiver [2373])
[ 23.061688] block drbd12: Handshake successful: Agreed network protocol version 91
[ 23.061696] block drbd12: conn( WFConnection -> WFReportParams )
[ 23.061763] block drbd12: Starting asender thread (from drbd12_receiver [2380])
[ 23.062138] block drbd5: data-integrity-alg: <not-used>
[ 23.062185] block drbd12: data-integrity-alg: <not-used>
[ 23.062250] block drbd5: drbd_sync_handshake:
[ 23.062255] block drbd5: self 1A85EE39384808A6:0000000000000000:DF27E1F047DE30CC:99A3CB023638CB95 bits:0 flags:0
[ 23.062273] block drbd5: peer 44D59FA26A3AEA49:1A85EE39384808A7:DF27E1F047DE30CC:99A3CB023638CB95 bits:0 flags:0
[ 23.062278] block drbd5: uuid_compare()=-1 by rule 50
[ 23.062285] block drbd5: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.062339] block drbd12: drbd_sync_handshake:
[ 23.062344] block drbd12: self 4C9C7BF4E072F39C:0000000000000000:7971F16F42442182:289CCB228BFC0B9B bits:0 flags:0
[ 23.062351] block drbd12: peer B2E6852535E5A0DB:4C9C7BF4E072F39D:7971F16F42442183:289CCB228BFC0B9B bits:278 flags:0
[ 23.062355] block drbd12: uuid_compare()=-1 by rule 50
[ 23.062363] block drbd12: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.065602] block drbd13: Handshake successful: Agreed network protocol version 91
[ 23.065610] block drbd13: conn( WFConnection -> WFReportParams )
[ 23.065656] block drbd13: Starting asender thread (from drbd13_receiver [2386])
[ 23.066712] block drbd13: data-integrity-alg: <not-used>
[ 23.066834] block drbd13: drbd_sync_handshake:
[ 23.066839] block drbd13: self 100EFC9CBE465CC2:0000000000000000:638984EE5A0908EC:9F877D200E9B5DED bits:0 flags:0
[ 23.066845] block drbd13: peer 0A96BB0C3FC6326F:100EFC9CBE465CC3:638984EE5A0908EC:9F877D200E9B5DED bits:0 flags:0
[ 23.066849] block drbd13: uuid_compare()=-1 by rule 50
[ 23.066856] block drbd13: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.073820] block drbd9: Handshake successful: Agreed network protocol version 91
[ 23.073829] block drbd9: conn( WFConnection -> WFReportParams )
[ 23.073911] block drbd9: Starting asender thread (from drbd9_receiver [2392])
[ 23.074324] block drbd9: data-integrity-alg: <not-used>
[ 23.074431] block drbd9: drbd_sync_handshake:
[ 23.074437] block drbd9: self 5AD5CACAB5DAA6C2:0000000000000000:F079E875D41E2B0C:2DF98C6109EDD521 bits:0 flags:0
[ 23.074443] block drbd9: peer 7951822D15E3F61F:5AD5CACAB5DAA6C3:F079E875D41E2B0D:2DF98C6109EDD521 bits:9 flags:0
[ 23.074447] block drbd9: uuid_compare()=-1 by rule 50
[ 23.074455] block drbd9: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.077814] block drbd10: Handshake successful: Agreed network protocol version 91
[ 23.077823] block drbd10: conn( WFConnection -> WFReportParams )
[ 23.077904] block drbd10: Starting asender thread (from drbd10_receiver [2398])
[ 23.078449] block drbd10: data-integrity-alg: <not-used>
[ 23.078608] block drbd10: drbd_sync_handshake:
[ 23.078614] block drbd10: self 36679E9D7F7A4574:0000000000000000:99A9DF3596FA0214:F4D830BAF1EB5C27 bits:0 flags:0
[ 23.078619] block drbd10: peer 9CF55E404292BE29:36679E9D7F7A4575:99A9DF3596FA0214:F4D830BAF1EB5C27 bits:0 flags:0
[ 23.078623] block drbd10: uuid_compare()=-1 by rule 50
[ 23.078631] block drbd10: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.081839] block drbd16: Handshake successful: Agreed network protocol version 91
[ 23.081848] block drbd16: conn( WFConnection -> WFReportParams )
[ 23.081907] block drbd16: Starting asender thread (from drbd16_receiver [2404])
[ 23.082298] block drbd16: data-integrity-alg: <not-used>
[ 23.082530] block drbd16: drbd_sync_handshake:
[ 23.082536] block drbd16: self CAAFA6B198486FB4:0000000000000000:2E231EFCB41EC6E2:1E09897FACAB4BAB bits:0 flags:0
[ 23.082541] block drbd16: peer 8338807DFBEBA817:CAAFA6B198486FB5:2E231EFCB41EC6E3:1E09897FACAB4BAB bits:463 flags:0
[ 23.082546] block drbd16: uuid_compare()=-1 by rule 50
[ 23.082553] block drbd16: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.085784] block drbd17: Handshake successful: Agreed network protocol version 91
[ 23.085792] block drbd17: conn( WFConnection -> WFReportParams )
[ 23.085851] block drbd17: Starting asender thread (from drbd17_receiver [2410])
[ 23.086053] block drbd17: data-integrity-alg: <not-used>
[ 23.086074] block drbd17: drbd_sync_handshake:
[ 23.086079] block drbd17: self 00EDCA9391ADF4F2:0000000000000000:CD43868BBC7882A6:A5A7A1EF83B6D435 bits:0 flags:0
[ 23.086085] block drbd17: peer 5FD2340F385014D5:00EDCA9391ADF4F3:CD43868BBC7882A7:A5A7A1EF83B6D435 bits:2 flags:0
[ 23.086089] block drbd17: uuid_compare()=-1 by rule 50
[ 23.086097] block drbd17: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.090585] block drbd18: Handshake successful: Agreed network protocol version 91
[ 23.090594] block drbd18: conn( WFConnection -> WFReportParams )
[ 23.090644] block drbd18: Starting asender thread (from drbd18_receiver [2413])
[ 23.090832] block drbd18: data-integrity-alg: <not-used>
[ 23.091065] block drbd18: drbd_sync_handshake:
[ 23.091071] block drbd18: self 72E92035FE61A1BC:0000000000000000:6C6F66F993CA5B02:072D080C57399B43 bits:0 flags:0
[ 23.091076] block drbd18: peer A695310BA6680C13:72E92035FE61A1BD:6C6F66F993CA5B03:072D080C57399B43 bits:425 flags:0
[ 23.091080] block drbd18: uuid_compare()=-1 by rule 50
[ 23.091088] block drbd18: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.094554] block drbd19: Handshake successful: Agreed network protocol version 91
[ 23.094565] block drbd19: conn( WFConnection -> WFReportParams )
[ 23.094629] block drbd19: Starting asender thread (from drbd19_receiver [2418])
[ 23.095052] block drbd19: data-integrity-alg: <not-used>
[ 23.095074] block drbd19: drbd_sync_handshake:
[ 23.095079] block drbd19: self 11127A9B01124BC8:0000000000000000:6D96B71C3E9EB464:795D49BD2D2AD713 bits:0 flags:0
[ 23.095085] block drbd19: peer 3DE85EB610AF1911:11127A9B01124BC9:6D96B71C3E9EB465:795D49BD2D2AD713 bits:12 flags:0
[ 23.095090] block drbd19: uuid_compare()=-1 by rule 50
[ 23.095097] block drbd19: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.102388] block drbd20: Handshake successful: Agreed network protocol version 91
[ 23.102398] block drbd20: conn( WFConnection -> WFReportParams )
[ 23.102467] block drbd20: Starting asender thread (from drbd20_receiver [2424])
[ 23.102643] block drbd20: data-integrity-alg: <not-used>
[ 23.103248] block drbd20: drbd_sync_handshake:
[ 23.103254] block drbd20: self 9094066D5E87D9E2:0000000000000000:49986507DCBDA2B4:30CBF9BDE7924DFD bits:0 flags:0
[ 23.103261] block drbd20: peer 9B42DFB140B66AC7:9094066D5E87D9E3:49986507DCBDA2B5:30CBF9BDE7924DFD bits:0 flags:0
[ 23.103265] block drbd20: uuid_compare()=-1 by rule 50
[ 23.103274] block drbd20: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.105553] block drbd17: conn( WFBitMapT -> WFSyncUUID )
[ 23.106298] block drbd0: Handshake successful: Agreed network protocol version 91
[ 23.106304] block drbd0: conn( WFConnection -> WFReportParams )
[ 23.106371] block drbd0: Starting asender thread (from drbd0_receiver [2431])
[ 23.106581] block drbd0: data-integrity-alg: <not-used>
[ 23.107283] block drbd0: drbd_sync_handshake:
[ 23.107288] block drbd0: self 1D6F7FBCF234A076:0000000000000000:8A4291061DC2C84C:540AB1380157C68D bits:0 flags:0
[ 23.107294] block drbd0: peer 9E6AF05319F3AFCB:1D6F7FBCF234A077:8A4291061DC2C84C:540AB1380157C68D bits:264 flags:0
[ 23.107299] block drbd0: uuid_compare()=-1 by rule 50
[ 23.107306] block drbd0: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.109548] block drbd11: Handshake successful: Agreed network protocol version 91
[ 23.109556] block drbd11: conn( WFConnection -> WFReportParams )
[ 23.109621] block drbd11: Starting asender thread (from drbd11_receiver [2435])
[ 23.110233] block drbd11: data-integrity-alg: <not-used>
[ 23.110759] block drbd11: drbd_sync_handshake:
[ 23.110764] block drbd11: self 0E57951FC273CAA6:0000000000000000:89884E7EBF8A9884:4A94D90E7AE7C94B bits:0 flags:0
[ 23.110770] block drbd11: peer 57946C733550AAC3:0E57951FC273CAA7:89884E7EBF8A9885:4A94D90E7AE7C94B bits:1 flags:0
[ 23.110775] block drbd11: uuid_compare()=-1 by rule 50
[ 23.110784] block drbd11: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.110852] block drbd17: updated sync uuid F78ADD414AA45D62:0000000000000000:CD43868BBC7882A6:A5A7A1EF83B6D435
[ 23.111161] block drbd17: helper command: /sbin/drbdadm before-resync-target minor-17
[ 23.111783] block drbd18: conn( WFBitMapT -> WFSyncUUID )
[ 23.113635] block drbd6: Handshake successful: Agreed network protocol version 91
[ 23.113644] block drbd6: conn( WFConnection -> WFReportParams )
[ 23.113707] block drbd6: Starting asender thread (from drbd6_receiver [2441])
[ 23.114170] block drbd6: data-integrity-alg: <not-used>
[ 23.114938] block drbd6: drbd_sync_handshake:
[ 23.114946] block drbd6: self 4D53F128241921BE:0000000000000000:DC0204DD03AFC92C:843C04A477CC443B bits:0 flags:0
[ 23.114951] block drbd6: peer E5C9633072F61E4B:4D53F128241921BF:DC0204DD03AFC92C:843C04A477CC443B bits:245 flags:0
[ 23.114955] block drbd6: uuid_compare()=-1 by rule 50
[ 23.114963] block drbd6: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.115506] block drbd18: updated sync uuid 0A7B04A90BE11EC2:0000000000000000:6C6F66F993CA5B02:072D080C57399B43
[ 23.115773] block drbd18: helper command: /sbin/drbdadm before-resync-target minor-18
[ 23.119559] block drbd17: helper command: /sbin/drbdadm before-resync-target minor-17 exit code 0 (0x0)
[ 23.119567] block drbd17: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.119590] block drbd17: Began resync as SyncTarget (will sync 8 KB [2 bits set]).
[ 23.121721] block drbd7: Handshake successful: Agreed network protocol version 91
[ 23.121730] block drbd7: conn( WFConnection -> WFReportParams )
[ 23.121793] block drbd7: Starting asender thread (from drbd7_receiver [2448])
[ 23.122225] block drbd7: data-integrity-alg: <not-used>
[ 23.122442] block drbd7: drbd_sync_handshake:
[ 23.122447] block drbd7: self C32B547729275370:0000000000000000:F399F87DA26B521E:91CE13D3661CD893 bits:0 flags:0
[ 23.122453] block drbd7: peer 32E5D2ADEF2A6093:C32B547729275371:F399F87DA26B521F:91CE13D3661CD893 bits:189 flags:0
[ 23.122458] block drbd7: uuid_compare()=-1 by rule 50
[ 23.122465] block drbd7: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.123368] block drbd18: helper command: /sbin/drbdadm before-resync-target minor-18 exit code 0 (0x0)
[ 23.123376] block drbd18: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.123399] block drbd18: Began resync as SyncTarget (will sync 1700 KB [425 bits set]).
[ 23.123635] block drbd17: Resync done (total 1 sec; paused 0 sec; 8 K/sec)
[ 23.123645] block drbd17: updated UUIDs 5FD2340F385014D4:0000000000000000:F78ADD414AA45D62:00EDCA9391ADF4F3
[ 23.123655] block drbd17: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 23.124013] block drbd17: helper command: /sbin/drbdadm after-resync-target minor-17
[ 23.125769] block drbd8: Handshake successful: Agreed network protocol version 91
[ 23.125778] block drbd8: conn( WFConnection -> WFReportParams )
[ 23.125805] block drbd8: Starting asender thread (from drbd8_receiver [2452])
[ 23.126765] block drbd8: data-integrity-alg: <not-used>
[ 23.126933] block drbd8: drbd_sync_handshake:
[ 23.126938] block drbd8: self 816BEF9EF6DDA02C:0000000000000000:480A7A5CAD15A434:CFE0E1F2C4D5F299 bits:0 flags:0
[ 23.126943] block drbd8: peer 279CF37B0E5E3A93:816BEF9EF6DDA02D:480A7A5CAD15A434:CFE0E1F2C4D5F299 bits:147 flags:0
[ 23.126948] block drbd8: uuid_compare()=-1 by rule 50
[ 23.126955] block drbd8: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.129524] block drbd15: Handshake successful: Agreed network protocol version 91
[ 23.129533] block drbd15: conn( WFConnection -> WFReportParams )
[ 23.129594] block drbd15: Starting asender thread (from drbd15_receiver [2458])
[ 23.130069] block drbd15: data-integrity-alg: <not-used>
[ 23.130220] block drbd15: drbd_sync_handshake:
[ 23.130225] block drbd15: self 35D4F70B985F6624:0000000000000000:0F608BC467E3DFB0:CCBA03DE261F464D bits:0 flags:0
[ 23.130230] block drbd15: peer 35D4F70B985F6624:0000000000000000:0F608BC467E3DFB1:CCBA03DE261F464D bits:0 flags:0
[ 23.130235] block drbd15: uuid_compare()=0 by rule 40
[ 23.130242] block drbd15: peer( Unknown -> Secondary ) conn( WFReportParams -> Connected ) pdsk( DUnknown -> UpToDate )
[ 23.132011] block drbd17: helper command: /sbin/drbdadm after-resync-target minor-17 exit code 0 (0x0)
[ 23.132152] block drbd13: conn( WFBitMapT -> WFSyncUUID )
[ 23.132348] block drbd9: conn( WFBitMapT -> WFSyncUUID )
[ 23.133024] block drbd12: conn( WFBitMapT -> WFSyncUUID )
[ 23.133241] block drbd4: conn( WFBitMapT -> WFSyncUUID )
[ 23.133341] block drbd5: conn( WFBitMapT -> WFSyncUUID )
[ 23.133938] block drbd1: Handshake successful: Agreed network protocol version 91
[ 23.133947] block drbd1: conn( WFConnection -> WFReportParams )
[ 23.134013] block drbd1: Starting asender thread (from drbd1_receiver [2462])
[ 23.134199] block drbd1: data-integrity-alg: <not-used>
[ 23.134530] block drbd1: drbd_sync_handshake:
[ 23.134542] block drbd1: self 3855BCD58C4E52C8:0000000000000000:0410B8A74E50F3FE:14C4D04A89475403 bits:0 flags:0
[ 23.134547] block drbd1: peer 2B1BDA1754B13F1F:3855BCD58C4E52C9:0410B8A74E50F3FE:14C4D04A89475403 bits:13 flags:0
[ 23.134552] block drbd1: uuid_compare()=-1 by rule 50
[ 23.134560] block drbd1: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.134660] block drbd4: updated sync uuid 95F5885207E7A7D2:0000000000000000:77A99C1BED0834FC:98F285E3706F6779
[ 23.137339] block drbd17: bitmap WRITE of 199 pages took 2 jiffies
[ 23.139842] block drbd17: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 23.142283] block drbd2: Handshake successful: Agreed network protocol version 91
[ 23.142294] block drbd2: conn( WFConnection -> WFReportParams )
[ 23.142399] block drbd2: Starting asender thread (from drbd2_receiver [2469])
[ 23.142667] block drbd2: data-integrity-alg: <not-used>
[ 23.143069] block drbd2: drbd_sync_handshake:
[ 23.143076] block drbd2: self 8CCF7E298C8F2D3E:0000000000000000:FDC151B8FDC2C738:7370109581D43D3F bits:0 flags:0
[ 23.143082] block drbd2: peer 051D31FB6B46654B:8CCF7E298C8F2D3F:FDC151B8FDC2C739:7370109581D43D3F bits:34 flags:0
[ 23.143086] block drbd2: uuid_compare()=-1 by rule 50
[ 23.143094] block drbd2: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.145735] block drbd3: Handshake successful: Agreed network protocol version 91
[ 23.145744] block drbd3: conn( WFConnection -> WFReportParams )
[ 23.145805] block drbd3: Starting asender thread (from drbd3_receiver [2476])
[ 23.145957] block drbd3: data-integrity-alg: <not-used>
[ 23.146254] block drbd3: drbd_sync_handshake:
[ 23.146260] block drbd3: self 6F689B2C6C924FBE:0000000000000000:03EED0C0647E3E0C:9D1360B3C2C580F3 bits:0 flags:0
[ 23.146266] block drbd3: peer 7F3F6CE7B5F74F55:6F689B2C6C924FBF:03EED0C0647E3E0C:9D1360B3C2C580F3 bits:0 flags:0
[ 23.146271] block drbd3: uuid_compare()=-1 by rule 50
[ 23.146280] block drbd3: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) disk( UpToDate -> Outdated ) pdsk( DUnknown -> UpToDate )
[ 23.154009] block drbd30: Handshake successful: Agreed network protocol version 91
[ 23.154021] block drbd30: conn( WFConnection -> WFReportParams )
[ 23.154095] block drbd30: Starting asender thread (from drbd30_receiver [2481])
[ 23.154309] block drbd30: data-integrity-alg: <not-used>
[ 23.154737] block drbd30: drbd_sync_handshake:
[ 23.154743] block drbd30: self F9EEE74785722580:0000000000000000:D13FFF3979601F43:0000000000000004 bits:0 flags:0
[ 23.154750] block drbd30: peer F9EEE74785722580:0000000000000000:D13FFF3979601F42:0000000000000004 bits:0 flags:0
[ 23.154755] block drbd30: uuid_compare()=0 by rule 40
[ 23.154764] block drbd30: peer( Unknown -> Secondary ) conn( WFReportParams -> Connected ) pdsk( DUnknown -> UpToDate )
[ 23.163159] block drbd18: Resync done (total 1 sec; paused 0 sec; 1700 K/sec)
[ 23.163168] block drbd18: updated UUIDs A695310BA6680C12:0000000000000000:0A7B04A90BE11EC2:72E92035FE61A1BD
[ 23.163177] block drbd18: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 23.163535] block drbd18: helper command: /sbin/drbdadm after-resync-target minor-18
[ 23.170914] block drbd18: helper command: /sbin/drbdadm after-resync-target minor-18 exit code 0 (0x0)
[ 23.175672] block drbd18: bitmap WRITE of 199 pages took 1 jiffies
[ 23.176541] block drbd18: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 23.224650] block drbd0: conn( WFBitMapT -> WFSyncUUID )
[ 23.226176] block drbd6: conn( WFBitMapT -> WFSyncUUID )
[ 23.227538] block drbd7: conn( WFBitMapT -> WFSyncUUID )
[ 23.280269] block drbd3: conn( WFBitMapT -> WFSyncUUID )
[ 23.280316] block drbd4: helper command: /sbin/drbdadm before-resync-target minor-4
[ 23.282862] block drbd1: conn( WFBitMapT -> WFSyncUUID )
[ 23.288132] block drbd4: helper command: /sbin/drbdadm before-resync-target minor-4 exit code 0 (0x0)
[ 23.288143] block drbd4: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.288159] block drbd4: Began resync as SyncTarget (will sync 952 KB [238 bits set]).
[ 23.293698] block drbd5: updated sync uuid F05E504935728EDC:0000000000000000:DF27E1F047DE30CC:99A3CB023638CB95
[ 23.294061] block drbd12: updated sync uuid F0E3CFF54A1423A4:0000000000000000:7971F16F42442182:289CCB228BFC0B9B
[ 23.331729] block drbd12: helper command: /sbin/drbdadm before-resync-target minor-12
[ 23.331758] block drbd5: helper command: /sbin/drbdadm before-resync-target minor-5
[ 23.339889] block drbd12: helper command: /sbin/drbdadm before-resync-target minor-12 exit code 0 (0x0)
[ 23.339900] block drbd12: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.339919] block drbd12: Began resync as SyncTarget (will sync 1112 KB [278 bits set]).
[ 23.344328] block drbd5: helper command: /sbin/drbdadm before-resync-target minor-5 exit code 0 (0x0)
[ 23.344338] block drbd5: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.344354] block drbd5: Began resync as SyncTarget (will sync 0 KB [0 bits set]).
[ 23.346006] block drbd5: Resync done (total 1 sec; paused 0 sec; 0 K/sec)
[ 23.346014] block drbd5: updated UUIDs 44D59FA26A3AEA48:0000000000000000:F05E504935728EDC:1A85EE39384808A7
[ 23.346021] block drbd5: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 23.375161] block drbd5: helper command: /sbin/drbdadm after-resync-target minor-5
[ 23.382982] block drbd5: helper command: /sbin/drbdadm after-resync-target minor-5 exit code 0 (0x0)
[ 23.383679] block drbd5: bitmap WRITE of 16 pages took 0 jiffies
[ 23.384838] block drbd2: conn( WFBitMapT -> WFSyncUUID )
[ 23.391564] block drbd5: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 23.456110] block drbd13: updated sync uuid 648C1BA835CCED78:0000000000000000:638984EE5A0908EC:9F877D200E9B5DED
[ 23.467453] block drbd13: helper command: /sbin/drbdadm before-resync-target minor-13
[ 23.475125] block drbd13: helper command: /sbin/drbdadm before-resync-target minor-13 exit code 0 (0x0)
[ 23.475135] block drbd13: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.475156] block drbd13: Began resync as SyncTarget (will sync 0 KB [0 bits set]).
[ 23.475729] block drbd13: Resync done (total 1 sec; paused 0 sec; 0 K/sec)
[ 23.475737] block drbd13: updated UUIDs 0A96BB0C3FC6326E:0000000000000000:648C1BA835CCED78:100EFC9CBE465CC3
[ 23.475744] block drbd13: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 23.534148] block drbd13: helper command: /sbin/drbdadm after-resync-target minor-13
[ 23.542068] block drbd13: helper command: /sbin/drbdadm after-resync-target minor-13 exit code 0 (0x0)
[ 23.542513] block drbd13: bitmap WRITE of 8 pages took 0 jiffies
[ 23.550280] block drbd9: updated sync uuid F795FFFF8001543E:0000000000000000:F079E875D41E2B0C:2DF98C6109EDD521
[ 23.550559] block drbd13: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 23.554991] block drbd16: conn( WFBitMapT -> WFSyncUUID )
[ 23.556562] block drbd16: updated sync uuid 7C320F5A9FB9A5B4:0000000000000000:2E231EFCB41EC6E2:1E09897FACAB4BAB
[ 23.563396] block drbd9: helper command: /sbin/drbdadm before-resync-target minor-9
[ 23.570894] block drbd9: helper command: /sbin/drbdadm before-resync-target minor-9 exit code 0 (0x0)
[ 23.570903] block drbd9: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.570927] block drbd9: Began resync as SyncTarget (will sync 36 KB [9 bits set]).
[ 23.575328] block drbd16: helper command: /sbin/drbdadm before-resync-target minor-16
[ 23.582795] block drbd16: helper command: /sbin/drbdadm before-resync-target minor-16 exit code 0 (0x0)
[ 23.582805] block drbd16: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.582827] block drbd16: Began resync as SyncTarget (will sync 1852 KB [463 bits set]).
[ 23.639024] block drbd10: conn( WFBitMapT -> WFSyncUUID )
[ 23.655379] block drbd10: updated sync uuid C29CDD4415A00776:0000000000000000:99A9DF3596FA0214:F4D830BAF1EB5C27
[ 23.671408] block drbd10: helper command: /sbin/drbdadm before-resync-target minor-10
[ 23.679181] block drbd10: helper command: /sbin/drbdadm before-resync-target minor-10 exit code 0 (0x0)
[ 23.679191] block drbd10: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.679208] block drbd10: Began resync as SyncTarget (will sync 0 KB [0 bits set]).
[ 23.679914] block drbd10: Resync done (total 1 sec; paused 0 sec; 0 K/sec)
[ 23.679923] block drbd10: updated UUIDs 9CF55E404292BE28:0000000000000000:C29CDD4415A00776:36679E9D7F7A4575
[ 23.679930] block drbd10: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 23.696379] block drbd10: helper command: /sbin/drbdadm after-resync-target minor-10
[ 23.703946] block drbd10: helper command: /sbin/drbdadm after-resync-target minor-10 exit code 0 (0x0)
[ 23.754350] block drbd0: updated sync uuid 1FF840265D23C36C:0000000000000000:8A4291061DC2C84C:540AB1380157C68D
[ 23.755048] block drbd6: updated sync uuid AFCC7186611458D2:0000000000000000:DC0204DD03AFC92C:843C04A477CC443B
[ 23.759785] block drbd11: conn( WFBitMapT -> WFSyncUUID )
[ 23.766713] block drbd11: updated sync uuid C4B52B2ADE463148:0000000000000000:89884E7EBF8A9884:4A94D90E7AE7C94B
[ 23.772914] block drbd19: conn( WFBitMapT -> WFSyncUUID )
[ 23.774580] block drbd20: conn( WFBitMapT -> WFSyncUUID )
[ 23.774801] block drbd10: bitmap WRITE of 2000 pages took 18 jiffies
[ 23.784810] block drbd20: updated sync uuid 128B0E7F83705A18:0000000000000000:49986507DCBDA2B4:30CBF9BDE7924DFD
[ 23.787631] block drbd19: updated sync uuid 2DC076D72D1B7738:0000000000000000:6D96B71C3E9EB464:795D49BD2D2AD713
[ 23.803968] block drbd7: updated sync uuid 0C77B99A34989202:0000000000000000:F399F87DA26B521E:91CE13D3661CD893
[ 23.810341] block drbd10: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 23.885033] block drbd0: helper command: /sbin/drbdadm before-resync-target minor-0
[ 23.892645] block drbd2: updated sync uuid 1988383E7BB57E04:0000000000000000:FDC151B8FDC2C738:7370109581D43D3F
[ 23.892911] block drbd0: helper command: /sbin/drbdadm before-resync-target minor-0 exit code 0 (0x0)
[ 23.892920] block drbd0: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.892941] block drbd0: Began resync as SyncTarget (will sync 1056 KB [264 bits set]).
[ 23.893215] block drbd1: updated sync uuid 3A58E819C95E95BA:0000000000000000:0410B8A74E50F3FE:14C4D04A89475403
[ 23.907708] block drbd6: helper command: /sbin/drbdadm before-resync-target minor-6
[ 23.907724] block drbd19: helper command: /sbin/drbdadm before-resync-target minor-19
[ 23.907734] block drbd20: helper command: /sbin/drbdadm before-resync-target minor-20
[ 23.907744] block drbd11: helper command: /sbin/drbdadm before-resync-target minor-11
[ 23.907753] block drbd7: helper command: /sbin/drbdadm before-resync-target minor-7
[ 23.915630] block drbd20: helper command: /sbin/drbdadm before-resync-target minor-20 exit code 0 (0x0)
[ 23.915646] block drbd20: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.915675] block drbd20: Began resync as SyncTarget (will sync 0 KB [0 bits set]).
[ 23.915902] block drbd6: helper command: /sbin/drbdadm before-resync-target minor-6 exit code 0 (0x0)
[ 23.915912] block drbd7: helper command: /sbin/drbdadm before-resync-target minor-7 exit code 0 (0x0)
[ 23.915917] block drbd6: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.915940] block drbd6: Began resync as SyncTarget (will sync 980 KB [245 bits set]).
[ 23.915946] block drbd7: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.915983] block drbd7: Began resync as SyncTarget (will sync 756 KB [189 bits set]).
[ 23.916105] block drbd20: Resync done (total 1 sec; paused 0 sec; 0 K/sec)
[ 23.916111] block drbd20: updated UUIDs 9B42DFB140B66AC6:0000000000000000:128B0E7F83705A18:9094066D5E87D9E3
[ 23.916117] block drbd20: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 23.920518] block drbd19: helper command: /sbin/drbdadm before-resync-target minor-19 exit code 0 (0x0)
[ 23.920529] block drbd19: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.920546] block drbd19: Began resync as SyncTarget (will sync 48 KB [12 bits set]).
[ 23.920812] block drbd11: helper command: /sbin/drbdadm before-resync-target minor-11 exit code 0 (0x0)
[ 23.920822] block drbd11: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.920840] block drbd11: Began resync as SyncTarget (will sync 4 KB [1 bits set]).
[ 23.942380] block drbd1: helper command: /sbin/drbdadm before-resync-target minor-1
[ 23.942418] block drbd2: helper command: /sbin/drbdadm before-resync-target minor-2
[ 23.944900] block drbd3: updated sync uuid 0EABE11D0DADAEDC:0000000000000000:03EED0C0647E3E0C:9D1360B3C2C580F3
[ 23.950734] block drbd1: helper command: /sbin/drbdadm before-resync-target minor-1 exit code 0 (0x0)
[ 23.950745] block drbd1: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.950766] block drbd1: Began resync as SyncTarget (will sync 52 KB [13 bits set]).
[ 23.951522] block drbd2: helper command: /sbin/drbdadm before-resync-target minor-2 exit code 0 (0x0)
[ 23.951529] block drbd2: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 23.951543] block drbd2: Began resync as SyncTarget (will sync 136 KB [34 bits set]).
[ 23.952537] block drbd8: conn( WFBitMapT -> WFSyncUUID )
[ 23.958447] block drbd8: updated sync uuid E3642142CEB8C656:0000000000000000:480A7A5CAD15A434:CFE0E1F2C4D5F299
[ 24.006939] block drbd20: helper command: /sbin/drbdadm after-resync-target minor-20
[ 24.014663] block drbd20: helper command: /sbin/drbdadm after-resync-target minor-20 exit code 0 (0x0)
[ 24.154646] block drbd8: helper command: /sbin/drbdadm before-resync-target minor-8
[ 24.154678] block drbd3: helper command: /sbin/drbdadm before-resync-target minor-3
[ 24.155500] block drbd4: Resync done (total 1 sec; paused 0 sec; 952 K/sec)
[ 24.155514] block drbd4: updated UUIDs 4CD4000D22AE680E:0000000000000000:95F5885207E7A7D2:ADADE964A3BCBA37
[ 24.155527] block drbd4: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 24.162851] block drbd3: helper command: /sbin/drbdadm before-resync-target minor-3 exit code 0 (0x0)
[ 24.162866] block drbd3: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 24.162890] block drbd3: Began resync as SyncTarget (will sync 0 KB [0 bits set]).
[ 24.163105] block drbd8: helper command: /sbin/drbdadm before-resync-target minor-8 exit code 0 (0x0)
[ 24.163115] block drbd8: conn( WFSyncUUID -> SyncTarget ) disk( Outdated -> Inconsistent )
[ 24.163128] block drbd8: Began resync as SyncTarget (will sync 588 KB [147 bits set]).
[ 24.163634] block drbd3: Resync done (total 1 sec; paused 0 sec; 0 K/sec)
[ 24.163642] block drbd3: updated UUIDs 7F3F6CE7B5F74F54:0000000000000000:0EABE11D0DADAEDC:6F689B2C6C924FBF
[ 24.163650] block drbd3: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 24.188318] block drbd20: bitmap WRITE of 408 pages took 44 jiffies
[ 24.370211] block drbd20: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 24.411874] block drbd4: helper command: /sbin/drbdadm after-resync-target minor-4
[ 24.419633] block drbd4: helper command: /sbin/drbdadm after-resync-target minor-4 exit code 0 (0x0)
[ 24.582103] block drbd3: helper command: /sbin/drbdadm after-resync-target minor-3
[ 24.590425] block drbd3: helper command: /sbin/drbdadm after-resync-target minor-3 exit code 0 (0x0)
[ 24.651275] block drbd19: Resync done (total 1 sec; paused 0 sec; 48 K/sec)
[ 24.651287] block drbd19: updated UUIDs 3DE85EB610AF1910:0000000000000000:2DC076D72D1B7738:11127A9B01124BC9
[ 24.651297] block drbd19: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 24.651328] block drbd2: Resync done (total 1 sec; paused 0 sec; 136 K/sec)
[ 24.651334] block drbd2: updated UUIDs 051D31FB6B46654A:0000000000000000:1988383E7BB57E04:8CCF7E298C8F2D3F
[ 24.651341] block drbd2: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 25.035146] block drbd6: Resync done (total 1 sec; paused 0 sec; 980 K/sec)
[ 25.035156] block drbd6: updated UUIDs E5C9633072F61E4A:0000000000000000:AFCC7186611458D2:4D53F128241921BF
[ 25.035166] block drbd6: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 25.040094] eth2: no IPv6 routers present
[ 25.104201] block drbd7: Resync done (total 1 sec; paused 0 sec; 756 K/sec)
[ 25.104211] block drbd7: updated UUIDs 32E5D2ADEF2A6092:0000000000000000:0C77B99A34989202:C32B547729275371
[ 25.104223] block drbd7: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 25.183917] block drbd2: helper command: /sbin/drbdadm after-resync-target minor-2
[ 25.183924] block drbd19: helper command: /sbin/drbdadm after-resync-target minor-19
[ 25.191680] block drbd19: helper command: /sbin/drbdadm after-resync-target minor-19 exit code 0 (0x0)
[ 25.191882] block drbd2: helper command: /sbin/drbdadm after-resync-target minor-2 exit code 0 (0x0)
[ 25.214407] block drbd3: bitmap WRITE of 4 pages took 156 jiffies
[ 25.401827] block drbd7: helper command: /sbin/drbdadm after-resync-target minor-7
[ 25.401849] block drbd6: helper command: /sbin/drbdadm after-resync-target minor-6
[ 25.401941] block drbd3: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 25.402723] block drbd12: Resync done (total 2 sec; paused 0 sec; 556 K/sec)
[ 25.402733] block drbd12: updated UUIDs B2E6852535E5A0DA:0000000000000000:F0E3CFF54A1423A4:4C9C7BF4E072F39D
[ 25.402741] block drbd11: Resync done (total 1 sec; paused 0 sec; 4 K/sec)
[ 25.402752] block drbd11: updated UUIDs 57946C733550AAC2:0000000000000000:C4B52B2ADE463148:0E57951FC273CAA7
[ 25.402759] block drbd12: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 25.402769] block drbd9: Resync done (total 1 sec; paused 0 sec; 36 K/sec)
[ 25.402778] block drbd9: updated UUIDs 7951822D15E3F61E:0000000000000000:F795FFFF8001543E:5AD5CACAB5DAA6C3
[ 25.402783] block drbd11: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 25.402798] block drbd9: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 25.403153] block drbd1: Resync done (total 1 sec; paused 0 sec; 52 K/sec)
[ 25.403166] block drbd1: updated UUIDs 2B1BDA1754B13F1E:0000000000000000:3A58E819C95E95BA:3855BCD58C4E52C9
[ 25.403175] block drbd1: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 25.410304] block drbd6: helper command: /sbin/drbdadm after-resync-target minor-6 exit code 0 (0x0)
[ 25.411151] block drbd7: helper command: /sbin/drbdadm after-resync-target minor-7 exit code 0 (0x0)
[ 25.599604] block drbd2: bitmap WRITE of 0 pages took 0 jiffies
[ 25.612115] extern: no IPv6 routers present
[ 25.663565] block drbd9: helper command: /sbin/drbdadm after-resync-target minor-9
[ 25.663574] block drbd1: helper command: /sbin/drbdadm after-resync-target minor-1
[ 25.663581] block drbd11: helper command: /sbin/drbdadm after-resync-target minor-11
[ 25.663590] block drbd12: helper command: /sbin/drbdadm after-resync-target minor-12
[ 25.663706] block drbd2: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 25.671072] block drbd11: helper command: /sbin/drbdadm after-resync-target minor-11 exit code 0 (0x0)
[ 25.674643] block drbd12: helper command: /sbin/drbdadm after-resync-target minor-12 exit code 0 (0x0)
[ 25.674732] block drbd9: helper command: /sbin/drbdadm after-resync-target minor-9 exit code 0 (0x0)
[ 25.674956] block drbd1: helper command: /sbin/drbdadm after-resync-target minor-1 exit code 0 (0x0)
[ 25.680486] block drbd19: bitmap WRITE of 485 pages took 70 jiffies
[ 25.702126] block drbd4: bitmap WRITE of 7 pages took 75 jiffies
[ 25.745150] block drbd4: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 25.745181] block drbd19: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 25.752440] block drbd7: bitmap WRITE of 22 pages took 86 jiffies
[ 25.825879] block drbd7: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 25.849263] block drbd6: bitmap WRITE of 13 pages took 63 jiffies
[ 25.849386] block drbd9: bitmap WRITE of 6 pages took 29 jiffies
[ 25.945866] block drbd12: bitmap WRITE of 2 pages took 50 jiffies
[ 25.945873] block drbd11: bitmap WRITE of 135 pages took 69 jiffies
[ 25.983724] block drbd6: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 25.983869] block drbd9: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 25.993521] block drbd12: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 25.993542] block drbd11: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 26.011780] block drbd8: Resync done (total 1 sec; paused 0 sec; 588 K/sec)
[ 26.011790] block drbd8: updated UUIDs 279CF37B0E5E3A92:0000000000000000:E3642142CEB8C656:816BEF9EF6DDA02D
[ 26.011800] block drbd8: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 26.088666] block drbd1: bitmap WRITE of 7 pages took 86 jiffies
[ 26.092165] block drbd8: helper command: /sbin/drbdadm after-resync-target minor-8
[ 26.099314] block drbd8: helper command: /sbin/drbdadm after-resync-target minor-8 exit code 0 (0x0)
[ 26.112083] eth1: no IPv6 routers present
[ 26.131376] block drbd1: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 26.166263] block drbd0: Resync done (total 2 sec; paused 0 sec; 528 K/sec)
[ 26.166273] block drbd0: updated UUIDs 9E6AF05319F3AFCA:0000000000000000:1FF840265D23C36C:1D6F7FBCF234A077
[ 26.166282] block drbd0: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 26.184694] block drbd8: bitmap WRITE of 316 pages took 22 jiffies
[ 26.235918] block drbd8: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 26.249513] block drbd0: helper command: /sbin/drbdadm after-resync-target minor-0
[ 26.256812] block drbd0: helper command: /sbin/drbdadm after-resync-target minor-0 exit code 0 (0x0)
[ 26.273778] block drbd0: bitmap WRITE of 3 pages took 4 jiffies
[ 26.319605] block drbd0: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 26.548597] block drbd16: Resync done (total 2 sec; paused 0 sec; 924 K/sec)
[ 26.548608] block drbd16: updated UUIDs 8338807DFBEBA816:0000000000000000:7C320F5A9FB9A5B4:CAAFA6B198486FB5
[ 26.548619] block drbd16: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
[ 26.652665] block drbd16: helper command: /sbin/drbdadm after-resync-target minor-16
[ 26.660127] block drbd16: helper command: /sbin/drbdadm after-resync-target minor-16 exit code 0 (0x0)
[ 26.674376] block drbd16: bitmap WRITE of 103 pages took 1 jiffies
[ 26.754126] block drbd16: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 28.952099] dmz: no IPv6 routers present
[ 29.512120] eth3: no IPv6 routers present
[ 30.431614] device vif1.0 entered promiscuous mode
[ 30.436962] ADDRCONF(NETDEV_UP): vif1.0: link is not ready
[ 30.451846] device vif1.1 entered promiscuous mode
[ 30.456854] ADDRCONF(NETDEV_UP): vif1.1: link is not ready
[ 30.542178] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 30.590428] device vif1.2 entered promiscuous mode
[ 30.595002] ADDRCONF(NETDEV_UP): vif1.2: link is not ready
[ 31.212777] xen-blkback:ring-ref 8, event-channel 6, protocol 2 (x86_32-abi)
[ 31.230451] ADDRCONF(NETDEV_CHANGE): vif1.0: link becomes ready
[ 31.230508] intern: port 2(vif1.0) entering forwarding state
[ 31.230513] intern: port 2(vif1.0) entering forwarding state
[ 31.238135] ADDRCONF(NETDEV_CHANGE): vif1.1: link becomes ready
[ 31.238174] extern: port 2(vif1.1) entering forwarding state
[ 31.238179] extern: port 2(vif1.1) entering forwarding state
[ 31.244015] ADDRCONF(NETDEV_CHANGE): vif1.2: link becomes ready
[ 31.244097] dmz: port 2(vif1.2) entering forwarding state
[ 31.244102] dmz: port 2(vif1.2) entering forwarding state
[ 32.228115] intern: port 2(vif1.0) entering forwarding state
[ 32.236116] extern: port 2(vif1.1) entering forwarding state
[ 32.244112] dmz: port 2(vif1.2) entering forwarding state
[ 41.920079] vif1.1: no IPv6 routers present
[ 41.920085] vif1.2: no IPv6 routers present
[ 42.116111] vif1.0: no IPv6 routers present
[ 387.001811] xen-blkback:ring-ref 8, event-channel 75, protocol 1 (x86_64-abi)
[ 387.182164] block drbd15: role( Secondary -> Primary )
[ 387.464639] blkfront: xvdp: barrier or flush: disabled
[ 387.467171] xvdp: unknown partition table
[ 389.180239] device vif2.0 entered promiscuous mode
[ 389.184736] ADDRCONF(NETDEV_UP): vif2.0: link is not ready
[ 389.718671] xen-blkback:ring-ref 8, event-channel 13, protocol 1 (x86_64-abi)
[ 389.733634] ADDRCONF(NETDEV_CHANGE): vif2.0: link becomes ready
[ 389.733696] intern: port 3(vif2.0) entering forwarding state
[ 389.733702] intern: port 3(vif2.0) entering forwarding state
[ 390.732113] intern: port 3(vif2.0) entering forwarding state
[ 400.072080] vif2.0: no IPv6 routers present
[ 422.355882] intern: port 3(vif2.0) entering forwarding state
[ 422.356112] intern: port 3(vif2.0) entering disabled state
[ 422.674115] block drbd15: role( Primary -> Secondary )
[ 422.674183] block drbd15: bitmap WRITE of 0 pages took 0 jiffies
[ 422.691821] block drbd15: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 426.274215] device vif3.0 entered promiscuous mode
[ 426.279895] ADDRCONF(NETDEV_UP): vif3.0: link is not ready
[ 426.311529] block drbd15: role( Secondary -> Primary )
[ 436.191693] xen-blkback:ring-ref 8, event-channel 13, protocol 1 (x86_64-abi)
[ 436.256294] ADDRCONF(NETDEV_CHANGE): vif3.0: link becomes ready
[ 436.256362] intern: port 3(vif3.0) entering forwarding state
[ 436.256369] intern: port 3(vif3.0) entering forwarding state
[ 437.256078] intern: port 3(vif3.0) entering forwarding state
[ 446.496119] vif3.0: no IPv6 routers present
[ 455.624594] intern: port 3(vif3.0) entering forwarding state
[ 455.625204] intern: port 3(vif3.0) entering disabled state
[ 455.829853] block drbd15: role( Primary -> Secondary )
[ 455.829919] block drbd15: bitmap WRITE of 0 pages took 0 jiffies
[ 455.893113] block drbd15: 0 KB (0 bits) marked out-of-sync by on disk bit-map.
[ 483.330038] dmz: port 2(vif1.2) entering forwarding state
[ 483.330250] dmz: port 2(vif1.2) entering disabled state
[ 483.387501] extern: port 2(vif1.1) entering forwarding state
[ 483.387918] extern: port 2(vif1.1) entering disabled state
[ 483.430698] intern: port 2(vif1.0) entering forwarding state
[ 483.430820] intern: port 2(vif1.0) entering disabled state
[ 487.821951] device vif4.0 entered promiscuous mode
[ 487.826562] ADDRCONF(NETDEV_UP): vif4.0: link is not ready
[ 487.848865] block drbd15: role( Secondary -> Primary )
[ 497.344710] xen-blkback:ring-ref 8, event-channel 13, protocol 1 (x86_64-abi)
[ 497.441815] ADDRCONF(NETDEV_CHANGE): vif4.0: link becomes ready
[ 497.441875] intern: port 2(vif4.0) entering forwarding state
[ 497.441881] intern: port 2(vif4.0) entering forwarding state
[ 498.440111] intern: port 2(vif4.0) entering forwarding state
[ 508.048127] vif4.0: no IPv6 routers present
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: ### ----- xc domain builder logfile opened -----
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_allocate: cmdline="root=/dev/hda1 ro ", features=""
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_kernel_file: filename="/var/run/xend/boot/boot_kernel.NC-MEW"
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_malloc_filemap : 2294 kB
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_malloc : 5113 kB
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_do_gunzip: unzip ok, 0x23dac4 -> 0x4fe5b0
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_ramdisk_file: filename="/var/run/xend/boot/boot_ramdisk.Y_ENCk"
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_malloc_filemap : 232 kB
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_boot_xen_init: ver 4.1, caps xen-3.0-x86_64 xen-3.0-x86_32p hvm-3.0-x86_32 hvm-3.0-x86_32p hvm-3.0-x86_64
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_parse_image: called
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_find_loader: trying multiboot-binary loader ...
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: loader probe failed
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_find_loader: trying Linux bzImage loader ...
2011-08-16 01:59:31 CEST [1921] xc: error: panic: xc_dom_bzimageloader.c:556: xc_dom_probe_bzimage_kernel: kernel is not a bzImage: Invalid kernel
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: loader probe failed
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_find_loader: trying ELF-generic loader ...
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: loader probe OK
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x1000000 memsz=0x2ff890
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x1300000 memsz=0x139000
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_parse_binary: memory: 0x1000000 -> 0x1439000
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: GUEST_OS = "linux"
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: GUEST_VERSION = "2.6"
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: XEN_VERSION = "xen-3.0"
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: VIRT_BASE = 0xc0000000
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: PADDR_OFFSET = 0x0
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: ENTRY = 0xc1000000
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: HYPERCALL_PAGE = 0xc1001000
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: HV_START_LOW = 0xf5800000
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: FEATURES = "writable_page_tables|writable_descriptor_tables|auto_translated_physmap|pae_pgdir_above_4gb|supervisor_mode_kernel"
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: PAE_MODE = "yes"
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: unknown xen elf note (0xd)
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: LOADER = "generic"
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_parse_note: SUSPEND_CANCEL = 0x1
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_xen_addr_calc_check: addresses:
2011-08-16 01:59:31 CEST [1921] xc: detail: virt_base = 0xc0000000
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_paddr_offset = 0x0
2011-08-16 01:59:31 CEST [1921] xc: detail: virt_offset = 0xc0000000
2011-08-16 01:59:31 CEST [1921] xc: detail: virt_kstart = 0xc1000000
2011-08-16 01:59:31 CEST [1921] xc: detail: virt_kend = 0xc1439000
2011-08-16 01:59:31 CEST [1921] xc: detail: virt_entry = 0xc1000000
2011-08-16 01:59:31 CEST [1921] xc: detail: p2m_base = 0xffffffffffffffff
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_parse_elf_kernel: xen-3.0-x86_32p: 0xc1000000 -> 0xc1439000
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_mem_init: mem 128 MB, pages 0x8000 pages, 4k each
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_mem_init: 0x8000 pages
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_boot_mem_init: called
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: x86_compat: guest xen-3.0-x86_32p, address size 32
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_malloc : 256 kB
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_build_image: called
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_alloc_segment: kernel : 0xc1000000 -> 0xc1439000 (pfn 0x1000 + 0x439 pages)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x1000+0x439 at 0x7f069e0f2000
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_load_binary: phdr 0 at 0x0x7f069e0f2000 -> 0x0x7f069e3f1890
2011-08-16 01:59:31 CEST [1921] xc: detail: elf_load_binary: phdr 1 at 0x0x7f069e3f2000 -> 0x0x7f069e46cb64
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_alloc_segment: ramdisk : 0xc1439000 -> 0xc14d0000 (pfn 0x1439 + 0x97 pages)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x1439+0x97 at 0x7f069e05b000
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_do_gunzip: unzip ok, 0x3a288 -> 0x96210
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_alloc_segment: phys2mach : 0xc14d0000 -> 0xc14f0000 (pfn 0x14d0 + 0x20 pages)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x14d0+0x20 at 0x7f06a58ae000
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_alloc_page : start info : 0xc14f0000 (pfn 0x14f0)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_alloc_page : xenstore : 0xc14f1000 (pfn 0x14f1)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_alloc_page : console : 0xc14f2000 (pfn 0x14f2)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: nr_page_tables: 0x00000000ffffffff/32: 0x0000000000000000 -> 0x00000000ffffffff, 1 table(s)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: nr_page_tables: 0x000000003fffffff/30: 0x00000000c0000000 -> 0x00000000ffffffff, 1 table(s)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: nr_page_tables: 0x00000000001fffff/21: 0x00000000c0000000 -> 0x00000000c17fffff, 12 table(s)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_alloc_segment: page tables : 0xc14f3000 -> 0xc1501000 (pfn 0x14f3 + 0xe pages)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x14f3+0xe at 0x7f06a58a0000
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_alloc_page : boot stack : 0xc1501000 (pfn 0x1501)
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_build_image : virt_alloc_end : 0xc1502000
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_build_image : virt_pgtab_end : 0xc1800000
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_boot_image: called
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: arch_setup_bootearly: doing nothing
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: xen-3.0-x86_64
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: xen-3.0-x86_32p <= matches
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: hvm-3.0-x86_32
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: hvm-3.0-x86_32p
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: hvm-3.0-x86_64
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_update_guest_p2m: dst 32bit, pages 0x8000
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: clear_page: pfn 0x14f2, mfn 0x41ee19
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: clear_page: pfn 0x14f1, mfn 0x21cfb3
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x14f0+0x1 at 0x7f06a589f000
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: start_info_x86_32: called
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: setup_hypercall_page: vaddr=0xc1001000 pfn=0x1001
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: domain builder memory footprint
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: allocated
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: malloc : 5402 kB
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: anon mmap : 0 bytes
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: mapped
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: file mmap : 2527 kB
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: domU mmap : 5116 kB
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: arch_setup_bootlate: shared_info: pfn 0x0, mfn 0xdfc74
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: shared_info_x86_32: called
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: vcpu_x86_32: called
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: vcpu_x86_32: cr3: pfn 0x14f3 mfn 0x21cfb2
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: launch_vm: called, ctxt=0x7f069ef65a10
2011-08-16 01:59:31 CEST [1921] domainbuilder: detail: xc_dom_release: called
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_allocate: cmdline="root=/dev/xvda1 ro quiet ", features=""
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_kernel_file: filename="/var/run/xend/boot/boot_kernel.kciRLL"
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_malloc_filemap : 2417 kB
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_parse_image: called
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_find_loader: trying multiboot-binary loader ...
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: loader probe failed
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_find_loader: trying Linux bzImage loader ...
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_malloc : 11850 kB
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_do_gunzip: unzip ok, 0x254e77 -> 0xb92b00
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: loader probe OK
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x1000000 memsz=0x444000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x1444000 memsz=0xac080
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x14f1000 memsz=0x888
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x14f2000 memsz=0x160d8
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x1509000 memsz=0x1b1000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: memory: 0x1000000 -> 0x16ba000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: GUEST_OS = "linux"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: GUEST_VERSION = "2.6"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: XEN_VERSION = "xen-3.0"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: VIRT_BASE = 0xffffffff80000000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: ENTRY = 0xffffffff81509200
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: HYPERCALL_PAGE = 0xffffffff81009000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: FEATURES = "!writable_page_tables|pae_pgdir_above_4gb"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: PAE_MODE = "yes"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: LOADER = "generic"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: unknown xen elf note (0xd)
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: SUSPEND_CANCEL = 0x1
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: HV_START_LOW = 0xffff800000000000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: PADDR_OFFSET = 0x0
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_addr_calc_check: addresses:
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_base = 0xffffffff80000000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_paddr_offset = 0x0
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_offset = 0xffffffff80000000
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_kstart = 0xffffffff81000000
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_kend = 0xffffffff816ba000
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_entry = 0xffffffff81509200
2011-08-16 02:05:28 CEST [1921] xc: detail: p2m_base = 0xffffffffffffffff
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_parse_elf_kernel: xen-3.0-x86_64: 0xffffffff81000000 -> 0xffffffff816ba000
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_release: called
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_allocate: cmdline="root=/dev/xvda1 ro quiet ", features=""
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_kernel_file: filename="/var/run/xend/boot/boot_kernel.kciRLL"
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_malloc_filemap : 2417 kB
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_ramdisk_file: filename="/var/run/xend/boot/boot_ramdisk.Z2pO51"
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_malloc_filemap : 8907 kB
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_boot_xen_init: ver 4.1, caps xen-3.0-x86_64 xen-3.0-x86_32p hvm-3.0-x86_32 hvm-3.0-x86_32p hvm-3.0-x86_64
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_parse_image: called
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_find_loader: trying multiboot-binary loader ...
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: loader probe failed
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_find_loader: trying Linux bzImage loader ...
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_malloc : 11850 kB
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_do_gunzip: unzip ok, 0x254e77 -> 0xb92b00
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: loader probe OK
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x1000000 memsz=0x444000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x1444000 memsz=0xac080
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x14f1000 memsz=0x888
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x14f2000 memsz=0x160d8
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: phdr: paddr=0x1509000 memsz=0x1b1000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_parse_binary: memory: 0x1000000 -> 0x16ba000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: GUEST_OS = "linux"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: GUEST_VERSION = "2.6"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: XEN_VERSION = "xen-3.0"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: VIRT_BASE = 0xffffffff80000000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: ENTRY = 0xffffffff81509200
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: HYPERCALL_PAGE = 0xffffffff81009000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: FEATURES = "!writable_page_tables|pae_pgdir_above_4gb"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: PAE_MODE = "yes"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: LOADER = "generic"
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: unknown xen elf note (0xd)
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: SUSPEND_CANCEL = 0x1
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: HV_START_LOW = 0xffff800000000000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_parse_note: PADDR_OFFSET = 0x0
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_xen_addr_calc_check: addresses:
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_base = 0xffffffff80000000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_paddr_offset = 0x0
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_offset = 0xffffffff80000000
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_kstart = 0xffffffff81000000
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_kend = 0xffffffff816ba000
2011-08-16 02:05:28 CEST [1921] xc: detail: virt_entry = 0xffffffff81509200
2011-08-16 02:05:28 CEST [1921] xc: detail: p2m_base = 0xffffffffffffffff
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_parse_elf_kernel: xen-3.0-x86_64: 0xffffffff81000000 -> 0xffffffff816ba000
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_mem_init: mem 512 MB, pages 0x20000 pages, 4k each
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_mem_init: 0x20000 pages
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_boot_mem_init: called
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: x86_compat: guest xen-3.0-x86_64, address size 64
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_malloc : 1024 kB
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_build_image: called
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_alloc_segment: kernel : 0xffffffff81000000 -> 0xffffffff816ba000 (pfn 0x1000 + 0x6ba pages)
2011-08-16 02:05:28 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x1000+0x6ba at 0x7f069d59f000
2011-08-16 02:05:28 CEST [1921] xc: detail: elf_load_binary: phdr 0 at 0x0x7f069d59f000 -> 0x0x7f069d9e3000
2011-08-16 02:05:29 CEST [1921] xc: detail: elf_load_binary: phdr 1 at 0x0x7f069d9e3000 -> 0x0x7f069da8f080
2011-08-16 02:05:29 CEST [1921] xc: detail: elf_load_binary: phdr 2 at 0x0x7f069da90000 -> 0x0x7f069da90888
2011-08-16 02:05:29 CEST [1921] xc: detail: elf_load_binary: phdr 3 at 0x0x7f069da91000 -> 0x0x7f069daa70d8
2011-08-16 02:05:29 CEST [1921] xc: detail: elf_load_binary: phdr 4 at 0x0x7f069daa8000 -> 0x0x7f069db31000
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_alloc_segment: ramdisk : 0xffffffff816ba000 -> 0xffffffff83049000 (pfn 0x16ba + 0x198f pages)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_malloc : 153 kB
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x16ba+0x198f at 0x7f0696671000
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_do_gunzip: unzip ok, 0x8b2d19 -> 0x198e210
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_alloc_segment: phys2mach : 0xffffffff83049000 -> 0xffffffff83149000 (pfn 0x3049 + 0x100 pages)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x3049+0x100 at 0x7f069d49f000
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_alloc_page : start info : 0xffffffff83149000 (pfn 0x3149)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_alloc_page : xenstore : 0xffffffff8314a000 (pfn 0x314a)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_alloc_page : console : 0xffffffff8314b000 (pfn 0x314b)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: nr_page_tables: 0x0000ffffffffffff/48: 0xffff000000000000 -> 0xffffffffffffffff, 1 table(s)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: nr_page_tables: 0x0000007fffffffff/39: 0xffffff8000000000 -> 0xffffffffffffffff, 1 table(s)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: nr_page_tables: 0x000000003fffffff/30: 0xffffffff80000000 -> 0xffffffffbfffffff, 1 table(s)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: nr_page_tables: 0x00000000001fffff/21: 0xffffffff80000000 -> 0xffffffff833fffff, 26 table(s)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_alloc_segment: page tables : 0xffffffff8314c000 -> 0xffffffff83169000 (pfn 0x314c + 0x1d pages)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x314c+0x1d at 0x7f069d482000
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_alloc_page : boot stack : 0xffffffff83169000 (pfn 0x3169)
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_build_image : virt_alloc_end : 0xffffffff8316a000
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_build_image : virt_pgtab_end : 0xffffffff83400000
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_boot_image: called
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: arch_setup_bootearly: doing nothing
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: xen-3.0-x86_64 <= matches
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: xen-3.0-x86_32p
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: hvm-3.0-x86_32
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: hvm-3.0-x86_32p
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_compat_check: supported guest type: hvm-3.0-x86_64
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_update_guest_p2m: dst 64bit, pages 0x20000
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: clear_page: pfn 0x314b, mfn 0x214186
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: clear_page: pfn 0x314a, mfn 0x40d7c8
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_pfn_to_ptr: domU mapping: pfn 0x3149+0x1 at 0x7f069d481000
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: start_info_x86_64: called
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: setup_hypercall_page: vaddr=0xffffffff81009000 pfn=0x1009
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: domain builder memory footprint
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: allocated
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: malloc : 13077 kB
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: anon mmap : 0 bytes
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: mapped
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: file mmap : 11325 kB
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: domU mmap : 33 MB
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: arch_setup_bootlate: shared_info: pfn 0x0, mfn 0xdfc71
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: shared_info_x86_64: called
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: vcpu_x86_64: called
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: vcpu_x86_64: cr3: pfn 0x314c mfn 0x40d7c7
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: launch_vm: called, ctxt=0x7f069ef65a10
2011-08-16 02:05:29 CEST [1921] domainbuilder: detail: xc_dom_release: called
[2011-08-16 01:59:23 1921] INFO (SrvDaemon:332) Xend Daemon started
[2011-08-16 01:59:23 1921] INFO (SrvDaemon:336) Xend changeset: unavailable.
[2011-08-16 01:59:23 1921] DEBUG (tcp:96) Listening on :8002
[2011-08-16 01:59:25 1921] DEBUG (XendNode:332) pscsi record count: 16
[2011-08-16 01:59:25 1921] DEBUG (XendCPUPool:747) recreate_active_pools
[2011-08-16 01:59:27 1921] DEBUG (XendDomainInfo:151) XendDomainInfo.recreate({'max_vcpu_id': 7, 'cpu_time': 17914643096L, 'ssidref': 0, 'hvm': 0, 'shutdown_reason': 255, 'dying': 0, 'online_vcpus': 8, 'domid': 0, 'paused': 0, 'crashed': 0, 'running': 1, 'maxmem_kb': 17179869180L, 'shutdown': 0, 'mem_kb': 524288L, 'blocked': 0, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'cpupool': 0, 'name': 'Domain-0'})
[2011-08-16 01:59:27 1921] INFO (XendDomainInfo:169) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000. at /local/domain/0
[2011-08-16 01:59:27 1921] DEBUG (XendDomainInfo:3420) Storing VM details: {'on_xend_stop': 'ignore', 'pool_name': 'Pool-0', 'shadow_memory': '0', 'uuid': '00000000-0000-0000-0000-000000000000', 'on_reboot': 'restart', 'image': "(linux (kernel '') (superpages 0) (nomigrate 0) (tsc_mode 0))", 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '8', 'vcpu_avail': '255', 'bootloader': '', 'name': 'Domain-0'}
[2011-08-16 01:59:27 1921] DEBUG (XendDomainInfo:1794) Storing domain details: {'cpu/3/availability': 'online', 'description': '', 'console/limit': '1048576', 'memory/target': '524288', 'cpu/2/availability': 'online', 'vm': '/vm/00000000-0000-0000-0000-000000000000', 'domid': '0', 'cpu/7/availability': 'online', 'cpu/0/availability': 'online', 'cpu/1/availability': 'online', 'cpu/5/availability': 'online', 'control/platform-feature-multiprocessor-suspend': '1', 'cpu/6/availability': 'online', 'console/type': 'xenconsoled', 'cpu/4/availability': 'online', 'name': 'Domain-0'}
[2011-08-16 01:59:27 1921] DEBUG (XendDomain:476) Adding Domain: 0
[2011-08-16 01:59:27 1921] DEBUG (XendDomain:410) number of vcpus to use is 0
[2011-08-16 01:59:28 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 01:59:28 1921] WARNING (XendAPI:708) API call: VBD.set_device not found
[2011-08-16 01:59:28 1921] WARNING (XendAPI:708) API call: VBD.set_type not found
[2011-08-16 01:59:28 1921] WARNING (XendAPI:708) API call: session.get_all_records not found
[2011-08-16 01:59:28 1921] WARNING (XendAPI:708) API call: event.get_record not found
[2011-08-16 01:59:28 1921] WARNING (XendAPI:708) API call: event.get_all not found
[2011-08-16 01:59:28 1921] WARNING (XendAPI:708) API call: VIF.set_device not found
[2011-08-16 01:59:28 1921] WARNING (XendAPI:708) API call: VIF.set_MAC not found
[2011-08-16 01:59:28 1921] WARNING (XendAPI:708) API call: VIF.set_MTU not found
[2011-08-16 01:59:28 1921] WARNING (XendAPI:708) API call: debug.get_all not found
[2011-08-16 01:59:28 1921] INFO (XMLRPCServer:161) Opening Unix domain socket XML-RPC server on /var/run/xend/xen-api.sock; authentication has been disabled for this server.
[2011-08-16 01:59:28 1921] INFO (XMLRPCServer:161) Opening Unix domain socket XML-RPC server on /var/run/xend/xmlrpc.sock.
[2011-08-16 01:59:30 1921] DEBUG (XendDomainInfo:103) XendDomainInfo.create(['vm', ['name', 'hottie'], ['memory', '128'], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['vcpus', 1], ['cpus', '2-7'], ['oos', 1], ['bootloader', '/usr/lib/xen-default/bin/pygrub'], ['bootloader_args', '-q'], ['image', ['linux', ['videoram', 4], ['tsc_mode', 0], ['nomigrate', 0]]], ['s3_integrity', 1], ['device', ['vbd', ['uname', 'phy:/dev/lv/hottie'], ['dev', 'hda1'], ['mode', 'w']]], ['device', ['vif', ['bridge', 'intern']]], ['device', ['vif', ['bridge', 'extern']]], ['device', ['vif', ['bridge', 'dmz']]]])
[2011-08-16 01:59:30 1921] DEBUG (XendDomainInfo:2498) XendDomainInfo.constructDomain
[2011-08-16 01:59:30 1921] DEBUG (balloon:187) Balloon: 16063216 KiB free; need 16384; done.
[2011-08-16 01:59:30 1921] DEBUG (XendDomain:476) Adding Domain: 1
[2011-08-16 01:59:30 1921] DEBUG (XendDomainInfo:2836) XendDomainInfo.initDomain: 1 256
[2011-08-16 01:59:30 3075] DEBUG (XendBootloader:113) Launching bootloader as ['/usr/lib/xen-default/bin/pygrub', '--output=/var/run/xend/boot/xenbl.24073', '-q', '/dev/lv/hottie'].
[2011-08-16 01:59:30 1921] DEBUG (XendDomainInfo:2863) _initDomain:shadow_memory=0x0, memory_static_max=0x8000000, memory_static_min=0x0.
[2011-08-16 01:59:31 1921] INFO (image:182) buildDomain os=linux dom=1 vcpus=1
[2011-08-16 01:59:31 1921] DEBUG (image:721) domid = 1
[2011-08-16 01:59:31 1921] DEBUG (image:722) memsize = 128
[2011-08-16 01:59:31 1921] DEBUG (image:723) image = /var/run/xend/boot/boot_kernel.NC-MEW
[2011-08-16 01:59:31 1921] DEBUG (image:724) store_evtchn = 1
[2011-08-16 01:59:31 1921] DEBUG (image:725) console_evtchn = 2
[2011-08-16 01:59:31 1921] DEBUG (image:726) cmdline = root=/dev/hda1 ro
[2011-08-16 01:59:31 1921] DEBUG (image:727) ramdisk = /var/run/xend/boot/boot_ramdisk.Y_ENCk
[2011-08-16 01:59:31 1921] DEBUG (image:728) vcpus = 1
[2011-08-16 01:59:31 1921] DEBUG (image:729) features =
[2011-08-16 01:59:31 1921] DEBUG (image:730) flags = 0
[2011-08-16 01:59:31 1921] DEBUG (image:731) superpages = 0
[2011-08-16 01:59:31 1921] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': '25d42949-13db-ebb6-3108-16a5cb723aeb', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda1', 'uname': 'phy:/dev/lv/hottie', 'mode': 'w'}
[2011-08-16 01:59:31 1921] DEBUG (DevController:95) DevController: writing {'virtual-device': '769', 'device-type': 'disk', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vbd/1/769'} to /local/domain/1/device/vbd/769.
[2011-08-16 01:59:31 1921] DEBUG (DevController:97) DevController: writing {'domain': 'hottie', 'frontend': '/local/domain/1/device/vbd/769', 'uuid': '25d42949-13db-ebb6-3108-16a5cb723aeb', 'bootable': '1', 'dev': 'hda1', 'state': '1', 'params': '/dev/lv/hottie', 'mode': 'w', 'online': '1', 'frontend-id': '1', 'type': 'phy'} to /local/domain/0/backend/vbd/1/769.
[2011-08-16 01:59:31 1921] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'intern', 'mac': '00:16:3e:70:cb:bd', 'uuid': '0f200c19-879f-ac05-46ac-51b6aca19508'}
[2011-08-16 01:59:31 1921] DEBUG (DevController:95) DevController: writing {'mac': '00:16:3e:70:cb:bd', 'handle': '0', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/1/0'} to /local/domain/1/device/vif/0.
[2011-08-16 01:59:31 1921] DEBUG (DevController:97) DevController: writing {'bridge': 'intern', 'domain': 'hottie', 'handle': '0', 'uuid': '0f200c19-879f-ac05-46ac-51b6aca19508', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:70:cb:bd', 'frontend-id': '1', 'state': '1', 'online': '1', 'frontend': '/local/domain/1/device/vif/0'} to /local/domain/0/backend/vif/1/0.
[2011-08-16 01:59:31 1921] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'extern', 'mac': '00:16:3e:34:aa:5e', 'uuid': '28218c6a-3f18-6bc9-137c-aa4900e714dc'}
[2011-08-16 01:59:31 1921] DEBUG (DevController:95) DevController: writing {'mac': '00:16:3e:34:aa:5e', 'handle': '1', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/1/1'} to /local/domain/1/device/vif/1.
[2011-08-16 01:59:31 1921] DEBUG (DevController:97) DevController: writing {'bridge': 'extern', 'domain': 'hottie', 'handle': '1', 'uuid': '28218c6a-3f18-6bc9-137c-aa4900e714dc', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:34:aa:5e', 'frontend-id': '1', 'state': '1', 'online': '1', 'frontend': '/local/domain/1/device/vif/1'} to /local/domain/0/backend/vif/1/1.
[2011-08-16 01:59:31 1921] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'dmz', 'mac': '00:16:3e:3b:df:99', 'uuid': '59a8cc06-c76d-e5f7-aab5-9c9173a043b0'}
[2011-08-16 01:59:31 1921] DEBUG (DevController:95) DevController: writing {'mac': '00:16:3e:3b:df:99', 'handle': '2', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/1/2'} to /local/domain/1/device/vif/2.
[2011-08-16 01:59:31 1921] DEBUG (DevController:97) DevController: writing {'bridge': 'dmz', 'domain': 'hottie', 'handle': '2', 'uuid': '59a8cc06-c76d-e5f7-aab5-9c9173a043b0', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:3b:df:99', 'frontend-id': '1', 'state': '1', 'online': '1', 'frontend': '/local/domain/1/device/vif/2'} to /local/domain/0/backend/vif/1/2.
[2011-08-16 01:59:31 1921] DEBUG (XendDomainInfo:3420) Storing VM details: {'on_xend_stop': 'ignore', 'pool_name': 'Pool-0', 'shadow_memory': '0', 'uuid': '4041d776-27b3-ef2d-12aa-e7c6aa9963c4', 'on_reboot': 'restart', 'start_time': '1313452771.4', 'on_poweroff': 'destroy', 'bootloader_args': '-q', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'bootloader': '/usr/lib/xen-default/bin/pygrub', 'image': "(linux (kernel '') (superpages 0) (videoram 4) (pci ()) (nomigrate 0) (tsc_mode 0) (notes (HV_START_LOW 4118806528) (FEATURES 'writable_page_tables|writable_descriptor_tables|auto_translated_physmap|pae_pgdir_above_4gb|supervisor_mode_kernel') (VIRT_BASE 3221225472) (GUEST_VERSION 2.6) (PADDR_OFFSET 0) (GUEST_OS linux) (HYPERCALL_PAGE 3238006784) (LOADER generic) (SUSPEND_CANCEL 1) (PAE_MODE yes) (ENTRY 3238002688) (XEN_VERSION xen-3.0)))", 'name': 'hottie'}
[2011-08-16 01:59:31 1921] DEBUG (XendDomainInfo:1794) Storing domain details: {'console/ring-ref': '4320793', 'image/entry': '3238002688', 'console/port': '2', 'store/ring-ref': '2215859', 'image/loader': 'generic', 'vm': '/vm/4041d776-27b3-ef2d-12aa-e7c6aa9963c4', 'control/platform-feature-multiprocessor-suspend': '1', 'image/hv-start-low': '4118806528', 'image/guest-os': 'linux', 'image/features/writable-descriptor-tables': '1', 'image/virt-base': '3221225472', 'memory/target': '131072', 'image/guest-version': '2.6', 'image/features/supervisor-mode-kernel': '1', 'image/pae-mode': 'yes', 'description': '', 'console/limit': '1048576', 'image/paddr-offset': '0', 'image/hypercall-page': '3238006784', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '1', 'console/type': 'xenconsoled', 'image/features/auto-translated-physmap': '1', 'name': 'hottie', 'domid': '1', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
[2011-08-16 01:59:31 1921] DEBUG (DevController:95) DevController: writing {'protocol': 'x86_32-abi', 'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/1/0'} to /local/domain/1/device/console/0.
[2011-08-16 01:59:31 1921] DEBUG (DevController:97) DevController: writing {'domain': 'hottie', 'frontend': '/local/domain/1/device/console/0', 'uuid': 'e3d10db2-38cc-00f0-f847-8cae9c7ac08b', 'frontend-id': '1', 'state': '1', 'location': '2', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/1/0.
[2011-08-16 01:59:31 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices tap2.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices vif.
[2011-08-16 01:59:31 1921] DEBUG (DevController:144) Waiting for 0.
[2011-08-16 01:59:31 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
[2011-08-16 01:59:31 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 01:59:31 1921] DEBUG (DevController:144) Waiting for 1.
[2011-08-16 01:59:31 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/1/1/hotplug-status.
[2011-08-16 01:59:31 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 01:59:31 1921] DEBUG (DevController:144) Waiting for 2.
[2011-08-16 01:59:31 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/1/2/hotplug-status.
[2011-08-16 01:59:31 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices vkbd.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices ioports.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices tap.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices vif2.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices console.
[2011-08-16 01:59:31 1921] DEBUG (DevController:144) Waiting for 0.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices vscsi.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices vbd.
[2011-08-16 01:59:31 1921] DEBUG (DevController:144) Waiting for 769.
[2011-08-16 01:59:31 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/1/769/hotplug-status.
[2011-08-16 01:59:31 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices irq.
[2011-08-16 01:59:31 1921] DEBUG (DevController:139) Waiting for devices vfb.
[2011-08-16 01:59:32 1921] DEBUG (DevController:139) Waiting for devices pci.
[2011-08-16 01:59:32 1921] DEBUG (DevController:139) Waiting for devices vusb.
[2011-08-16 01:59:32 1921] DEBUG (DevController:139) Waiting for devices vtpm.
[2011-08-16 01:59:32 1921] INFO (XendDomain:1225) Domain hottie (1) unpaused.
[2011-08-16 02:05:27 1921] DEBUG (XendDomainInfo:103) XendDomainInfo.create(['vm', ['name', 'mailscanner'], ['memory', '512'], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['vcpus', 2], ['cpus', '2-7'], ['oos', 1], ['bootloader', '/usr/lib/xen-default/bin/pygrub'], ['bootloader_args', '-q'], ['image', ['linux', ['videoram', 4], ['tsc_mode', 0], ['nomigrate', 0]]], ['s3_integrity', 1], ['device', ['vbd', ['uname', 'drbd:mailscanner'], ['dev', 'xvda1'], ['mode', 'w']]], ['device', ['vif', ['bridge', 'intern']]]])
[2011-08-16 02:05:27 1921] DEBUG (XendDomainInfo:2498) XendDomainInfo.constructDomain
[2011-08-16 02:05:27 1921] DEBUG (balloon:187) Balloon: 15931972 KiB free; need 16384; done.
[2011-08-16 02:05:27 1921] DEBUG (XendDomain:476) Adding Domain: 2
[2011-08-16 02:05:27 1921] DEBUG (XendDomainInfo:2836) XendDomainInfo.initDomain: 2 256
[2011-08-16 02:05:27 1921] INFO (XendDomainInfo:3270) Mounting mailscanner on /dev/xvdp.
[2011-08-16 02:05:27 1921] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '51952', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/0/51952'} to /local/domain/0/device/vbd/51952.
[2011-08-16 02:05:27 1921] DEBUG (DevController:97) DevController: writing {'domain': 'Domain-0', 'frontend': '/local/domain/0/device/vbd/51952', 'uuid': 'c4ff59b7-b29e-616e-472d-413832721d48', 'bootable': '0', 'dev': '/dev/xvdp', 'state': '1', 'params': 'mailscanner', 'mode': 'r', 'online': '1', 'frontend-id': '0', 'type': 'drbd'} to /local/domain/0/backend/vbd/0/51952.
[2011-08-16 02:05:27 1921] DEBUG (DevController:144) Waiting for 51952.
[2011-08-16 02:05:27 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/0/51952/hotplug-status.
[2011-08-16 02:05:28 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/0/51952/hotplug-status.
[2011-08-16 02:05:28 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 02:05:28 1921] DEBUG (DevController:144) Waiting for 51952.
[2011-08-16 02:05:28 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/0/51952/hotplug-status.
[2011-08-16 02:05:28 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 02:05:28 4207] DEBUG (XendBootloader:113) Launching bootloader as ['/usr/lib/xen-default/bin/pygrub', '--output=/var/run/xend/boot/xenbl.17987', '-q', '/dev/xvdp'].
[2011-08-16 02:05:28 1921] INFO (XendDomainInfo:3289) Unmounting /dev/xvdp from /dev/xvdp.
[2011-08-16 02:05:28 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = /dev/xvdp
[2011-08-16 02:05:28 1921] DEBUG (XendDomainInfo:2863) _initDomain:shadow_memory=0x0, memory_static_max=0x20000000, memory_static_min=0x0.
[2011-08-16 02:05:28 1921] INFO (image:182) buildDomain os=linux dom=2 vcpus=2
[2011-08-16 02:05:28 1921] DEBUG (image:721) domid = 2
[2011-08-16 02:05:28 1921] DEBUG (image:722) memsize = 512
[2011-08-16 02:05:28 1921] DEBUG (image:723) image = /var/run/xend/boot/boot_kernel.kciRLL
[2011-08-16 02:05:28 1921] DEBUG (image:724) store_evtchn = 1
[2011-08-16 02:05:28 1921] DEBUG (image:725) console_evtchn = 2
[2011-08-16 02:05:28 1921] DEBUG (image:726) cmdline = root=/dev/xvda1 ro quiet
[2011-08-16 02:05:28 1921] DEBUG (image:727) ramdisk = /var/run/xend/boot/boot_ramdisk.Z2pO51
[2011-08-16 02:05:28 1921] DEBUG (image:728) vcpus = 2
[2011-08-16 02:05:28 1921] DEBUG (image:729) features =
[2011-08-16 02:05:28 1921] DEBUG (image:730) flags = 0
[2011-08-16 02:05:28 1921] DEBUG (image:731) superpages = 0
[2011-08-16 02:05:29 1921] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': 'd1cd446f-59a3-f620-535c-319401ec85fd', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'xvda1', 'uname': 'drbd:mailscanner', 'mode': 'w'}
[2011-08-16 02:05:29 1921] DEBUG (DevController:95) DevController: writing {'virtual-device': '51713', 'device-type': 'disk', 'protocol': 'x86_64-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vbd/2/51713'} to /local/domain/2/device/vbd/51713.
[2011-08-16 02:05:29 1921] DEBUG (DevController:97) DevController: writing {'domain': 'mailscanner', 'frontend': '/local/domain/2/device/vbd/51713', 'uuid': 'd1cd446f-59a3-f620-535c-319401ec85fd', 'bootable': '1', 'dev': 'xvda1', 'state': '1', 'params': 'mailscanner', 'mode': 'w', 'online': '1', 'frontend-id': '2', 'type': 'drbd'} to /local/domain/0/backend/vbd/2/51713.
[2011-08-16 02:05:29 1921] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'intern', 'mac': '00:16:3e:45:e2:18', 'uuid': 'e1893c00-856f-f162-fec5-7583a1fad8d6'}
[2011-08-16 02:05:29 1921] DEBUG (DevController:95) DevController: writing {'mac': '00:16:3e:45:e2:18', 'handle': '0', 'protocol': 'x86_64-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/2/0'} to /local/domain/2/device/vif/0.
[2011-08-16 02:05:29 1921] DEBUG (DevController:97) DevController: writing {'bridge': 'intern', 'domain': 'mailscanner', 'handle': '0', 'uuid': 'e1893c00-856f-f162-fec5-7583a1fad8d6', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:45:e2:18', 'frontend-id': '2', 'state': '1', 'online': '1', 'frontend': '/local/domain/2/device/vif/0'} to /local/domain/0/backend/vif/2/0.
[2011-08-16 02:05:29 1921] DEBUG (XendDomainInfo:3420) Storing VM details: {'on_xend_stop': 'ignore', 'pool_name': 'Pool-0', 'shadow_memory': '0', 'uuid': 'a4cf4612-e34e-344e-266d-1cd74c2fc6fb', 'on_reboot': 'restart', 'start_time': '1313453129.54', 'on_poweroff': 'destroy', 'bootloader_args': '-q', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '2', 'vcpu_avail': '3', 'bootloader': '/usr/lib/xen-default/bin/pygrub', 'image': "(linux (kernel '') (superpages 0) (videoram 4) (pci ()) (nomigrate 0) (tsc_mode 0) (notes (HV_START_LOW 18446603336221196288) (FEATURES '!writable_page_tables|pae_pgdir_above_4gb') (VIRT_BASE 18446744071562067968) (GUEST_VERSION 2.6) (PADDR_OFFSET 0) (GUEST_OS linux) (HYPERCALL_PAGE 18446744071578882048) (LOADER generic) (SUSPEND_CANCEL 1) (PAE_MODE yes) (ENTRY 18446744071584125440) (XEN_VERSION xen-3.0)))", 'name': 'mailscanner'}
[2011-08-16 02:05:29 1921] DEBUG (XendDomainInfo:1794) Storing domain details: {'console/ring-ref': '2179462', 'image/entry': '18446744071584125440', 'console/port': '2', 'store/ring-ref': '4249544', 'image/loader': 'generic', 'vm': '/vm/a4cf4612-e34e-344e-266d-1cd74c2fc6fb', 'control/platform-feature-multiprocessor-suspend': '1', 'image/hv-start-low': '18446603336221196288', 'image/guest-os': 'linux', 'cpu/1/availability': 'online', 'image/virt-base': '18446744071562067968', 'memory/target': '524288', 'image/guest-version': '2.6', 'image/pae-mode': 'yes', 'description': '', 'console/limit': '1048576', 'image/paddr-offset': '0', 'image/hypercall-page': '18446744071578882048', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '0', 'console/type': 'xenconsoled', 'name': 'mailscanner', 'domid': '2', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
[2011-08-16 02:05:29 1921] DEBUG (DevController:95) DevController: writing {'protocol': 'x86_64-abi', 'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/2/0'} to /local/domain/2/device/console/0.
[2011-08-16 02:05:29 1921] DEBUG (DevController:97) DevController: writing {'domain': 'mailscanner', 'frontend': '/local/domain/2/device/console/0', 'uuid': 'd5c599ff-4637-f35b-b0b5-9aea2590211f', 'frontend-id': '2', 'state': '1', 'location': '2', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/2/0.
[2011-08-16 02:05:29 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices tap2.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices vif.
[2011-08-16 02:05:29 1921] DEBUG (DevController:144) Waiting for 0.
[2011-08-16 02:05:29 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
[2011-08-16 02:05:29 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices vkbd.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices ioports.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices tap.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices vif2.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices console.
[2011-08-16 02:05:29 1921] DEBUG (DevController:144) Waiting for 0.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices vscsi.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices vbd.
[2011-08-16 02:05:29 1921] DEBUG (DevController:144) Waiting for 51713.
[2011-08-16 02:05:29 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/2/51713/hotplug-status.
[2011-08-16 02:05:29 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/2/51713/hotplug-status.
[2011-08-16 02:05:29 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices irq.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices vfb.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices pci.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices vusb.
[2011-08-16 02:05:29 1921] DEBUG (DevController:139) Waiting for devices vtpm.
[2011-08-16 02:05:29 1921] INFO (XendDomain:1225) Domain mailscanner (2) unpaused.
[2011-08-16 02:05:54 1921] DEBUG (XendCheckpoint:124) [xc_save]: /usr/lib/xen-4.1/bin/xc_save 23 2 0 0 0
[2011-08-16 02:05:54 1921] INFO (XendCheckpoint:423) xc_save: failed to get the suspend evtchn port
[2011-08-16 02:05:54 1921] INFO (XendCheckpoint:423)
[2011-08-16 02:05:54 1921] DEBUG (XendCheckpoint:394) suspend
[2011-08-16 02:05:54 1921] DEBUG (XendCheckpoint:127) In saveInputHandler suspend
[2011-08-16 02:05:54 1921] DEBUG (XendCheckpoint:129) Suspending 2 ...
[2011-08-16 02:05:54 1921] DEBUG (XendDomainInfo:524) XendDomainInfo.shutdown(suspend)
[2011-08-16 02:05:54 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 02:05:54 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 02:05:54 1921] INFO (XendDomainInfo:2078) Domain has shutdown: name=migrating-mailscanner id=2 reason=suspend.
[2011-08-16 02:05:54 1921] INFO (XendCheckpoint:135) Domain 2 suspended.
[2011-08-16 02:05:54 1921] DEBUG (XendCheckpoint:144) Written done
[2011-08-16 02:06:02 1921] DEBUG (XendDomainInfo:3071) XendDomainInfo.destroy: domid=2
[2011-08-16 02:06:02 1921] DEBUG (XendDomainInfo:2401) Destroying device model
[2011-08-16 02:06:02 1921] DEBUG (XendDomainInfo:2408) Releasing devices
[2011-08-16 02:06:02 1921] DEBUG (XendDomainInfo:2414) Removing vif/0
[2011-08-16 02:06:02 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2011-08-16 02:06:02 1921] DEBUG (XendDomainInfo:2414) Removing console/0
[2011-08-16 02:06:02 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2011-08-16 02:06:02 1921] DEBUG (XendDomainInfo:2414) Removing vbd/51713
[2011-08-16 02:06:02 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/51713
[2011-08-16 02:06:06 1921] DEBUG (XendDomainInfo:237) XendDomainInfo.restore(['domain', ['domid', '2'], ['cpu_weight', '256'], ['cpu_cap', '0'], ['pool_name', 'Pool-0'], ['bootloader', '/usr/lib/xen-default/bin/pygrub'], ['vcpus', '2'], ['cpus', [['2', '3', '4', '5', '6', '7'], ['2', '3', '4', '5', '6', '7']]], ['on_poweroff', 'destroy'], ['description', ''], ['on_crash', 'restart'], ['uuid', 'a4cf4612-e34e-344e-266d-1cd74c2fc6fb'], ['bootloader_args', '-q'], ['name', 'mailscanner'], ['on_reboot', 'restart'], ['maxmem', '512'], ['memory', '512'], ['shadow_memory', '0'], ['vcpu_avail', '3'], ['features', ''], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['start_time', '1313453129.54'], ['cpu_time', '0.0'], ['online_vcpus', '1'], ['image', ['linux', ['kernel', ''], ['superpages', '0'], ['videoram', '4'], ['pci', []], ['nomigrate', '0'], ['tsc_mode', '0'], ['notes', ['HV_START_LOW', '18446603336221196288'], ['FEATURES', '!writable_page_tables|pae_pgdir_above_4gb'], ['VIRT_BASE', '18446744071562067968'], ['GUEST_VERSION', '2.6'], ['PADDR_OFFSET', '0'], ['GUEST_OS', 'linux'], ['HYPERCALL_PAGE', '18446744071578882048'], ['LOADER', 'generic'], ['SUSPEND_CANCEL', '1'], ['PAE_MODE', 'yes'], ['ENTRY', '18446744071584125440'], ['XEN_VERSION', 'xen-3.0']]]], ['status', '2'], ['state', '--p---'], ['store_mfn', '4249544'], ['console_mfn', '2179462'], ['device', ['vif', ['bridge', 'intern'], ['mac', '00:16:3e:45:e2:18'], ['script', '/etc/xen/scripts/vif-bridge'], ['uuid', 'e1893c00-856f-f162-fec5-7583a1fad8d6'], ['backend', '0']]], ['device', ['console', ['protocol', 'vt100'], ['location', '2'], ['uuid', 'd5c599ff-4637-f35b-b0b5-9aea2590211f']]], ['device', ['vbd', ['protocol', 'x86_64-abi'], ['uuid', 'd1cd446f-59a3-f620-535c-319401ec85fd'], ['bootable', '1'], ['dev', 'xvda1:disk'], ['uname', 'drbd:mailscanner'], ['mode', 'w'], ['backend', '0'], ['VDI', '']]]])
[2011-08-16 02:06:06 1921] DEBUG (XendDomainInfo:2498) XendDomainInfo.constructDomain
[2011-08-16 02:06:06 1921] DEBUG (balloon:187) Balloon: 15931972 KiB free; need 16384; done.
[2011-08-16 02:06:06 1921] DEBUG (XendDomain:476) Adding Domain: 3
[2011-08-16 02:06:06 1921] DEBUG (XendDomainInfo:3420) Storing VM details: {'on_xend_stop': 'ignore', 'pool_name': 'Pool-0', 'shadow_memory': '0', 'uuid': 'a4cf4612-e34e-344e-266d-1cd74c2fc6fb', 'on_reboot': 'restart', 'start_time': '1313453129.54', 'on_poweroff': 'destroy', 'bootloader_args': '-q', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '2', 'vcpu_avail': '3', 'bootloader': '/usr/lib/xen-default/bin/pygrub', 'image': "(linux (kernel '') (superpages 0) (videoram 4) (pci ()) (nomigrate 0) (tsc_mode 0) (notes (HV_START_LOW 18446603336221196288) (FEATURES '!writable_page_tables|pae_pgdir_above_4gb') (VIRT_BASE 18446744071562067968) (GUEST_VERSION 2.6) (PADDR_OFFSET 0) (GUEST_OS linux) (HYPERCALL_PAGE 18446744071578882048) (LOADER generic) (SUSPEND_CANCEL 1) (PAE_MODE yes) (ENTRY 18446744071584125440) (XEN_VERSION xen-3.0)))", 'name': 'mailscanner'}
[2011-08-16 02:06:06 1921] INFO (XendDomainInfo:2357) createDevice: console : {'protocol': 'vt100', 'location': '2', 'uuid': 'd5c599ff-4637-f35b-b0b5-9aea2590211f'}
[2011-08-16 02:06:06 1921] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/3/0'} to /local/domain/3/device/console/0.
[2011-08-16 02:06:06 1921] DEBUG (DevController:97) DevController: writing {'domain': 'mailscanner', 'frontend': '/local/domain/3/device/console/0', 'uuid': 'd5c599ff-4637-f35b-b0b5-9aea2590211f', 'frontend-id': '3', 'state': '1', 'location': '2', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/3/0.
[2011-08-16 02:06:06 1921] INFO (XendDomainInfo:2357) createDevice: vbd : {'protocol': 'x86_64-abi', 'uuid': 'd1cd446f-59a3-f620-535c-319401ec85fd', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'xvda1:disk', 'uname': 'drbd:mailscanner', 'mode': 'w', 'VDI': '', 'backend': '0'}
[2011-08-16 02:06:06 1921] DEBUG (DevController:95) DevController: writing {'virtual-device': '51713', 'protocol': 'x86_64-abi', 'device-type': 'disk', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vbd/3/51713'} to /local/domain/3/device/vbd/51713.
[2011-08-16 02:06:06 1921] DEBUG (DevController:97) DevController: writing {'domain': 'mailscanner', 'frontend': '/local/domain/3/device/vbd/51713', 'uuid': 'd1cd446f-59a3-f620-535c-319401ec85fd', 'bootable': '1', 'dev': 'xvda1', 'state': '1', 'params': 'mailscanner', 'mode': 'w', 'online': '1', 'frontend-id': '3', 'type': 'drbd'} to /local/domain/0/backend/vbd/3/51713.
[2011-08-16 02:06:06 1921] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'intern', 'mac': '00:16:3e:45:e2:18', 'script': '/etc/xen/scripts/vif-bridge', 'uuid': 'e1893c00-856f-f162-fec5-7583a1fad8d6', 'backend': '0'}
[2011-08-16 02:06:06 1921] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:45:e2:18', 'handle': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/3/0'} to /local/domain/3/device/vif/0.
[2011-08-16 02:06:06 1921] DEBUG (DevController:97) DevController: writing {'bridge': 'intern', 'domain': 'mailscanner', 'handle': '0', 'uuid': 'e1893c00-856f-f162-fec5-7583a1fad8d6', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:45:e2:18', 'frontend-id': '3', 'state': '1', 'online': '1', 'frontend': '/local/domain/3/device/vif/0'} to /local/domain/0/backend/vif/3/0.
[2011-08-16 02:06:06 1921] DEBUG (XendDomainInfo:1794) Storing domain details: {'image/entry': '18446744071584125440', 'console/port': '2', 'image/loader': 'generic', 'vm': '/vm/a4cf4612-e34e-344e-266d-1cd74c2fc6fb', 'control/platform-feature-multiprocessor-suspend': '1', 'image/hv-start-low': '18446603336221196288', 'image/guest-os': 'linux', 'cpu/1/availability': 'online', 'image/virt-base': '18446744071562067968', 'memory/target': '524288', 'image/guest-version': '2.6', 'image/pae-mode': 'yes', 'description': '', 'console/limit': '1048576', 'image/paddr-offset': '0', 'image/hypercall-page': '18446744071578882048', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '0', 'console/type': 'xenconsoled', 'name': 'mailscanner', 'domid': '3', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
[2011-08-16 02:06:06 1921] DEBUG (XendCheckpoint:278) restore:shadow=0x0, _static_max=0x20000000, _static_min=0x0,
[2011-08-16 02:06:06 1921] DEBUG (XendCheckpoint:305) [xc_restore]: /usr/lib/xen-4.1/bin/xc_restore 23 3 1 2 0 0 0 0
[2011-08-16 02:06:16 1921] DEBUG (XendCheckpoint:394) store-mfn 2339851
[2011-08-16 02:06:16 1921] DEBUG (XendCheckpoint:394) console-mfn 1531335
[2011-08-16 02:06:16 1921] DEBUG (XendDomainInfo:3010) XendDomainInfo.completeRestore
[2011-08-16 02:06:16 1921] DEBUG (XendDomainInfo:1794) Storing domain details: {'console/ring-ref': '1531335', 'image/entry': '18446744071584125440', 'console/port': '2', 'store/ring-ref': '2339851', 'image/loader': 'generic', 'vm': '/vm/a4cf4612-e34e-344e-266d-1cd74c2fc6fb', 'control/platform-feature-multiprocessor-suspend': '1', 'image/hv-start-low': '18446603336221196288', 'image/guest-os': 'linux', 'cpu/1/availability': 'online', 'image/virt-base': '18446744071562067968', 'memory/target': '524288', 'image/guest-version': '2.6', 'image/pae-mode': 'yes', 'description': '', 'console/limit': '1048576', 'image/paddr-offset': '0', 'image/hypercall-page': '18446744071578882048', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '0', 'console/type': 'xenconsoled', 'name': 'mailscanner', 'domid': '3', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
[2011-08-16 02:06:16 1921] DEBUG (XendDomainInfo:3023) XendDomainInfo.completeRestore done
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices tap2.
[2011-08-16 02:06:16 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices vif.
[2011-08-16 02:06:16 1921] DEBUG (DevController:144) Waiting for 0.
[2011-08-16 02:06:16 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
[2011-08-16 02:06:16 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices vkbd.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices ioports.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices tap.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices vif2.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices console.
[2011-08-16 02:06:16 1921] DEBUG (DevController:144) Waiting for 0.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices vscsi.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices vbd.
[2011-08-16 02:06:16 1921] DEBUG (DevController:144) Waiting for 51713.
[2011-08-16 02:06:16 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/3/51713/hotplug-status.
[2011-08-16 02:06:16 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices irq.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices vfb.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices pci.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices vusb.
[2011-08-16 02:06:16 1921] DEBUG (DevController:139) Waiting for devices vtpm.
[2011-08-16 02:06:28 1921] DEBUG (XendCheckpoint:124) [xc_save]: /usr/lib/xen-4.1/bin/xc_save 23 3 0 0 0
[2011-08-16 02:06:28 1921] INFO (XendCheckpoint:423) xc_save: failed to get the suspend evtchn port
[2011-08-16 02:06:28 1921] INFO (XendCheckpoint:423)
[2011-08-16 02:06:28 1921] DEBUG (XendCheckpoint:394) suspend
[2011-08-16 02:06:28 1921] DEBUG (XendCheckpoint:127) In saveInputHandler suspend
[2011-08-16 02:06:28 1921] DEBUG (XendCheckpoint:129) Suspending 3 ...
[2011-08-16 02:06:28 1921] DEBUG (XendDomainInfo:524) XendDomainInfo.shutdown(suspend)
[2011-08-16 02:06:28 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 02:06:28 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 02:06:28 1921] INFO (XendDomainInfo:2078) Domain has shutdown: name=migrating-mailscanner id=3 reason=suspend.
[2011-08-16 02:06:28 1921] INFO (XendCheckpoint:135) Domain 3 suspended.
[2011-08-16 02:06:28 1921] DEBUG (XendCheckpoint:144) Written done
[2011-08-16 02:06:35 1921] DEBUG (XendDomainInfo:3071) XendDomainInfo.destroy: domid=3
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:2401) Destroying device model
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:2408) Releasing devices
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:2414) Removing vif/0
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:2414) Removing console/0
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:2414) Removing vbd/51713
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/51713
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:2406) No device model
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:2408) Releasing devices
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:2414) Removing vif/0
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:2414) Removing vbd/51713
[2011-08-16 02:06:36 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/51713
[2011-08-16 02:06:49 1921] DEBUG (XendDomainInfo:524) XendDomainInfo.shutdown(poweroff)
[2011-08-16 02:06:49 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 02:06:49 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 02:07:04 1921] INFO (XendDomainInfo:2078) Domain has shutdown: name=hottie id=1 reason=poweroff.
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:3071) XendDomainInfo.destroy: domid=1
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:2401) Destroying device model
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:2408) Releasing devices
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:2414) Removing vif/0
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:2414) Removing vif/1
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/1
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:2414) Removing vif/2
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/2
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:2414) Removing console/0
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:2414) Removing vbd/769
[2011-08-16 02:07:04 1921] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/769
[2011-08-16 02:07:08 1921] DEBUG (XendDomainInfo:237) XendDomainInfo.restore(['domain', ['domid', '3'], ['cpu_weight', '256'], ['cpu_cap', '0'], ['pool_name', 'Pool-0'], ['bootloader', '/usr/lib/xen-default/bin/pygrub'], ['vcpus', '2'], ['cpus', [['2', '3', '4', '5', '6', '7'], ['2', '3', '4', '5', '6', '7']]], ['on_poweroff', 'destroy'], ['description', ''], ['on_crash', 'restart'], ['uuid', 'a4cf4612-e34e-344e-266d-1cd74c2fc6fb'], ['bootloader_args', '-q'], ['name', 'mailscanner'], ['on_reboot', 'restart'], ['maxmem', '512'], ['memory', '512'], ['shadow_memory', '0'], ['vcpu_avail', '3'], ['features', ''], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['start_time', '1313453129.54'], ['cpu_time', '0.0'], ['online_vcpus', '2'], ['image', ['linux', ['kernel', ''], ['superpages', '0'], ['videoram', '4'], ['pci', []], ['nomigrate', '0'], ['tsc_mode', '0'], ['notes', ['HV_START_LOW', '18446603336221196288'], ['FEATURES', '!writable_page_tables|pae_pgdir_above_4gb'], ['VIRT_BASE', '18446744071562067968'], ['GUEST_VERSION', '2.6'], ['PADDR_OFFSET', '0'], ['GUEST_OS', 'linux'], ['HYPERCALL_PAGE', '18446744071578882048'], ['LOADER', 'generic'], ['SUSPEND_CANCEL', '1'], ['PAE_MODE', 'yes'], ['ENTRY', '18446744071584125440'], ['XEN_VERSION', 'xen-3.0']]]], ['status', '2'], ['state', '--p---'], ['store_mfn', '2339851'], ['console_mfn', '1531335'], ['device', ['vif', ['bridge', 'intern'], ['mac', '00:16:3e:45:e2:18'], ['script', '/etc/xen/scripts/vif-bridge'], ['uuid', 'e1893c00-856f-f162-fec5-7583a1fad8d6'], ['backend', '0']]], ['device', ['console', ['protocol', 'vt100'], ['location', '2'], ['uuid', 'd5c599ff-4637-f35b-b0b5-9aea2590211f']]], ['device', ['vbd', ['protocol', 'x86_64-abi'], ['uuid', 'd1cd446f-59a3-f620-535c-319401ec85fd'], ['bootable', '1'], ['dev', 'xvda1:disk'], ['uname', 'drbd:mailscanner'], ['mode', 'w'], ['backend', '0'], ['VDI', '']]]])
[2011-08-16 02:07:08 1921] DEBUG (XendDomainInfo:2498) XendDomainInfo.constructDomain
[2011-08-16 02:07:08 1921] DEBUG (balloon:187) Balloon: 16063208 KiB free; need 16384; done.
[2011-08-16 02:07:08 1921] DEBUG (XendDomain:476) Adding Domain: 4
[2011-08-16 02:07:08 1921] DEBUG (XendDomainInfo:3420) Storing VM details: {'on_xend_stop': 'ignore', 'pool_name': 'Pool-0', 'shadow_memory': '0', 'uuid': 'a4cf4612-e34e-344e-266d-1cd74c2fc6fb', 'on_reboot': 'restart', 'start_time': '1313453129.54', 'on_poweroff': 'destroy', 'bootloader_args': '-q', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '2', 'vcpu_avail': '3', 'bootloader': '/usr/lib/xen-default/bin/pygrub', 'image': "(linux (kernel '') (superpages 0) (videoram 4) (pci ()) (nomigrate 0) (tsc_mode 0) (notes (HV_START_LOW 18446603336221196288) (FEATURES '!writable_page_tables|pae_pgdir_above_4gb') (VIRT_BASE 18446744071562067968) (GUEST_VERSION 2.6) (PADDR_OFFSET 0) (GUEST_OS linux) (HYPERCALL_PAGE 18446744071578882048) (LOADER generic) (SUSPEND_CANCEL 1) (PAE_MODE yes) (ENTRY 18446744071584125440) (XEN_VERSION xen-3.0)))", 'name': 'mailscanner'}
[2011-08-16 02:07:08 1921] INFO (XendDomainInfo:2357) createDevice: console : {'protocol': 'vt100', 'location': '2', 'uuid': 'd5c599ff-4637-f35b-b0b5-9aea2590211f'}
[2011-08-16 02:07:08 1921] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/4/0'} to /local/domain/4/device/console/0.
[2011-08-16 02:07:08 1921] DEBUG (DevController:97) DevController: writing {'domain': 'mailscanner', 'frontend': '/local/domain/4/device/console/0', 'uuid': 'd5c599ff-4637-f35b-b0b5-9aea2590211f', 'frontend-id': '4', 'state': '1', 'location': '2', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/4/0.
[2011-08-16 02:07:08 1921] INFO (XendDomainInfo:2357) createDevice: vbd : {'protocol': 'x86_64-abi', 'uuid': 'd1cd446f-59a3-f620-535c-319401ec85fd', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'xvda1:disk', 'uname': 'drbd:mailscanner', 'mode': 'w', 'VDI': '', 'backend': '0'}
[2011-08-16 02:07:08 1921] DEBUG (DevController:95) DevController: writing {'virtual-device': '51713', 'protocol': 'x86_64-abi', 'device-type': 'disk', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vbd/4/51713'} to /local/domain/4/device/vbd/51713.
[2011-08-16 02:07:08 1921] DEBUG (DevController:97) DevController: writing {'domain': 'mailscanner', 'frontend': '/local/domain/4/device/vbd/51713', 'uuid': 'd1cd446f-59a3-f620-535c-319401ec85fd', 'bootable': '1', 'dev': 'xvda1', 'state': '1', 'params': 'mailscanner', 'mode': 'w', 'online': '1', 'frontend-id': '4', 'type': 'drbd'} to /local/domain/0/backend/vbd/4/51713.
[2011-08-16 02:07:08 1921] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'intern', 'mac': '00:16:3e:45:e2:18', 'script': '/etc/xen/scripts/vif-bridge', 'uuid': 'e1893c00-856f-f162-fec5-7583a1fad8d6', 'backend': '0'}
[2011-08-16 02:07:08 1921] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:45:e2:18', 'handle': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/4/0'} to /local/domain/4/device/vif/0.
[2011-08-16 02:07:08 1921] DEBUG (DevController:97) DevController: writing {'bridge': 'intern', 'domain': 'mailscanner', 'handle': '0', 'uuid': 'e1893c00-856f-f162-fec5-7583a1fad8d6', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:45:e2:18', 'frontend-id': '4', 'state': '1', 'online': '1', 'frontend': '/local/domain/4/device/vif/0'} to /local/domain/0/backend/vif/4/0.
[2011-08-16 02:07:08 1921] DEBUG (XendDomainInfo:1794) Storing domain details: {'image/entry': '18446744071584125440', 'console/port': '2', 'image/loader': 'generic', 'vm': '/vm/a4cf4612-e34e-344e-266d-1cd74c2fc6fb', 'control/platform-feature-multiprocessor-suspend': '1', 'image/hv-start-low': '18446603336221196288', 'image/guest-os': 'linux', 'cpu/1/availability': 'online', 'image/virt-base': '18446744071562067968', 'memory/target': '524288', 'image/guest-version': '2.6', 'image/pae-mode': 'yes', 'description': '', 'console/limit': '1048576', 'image/paddr-offset': '0', 'image/hypercall-page': '18446744071578882048', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '0', 'console/type': 'xenconsoled', 'name': 'mailscanner', 'domid': '4', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
[2011-08-16 02:07:08 1921] DEBUG (XendCheckpoint:278) restore:shadow=0x0, _static_max=0x20000000, _static_min=0x0,
[2011-08-16 02:07:08 1921] DEBUG (XendCheckpoint:305) [xc_restore]: /usr/lib/xen-4.1/bin/xc_restore 23 4 1 2 0 0 0 0
[2011-08-16 02:07:17 1921] DEBUG (XendCheckpoint:394) store-mfn 2356141
[2011-08-16 02:07:17 1921] DEBUG (XendCheckpoint:394) console-mfn 1809731
[2011-08-16 02:07:17 1921] DEBUG (XendDomainInfo:3010) XendDomainInfo.completeRestore
[2011-08-16 02:07:17 1921] DEBUG (XendDomainInfo:1794) Storing domain details: {'console/ring-ref': '1809731', 'image/entry': '18446744071584125440', 'console/port': '2', 'store/ring-ref': '2356141', 'image/loader': 'generic', 'vm': '/vm/a4cf4612-e34e-344e-266d-1cd74c2fc6fb', 'control/platform-feature-multiprocessor-suspend': '1', 'image/hv-start-low': '18446603336221196288', 'image/guest-os': 'linux', 'cpu/1/availability': 'online', 'image/virt-base': '18446744071562067968', 'memory/target': '524288', 'image/guest-version': '2.6', 'image/pae-mode': 'yes', 'description': '', 'console/limit': '1048576', 'image/paddr-offset': '0', 'image/hypercall-page': '18446744071578882048', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '0', 'console/type': 'xenconsoled', 'name': 'mailscanner', 'domid': '4', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
[2011-08-16 02:07:17 1921] DEBUG (XendDomainInfo:3023) XendDomainInfo.completeRestore done
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices tap2.
[2011-08-16 02:07:17 1921] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices vif.
[2011-08-16 02:07:17 1921] DEBUG (DevController:144) Waiting for 0.
[2011-08-16 02:07:17 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
[2011-08-16 02:07:17 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices vkbd.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices ioports.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices tap.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices vif2.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices console.
[2011-08-16 02:07:17 1921] DEBUG (DevController:144) Waiting for 0.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices vscsi.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices vbd.
[2011-08-16 02:07:17 1921] DEBUG (DevController:144) Waiting for 51713.
[2011-08-16 02:07:17 1921] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/4/51713/hotplug-status.
[2011-08-16 02:07:17 1921] DEBUG (DevController:642) hotplugStatusCallback 1.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices irq.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices vfb.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices pci.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices vusb.
[2011-08-16 02:07:17 1921] DEBUG (DevController:139) Waiting for devices vtpm.
Xend started at Tue Aug 16 01:59:23 2011.
cat: /sys/bus/scsi/devices/host0/vendor: No such file or directory
cat: /sys/bus/scsi/devices/host0/model: No such file or directory
cat: /sys/bus/scsi/devices/host0/type: No such file or directory
cat: /sys/bus/scsi/devices/host0/rev: No such file or directory
cat: /sys/bus/scsi/devices/host0/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/host1/vendor: No such file or directory
cat: /sys/bus/scsi/devices/host1/model: No such file or directory
cat: /sys/bus/scsi/devices/host1/type: No such file or directory
cat: /sys/bus/scsi/devices/host1/rev: No such file or directory
cat: /sys/bus/scsi/devices/host1/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/host2/vendor: No such file or directory
cat: /sys/bus/scsi/devices/host2/model: No such file or directory
cat: /sys/bus/scsi/devices/host2/type: No such file or directory
cat: /sys/bus/scsi/devices/host2/rev: No such file or directory
cat: /sys/bus/scsi/devices/host2/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/host3/vendor: No such file or directory
cat: /sys/bus/scsi/devices/host3/model: No such file or directory
cat: /sys/bus/scsi/devices/host3/type: No such file or directory
cat: /sys/bus/scsi/devices/host3/rev: No such file or directory
cat: /sys/bus/scsi/devices/host3/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/target0:0:0/vendor: No such file or directory
cat: /sys/bus/scsi/devices/target0:0:0/model: No such file or directory
cat: /sys/bus/scsi/devices/target0:0:0/type: No such file or directory
cat: /sys/bus/scsi/devices/target0:0:0/rev: No such file or directory
cat: /sys/bus/scsi/devices/target0:0:0/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/target1:0:0/vendor: No such file or directory
cat: /sys/bus/scsi/devices/target1:0:0/model: No such file or directory
cat: /sys/bus/scsi/devices/target1:0:0/type: No such file or directory
cat: /sys/bus/scsi/devices/target1:0:0/rev: No such file or directory
cat: /sys/bus/scsi/devices/target1:0:0/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/target2:0:0/vendor: No such file or directory
cat: /sys/bus/scsi/devices/target2:0:0/model: No such file or directory
cat: /sys/bus/scsi/devices/target2:0:0/type: No such file or directory
cat: /sys/bus/scsi/devices/target2:0:0/rev: No such file or directory
cat: /sys/bus/scsi/devices/target2:0:0/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/target3:0:0/vendor: No such file or directory
cat: /sys/bus/scsi/devices/target3:0:0/model: No such file or directory
cat: /sys/bus/scsi/devices/target3:0:0/type: No such file or directory
cat: /sys/bus/scsi/devices/target3:0:0/rev: No such file or directory
cat: /sys/bus/scsi/devices/target3:0:0/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/host4/vendor: No such file or directory
cat: /sys/bus/scsi/devices/host4/model: No such file or directory
cat: /sys/bus/scsi/devices/host4/type: No such file or directory
cat: /sys/bus/scsi/devices/host4/rev: No such file or directory
cat: /sys/bus/scsi/devices/host4/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/host5/vendor: No such file or directory
cat: /sys/bus/scsi/devices/host5/model: No such file or directory
cat: /sys/bus/scsi/devices/host5/type: No such file or directory
cat: /sys/bus/scsi/devices/host5/rev: No such file or directory
cat: /sys/bus/scsi/devices/host5/scsi_level: No such file or directory
cat: /sys/bus/scsi/devices/target5:0:0/vendor: No such file or directory
cat: /sys/bus/scsi/devices/target5:0:0/model: No such file or directory
cat: /sys/bus/scsi/devices/target5:0:0/type: No such file or directory
cat: /sys/bus/scsi/devices/target5:0:0/rev: No such file or directory
cat: /sys/bus/scsi/devices/target5:0:0/scsi_level: No such file or directory
/usr/lib/xen-4.1/bin/../lib/python/xen/xend/XendAPI.py:551: DeprecationWarning: object.__new__() takes no parameters
return object.__new__(cls, *args, **kwds)
xc: error: panic: xc_dom_bzimageloader.c:556: xc_dom_probe_bzimage_kernel: kernel is not a bzImage: Invalid kernel
RTNETLINK answers: Operation not supported
RTNETLINK answers: Operation not supported
RTNETLINK answers: Operation not supported
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
RTNETLINK answers: Operation not supported
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
RTNETLINK answers: Operation not supported
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
RTNETLINK answers: Operation not supported
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
DRBD module version: 8.3.11
userland version: 8.3.7
you should upgrade your drbd tools!
Reply to: