New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

docker-17.06.1-ce: error creating overlay mount to /var/lib/docker/overlay2/ID/merged: device or resource busy" #2127

Closed
euank opened this Issue Aug 29, 2017 · 6 comments

Comments

Projects
None yet
2 participants
@euank
Contributor

euank commented Aug 29, 2017

Bug

Container Linux Version

Current nightly test runs, e.g.:

NAME="Container Linux by CoreOS"
ID=coreos
VERSION=1506.0.0+2017-08-25-1813
VERSION_ID=1506.0.0
BUILD_ID=2017-08-25-1813
PRETTY_NAME="Container Linux by CoreOS 1506.0.0+2017-08-25-1813 (Ladybug)"
ANSI_COLOR="38;5;75"
HOME_URL="https://coreos.com/"
BUG_REPORT_URL="https://issues.coreos.com"
COREOS_BOARD="amd64-usr"

Environment

Happens on qemu and AWS, probably all of them.

Expected Behavior

docker run works reliably

Actual Behavior

Every once in a while, the first run has the below error:

$ journalctl -u docker -o cat | head -n 12
Starting Docker Application Container Engine...
time="2017-08-29T01:38:38.034391242Z" level=warning msg="failed to rename /var/lib/docker/tmp for background deletion: rename /var/lib/docker/tmp /var/lib/docker/tmp-old: no such file or directory. Deleting synchronously"
time="2017-08-29T01:38:38.047270072Z" level=error msg="Failed to built-in GetDriver graph aufs /var/lib/docker"
time="2017-08-29T01:38:38.244953954Z" level=info msg="Graph migration to content-addressability took 0.00 seconds"
time="2017-08-29T01:38:38.248685288Z" level=info msg="Loading containers: start."
time="2017-08-29T01:38:38.416311592Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address"
time="2017-08-29T01:38:38.539319520Z" level=info msg="Loading containers: done."
time="2017-08-29T01:38:38.562007032Z" level=info msg="Daemon has completed initialization"
time="2017-08-29T01:38:38.562264173Z" level=info msg="Docker daemon" commit=874a737 graphdriver=overlay2 version=17.06.1-ce
Started Docker Application Container Engine.
time="2017-08-29T01:38:38.571944666Z" level=info msg="API listen on /var/run/docker.sock"
time="2017-08-29T01:38:39.756677347Z" level=error msg="Handler for POST /v1.30/containers/5b2752feaa6cff592771206bb4d5fe27a14bf5936bfdd167abc470648df25165/start returned error: error creating overlay mount to /var/lib/docker/overlay2/ef86febd7609b1508ef12b147c5aaf07ed750d09ca90b4d362b7e81543f33a3e/merged: device or resource busy"

Reproduction Steps

  1. kola run "docker.base" and get really (un)lucky

Other info

We, unfortunately, bumped the kernel to 4.13 at around the same time we bumped docker to 17.06.
I expect it'll be one of those two changes.

This happens very infrequently. When it does happen, the impact is minimal; the first container run fails, but subsequent ones are fine. I think it can only happen as the first container run, so anything that's retrying appropriately should be fine.

@euank

This comment has been minimized.

Show comment
Hide comment
@euank

euank Aug 29, 2017

Contributor

There's also an overlayfs related message in dmesg at the time:

$ dmesg -T | grep -C 5 upper
[Tue Aug 29 01:38:38 2017] docker0: port 1(vetha03b986) entered blocking state
[Tue Aug 29 01:38:38 2017] docker0: port 1(vetha03b986) entered forwarding state
[Tue Aug 29 01:38:38 2017] docker0: port 1(vetha03b986) entered disabled state
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered blocking state
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered disabled state
[Tue Aug 29 01:38:39 2017] overlayfs: upperdir is in-use by another mount
[Tue Aug 29 01:38:39 2017] device vethfdee011 entered promiscuous mode
[Tue Aug 29 01:38:39 2017] IPv6: ADDRCONF(NETDEV_UP): vethfdee011: link is not ready
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered blocking state
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered forwarding state
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered disabled state

Full dmesg below:

