search

clalancette / pycdlib

Python library to read and write ISOs
GNU Lesser General Public License v2.1
151 stars 39 forks source link

iso image made by pycdlib not working as config drive for cloudinit #7

Closed ghost closed 6 years ago

ghost commented 6 years ago

Attached are two iso files.

seednew.iso is created by genisoimage, cidata.iso is created by pycdlib

seednew.iso.zip cidata.iso.zip

I am trying to make a config drive for cloudinit http://cloudinit.readthedocs.io/en/latest/topics/datasources/nocloud.html

seednew.iso works cidata.iso does not seem to work for some reason.

I can't see what is different between them that would prevent cidata.iso working

There's only two files and hopefully they are the same on each iso:

root@kvmhost:/home/ubuntu/livedomainfiles/90dc8caf-fb05-43a1-81be-ef789706b7fe# cat meta-data
local-hostname: cloudimg
root@kvmhost:/home/ubuntu/livedomainfiles/90dc8caf-fb05-43a1-81be-ef789706b7fe# cat user-data
#cloud-config
password: password
chpasswd: { expire: False }
ssh_pwauth: True
root@kvmhost:/home/ubuntu/livedomainfiles/90dc8caf-fb05-43a1-81be-ef789706b7fe#

The command to generate the iso image is this:

genisoimage -output seednew.iso -volid cidata -joliet -rock user-data meta-data

My Python code to generate the iso looks like this:

import pycdlib
from io import StringIO, BytesIO
import sys
import textwrap
import helpers

def make_iso(userdata, metadata, live_instance_files_dir):
    # Create a new PyCdlib object.
    iso = pycdlib.PyCdlib()

    # Create a new ISO, accepting all of the defaults.
    iso.new(interchange_level=4,
            joliet=True,
            rock_ridge='1.09',
            vol_ident='cidata')
    # Add a new file to the ISO, with the contents coming from the file object.
    # Note that the file object must remain open for the lifetime of the PyCdlib
    # object, as the PyCdlib object uses it for internal operations.  Also note that
    # the filename passed here is the filename the data will get assigned on the
    # final ISO; it must begin with a forward slash, and according to ISO9660 must
    # have a '.', and a semicolon followed by a number.  PyCdlib will raise a
    # PyCdlibException if any of the rules for an ISO9660 filename are violated.

    metadata_file = BytesIO(metadata.encode())
    metadata_file.seek(0)
    iso.add_fp(metadata_file,
               len(metadata),
               '/METADATA.;1',
               rr_name="meta-data",
               joliet_path='/meta-data',
               )

    userdata_file = BytesIO(userdata.encode())
    iso.add_fp(userdata_file,
               len(userdata),
               '/USERDATA.;1',
               rr_name="user-data",
               joliet_path='/user-data',
               )

    # Write out the ISO to the file called 'new.iso'.  This will fully master the
    # ISO, creating a file that can be burned onto a CD.
    domain_cidata_iso_file_abs_path = helpers.cleanpath(live_instance_files_dir, 'cidata.iso')
    iso.write(domain_cidata_iso_file_abs_path)

    # Close the ISO object.  After this call, the PyCdlib object has forgotten
    # everything about the previous ISO, and can be re-used.
    iso.close()
    userdata_file.close()
    metadata_file.close()
    return domain_cidata_iso_file_abs_path

Any guess as to why one of these isos works and the other does not?

thanks!

clalancette commented 6 years ago

I'll take a look at this in more detail over the weekend. Two preliminary questions, though:

  1. What version of pycdlib are you using? If you are using it from git, which hash?
  2. What exactly do you mean by "it doesn't work"? Can you not read the files from it, boot from it, or something else?

Thanks!

clalancette commented 6 years ago

So looking at those two isos that you attached, a couple of things stood out at me that might be different between them:

  1. You are using 'interchange_level=4' for the pycdlib ISO, but not for the genisoimage one. Try changing that to 'interchange_level=3' for pycdlib. Also, try adding -iso-level 4 to the genisoimage command-line, and see if that makes it start failing with genisoimage.
  2. Try passing -no-pad on the genisoimage command-line, and see if that changes anything.
  3. Try passing sys_ident='LINUX' to the pycdlib new method.

Besides those things, nothing is very different between the two. If doing none of the above helps, it would help to get some debugging output from the system where this is failing so I can get a better idea of what exactly it doesn't like.

ghost commented 6 years ago

Below are two captures of commands. The first is launching a virtual machine using qemu. The iso image is a cloudinit iso that the operating system uses to configure things, in this case we're interested in it configuring the password for the default user 'ubuntu'.

The first transcript - with seednew.iso generated by genisoimage (refer to top of transcript for command params) shows boot all the way to login prompt and successful login which indicates that the iso was read and password set.

I wondered if there might be a clue in this discussion but it went over my head https://bbs.archlinux.org/viewtopic.php?id=195763&p=2

Python code is at bottom.

The second transcript shows the same process but instead using iso generated by pycdlib. The noticable error in the boot commands is:

