search

canonical / lxd

Powerful system container and virtual machine manager
https://canonical.com/lxd
GNU Affero General Public License v3.0
4.38k stars 931 forks source link

LXD publish hangs frequently at initializing a ZFS driver #4821

Closed thomasf1234 closed 6 years ago

thomasf1234 commented 6 years ago

Required information

Issue description

I've been using LXD a lot recently with packer, but I am facing frequent hanging when publishing the stopped container. The packer build runs apt-get update, apt-get upgrade, and installs puppet. It then stops the container and issues a lxc publish command, but lxc operation list shows a running operation on image POST. If I run this 5 times, then I would expect to see at least 1 case where this problem occurs, and I have to reboot the VM, which is not practical when running through CI. Note, the last few entries of dmesg display as red messages when printed in the console (full output showed below) but I do not have the context to determine if related.

Can someone please help me understand how to overcome this?

process

vagrant  14608  0.0  0.5 470808 12240 pts/0    Sl+  03:01   0:00 lxc publish packer-mycont --alias mycont description=Trivial repackage with Packer

lxc monitor

The last entry Initializing a ZFS driver hangs indefinitely.

metadata:
  context:
    ip: '@'
    method: POST
    url: /1.0/images
  level: dbug
  message: handling
timestamp: "2018-07-23T03:01:47.430533982+01:00"
type: logging

metadata:
  context: {}
  level: dbug
  message: 'Started task operation: 2fd16dc4-74a4-4bc4-9d87-e15874b24fe5'
timestamp: "2018-07-23T03:01:47.452237771+01:00"
type: logging

metadata:
  class: task
  created_at: "2018-07-23T03:01:47.432262424+01:00"
  description: Downloading image
  err: ""
  id: 2fd16dc4-74a4-4bc4-9d87-e15874b24fe5
  may_cancel: false
  metadata: null
  resources: null
  status: Pending
  status_code: 105
  updated_at: "2018-07-23T03:01:47.432262424+01:00"
timestamp: "2018-07-23T03:01:47.452202459+01:00"
type: operation

metadata:
  context: {}
  level: dbug
  message: 'New task operation: 2fd16dc4-74a4-4bc4-9d87-e15874b24fe5'
timestamp: "2018-07-23T03:01:47.452155868+01:00"
type: logging

metadata:
  class: task
  created_at: "2018-07-23T03:01:47.432262424+01:00"
  description: Downloading image
  err: ""
  id: 2fd16dc4-74a4-4bc4-9d87-e15874b24fe5
  may_cancel: false
  metadata: null
  resources: null
  status: Running
  status_code: 103
  updated_at: "2018-07-23T03:01:47.432262424+01:00"
timestamp: "2018-07-23T03:01:47.452251716+01:00"
type: operation

metadata:
  context:
    ip: '@'
    method: GET
    url: /1.0/operations/2fd16dc4-74a4-4bc4-9d87-e15874b24fe5
  level: dbug
  message: handling
timestamp: "2018-07-23T03:01:47.460303796+01:00"
type: logging

metadata:
  context:
    created: 2018-07-23 02:58:49 +0100 BST
    ephemeral: "false"
    name: packer-mycont
    used: 2018-07-23 02:58:53 +0100 BST
  level: info
  message: Exporting container
timestamp: "2018-07-23T03:01:47.469379946+01:00"
type: logging

metadata:
  context: {}
  level: dbug
  message: Mounting ZFS storage volume for container "packer-mycont"
    on storage pool "default"
timestamp: "2018-07-23T03:01:47.472920073+01:00"
type: logging

metadata:
  context: {}
  level: dbug
  message: Initializing a ZFS driver.
timestamp: "2018-07-23T03:01:47.472884643+01:00"
type: logging

lxc config show mycont

architecture: x86_64
config:
  image.architecture: amd64
  image.description: ubuntu 16.04 LTS amd64 (release) (20180703)
  image.label: release
  image.os: ubuntu
  image.release: xenial
  image.serial: "20180703"
  image.version: "16.04"
  volatile.base_image: f2228450779fee27020d6024af587379b8f51062c32a335327f2b028c924bfa1
  volatile.eth0.hwaddr: 00:16:3e:c0:d7:72
  volatile.idmap.base: "0"
  volatile.idmap.next: '[{"Isuid":true,"Isgid":false,"Hostid":296608,"Nsid":0,"Maprange":65536},{"Isuid":false,"Isgid":true,"Hostid":296608,"Nsid":0,"Maprange":65536}]'
  volatile.last_state.idmap: '[{"Isuid":true,"Isgid":false,"Hostid":296608,"Nsid":0,"Maprange":65536},{"Isuid":false,"Isgid":true,"Hostid":296608,"Nsid":0,"Maprange":65536}]'
  volatile.last_state.power: STOPPED
devices: {}
ephemeral: false
profiles:
- default
stateful: false
description: ""

dmesg