[    0.000000] random: get_random_bytes called from start_kernel+0x42/0x477 with crng_init=0
[    0.000000] Linux version 4.13.0-rc6-coreos (esk@multivac.euank.com) (gcc version 4.9.4 (Gentoo Hardened 4.9.4 p1.0, pie-0.6.4)) #1 SMP Fri Aug 25 18:04:02 PDT 2017
[    0.000000] Command line: BOOT_IMAGE=/coreos/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 coreos.first_boot=detected coreos.randomize_disk_guid=00000000-0000-0000-0000-000000000001 verity.usrhash=fe2505e6e7e57765cf42ea82559eedd56de26688f1ee9c2a51cac80dbefacdc1
[    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
[    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000] x86/fpu: xstate_offset[3]:  832, xstate_sizes[3]:   64
[    0.000000] x86/fpu: xstate_offset[4]:  896, xstate_sizes[4]:   64
[    0.000000] x86/fpu: Enabled xstate features 0x1f, context size is 960 bytes, using 'compacted' format.
[    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-0x000000003ffddfff] usable
[    0.000000] BIOS-e820: [mem 0x000000003ffde000-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] random: fast init done
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.10.2-0-g5f4c7b1-prebuilt.qemu-project.org 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x3ffde max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: write-back
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 0080000000 mask FF80000000 uncachable
[    0.000000]   1 disabled
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT  
[    0.000000] Base memory trampoline at [ffff9a0740099000] 99000 size 24576
[    0.000000] Using GB pages for direct mapping
[    0.000000] BRK [0x1cd66000, 0x1cd66fff] PGTABLE
[    0.000000] BRK [0x1cd67000, 0x1cd67fff] PGTABLE
[    0.000000] BRK [0x1cd68000, 0x1cd68fff] PGTABLE
[    0.000000] BRK [0x1cd69000, 0x1cd69fff] PGTABLE
[    0.000000] BRK [0x1cd6a000, 0x1cd6afff] PGTABLE
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F68B0 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000003FFE154E 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000003FFE142A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000003FFE0040 0013EA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000003FFE0000 000040
[    0.000000] ACPI: APIC 0x000000003FFE149E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000003FFE1516 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffddfff]
[    0.000000] NODE_DATA(0) allocated [mem 0x3ffd8000-0x3ffddfff]
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:3ffd0001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 2075884851 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-0x000000003ffddfff]
[    0.000000]   Normal   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-0x000000003ffddfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffddfff]
[    0.000000] On node 0 totalpages: 262012
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 4032 pages used for memmap
[    0.000000]   DMA32 zone: 258014 pages, LIFO batch:31
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    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] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] [Firmware Bug]: TSC_DEADLINE disabled due to Errata; please update microcode to version: 0xb2 (or later)
[    0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[    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: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] percpu: Embedded 38 pages/cpu @ffff9a077fc00000 s115032 r8192 d32424 u2097152
[    0.000000] pcpu-alloc: s115032 r8192 d32424 u2097152 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 3fc0db00
[    0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes)
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 257895
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/coreos/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 coreos.first_boot=detected coreos.randomize_disk_guid=00000000-0000-0000-0000-000000000001 verity.usrhash=fe2505e6e7e57765cf42ea82559eedd56de26688f1ee9c2a51cac80dbefacdc1
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Memory: 984772K/1048048K available (6295K kernel code, 1219K rwdata, 2696K rodata, 32156K init, 740K bss, 63276K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] ftrace: allocating 26583 entries in 104 pages
[    0.001000] Hierarchical RCU implementation.
[    0.001000] 	RCU event tracing is enabled.
[    0.001000] 	RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.
[    0.001000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.001000] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16
[    0.001000] Console: colour VGA+ 80x25
[    0.001000] console [tty0] enabled
[    0.001000] console [ttyS0] enabled
[    0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.001000] hpet clockevent registered
[    0.001006] tsc: Detected 2808.000 MHz processor
[    0.001875] Calibrating delay loop (skipped) preset value.. 5616.00 BogoMIPS (lpj=2808000)
[    0.002003] pid_max: default: 32768 minimum: 301
[    0.003012] ACPI: Core revision 20170531
[    0.005056] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.006035] Security Framework initialized
[    0.007008] SELinux:  Initializing.
[    0.008016] SELinux:  Starting in permissive mode
[    0.008201] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.010133] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.011021] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.012008] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.014138] CPU: Physical Processor ID: 0
[    0.014943] mce: CPU supports 10 MCE banks
[    0.015031] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.016003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.022303] Freeing SMP alternatives memory: 24K
[    0.023692] smpboot: Max logical packages: 1
[    0.024121] x2apic enabled
[    0.025003] Switched APIC routing to physical x2apic.
[    0.026634] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.027000] smpboot: CPU0: Intel(R) Core(TM) i7-6600U CPU @ 2.60GHz (family: 0x6, model: 0x4e, stepping: 0x3)
[    0.027092] Performance Events: Skylake events, Intel PMU driver.
[    0.028004] ... version:                2
[    0.029001] ... bit width:              48
[    0.030001] ... generic registers:      4
[    0.031001] ... value mask:             0000ffffffffffff
[    0.032006] ... max period:             000000007fffffff
[    0.033003] ... fixed-purpose events:   3
[    0.034001] ... event mask:             000000070000000f
[    0.034943] Hierarchical SRCU implementation.
[    0.036063] smp: Bringing up secondary CPUs ...
[    0.036935] smp: Brought up 1 node, 1 CPU
[    0.037003] smpboot: Total of 1 processors activated (5616.00 BogoMIPS)
[    0.038240] devtmpfs: initialized
[    0.039037] x86/mm: Memory block size: 128MB
[    0.040197] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.041006] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.042037] pinctrl core: initialized pinctrl subsystem
[    0.043067] NET: Registered protocol family 16
[    0.044101] cpuidle: using governor menu
[    0.045003] PCCT header not found.
[    0.045775] ACPI: bus type PCI registered
[    0.046002] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.047040] dca service started, version 1.12.1
[    0.048052] PCI: Using configuration type 1 for base access
[    0.050098] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.051005] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.053089] ACPI: Added _OSI(Module Device)
[    0.054006] ACPI: Added _OSI(Processor Device)
[    0.055007] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.056005] ACPI: Added _OSI(Processor Aggregator Device)
[    0.058910] ACPI: Interpreter enabled
[    0.059012] ACPI: (supports S0 S3 S5)
[    0.060002] ACPI: Using IOAPIC for interrupt routing
[    0.061012] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.065087] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.066006] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.067006] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.068008] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.069224] acpiphp: Slot [3] registered
[    0.070017] acpiphp: Slot [4] registered
[    0.070790] acpiphp: Slot [5] registered
[    0.071015] acpiphp: Slot [6] registered
[    0.072015] acpiphp: Slot [7] registered
[    0.073018] acpiphp: Slot [8] registered
[    0.074018] acpiphp: Slot [9] registered
[    0.074801] acpiphp: Slot [10] registered
[    0.075030] acpiphp: Slot [11] registered
[    0.076017] acpiphp: Slot [12] registered
[    0.077020] acpiphp: Slot [13] registered
[    0.078016] acpiphp: Slot [14] registered
[    0.079029] acpiphp: Slot [15] registered
[    0.080022] acpiphp: Slot [16] registered
[    0.080805] acpiphp: Slot [17] registered
[    0.081016] acpiphp: Slot [18] registered
[    0.082021] acpiphp: Slot [19] registered
[    0.083016] acpiphp: Slot [20] registered
[    0.083796] acpiphp: Slot [21] registered
[    0.084017] acpiphp: Slot [22] registered
[    0.085016] acpiphp: Slot [23] registered
[    0.086015] acpiphp: Slot [24] registered
[    0.087015] acpiphp: Slot [25] registered
[    0.087794] acpiphp: Slot [26] registered
[    0.088055] acpiphp: Slot [27] registered
[    0.089031] acpiphp: Slot [28] registered
[    0.090037] acpiphp: Slot [29] registered
[    0.091039] acpiphp: Slot [30] registered
[    0.092030] acpiphp: Slot [31] registered
[    0.093018] PCI host bridge to bus 0000:00
[    0.094017] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.095007] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.096003] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.097002] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[    0.098003] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.099034] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.099366] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.100142] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.103387] pci 0000:00:01.1: reg 0x20: [io  0xc060-0xc06f]
[    0.105021] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.106002] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.107002] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.108002] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.109148] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.109445] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.110011] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.112052] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    0.113004] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    0.116003] pci 0000:00:02.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff]
[    0.122007] pci 0000:00:02.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref]
[    0.122215] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000
[    0.124003] pci 0000:00:03.0: reg 0x10: [io  0xc000-0xc03f]
[    0.126003] pci 0000:00:03.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff]
[    0.133006] pci 0000:00:03.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[    0.135434] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000
[    0.137006] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc05f]
[    0.139006] pci 0000:00:04.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff]
[    0.146006] pci 0000:00:04.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
[    0.147003] pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    0.148360] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.149072] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.150105] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.151071] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.152038] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.153116] ACPI: Enabled 2 GPEs in block 00 to 0F
[    0.154108] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    0.155000] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.155003] pci 0000:00:02.0: vgaarb: bridge control possible
[    0.156001] vgaarb: loaded
[    0.157033] PCI: Using ACPI for IRQ routing
[    0.158003] PCI: pci_cache_line_size set to 64 bytes
[    0.158087] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.158088] e820: reserve RAM buffer [mem 0x3ffde000-0x3fffffff]
[    0.158237] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.159003] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    0.165014] clocksource: Switched to clocksource kvm-clock
[    0.172755] VFS: Disk quotas dquot_6.6.0
[    0.173576] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.174685] pnp: PnP ACPI init
[    0.175418] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.175441] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.175456] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.175462] pnp 00:03: [dma 2]
[    0.175469] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.175508] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    0.175538] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.175679] pnp: PnP ACPI: found 6 devices
[    0.181731] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.183358] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.183359] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.183360] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.183361] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
[    0.183430] NET: Registered protocol family 2
[    0.184421] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[    0.185538] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[    0.186691] TCP: Hash tables configured (established 8192 bind 8192)
[    0.188371] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.189804] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.190898] NET: Registered protocol family 1
[    0.191715] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.192674] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.193618] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.194619] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.196146] PCI: CLS 0 bytes, default 64
[    0.619955] audit: initializing netlink subsys (disabled)
[    0.621172] Initialise system trusted keyrings
[    0.622025] workingset: timestamp_bits=39 max_order=18 bucket_order=0
[    0.623927] audit: type=2000 audit(1503970709.005:1): state=initialized audit_enabled=0 res=1
[    0.625497] SELinux:  Registering netfilter hooks
[    0.894988] Key type asymmetric registered
[    0.895827] Asymmetric key parser 'x509' registered
[    0.896742] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.898194] io scheduler noop registered
[    0.898956] io scheduler deadline registered
[    0.899834] io scheduler cfq registered (default)
[    0.900682] io scheduler mq-deadline registered
[    0.901503] io scheduler kyber registered
[    0.902338] intel_idle: does not run on family 6 model 78
[    0.902400] GHES: HEST is not enabled!
[    0.903190] ioatdma: Intel(R) QuickData Technology Driver 4.00
[    0.904189] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.927247] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    0.929057] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    0.931080] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.931946] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.933026] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    0.934837] rtc_cmos 00:00: RTC can wake from S4
[    0.935959] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[    0.937191] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[    0.939303] ip_tables: (C) 2000-2006 Netfilter Core Team
[    0.940495] NET: Registered protocol family 10
[    0.941467] Segment Routing with IPv6
[    0.942227] NET: Registered protocol family 17
[    0.943051] Key type dns_resolver registered
[    0.944065] sched_clock: Marking stable (944006650, 0)->(1215446124, -271439474)
[    0.946033] registered taskstats version 1
[    0.946825] Loading compiled-in X.509 certificates
[    0.982091] Loaded X.509 cert 'CoreOS, Inc: Module signing key for 4.13.0-rc6-coreos: fa895088072dbeb9db3fe2f88489ad418db70068'
[    0.984970] ima: No TPM chip found, activating TPM-bypass! (rc=-19)
[    0.986875] rtc_cmos 00:00: setting system clock to 2017-08-29 01:38:29 UTC (1503970709)
[    0.993029] Freeing unused kernel memory: 32156K
[    0.993911] Write protecting the kernel read-only data: 12288k
[    0.995188] Freeing unused kernel memory: 1884K
[    0.998571] Freeing unused kernel memory: 1400K
[    1.003977] systemd[1]: systemd 234 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT -GNUTLS -ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN default-hierarchy=legacy)
[    1.007049] systemd[1]: Detected virtualization kvm.
[    1.007918] systemd[1]: Detected architecture x86-64.
[    1.008852] systemd[1]: Running in initial RAM disk.
[    1.011130] systemd[1]: No hostname configured.
[    1.011956] systemd[1]: Set hostname to <localhost>.
[    1.012844] systemd[1]: Initializing machine ID from KVM UUID.
[    1.091885] systemd[1]: Listening on Journal Socket.
[    1.093716] systemd[1]: Listening on udev Kernel Socket.
[    1.095564] systemd[1]: Listening on Journal Audit Socket.
[    1.142180] audit: type=1130 audit(1503970709.655:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.148085] audit: type=1130 audit(1503970709.660:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.164054] audit: type=1130 audit(1503970709.677:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.178024] audit: type=1130 audit(1503970709.691:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.222127] audit: type=1130 audit(1503970709.735:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.243350] device-mapper: uevent: version 1.0.3
[    1.244329] device-mapper: ioctl: 4.36.0-ioctl (2017-06-09) initialised: dm-devel@redhat.com
[    1.259575] audit: type=1130 audit(1503970709.772:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.273026] audit: type=1130 audit(1503970709.785:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.302899] audit: type=1130 audit(1503970709.816:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.331020] audit: type=1130 audit(1503970709.843:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.463866] SCSI subsystem initialized
[    1.483958] AVX2 version of gcm_enc/dec engaged.
[    1.484871] AES CTR mode by8 optimization enabled
[    1.499036] libata version 3.00 loaded.
[    1.535296] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[    1.537042] ata_piix 0000:00:01.1: version 2.13
[    1.542013] scsi host0: ata_piix
[    1.547420] scsi host1: ata_piix
[    1.547458] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 irq 14
[    1.547458] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 irq 15
[    1.578277] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10
[    1.590568]  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
[    1.632054] tsc: Refined TSC clocksource calibration: 2808.009 MHz
[    1.633603] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2879ce56af2, max_idle_ns: 440795206607 ns
[    1.667823]  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
[    1.704665] ata2.01: NODEV after polling detection
[    1.704879] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    1.706338] ata2.00: configured for MWDMA2
[    1.707538] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    1.730675] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    1.732286] cdrom: Uniform CD-ROM driver Revision: 3.20
[    1.735245] sr 1:0:0:0: Attached scsi CD-ROM sr0
[    2.672924]  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
[    2.855372] EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null)
[    2.875978] EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null)
[    3.301832] systemd-journald[134]: Received SIGTERM from PID 1 (systemd).
[    3.328751] systemd: 18 output lines suppressed due to ratelimiting
[    3.394742] SELinux: 4096 avtab hash slots, 13428 rules.
[    3.395898] SELinux: 4096 avtab hash slots, 13428 rules.
[    3.396270] SELinux:  6 users, 6 roles, 1327 types, 55 bools, 1 sens, 1024 cats
[    3.396272] SELinux:  92 classes, 13428 rules
[    3.396752] SELinux:  Permission validate_trans in class security not defined in policy.
[    3.398321] SELinux:  Permission getrlimit in class process not defined in policy.
[    3.399909] SELinux:  Permission module_load in class system not defined in policy.
[    3.401367] SELinux:  Permission map in class file not defined in policy.
[    3.402444] SELinux:  Permission map in class dir not defined in policy.
[    3.403539] SELinux:  Permission map in class lnk_file not defined in policy.
[    3.404668] SELinux:  Permission map in class chr_file not defined in policy.
[    3.405744] SELinux:  Permission map in class blk_file not defined in policy.
[    3.406809] SELinux:  Permission map in class sock_file not defined in policy.
[    3.408209] SELinux:  Permission map in class fifo_file not defined in policy.
[    3.409616] SELinux:  Permission map in class socket not defined in policy.
[    3.410689] SELinux:  Permission map in class tcp_socket not defined in policy.
[    3.412097] SELinux:  Permission map in class udp_socket not defined in policy.
[    3.413511] SELinux:  Permission map in class rawip_socket not defined in policy.
[    3.415104] SELinux:  Permission map in class netlink_socket not defined in policy.
[    3.416678] SELinux:  Permission map in class packet_socket not defined in policy.
[    3.418127] SELinux:  Permission map in class key_socket not defined in policy.
[    3.419674] SELinux:  Permission map in class unix_stream_socket not defined in policy.
[    3.421201] SELinux:  Permission map in class unix_dgram_socket not defined in policy.
[    3.422682] SELinux:  Permission map in class netlink_route_socket not defined in policy.
[    3.424180] SELinux:  Permission map in class netlink_tcpdiag_socket not defined in policy.
[    3.425725] SELinux:  Permission map in class netlink_nflog_socket not defined in policy.
[    3.427219] SELinux:  Permission map in class netlink_xfrm_socket not defined in policy.
[    3.428706] SELinux:  Permission map in class netlink_selinux_socket not defined in policy.
[    3.430224] SELinux:  Permission map in class netlink_iscsi_socket not defined in policy.
[    3.431739] SELinux:  Permission map in class netlink_audit_socket not defined in policy.
[    3.433233] SELinux:  Permission map in class netlink_fib_lookup_socket not defined in policy.
[    3.434891] SELinux:  Permission map in class netlink_connector_socket not defined in policy.
[    3.445441] SELinux:  Permission map in class netlink_netfilter_socket not defined in policy.
[    3.446978] SELinux:  Permission map in class netlink_dnrt_socket not defined in policy.
[    3.448640] SELinux:  Permission map in class netlink_kobject_uevent_socket not defined in policy.
[    3.450262] SELinux:  Permission map in class netlink_generic_socket not defined in policy.
[    3.451805] SELinux:  Permission map in class netlink_scsitransport_socket not defined in policy.
[    3.453403] SELinux:  Permission map in class netlink_rdma_socket not defined in policy.
[    3.454892] SELinux:  Permission map in class netlink_crypto_socket not defined in policy.
[    3.456407] SELinux:  Permission map in class appletalk_socket not defined in policy.
[    3.457875] SELinux:  Permission map in class dccp_socket not defined in policy.
[    3.459358] SELinux:  Permission map in class tun_socket not defined in policy.
[    3.460776] SELinux:  Class cap_userns not defined in policy.
[    3.461710] SELinux:  Class cap2_userns not defined in policy.
[    3.462652] SELinux:  Class sctp_socket not defined in policy.
[    3.463591] SELinux:  Class icmp_socket not defined in policy.
[    3.464530] SELinux:  Class ax25_socket not defined in policy.
[    3.465471] SELinux:  Class ipx_socket not defined in policy.
[    3.466403] SELinux:  Class netrom_socket not defined in policy.
[    3.467385] SELinux:  Class atmpvc_socket not defined in policy.
[    3.468347] SELinux:  Class x25_socket not defined in policy.
[    3.469281] SELinux:  Class rose_socket not defined in policy.
[    3.470222] SELinux:  Class decnet_socket not defined in policy.
[    3.471181] SELinux:  Class atmsvc_socket not defined in policy.
[    3.472135] SELinux:  Class rds_socket not defined in policy.
[    3.473069] SELinux:  Class irda_socket not defined in policy.
[    3.474012] SELinux:  Class pppox_socket not defined in policy.
[    3.474956] SELinux:  Class llc_socket not defined in policy.
[    3.475885] SELinux:  Class can_socket not defined in policy.
[    3.476826] SELinux:  Class tipc_socket not defined in policy.
[    3.477766] SELinux:  Class bluetooth_socket not defined in policy.
[    3.478745] SELinux:  Class iucv_socket not defined in policy.
[    3.479684] SELinux:  Class rxrpc_socket not defined in policy.
[    3.480630] SELinux:  Class isdn_socket not defined in policy.
[    3.481789] SELinux:  Class phonet_socket not defined in policy.
[    3.482779] SELinux:  Class ieee802154_socket not defined in policy.
[    3.483854] SELinux:  Class caif_socket not defined in policy.
[    3.484852] SELinux:  Class alg_socket not defined in policy.
[    3.485850] SELinux:  Class nfc_socket not defined in policy.
[    3.486974] SELinux:  Class vsock_socket not defined in policy.
[    3.488394] SELinux:  Class kcm_socket not defined in policy.
[    3.489749] SELinux:  Class qipcrtr_socket not defined in policy.
[    3.490768] SELinux:  Class smc_socket not defined in policy.
[    3.491755] SELinux:  Class infiniband_pkey not defined in policy.
[    3.492742] SELinux:  Class infiniband_endport not defined in policy.
[    3.493771] SELinux: the above unknown classes and permissions will be allowed
[    3.495199] SELinux:  policy capability network_peer_controls=1
[    3.496153] SELinux:  policy capability open_perms=1
[    3.497038] SELinux:  policy capability extended_socket_class=0
[    3.497988] SELinux:  policy capability always_check_network=0
[    3.498927] SELinux:  policy capability cgroup_seclabel=0
[    3.499852] SELinux:  Completing initialization.
[    3.499853] SELinux:  Setting up existing superblocks.
[    3.507786] systemd[1]: Successfully loaded SELinux policy in 135.055ms.
[    3.521840] systemd[1]: Relabelled /dev and /run in 8.622ms.
[    5.006060] systemd-journald[600]: Received request to flush runtime journal from PID 1
[    5.374329] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[    5.382966] ACPI: Power Button [PWRF]
[    5.469383] EXT4-fs (vda6): mounted filesystem with ordered data mode. Opts: commit=600
[    5.485316] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[    5.496657] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[    5.543044] mousedev: PS/2 mouse device common for all mice
[    5.634495] kauditd_printk_skb: 78 callbacks suppressed
[    5.635958] audit: type=1130 audit(1503970714.147:89): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.653836] audit: type=1131 audit(1503970714.155:90): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.693838] audit: type=1130 audit(1503970714.206:91): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.766989] audit: type=1130 audit(1503970714.280:92): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.816311] audit: type=1127 audit(1503970714.329:93): pid=676 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib64/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
[    5.857039] audit: type=1130 audit(1503970714.346:94): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.866944] audit: type=1130 audit(1503970714.352:95): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.877572] audit: type=1131 audit(1503970714.352:96): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.900029] audit: type=1130 audit(1503970714.412:97): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.908930] audit: type=1305 audit(1503970714.421:98): audit_backlog_limit=320 old=64 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1
[    8.127254] random: crng init done
[    9.705139] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    9.708949] Bridge firewalling registered
[    9.719058] nf_conntrack version 0.5.0 (8192 buckets, 32768 max)
[    9.844376] Initializing XFRM netlink socket
[    9.853382] Netfilter messages via NETLINK v0.30.
[    9.856953] ctnetlink v0.93: registering with nfnetlink.
[    9.947397] IPv6: ADDRCONF(NETDEV_UP): docker0: link is not ready
[   10.630555] docker0: port 1(vetha03b986) entered blocking state
[   10.631280] docker0: port 1(vetha03b986) entered disabled state
[   10.634218] device vetha03b986 entered promiscuous mode
[   10.722755] IPv6: ADDRCONF(NETDEV_UP): vetha03b986: link is not ready
[   10.723534] docker0: port 1(vetha03b986) entered blocking state
[   10.724168] docker0: port 1(vetha03b986) entered forwarding state
[   10.776855] docker0: port 1(vetha03b986) entered disabled state
[   11.023607] docker0: port 2(vethfdee011) entered blocking state
[   11.024391] docker0: port 2(vethfdee011) entered disabled state
[   11.050914] overlayfs: upperdir is in-use by another mount
[   11.055428] device vethfdee011 entered promiscuous mode
[   11.064899] IPv6: ADDRCONF(NETDEV_UP): vethfdee011: link is not ready
[   11.065690] docker0: port 2(vethfdee011) entered blocking state
[   11.066699] docker0: port 2(vethfdee011) entered forwarding state
[   11.072145] docker0: port 2(vethfdee011) entered disabled state
[   11.234364] docker0: port 3(vethc9d8402) entered blocking state
[   11.235797] docker0: port 3(vethc9d8402) entered disabled state
[   11.243687] device vethc9d8402 entered promiscuous mode
[   11.271650] IPv6: ADDRCONF(NETDEV_UP): vethc9d8402: link is not ready
[   11.272769] docker0: port 3(vethc9d8402) entered blocking state
[   11.273745] docker0: port 3(vethc9d8402) entered forwarding state
[   11.276868] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[   11.278063] docker0: port 3(vethc9d8402) entered disabled state
[   11.287136] eth0: renamed from veth53f019a
[   11.297858] IPv6: ADDRCONF(NETDEV_CHANGE): vetha03b986: link becomes ready
[   11.299042] docker0: port 1(vetha03b986) entered blocking state
[   11.300042] docker0: port 1(vetha03b986) entered forwarding state
[   11.401826] docker0: port 4(vethee3ed3a) entered blocking state
[   11.403414] docker0: port 4(vethee3ed3a) entered disabled state
[   11.408770] device vethee3ed3a entered promiscuous mode
[   11.417129] IPv6: ADDRCONF(NETDEV_UP): vethee3ed3a: link is not ready
[   11.418877] docker0: port 4(vethee3ed3a) entered blocking state
[   11.420057] docker0: port 4(vethee3ed3a) entered forwarding state
[   11.480257] docker0: port 5(vethdfb22be) entered blocking state
[   11.481555] docker0: port 5(vethdfb22be) entered disabled state
[   11.484594] device vethdfb22be entered promiscuous mode
[   11.496796] IPv6: ADDRCONF(NETDEV_UP): vethdfb22be: link is not ready
[   11.497989] docker0: port 5(vethdfb22be) entered blocking state
[   11.499054] docker0: port 5(vethdfb22be) entered forwarding state
[   11.552304] docker0: port 6(veth19132fc) entered blocking state
[   11.553436] docker0: port 6(veth19132fc) entered disabled state
[   11.558297] device veth19132fc entered promiscuous mode
[   11.565281] IPv6: ADDRCONF(NETDEV_UP): veth19132fc: link is not ready
[   11.566575] docker0: port 6(veth19132fc) entered blocking state
[   11.567581] docker0: port 6(veth19132fc) entered forwarding state
[   11.646724] docker0: port 7(veth8439f51) entered blocking state
[   11.648402] docker0: port 7(veth8439f51) entered disabled state
[   11.678649] device veth8439f51 entered promiscuous mode
[   11.696490] IPv6: ADDRCONF(NETDEV_UP): veth8439f51: link is not ready
[   11.697928] docker0: port 7(veth8439f51) entered blocking state
[   11.699217] docker0: port 7(veth8439f51) entered forwarding state
[   11.721132] eth0: renamed from veth0fe7997
[   11.722215] docker0: port 4(vethee3ed3a) entered disabled state
[   11.723223] docker0: port 5(vethdfb22be) entered disabled state
[   11.724203] docker0: port 6(veth19132fc) entered disabled state
[   11.725202] docker0: port 7(veth8439f51) entered disabled state
[   11.727898] IPv6: ADDRCONF(NETDEV_CHANGE): vethfdee011: link becomes ready
[   11.729004] docker0: port 2(vethfdee011) entered blocking state
[   11.729976] docker0: port 2(vethfdee011) entered forwarding state
[   11.842141] docker0: port 8(vethab34a52) entered blocking state
[   11.843138] docker0: port 8(vethab34a52) entered disabled state
[   11.847118] device vethab34a52 entered promiscuous mode
[   11.859179] IPv6: ADDRCONF(NETDEV_UP): vethab34a52: link is not ready
[   11.922397] eth0: renamed from vethaa6f66d
[   11.927340] IPv6: ADDRCONF(NETDEV_CHANGE): vethc9d8402: link becomes ready
[   11.928433] docker0: port 3(vethc9d8402) entered blocking state
[   11.929380] docker0: port 3(vethc9d8402) entered forwarding state
[   11.947556] docker0: port 9(veth401d882) entered blocking state
[   11.948848] docker0: port 9(veth401d882) entered disabled state
[   11.953990] device veth401d882 entered promiscuous mode
[   11.962091] IPv6: ADDRCONF(NETDEV_UP): veth401d882: link is not ready
[   11.963331] docker0: port 9(veth401d882) entered blocking state
[   11.964314] docker0: port 9(veth401d882) entered forwarding state
[   12.033981] docker0: port 9(veth401d882) entered disabled state
[   12.035586] docker0: port 1(vetha03b986) entered disabled state
[   12.046116] veth53f019a: renamed from eth0
[   12.103199] docker0: port 1(vetha03b986) entered disabled state
[   12.110498] device vetha03b986 left promiscuous mode
[   12.111445] docker0: port 1(vetha03b986) entered disabled state
[   12.164865] eth0: renamed from veth127d96e
[   12.167515] IPv6: ADDRCONF(NETDEV_CHANGE): vethee3ed3a: link becomes ready
[   12.168702] docker0: port 4(vethee3ed3a) entered blocking state
[   12.169847] docker0: port 4(vethee3ed3a) entered forwarding state
[   12.342767] eth0: renamed from veth5bc6d97
[   12.349999] IPv6: ADDRCONF(NETDEV_CHANGE): vethdfb22be: link becomes ready
[   12.351619] docker0: port 5(vethdfb22be) entered blocking state
[   12.352990] docker0: port 5(vethdfb22be) entered forwarding state
[   12.488476] docker0: port 1(veth5bd9924) entered blocking state
[   12.490118] docker0: port 1(veth5bd9924) entered disabled state
[   12.496796] device veth5bd9924 entered promiscuous mode
[   12.507137] IPv6: ADDRCONF(NETDEV_UP): veth5bd9924: link is not ready
[   12.508425] docker0: port 1(veth5bd9924) entered blocking state
[   12.509460] docker0: port 1(veth5bd9924) entered forwarding state
[   12.528881] docker0: port 1(veth5bd9924) entered disabled state
[   12.530134] docker0: port 2(vethfdee011) entered disabled state
[   12.536481] veth0fe7997: renamed from eth0
[   12.608534] eth0: renamed from vethe36691e
[   12.611545] IPv6: ADDRCONF(NETDEV_CHANGE): veth19132fc: link becomes ready
[   12.612816] docker0: port 6(veth19132fc) entered blocking state
[   12.614044] docker0: port 6(veth19132fc) entered forwarding state
[   12.626195] docker0: port 2(vethfdee011) entered disabled state
[   12.637946] device vethfdee011 left promiscuous mode
[   12.638914] docker0: port 2(vethfdee011) entered disabled state
[   12.782896] docker0: port 3(vethc9d8402) entered disabled state
[   12.810278] vethaa6f66d: renamed from eth0
[   12.842183] eth0: renamed from veth23d5829
[   12.846372] IPv6: ADDRCONF(NETDEV_CHANGE): veth8439f51: link becomes ready
[   12.847716] docker0: port 7(veth8439f51) entered blocking state
[   12.849223] docker0: port 7(veth8439f51) entered forwarding state
[   12.875817] docker0: port 3(vethc9d8402) entered disabled state
[   12.885072] device vethc9d8402 left promiscuous mode
[   12.886049] docker0: port 3(vethc9d8402) entered disabled state
[   13.003234] docker0: port 4(vethee3ed3a) entered disabled state
[   13.006316] veth127d96e: renamed from eth0
[   13.073841] docker0: port 4(vethee3ed3a) entered disabled state
[   13.082248] device vethee3ed3a left promiscuous mode
[   13.083177] docker0: port 4(vethee3ed3a) entered disabled state
[   13.098398] docker0: port 2(veth1823c63) entered blocking state
[   13.098399] docker0: port 2(veth1823c63) entered disabled state
[   13.098423] device veth1823c63 entered promiscuous mode
[   13.101888] IPv6: ADDRCONF(NETDEV_UP): veth1823c63: link is not ready
[   13.101891] docker0: port 2(veth1823c63) entered blocking state
[   13.101892] docker0: port 2(veth1823c63) entered forwarding state
[   13.302018] eth0: renamed from veth57b6693
[   13.302800] docker0: port 2(veth1823c63) entered disabled state
[   13.304191] IPv6: ADDRCONF(NETDEV_CHANGE): veth401d882: link becomes ready
[   13.305429] docker0: port 9(veth401d882) entered blocking state
[   13.306482] docker0: port 9(veth401d882) entered forwarding state
[   13.311936] docker0: port 5(vethdfb22be) entered disabled state
[   13.316131] veth5bc6d97: renamed from eth0
[   13.332876] eth0: renamed from veth874e2c7
[   13.343424] IPv6: ADDRCONF(NETDEV_CHANGE): vethab34a52: link becomes ready
[   13.345107] docker0: port 8(vethab34a52) entered blocking state
[   13.346480] docker0: port 8(vethab34a52) entered forwarding state
[   13.421228] docker0: port 6(veth19132fc) entered disabled state
[   13.427762] vethe36691e: renamed from eth0
[   13.480452] docker0: port 5(vethdfb22be) entered disabled state
[   13.498357] device vethdfb22be left promiscuous mode
[   13.499002] docker0: port 5(vethdfb22be) entered disabled state
[   13.529456] docker0: port 6(veth19132fc) entered disabled state
[   13.536631] device veth19132fc left promiscuous mode
[   13.537388] docker0: port 6(veth19132fc) entered disabled state
[   13.555279] docker0: port 7(veth8439f51) entered disabled state
[   13.558536] veth23d5829: renamed from eth0
[   13.612133] docker0: port 7(veth8439f51) entered disabled state
[   13.623656] device veth8439f51 left promiscuous mode
[   13.624298] docker0: port 7(veth8439f51) entered disabled state
[   13.681703] eth0: renamed from vethea3bdda
[   13.685187] IPv6: ADDRCONF(NETDEV_CHANGE): veth5bd9924: link becomes ready
[   13.686879] docker0: port 1(veth5bd9924) entered blocking state
[   13.688087] docker0: port 1(veth5bd9924) entered forwarding state
[   13.729426] eth0: renamed from vetha22a551
[   13.730452] IPv6: ADDRCONF(NETDEV_CHANGE): veth1823c63: link becomes ready
[   13.731499] docker0: port 2(veth1823c63) entered blocking state
[   13.732451] docker0: port 2(veth1823c63) entered forwarding state
[   13.880092] docker0: port 9(veth401d882) entered disabled state
[   13.884959] veth57b6693: renamed from eth0
[   13.905418] docker0: port 9(veth401d882) entered disabled state
[   13.912143] device veth401d882 left promiscuous mode
[   13.913528] docker0: port 9(veth401d882) entered disabled state
[   14.159168] docker0: port 1(veth5bd9924) entered disabled state
[   14.163740] vethea3bdda: renamed from eth0
[   14.175372] docker0: port 1(veth5bd9924) entered disabled state
[   14.180855] device veth5bd9924 left promiscuous mode
[   14.182140] docker0: port 1(veth5bd9924) entered disabled state
[   14.272556] docker0: port 2(veth1823c63) entered disabled state
[   14.279225] vetha22a551: renamed from eth0
[   14.322528] docker0: port 2(veth1823c63) entered disabled state
[   14.329657] device veth1823c63 left promiscuous mode
[   14.330687] docker0: port 2(veth1823c63) entered disabled state
[   16.160723] docker0: port 8(vethab34a52) entered disabled state
[   16.164902] veth874e2c7: renamed from eth0
[   16.194810] docker0: port 8(vethab34a52) entered disabled state
[   16.233540] device vethab34a52 left promiscuous mode
[   16.233544] docker0: port 8(vethab34a52) entered disabled state
Contributor