[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
[    9.464456] blk_update_request: I/O error, dev fd0, sector 0
[    9.512448] blk_update_request: I/O error, dev fd0, sector 0
[    9.568440] blk_update_request: I/O error, dev fd0, sector 0
[    9.676433] blk_update_request: I/O error, dev fd0, sector 0
[    9.728434] blk_update_request: I/O error, dev fd0, sector 0
[    9.780444] blk_update_request: I/O error, dev fd0, sector 0
[    9.892442] blk_update_request: I/O error, dev fd0, sector 0
[    9.944444] blk_update_request: I/O error, dev fd0, sector 0
[   10.146121] cloud-init[409]: Cloud-init v. 17.1 running 'init-local' at Sat, 17 Feb 2018 00:24:37 +0000. Up 8.50 seconds.

Exhibit 1: successful boot:

root@kvmhost:/home/ubuntu/livedomainfiles/8d4e4dfc-2191-4a96-b4cb-f760dba06b79# genisoimage  -no-pad -iso-level 4 -output seednew.iso -volid cidata -joliet -rock user-data meta-data
Warning: Creating ISO-9660:1999 (version 2) filesystem.
Warning: ISO-9660 filenames longer than 31 may cause buffer overflows in the OS.
Total translation table size: 0
Total rockridge attributes bytes: 331
Total directory bytes: 0
Path table size(bytes): 10
Max brk space used 0
34 extents written (0 MB)
root@kvmhost:/home/ubuntu/livedomainfiles/8d4e4dfc-2191-4a96-b4cb-f760dba06b79# cp /home/ubuntu/sourceosimages/xenial-server-cloudimg-amd64-disk1.img .
root@kvmhost:/home/ubuntu/livedomainfiles/8d4e4dfc-2191-4a96-b4cb-f760dba06b79# qemu-system-x86_64 --enable-kvm -net nic,macaddr='52:54:be:36:42:01' -net bridge,br=br0  -hda ./xenial-server-cloudimg-amd64-disk1.img -m 1024 -nographic -drive file=./seednew.iso,media=cdrom,readonly
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.0-112-generic (buildd@lgw01-amd64-010) (gcc version 5.4.0 20160609 (Ubuntu 5.4.0-6ubuntu1~16.04.5) ) #135-Ubuntu SMP Fri Jan 19 11:48:36 UTC 2018 (Ubuntu 4.4.0-112.135-generic 4.4.98)
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.4.0-112-generic root=LABEL=cloudimg-rootfs ro console=tty1 console=ttyS0
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    0.000000] x86/fpu: Legacy x87 FPU detected.
[    0.000000] x86/fpu: Using 'lazy' FPU context switches.
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdffff] usable
[    0.000000] BIOS-e820: [mem 0x000000003ffe0000-0x000000003fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] Hypervisor detected: KVM
[    0.000000] e820: last_pfn = 0x3ffe0 max_arch_pfn = 0x400000000
[    0.000000] x86/PAT: PAT not supported by CPU.
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC
[    0.000000] found SMP MP-table at [mem 0x000f6640-0x000f664f] mapped at [ffff8800000f6640]
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] RAMDISK: [mem 0x36adc000-0x37565fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6460 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000003FFE16EE 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000003FFE0C14 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000003FFE0040 000BD4 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000003FFE0000 000040
[    0.000000] ACPI: SSDT 0x000000003FFE0C88 0009B6 (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: APIC 0x000000003FFE163E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000003FFE16B6 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffdffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x3ffdb000-0x3ffdffff]
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:3ffd3001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 2984853170 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x000000003ffdffff]
[    0.000000]   Normal   empty
[    0.000000]   Device   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000003ffdffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdffff]
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.000000] e820: [mem 0x40000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] PERCPU: Embedded 34 pages/cpu @ffff88003fc00000 s99544 r8192 d31528 u2097152
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 3fc101c0
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 257897
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.4.0-112-generic root=LABEL=cloudimg-rootfs ro console=tty1 console=ttyS0
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Memory: 1001816K/1048056K available (8499K kernel code, 1309K rwdata, 3988K rodata, 1508K init, 1316K bss, 46240K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Kernel/User page tables isolation: enabled
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 64.
[    0.000000]  RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1
[    0.000000] NR_IRQS:33024 nr_irqs:256 16
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty1] enabled
[    0.000000] console [ttyS0] enabled
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.000000] tsc: Detected 2095.148 MHz processor
[    0.128384] Calibrating delay loop (skipped) preset value.. 4190.29 BogoMIPS (lpj=8380592)
[    0.129903] pid_max: default: 32768 minimum: 301
[    0.130726] ACPI: Core revision 20150930
[    0.132665] ACPI: 2 ACPI AML tables successfully acquired and loaded
[    0.133876] Security Framework initialized
[    0.134623] Yama: becoming mindful.
[    0.135313] AppArmor: AppArmor initialized
[    0.136189] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.137804] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.139027] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.140159] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.141489] Initializing cgroup subsys io
[    0.142241] Initializing cgroup subsys memory
[    0.143034] Initializing cgroup subsys devices
[    0.143873] Initializing cgroup subsys freezer
[    0.144667] Initializing cgroup subsys net_cls
[    0.145464] Initializing cgroup subsys perf_event
[    0.146297] Initializing cgroup subsys net_prio
[    0.147102] Initializing cgroup subsys hugetlb
[    0.147933] Initializing cgroup subsys pids
[    0.148771] FEATURE SPEC_CTRL Not Present
[    0.149516] mce: CPU supports 10 MCE banks
[    0.150301] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.151209] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.167673] Freeing SMP alternatives memory: 32K
[    0.174256] ftrace: allocating 32180 entries in 126 pages
[    0.207755] smpboot: APIC(0) Converting physical 0 to logical package 0
[    0.208824] smpboot: Max logical packages: 1
[    0.209758] x2apic enabled
[    0.210568] Switched APIC routing to physical x2apic.
[    0.212445] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.319514] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    0.321306] Performance Events: Broken PMU hardware detected, using software events only.
[    0.323521] Failed to access perfctr msr (MSR c2 is 0)
[    0.325157] x86: Booted up 1 node, 1 CPUs
[    0.325867] smpboot: Total of 1 processors activated (4190.29 BogoMIPS)
[    0.327165] devtmpfs: initialized
[    0.329635] evm: security.selinux
[    0.330262] evm: security.SMACK64
[    0.330876] evm: security.SMACK64EXEC
[    0.331549] evm: security.SMACK64TRANSMUTE
[    0.332261] evm: security.SMACK64MMAP
[    0.332919] evm: security.ima
[    0.333495] evm: security.capability
[    0.334255] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.335827] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.336870] pinctrl core: initialized pinctrl subsystem
[    0.337864] RTC time:  0:22:18, date: 02/17/18
[    0.338728] NET: Registered protocol family 16
[    0.339669] cpuidle: using governor ladder
[    0.340392] cpuidle: using governor menu
[    0.341084] PCCT header not found.
[    0.341778] ACPI: bus type PCI registered
[    0.342479] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.343593] PCI: Using configuration type 1 for base access
[    0.345852] ACPI: Added _OSI(Module Device)
[    0.346600] ACPI: Added _OSI(Processor Device)
[    0.347372] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.348172] ACPI: Added _OSI(Processor Aggregator Device)
[    0.350090] ACPI: Interpreter enabled
[    0.350788] ACPI: (supports S0 S3 S4 S5)
[    0.351495] ACPI: Using IOAPIC for interrupt routing
[    0.352339] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.356448] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.357449] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.358515] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.359561] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.361635] acpiphp: Slot [3] registered
[    0.362361] acpiphp: Slot [4] registered
[    0.363083] acpiphp: Slot [5] registered
[    0.363815] acpiphp: Slot [6] registered
[    0.364534] acpiphp: Slot [7] registered
[    0.365255] acpiphp: Slot [8] registered
[    0.365976] acpiphp: Slot [9] registered
[    0.366693] acpiphp: Slot [10] registered
[    0.367428] acpiphp: Slot [11] registered
[    0.368169] acpiphp: Slot [12] registered
[    0.368895] acpiphp: Slot [13] registered
[    0.369622] acpiphp: Slot [14] registered
[    0.381894] acpiphp: Slot [15] registered
[    0.382629] acpiphp: Slot [16] registered
[    0.383359] acpiphp: Slot [17] registered
[    0.384085] acpiphp: Slot [18] registered
[    0.384804] acpiphp: Slot [19] registered
[    0.385523] acpiphp: Slot [20] registered
[    0.386241] acpiphp: Slot [21] registered
[    0.386961] acpiphp: Slot [22] registered
[    0.387679] acpiphp: Slot [23] registered
[    0.388405] acpiphp: Slot [24] registered
[    0.389127] acpiphp: Slot [25] registered
[    0.389843] acpiphp: Slot [26] registered
[    0.390564] acpiphp: Slot [27] registered
[    0.391282] acpiphp: Slot [28] registered
[    0.392006] acpiphp: Slot [29] registered
[    0.392727] acpiphp: Slot [30] registered
[    0.393444] acpiphp: Slot [31] registered
[    0.394152] PCI host bridge to bus 0000:00
[    0.394861] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.395900] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.396938] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.398201] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[    0.399468] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.404742] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.405848] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.406845] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.407937] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.409422] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.410656] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.427828] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.429179] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.430526] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.431890] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.433798] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.435126] ACPI: Enabled 16 GPEs in block 00 to 0F
[    0.436270] vgaarb: setting as boot device: PCI:0000:00:02.0
[    0.437208] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.438608] vgaarb: loaded
[    0.439170] vgaarb: bridge control possible 0000:00:02.0
[    0.440336] SCSI subsystem initialized
[    0.441092] ACPI: bus type USB registered
[    0.441841] usbcore: registered new interface driver usbfs
[    0.442763] usbcore: registered new interface driver hub
[    0.443673] usbcore: registered new device driver usb
[    0.444670] PCI: Using ACPI for IRQ routing
[    0.445619] NetLabel: Initializing
[    0.446271] NetLabel:  domain hash size = 128
[    0.447045] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.447922] NetLabel:  unlabeled traffic allowed by default
[    0.448923] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    0.450045] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.451159] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    0.456292] amd_nb: Cannot enumerate AMD northbridges
[    0.457174] clocksource: Switched to clocksource kvm-clock
[    0.463935] AppArmor: AppArmor Filesystem Enabled
[    0.464854] pnp: PnP ACPI init
[    0.465917] pnp: PnP ACPI: found 6 devices
[    0.472629] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.474193] NET: Registered protocol family 2
[    0.475117] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[    0.476267] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[    0.477328] TCP: Hash tables configured (established 8192 bind 8192)
[    0.478372] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.479351] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.480408] NET: Registered protocol family 1
[    0.481211] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.482190] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.483155] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.484240] Unpacking initramfs...
[    2.844938] Freeing initrd memory: 10792K
[    2.845930] Scanning for low memory corruption every 60 seconds
[    2.847189] audit: initializing netlink subsys (disabled)
[    2.848115] audit: type=2000 audit(1518826941.482:1): initialized
[    2.849448] Initialise system trusted keyring
[    2.850317] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    2.852898] zbud: loaded
[    2.853657] VFS: Disk quotas dquot_6.6.0
[    2.854417] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    2.855784] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    2.856981] fuse init (API version 7.23)
[    2.857861] Key type big_key registered
[    2.858599] Allocating IMA MOK and blacklist keyrings.
[    2.859654] Key type asymmetric registered
[    2.860404] Asymmetric key parser 'x509' registered
[    2.861298] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    2.862632] io scheduler noop registered
[    2.863360] io scheduler deadline registered (default)
[    2.864264] io scheduler cfq registered
[    2.865065] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    2.866020] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    2.867182] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    2.868491] ACPI: Power Button [PWRF]
[    2.869328] GHES: HEST is not enabled!
[    2.870108] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    2.894363] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    2.896839] Linux agpgart interface v0.103
[    2.898654] loop: module loaded
[    2.900107] scsi host0: ata_piix
[    2.900816] scsi host1: ata_piix
[    2.901498] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14
[    2.902574] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15
[    2.903936] libphy: Fixed MDIO Bus: probed
[    2.904674] tun: Universal TUN/TAP device driver, 1.6
[    2.905545] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    2.906773] PPP generic driver version 2.4.2
[    2.907602] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.908643] ehci-pci: EHCI PCI platform driver
[    2.909444] ehci-platform: EHCI generic platform driver
[    2.910334] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.911344] ohci-pci: OHCI PCI platform driver
[    2.912126] ohci-platform: OHCI generic platform driver
[    2.913016] uhci_hcd: USB Universal Host Controller Interface driver
[    2.914105] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    2.916110] serio: i8042 KBD port at 0x60,0x64 irq 1
[    2.916966] serio: i8042 AUX port at 0x60,0x64 irq 12
[    2.917938] mousedev: PS/2 mouse device common for all mice
[    2.919112] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    2.920819] rtc_cmos 00:00: RTC can wake from S4
[    2.921865] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[    2.922971] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[    2.924111] i2c /dev entries driver
[    2.924830] device-mapper: uevent: version 1.0.3
[    2.925728] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com
[    2.927192] ledtrig-cpu: registered to indicate activity on CPUs
[    2.928460] NET: Registered protocol family 10
[    2.929422] NET: Registered protocol family 17
[    2.930229] Key type dns_resolver registered
[    2.931120] microcode: CPU0 sig=0x663, pf=0x1, revision=0x1
[    2.932096] microcode: Microcode Update Driver: v2.01 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    2.933719] registered taskstats version 1
[    2.934474] Loading compiled-in X.509 certificates
[    2.936170] Loaded X.509 cert 'Build time autogenerated kernel key: 4c603087bab8f0a02cb3b4e1fb4c40f507e71ef1'
[    2.937834] zswap: loaded using pool lzo/zbud
[    2.940607] Key type trusted registered
[    2.944863] Key type encrypted registered
[    2.945652] AppArmor: AppArmor sha1 policy hashing enabled
[    2.946577] ima: No TPM chip found, activating TPM-bypass!
[    2.947509] evm: HMAC attrs: 0x1
[    2.948283]   Magic number: 6:72:355
[    2.948963] input event1: hash matches
[    2.949805] rtc_cmos 00:00: setting system clock to 2018-02-17 00:22:20 UTC (1518826940)
[    2.951352] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    2.952337] EDD information not available.
[    3.057755] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
[    3.058711] ata1.00: 4612096 sectors, multi 16: LBA48
[    3.060150] ata1.01: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    3.064029] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    3.067529] ata1.00: configured for MWDMA2
[    3.069714] ata2.00: configured for MWDMA2
[    3.071795] ata1.01: configured for MWDMA2
[    3.072868] scsi 0:0:0:0: Direct-Access     ATA      QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    3.074549] sd 0:0:0:0: [sda] 4612096 512-byte logical blocks: (2.36 GB/2.20 GiB)
[    3.075979] sd 0:0:0:0: [sda] Write Protect is off
[    3.076886] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    3.077840] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.080333] scsi 0:0:1:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    3.081786]  sda: sda1
[    3.083015] sr 0:0:1:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    3.084101] cdrom: Uniform CD-ROM driver Revision: 3.20
[    3.085142] sd 0:0:0:0: [sda] Attached SCSI disk
[    3.086265] sr 0:0:1:0: Attached scsi generic sg1 type 5
[    3.087435] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    3.105580] sr 1:0:0:0: [sr1] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    3.106990] sr 1:0:0:0: Attached scsi generic sg2 type 5
[    3.109113] Freeing unused kernel memory: 1508K
[    3.109937] Write protecting the kernel read-only data: 14336k
[    3.111336] Freeing unused kernel memory: 1728K
[    3.112432] Freeing unused kernel memory: 108K
Loading, please wait...
starting version 229
[    3.127909] random: udevadm: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.130282] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.132051] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.133738] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.135383] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.137244] random: udevadm: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.149816] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.151453] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.153076] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.157349] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.264573] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    3.265747] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    3.267191] Floppy drive(s): fd0 is 1.44M
[    3.281544] FDC 0 is a S82078B
[    3.290765] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4
[    3.292488] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3
[    3.385429] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[    3.722868] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:be:36:42:01
[    3.724677] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[    3.728167] e1000 0000:00:03.0 ens3: renamed from eth0
[    3.845287] tsc: Refined TSC clocksource calibration: 2095.122 MHz
[    3.846757] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e333095e4b, max_idle_ns: 440795270105 ns
Begin: Loading essential drivers ... [    5.037898] md: linear personality registered for level -1
[    5.042255] md: multipath personality registered for level -4
[    5.046681] md: raid0 personality registered for level 0
[    5.051587] md: raid1 personality registered for level 1
[    5.125185] raid6: sse2x1   gen()  6469 MB/s
[    5.193182] raid6: sse2x1   xor()  4749 MB/s
[    5.261180] raid6: sse2x2   gen()  7934 MB/s
[    5.329179] raid6: sse2x2   xor()  5233 MB/s
[    5.397183] raid6: sse2x4   gen()  9178 MB/s
[    5.465180] raid6: sse2x4   xor()  6372 MB/s
[    5.465944] raid6: using algorithm sse2x4 gen() 9178 MB/s
[    5.466846] raid6: .... xor() 6372 MB/s, rmw enabled
[    5.467697] raid6: using intx1 recovery algorithm
[    5.470587] xor: measuring software checksum speed
[    5.509179]    prefetch64-sse: 10132.000 MB/sec
[    5.549177]    generic_sse:  9350.000 MB/sec
[    5.549913] xor: using function: prefetch64-sse (10132.000 MB/sec)
[    5.552818] async_tx: api initialized (async)
[    5.566277] md: raid6 personality registered for level 6
[    5.567232] md: raid5 personality registered for level 5
[    5.568122] md: raid4 personality registered for level 4
[    5.575875] md: raid10 personality registered for level 10
done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... [    5.613376] Btrfs loaded
Scanning for Btrfs filesystems
[    5.713201] blk_update_request: I/O error, dev fd0, sector 0
[    5.714164] floppy: error -5 while reading block 0
done.
Warning: fsck not present, so skipping root file system
[    5.739637] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
[    5.900573] systemd[1]: systemd 229 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN)
[    5.903505] systemd[1]: Detected virtualization qemu.
[    5.904380] systemd[1]: Detected architecture x86-64.

Welcome to Ubuntu 16.04.3 LTS!