[    0.000000] Linux version 4.15.0-24-generic (buildd@lgw01-amd64-056) (gcc version 7.3.0 (Ubuntu 7.3.0-16ubuntu3)) #26-Ubuntu SMP Wed Jun 13 08:44:47 UTC 2018 (Ubuntu 4.15.0-24.26-generic 4.15.18)
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.15.0-24-generic root=/dev/mapper/template--ub18--vg-root ro nousb net.ifnames=0 biosdevname=0 quiet
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    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: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' 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-0x000000007ffeffff] usable
[    0.000000] BIOS-e820: [mem 0x000000007fff0000-0x000000007fffffff] ACPI data
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.5 present.
[    0.000000] DMI: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006
[    0.000000] Hypervisor detected: KVM
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x7fff0 max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR variable ranges disabled:
[    0.000000] MTRR: Disabled
[    0.000000] x86/PAT: MTRRs disabled, skipping PAT initialization too.
[    0.000000] CPU MTRRs all blank - virtualized system.
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC
[    0.000000] found SMP MP-table at [mem 0x0009fff0-0x0009ffff] mapped at [        (ptrval)]
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [        (ptrval)] 99000 size 24576
[    0.000000] BRK [0x6053f000, 0x6053ffff] PGTABLE
[    0.000000] BRK [0x60540000, 0x60540fff] PGTABLE
[    0.000000] BRK [0x60541000, 0x60541fff] PGTABLE
[    0.000000] BRK [0x60542000, 0x60542fff] PGTABLE
[    0.000000] BRK [0x60543000, 0x60543fff] PGTABLE
[    0.000000] BRK [0x60544000, 0x60544fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x31c97000-0x34e42fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000E0000 000024 (v02 VBOX  )
[    0.000000] ACPI: XSDT 0x000000007FFF0030 00003C (v01 VBOX   VBOXXSDT 00000001 ASL  00000061)
[    0.000000] ACPI: FACP 0x000000007FFF00F0 0000F4 (v04 VBOX   VBOXFACP 00000001 ASL  00000061)
[    0.000000] ACPI: DSDT 0x000000007FFF0470 0021FF (v02 VBOX   VBOXBIOS 00000002 INTL 20100528)
[    0.000000] ACPI: FACS 0x000000007FFF0200 000040
[    0.000000] ACPI: FACS 0x000000007FFF0200 000040
[    0.000000] ACPI: APIC 0x000000007FFF0240 00005C (v02 VBOX   VBOXAPIC 00000001 ASL  00000061)
[    0.000000] ACPI: SSDT 0x000000007FFF02A0 0001CC (v01 VBOX   VBOXCPUT 00000002 INTL 20100528)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000007ffeffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x7ffc5000-0x7ffeffff]
[    0.000000] kvm-clock: cpu 0, msr 0:7ff44001, primary cpu clock
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: using sched offset of 3697981830 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-0x000000007ffeffff]
[    0.000000]   Normal   empty
[    0.000000]   Device   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000007ffeffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffeffff]
[    0.000000] On node 0 totalpages: 524174
[    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: 8128 pages used for memmap
[    0.000000]   DMA32 zone: 520176 pages, LIFO batch:31
[    0.000000] Reserved but unavailable: 98 pages
[    0.000000] ACPI: PM-Timer IO Port: 0x4008
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] IOAPIC[0]: apic_id 2, version 32, 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 9 global_irq 9 low level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.000000] e820: [mem 0x80000000-0xfebfffff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.000000] random: get_random_bytes called from start_kernel+0x99/0x4fd with crng_init=0
[    0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 46 pages/cpu @        (ptrval) s151552 r8192 d28672 u1048576
[    0.000000] pcpu-alloc: s151552 r8192 d28672 u1048576 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1
[    0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes)
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 515961
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.15.0-24-generic root=/dev/mapper/template--ub18--vg-root ro nousb net.ifnames=0 biosdevname=0 quiet
[    0.000000] Calgary: detecting Calgary via BIOS EBDA area
[    0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
[    0.000000] Memory: 1983912K/2096696K available (12300K kernel code, 2470K rwdata, 4240K rodata, 2408K init, 2416K bss, 112784K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Kernel/User page tables isolation: enabled
[    0.000000] ftrace: allocating 39092 entries in 153 pages
[    0.004000] Hierarchical RCU implementation.
[    0.004000]  RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[    0.004000]  Tasks RCU enabled.
[    0.004000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.004000] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[    0.004000] Console: colour VGA+ 80x25
[    0.004000] console [tty0] enabled
[    0.004000] ACPI: Core revision 20170831
[    0.004000] ACPI: 2 ACPI AML tables successfully acquired and loaded
[    0.004000] APIC: Switch to symmetric I/O mode setup
[    0.004000] x2apic enabled
[    0.004000] Switched APIC routing to physical x2apic.
[    0.004000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.004000] tsc: Detected 2599.998 MHz processor
[    0.004000] Calibrating delay loop (skipped) preset value.. 5199.99 BogoMIPS (lpj=10399992)
[    0.004000] pid_max: default: 32768 minimum: 301
[    0.004000] Security Framework initialized
[    0.004000] Yama: becoming mindful.
[    0.004000] AppArmor: AppArmor initialized
[    0.004000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.004000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.004000] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    0.004000] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    0.004048] CPU: Physical Processor ID: 0
[    0.004049] CPU: Processor Core ID: 0
[    0.004069] mce: CPU supports 0 MCE banks
[    0.004083] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
[    0.004084] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
[    0.004085] Spectre V2 : Mitigation: Full generic retpoline
[    0.004086] Speculative Store Bypass: Vulnerable
[    0.004185] Freeing SMP alternatives memory: 36K
[    0.008000] smpboot: CPU0: Intel(R) Core(TM) i5-4288U CPU @ 2.60GHz (family: 0x6, model: 0x45, stepping: 0x1)
[    0.008000] Performance Events: unsupported p6 CPU model 69 no PMU driver, software events only.
[    0.008000] Hierarchical SRCU implementation.
[    0.008000] NMI watchdog: Perf event create on CPU 0 failed with -2
[    0.008000] NMI watchdog: Perf NMI watchdog permanently disabled
[    0.008000] smp: Bringing up secondary CPUs ...
[    0.008000] x86: Booting SMP configuration:
[    0.008000] .... node  #0, CPUs:      #1
[    0.004000] kvm-clock: cpu 1, msr 0:7ff44041, secondary cpu clock
[    0.004000] mce: CPU supports 0 MCE banks
[    0.008000] smp: Brought up 1 node, 2 CPUs
[    0.008000] smpboot: Max logical packages: 1
[    0.008000] smpboot: Total of 2 processors activated (10399.99 BogoMIPS)
[    0.008010] devtmpfs: initialized
[    0.008056] x86/mm: Memory block size: 128MB
[    0.008270] evm: security.selinux
[    0.008271] evm: security.SMACK64
[    0.008271] evm: security.SMACK64EXEC
[    0.008272] evm: security.SMACK64TRANSMUTE
[    0.008272] evm: security.SMACK64MMAP
[    0.008273] evm: security.apparmor
[    0.008273] evm: security.ima
[    0.008274] evm: security.capability
[    0.008307] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.008307] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.008307] pinctrl core: initialized pinctrl subsystem
[    0.008307] RTC time: 22:38:15, date: 07/22/18
[    0.008328] NET: Registered protocol family 16
[    0.008397] audit: initializing netlink subsys (disabled)
[    0.008416] audit: type=2000 audit(1532299101.367:1): state=initialized audit_enabled=0 res=1
[    0.008416] cpuidle: using governor ladder
[    0.008416] cpuidle: using governor menu
[    0.008416] ACPI: bus type PCI registered
[    0.008416] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.008416] PCI: Using configuration type 1 for base access
[    0.012056] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.012091] ACPI: Added _OSI(Module Device)
[    0.012091] ACPI: Added _OSI(Processor Device)
[    0.012092] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.012093] ACPI: Added _OSI(Processor Aggregator Device)
[    0.012094] ACPI: Added _OSI(Linux-Dell-Video)
[    0.012485] ACPI: Executed 1 blocks of module-level executable AML code
[    0.016105] ACPI: Interpreter enabled
[    0.016114] ACPI: (supports S0 S5)
[    0.016115] ACPI: Using IOAPIC for interrupt routing
[    0.016327] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.016519] ACPI: Enabled 2 GPEs in block 00 to 07
[    0.022768] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.022773] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.022792] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
[    0.022802] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.023207] PCI host bridge to bus 0000:00
[    0.023209] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.023211] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.023212] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.023213] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfdffffff window]
[    0.023215] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.023270] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.024770] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.025730] pci 0000:00:01.1: [8086:7111] type 00 class 0x01018a
[    0.026494] pci 0000:00:01.1: reg 0x20: [io  0xd000-0xd00f]
[    0.026763] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.026764] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.026765] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.026766] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.027142] pci 0000:00:02.0: [80ee:beef] type 00 class 0x030000
[    0.028000] pci 0000:00:02.0: reg 0x10: [mem 0xe0000000-0xe07fffff pref]
[    0.036104] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    0.037465] pci 0000:00:03.0: reg 0x10: [mem 0xf0000000-0xf001ffff]
[    0.040218] pci 0000:00:03.0: reg 0x18: [io  0xd010-0xd017]
[    0.045434] pci 0000:00:04.0: [80ee:cafe] type 00 class 0x088000
[    0.046706] pci 0000:00:04.0: reg 0x10: [io  0xd020-0xd03f]
[    0.048162] pci 0000:00:04.0: reg 0x14: [mem 0xf0400000-0xf07fffff]
[    0.049316] pci 0000:00:04.0: reg 0x18: [mem 0xf0800000-0xf0803fff pref]
[    0.054892] pci 0000:00:05.0: [8086:2415] type 00 class 0x040100
[    0.055158] pci 0000:00:05.0: reg 0x10: [io  0xd100-0xd1ff]
[    0.055302] pci 0000:00:05.0: reg 0x14: [io  0xd200-0xd23f]
[    0.056963] pci 0000:00:07.0: [8086:7113] type 00 class 0x068000
[    0.057800] pci 0000:00:07.0: quirk: [io  0x4000-0x403f] claimed by PIIX4 ACPI
[    0.057816] pci 0000:00:07.0: quirk: [io  0x4100-0x410f] claimed by PIIX4 SMB
[    0.058941] pci 0000:00:08.0: [8086:100e] type 00 class 0x020000
[    0.060158] pci 0000:00:08.0: reg 0x10: [mem 0xf0820000-0xf083ffff]
[    0.062509] pci 0000:00:08.0: reg 0x18: [io  0xd240-0xd247]
[    0.069564] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 9 10 *11)
[    0.069875] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 9 *10 11)
[    0.070001] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 *9 10 11)
[    0.070125] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 9 10 *11)
[    0.070526] SCSI subsystem initialized
[    0.070578] libata version 3.00 loaded.
[    0.070578] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    0.070578] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.070578] pci 0000:00:02.0: vgaarb: bridge control possible
[    0.070578] vgaarb: loaded
[    0.070578] ACPI: bus type USB registered
[    0.070578] usbcore: registered new interface driver usbfs
[    0.070578] usbcore: registered new interface driver hub
[    0.070578] usbcore: registered new device driver usb
[    0.070578] EDAC MC: Ver: 3.0.0
[    0.070578] PCI: Using ACPI for IRQ routing
[    0.070578] PCI: pci_cache_line_size set to 64 bytes
[    0.070578] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.070578] e820: reserve RAM buffer [mem 0x7fff0000-0x7fffffff]
[    0.070578] NetLabel: Initializing
[    0.070578] NetLabel:  domain hash size = 128
[    0.070578] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.070578] NetLabel:  unlabeled traffic allowed by default
[    0.072138] clocksource: Switched to clocksource kvm-clock
[    0.085647] VFS: Disk quotas dquot_6.6.0
[    0.085661] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.085749] AppArmor: AppArmor Filesystem Enabled
[    0.085776] pnp: PnP ACPI init
[    0.085849] pnp 00:00: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.085930] pnp 00:01: Plug and Play ACPI device, IDs PNP0f03 (active)
[    0.087079] pnp: PnP ACPI: found 2 devices
[    0.098654] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.098664] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.098666] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.098667] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.098668] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfdffffff window]
[    0.098721] NET: Registered protocol family 2
[    0.098949] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[    0.098976] TCP bind hash table entries: 16384 (order: 6, 262144 bytes)
[    0.100488] TCP: Hash tables configured (established 16384 bind 16384)
[    0.100518] UDP hash table entries: 1024 (order: 3, 32768 bytes)
[    0.100524] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes)
[    0.100557] NET: Registered protocol family 1
[    0.100569] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.100608] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.100676] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.100754] PCI: CLS 0 bytes, default 64
[    0.100788] Unpacking initramfs...
[    0.856840] Freeing initrd memory: 50864K
[    0.857035] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.857118] Scanning for low memory corruption every 60 seconds
[    0.858422] Initialise system trusted keyrings
[    0.858432] Key type blacklist registered
[    0.858525] workingset: timestamp_bits=36 max_order=19 bucket_order=0
[    0.861094] zbud: loaded
[    0.861922] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.862357] fuse init (API version 7.26)
[    0.873484] Key type asymmetric registered
[    0.873485] Asymmetric key parser 'x509' registered
[    0.873548] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    0.873732] io scheduler noop registered
[    0.873734] io scheduler deadline registered
[    0.873800] io scheduler cfq registered (default)
[    0.874272] intel_idle: Please enable MWAIT in BIOS SETUP
[    0.874839] ACPI: AC Adapter [AC] (off-line)
[    0.875003] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    0.875125] ACPI: Power Button [PWRF]
[    0.875277] input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input1
[    0.875283] ACPI: Sleep Button [SLPF]
[    0.878592] ACPI: Battery Slot [BAT0] (battery present)
[    0.878684] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    0.887489] Linux agpgart interface v0.103
[    0.891695] loop: module loaded
[    0.892018] ata_piix 0000:00:01.1: version 2.13
[    0.893225] scsi host0: ata_piix
[    0.893566] scsi host1: ata_piix
[    0.893644] ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xd000 irq 14
[    0.893646] ata2: PATA max UDMA/33 cmd 0x170 ctl 0x376 bmdma 0xd008 irq 15
[    0.893931] libphy: Fixed MDIO Bus: probed
[    0.893933] tun: Universal TUN/TAP device driver, 1.6
[    0.894298] PPP generic driver version 2.4.2
[    0.894754] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.894759] ehci-pci: EHCI PCI platform driver
[    0.894783] ehci-platform: EHCI generic platform driver
[    0.894793] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.894796] ohci-pci: OHCI PCI platform driver
[    0.894804] ohci-platform: OHCI generic platform driver
[    0.894811] uhci_hcd: USB Universal Host Controller Interface driver
[    0.894975] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
[    0.896006] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.896014] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.896336] mousedev: PS/2 mouse device common for all mice
[    0.897259] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
[    0.897284] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
[    0.897485] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram
[    0.897494] i2c /dev entries driver
[    0.897548] device-mapper: uevent: version 1.0.3
[    0.897668] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel@redhat.com
[    0.897740] ledtrig-cpu: registered to indicate activity on CPUs
[    0.898393] NET: Registered protocol family 10
[    0.902028] Segment Routing with IPv6
[    0.902052] NET: Registered protocol family 17
[    0.902137] Key type dns_resolver registered
[    0.902399] RAS: Correctable Errors collector initialized.
[    0.902417] sched_clock: Marking stable (902357553, 0)->(1064993355, -162635802)
[    0.903126] registered taskstats version 1
[    0.903133] Loading compiled-in X.509 certificates
[    0.905511] Loaded X.509 cert 'Build time autogenerated kernel key: f8416e9c94111a665d2364ad111156633cf58b6a'
[    0.905537] zswap: loaded using pool lzo/zbud
[    0.911332] Key type big_key registered
[    0.911337] Key type trusted registered
[    0.913533] Key type encrypted registered
[    0.913539] AppArmor: AppArmor sha1 policy hashing enabled
[    0.913596] ima: No TPM chip found, activating TPM-bypass! (rc=-19)
[    0.913619] evm: HMAC attrs: 0x1
[    0.913963]   Magic number: 10:575:654
[    0.914080] rtc_cmos rtc_cmos: setting system clock to 2018-07-22 22:38:16 UTC (1532299096)
[    0.914234] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    0.914235] EDD information not available.
[    1.053440] ata1.00: ATA-6: VBOX HARDDISK, 1.0, max UDMA/133
[    1.053444] ata1.00: 20480000 sectors, multi 128: LBA
[    1.053449] ata1.01: ATA-6: VBOX HARDDISK, 1.0, max UDMA/133
[    1.053451] ata1.01: 41943040 sectors, multi 128: LBA
[    1.054210] ata1.00: configured for UDMA/33
[    1.054553] ata1.01: configured for UDMA/33
[    1.054823] scsi 0:0:0:0: Direct-Access     ATA      VBOX HARDDISK    1.0  PQ: 0 ANSI: 5
[    1.055060] sd 0:0:0:0: [sda] 20480000 512-byte logical blocks: (10.5 GB/9.77 GiB)
[    1.055067] sd 0:0:0:0: [sda] Write Protect is off
[    1.055069] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    1.055079] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.055167] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    1.055627]  sda: sda1
[    1.055675] scsi 0:0:1:0: Direct-Access     ATA      VBOX HARDDISK    1.0  PQ: 0 ANSI: 5
[    1.055987] sd 0:0:1:0: [sdb] 41943040 512-byte logical blocks: (21.5 GB/20.0 GiB)
[    1.055994] sd 0:0:1:0: [sdb] Write Protect is off
[    1.055995] sd 0:0:1:0: [sdb] Mode Sense: 00 3a 00 00
[    1.056005] sd 0:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.056196] sd 0:0:0:0: [sda] Attached SCSI disk
[    1.056391] sd 0:0:1:0: Attached scsi generic sg1 type 0
[    1.056475]  sdb: sdb1
[    1.056718] sd 0:0:1:0: [sdb] Attached SCSI disk
[    1.060248] Freeing unused kernel memory: 2408K
[    1.077330] Write protecting the kernel read-only data: 20480k
[    1.077999] Freeing unused kernel memory: 2008K
[    1.081800] Freeing unused kernel memory: 1904K
[    1.086932] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.086933] x86/mm: Checking user space page tables
[    1.091827] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.154649] ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
[    1.154715] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input4
[    1.161444] piix4_smbus 0000:00:07.0: SMBus Host Controller at 0x4100, revision 0
[    1.163054] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    1.163055] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    1.392219] random: fast init done
[    1.393778] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input5
[    1.571155] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 08:00:27:b3:a2:4b
[    1.571161] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[    1.884180] tsc: Refined TSC clocksource calibration: 2600.255 MHz
[    1.884190] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x257b2cf160e, max_idle_ns: 440795303091 ns
[    1.988708] e1000 0000:00:08.0 eth1: (PCI:33MHz:32-bit) 08:00:27:14:2f:6f
[    1.988713] e1000 0000:00:08.0 eth1: Intel(R) PRO/1000 Network Connection
[    2.020048] random: lvm: uninitialized urandom read (4 bytes read)
[    2.043837] random: lvm: uninitialized urandom read (2 bytes read)
[    2.088087] random: lvm: uninitialized urandom read (4 bytes read)
[    2.111519] random: lvm: uninitialized urandom read (2 bytes read)
[    2.188614] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
[    2.502611] ip_tables: (C) 2000-2006 Netfilter Core Team
[    2.512056] systemd[1]: systemd 237 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[    2.512089] systemd[1]: Detected virtualization oracle.
[    2.512094] systemd[1]: Detected architecture x86-64.
[    2.518551] systemd[1]: Set hostname to <dev-ub18>.
[    2.976323] random: systemd: uninitialized urandom read (16 bytes read)
[    2.976392] systemd[1]: Reached target User and Group Name Lookups.
[    2.976476] random: systemd: uninitialized urandom read (16 bytes read)
[    2.978904] systemd[1]: Created slice System Slice.
[    2.978923] random: systemd: uninitialized urandom read (16 bytes read)
[    2.978980] systemd[1]: Listening on fsck to fsckd communication Socket.
[    2.978992] random: systemd: uninitialized urandom read (16 bytes read)
[    2.979226] systemd[1]: Created slice system-postfix.slice.
[    2.979238] random: systemd: uninitialized urandom read (16 bytes read)
[    2.979298] systemd[1]: Listening on Journal Socket.
[    2.979354] random: systemd: uninitialized urandom read (16 bytes read)
[    2.980052] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[    3.594573] EXT4-fs (dm-0): re-mounted. Opts: errors=remount-ro
[    3.673694] systemd-journald[273]: Received request to flush runtime journal from PID 1
[    3.705784] RPC: Registered named UNIX socket transport module.
[    3.705786] RPC: Registered udp transport module.
[    3.705786] RPC: Registered tcp transport module.
[    3.705787] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    3.980204] vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
[    3.980290] input: Unspecified device as /devices/pci0000:00/0000:00:04.0/input/input6
[    3.980422] vboxguest: misc device minor 55, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
[    3.980424] vboxguest: Successfully loaded version 5.2.8_KernelUbuntu (interface 0x00010004)
[    4.088335] vboxvideo: module is from the staging directory, the quality is unknown, you have been warned.
[    4.088406] vboxvideo: module verification failed: signature and/or required key missing - tainting kernel
[    4.091453] [drm] VRAM 00800000
[    4.091611] [TTM] Zone  kernel: Available graphics memory: 1020566 kiB
[    4.091611] [TTM] Initializing pool allocator
[    4.091615] [TTM] Initializing DMA pool allocator
[    4.094568] fbcon: vboxdrmfb (fb0) is primary device
[    4.096863] Console: switching to colour frame buffer device 100x37
[    4.100355] vboxvideo 0000:00:02.0: fb0: vboxdrmfb frame buffer device
[    4.116150] [drm] Initialized vboxvideo 1.0.0 20130823 for 0000:00:02.0 on minor 0
[    4.270227] Adding 1003516k swap on /dev/mapper/template--ub18--vg-swap_1.  Priority:-2 extents:1 across:1003516k FS
[    4.288541] RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 10737418240 ms ovfl timer
[    4.288543] RAPL PMU: hw unit of domain pp0-core 2^-0 Joules
[    4.288544] RAPL PMU: hw unit of domain package 2^-0 Joules
[    4.288544] RAPL PMU: hw unit of domain dram 2^-0 Joules
[    4.288545] RAPL PMU: hw unit of domain pp1-gpu 2^-0 Joules
[    4.356793] AVX2 version of gcm_enc/dec engaged.
[    4.356794] AES CTR mode by8 optimization enabled
[    4.881980] random: crng init done
[    5.456564] snd_intel8x0 0000:00:05.0: intel8x0_measure_ac97_clock: measured 58618 usecs (16384 samples)
[    5.456574] snd_intel8x0 0000:00:05.0: measured clock 279504 rejected
[    5.583830] EXT4-fs (dm-2): mounted filesystem with ordered data mode. Opts: (null)
[    5.868815] snd_intel8x0 0000:00:05.0: intel8x0_measure_ac97_clock: measured 57555 usecs (16384 samples)
[    5.868818] snd_intel8x0 0000:00:05.0: measured clock 284666 rejected
[    6.284976] snd_intel8x0 0000:00:05.0: intel8x0_measure_ac97_clock: measured 58337 usecs (16384 samples)
[    6.284978] snd_intel8x0 0000:00:05.0: measured clock 280850 rejected
[    6.284980] snd_intel8x0 0000:00:05.0: clocking to 48000
[    6.315772] spl: loading out-of-tree module taints kernel.
[    6.318484] SPL: Loaded module v0.7.5-1ubuntu1
[    6.321302] znvpair: module license 'CDDL' taints kernel.
[    6.321304] Disabling lock debugging due to kernel taint
[    8.295852] ZFS: Loaded module v0.7.5-1ubuntu15, ZFS pool version 5000, ZFS filesystem version 5
[    9.069008] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[    9.176092] audit: type=1400 audit(1532299104.756:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default" pid=874 comm="apparmor_parser"
[    9.176095] audit: type=1400 audit(1532299104.756:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-cgns" pid=874 comm="apparmor_parser"
[    9.176098] audit: type=1400 audit(1532299104.756:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-mounting" pid=874 comm="apparmor_parser"
[    9.176100] audit: type=1400 audit(1532299104.756:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-nesting" pid=874 comm="apparmor_parser"
[    9.178386] audit: type=1400 audit(1532299104.760:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/sbin/dhclient" pid=875 comm="apparmor_parser"
[    9.178390] audit: type=1400 audit(1532299104.760:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=875 comm="apparmor_parser"
[    9.178393] audit: type=1400 audit(1532299104.760:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=875 comm="apparmor_parser"
[    9.178395] audit: type=1400 audit(1532299104.760:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=875 comm="apparmor_parser"
[    9.190305] audit: type=1400 audit(1532299104.772:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=879 comm="apparmor_parser"
[    9.190310] audit: type=1400 audit(1532299104.772:11): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=879 comm="apparmor_parser"
[    9.262289] new mount options do not match the existing superblock, will be ignored
[    9.642051] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   10.307584] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   10.316594] e1000: eth1 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[   10.318402] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   10.318433] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[   10.324672] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[   10.325038] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   12.230441] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[  115.762955] FS-Cache: Loaded
[  115.780351] FS-Cache: Netfs 'nfs' registered for caching
[  411.327525] nfs: server 192.168.20.1 not responding, still trying
[  411.335727] nfs: server 192.168.20.1 OK
[ 7132.296774] Ebtables v2.0 registered
[ 7132.634487] kauditd_printk_skb: 8 callbacks suppressed
[ 7132.634488] audit: type=1400 audit(1532307547.688:20): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxd-refined-pangolin_</var/lib/lxd>" pid=6724 comm="apparmor_parser"
[ 7132.675828] lxdbr0: port 1(vethHH6AM1) entered blocking state
[ 7132.675831] lxdbr0: port 1(vethHH6AM1) entered disabled state
[ 7132.682229] device vethHH6AM1 entered promiscuous mode
[ 7132.684992] IPv6: ADDRCONF(NETDEV_UP): vethHH6AM1: link is not ready
[ 7132.771384] eth0: renamed from vethRX1P9D
[ 7132.783891] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 7132.784733] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 7132.784759] IPv6: ADDRCONF(NETDEV_CHANGE): vethHH6AM1: link becomes ready
[ 7132.784786] lxdbr0: port 1(vethHH6AM1) entered blocking state
[ 7132.784788] lxdbr0: port 1(vethHH6AM1) entered forwarding state
[ 7134.411611] audit: type=1400 audit(1532307549.464:21): apparmor="STATUS" operation="profile_load" label="lxd-refined-pangolin_</var/lib/lxd>//&:lxd-refined-pangolin_<var-lib-lxd>:unconfined" name="/sbin/dhclient" pid=7231 comm="apparmor_parser"
[ 7134.412523] audit: type=1400 audit(1532307549.464:22): apparmor="STATUS" operation="profile_load" label="lxd-refined-pangolin_</var/lib/lxd>//&:lxd-refined-pangolin_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=7231 comm="apparmor_parser"
[ 7134.412990] audit: type=1400 audit(1532307549.464:23): apparmor="STATUS" operation="profile_load" label="lxd-refined-pangolin_</var/lib/lxd>//&:lxd-refined-pangolin_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=7231 comm="apparmor_parser"
[ 7134.413732] audit: type=1400 audit(1532307549.464:24): apparmor="STATUS" operation="profile_load" label="lxd-refined-pangolin_</var/lib/lxd>//&:lxd-refined-pangolin_<var-lib-lxd>:unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=7231 comm="apparmor_parser"
[ 7134.425495] audit: type=1400 audit(1532307549.480:25): apparmor="STATUS" operation="profile_load" label="lxd-refined-pangolin_</var/lib/lxd>//&:lxd-refined-pangolin_<var-lib-lxd>:unconfined" name="/usr/bin/lxc-start" pid=7241 comm="apparmor_parser"
[ 7134.457383] audit: type=1400 audit(1532307549.512:26): apparmor="STATUS" operation="profile_load" label="lxd-refined-pangolin_</var/lib/lxd>//&:lxd-refined-pangolin_<var-lib-lxd>:unconfined" name="lxc-container-default" pid=7232 comm="apparmor_parser"
[ 7134.457982] audit: type=1400 audit(1532307549.512:27): apparmor="STATUS" operation="profile_load" label="lxd-refined-pangolin_</var/lib/lxd>//&:lxd-refined-pangolin_<var-lib-lxd>:unconfined" name="lxc-container-default-cgns" pid=7232 comm="apparmor_parser"
[ 7134.458408] audit: type=1400 audit(1532307549.512:28): apparmor="STATUS" operation="profile_load" label="lxd-refined-pangolin_</var/lib/lxd>//&:lxd-refined-pangolin_<var-lib-lxd>:unconfined" name="lxc-container-default-with-mounting" pid=7232 comm="apparmor_parser"
[ 7134.459856] audit: type=1400 audit(1532307549.512:29): apparmor="STATUS" operation="profile_load" label="lxd-refined-pangolin_</var/lib/lxd>//&:lxd-refined-pangolin_<var-lib-lxd>:unconfined" name="lxc-container-default-with-nesting" pid=7232 comm="apparmor_parser"
[ 7139.919935] Loading iSCSI transport class v2.0-870.
[ 7189.957313] lxdbr0: port 1(vethHH6AM1) entered disabled state
[ 7190.155552] lxdbr0: port 1(vethHH6AM1) entered disabled state
[ 7190.157959] device vethHH6AM1 left promiscuous mode
[ 7190.157964] lxdbr0: port 1(vethHH6AM1) entered disabled state
[ 7190.941448] kauditd_printk_skb: 4 callbacks suppressed
[ 7190.941449] audit: type=1400 audit(1532307605.999:34): apparmor="STATUS" operation="profile_remove" profile="unconfined" name="lxd-refined-pangolin_</var/lib/lxd>" pid=8883 comm="apparmor_parser"
[ 7482.598062] audit: type=1400 audit(1532307897.673:35): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=20623 comm="apparmor_parser"
[ 7482.631980] lxdbr0: port 1(vethRNLX7V) entered blocking state
[ 7482.631982] lxdbr0: port 1(vethRNLX7V) entered disabled state
[ 7482.632031] device vethRNLX7V entered promiscuous mode
[ 7482.632301] IPv6: ADDRCONF(NETDEV_UP): vethRNLX7V: link is not ready
[ 7482.830700] eth0: renamed from vethBQNNDW
[ 7482.843697] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 7482.844606] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 7482.844646] IPv6: ADDRCONF(NETDEV_CHANGE): vethRNLX7V: link becomes ready
[ 7482.844689] lxdbr0: port 1(vethRNLX7V) entered blocking state
[ 7482.844691] lxdbr0: port 1(vethRNLX7V) entered forwarding state
[ 7483.606772] audit: type=1400 audit(1532307898.685:36): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/sbin/dhclient" pid=20835 comm="apparmor_parser"
[ 7483.609777] audit: type=1400 audit(1532307898.685:37): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=20835 comm="apparmor_parser"
[ 7483.610711] audit: type=1400 audit(1532307898.689:38): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=20835 comm="apparmor_parser"
[ 7483.611299] audit: type=1400 audit(1532307898.689:39): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=20835 comm="apparmor_parser"
[ 7483.622140] audit: type=1400 audit(1532307898.697:40): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/bin/lxc-start" pid=20878 comm="apparmor_parser"
[ 7483.695771] audit: type=1400 audit(1532307898.773:41): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/lxd/lxd-bridge-proxy" pid=20895 comm="apparmor_parser"
[ 7483.881388] audit: type=1400 audit(1532307898.957:42): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/snapd/snap-confine" pid=20946 comm="apparmor_parser"
[ 7483.881741] audit: type=1400 audit(1532307898.957:43): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/snapd/snap-confine//mount-namespace-capture-helper" pid=20946 comm="apparmor_parser"
[ 7484.010361] audit: type=1400 audit(1532307899.085:44): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/sbin/tcpdump" pid=20994 comm="apparmor_parser"
[ 7904.223647] lxdbr0: port 1(vethRNLX7V) entered disabled state
[ 7904.226507] device vethRNLX7V left promiscuous mode
[ 7904.226512] lxdbr0: port 1(vethRNLX7V) entered disabled state
[ 7905.139969] kauditd_printk_skb: 4 callbacks suppressed
[ 7905.139976] audit: type=1400 audit(1532308320.237:49): apparmor="STATUS" operation="profile_remove" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=9673 comm="apparmor_parser"
[ 7978.925423] audit: type=1400 audit(1532308394.027:50): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=11820 comm="apparmor_parser"
[ 7978.965188] lxdbr0: port 1(veth0WREPS) entered blocking state
[ 7978.965191] lxdbr0: port 1(veth0WREPS) entered disabled state
[ 7978.965369] device veth0WREPS entered promiscuous mode
[ 7978.970450] IPv6: ADDRCONF(NETDEV_UP): veth0WREPS: link is not ready
[ 7979.059105] eth0: renamed from veth3HUUR7
[ 7979.072388] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 7979.074269] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 7979.074301] IPv6: ADDRCONF(NETDEV_CHANGE): veth0WREPS: link becomes ready
[ 7979.074341] lxdbr0: port 1(veth0WREPS) entered blocking state
[ 7979.074343] lxdbr0: port 1(veth0WREPS) entered forwarding state
[ 7980.603492] audit: type=1400 audit(1532308395.703:51): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/sbin/dhclient" pid=12243 comm="apparmor_parser"
[ 7980.606746] audit: type=1400 audit(1532308395.707:52): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=12243 comm="apparmor_parser"
[ 7980.607274] audit: type=1400 audit(1532308395.707:53): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=12243 comm="apparmor_parser"
[ 7980.607732] audit: type=1400 audit(1532308395.707:54): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=12243 comm="apparmor_parser"
[ 7980.627613] audit: type=1400 audit(1532308395.727:55): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/bin/lxc-start" pid=12254 comm="apparmor_parser"
[ 7980.728779] audit: type=1400 audit(1532308395.831:56): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/lxd/lxd-bridge-proxy" pid=12257 comm="apparmor_parser"
[ 7980.757341] audit: type=1400 audit(1532308395.859:57): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default" pid=12242 comm="apparmor_parser"
[ 7980.758277] audit: type=1400 audit(1532308395.859:58): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-cgns" pid=12242 comm="apparmor_parser"
[ 7980.759160] audit: type=1400 audit(1532308395.859:59): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-with-mounting" pid=12242 comm="apparmor_parser"
[ 8473.869498] lxdbr0: port 1(veth0WREPS) entered disabled state
[ 8474.096402] lxdbr0: port 1(veth0WREPS) entered disabled state
[ 8474.099488] device veth0WREPS left promiscuous mode
[ 8474.099493] lxdbr0: port 1(veth0WREPS) entered disabled state
[ 8474.837605] kauditd_printk_skb: 4 callbacks suppressed
[ 8474.837607] audit: type=1400 audit(1532308889.956:64): apparmor="STATUS" operation="profile_remove" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=7711 comm="apparmor_parser"
[ 8749.277155] audit: type=1400 audit(1532309164.405:65): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=22214 comm="apparmor_parser"
[ 8749.328409] lxdbr0: port 1(vethD1NGJT) entered blocking state
[ 8749.328412] lxdbr0: port 1(vethD1NGJT) entered disabled state
[ 8749.328513] device vethD1NGJT entered promiscuous mode
[ 8749.329641] IPv6: ADDRCONF(NETDEV_UP): vethD1NGJT: link is not ready
[ 8749.533432] eth0: renamed from vethT0FXLK
[ 8749.553578] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 8749.554721] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 8749.554746] IPv6: ADDRCONF(NETDEV_CHANGE): vethD1NGJT: link becomes ready
[ 8749.554774] lxdbr0: port 1(vethD1NGJT) entered blocking state
[ 8749.554775] lxdbr0: port 1(vethD1NGJT) entered forwarding state
[ 8750.840994] audit: type=1400 audit(1532309165.969:66): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/sbin/dhclient" pid=22606 comm="apparmor_parser"
[ 8750.842179] audit: type=1400 audit(1532309165.973:67): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=22606 comm="apparmor_parser"
[ 8750.842621] audit: type=1400 audit(1532309165.973:68): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=22606 comm="apparmor_parser"
[ 8750.843309] audit: type=1400 audit(1532309165.973:69): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=22606 comm="apparmor_parser"
[ 8750.856891] audit: type=1400 audit(1532309165.985:70): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/bin/lxc-start" pid=22619 comm="apparmor_parser"
[ 8750.897537] audit: type=1400 audit(1532309166.029:71): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default" pid=22605 comm="apparmor_parser"
[ 8750.898542] audit: type=1400 audit(1532309166.029:72): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-cgns" pid=22605 comm="apparmor_parser"
[ 8750.899109] audit: type=1400 audit(1532309166.029:73): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-with-mounting" pid=22605 comm="apparmor_parser"
[ 8750.899597] audit: type=1400 audit(1532309166.029:74): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-with-nesting" pid=22605 comm="apparmor_parser"
[ 9119.237682] lxdbr0: port 1(vethD1NGJT) entered disabled state
[ 9119.533912] lxdbr0: port 1(vethD1NGJT) entered disabled state
[ 9119.536945] device vethD1NGJT left promiscuous mode
[ 9119.536951] lxdbr0: port 1(vethD1NGJT) entered disabled state
[ 9120.555071] kauditd_printk_skb: 4 callbacks suppressed
[ 9120.555073] audit: type=1400 audit(1532309535.699:79): apparmor="STATUS" operation="profile_remove" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=15303 comm="apparmor_parser"
[ 9533.128150] audit: type=1400 audit(1532309948.286:80): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=4105 comm="apparmor_parser"
[ 9533.166775] lxdbr0: port 1(veth9WJYL6) entered blocking state
[ 9533.166778] lxdbr0: port 1(veth9WJYL6) entered disabled state
[ 9533.166919] device veth9WJYL6 entered promiscuous mode
[ 9533.168607] IPv6: ADDRCONF(NETDEV_UP): veth9WJYL6: link is not ready
[ 9533.387518] eth0: renamed from veth1FHYHH
[ 9533.401928] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 9533.405277] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 9533.405331] IPv6: ADDRCONF(NETDEV_CHANGE): veth9WJYL6: link becomes ready
[ 9533.405377] lxdbr0: port 1(veth9WJYL6) entered blocking state
[ 9533.405379] lxdbr0: port 1(veth9WJYL6) entered forwarding state
[ 9534.476703] audit: type=1400 audit(1532309949.634:81): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/sbin/dhclient" pid=4438 comm="apparmor_parser"
[ 9534.479414] audit: type=1400 audit(1532309949.638:82): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=4438 comm="apparmor_parser"
[ 9534.479978] audit: type=1400 audit(1532309949.638:83): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=4438 comm="apparmor_parser"
[ 9534.481571] audit: type=1400 audit(1532309949.642:84): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=4438 comm="apparmor_parser"
[ 9534.500545] audit: type=1400 audit(1532309949.658:85): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/bin/lxc-start" pid=4553 comm="apparmor_parser"
[ 9534.623545] audit: type=1400 audit(1532309949.782:86): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/lxd/lxd-bridge-proxy" pid=4562 comm="apparmor_parser"
[ 9534.987383] audit: type=1400 audit(1532309950.146:87): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default" pid=4593 comm="apparmor_parser"
[ 9534.988039] audit: type=1400 audit(1532309950.146:88): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-cgns" pid=4593 comm="apparmor_parser"
[ 9534.989074] audit: type=1400 audit(1532309950.146:89): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-with-mounting" pid=4593 comm="apparmor_parser"
[ 9986.220751] lxdbr0: port 1(veth9WJYL6) entered disabled state
[ 9986.480479] lxdbr0: port 1(veth9WJYL6) entered disabled state
[ 9986.484074] device veth9WJYL6 left promiscuous mode
[ 9986.484079] lxdbr0: port 1(veth9WJYL6) entered disabled state
[ 9987.304991] kauditd_printk_skb: 4 callbacks suppressed
[ 9987.304992] audit: type=1400 audit(1532310402.481:94): apparmor="STATUS" operation="profile_remove" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=31619 comm="apparmor_parser"
[10357.482461] audit: type=1400 audit(1532310772.669:95): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=14202 comm="apparmor_parser"
[10357.539939] lxdbr0: port 1(veth2CHTDA) entered blocking state
[10357.539941] lxdbr0: port 1(veth2CHTDA) entered disabled state
[10357.542296] device veth2CHTDA entered promiscuous mode
[10357.543841] IPv6: ADDRCONF(NETDEV_UP): veth2CHTDA: link is not ready
[10357.741446] eth0: renamed from veth9F490H
[10357.754373] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[10357.757158] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[10357.757207] IPv6: ADDRCONF(NETDEV_CHANGE): veth2CHTDA: link becomes ready
[10357.757243] lxdbr0: port 1(veth2CHTDA) entered blocking state
[10357.757244] lxdbr0: port 1(veth2CHTDA) entered forwarding state
[10359.402854] audit: type=1400 audit(1532310774.593:96): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/sbin/dhclient" pid=14656 comm="apparmor_parser"
[10359.406692] audit: type=1400 audit(1532310774.597:97): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=14656 comm="apparmor_parser"
[10359.407294] audit: type=1400 audit(1532310774.597:98): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=14656 comm="apparmor_parser"
[10359.407703] audit: type=1400 audit(1532310774.597:99): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=14656 comm="apparmor_parser"
[10359.429907] audit: type=1400 audit(1532310774.617:100): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/bin/lxc-start" pid=14672 comm="apparmor_parser"
[10359.533903] audit: type=1400 audit(1532310774.721:101): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/lxd/lxd-bridge-proxy" pid=14675 comm="apparmor_parser"
[10359.604918] audit: type=1400 audit(1532310774.793:102): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default" pid=14660 comm="apparmor_parser"
[10359.605530] audit: type=1400 audit(1532310774.793:103): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-cgns" pid=14660 comm="apparmor_parser"
[10359.606408] audit: type=1400 audit(1532310774.797:104): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-with-mounting" pid=14660 comm="apparmor_parser"
[10360.919426] lxdbr0: port 1(veth2CHTDA) entered disabled state
[10360.925348] device veth2CHTDA left promiscuous mode
[10360.925356] lxdbr0: port 1(veth2CHTDA) entered disabled state
[10382.960591] kauditd_printk_skb: 5 callbacks suppressed
[10382.960594] audit: type=1400 audit(1532310798.149:110): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=16355 comm="apparmor_parser"
[10382.996612] lxdbr0: port 1(vethQVJAN0) entered blocking state
[10382.996615] lxdbr0: port 1(vethQVJAN0) entered disabled state
[10382.996791] device vethQVJAN0 entered promiscuous mode
[10382.998061] IPv6: ADDRCONF(NETDEV_UP): vethQVJAN0: link is not ready
[10383.092124] eth0: renamed from vethKKDC9S
[10383.109636] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[10383.110863] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[10383.110905] IPv6: ADDRCONF(NETDEV_CHANGE): vethQVJAN0: link becomes ready
[10383.110938] lxdbr0: port 1(vethQVJAN0) entered blocking state
[10383.110940] lxdbr0: port 1(vethQVJAN0) entered forwarding state
[10384.084887] audit: type=1400 audit(1532310799.273:111): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/sbin/dhclient" pid=16592 comm="apparmor_parser"
[10384.086304] audit: type=1400 audit(1532310799.277:112): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=16592 comm="apparmor_parser"
[10384.086808] audit: type=1400 audit(1532310799.277:113): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=16592 comm="apparmor_parser"
[10384.089792] audit: type=1400 audit(1532310799.281:114): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=16592 comm="apparmor_parser"
[10384.102235] audit: type=1400 audit(1532310799.293:115): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/bin/lxc-start" pid=16629 comm="apparmor_parser"
[10384.212698] audit: type=1400 audit(1532310799.401:116): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/lxd/lxd-bridge-proxy" pid=16634 comm="apparmor_parser"
[10384.451698] audit: type=1400 audit(1532310799.641:117): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/snapd/snap-confine" pid=16675 comm="apparmor_parser"
[10384.452486] audit: type=1400 audit(1532310799.641:118): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/snapd/snap-confine//mount-namespace-capture-helper" pid=16675 comm="apparmor_parser"
[10384.645691] audit: type=1400 audit(1532310799.837:119): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/sbin/tcpdump" pid=16709 comm="apparmor_parser"
[10470.665928] kauditd_printk_skb: 4 callbacks suppressed
[10470.665930] audit: type=1400 audit(1532310885.856:124): apparmor="STATUS" operation="profile_replace" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/snapd/snap-confine" pid=22835 comm="apparmor_parser"
[10470.679074] audit: type=1400 audit(1532310885.872:125): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/snapd/snap-confine//mount-namespace-capture-helper" pid=22835 comm="apparmor_parser"
[10565.313669] lxdbr0: port 1(vethQVJAN0) entered disabled state
[10565.596792] lxdbr0: port 1(vethQVJAN0) entered disabled state
[10565.600516] device vethQVJAN0 left promiscuous mode
[10565.600530] lxdbr0: port 1(vethQVJAN0) entered disabled state
[10566.387031] audit: type=1400 audit(1532310981.579:126): apparmor="STATUS" operation="profile_remove" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=27475 comm="apparmor_parser"
[10718.002254] audit: type=1400 audit(1532311133.205:127): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=3779 comm="apparmor_parser"
[10718.028609] lxdbr0: port 1(vethNIX1WE) entered blocking state
[10718.028611] lxdbr0: port 1(vethNIX1WE) entered disabled state
[10718.028655] device vethNIX1WE entered promiscuous mode
[10718.029089] IPv6: ADDRCONF(NETDEV_UP): vethNIX1WE: link is not ready
[10718.142462] eth0: renamed from vethAXJWYR
[10718.161675] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[10718.163004] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[10718.163036] IPv6: ADDRCONF(NETDEV_CHANGE): vethNIX1WE: link becomes ready
[10718.163065] lxdbr0: port 1(vethNIX1WE) entered blocking state
[10718.163067] lxdbr0: port 1(vethNIX1WE) entered forwarding state
[10719.322152] audit: type=1400 audit(1532311134.525:128): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/sbin/dhclient" pid=4111 comm="apparmor_parser"
[10719.323608] audit: type=1400 audit(1532311134.525:129): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=4111 comm="apparmor_parser"
[10719.324732] audit: type=1400 audit(1532311134.525:130): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=4111 comm="apparmor_parser"
[10719.327297] audit: type=1400 audit(1532311134.529:131): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=4111 comm="apparmor_parser"
[10719.383824] audit: type=1400 audit(1532311134.585:132): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/bin/lxc-start" pid=4245 comm="apparmor_parser"
[10719.507711] audit: type=1400 audit(1532311134.709:133): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/lxd/lxd-bridge-proxy" pid=4261 comm="apparmor_parser"
[10719.752044] audit: type=1400 audit(1532311134.953:134): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default" pid=4224 comm="apparmor_parser"
[10719.753691] audit: type=1400 audit(1532311134.957:135): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-cgns" pid=4224 comm="apparmor_parser"
[10719.754658] audit: type=1400 audit(1532311134.957:136): apparmor="STATUS" operation="profile_load" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="lxc-container-default-with-mounting" pid=4224 comm="apparmor_parser"
[10799.069906] kauditd_printk_skb: 4 callbacks suppressed
[10799.069908] audit: type=1400 audit(1532311214.272:141): apparmor="STATUS" operation="profile_replace" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/snapd/snap-confine" pid=10158 comm="apparmor_parser"
[10799.082954] audit: type=1400 audit(1532311214.288:142): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" label="lxd-mycont_</var/lib/lxd>//&:lxd-mycont_<var-lib-lxd>:unconfined" name="/usr/lib/snapd/snap-confine//mount-namespace-capture-helper" pid=10158 comm="apparmor_parser"
[10891.126534] lxdbr0: port 1(vethNIX1WE) entered disabled state
[10891.385224] lxdbr0: port 1(vethNIX1WE) entered disabled state
[10891.390229] device vethNIX1WE left promiscuous mode
[10891.390238] lxdbr0: port 1(vethNIX1WE) entered disabled state
[10892.180716] audit: type=1400 audit(1532311307.391:143): apparmor="STATUS" operation="profile_remove" profile="unconfined" name="lxd-mycont_</var/lib/lxd>" pid=14592 comm="apparmor_parser"
[11116.895431] INFO: task txg_sync:608 blocked for more than 120 seconds.
[11116.895467]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11116.895488] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11116.895511] txg_sync        D    0   608      2 0x80000000
[11116.895514] Call Trace:
[11116.895531]  __schedule+0x291/0x8a0
[11116.895533]  schedule+0x2c/0x80
[11116.895542]  cv_wait_common+0x11e/0x140 [spl]
[11116.895549]  ? wait_woken+0x80/0x80
[11116.895553]  __cv_wait+0x15/0x20 [spl]
[11116.895601]  rrw_enter_write+0x3c/0xa0 [zfs]
[11116.895631]  rrw_enter+0x13/0x20 [zfs]
[11116.895662]  spa_sync+0x7c9/0xd80 [zfs]
[11116.895696]  txg_sync_thread+0x2cd/0x4a0 [zfs]
[11116.895727]  ? txg_quiesce_thread+0x3d0/0x3d0 [zfs]
[11116.895731]  thread_generic_wrapper+0x74/0x90 [spl]
[11116.895735]  kthread+0x121/0x140
[11116.895738]  ? __thread_exit+0x20/0x20 [spl]
[11116.895740]  ? kthread_create_worker_on_cpu+0x70/0x70
[11116.895742]  ? kthread_create_worker_on_cpu+0x70/0x70
[11116.895745]  ret_from_fork+0x35/0x40
[11116.895753] INFO: task lxd:1200 blocked for more than 120 seconds.
[11116.895773]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11116.895793] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11116.895817] lxd             D    0  1200      1 0x00000000
[11116.895819] Call Trace:
[11116.895822]  __schedule+0x291/0x8a0
[11116.895824]  schedule+0x2c/0x80
[11116.895826]  rwsem_down_write_failed+0x162/0x350
[11116.895850]  ? dbuf_rele_and_unlock+0x1b2/0x4b0 [zfs]
[11116.895853]  call_rwsem_down_write_failed+0x17/0x30
[11116.895855]  ? call_rwsem_down_write_failed+0x17/0x30
[11116.895856]  down_write+0x2d/0x40
[11116.895864]  grab_super+0x30/0x90
[11116.895895]  ? zpl_create+0x160/0x160 [zfs]
[11116.895897]  sget_userns+0x91/0x490
[11116.895899]  ? get_anon_bdev+0x100/0x100
[11116.895930]  ? zpl_create+0x160/0x160 [zfs]
[11116.895932]  sget+0x7d/0xa0
[11116.895933]  ? get_anon_bdev+0x100/0x100
[11116.895963]  zpl_mount+0xa8/0x160 [zfs]
[11116.895965]  mount_fs+0x37/0x150
[11116.895969]  vfs_kern_mount.part.23+0x5d/0x110
[11116.895971]  do_mount+0x5ed/0xce0
[11116.895973]  ? copy_mount_options+0x2c/0x220
[11116.895974]  SyS_mount+0x98/0xe0
[11116.895978]  do_syscall_64+0x73/0x130
[11116.895980]  entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[11116.895982] RIP: 0033:0x4dbd5a
[11116.895983] RSP: 002b:000000c423565290 EFLAGS: 00000206 ORIG_RAX: 00000000000000a5
[11116.895985] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00000000004dbd5a
[11116.895986] RDX: 000000c421a78828 RSI: 000000c42188fea0 RDI: 000000c4204059c0
[11116.895987] RBP: 000000c423565330 R08: 000000c4208f9a40 R09: 0000000000000000
[11116.895988] R10: 0000000000000000 R11: 0000000000000206 R12: ffffffffffffffff
[11116.895989] R13: 0000000000000063 R14: 0000000000000062 R15: 0000000000000066
[11116.896011] INFO: task lxd:3769 blocked for more than 120 seconds.
[11116.896032]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11116.896052] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11116.896074] lxd             D    0  3769      1 0x00000002
[11116.896076] Call Trace:
[11116.896079]  __schedule+0x291/0x8a0
[11116.896081]  ? __wake_up_common+0x73/0x130
[11116.896083]  schedule+0x2c/0x80
[11116.896088]  cv_wait_common+0x11e/0x140 [spl]
[11116.896090]  ? wait_woken+0x80/0x80
[11116.896094]  __cv_wait+0x15/0x20 [spl]
[11116.896126]  txg_wait_synced+0xdd/0x130 [zfs]
[11116.896159]  zfsvfs_teardown+0x2b1/0x2e0 [zfs]
[11116.896190]  zfs_umount+0x30/0xf0 [zfs]
[11116.896220]  zpl_put_super+0x2c/0x40 [zfs]
[11116.896222]  generic_shutdown_super+0x72/0x120
[11116.896224]  kill_anon_super+0x12/0x20
[11116.896253]  zpl_kill_sb+0x1a/0x20 [zfs]
[11116.896255]  deactivate_locked_super+0x48/0x80
[11116.896257]  deactivate_super+0x40/0x60
[11116.896259]  cleanup_mnt+0x3f/0x80
[11116.896261]  __cleanup_mnt+0x12/0x20
[11116.896263]  task_work_run+0x9d/0xc0
[11116.896265]  exit_to_usermode_loop+0xc0/0xd0
[11116.896267]  do_syscall_64+0x115/0x130
[11116.896269]  entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[11116.896270] RIP: 0033:0x7fa507614447
[11116.896271] RSP: 002b:00007ffdaccb7190 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
[11116.896273] RAX: 0000000000000000 RBX: 0000000000000010 RCX: 00007fa507614447
[11116.896274] RDX: 0000000000000000 RSI: 00007fa507148c50 RDI: 0000000000000010
[11116.896275] RBP: 000000000304b8d0 R08: 0000000000000000 R09: 0000000000000000
[11116.896276] R10: 0000000000000008 R11: 0000000000000293 R12: 000000000304b8fc
[11116.896276] R13: 0000000000000007 R14: 00007fa507aa7aa7 R15: 000000000304f8b0
[11237.722750] INFO: task txg_sync:608 blocked for more than 120 seconds.
[11237.722786]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11237.722807] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11237.722830] txg_sync        D    0   608      2 0x80000000
[11237.722833] Call Trace:
[11237.722841]  __schedule+0x291/0x8a0
[11237.722843]  schedule+0x2c/0x80
[11237.722851]  cv_wait_common+0x11e/0x140 [spl]
[11237.722855]  ? wait_woken+0x80/0x80
[11237.722859]  __cv_wait+0x15/0x20 [spl]
[11237.722908]  rrw_enter_write+0x3c/0xa0 [zfs]
[11237.722938]  rrw_enter+0x13/0x20 [zfs]
[11237.722968]  spa_sync+0x7c9/0xd80 [zfs]
[11237.723001]  txg_sync_thread+0x2cd/0x4a0 [zfs]
[11237.723032]  ? txg_quiesce_thread+0x3d0/0x3d0 [zfs]
[11237.723036]  thread_generic_wrapper+0x74/0x90 [spl]
[11237.723040]  kthread+0x121/0x140
[11237.723043]  ? __thread_exit+0x20/0x20 [spl]
[11237.723045]  ? kthread_create_worker_on_cpu+0x70/0x70
[11237.723047]  ? kthread_create_worker_on_cpu+0x70/0x70
[11237.723050]  ret_from_fork+0x35/0x40
[11237.723059] INFO: task lxd:1200 blocked for more than 120 seconds.
[11237.723079]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11237.723099] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11237.723122] lxd             D    0  1200      1 0x00000000
[11237.723124] Call Trace:
[11237.723127]  __schedule+0x291/0x8a0
[11237.723129]  schedule+0x2c/0x80
[11237.723131]  rwsem_down_write_failed+0x162/0x350
[11237.723155]  ? dbuf_rele_and_unlock+0x1b2/0x4b0 [zfs]
[11237.723158]  call_rwsem_down_write_failed+0x17/0x30
[11237.723160]  ? call_rwsem_down_write_failed+0x17/0x30
[11237.723161]  down_write+0x2d/0x40
[11237.723165]  grab_super+0x30/0x90
[11237.723196]  ? zpl_create+0x160/0x160 [zfs]
[11237.723198]  sget_userns+0x91/0x490
[11237.723199]  ? get_anon_bdev+0x100/0x100
[11237.723231]  ? zpl_create+0x160/0x160 [zfs]
[11237.723232]  sget+0x7d/0xa0
[11237.723234]  ? get_anon_bdev+0x100/0x100
[11237.723264]  zpl_mount+0xa8/0x160 [zfs]
[11237.723267]  mount_fs+0x37/0x150
[11237.723270]  vfs_kern_mount.part.23+0x5d/0x110
[11237.723272]  do_mount+0x5ed/0xce0
[11237.723274]  ? copy_mount_options+0x2c/0x220
[11237.723276]  SyS_mount+0x98/0xe0
[11237.723279]  do_syscall_64+0x73/0x130
[11237.723281]  entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[11237.723283] RIP: 0033:0x4dbd5a
[11237.723284] RSP: 002b:000000c423565290 EFLAGS: 00000206 ORIG_RAX: 00000000000000a5
[11237.723286] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00000000004dbd5a
[11237.723287] RDX: 000000c421a78828 RSI: 000000c42188fea0 RDI: 000000c4204059c0
[11237.723288] RBP: 000000c423565330 R08: 000000c4208f9a40 R09: 0000000000000000
[11237.723289] R10: 0000000000000000 R11: 0000000000000206 R12: ffffffffffffffff
[11237.723290] R13: 0000000000000063 R14: 0000000000000062 R15: 0000000000000066
[11237.723311] INFO: task lxd:3769 blocked for more than 120 seconds.
[11237.723331]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11237.723351] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11237.723374] lxd             D    0  3769      1 0x00000002
[11237.723376] Call Trace:
[11237.723379]  __schedule+0x291/0x8a0
[11237.723381]  ? __wake_up_common+0x73/0x130
[11237.723383]  schedule+0x2c/0x80
[11237.723388]  cv_wait_common+0x11e/0x140 [spl]
[11237.723390]  ? wait_woken+0x80/0x80
[11237.723394]  __cv_wait+0x15/0x20 [spl]
[11237.723427]  txg_wait_synced+0xdd/0x130 [zfs]
[11237.723459]  zfsvfs_teardown+0x2b1/0x2e0 [zfs]
[11237.723516]  zfs_umount+0x30/0xf0 [zfs]
[11237.723547]  zpl_put_super+0x2c/0x40 [zfs]
[11237.723549]  generic_shutdown_super+0x72/0x120
[11237.723551]  kill_anon_super+0x12/0x20
[11237.723580]  zpl_kill_sb+0x1a/0x20 [zfs]
[11237.723582]  deactivate_locked_super+0x48/0x80
[11237.723584]  deactivate_super+0x40/0x60
[11237.723586]  cleanup_mnt+0x3f/0x80
[11237.723588]  __cleanup_mnt+0x12/0x20
[11237.723590]  task_work_run+0x9d/0xc0
[11237.723592]  exit_to_usermode_loop+0xc0/0xd0
[11237.723594]  do_syscall_64+0x115/0x130
[11237.723596]  entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[11237.723597] RIP: 0033:0x7fa507614447
[11237.723598] RSP: 002b:00007ffdaccb7190 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
[11237.723600] RAX: 0000000000000000 RBX: 0000000000000010 RCX: 00007fa507614447
[11237.723601] RDX: 0000000000000000 RSI: 00007fa507148c50 RDI: 0000000000000010
[11237.723602] RBP: 000000000304b8d0 R08: 0000000000000000 R09: 0000000000000000
[11237.723603] R10: 0000000000000008 R11: 0000000000000293 R12: 000000000304b8fc
[11237.723603] R13: 0000000000000007 R14: 00007fa507aa7aa7 R15: 000000000304f8b0
[11358.549694] INFO: task txg_sync:608 blocked for more than 120 seconds.
[11358.549730]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11358.549750] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11358.549773] txg_sync        D    0   608      2 0x80000000
[11358.549776] Call Trace:
[11358.549784]  __schedule+0x291/0x8a0
[11358.549787]  schedule+0x2c/0x80
[11358.549795]  cv_wait_common+0x11e/0x140 [spl]
[11358.549798]  ? wait_woken+0x80/0x80
[11358.549802]  __cv_wait+0x15/0x20 [spl]
[11358.549852]  rrw_enter_write+0x3c/0xa0 [zfs]
[11358.549882]  rrw_enter+0x13/0x20 [zfs]
[11358.549912]  spa_sync+0x7c9/0xd80 [zfs]
[11358.549945]  txg_sync_thread+0x2cd/0x4a0 [zfs]
[11358.549976]  ? txg_quiesce_thread+0x3d0/0x3d0 [zfs]
[11358.549981]  thread_generic_wrapper+0x74/0x90 [spl]
[11358.549984]  kthread+0x121/0x140
[11358.549987]  ? __thread_exit+0x20/0x20 [spl]
[11358.549990]  ? kthread_create_worker_on_cpu+0x70/0x70
[11358.549992]  ? kthread_create_worker_on_cpu+0x70/0x70
[11358.549995]  ret_from_fork+0x35/0x40
[11358.550002] INFO: task lxd:1200 blocked for more than 120 seconds.
[11358.550022]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11358.550042] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11358.550065] lxd             D    0  1200      1 0x00000000
[11358.550067] Call Trace:
[11358.550070]  __schedule+0x291/0x8a0
[11358.550072]  schedule+0x2c/0x80
[11358.550074]  rwsem_down_write_failed+0x162/0x350
[11358.550098]  ? dbuf_rele_and_unlock+0x1b2/0x4b0 [zfs]
[11358.550101]  call_rwsem_down_write_failed+0x17/0x30
[11358.550103]  ? call_rwsem_down_write_failed+0x17/0x30
[11358.550104]  down_write+0x2d/0x40
[11358.550108]  grab_super+0x30/0x90
[11358.550139]  ? zpl_create+0x160/0x160 [zfs]
[11358.550141]  sget_userns+0x91/0x490
[11358.550143]  ? get_anon_bdev+0x100/0x100
[11358.550174]  ? zpl_create+0x160/0x160 [zfs]
[11358.550175]  sget+0x7d/0xa0
[11358.550177]  ? get_anon_bdev+0x100/0x100
[11358.550207]  zpl_mount+0xa8/0x160 [zfs]
[11358.550209]  mount_fs+0x37/0x150
[11358.550213]  vfs_kern_mount.part.23+0x5d/0x110
[11358.550215]  do_mount+0x5ed/0xce0
[11358.550217]  ? copy_mount_options+0x2c/0x220
[11358.550218]  SyS_mount+0x98/0xe0
[11358.550222]  do_syscall_64+0x73/0x130
[11358.550224]  entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[11358.550226] RIP: 0033:0x4dbd5a
[11358.550227] RSP: 002b:000000c423565290 EFLAGS: 00000206 ORIG_RAX: 00000000000000a5
[11358.550229] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00000000004dbd5a
[11358.550230] RDX: 000000c421a78828 RSI: 000000c42188fea0 RDI: 000000c4204059c0
[11358.550230] RBP: 000000c423565330 R08: 000000c4208f9a40 R09: 0000000000000000
[11358.550231] R10: 0000000000000000 R11: 0000000000000206 R12: ffffffffffffffff
[11358.550232] R13: 0000000000000063 R14: 0000000000000062 R15: 0000000000000066
[11358.550255] INFO: task lxd:3769 blocked for more than 120 seconds.
[11358.550274]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11358.550294] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11358.550317] lxd             D    0  3769      1 0x00000002
[11358.550319] Call Trace:
[11358.550322]  __schedule+0x291/0x8a0
[11358.550324]  ? __wake_up_common+0x73/0x130
[11358.550326]  schedule+0x2c/0x80
[11358.550331]  cv_wait_common+0x11e/0x140 [spl]
[11358.550333]  ? wait_woken+0x80/0x80
[11358.550337]  __cv_wait+0x15/0x20 [spl]
[11358.550370]  txg_wait_synced+0xdd/0x130 [zfs]
[11358.550402]  zfsvfs_teardown+0x2b1/0x2e0 [zfs]
[11358.550433]  zfs_umount+0x30/0xf0 [zfs]
[11358.550463]  zpl_put_super+0x2c/0x40 [zfs]
[11358.550465]  generic_shutdown_super+0x72/0x120
[11358.550467]  kill_anon_super+0x12/0x20
[11358.550496]  zpl_kill_sb+0x1a/0x20 [zfs]
[11358.550498]  deactivate_locked_super+0x48/0x80
[11358.550500]  deactivate_super+0x40/0x60
[11358.550502]  cleanup_mnt+0x3f/0x80
[11358.550504]  __cleanup_mnt+0x12/0x20
[11358.550506]  task_work_run+0x9d/0xc0
[11358.550508]  exit_to_usermode_loop+0xc0/0xd0
[11358.550510]  do_syscall_64+0x115/0x130
[11358.550512]  entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[11358.550513] RIP: 0033:0x7fa507614447
[11358.550514] RSP: 002b:00007ffdaccb7190 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
[11358.550515] RAX: 0000000000000000 RBX: 0000000000000010 RCX: 00007fa507614447
[11358.550516] RDX: 0000000000000000 RSI: 00007fa507148c50 RDI: 0000000000000010
[11358.550517] RBP: 000000000304b8d0 R08: 0000000000000000 R09: 0000000000000000
[11358.550518] R10: 0000000000000008 R11: 0000000000000293 R12: 000000000304b8fc
[11358.550519] R13: 0000000000000007 R14: 00007fa507aa7aa7 R15: 000000000304f8b0
[11479.374469] INFO: task txg_sync:608 blocked for more than 120 seconds.
[11479.374501]       Tainted: P         C OE    4.15.0-24-generic #26-Ubuntu
[11479.374522] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[11479.374545] txg_sync        D    0   608      2 0x80000000
[11479.374548] Call Trace:
[11479.374556]  __schedule+0x291/0x8a0
[11479.374559]  schedule+0x2c/0x80
[11479.374567]  cv_wait_common+0x11e/0x140 [spl]
[11479.374571]  ? wait_woken+0x80/0x80
[11479.374575]  __cv_wait+0x15/0x20 [spl]
[11479.374621]  rrw_enter_write+0x3c/0xa0 [zfs]
[11479.374651]  rrw_enter+0x13/0x20 [zfs]
[11479.374682]  spa_sync+0x7c9/0xd80 [zfs]
[11479.374715]  txg_sync_thread+0x2cd/0x4a0 [zfs]
[11479.374745]  ? txg_quiesce_thread+0x3d0/0x3d0 [zfs]
[11479.374749]  thread_generic_wrapper+0x74/0x90 [spl]
[11479.374753]  kthread+0x121/0x140
[11479.374756]  ? __thread_exit+0x20/0x20 [spl]
[11479.374758]  ? kthread_create_worker_on_cpu+0x70/0x70
[11479.374760]  ? kthread_create_worker_on_cpu+0x70/0x70
[11479.374763]  ret_from_fork+0x35/0x40
stgraber commented 6 years ago

You appear to have hit a kernel bug resulting in ZFS transactions being completely stuck. This was reported on earlier Ubuntu kernels, so I'd recommend making sure you upgrade to the latest bugfix kernel (4.15.0-29 I believe), then see if that helps.

Closing this bug as based on the dmesg output above, your kernel isn't in a working state right now.

thomasf1234 commented 6 years ago

Thank you so much for diagnosing that