euank commented Aug 29, 2017

There's also an overlayfs related message in dmesg at the time:

$ dmesg -T | grep -C 5 upper
[Tue Aug 29 01:38:38 2017] docker0: port 1(vetha03b986) entered blocking state
[Tue Aug 29 01:38:38 2017] docker0: port 1(vetha03b986) entered forwarding state
[Tue Aug 29 01:38:38 2017] docker0: port 1(vetha03b986) entered disabled state
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered blocking state
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered disabled state
[Tue Aug 29 01:38:39 2017] overlayfs: upperdir is in-use by another mount
[Tue Aug 29 01:38:39 2017] device vethfdee011 entered promiscuous mode
[Tue Aug 29 01:38:39 2017] IPv6: ADDRCONF(NETDEV_UP): vethfdee011: link is not ready
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered blocking state
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered forwarding state
[Tue Aug 29 01:38:39 2017] docker0: port 2(vethfdee011) entered disabled state

Full dmesg below:

[    0.000000] random: get_random_bytes called from start_kernel+0x42/0x477 with crng_init=0
[    0.000000] Linux version 4.13.0-rc6-coreos (esk@multivac.euank.com) (gcc version 4.9.4 (Gentoo Hardened 4.9.4 p1.0, pie-0.6.4)) #1 SMP Fri Aug 25 18:04:02 PDT 2017
[    0.000000] Command line: BOOT_IMAGE=/coreos/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 coreos.first_boot=detected coreos.randomize_disk_guid=00000000-0000-0000-0000-000000000001 verity.usrhash=fe2505e6e7e57765cf42ea82559eedd56de26688f1ee9c2a51cac80dbefacdc1
[    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
[    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000] x86/fpu: xstate_offset[3]:  832, xstate_sizes[3]:   64
[    0.000000] x86/fpu: xstate_offset[4]:  896, xstate_sizes[4]:   64
[    0.000000] x86/fpu: Enabled xstate features 0x1f, context size is 960 bytes, using 'compacted' format.
[    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-0x000000003ffddfff] usable
[    0.000000] BIOS-e820: [mem 0x000000003ffde000-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] random: fast init done
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.10.2-0-g5f4c7b1-prebuilt.qemu-project.org 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x3ffde max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: write-back
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 0080000000 mask FF80000000 uncachable
[    0.000000]   1 disabled
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT  
[    0.000000] Base memory trampoline at [ffff9a0740099000] 99000 size 24576
[    0.000000] Using GB pages for direct mapping
[    0.000000] BRK [0x1cd66000, 0x1cd66fff] PGTABLE
[    0.000000] BRK [0x1cd67000, 0x1cd67fff] PGTABLE
[    0.000000] BRK [0x1cd68000, 0x1cd68fff] PGTABLE
[    0.000000] BRK [0x1cd69000, 0x1cd69fff] PGTABLE
[    0.000000] BRK [0x1cd6a000, 0x1cd6afff] PGTABLE
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F68B0 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000003FFE154E 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000003FFE142A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000003FFE0040 0013EA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000003FFE0000 000040
[    0.000000] ACPI: APIC 0x000000003FFE149E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000003FFE1516 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffddfff]
[    0.000000] NODE_DATA(0) allocated [mem 0x3ffd8000-0x3ffddfff]
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:3ffd0001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 2075884851 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-0x000000003ffddfff]
[    0.000000]   Normal   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-0x000000003ffddfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffddfff]
[    0.000000] On node 0 totalpages: 262012
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 4032 pages used for memmap
[    0.000000]   DMA32 zone: 258014 pages, LIFO batch:31
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    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] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] [Firmware Bug]: TSC_DEADLINE disabled due to Errata; please update microcode to version: 0xb2 (or later)
[    0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[    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: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] percpu: Embedded 38 pages/cpu @ffff9a077fc00000 s115032 r8192 d32424 u2097152
[    0.000000] pcpu-alloc: s115032 r8192 d32424 u2097152 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 3fc0db00
[    0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes)
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 257895
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/coreos/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 coreos.first_boot=detected coreos.randomize_disk_guid=00000000-0000-0000-0000-000000000001 verity.usrhash=fe2505e6e7e57765cf42ea82559eedd56de26688f1ee9c2a51cac80dbefacdc1
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Memory: 984772K/1048048K available (6295K kernel code, 1219K rwdata, 2696K rodata, 32156K init, 740K bss, 63276K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] ftrace: allocating 26583 entries in 104 pages
[    0.001000] Hierarchical RCU implementation.
[    0.001000] 	RCU event tracing is enabled.
[    0.001000] 	RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.
[    0.001000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.001000] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16
[    0.001000] Console: colour VGA+ 80x25
[    0.001000] console [tty0] enabled
[    0.001000] console [ttyS0] enabled
[    0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.001000] hpet clockevent registered
[    0.001006] tsc: Detected 2808.000 MHz processor
[    0.001875] Calibrating delay loop (skipped) preset value.. 5616.00 BogoMIPS (lpj=2808000)
[    0.002003] pid_max: default: 32768 minimum: 301
[    0.003012] ACPI: Core revision 20170531
[    0.005056] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.006035] Security Framework initialized
[    0.007008] SELinux:  Initializing.
[    0.008016] SELinux:  Starting in permissive mode
[    0.008201] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.010133] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.011021] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.012008] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.014138] CPU: Physical Processor ID: 0
[    0.014943] mce: CPU supports 10 MCE banks
[    0.015031] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.016003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.022303] Freeing SMP alternatives memory: 24K
[    0.023692] smpboot: Max logical packages: 1
[    0.024121] x2apic enabled
[    0.025003] Switched APIC routing to physical x2apic.
[    0.026634] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.027000] smpboot: CPU0: Intel(R) Core(TM) i7-6600U CPU @ 2.60GHz (family: 0x6, model: 0x4e, stepping: 0x3)
[    0.027092] Performance Events: Skylake events, Intel PMU driver.
[    0.028004] ... version:                2
[    0.029001] ... bit width:              48
[    0.030001] ... generic registers:      4
[    0.031001] ... value mask:             0000ffffffffffff
[    0.032006] ... max period:             000000007fffffff
[    0.033003] ... fixed-purpose events:   3
[    0.034001] ... event mask:             000000070000000f
[    0.034943] Hierarchical SRCU implementation.
[    0.036063] smp: Bringing up secondary CPUs ...
[    0.036935] smp: Brought up 1 node, 1 CPU
[    0.037003] smpboot: Total of 1 processors activated (5616.00 BogoMIPS)
[    0.038240] devtmpfs: initialized
[    0.039037] x86/mm: Memory block size: 128MB
[    0.040197] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.041006] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.042037] pinctrl core: initialized pinctrl subsystem
[    0.043067] NET: Registered protocol family 16
[    0.044101] cpuidle: using governor menu
[    0.045003] PCCT header not found.
[    0.045775] ACPI: bus type PCI registered
[    0.046002] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.047040] dca service started, version 1.12.1
[    0.048052] PCI: Using configuration type 1 for base access
[    0.050098] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.051005] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.053089] ACPI: Added _OSI(Module Device)
[    0.054006] ACPI: Added _OSI(Processor Device)
[    0.055007] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.056005] ACPI: Added _OSI(Processor Aggregator Device)
[    0.058910] ACPI: Interpreter enabled
[    0.059012] ACPI: (supports S0 S3 S5)
[    0.060002] ACPI: Using IOAPIC for interrupt routing
[    0.061012] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.065087] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.066006] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.067006] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.068008] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.069224] acpiphp: Slot [3] registered
[    0.070017] acpiphp: Slot [4] registered
[    0.070790] acpiphp: Slot [5] registered
[    0.071015] acpiphp: Slot [6] registered
[    0.072015] acpiphp: Slot [7] registered
[    0.073018] acpiphp: Slot [8] registered
[    0.074018] acpiphp: Slot [9] registered
[    0.074801] acpiphp: Slot [10] registered
[    0.075030] acpiphp: Slot [11] registered
[    0.076017] acpiphp: Slot [12] registered
[    0.077020] acpiphp: Slot [13] registered
[    0.078016] acpiphp: Slot [14] registered
[    0.079029] acpiphp: Slot [15] registered
[    0.080022] acpiphp: Slot [16] registered
[    0.080805] acpiphp: Slot [17] registered
[    0.081016] acpiphp: Slot [18] registered
[    0.082021] acpiphp: Slot [19] registered
[    0.083016] acpiphp: Slot [20] registered
[    0.083796] acpiphp: Slot [21] registered
[    0.084017] acpiphp: Slot [22] registered
[    0.085016] acpiphp: Slot [23] registered
[    0.086015] acpiphp: Slot [24] registered
[    0.087015] acpiphp: Slot [25] registered
[    0.087794] acpiphp: Slot [26] registered
[    0.088055] acpiphp: Slot [27] registered
[    0.089031] acpiphp: Slot [28] registered
[    0.090037] acpiphp: Slot [29] registered
[    0.091039] acpiphp: Slot [30] registered
[    0.092030] acpiphp: Slot [31] registered
[    0.093018] PCI host bridge to bus 0000:00
[    0.094017] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.095007] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.096003] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.097002] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[    0.098003] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.099034] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.099366] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.100142] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.103387] pci 0000:00:01.1: reg 0x20: [io  0xc060-0xc06f]
[    0.105021] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.106002] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.107002] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.108002] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.109148] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.109445] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.110011] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.112052] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    0.113004] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    0.116003] pci 0000:00:02.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff]
[    0.122007] pci 0000:00:02.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref]
[    0.122215] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000
[    0.124003] pci 0000:00:03.0: reg 0x10: [io  0xc000-0xc03f]
[    0.126003] pci 0000:00:03.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff]
[    0.133006] pci 0000:00:03.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[    0.135434] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000
[    0.137006] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc05f]
[    0.139006] pci 0000:00:04.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff]
[    0.146006] pci 0000:00:04.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
[    0.147003] pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    0.148360] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.149072] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.150105] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.151071] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.152038] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.153116] ACPI: Enabled 2 GPEs in block 00 to 0F
[    0.154108] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    0.155000] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.155003] pci 0000:00:02.0: vgaarb: bridge control possible
[    0.156001] vgaarb: loaded
[    0.157033] PCI: Using ACPI for IRQ routing
[    0.158003] PCI: pci_cache_line_size set to 64 bytes
[    0.158087] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.158088] e820: reserve RAM buffer [mem 0x3ffde000-0x3fffffff]
[    0.158237] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.159003] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    0.165014] clocksource: Switched to clocksource kvm-clock
[    0.172755] VFS: Disk quotas dquot_6.6.0
[    0.173576] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.174685] pnp: PnP ACPI init
[    0.175418] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.175441] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.175456] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.175462] pnp 00:03: [dma 2]
[    0.175469] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.175508] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    0.175538] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.175679] pnp: PnP ACPI: found 6 devices
[    0.181731] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.183358] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.183359] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.183360] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.183361] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
[    0.183430] NET: Registered protocol family 2
[    0.184421] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[    0.185538] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[    0.186691] TCP: Hash tables configured (established 8192 bind 8192)
[    0.188371] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.189804] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.190898] NET: Registered protocol family 1
[    0.191715] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.192674] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.193618] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.194619] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.196146] PCI: CLS 0 bytes, default 64
[    0.619955] audit: initializing netlink subsys (disabled)
[    0.621172] Initialise system trusted keyrings
[    0.622025] workingset: timestamp_bits=39 max_order=18 bucket_order=0
[    0.623927] audit: type=2000 audit(1503970709.005:1): state=initialized audit_enabled=0 res=1
[    0.625497] SELinux:  Registering netfilter hooks
[    0.894988] Key type asymmetric registered
[    0.895827] Asymmetric key parser 'x509' registered
[    0.896742] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.898194] io scheduler noop registered
[    0.898956] io scheduler deadline registered
[    0.899834] io scheduler cfq registered (default)
[    0.900682] io scheduler mq-deadline registered
[    0.901503] io scheduler kyber registered
[    0.902338] intel_idle: does not run on family 6 model 78
[    0.902400] GHES: HEST is not enabled!
[    0.903190] ioatdma: Intel(R) QuickData Technology Driver 4.00
[    0.904189] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.927247] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    0.929057] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    0.931080] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.931946] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.933026] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    0.934837] rtc_cmos 00:00: RTC can wake from S4
[    0.935959] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[    0.937191] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[    0.939303] ip_tables: (C) 2000-2006 Netfilter Core Team
[    0.940495] NET: Registered protocol family 10
[    0.941467] Segment Routing with IPv6
[    0.942227] NET: Registered protocol family 17
[    0.943051] Key type dns_resolver registered
[    0.944065] sched_clock: Marking stable (944006650, 0)->(1215446124, -271439474)
[    0.946033] registered taskstats version 1
[    0.946825] Loading compiled-in X.509 certificates
[    0.982091] Loaded X.509 cert 'CoreOS, Inc: Module signing key for 4.13.0-rc6-coreos: fa895088072dbeb9db3fe2f88489ad418db70068'
[    0.984970] ima: No TPM chip found, activating TPM-bypass! (rc=-19)
[    0.986875] rtc_cmos 00:00: setting system clock to 2017-08-29 01:38:29 UTC (1503970709)
[    0.993029] Freeing unused kernel memory: 32156K
[    0.993911] Write protecting the kernel read-only data: 12288k
[    0.995188] Freeing unused kernel memory: 1884K
[    0.998571] Freeing unused kernel memory: 1400K
[    1.003977] systemd[1]: systemd 234 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT -GNUTLS -ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN default-hierarchy=legacy)
[    1.007049] systemd[1]: Detected virtualization kvm.
[    1.007918] systemd[1]: Detected architecture x86-64.
[    1.008852] systemd[1]: Running in initial RAM disk.
[    1.011130] systemd[1]: No hostname configured.
[    1.011956] systemd[1]: Set hostname to <localhost>.
[    1.012844] systemd[1]: Initializing machine ID from KVM UUID.
[    1.091885] systemd[1]: Listening on Journal Socket.
[    1.093716] systemd[1]: Listening on udev Kernel Socket.
[    1.095564] systemd[1]: Listening on Journal Audit Socket.
[    1.142180] audit: type=1130 audit(1503970709.655:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.148085] audit: type=1130 audit(1503970709.660:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.164054] audit: type=1130 audit(1503970709.677:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.178024] audit: type=1130 audit(1503970709.691:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.222127] audit: type=1130 audit(1503970709.735:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.243350] device-mapper: uevent: version 1.0.3
[    1.244329] device-mapper: ioctl: 4.36.0-ioctl (2017-06-09) initialised: dm-devel@redhat.com
[    1.259575] audit: type=1130 audit(1503970709.772:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.273026] audit: type=1130 audit(1503970709.785:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.302899] audit: type=1130 audit(1503970709.816:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.331020] audit: type=1130 audit(1503970709.843:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.463866] SCSI subsystem initialized
[    1.483958] AVX2 version of gcm_enc/dec engaged.
[    1.484871] AES CTR mode by8 optimization enabled
[    1.499036] libata version 3.00 loaded.
[    1.535296] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[    1.537042] ata_piix 0000:00:01.1: version 2.13
[    1.542013] scsi host0: ata_piix
[    1.547420] scsi host1: ata_piix
[    1.547458] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 irq 14
[    1.547458] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 irq 15
[    1.578277] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10
[    1.590568]  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
[    1.632054] tsc: Refined TSC clocksource calibration: 2808.009 MHz
[    1.633603] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2879ce56af2, max_idle_ns: 440795206607 ns
[    1.667823]  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
[    1.704665] ata2.01: NODEV after polling detection
[    1.704879] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    1.706338] ata2.00: configured for MWDMA2
[    1.707538] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    1.730675] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    1.732286] cdrom: Uniform CD-ROM driver Revision: 3.20
[    1.735245] sr 1:0:0:0: Attached scsi CD-ROM sr0
[    2.672924]  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
[    2.855372] EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null)
[    2.875978] EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null)
[    3.301832] systemd-journald[134]: Received SIGTERM from PID 1 (systemd).
[    3.328751] systemd: 18 output lines suppressed due to ratelimiting
[    3.394742] SELinux: 4096 avtab hash slots, 13428 rules.
[    3.395898] SELinux: 4096 avtab hash slots, 13428 rules.
[    3.396270] SELinux:  6 users, 6 roles, 1327 types, 55 bools, 1 sens, 1024 cats
[    3.396272] SELinux:  92 classes, 13428 rules
[    3.396752] SELinux:  Permission validate_trans in class security not defined in policy.
[    3.398321] SELinux:  Permission getrlimit in class process not defined in policy.
[    3.399909] SELinux:  Permission module_load in class system not defined in policy.
[    3.401367] SELinux:  Permission map in class file not defined in policy.
[    3.402444] SELinux:  Permission map in class dir not defined in policy.
[    3.403539] SELinux:  Permission map in class lnk_file not defined in policy.
[    3.404668] SELinux:  Permission map in class chr_file not defined in policy.
[    3.405744] SELinux:  Permission map in class blk_file not defined in policy.
[    3.406809] SELinux:  Permission map in class sock_file not defined in policy.
[    3.408209] SELinux:  Permission map in class fifo_file not defined in policy.
[    3.409616] SELinux:  Permission map in class socket not defined in policy.
[    3.410689] SELinux:  Permission map in class tcp_socket not defined in policy.
[    3.412097] SELinux:  Permission map in class udp_socket not defined in policy.
[    3.413511] SELinux:  Permission map in class rawip_socket not defined in policy.
[    3.415104] SELinux:  Permission map in class netlink_socket not defined in policy.
[    3.416678] SELinux:  Permission map in class packet_socket not defined in policy.
[    3.418127] SELinux:  Permission map in class key_socket not defined in policy.
[    3.419674] SELinux:  Permission map in class unix_stream_socket not defined in policy.
[    3.421201] SELinux:  Permission map in class unix_dgram_socket not defined in policy.
[    3.422682] SELinux:  Permission map in class netlink_route_socket not defined in policy.
[    3.424180] SELinux:  Permission map in class netlink_tcpdiag_socket not defined in policy.
[    3.425725] SELinux:  Permission map in class netlink_nflog_socket not defined in policy.
[    3.427219] SELinux:  Permission map in class netlink_xfrm_socket not defined in policy.
[    3.428706] SELinux:  Permission map in class netlink_selinux_socket not defined in policy.
[    3.430224] SELinux:  Permission map in class netlink_iscsi_socket not defined in policy.
[    3.431739] SELinux:  Permission map in class netlink_audit_socket not defined in policy.
[    3.433233] SELinux:  Permission map in class netlink_fib_lookup_socket not defined in policy.
[    3.434891] SELinux:  Permission map in class netlink_connector_socket not defined in policy.
[    3.445441] SELinux:  Permission map in class netlink_netfilter_socket not defined in policy.
[    3.446978] SELinux:  Permission map in class netlink_dnrt_socket not defined in policy.
[    3.448640] SELinux:  Permission map in class netlink_kobject_uevent_socket not defined in policy.
[    3.450262] SELinux:  Permission map in class netlink_generic_socket not defined in policy.
[    3.451805] SELinux:  Permission map in class netlink_scsitransport_socket not defined in policy.
[    3.453403] SELinux:  Permission map in class netlink_rdma_socket not defined in policy.
[    3.454892] SELinux:  Permission map in class netlink_crypto_socket not defined in policy.
[    3.456407] SELinux:  Permission map in class appletalk_socket not defined in policy.
[    3.457875] SELinux:  Permission map in class dccp_socket not defined in policy.
[    3.459358] SELinux:  Permission map in class tun_socket not defined in policy.
[    3.460776] SELinux:  Class cap_userns not defined in policy.
[    3.461710] SELinux:  Class cap2_userns not defined in policy.
[    3.462652] SELinux:  Class sctp_socket not defined in policy.
[    3.463591] SELinux:  Class icmp_socket not defined in policy.
[    3.464530] SELinux:  Class ax25_socket not defined in policy.
[    3.465471] SELinux:  Class ipx_socket not defined in policy.
[    3.466403] SELinux:  Class netrom_socket not defined in policy.
[    3.467385] SELinux:  Class atmpvc_socket not defined in policy.
[    3.468347] SELinux:  Class x25_socket not defined in policy.
[    3.469281] SELinux:  Class rose_socket not defined in policy.
[    3.470222] SELinux:  Class decnet_socket not defined in policy.
[    3.471181] SELinux:  Class atmsvc_socket not defined in policy.
[    3.472135] SELinux:  Class rds_socket not defined in policy.
[    3.473069] SELinux:  Class irda_socket not defined in policy.
[    3.474012] SELinux:  Class pppox_socket not defined in policy.
[    3.474956] SELinux:  Class llc_socket not defined in policy.
[    3.475885] SELinux:  Class can_socket not defined in policy.
[    3.476826] SELinux:  Class tipc_socket not defined in policy.
[    3.477766] SELinux:  Class bluetooth_socket not defined in policy.
[    3.478745] SELinux:  Class iucv_socket not defined in policy.
[    3.479684] SELinux:  Class rxrpc_socket not defined in policy.
[    3.480630] SELinux:  Class isdn_socket not defined in policy.
[    3.481789] SELinux:  Class phonet_socket not defined in policy.
[    3.482779] SELinux:  Class ieee802154_socket not defined in policy.
[    3.483854] SELinux:  Class caif_socket not defined in policy.
[    3.484852] SELinux:  Class alg_socket not defined in policy.
[    3.485850] SELinux:  Class nfc_socket not defined in policy.
[    3.486974] SELinux:  Class vsock_socket not defined in policy.
[    3.488394] SELinux:  Class kcm_socket not defined in policy.
[    3.489749] SELinux:  Class qipcrtr_socket not defined in policy.
[    3.490768] SELinux:  Class smc_socket not defined in policy.
[    3.491755] SELinux:  Class infiniband_pkey not defined in policy.
[    3.492742] SELinux:  Class infiniband_endport not defined in policy.
[    3.493771] SELinux: the above unknown classes and permissions will be allowed
[    3.495199] SELinux:  policy capability network_peer_controls=1
[    3.496153] SELinux:  policy capability open_perms=1
[    3.497038] SELinux:  policy capability extended_socket_class=0
[    3.497988] SELinux:  policy capability always_check_network=0
[    3.498927] SELinux:  policy capability cgroup_seclabel=0
[    3.499852] SELinux:  Completing initialization.
[    3.499853] SELinux:  Setting up existing superblocks.
[    3.507786] systemd[1]: Successfully loaded SELinux policy in 135.055ms.
[    3.521840] systemd[1]: Relabelled /dev and /run in 8.622ms.
[    5.006060] systemd-journald[600]: Received request to flush runtime journal from PID 1
[    5.374329] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[    5.382966] ACPI: Power Button [PWRF]
[    5.469383] EXT4-fs (vda6): mounted filesystem with ordered data mode. Opts: commit=600
[    5.485316] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[    5.496657] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[    5.543044] mousedev: PS/2 mouse device common for all mice
[    5.634495] kauditd_printk_skb: 78 callbacks suppressed
[    5.635958] audit: type=1130 audit(1503970714.147:89): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.653836] audit: type=1131 audit(1503970714.155:90): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.693838] audit: type=1130 audit(1503970714.206:91): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.766989] audit: type=1130 audit(1503970714.280:92): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.816311] audit: type=1127 audit(1503970714.329:93): pid=676 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib64/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
[    5.857039] audit: type=1130 audit(1503970714.346:94): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.866944] audit: type=1130 audit(1503970714.352:95): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.877572] audit: type=1131 audit(1503970714.352:96): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.900029] audit: type=1130 audit(1503970714.412:97): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    5.908930] audit: type=1305 audit(1503970714.421:98): audit_backlog_limit=320 old=64 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1
[    8.127254] random: crng init done
[    9.705139] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    9.708949] Bridge firewalling registered
[    9.719058] nf_conntrack version 0.5.0 (8192 buckets, 32768 max)
[    9.844376] Initializing XFRM netlink socket
[    9.853382] Netfilter messages via NETLINK v0.30.
[    9.856953] ctnetlink v0.93: registering with nfnetlink.
[    9.947397] IPv6: ADDRCONF(NETDEV_UP): docker0: link is not ready
[   10.630555] docker0: port 1(vetha03b986) entered blocking state
[   10.631280] docker0: port 1(vetha03b986) entered disabled state
[   10.634218] device vetha03b986 entered promiscuous mode
[   10.722755] IPv6: ADDRCONF(NETDEV_UP): vetha03b986: link is not ready
[   10.723534] docker0: port 1(vetha03b986) entered blocking state
[   10.724168] docker0: port 1(vetha03b986) entered forwarding state
[   10.776855] docker0: port 1(vetha03b986) entered disabled state
[   11.023607] docker0: port 2(vethfdee011) entered blocking state
[   11.024391] docker0: port 2(vethfdee011) entered disabled state
[   11.050914] overlayfs: upperdir is in-use by another mount
[   11.055428] device vethfdee011 entered promiscuous mode
[   11.064899] IPv6: ADDRCONF(NETDEV_UP): vethfdee011: link is not ready
[   11.065690] docker0: port 2(vethfdee011) entered blocking state
[   11.066699] docker0: port 2(vethfdee011) entered forwarding state
[   11.072145] docker0: port 2(vethfdee011) entered disabled state
[   11.234364] docker0: port 3(vethc9d8402) entered blocking state
[   11.235797] docker0: port 3(vethc9d8402) entered disabled state
[   11.243687] device vethc9d8402 entered promiscuous mode
[   11.271650] IPv6: ADDRCONF(NETDEV_UP): vethc9d8402: link is not ready
[   11.272769] docker0: port 3(vethc9d8402) entered blocking state
[   11.273745] docker0: port 3(vethc9d8402) entered forwarding state
[   11.276868] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[   11.278063] docker0: port 3(vethc9d8402) entered disabled state
[   11.287136] eth0: renamed from veth53f019a
[   11.297858] IPv6: ADDRCONF(NETDEV_CHANGE): vetha03b986: link becomes ready
[   11.299042] docker0: port 1(vetha03b986) entered blocking state
[   11.300042] docker0: port 1(vetha03b986) entered forwarding state
[   11.401826] docker0: port 4(vethee3ed3a) entered blocking state
[   11.403414] docker0: port 4(vethee3ed3a) entered disabled state
[   11.408770] device vethee3ed3a entered promiscuous mode
[   11.417129] IPv6: ADDRCONF(NETDEV_UP): vethee3ed3a: link is not ready
[   11.418877] docker0: port 4(vethee3ed3a) entered blocking state
[   11.420057] docker0: port 4(vethee3ed3a) entered forwarding state
[   11.480257] docker0: port 5(vethdfb22be) entered blocking state
[   11.481555] docker0: port 5(vethdfb22be) entered disabled state
[   11.484594] device vethdfb22be entered promiscuous mode
[   11.496796] IPv6: ADDRCONF(NETDEV_UP): vethdfb22be: link is not ready
[   11.497989] docker0: port 5(vethdfb22be) entered blocking state
[   11.499054] docker0: port 5(vethdfb22be) entered forwarding state
[   11.552304] docker0: port 6(veth19132fc) entered blocking state
[   11.553436] docker0: port 6(veth19132fc) entered disabled state
[   11.558297] device veth19132fc entered promiscuous mode
[   11.565281] IPv6: ADDRCONF(NETDEV_UP): veth19132fc: link is not ready
[   11.566575] docker0: port 6(veth19132fc) entered blocking state
[   11.567581] docker0: port 6(veth19132fc) entered forwarding state
[   11.646724] docker0: port 7(veth8439f51) entered blocking state
[   11.648402] docker0: port 7(veth8439f51) entered disabled state
[   11.678649] device veth8439f51 entered promiscuous mode
[   11.696490] IPv6: ADDRCONF(NETDEV_UP): veth8439f51: link is not ready
[   11.697928] docker0: port 7(veth8439f51) entered blocking state
[   11.699217] docker0: port 7(veth8439f51) entered forwarding state
[   11.721132] eth0: renamed from veth0fe7997
[   11.722215] docker0: port 4(vethee3ed3a) entered disabled state
[   11.723223] docker0: port 5(vethdfb22be) entered disabled state
[   11.724203] docker0: port 6(veth19132fc) entered disabled state
[   11.725202] docker0: port 7(veth8439f51) entered disabled state
[   11.727898] IPv6: ADDRCONF(NETDEV_CHANGE): vethfdee011: link becomes ready
[   11.729004] docker0: port 2(vethfdee011) entered blocking state
[   11.729976] docker0: port 2(vethfdee011) entered forwarding state
[   11.842141] docker0: port 8(vethab34a52) entered blocking state
[   11.843138] docker0: port 8(vethab34a52) entered disabled state
[   11.847118] device vethab34a52 entered promiscuous mode
[   11.859179] IPv6: ADDRCONF(NETDEV_UP): vethab34a52: link is not ready
[   11.922397] eth0: renamed from vethaa6f66d
[   11.927340] IPv6: ADDRCONF(NETDEV_CHANGE): vethc9d8402: link becomes ready
[   11.928433] docker0: port 3(vethc9d8402) entered blocking state
[   11.929380] docker0: port 3(vethc9d8402) entered forwarding state
[   11.947556] docker0: port 9(veth401d882) entered blocking state
[   11.948848] docker0: port 9(veth401d882) entered disabled state
[   11.953990] device veth401d882 entered promiscuous mode
[   11.962091] IPv6: ADDRCONF(NETDEV_UP): veth401d882: link is not ready
[   11.963331] docker0: port 9(veth401d882) entered blocking state
[   11.964314] docker0: port 9(veth401d882) entered forwarding state
[   12.033981] docker0: port 9(veth401d882) entered disabled state
[   12.035586] docker0: port 1(vetha03b986) entered disabled state
[   12.046116] veth53f019a: renamed from eth0
[   12.103199] docker0: port 1(vetha03b986) entered disabled state
[   12.110498] device vetha03b986 left promiscuous mode
[   12.111445] docker0: port 1(vetha03b986) entered disabled state
[   12.164865] eth0: renamed from veth127d96e
[   12.167515] IPv6: ADDRCONF(NETDEV_CHANGE): vethee3ed3a: link becomes ready
[   12.168702] docker0: port 4(vethee3ed3a) entered blocking state
[   12.169847] docker0: port 4(vethee3ed3a) entered forwarding state
[   12.342767] eth0: renamed from veth5bc6d97
[   12.349999] IPv6: ADDRCONF(NETDEV_CHANGE): vethdfb22be: link becomes ready
[   12.351619] docker0: port 5(vethdfb22be) entered blocking state
[   12.352990] docker0: port 5(vethdfb22be) entered forwarding state
[   12.488476] docker0: port 1(veth5bd9924) entered blocking state
[   12.490118] docker0: port 1(veth5bd9924) entered disabled state
[   12.496796] device veth5bd9924 entered promiscuous mode
[   12.507137] IPv6: ADDRCONF(NETDEV_UP): veth5bd9924: link is not ready
[   12.508425] docker0: port 1(veth5bd9924) entered blocking state
[   12.509460] docker0: port 1(veth5bd9924) entered forwarding state
[   12.528881] docker0: port 1(veth5bd9924) entered disabled state
[   12.530134] docker0: port 2(vethfdee011) entered disabled state
[   12.536481] veth0fe7997: renamed from eth0
[   12.608534] eth0: renamed from vethe36691e
[   12.611545] IPv6: ADDRCONF(NETDEV_CHANGE): veth19132fc: link becomes ready
[   12.612816] docker0: port 6(veth19132fc) entered blocking state
[   12.614044] docker0: port 6(veth19132fc) entered forwarding state
[   12.626195] docker0: port 2(vethfdee011) entered disabled state
[   12.637946] device vethfdee011 left promiscuous mode
[   12.638914] docker0: port 2(vethfdee011) entered disabled state
[   12.782896] docker0: port 3(vethc9d8402) entered disabled state
[   12.810278] vethaa6f66d: renamed from eth0
[   12.842183] eth0: renamed from veth23d5829
[   12.846372] IPv6: ADDRCONF(NETDEV_CHANGE): veth8439f51: link becomes ready
[   12.847716] docker0: port 7(veth8439f51) entered blocking state
[   12.849223] docker0: port 7(veth8439f51) entered forwarding state
[   12.875817] docker0: port 3(vethc9d8402) entered disabled state
[   12.885072] device vethc9d8402 left promiscuous mode
[   12.886049] docker0: port 3(vethc9d8402) entered disabled state
[   13.003234] docker0: port 4(vethee3ed3a) entered disabled state
[   13.006316] veth127d96e: renamed from eth0
[   13.073841] docker0: port 4(vethee3ed3a) entered disabled state
[   13.082248] device vethee3ed3a left promiscuous mode
[   13.083177] docker0: port 4(vethee3ed3a) entered disabled state
[   13.098398] docker0: port 2(veth1823c63) entered blocking state
[   13.098399] docker0: port 2(veth1823c63) entered disabled state
[   13.098423] device veth1823c63 entered promiscuous mode
[   13.101888] IPv6: ADDRCONF(NETDEV_UP): veth1823c63: link is not ready
[   13.101891] docker0: port 2(veth1823c63) entered blocking state
[   13.101892] docker0: port 2(veth1823c63) entered forwarding state
[   13.302018] eth0: renamed from veth57b6693
[   13.302800] docker0: port 2(veth1823c63) entered disabled state
[   13.304191] IPv6: ADDRCONF(NETDEV_CHANGE): veth401d882: link becomes ready
[   13.305429] docker0: port 9(veth401d882) entered blocking state
[   13.306482] docker0: port 9(veth401d882) entered forwarding state
[   13.311936] docker0: port 5(vethdfb22be) entered disabled state
[   13.316131] veth5bc6d97: renamed from eth0
[   13.332876] eth0: renamed from veth874e2c7
[   13.343424] IPv6: ADDRCONF(NETDEV_CHANGE): vethab34a52: link becomes ready
[   13.345107] docker0: port 8(vethab34a52) entered blocking state
[   13.346480] docker0: port 8(vethab34a52) entered forwarding state
[   13.421228] docker0: port 6(veth19132fc) entered disabled state
[   13.427762] vethe36691e: renamed from eth0
[   13.480452] docker0: port 5(vethdfb22be) entered disabled state
[   13.498357] device vethdfb22be left promiscuous mode
[   13.499002] docker0: port 5(vethdfb22be) entered disabled state
[   13.529456] docker0: port 6(veth19132fc) entered disabled state
[   13.536631] device veth19132fc left promiscuous mode
[   13.537388] docker0: port 6(veth19132fc) entered disabled state
[   13.555279] docker0: port 7(veth8439f51) entered disabled state
[   13.558536] veth23d5829: renamed from eth0
[   13.612133] docker0: port 7(veth8439f51) entered disabled state
[   13.623656] device veth8439f51 left promiscuous mode
[   13.624298] docker0: port 7(veth8439f51) entered disabled state
[   13.681703] eth0: renamed from vethea3bdda
[   13.685187] IPv6: ADDRCONF(NETDEV_CHANGE): veth5bd9924: link becomes ready
[   13.686879] docker0: port 1(veth5bd9924) entered blocking state
[   13.688087] docker0: port 1(veth5bd9924) entered forwarding state
[   13.729426] eth0: renamed from vetha22a551
[   13.730452] IPv6: ADDRCONF(NETDEV_CHANGE): veth1823c63: link becomes ready
[   13.731499] docker0: port 2(veth1823c63) entered blocking state
[   13.732451] docker0: port 2(veth1823c63) entered forwarding state
[   13.880092] docker0: port 9(veth401d882) entered disabled state
[   13.884959] veth57b6693: renamed from eth0
[   13.905418] docker0: port 9(veth401d882) entered disabled state
[   13.912143] device veth401d882 left promiscuous mode
[   13.913528] docker0: port 9(veth401d882) entered disabled state
[   14.159168] docker0: port 1(veth5bd9924) entered disabled state
[   14.163740] vethea3bdda: renamed from eth0
[   14.175372] docker0: port 1(veth5bd9924) entered disabled state
[   14.180855] device veth5bd9924 left promiscuous mode
[   14.182140] docker0: port 1(veth5bd9924) entered disabled state
[   14.272556] docker0: port 2(veth1823c63) entered disabled state
[   14.279225] vetha22a551: renamed from eth0
[   14.322528] docker0: port 2(veth1823c63) entered disabled state
[   14.329657] device veth1823c63 left promiscuous mode
[   14.330687] docker0: port 2(veth1823c63) entered disabled state
[   16.160723] docker0: port 8(vethab34a52) entered disabled state
[   16.164902] veth874e2c7: renamed from eth0
[   16.194810] docker0: port 8(vethab34a52) entered disabled state
[   16.233540] device vethab34a52 left promiscuous mode
[   16.233544] docker0: port 8(vethab34a52) entered disabled state
@euank