[    5.908093] systemd[1]: Set hostname to <ubuntu>.
[    5.910697] systemd[1]: Initializing machine ID from random generator.
[    5.911810] systemd[1]: Installed transient /etc/machine-id file.
[    6.085201] blk_update_request: I/O error, dev fd0, sector 0
[    6.086162] floppy: error -5 while reading block 0
[    6.179263] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[  OK  ] Listening on Device-mapper event daemon FIFOs.
[    6.181567] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    6.183505] systemd[1]: Listening on Syslog Socket.
[  OK  ] Listening on Syslog Socket.
[    6.185311] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    6.187163] systemd[1]: Listening on LVM2 metadata daemon socket.
[  OK  ] Listening on LVM2 metadata daemon socket.
[    6.189523] systemd[1]: Created slice System Slice.
[  OK  ] Created slice System Slice.
[    6.191647] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[    6.193777] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    6.196317] systemd[1]: Starting Uncomplicated firewall...
         Starting Uncomplicated firewall...
[    6.198226] systemd[1]: Listening on Journal Audit Socket.
[  OK  ] Listening on Journal Audit Socket.
[    6.202947] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting POSIX Message Queue File System...
[    6.216509] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
         Starting Monitoring of LVM2 mirrors... dmeventd or progress polling...
[    6.220067] systemd[1]: Starting Create list of required static device nodes for the current kernel...
         Starting Create list of required st... nodes for the current kernel...
[    6.244943] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[  OK  ] Set up automount Arbitrary Executab...ats File System Automount Point.
[    6.261591] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    6.263645] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[    6.265897] systemd[1]: Listening on LVM2 poll daemon socket.
[  OK  ] Listening on LVM2 poll daemon socket.
[    6.268623] systemd[1]: Mounting Debug File System...
         Mounting Debug File System...
[    6.270396] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[    6.274770] systemd[1]: Reached target Swap.
[  OK  ] Reached target Swap.
[    6.277283] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    6.281415] systemd[1]: Started Trigger resolvconf update for networkd DNS.
[  OK  ] Started Trigger resolvconf update for networkd DNS.
[    6.283654] systemd[1]: Reached target Slices.
[  OK  ] Reached target Slices.
[    6.285373] systemd[1]: Reached target User and Group Name Lookups.
[  OK  ] Reached target User and Group Name Lookups.
[    6.289441] systemd[1]: Starting Load Kernel Modules...
         Starting Load Kernel Modules...
[    6.291162] systemd[1]: Reached target Encrypted Volumes.
[  OK  ] Reached target Encrypted Volumes.
[    6.295000] systemd[1]: Starting Set console keymap...
[    6.303431] Loading iSCSI transport class v2.0-870.
         Starting Set console keymap...
[    6.306834] systemd[1]: Starting Nameserver information manager...
         Starting Nameserver information manager...
[    6.315278] systemd[1]: Starting Remount Root and Kernel File Systems...
         Starting Remount Root and Kernel File Systems...
[    6.323776] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[    6.330738] systemd[1]: Mounting Huge Pages File System...
         Mounting Huge Pages File System...
[    6.340066] systemd[1]: Mounted Debug File System.
[  OK  ] Mounted Debug File System.
[    6.344778] systemd[1]: Mounted POSIX Message Queue File System.
[  OK  ] Mounted POSIX Message Queue File System.
[    6.349408] EXT4-fs (sda1): re-mounted. Opts: (null)
[    6.353841] systemd[1]: Mounted Huge Pages File System.
[  OK  ] Mounted Huge Pages File System.
[    6.357961] systemd[1]: Started Uncomplicated firewall.
[  OK  ] Started Uncomplicated firewall.
[    6.362467] iscsi: registered transport (tcp)
[    6.365982] systemd[1]: Started Create list of required static device nodes for the current kernel.
[  OK  ] Started Create list of required sta...ce nodes for the current kernel.
[    6.374711] systemd[1]: Started Remount Root and Kernel File Systems.
[  OK  ] Started Remount Root and Kernel File Systems.
[    6.394617] systemd[1]: Started Nameserver information manager.
[  OK  ] Started Nameserver information manager.
[    6.470152] random: nonblocking pool is initialized
[    6.487442] systemd[1]: Started Journal Service.
[  OK  ] Started Journal Service.
[  OK  ] Started LVM2 metadata daemon.
         Starting udev Coldplug all Devices...
         Starting Load/Save Random Seed...
[    6.521982] iscsi: registered transport (iser)
         Starting Initial cloud-init job (pre-networking)...
         Starting Flush Journal to Persistent Storage...
         Starting Create Static Device Nodes in /dev...
[  OK  ] Started Load/Save Random Seed.
[  OK  ] Started Load Kernel Modules.
         Starting Apply Kernel Variables...
[    6.617279] systemd-journald[348]: Received request to flush runtime journal from PID 1
         Mounting FUSE Control File System...
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Mounted FUSE Control File System.
[  OK  ] Started Create Static Device Nodes in /dev.
[  OK  ] Started Apply Kernel Variables.
         Starting udev Kernel Device Manager...
[  OK  ] Started Monitoring of LVM2 mirrors,...ng dmeventd or progress polling.
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Started Set console keymap.
[  OK  ] Reached target Local File Systems (Pre).
[  OK  ] Reached target Local File Systems.
         Starting Set console font and keymap...
         Starting Commit a transient machine-id on disk...
         Starting Tell Plymouth To Write Out Runtime Data...
         Starting Create Volatile Files and Directories...
         Starting LSB: AppArmor initialization...
[  OK  ] Started Commit a transient machine-id on disk.
[  OK  ] Started Tell Plymouth To Write Out Runtime Data.
[  OK  ] Started Create Volatile Files and Directories.
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
         Starting Update UTMP about System Boot/Shutdown...
         Starting Network Time Synchronization...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Found device /dev/ttyS0.