This comment has been minimized.

Show comment
Hide comment
@euank

euank Aug 29, 2017

Contributor

That printk message, and resulting mount error, are introduced in https://patchwork.kernel.org/patch/9760547/

That's new to the 4.13 kernel.

It seems very likely to me docker internally has a race or logic bug resulting in it mounting twice in some cases, and until now it silently succeeded.

Contributor

euank commented Aug 29, 2017

That printk message, and resulting mount error, are introduced in https://patchwork.kernel.org/patch/9760547/

That's new to the 4.13 kernel.

It seems very likely to me docker internally has a race or logic bug resulting in it mounting twice in some cases, and until now it silently succeeded.

euank added a commit to euank/coreos-overlay that referenced this issue Sep 22, 2017

app-emulation/docker: apply ebusy overlayfs patch
See coreos/bugs#2127 and
moby/moby#34672 for discussion.

Patch files have been split into more folders, plus some manual eapply
calls, to allow moby/moby patches to be used unmodified against
docker-ce

euank added a commit to euank/coreos-overlay that referenced this issue Sep 22, 2017

app-emulation/docker: apply ebusy overlayfs patch
See coreos/bugs#2127 and
moby/moby#34672 for discussion.

Patch files have been split into more folders, plus some manual eapply
calls, to allow moby/moby patches to be used unmodified against
docker-ce