[    9.457202] blk_update_request: I/O error, dev fd0, sector 0
[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
[    9.505199] blk_update_request: I/O error, dev fd0, sector 0
[    9.561210] blk_update_request: I/O error, dev fd0, sector 0
[    9.612144] cloud-init[388]: Cloud-init v. 17.1 running 'init-local' at Sat, 17 Feb 2018 00:22:26 +0000. Up 8.53 seconds.
[  OK  ] Started Initial cloud-init job (pre-networking).
[  OK  ] Reached target Network (Pre).
[  OK  ] Started LSB: AppArmor initialization.
         Starting Raise network interfaces...
[  OK  ] Started Set console font and keymap.
[  OK  ] Created slice system-getty.slice.
[  OK  ] Started Raise network interfaces.
         Starting Initial cloud-init job (metadata service crawler)...
[  OK  ] Reached target Network.
[   15.217034] cloud-init[896]: Cloud-init v. 17.1 running 'init' at Sat, 17 Feb 2018 00:22:32 +0000. Up 14.28 seconds.
[   15.219132] cloud-init[896]: ci-info: ++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++
[   15.224527] cloud-init[896]: ci-info: +--------+------+------------------------------+---------------+-------+-------------------+
[   15.226465] cloud-init[896]: ci-info: | Device |  Up  |           Address            |      Mask     | Scope |     Hw-Address    |
[   15.232564] cloud-init[896]: ci-info: +--------+------+------------------------------+---------------+-------+-------------------+
[   15.234521] cloud-init[896]: ci-info: |  ens3  | True |        192.168.1.142         | 255.255.255.0 |   .   | 52:54:be:36:42:01 |
[   15.240546] cloud-init[896]: ci-info: |  ens3  | True | fe80::5054:beff:fe36:4201/64 |       .       |  link | 52:54:be:36:42:01 |
[   15.242458] cloud-init[896]: ci-info: |   lo   | True |          127.0.0.1           |   255.0.0.0   |   .   |         .         |
[   15.248545] cloud-init[896]: ci-info: |   lo   | True |           ::1/128            |       .       |  host |         .         |
[   15.250491] cloud-init[896]: ci-info: +--------+------+------------------------------+---------------+-------+-------------------+
[   15.256540] cloud-init[896]: ci-info: +++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++
[   15.258352] cloud-init[896]: ci-info: +-------+-------------+-------------+---------------+-----------+-------+
[   15.264287] cloud-init[896]: ci-info: | Route | Destination |   Gateway   |    Genmask    | Interface | Flags |
[   15.266155] cloud-init[896]: ci-info: +-------+-------------+-------------+---------------+-----------+-------+
[   15.272290] cloud-init[896]: ci-info: |   0   |   0.0.0.0   | 192.168.1.1 |    0.0.0.0    |    ens3   |   UG  |
[   15.274140] cloud-init[896]: ci-info: |   1   | 192.168.1.0 |   0.0.0.0   | 255.255.255.0 |    ens3   |   U   |
[   15.276240] cloud-init[896]: ci-info: +-------+-------------+-------------+---------------+-----------+-------+
[   15.280300] cloud-init[896]: Generating public/private rsa key pair.
[   15.284283] cloud-init[896]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key.
[   15.288291] cloud-init[896]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub.
[   15.289965] cloud-init[896]: The key fingerprint is:
[   15.292265] cloud-init[896]: SHA256:CdBsZBpNPjM7wLm1YDfYx20gmDOnsZULqo2U+Z4JV84 root@cloudimg
[   15.296285] cloud-init[896]: The key's randomart image is:
[   15.300300] cloud-init[896]: +---[RSA 2048]----+
[   15.301589] cloud-init[896]: |    oO=..        |
[   15.304293] cloud-init[896]: |   .BXOo o       |
[   15.308291] cloud-init[896]: |  o.O@@.o o      |
[   15.309598] cloud-init[896]: | +..oB.X o       |
[   15.312288] cloud-init[896]: |.+. = + S        |
[   15.316297] cloud-init[896]: |o..o E .         |
[   15.317606] cloud-init[896]: |  + o            |
[   15.320282] cloud-init[896]: |   +             |
[   15.323832] cloud-init[896]: |                 |
[   15.325354] cloud-init[896]: +----[SHA256]-----+
[  OK  ] Started Initial cloud-init job (metadata service crawler).
[  OK  ] Reached target System Initialization.
[   15.329815] cloud-init[896]: Generating public/private dsa key pair.
         Starting Socket activation for snappy daemon.
[   15.333378] cloud-init[896]: Your identification has been saved in /etc/ssh/ssh_host_dsa_key.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[   15.336531] cloud-init[896]: Your public key has been saved in /etc/ssh/ssh_host_dsa_key.pub.
[   15.338280]          Starting LXD - unix socket.
[  OK  ] Listening on ACPID Listen Socket.
[  OK  ] Started Timer to automatically refresh installed snaps.
[  OK  ] Listening on UUID daemon activation socket.
[  OK  cloud-init[896]: The key fingerprint is:
[   15.344838] cloud-init[896]: SHA256:l/gRd+us40O4NUVS1wiySF+VrTwGpUKCo3S/S4afVrw root@cloudimg
[   15.346089] cloud-init[896]: The key's randomart image is:
[   15.347023] cloud-init[896]: +---[DSA 1024]----+
[   15.347827] ] Listening on D-Bus System Message Bus Socket.
cloud-init[896]: |       .o o o=++o|
[   15.350126] cloud-init[896]: |    . +. = +o.+.o|
[  OK  ] Started ACPI Events Check.
[  OK  ] Reached target Paths.
[   15.353636] cloud-init[896]: |   . o o. = o=.. |
[  OK  ] Reached target Cloud-config availability.
[   15.356034] cloud-init[896]: |    .   .. = .*. |
[  OK  ] Reached target Network is Online.
[   15.358268] cloud-init[896]: |       .So+. o.. |
[   15.360118] cloud-init[896]: |      . +o+.+o   |
         Starting iSCSI initiator daemon (iscsid)...
[   15.362480] cloud-init[896]: |       + +.= .o  |
[  OK  [   15.365189] ] Started Daily apt download activities.
[  OK  ] Started Daily apt upgrade and clean activities.cloud-init
[  OK  ] Reached target Timers.
[  OK  ] Listening on Socket activation for snappy daemon.
[  OK  ] Listening on LXD - unix socket.
[896]: |        = E o.   |
[   15.378727] cloud-init[896]: |       .   .oo   |
[  OK  ] Reached target Sockets.
[   15.392164] cloud-init[896]: +----[SHA256]-----+
[   15.393795] cloud-init[896]: Generating public/private ecdsa key pair.
[  OK  ] Reached target Basic System.
         Starting System Logging Service...
[   15.397210]          Starting LXD - container startup/shutdown...
cloud-init[  OK  ] Started ACPI event daemon.
         Starting Apply the settings specified in cloud-config...
[  OK  ] Started D-Bus System Message Bus.
[896]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key.
[   15.460156] cloud-init[896]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub.
[   15.493875] cloud-init[896]: The key fingerprint is:
         Starting LSB: Record successful boot for GRUB...
[  OK  ] Started Deferred execution scheduler.
         Starting /etc/rc.local Compatibility...
[  OK  ] Started Unattended Upgrades Shutdown.
         Starting Accounts Service...[   15.546449]
         Starting Snappy daemon...
         Starting Login Service...
[  OK  ] Started FUSE filesystem for LXC.
         Starting Pollinate to seed the pseudo random number generator...
[  OK  cloud-init] Started Regular background program processing daemon.
         [896]: Starting LSB: MD monitoring daemon...
[  OK  SHA256:spZkCLf8r8h8xZ8oIN0nnpu0e27tSLvEI4W6InCtt5Q root@cloudimg] Started System Logging Service.
[  OK  ] Started iSCSI initiator daemon (iscsid).
[  OK  ] Started /etc/rc.local Compatibility.

[   15.738345] cloud-init[896]:          Starting Login to default iSCSI targets...
[  OK  ] Started Login Service.
         Starting Authenticate and Authorize Users to Run Privileged Tasks...
The key's randomart image is:
[   15.942155] cloud-init[896]: +---[ECDSA 256]---+
[   15.947795] cloud-init[896]: |                 |
[   15.951568] cloud-init[896]: |                 |
[   15.955244] cloud-init[[896]:   OK  ] Started LSB: Record successful boot for GRUB.
[  OK  ] Started Login to default iSCSI targets.
[  OK  |  . .            |] Started LXD - container startup/shutdown.
[  OK  ] Started LSB: MD monitoring daemon.

[  OK  ] Started Authenticate and Authorize Users to Run Privileged Tasks.
[  OK  ] Started Accounts Service.
[  OK  ] Reached target Remote File Systems (Pre).
[  OK  ] Reached target Remote File Systems.
         Starting LSB: Set the CPU Frequency Scaling governor to "ondemand"...
         [   16.034010] Starting Permit User Sessions...
         Starting LSB: automatic crash report generation...
         Starting LSB: daemon to balance interrupts for SMP systems...
cloud-init[  OK  ] Started Permit User Sessions.
[896]:          Starting Terminate Plymouth Boot Screen...
         Starting Hold until boot process finishes up...
|   + o .         |[  OK  ] Started Hold until boot process finishes up.
[  OK  ] Started Serial Getty on ttyS0.
         Starting Set console scheme...
[  OK  ] Started Getty on tty1.
[  OK  ] Reached target Login Prompts.

[  OK  ] Started LSB: Set the CPU Frequency Scaling governor to "ondemand".
[  OK  ] Started Terminate Plymouth Boot Screen.
[  OK  ] Started Set console scheme.
[   16.184434] cloud-init[896]: |   o+.=.S        |
[   16.185657] cloud-init[896]: |. o +*o*+        |
[   16.220222] cloud-init[896]: |.. oEoB==+ .     |
[   16.224278] cloud-init[896]: |. o+.==B+++      |
[  OK  ] Started LSB: automatic crash report generation.
[  OK  ] Started LSB: daemon to balance interrupts for SMP systems.
[  OK  ] Started Snappy daemon.
[   16.247720] cloud-init[896]: | . o*o*O*o.      |
[   16.260453] cloud-init[896]: +----[SHA256]-----+
[   16.267027] cloud-init[896]: Generating public/private ed25519 key pair.
[   16.272514] cloud-init[896]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key.
[   16.276220] cloud-init[896]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub.
[   16.281007] cloud-init[896]: The key fingerprint is:
[   16.284195] cloud-init[896]: SHA256:nMZScks/1wJjN3Z/TafdfieB5+afQ7kzoRFRCuaLtlw root@cloudimg
[   16.292235] cloud-init[896]: The key's randomart image is:
[   16.293559] cloud-init[896]: +--[ED25519 256]--+
[   16.296185] cloud-init[896]: |           o  .. |
[   16.300214] cloud-init[896]: |          o ...  |
[   16.304229] cloud-init[896]: |      . + +.+.o o|
[   16.308235] cloud-init[896]: |       B =.=.* =+|
[   16.312212] cloud-init[896]: |      . SooE+ * B|
[   16.313500] cloud-init[896]: |       oo oo = *.|
[   16.320218] cloud-init[896]: |         o    B *|
[   16.324214] cloud-init[896]: |             + *+|
[   16.325437] cloud-init[896]: |              .o=|
[   16.332661] cloud-init[896]: +----[SHA256]-----+
[   16.797427] cloud-init[978]: Generating locales (this might take a while)...
[   17.715301] cloud-init[978]:   en_US.UTF-8... done
[   17.718041] cloud-init[978]: Generation complete.
[  OK  ] Stopped OpenBSD Secure Shell server.
         Starting OpenBSD Secure Shell server...
[  OK  ] Started OpenBSD Secure Shell server.
[  OK  ] Started Pollinate to seed the pseudo random number generator.
[  OK  ] Reached target Multi-User System.
[  OK  ] Reached target Graphical Interface.
         Starting Update UTMP about System Runlevel Changes...
[  OK  ] Started Update UTMP about System Runlevel Changes.
[   18.275972] cloud-init[978]: Cloud-init v. 17.1 running 'modules:config' at Sat, 17 Feb 2018 00:22:34 +0000. Up 16.63 seconds.
[  OK  ] Started Apply the settings specified in cloud-config.
         Starting Execute cloud user/final scripts...
ci-info: no authorized ssh keys fingerprints found for user ubuntu.
<14>Feb 17 00:22:36 ec2:
<14>Feb 17 00:22:36 ec2: #############################################################
<14>Feb 17 00:22:36 ec2: -----BEGIN SSH HOST KEY FINGERPRINTS-----
<14>Feb 17 00:22:36 ec2: 1024 SHA256:l/gRd+us40O4NUVS1wiySF+VrTwGpUKCo3S/S4afVrw root@cloudimg (DSA)
<14>Feb 17 00:22:36 ec2: 256 SHA256:spZkCLf8r8h8xZ8oIN0nnpu0e27tSLvEI4W6InCtt5Q root@cloudimg (ECDSA)
<14>Feb 17 00:22:36 ec2: 256 SHA256:nMZScks/1wJjN3Z/TafdfieB5+afQ7kzoRFRCuaLtlw root@cloudimg (ED25519)
<14>Feb 17 00:22:36 ec2: 2048 SHA256:CdBsZBpNPjM7wLm1YDfYx20gmDOnsZULqo2U+Z4JV84 root@cloudimg (RSA)
<14>Feb 17 00:22:36 ec2: -----END SSH HOST KEY FINGERPRINTS-----
<14>Feb 17 00:22:36 ec2: #############################################################
-----BEGIN SSH HOST KEY KEYS-----
ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBELyLJJWk/Pa0gnHO+9JeLsnPSQWxzapLmdbtZl/bLJoEjQLiS2+/CMfpGTVM3STbttiGftbL2kMJtevgUwIy5Y= root@cloudimg
ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIN9ycED6uwUHl+dr4e0IvBwoYDeZSzRnu+cf1EDhwuPN root@cloudimg
ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDSiA+NIOX9knhaJ+Jfwh9dn7D4ZxWFMaPHqFv7avp5fhHa2j96tD4o3rtLwCOXKP5P2t14EYsDbtt4wLjbJv0SJUivCIcToVIIib21PnlsC+F0zvt9jM2i7sICV1zk6UJ90jNDmkxSYsqeuXzB6ngs0F3fo8roD1zTDVkKBOWTw4bcihCxpEw4tBbySnR7gR28gMkgwmo7PnjRKmrz18r6++3W40jtUHVeM5SU0wNT8TgcyTWilkf1hrYfbkuHgGJjMEsnewQ1Ss7xw2Ru8H/NCj0PqhzpbWWoIiCox90/orBXdJ9O+xUDOhZiMicjMlSMO4Js4nGcebYh5/tMWg/j root@cloudimg
-----END SSH HOST KEY KEYS-----
[   18.912783] cloud-init[1224]: Cloud-init v. 17.1 running 'modules:final' at Sat, 17 Feb 2018 00:22:36 +0000. Up 18.67 seconds.
[   18.915264] cloud-init[1224]: ci-info: no authorized ssh keys fingerprints found for user ubuntu.
[   18.920365] cloud-init[1224]: Cloud-init v. 17.1 finished at Sat, 17 Feb 2018 00:22:36 +0000. Datasource DataSourceNoCloud [seed=/dev/sr0][dsmode=net].  Up 18.90 seconds
[  OK  ] Started Execute cloud user/final scripts.
[  OK  ] Reached target Cloud-init target.

Ubuntu 16.04.3 LTS cloudimg ttyS0

cloudimg login: ubuntu
Password:
Welcome to Ubuntu 16.04.3 LTS (GNU/Linux 4.4.0-112-generic x86_64)

 * Documentation:  https://help.ubuntu.com
 * Management:     https://landscape.canonical.com
 * Support:        https://ubuntu.com/advantage

  Get cloud support with Ubuntu Advantage Cloud Guest:
    http://www.ubuntu.com/business/services/cloud

0 packages can be updated.
0 updates are security updates.

The programs included with the Ubuntu system are free software;
the exact distribution terms for each program are described in the
individual files in /usr/share/doc/*/copyright.

Ubuntu comes with ABSOLUTELY NO WARRANTY, to the extent permitted by
applicable law.

To run a command as administrator (user "root"), use "sudo <command>".
See "man sudo_root" for details.

ubuntu@cloudimg:~$

Exhibit 2

root@kvmhost:/home/ubuntu/livedomainfiles/8d4e4dfc-2191-4a96-b4cb-f760dba06b79# cp /home/ubuntu/sourceosimages/xenial-server-cloudimg-amd64-disk1.img .
root@kvmhost:/home/ubuntu/livedomainfiles/8d4e4dfc-2191-4a96-b4cb-f760dba06b79# qemu-system-x86_64 --enable-kvm -net nic,macaddr='52:54:be:36:42:01' -net bridge,br=br0  -hda ./xenial-server-cloudimg-amd64-disk1.img -m 1024 -nographic -drive file=./cidata.iso,media=cdrom,readonly
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.0-112-generic (buildd@lgw01-amd64-010) (gcc version 5.4.0 20160609 (Ubuntu 5.4.0-6ubuntu1~16.04.5) ) #135-Ubuntu SMP Fri Jan 19 11:48:36 UTC 2018 (Ubuntu 4.4.0-112.135-generic 4.4.98)
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.4.0-112-generic root=LABEL=cloudimg-rootfs ro console=tty1 console=ttyS0
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    0.000000] x86/fpu: Legacy x87 FPU detected.
[    0.000000] x86/fpu: Using 'lazy' FPU context switches.
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdffff] usable
[    0.000000] BIOS-e820: [mem 0x000000003ffe0000-0x000000003fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] Hypervisor detected: KVM
[    0.000000] e820: last_pfn = 0x3ffe0 max_arch_pfn = 0x400000000
[    0.000000] x86/PAT: PAT not supported by CPU.
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC
[    0.000000] found SMP MP-table at [mem 0x000f6640-0x000f664f] mapped at [ffff8800000f6640]
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] RAMDISK: [mem 0x36adc000-0x37565fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6460 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000003FFE16EE 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000003FFE0C14 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000003FFE0040 000BD4 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000003FFE0000 000040
[    0.000000] ACPI: SSDT 0x000000003FFE0C88 0009B6 (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: APIC 0x000000003FFE163E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000003FFE16B6 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffdffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x3ffdb000-0x3ffdffff]
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:3ffd3001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 2983746928 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x000000003ffdffff]
[    0.000000]   Normal   empty
[    0.000000]   Device   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000003ffdffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdffff]
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.000000] e820: [mem 0x40000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] PERCPU: Embedded 34 pages/cpu @ffff88003fc00000 s99544 r8192 d31528 u2097152
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 3fc101c0
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 257897
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.4.0-112-generic root=LABEL=cloudimg-rootfs ro console=tty1 console=ttyS0
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Memory: 1001816K/1048056K available (8499K kernel code, 1309K rwdata, 3988K rodata, 1508K init, 1316K bss, 46240K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Kernel/User page tables isolation: enabled
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 64.
[    0.000000]  RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1
[    0.000000] NR_IRQS:33024 nr_irqs:256 16
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty1] enabled
[    0.000000] console [ttyS0] enabled
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.000000] tsc: Detected 2095.148 MHz processor
[    0.162081] Calibrating delay loop (skipped) preset value.. 4190.29 BogoMIPS (lpj=8380592)
[    0.164128] pid_max: default: 32768 minimum: 301
[    0.165271] ACPI: Core revision 20150930
[    0.167466] ACPI: 2 ACPI AML tables successfully acquired and loaded
[    0.169128] Security Framework initialized
[    0.170153] Yama: becoming mindful.
[    0.171065] AppArmor: AppArmor initialized
[    0.172190] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.174249] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.175902] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.177440] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.179204] Initializing cgroup subsys io
[    0.180214] Initializing cgroup subsys memory
[    0.181303] Initializing cgroup subsys devices
[    0.182382] Initializing cgroup subsys freezer
[    0.183460] Initializing cgroup subsys net_cls
[    0.184539] Initializing cgroup subsys perf_event
[    0.185673] Initializing cgroup subsys net_prio
[    0.186763] Initializing cgroup subsys hugetlb
[    0.187853] Initializing cgroup subsys pids
[    0.188967] FEATURE SPEC_CTRL Not Present
[    0.189967] mce: CPU supports 10 MCE banks
[    0.191013] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.192255] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.208140] Freeing SMP alternatives memory: 32K
[    0.215050] ftrace: allocating 32180 entries in 126 pages
[    0.248921] smpboot: APIC(0) Converting physical 0 to logical package 0
[    0.250416] smpboot: Max logical packages: 1
[    0.251641] x2apic enabled
[    0.252642] Switched APIC routing to physical x2apic.
[    0.254830] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.360928] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    0.363374] Performance Events: Broken PMU hardware detected, using software events only.
[    0.366106] Failed to access perfctr msr (MSR c2 is 0)
[    0.368099] x86: Booted up 1 node, 1 CPUs
[    0.369090] smpboot: Total of 1 processors activated (4190.29 BogoMIPS)
[    0.370824] devtmpfs: initialized
[    0.373435] evm: security.selinux
[    0.374282] evm: security.SMACK64
[    0.375127] evm: security.SMACK64EXEC
[    0.376049] evm: security.SMACK64TRANSMUTE
[    0.377047] evm: security.SMACK64MMAP
[    0.377954] evm: security.ima
[    0.378733] evm: security.capability
[    0.379749] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.381922] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.383365] pinctrl core: initialized pinctrl subsystem
[    0.384718] RTC time:  0:24:28, date: 02/17/18
[    0.385887] NET: Registered protocol family 16
[    0.387131] cpuidle: using governor ladder
[    0.388132] cpuidle: using governor menu
[    0.389100] PCCT header not found.
[    0.390024] ACPI: bus type PCI registered
[    0.391002] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.392527] PCI: Using configuration type 1 for base access
[    0.395171] ACPI: Added _OSI(Module Device)
[    0.396198] ACPI: Added _OSI(Processor Device)
[    0.397253] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.398355] ACPI: Added _OSI(Processor Aggregator Device)
[    0.400581] ACPI: Interpreter enabled
[    0.401528] ACPI: (supports S0 S3 S4 S5)
[    0.402482] ACPI: Using IOAPIC for interrupt routing
[    0.403645] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.408122] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.409505] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.410995] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.412449] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.415241] acpiphp: Slot [3] registered
[    0.416232] acpiphp: Slot [4] registered
[    0.417224] acpiphp: Slot [5] registered
[    0.418200] acpiphp: Slot [6] registered
[    0.419167] acpiphp: Slot [7] registered
[    0.420165] acpiphp: Slot [8] registered
[    0.421157] acpiphp: Slot [9] registered
[    0.422128] acpiphp: Slot [10] registered
[    0.423120] acpiphp: Slot [11] registered
[    0.424020] acpiphp: Slot [12] registered
[    0.424800] acpiphp: Slot [13] registered
[    0.425613] acpiphp: Slot [14] registered
[    0.437620] acpiphp: Slot [15] registered
[    0.438429] acpiphp: Slot [16] registered
[    0.439243] acpiphp: Slot [17] registered
[    0.440040] acpiphp: Slot [18] registered
[    0.440833] acpiphp: Slot [19] registered
[    0.441656] acpiphp: Slot [20] registered
[    0.442455] acpiphp: Slot [21] registered
[    0.443256] acpiphp: Slot [22] registered
[    0.444065] acpiphp: Slot [23] registered
[    0.444866] acpiphp: Slot [24] registered
[    0.445674] acpiphp: Slot [25] registered
[    0.446472] acpiphp: Slot [26] registered
[    0.447279] acpiphp: Slot [27] registered
[    0.448078] acpiphp: Slot [28] registered
[    0.448898] acpiphp: Slot [29] registered
[    0.449704] acpiphp: Slot [30] registered
[    0.450502] acpiphp: Slot [31] registered
[    0.451296] PCI host bridge to bus 0000:00
[    0.452099] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.453287] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.454463] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.455879] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[    0.457333] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.462788] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.464072] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.465236] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.466433] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.468075] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.469722] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.486398] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.487925] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.489435] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.490883] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.492884] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.494337] ACPI: Enabled 16 GPEs in block 00 to 0F
[    0.495582] vgaarb: setting as boot device: PCI:0000:00:02.0
[    0.496606] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.498111] vgaarb: loaded
[    0.498727] vgaarb: bridge control possible 0000:00:02.0
[    0.499955] SCSI subsystem initialized
[    0.500735] ACPI: bus type USB registered
[    0.501539] usbcore: registered new interface driver usbfs
[    0.502515] usbcore: registered new interface driver hub
[    0.503467] usbcore: registered new device driver usb
[    0.504497] PCI: Using ACPI for IRQ routing
[    0.505485] NetLabel: Initializing
[    0.506170] NetLabel:  domain hash size = 128
[    0.506970] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.507902] NetLabel:  unlabeled traffic allowed by default
[    0.508958] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    0.510136] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.511331] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    0.515490] amd_nb: Cannot enumerate AMD northbridges
[    0.516411] clocksource: Switched to clocksource kvm-clock
[    0.523275] AppArmor: AppArmor Filesystem Enabled
[    0.524308] pnp: PnP ACPI init
[    0.525421] pnp: PnP ACPI: found 6 devices
[    0.532205] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.533913] NET: Registered protocol family 2
[    0.534902] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[    0.536144] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[    0.537279] TCP: Hash tables configured (established 8192 bind 8192)
[    0.538386] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.539403] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.540545] NET: Registered protocol family 1
[    0.541359] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.542398] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.543416] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.544579] Unpacking initramfs...
[    2.905546] Freeing initrd memory: 10792K
[    2.906623] Scanning for low memory corruption every 60 seconds
[    2.908045] audit: initializing netlink subsys (disabled)
[    2.909149] audit: type=2000 audit(1518827071.401:1): initialized
[    2.910634] Initialise system trusted keyring
[    2.911610] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    2.914392] zbud: loaded
[    2.915197] VFS: Disk quotas dquot_6.6.0
[    2.916081] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    2.917649] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    2.919021] fuse init (API version 7.23)
[    2.920004] Key type big_key registered
[    2.920835] Allocating IMA MOK and blacklist keyrings.
[    2.921954] Key type asymmetric registered
[    2.922785] Asymmetric key parser 'x509' registered
[    2.923754] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    2.925249] io scheduler noop registered
[    2.926043] io scheduler deadline registered (default)
[    2.927058] io scheduler cfq registered
[    2.927958] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    2.929039] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    2.930341] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    2.931831] ACPI: Power Button [PWRF]
[    2.932744] GHES: HEST is not enabled!
[    2.933593] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    2.958005] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    2.960617] Linux agpgart interface v0.103
[    2.962493] loop: module loaded
[    2.964021] scsi host0: ata_piix
[    2.964806] scsi host1: ata_piix
[    2.965535] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14
[    2.966773] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15
[    2.968301] libphy: Fixed MDIO Bus: probed
[    2.969137] tun: Universal TUN/TAP device driver, 1.6
[    2.970078] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    2.971456] PPP generic driver version 2.4.2
[    2.972352] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.973554] ehci-pci: EHCI PCI platform driver
[    2.974450] ehci-platform: EHCI generic platform driver
[    2.975443] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.976585] ohci-pci: OHCI PCI platform driver
[    2.977455] ohci-platform: OHCI generic platform driver
[    2.978423] uhci_hcd: USB Universal Host Controller Interface driver
[    2.979638] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    2.981856] serio: i8042 KBD port at 0x60,0x64 irq 1
[    2.982805] serio: i8042 AUX port at 0x60,0x64 irq 12
[    2.983874] mousedev: PS/2 mouse device common for all mice
[    2.985170] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    2.987037] rtc_cmos 00:00: RTC can wake from S4
[    2.988142] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[    2.989447] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[    2.990707] i2c /dev entries driver
[    2.991504] device-mapper: uevent: version 1.0.3
[    2.992462] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com
[    2.994147] ledtrig-cpu: registered to indicate activity on CPUs
[    2.995548] NET: Registered protocol family 10
[    2.996623] NET: Registered protocol family 17
[    2.997514] Key type dns_resolver registered
[    2.998495] microcode: CPU0 sig=0x663, pf=0x1, revision=0x1
[    2.999539] microcode: Microcode Update Driver: v2.01 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    3.001348] registered taskstats version 1
[    3.002156] Loading compiled-in X.509 certificates
[    3.003910] Loaded X.509 cert 'Build time autogenerated kernel key: 4c603087bab8f0a02cb3b4e1fb4c40f507e71ef1'
[    3.005799] zswap: loaded using pool lzo/zbud
[    3.008635] Key type trusted registered
[    3.012942] Key type encrypted registered
[    3.013820] AppArmor: AppArmor sha1 policy hashing enabled
[    3.014822] ima: No TPM chip found, activating TPM-bypass!
[    3.015828] evm: HMAC attrs: 0x1
[    3.016689]   Magic number: 6:622:405
[    3.017511] rtc_cmos 00:00: setting system clock to 2018-02-17 00:24:31 UTC (1518827071)
[    3.019089] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    3.020187] EDD information not available.
[    3.120996] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
[    3.122072] ata1.00: 4612096 sectors, multi 16: LBA48
[    3.123051] ata1.01: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    3.126934] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    3.128691] ata2.00: configured for MWDMA2
[    3.129833] ata1.00: configured for MWDMA2
[    3.130994] ata1.01: configured for MWDMA2
[    3.132027] scsi 0:0:0:0: Direct-Access     ATA      QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    3.133743] sd 0:0:0:0: [sda] 4612096 512-byte logical blocks: (2.36 GB/2.20 GiB)
[    3.135204] sd 0:0:0:0: [sda] Write Protect is off
[    3.136232] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    3.137279] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.139341] scsi 0:0:1:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    3.141810]  sda: sda1
[    3.142927] sr 0:0:1:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    3.144110] cdrom: Uniform CD-ROM driver Revision: 3.20
[    3.145204] sd 0:0:0:0: [sda] Attached SCSI disk
[    3.146426] sr 0:0:1:0: Attached scsi generic sg1 type 5
[    3.147679] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    3.168815] sr 1:0:0:0: [sr1] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    3.170397] sr 1:0:0:0: Attached scsi generic sg2 type 5
[    3.172636] Freeing unused kernel memory: 1508K
[    3.173487] Write protecting the kernel read-only data: 14336k
[    3.174964] Freeing unused kernel memory: 1728K
[    3.176107] Freeing unused kernel memory: 108K
Loading, please wait...
starting version 229
[    3.191749] random: udevadm: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.194327] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.196313] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.198243] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.200053] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.201876] random: udevadm: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.214620] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.216589] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.218473] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.222964] random: systemd-udevd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
[    3.332432] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    3.333716] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    3.338275] Floppy drive(s): fd0 is 1.44M
[    3.352751] FDC 0 is a S82078B
[    3.364858] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4
[    3.366731] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3
[    3.453081] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[    3.789855] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:be:36:42:01
[    3.791860] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[    3.795678] e1000 0000:00:03.0 ens3: renamed from eth0
[    3.904526] tsc: Refined TSC clocksource calibration: 2095.137 MHz
[    3.907572] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e333e9d111, max_idle_ns: 440795240016 ns
Begin: Loading essential drivers ... [    5.105280] md: linear personality registered for level -1
[    5.109852] md: multipath personality registered for level -4
[    5.114497] md: raid0 personality registered for level 0
[    5.119590] md: raid1 personality registered for level 1
[    5.192425] raid6: sse2x1   gen()  6401 MB/s
[    5.260420] raid6: sse2x1   xor()  4749 MB/s
[    5.328422] raid6: sse2x2   gen()  7929 MB/s
[    5.396419] raid6: sse2x2   xor()  5227 MB/s
[    5.464415] raid6: sse2x4   gen()  9197 MB/s
[    5.532419] raid6: sse2x4   xor()  6365 MB/s
[    5.533276] raid6: using algorithm sse2x4 gen() 9197 MB/s
[    5.534276] raid6: .... xor() 6365 MB/s, rmw enabled
[    5.535252] raid6: using intx1 recovery algorithm
[    5.538305] xor: measuring software checksum speed
[    5.576415]    prefetch64-sse: 10132.000 MB/sec
[    5.616414]    generic_sse:  9350.000 MB/sec
[    5.617228] xor: using function: prefetch64-sse (10132.000 MB/sec)
[    5.620400] async_tx: api initialized (async)
[    5.634391] md: raid6 personality registered for level 6
[    5.635450] md: raid5 personality registered for level 5
[    5.636461] md: raid4 personality registered for level 4
[    5.644385] md: raid10 personality registered for level 10
done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... [    5.683339] Btrfs loaded
Scanning for Btrfs filesystems
[    5.780491] blk_update_request: I/O error, dev fd0, sector 0
[    5.781537] floppy: error -5 while reading block 0
done.
Warning: fsck not present, so skipping root file system
[    5.801305] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
[    5.962217] systemd[1]: systemd 229 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN)
[    5.965524] systemd[1]: Detected virtualization qemu.
[    5.966481] systemd[1]: Detected architecture x86-64.

Welcome to Ubuntu 16.04.3 LTS!

[    5.970346] systemd[1]: Set hostname to <ubuntu>.
[    5.973067] systemd[1]: Initializing machine ID from random generator.
[    5.974271] systemd[1]: Installed transient /etc/machine-id file.
[    6.144439] blk_update_request: I/O error, dev fd0, sector 0
[    6.145453] floppy: error -5 while reading block 0
[    6.240060] systemd[1]: Reached target Encrypted Volumes.
[  OK  ] Reached target Encrypted Volumes.
[    6.242290] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    6.244766] systemd[1]: Created slice System Slice.
[  OK  ] Created slice System Slice.
[    6.246984] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    6.249162] systemd[1]: Reached target Slices.
[  OK  ] Reached target Slices.
[    6.251030] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    6.253711] systemd[1]: Starting Uncomplicated firewall...
         Starting Uncomplicated firewall...
[    6.256445] systemd[1]: Starting Remount Root and Kernel File Systems...
         Starting Remount Root and Kernel File Systems...
[    6.262232] systemd[1]: Mounting Huge Pages File System...
         Mounting Huge Pages File System...
[    6.271781] systemd[1]: Starting Create list of required static device nodes for the current kernel...
         Starting Create list of required st... nodes for the current kernel...
[    6.279579] EXT4-fs (sda1): re-mounted. Opts: (null)
[    6.282636] systemd[1]: Mounting Debug File System...
         Mounting Debug File System...
[    6.294786] systemd[1]: Starting Nameserver information manager...
         Starting Nameserver information manager...
[    6.300377] systemd[1]: Listening on Journal Audit Socket.
[  OK  ] Listening on Journal Audit Socket.
[    6.304231] systemd[1]: Starting Set console keymap...
         Starting Set console keymap...
[    6.306357] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[  OK  ] Set up automount Arbitrary Executab...ats File System Automount Point.
[    6.320551] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[    6.328505] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[  OK  ] Listening on Device-mapper event daemon FIFOs.
[    6.330913] systemd[1]: Started Trigger resolvconf update for networkd DNS.
[  OK  ] Started Trigger resolvconf update for networkd DNS.
[    6.337385] systemd[1]: Reached target Swap.
[  OK  ] Reached target Swap.
[    6.339229] systemd[1]: Listening on LVM2 metadata daemon socket.
[  OK  ] Listening on LVM2 metadata daemon socket.
[    6.345811] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[    6.353223] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting POSIX Message Queue File System...
[    6.355776] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[    6.362697] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    6.369506] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
         Starting Monitoring of LVM2 mirrors... dmeventd or progress polling...
[    6.377806] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    6.383340] systemd[1]: Reached target User and Group Name Lookups.
[  OK  ] Reached target User and Group Name Lookups.
[    6.392462] systemd[1]: Starting Load Kernel Modules...
         Starting Load Kernel Modules...
[    6.401179] systemd[1]: Listening on Syslog Socket.
[  OK  ] Listening on Syslog Socket.
[    6.408209] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[    6.414832] systemd[1]: Listening on LVM2 poll daemon socket.
[  OK  ] Listening on LVM2 poll daemon socket.
[    6.422866] systemd[1]: Mounted Huge Pages File System.
[  OK  ] Mounted Huge Pages File System.
[    6.428383] systemd[1]: Mounted Debug File System.
[  OK  ] Mounted Debug File System.
[    6.437321] systemd[1]: Mounted POSIX Message Queue File System.
[  OK  ] Mounted POSIX Message Queue File System.
[    6.441298] systemd[1]: Started Uncomplicated firewall.
[    6.442707] Loading iSCSI transport class v2.0-870.
[  OK  ] Started Uncomplicated firewall.
[    6.451159] systemd[1]: Started Remount Root and Kernel File Systems.
[  OK  ] Started Remount Root and Kernel File Systems.
[    6.458985] systemd[1]: Started Create list of required static device nodes for the current kernel.
[  OK  ] Started Create list of required sta...ce nodes for the current kernel.
[    6.486214] iscsi: registered transport (tcp)
[    6.489888] systemd[1]: Started Nameserver information manager.
[  OK  ] Started Nameserver information manager.
[    6.569968] systemd[1]: Started Journal Service.
[  OK  ] Started Journal Service.
[  OK  ] Started Set console keymap.
[    6.614237] iscsi: registered transport (iser)
[  OK  ] Started LVM2 metadata daemon.
         Starting Create Static Device Nodes in /dev...
         Starting Load/Save Random Seed...
         Starting udev Coldplug all Devices...
         Starting Initial cloud-init job (pre-networking)...
         Starting Flush Journal to Persistent Storage...
[  OK  ] Started Load Kernel Modules.
[  OK  ] Started Load/Save Random Seed.
[  OK  ] Started Create Static Device Nodes in /dev.
[    6.728942] random: nonblocking pool is initialized
[    6.762824] systemd-journald[362]: Received request to flush runtime journal from PID 1
         Starting udev Kernel Device Manager...
         Starting Apply Kernel Variables...
         Mounting FUSE Control File System...
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Mounted FUSE Control File System.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Monitoring of LVM2 mirrors,...ng dmeventd or progress polling.
[  OK  ] Reached target Local File Systems (Pre).
[  OK  ] Reached target Local File Systems.
         Starting Set console font and keymap...
         Starting Create Volatile Files and Directories...
         Starting Commit a transient machine-id on disk...
         Starting Tell Plymouth To Write Out Runtime Data...
         Starting LSB: AppArmor initialization...
[  OK  ] Started Commit a transient machine-id on disk.
[  OK  ] Started Tell Plymouth To Write Out Runtime Data.
[  OK  ] Started Create Volatile Files and Directories.
         Starting Update UTMP about System Boot/Shutdown...
         Starting Network Time Synchronization...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Found device /dev/ttyS0.
[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
[    9.464456] blk_update_request: I/O error, dev fd0, sector 0
[    9.512448] blk_update_request: I/O error, dev fd0, sector 0
[    9.568440] blk_update_request: I/O error, dev fd0, sector 0
[    9.676433] blk_update_request: I/O error, dev fd0, sector 0
[    9.728434] blk_update_request: I/O error, dev fd0, sector 0
[    9.780444] blk_update_request: I/O error, dev fd0, sector 0
[    9.892442] blk_update_request: I/O error, dev fd0, sector 0
[    9.944444] blk_update_request: I/O error, dev fd0, sector 0
[   10.146121] cloud-init[409]: Cloud-init v. 17.1 running 'init-local' at Sat, 17 Feb 2018 00:24:37 +0000. Up 8.50 seconds.
[  OK  ] Started Initial cloud-init job (pre-networking).
[  OK  ] Reached target Network (Pre).
[  OK  ] Started LSB: AppArmor initialization.
         Starting Raise network interfaces...
[  OK  ] Started Set console font and keymap.
[  OK  ] Created slice system-getty.slice.
[  OK  ] Started Raise network interfaces.
[  OK  ] Reached target Network.
         Starting Initial cloud-init job (metadata service crawler)...
[   14.944590] blk_update_request: I/O error, dev fd0, sector 0
[   15.000572] blk_update_request: I/O error, dev fd0, sector 0
[   15.056566] blk_update_request: I/O error, dev fd0, sector 0
[   15.132439] blk_update_request: I/O error, dev fd0, sector 0
[   23.955616] cloud-init[946]: Cloud-init v. 17.1 running 'init' at Sat, 17 Feb 2018 00:24:43 +0000. Up 14.42 seconds.
[   23.957741] cloud-init[946]: ci-info: ++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++
[   23.960746] cloud-init[946]: ci-info: +--------+------+------------------------------+---------------+-------+-------------------+
[   23.962885] cloud-init[946]: ci-info: | Device |  Up  |           Address            |      Mask     | Scope |     Hw-Address    |
[   23.965035] cloud-init[946]: ci-info: +--------+------+------------------------------+---------------+-------+-------------------+
[   23.967042] cloud-init[946]: ci-info: |  ens3  | True |        192.168.1.142         | 255.255.255.0 |   .   | 52:54:be:36:42:01 |
[   23.969004] cloud-init[946]: ci-info: |  ens3  | True | fe80::5054:beff:fe36:4201/64 |       .       |  link | 52:54:be:36:42:01 |
[   23.971004] cloud-init[946]: ci-info: |   lo   | True |          127.0.0.1           |   255.0.0.0   |   .   |         .         |
[   23.973021] cloud-init[946]: ci-info: |   lo   | True |           ::1/128            |       .       |  host |         .         |
[   23.975066] cloud-init[946]: ci-info: +--------+------+------------------------------+---------------+-------+-------------------+
[   23.976917] cloud-init[946]: ci-info: +++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++
[   23.978582] cloud-init[946]: ci-info: +-------+-------------+-------------+---------------+-----------+-------+
[   23.980315] cloud-init[946]: ci-info: | Route | Destination |   Gateway   |    Genmask    | Interface | Flags |
[   23.981970] cloud-init[946]: ci-info: +-------+-------------+-------------+---------------+-----------+-------+
[   23.983671] cloud-init[946]: ci-info: |   0   |   0.0.0.0   | 192.168.1.1 |    0.0.0.0    |    ens3   |   UG  |
[   23.985405] cloud-init[946]: ci-info: |   1   | 192.168.1.0 |   0.0.0.0   | 255.255.255.0 |    ens3   |   U   |
[   23.987160] cloud-init[946]: ci-info: +-------+-------------+-------------+---------------+-----------+-------+
[   23.988865] cloud-init[946]: 2018-02-17 00:24:52,734 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [6/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4544d4588>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   26.954959] cloud-init[946]: 2018-02-17 00:24:55,733 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [8/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454047b00>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   30.956595] cloud-init[946]: 2018-02-17 00:24:59,734 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [13/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4540582e8>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   33.955048] cloud-init[946]: 2018-02-17 00:25:02,733 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [15/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454058b00>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   37.966053] cloud-init[946]: 2018-02-17 00:25:06,744 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [20/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4540585c0>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   41.055100] cloud-init[946]: 2018-02-17 00:25:09,833 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [23/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4544d4780>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   49.107131] cloud-init[946]: 2018-02-17 00:25:17,885 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [31/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454047ac8>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   52.130780] cloud-init[946]: 2018-02-17 00:25:20,909 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [34/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454063358>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   57.164152] cloud-init[946]: 2018-02-17 00:25:25,942 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [39/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454063b70>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   62.196192] cloud-init[946]: 2018-02-17 00:25:30,974 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [44/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4544d45f8>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   67.212762] cloud-init[946]: 2018-02-17 00:25:35,991 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [49/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454063550>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   73.225546] cloud-init[946]: 2018-02-17 00:25:42,004 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [55/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454058358>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   79.246633] cloud-init[946]: 2018-02-17 00:25:48,025 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [61/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454058dd8>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   85.267479] cloud-init[946]: 2018-02-17 00:25:54,046 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [67/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454047cc0>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   91.279223] cloud-init[946]: 2018-02-17 00:26:00,057 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [73/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4540476d8>: Failed to establish a new connection: [Errno 113] No route to host',))]
[   97.277772] cloud-init[946]: 2018-02-17 00:26:06,056 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [79/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4540580f0>: Failed to establish a new connection: [Errno 113] No route to host',))]
[  104.289095] cloud-init[946]: 2018-02-17 00:26:13,067 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [86/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4544d4550>: Failed to establish a new connection: [Errno 113] No route to host',))]
[  111.299335] cloud-init[946]: 2018-02-17 00:26:20,077 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [93/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4540634a8>: Failed to establish a new connection: [Errno 113] No route to host',))]
[  118.309730] cloud-init[946]: 2018-02-17 00:26:27,088 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [100/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454047048>: Failed to establish a new connection: [Errno 113] No route to host',))]
[  125.330130] cloud-init[946]: 2018-02-17 00:26:34,108 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [107/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4540692e8>: Failed to establish a new connection: [Errno 113] No route to host',))]
[  132.350348] cloud-init[946]: 2018-02-17 00:26:41,128 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [114/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe454047e80>: Failed to establish a new connection: [Errno 113] No route to host',))]
[  137.374061] cloud-init[946]: 2018-02-17 00:26:46,152 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [119/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4540633c8>: Failed to establish a new connection: [Errno 115] Operation now in progress',))]
[  142.395311] cloud-init[946]: 2018-02-17 00:26:51,169 - DataSourceEc2.py[CRITICAL]: Giving up on md from ['http://169.254.169.254/2009-04-04/meta-data/instance-id'] after 124 seconds
[  142.402879] cloud-init[946]: 2018-02-17 00:26:51,181 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [0/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  143.411321] cloud-init[946]: 2018-02-17 00:26:52,189 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [1/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  144.426510] cloud-init[946]: 2018-02-17 00:26:53,204 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [2/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  145.442680] cloud-init[946]: 2018-02-17 00:26:54,221 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [3/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  146.458258] cloud-init[946]: 2018-02-17 00:26:55,236 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [4/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  147.483456] cloud-init[946]: 2018-02-17 00:26:56,261 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [5/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  149.496985] cloud-init[946]: 2018-02-17 00:26:58,275 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [7/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  151.504669] cloud-init[946]: 2018-02-17 00:27:00,283 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [9/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  153.512811] cloud-init[946]: 2018-02-17 00:27:02,291 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [11/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  155.525704] cloud-init[946]: 2018-02-17 00:27:04,304 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [13/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  157.543259] cloud-init[946]: 2018-02-17 00:27:06,321 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [15/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  160.559659] cloud-init[946]: 2018-02-17 00:27:09,338 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [18/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  163.573704] cloud-init[946]: 2018-02-17 00:27:12,352 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [21/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  166.592783] cloud-init[946]: 2018-02-17 00:27:15,371 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [24/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  169.610675] cloud-init[946]: 2018-02-17 00:27:18,389 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [27/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  172.626291] cloud-init[946]: 2018-02-17 00:27:21,404 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [30/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  176.638061] cloud-init[946]: 2018-02-17 00:27:25,416 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [34/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  180.656528] cloud-init[946]: 2018-02-17 00:27:29,435 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [38/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  184.675228] cloud-init[946]: 2018-02-17 00:27:33,453 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [42/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  188.695705] cloud-init[946]: 2018-02-17 00:27:37,473 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [46/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  192.717038] cloud-init[946]: 2018-02-17 00:27:41,495 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [50/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  197.734949] cloud-init[946]: 2018-02-17 00:27:46,513 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [55/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  202.757780] cloud-init[946]: 2018-02-17 00:27:51,536 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [60/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  207.777423] cloud-init[946]: 2018-02-17 00:27:56,555 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [65/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  212.798477] cloud-init[946]: 2018-02-17 00:28:01,577 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [70/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  217.820366] cloud-init[946]: 2018-02-17 00:28:06,599 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [75/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  223.838081] cloud-init[946]: 2018-02-17 00:28:12,616 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [81/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  229.854962] cloud-init[946]: 2018-02-17 00:28:18,633 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [87/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  235.871979] cloud-init[946]: 2018-02-17 00:28:24,650 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [93/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  241.894247] cloud-init[946]: 2018-02-17 00:28:30,672 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [99/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  247.911637] cloud-init[946]: 2018-02-17 00:28:36,690 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [105/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  254.931452] cloud-init[946]: 2018-02-17 00:28:43,709 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [112/120s]: request error [('Connection aborted.', RemoteDisconnected('Remote end closed connection without response',))]
[  261.951833] cloud-init[946]: 2018-02-17 00:28:50,729 - url_helper.py[WARNING]: Calling 'http://192.168.1.1/latest/meta-data/instance-id' failed [119/120s]: request error [HTTPConnectionPool(host='192.168.1.1', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fe4540639b0>: Failed to establish a new connection: [Errno 115] Operation now in progress',))]
[  268.976223] cloud-init[946]: 2018-02-17 00:28:57,748 - DataSourceCloudStack.py[CRITICAL]: Giving up on waiting for the metadata from ['http://192.168.1.1/latest/meta-data/instance-id'] after 126 seconds
[  270.021886] cloud-init[946]: Generating public/private rsa key pair.
[  270.023586] cloud-init[946]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key.
[  270.028317] cloud-init[946]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub.
[  270.030094] cloud-init[946]: The key fingerprint is:
[  270.032283] cloud-init[946]: SHA256:v9a2RNlC6oV4pv2IPfFNRZjVt1PS3auIRYBe7hb/vYg root@ubuntu
[  270.036309] cloud-init[946]: The key's randomart image is:
[  270.040310] cloud-init[946]: +---[RSA 2048]----+
[  270.041683] cloud-init[946]: |        ...    ==|
[  270.044313] cloud-init[946]: |       . . .  + O|
[  270.048315] cloud-init[946]: |      . o . .  o=|
[  270.052326] cloud-init[946]: |       . + = o +.|
[  270.053695] cloud-init[946]: |        S X * o..|
[  270.056295] cloud-init[946]: |         @.= o.  |
[  270.060335] cloud-init[946]: |        o ++oo.  |
[  270.061688] cloud-init[946]: |         oo==.o. |
[  270.064316] cloud-init[946]: |        ..+Eoo ..|
[  270.068323] cloud-init[946]: +----[SHA256]-----+
[  270.069703] cloud-init[946]: Generating public/private dsa key pair.
[  270.072303] cloud-init[946]: Your identification has been saved in /etc/ssh/ssh_host_dsa_key.
[  270.076356] cloud-init[946]: Your public key has been saved in /etc/ssh/ssh_host_dsa_key.pub.
[  270.080321] cloud-init[946]: The key fingerprint is:
[  270.084307] cloud-init[946]: SHA256:b9GV1UCQl4U92RmOTrVniOLrOr+wmZCrpOBl55lI3sw root@ubuntu
[  270.088317] cloud-init[946]: The key's randomart image is:
[  270.089819] cloud-init[946]: +---[DSA 1024]----+
[  270.092262] cloud-init[946]: |            .+oOO|
[  270.096303] cloud-init[946]: |            ..BB=|
[  270.097652] cloud-init[946]: |          . .++o+|
[  270.100292] cloud-init[946]: |         . oo. ..|
[  270.104348] cloud-init[946]: |        S o ..   |
[  270.105706] cloud-init[946]: |       . . o     |
[  270.108299] cloud-init[946]: |.  +..o . +      |
[  270.112310] cloud-init[946]: |..=oB oo.B       |
[  270.116312] cloud-init[946]: | ..o.E. =++.     |
[  270.117663] cloud-init[946]: +----[SHA256]-----+
[  270.120310] cloud-init[946]: Generating public/private ecdsa key pair.
[  270.124313] cloud-init[946]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key.
[  270.128331] cloud-init[946]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub.
[  270.130176] cloud-init[946]: The key fingerprint is:
[  270.136304] cloud-init[946]: SHA256:D8DsYTe54JrGeDOhIW+H/nWVGS40blYHzc1VNAsH/dI root@ubuntu
[  270.138061] cloud-init[946]: The key's randomart image is:
[  270.139404] cloud-init[946]: +---[ECDSA 256]---+
[  270.144820] cloud-init[946]: |          .o =o==|
[  270.146088] cloud-init[946]: |     o   . .o =.o|
[  270.147357] cloud-init[946]: [  OK  ] Started Initial cloud-init job (metadata service crawler).
[  OK  ] Reached target Network is Online.
|      B * o .  o.|
[  270.152612] cloud-init[946]: |     + B * =  . E|
         Starting iSCSI initiator daemon (iscsid)...
[  270.156251] cloud-init[946]: |. . . o S =    . |
[  OK  ] Reached target System Initialization.
[  OK  ] Started Timer to automatically refresh installed snaps.
         Starting Socket activation for snappy daemon.
[  OK  ] Listening on UUID daemon activation socket.
[  270.160232] cloud-init[946]: | o * + o =       |
[  270.169725] cloud-init[946]: |  * X . . .      |
[  270.170409] cloud-init[946]: | o + + .         |
[  270.171084] cloud-init[946]: |  ...            |
[  270.171755] cloud-init[  OK  ] Started Daily Cleanup of Temporary Directories.
         Starting LXD - unix socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Listening on ACPID Listen Socket.
[946]: +----[SHA256]-----+
[  OK  ] Started Daily apt download activities.
[  OK  ] Started Daily apt upgrade and clean activities.
[  OK  ] Reached target Timers.
[  OK  ] Started ACPI Events Check.
[  270.189478] cloud-init[946]: Generating public/private ed25519 key pair.
[  OK  ] Reached target Paths.
[  270.191460] cloud-init[946]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key.[  OK  ] Reached target Cloud-config availability.

[  270.195434] cloud-init[946]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub.[  OK  ] Listening on Socket activation for snappy daemon.
[  OK  ] Listening on LXD - unix socket.

[  270.200382] cloud-init[946]: The key fingerprint is:
[  270.201382] cloud-init[946]: SHA256:9KlEJufWoL32UGDmWXvyo8m6KUxIN3wmPHWyrUgnNUo root@ubuntu
[  270.203560] cloud-init[946]: The key's randomart image is:
[  OK  ] Started iSCSI initiator daemon (iscsid).
[  270.230450] cloud-init[946]: +--[ED25519 256]--+
[  270.231471] cloud-init[946]:          Starting Login to default iSCSI targets...
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
|                 |
[  270.236518] cloud-init[946]: |      E = .      |
[  270.237517] cloud-init[946]: |     +.+X*.      |
[  270.238451] cloud-init[946]: |    . @&=B.o     |
[  270.239381] cloud-init[946]: |   . +.XS.B .    |         Starting Login Service...
         Starting LSB: Record successful boot for GRUB...
[  OK
[  270.246311] cloud-init[946]: |    . oo.+ +     |
[  270.247000] cloud-init[946]: |     o  =   o    |
[  270.247665] cloud-init[946]: ] Started Unattended Upgrades Shutdown.
|      o. = o .   |
         Starting Apply the settings specified in cloud-config...
[  OK  ] Started Regular background program processing daemon.
         Starting System Logging Service...
         Starting LXD - container startup/shutdown...
         Starting Pollinate to seed the pseudo random number generator...
[  OK  ] Started ACPI event daemon.[  270.332573]
[  OK  ] Started FUSE filesystem for LXC.
[  OK  ] Started D-Bus System Message Bus.
cloud-init[946]: |       .+o=      |
[  270.380428] cloud-init[946]: +----[SHA256]-----+
         Starting Accounts Service...
         Starting /etc/rc.local Compatibility...
[  OK  ] Started Deferred execution scheduler.
         Starting LSB: MD monitoring daemon...
         Starting Snappy daemon...
[  OK  ] Started System Logging Service.
[  OK  ] Started /etc/rc.local Compatibility.
[  OK  ] Started Login to default iSCSI targets.
[  OK  ] Started LSB: Record successful boot for GRUB.
[  OK  ] Started Login Service.
         Starting Authenticate and Authorize Users to Run Privileged Tasks...
[  OK  ] Reached target Remote File Systems (Pre).
[  OK  ] Reached target Remote File Systems.
         Starting LSB: Set the CPU Frequency Scaling governor to "ondemand"...
         Starting LSB: daemon to balance interrupts for SMP systems...
         Starting Permit User Sessions...
         Starting LSB: automatic crash report generation...
[  OK  ] Started LXD - container startup/shutdown.
[  OK  ] Started LSB: MD monitoring daemon.
[  OK  ] Started Permit User Sessions.
         Starting Hold until boot process finishes up...
         Starting Terminate Plymouth Boot Screen...
[  OK  ] Started Hold until boot process finishes up.
[  OK  ] Started Terminate Plymouth Boot Screen.
[  OK  ] Started Getty on tty1.
         Starting Set console scheme...
[  OK  ] Started Serial Getty on ttyS0.
[  OK  ] Reached target Login Prompts.
[  OK  ] Started Set console scheme.
[  OK  ] Started Authenticate and Authorize Users to Run Privileged Tasks.
[  OK  ] Started Accounts Service.
[  OK  ] Started LSB: Set the CPU Frequency Scaling governor to "ondemand".
[  OK  ] Started LSB: daemon to balance interrupts for SMP systems.
[  OK  ] Started LSB: automatic crash report generation.
[  OK  ] Started Snappy daemon.
[  271.627425] cloud-init[1066]: Generating locales (this might take a while)...
[  272.577641] cloud-init[1066]:   en_US.UTF-8... done
[  272.580117] cloud-init[1066]: Generation complete.
[  OK  ] Started Pollinate to seed the pseudo random number generator.
         Starting OpenBSD Secure Shell server...
[  OK  ] Started OpenBSD Secure Shell server.
[  OK  ] Reached target Multi-User System.
[  OK  ] Reached target Graphical Interface.
         Starting Update UTMP about System Runlevel Changes...
[  OK  ] Started Update UTMP about System Runlevel Changes.
[  273.073300] cloud-init[1066]: Cloud-init v. 17.1 running 'modules:config' at Sat, 17 Feb 2018 00:29:00 +0000. Up 271.46 seconds.
[  OK  ] Started Apply the settings specified in cloud-config.
         Starting Execute cloud user/final scripts...
ci-info: no authorized ssh keys fingerprints found for user ubuntu.
<14>Feb 17 00:29:02 ec2:
<14>Feb 17 00:29:02 ec2: #############################################################
<14>Feb 17 00:29:02 ec2: -----BEGIN SSH HOST KEY FINGERPRINTS-----
<14>Feb 17 00:29:02 ec2: 1024 SHA256:b9GV1UCQl4U92RmOTrVniOLrOr+wmZCrpOBl55lI3sw root@ubuntu (DSA)
<14>Feb 17 00:29:02 ec2: 256 SHA256:D8DsYTe54JrGeDOhIW+H/nWVGS40blYHzc1VNAsH/dI root@ubuntu (ECDSA)
<14>Feb 17 00:29:02 ec2: 256 SHA256:9KlEJufWoL32UGDmWXvyo8m6KUxIN3wmPHWyrUgnNUo root@ubuntu (ED25519)
<14>Feb 17 00:29:02 ec2: 2048 SHA256:v9a2RNlC6oV4pv2IPfFNRZjVt1PS3auIRYBe7hb/vYg root@ubuntu (RSA)
<14>Feb 17 00:29:02 ec2: -----END SSH HOST KEY FINGERPRINTS-----
<14>Feb 17 00:29:02 ec2: #############################################################
-----BEGIN SSH HOST KEY KEYS-----
ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBC+8tre0btBinvJkXnbKo67/ida1iZbmLMOP6Lmn/QV8z5KFYB6CDy79hEKvuQeDvO1zRu3vBq8xro4ln2TjKnQ= root@ubuntu
ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/2yNIdeHnYqwXza/8UUgZXmVvoaEF0lk13yIoGaTI3 root@ubuntu
ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCria/+aMo+8O94KxERa5NSorL4qnZLRSmMXRRIoreIgKtmyKSOku8MWhSG9wl5XLJphMWmzVqSXrODm/FDWpnK/cVMpQJZRBpMmy5x6gJmyHzQCN9Gof1WJU2crdUx2hUoc+nZM6jJ3fT9aMVm895OqV6hf0YK8Jjrl1pMfSpqHrxtE/MkApZQgdsDJp2EldWoTFmgjrCz7Sxig55dEr5TfDpm115fsRbnHRJIYtREsLGwyVpkTmf7W2AmQAi7FaDr2gf6MI/FrWNE7VypOCWTQqb8qwWutzcB2kQXocKCtmThyf9TN1PSP3Wl0XIrH6rfztRK3+ycVTMlwXFx7VDt root@ubuntu
-----END SSH HOST KEY KEYS-----
[  273.732366] cloud-init[1308]: Cloud-init v. 17.1 running 'modules:final' at Sat, 17 Feb 2018 00:29:02 +0000. Up 273.50 seconds.
[  273.734338] cloud-init[1308]: ci-info: no authorized ssh keys fingerprints found for user ubuntu.
[  273.736297] cloud-init[1308]: Cloud-init v. 17.1 finished at Sat, 17 Feb 2018 00:29:02 +0000. Datasource DataSourceNone.  Up 273.72 seconds
[  273.740405] cloud-init[1308]: 2018-02-17 00:29:02,511 - cc_final_message.py[WARNING]: Used fallback datasource
[  OK  ] Started Execute cloud user/final scripts.
[  OK  ] Reached target Cloud-init target.

Ubuntu 16.04.3 LTS ubuntu ttyS0

ubuntu login: ubuntu
Password:

Login incorrect
ubuntu login:
Login timed out after 60 seconds.

Ubuntu 16.04.3 LTS ubuntu ttyS0

ubuntu login:

Python code to generate cidata.iso:

import pycdlib
from io import StringIO, BytesIO
import sys
import textwrap
import helpers

def make_iso(userdata, metadata, live_instance_files_dir):
    # Create a new PyCdlib object.
    iso = pycdlib.PyCdlib()

    # Create a new ISO, accepting all of the defaults.
    iso.new(interchange_level=3,
            joliet=True,
            sys_ident='LINUX',
            rock_ridge='1.09',
            vol_ident='cidata')
    # Add a new file to the ISO, with the contents coming from the file object.
    # Note that the file object must remain open for the lifetime of the PyCdlib
    # object, as the PyCdlib object uses it for internal operations.  Also note that
    # the filename passed here is the filename the data will get assigned on the
    # final ISO; it must begin with a forward slash, and according to ISO9660 must
    # have a '.', and a semicolon followed by a number.  PyCdlib will raise a
    # PyCdlibException if any of the rules for an ISO9660 filename are violated.

    metadata_file = BytesIO(metadata.encode())
    metadata_file.seek(0)
    iso.add_fp(metadata_file,
               len(metadata),
               '/METADATA.;1',
               rr_name="meta-data",
               joliet_path='/meta-data',
               )

    userdata_file = BytesIO(userdata.encode())
    iso.add_fp(userdata_file,
               len(userdata),
               '/USERDATA.;1',
               rr_name="user-data",
               joliet_path='/user-data',
               )

    # Write out the ISO to the file called 'new.iso'.  This will fully master the
    # ISO, creating a file that can be burned onto a CD.
    domain_cidata_iso_file_abs_path = helpers.cleanpath(live_instance_files_dir, 'cidata.iso')
    iso.write(domain_cidata_iso_file_abs_path)

    # Close the ISO object.  After this call, the PyCdlib object has forgotten
    # everything about the previous ISO, and can be re-used.
    iso.close()
    userdata_file.close()
    metadata_file.close()
    return domain_cidata_iso_file_abs_path
clalancette commented 6 years ago

OK, I found some time to sit down and look at this, and I found the bug. It's a latent bug in how we were encoding the system identifier that goes into the Joliet extension. Basically, we were encoding to utf-16_be and then padding with spaces, which is incorrect. Instead, I've now changed it to pad with spaces and then encode with utf-16_be, which seems to do the correct thing. I've also written some tests so that this doesn't happen in the future. Commit f0a785dd839dd805ae879bce4615ab1d81d87d6f should fix the problem for you, so I'm going to close this out. Feel free to report back and/or reopen if you are still having trouble.

johnbatty commented 6 years ago

Thanks for the great library!

I need this fix, but it looks like it is not yet in an official release. Please can you tell me when you are likely to be releasing a new version that includes this.

clalancette commented 6 years ago

I have a couple of more bugs that I'm working on, then I should be able to release the next version. I'd guess another 2-3 weeks; sorry for so long, but I'm only working on this in my spare time.

clalancette commented 6 years ago

@johnbatty I've gone ahead and done a 1.4.0 release to PyPI (https://pypi.org/project/pycdlib/). Give it a whirl and let me know if you run into problems.