euank added a commit to euank/coreos-overlay that referenced this issue Sep 22, 2017

app-emulation/docker: apply ebusy overlayfs patch
See coreos/bugs#2127 and
moby/moby#34672 for discussion.

Patch files have been split into more folders, plus some manual eapply
calls, to allow moby/moby patches to be used unmodified against
docker-ce
@amir73il

This comment has been minimized.

Show comment
Hide comment
@amir73il

amir73il Sep 29, 2017

@euank there has been a failure in the process handling this regression.
As @rhvgoyal correctly pointed on #34948 (moby/moby#34948 (comment)), kernel changes that break userspace are not tolerable.
In this case, you spotted the regression very early at rc6 time. Had the regression been reported
to the kernel mailing list or to the author of the patch (myself) it could have been fixed in time for v4.13 release and probably could save you guys a lot of fuss.
Anyway, I will be posting a patch to fix the regression in the kernel and I expect the patch to land in some future stable v4.13.y update.
I am writing this for you to know for next time and quite frankly, must say I am surprised that CoreOs guys didn't follow up on your report and handle this regression as expected.
Sorry for the pain..

amir73il commented Sep 29, 2017

@euank there has been a failure in the process handling this regression.
As @rhvgoyal correctly pointed on #34948 (moby/moby#34948 (comment)), kernel changes that break userspace are not tolerable.
In this case, you spotted the regression very early at rc6 time. Had the regression been reported
to the kernel mailing list or to the author of the patch (myself) it could have been fixed in time for v4.13 release and probably could save you guys a lot of fuss.
Anyway, I will be posting a patch to fix the regression in the kernel and I expect the patch to land in some future stable v4.13.y update.
I am writing this for you to know for next time and quite frankly, must say I am surprised that CoreOs guys didn't follow up on your report and handle this regression as expected.
Sorry for the pain..

@euank

This comment has been minimized.

Show comment
Hide comment
@euank

euank Sep 29, 2017

Contributor

@amir73il
Apologies for not reporting it upstream early on.

As I read the patch, it seemed like it was intentionally preventing this behavior for the sake of correctness, which isn't so much breaking userspace as "userspace was already broken, let's make it more obvious".
Since the patch made it clear that erroring on re-using an inuse upper/workdir was intentional, I wasn't certain that emailing to tell you that your patch did that was really new information.

Regardless, I'm glad you've found your way to this info even though we neglected to email you. Thanks for the help!

Contributor

euank commented Sep 29, 2017

@amir73il
Apologies for not reporting it upstream early on.

As I read the patch, it seemed like it was intentionally preventing this behavior for the sake of correctness, which isn't so much breaking userspace as "userspace was already broken, let's make it more obvious".
Since the patch made it clear that erroring on re-using an inuse upper/workdir was intentional, I wasn't certain that emailing to tell you that your patch did that was really new information.

Regardless, I'm glad you've found your way to this info even though we neglected to email you. Thanks for the help!

@amir73il

This comment has been minimized.

Show comment
Hide comment
@amir73il

amir73il Sep 30, 2017

@euank you should read https://felipec.wordpress.com/2013/10/07/the-linux-way/
The fact that my patch was "correct" and changed behavior intentionally is no excuse to breaking docker, a program used by a few users out there..
The rational behind doing the behavior change unconditionally was that we were wrong to think that "who is the world would have this bizar setup of using same workdir/upperdir intentionally?".
We simply missed the obvious unintentional mount leak scenario.
The way I proposed to fix the regression is to make the "correct" behavior depend on an opt-in feature
https://marc.info/?l=linux-unionfs&m=150667387131734&w=2

Your work to fix docker is important none the less.
Containers should not leak mounts (even for administrative chroot operations).
First of all, leaking mounts is bad for other reasons, for example -
when leaking blockdev mounts like devicemapper, umount in one namespace does not clean umount the filesystem.

Secondly, at some point, it is hoped, docker will want to catch up with new overlayfs features
and allow users to enable the redirect_dir and index features, both solve real user issues
(directory rename and breaking hardlinks). When that happens, overlayfs may not be as forgiving
about leaked mounts, unless we end up implementing Vivek's suggestion to mount the same super block.

Thanks for testing early versions and reporting the issue!

amir73il commented Sep 30, 2017

@euank you should read https://felipec.wordpress.com/2013/10/07/the-linux-way/
The fact that my patch was "correct" and changed behavior intentionally is no excuse to breaking docker, a program used by a few users out there..
The rational behind doing the behavior change unconditionally was that we were wrong to think that "who is the world would have this bizar setup of using same workdir/upperdir intentionally?".
We simply missed the obvious unintentional mount leak scenario.
The way I proposed to fix the regression is to make the "correct" behavior depend on an opt-in feature
https://marc.info/?l=linux-unionfs&m=150667387131734&w=2

Your work to fix docker is important none the less.
Containers should not leak mounts (even for administrative chroot operations).
First of all, leaking mounts is bad for other reasons, for example -
when leaking blockdev mounts like devicemapper, umount in one namespace does not clean umount the filesystem.

Secondly, at some point, it is hoped, docker will want to catch up with new overlayfs features
and allow users to enable the redirect_dir and index features, both solve real user issues
(directory rename and breaking hardlinks). When that happens, overlayfs may not be as forgiving
about leaked mounts, unless we end up implementing Vivek's suggestion to mount the same super block.

Thanks for testing early versions and reporting the issue!

dm0- added a commit to dm0-/coreos-overlay that referenced this issue Oct 2, 2017

app-emulation/docker: apply ebusy overlayfs patch
See coreos/bugs#2127 and
moby/moby#34672 for discussion.

Patch files have been split into more folders, plus some manual eapply
calls, to allow moby/moby patches to be used unmodified against
docker-ce
@euank

This comment has been minimized.

Show comment
Hide comment
@euank

euank Oct 12, 2017

Contributor

The overlay partial-revert which avoids this issue is queued up for stable and has been cherry-picked into the most recent set of Container Linux releases. This should be fixed.

Contributor

euank commented Oct 12, 2017

The overlay partial-revert which avoids this issue is queued up for stable and has been cherry-picked into the most recent set of Container Linux releases. This should be fixed.

@euank euank closed this Oct 12, 2017

reisingerf added a commit to umccr/st2-docker-umccr that referenced this issue Nov 28, 2017

Add ssh key and container startup order
Add a config mount point to overwrite the default ssh keys for the
user stanley. Add depends_on section to control container startup
order and try to avoid issues (coreos/bugs#2127).

ChrisMcKenzie added a commit to ChrisMcKenzie/coreos-overlay that referenced this issue Dec 9, 2017

app-emulation/docker: apply ebusy overlayfs patch
See coreos/bugs#2127 and
moby/moby#34672 for discussion.

Patch files have been split into more folders, plus some manual eapply
calls, to allow moby/moby patches to be used unmodified against
docker-ce

brainstorm added a commit to umccr/st2-docker-umccr that referenced this issue Jan 17, 2018

Add ssh key and container startup order
Add a config mount point to overwrite the default ssh keys for the
user stanley. Add depends_on section to control container startup
order and try to avoid issues (coreos/bugs#2127).
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment