All of lore.kernel.org
 help / color / mirror / Atom feed
* btrfs send hung in pipe_wait
@ 2018-09-06  9:22 Stefan Löwen
  2018-09-06 15:04 ` Stefan Loewen
  0 siblings, 1 reply; 17+ messages in thread
From: Stefan Löwen @ 2018-09-06  9:22 UTC (permalink / raw)
  To: linux-btrfs

[-- Attachment #1: Type: text/plain, Size: 2149 bytes --]

Hello linux-btrfs,

I'm trying to clone a subvolume with 'btrfs send' but it always hangs 
for hours.

I tested this on multiple systems. All showed the same result:
- Manjaro (btrfs-progs v4.17.1; linux v4.18.5-1-MANJARO)
- Ubuntu 18.04 in VirtualBox (btrfs-progs v4.15.1; linux v4.15.0-33-generic)
- ArchLinux in VirtualBox (btrfs-progs v4.17.1; linux v4.18.5-arch1-1-ARCH)
All following logs are from the ArchLinux VM.

To make sure it's not the 'btrfs receive' at fault I tried sending into 
a file using the following command:
'strace -o btrfs-send.strace btrfs send -vvv -f intenso_white.snapshot 
/mnt/intenso_white/@data/.snapshots/test-snapshot'

The 'btrfs send' process always copies around 1.2-1.4G of data, then 
stops all disk IO and fully loads one cpu core. btrfs scrub found 0 
errors. Neither did btrfsck. 'btrfs device stats' is all 0.

I would be thankful for all ideas and tips.

Regards
Stefan

---------------------------------------------

The btrfs-send.strace is attached. So is the dmesg.log during the hang.

Stack traces of the hung process:
--- /proc/3022/task/3022/stack ---
[<0>] 0xffffffffffffffff
--- /proc/3022/task/3023/stack ---
[<0>] pipe_wait+0x6c/0xb0
[<0>] splice_from_pipe_next.part.3+0x24/0xa0
[<0>] __splice_from_pipe+0x43/0x180
[<0>] splice_from_pipe+0x5d/0x90
[<0>] default_file_splice_write+0x15/0x20
[<0>] __se_sys_splice+0x31b/0x770
[<0>] do_syscall_64+0x5b/0x170
[<0>] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[<0>] 0xffffffffffffffff

[vagrant@archlinux mnt]$ uname -a
Linux archlinux 4.18.5-arch1-1-ARCH #1 SMP PREEMPT Fri Aug 24 12:48:58 
UTC 2018 x86_64 GNU/Linux

[vagrant@archlinux mnt]$ btrfs --version
btrfs-progs v4.17.1

[vagrant@archlinux mnt]$ sudo btrfs fi show /dev/sdb1
Label: 'intenso_white'  uuid: 07bf61ed-7728-4151-a784-c4b840e343ed
Total devices 1 FS bytes used 655.82GiB
devid    1 size 911.51GiB used 703.09GiB path /dev/sdb1

[vagrant@archlinux mnt]$ sudo btrfs fi df /mnt/intenso_white/
Data, single: total=695.01GiB, used=653.69GiB
System, DUP: total=40.00MiB, used=96.00KiB
Metadata, DUP: total=4.00GiB, used=2.13GiB
GlobalReserve, single: total=512.00MiB, used=0.00B


[-- Attachment #2: btrfs-send.strace --]
[-- Type: text/plain, Size: 11194 bytes --]

execve("/usr/bin/btrfs", ["btrfs", "send", "-vvv", "-f", "intenso_white.snapshot", "/mnt/intenso_white/@data/.snapsh"...], 0x7ffcb8f52718 /* 16 vars */) = 0
brk(NULL)                               = 0x555c9eddb000
arch_prctl(0x3001 /* ARCH_??? */, 0x7ffe8c9971e0) = -1 EINVAL (Invalid argument)
access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=28542, ...}) = 0
mmap(NULL, 28542, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7ffa9a016000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/libuuid.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`\24\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=26552, ...}) = 0
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ffa9a014000
mmap(NULL, 2121752, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ffa99e0d000
mprotect(0x7ffa99e13000, 2093056, PROT_NONE) = 0
mmap(0x7ffa9a012000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x5000) = 0x7ffa9a012000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/libblkid.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240\231\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=326480, ...}) = 0
mmap(NULL, 2426656, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ffa99bbc000
mprotect(0x7ffa99c06000, 2097152, PROT_NONE) = 0
mmap(0x7ffa99e06000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4a000) = 0x7ffa99e06000
mmap(0x7ffa99e0c000, 1824, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7ffa99e0c000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/libz.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320!\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=91912, ...}) = 0
mmap(NULL, 2187280, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ffa999a5000
mprotect(0x7ffa999bb000, 2093056, PROT_NONE) = 0
mmap(0x7ffa99bba000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15000) = 0x7ffa99bba000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/liblzo2.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20'\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=137432, ...}) = 0
mmap(NULL, 2232528, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ffa99783000
mprotect(0x7ffa997a4000, 2093056, PROT_NONE) = 0
mmap(0x7ffa999a3000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x20000) = 0x7ffa999a3000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/libzstd.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260E\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=620224, ...}) = 0
mmap(NULL, 2715704, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ffa994eb000
mprotect(0x7ffa99582000, 2093056, PROT_NONE) = 0
mmap(0x7ffa99781000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x96000) = 0x7ffa99781000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340f\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=155408, ...}) = 0
lseek(3, 808, SEEK_SET)                 = 808
read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
mmap(NULL, 131528, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ffa994ca000
mmap(0x7ffa994d0000, 61440, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x7ffa994d0000
mmap(0x7ffa994df000, 24576, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15000) = 0x7ffa994df000
mmap(0x7ffa994e5000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1a000) = 0x7ffa994e5000
mmap(0x7ffa994e7000, 12744, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7ffa994e7000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000C\2\0\0\0\0\0"..., 832) = 832
lseek(3, 792, SEEK_SET)                 = 792
read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\210o7\21\1\307=\311\366\263\256\202p\3777\335"..., 68) = 68
fstat(3, {st_mode=S_IFREG|0755, st_size=2136840, ...}) = 0
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ffa994c8000
lseek(3, 792, SEEK_SET)                 = 792
read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\210o7\21\1\307=\311\366\263\256\202p\3777\335"..., 68) = 68
lseek(3, 864, SEEK_SET)                 = 864
read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
mmap(NULL, 1848896, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ffa99304000
mprotect(0x7ffa99326000, 1671168, PROT_NONE) = 0
mmap(0x7ffa99326000, 1355776, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x22000) = 0x7ffa99326000
mmap(0x7ffa99471000, 311296, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16d000) = 0x7ffa99471000
mmap(0x7ffa994be000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1b9000) = 0x7ffa994be000
mmap(0x7ffa994c4000, 13888, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7ffa994c4000
close(3)                                = 0
mmap(NULL, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ffa99301000
arch_prctl(ARCH_SET_FS, 0x7ffa993018c0) = 0
mprotect(0x7ffa994be000, 16384, PROT_READ) = 0
mprotect(0x7ffa994e5000, 4096, PROT_READ) = 0
mprotect(0x7ffa99781000, 4096, PROT_READ) = 0
mprotect(0x7ffa999a3000, 4096, PROT_READ) = 0
mprotect(0x7ffa99bba000, 4096, PROT_READ) = 0
mprotect(0x7ffa9a012000, 4096, PROT_READ) = 0
mprotect(0x7ffa99e06000, 20480, PROT_READ) = 0
mprotect(0x555c9e101000, 20480, PROT_READ) = 0
mprotect(0x7ffa9a046000, 4096, PROT_READ) = 0
munmap(0x7ffa9a016000, 28542)           = 0
set_tid_address(0x7ffa99301b90)         = 3022
set_robust_list(0x7ffa99301ba0, 24)     = 0
rt_sigaction(SIGRTMIN, {sa_handler=0x7ffa994d0130, sa_mask=[], sa_flags=SA_RESTORER|SA_SIGINFO, sa_restorer=0x7ffa994dc3c0}, NULL, 8) = 0
rt_sigaction(SIGRT_1, {sa_handler=0x7ffa994d01d0, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART|SA_SIGINFO, sa_restorer=0x7ffa994dc3c0}, NULL, 8) = 0
rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
openat(AT_FDCWD, "intenso_white.snapshot", O_WRONLY|O_TRUNC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "intenso_white.snapshot", O_WRONLY|O_CREAT|O_TRUNC, 0600) = 3
ioctl(3, TCGETS, 0x7ffe8c995f90)        = -1 ENOTTY (Inappropriate ioctl for device)
brk(NULL)                               = 0x555c9eddb000
brk(0x555c9edfc000)                     = 0x555c9edfc000
lstat("/mnt", {st_mode=S_IFDIR|0755, st_size=60, ...}) = 0
lstat("/mnt/intenso_white", {st_mode=S_IFDIR|0777, st_size=24, ...}) = 0
lstat("/mnt/intenso_white/@data", {st_mode=S_IFDIR|0755, st_size=28, ...}) = 0
lstat("/mnt/intenso_white/@data/.snapshots", {st_mode=S_IFDIR|0755, st_size=76, ...}) = 0
lstat("/mnt/intenso_white/@data/.snapshots/test-snapshot", {st_mode=S_IFDIR|0755, st_size=28, ...}) = 0
openat(AT_FDCWD, "/mnt/intenso_white/@data/.snapshots/test-snapshot", O_RDONLY|O_NOATIME) = 4
close(4)                                = 0
openat(AT_FDCWD, "/proc/self/mounts", O_RDONLY|O_CLOEXEC) = 4
futex(0x7ffa994c4fc8, FUTEX_WAKE_PRIVATE, 2147483647) = 0
fstat(4, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
read(4, "proc /proc proc rw,nosuid,nodev,"..., 1024) = 1024
read(4, "ime,perf_event 0 0\ncgroup /sys/f"..., 1024) = 1024
read(4, "size=2M 0 0\ndebugfs /sys/kernel/"..., 1024) = 318
read(4, "", 1024)                       = 0
close(4)                                = 0
lstat("/mnt", {st_mode=S_IFDIR|0755, st_size=60, ...}) = 0
lstat("/mnt/intenso_white", {st_mode=S_IFDIR|0777, st_size=24, ...}) = 0
openat(AT_FDCWD, "/mnt/intenso_white", O_RDONLY|O_NOATIME) = 4
ioctl(4, BTRFS_IOC_TREE_SEARCH, {key={tree_id=BTRFS_ROOT_TREE_OBJECTID, min_objectid=BTRFS_UUID_TREE_OBJECTID, max_objectid=BTRFS_UUID_TREE_OBJECTID, min_offset=0, max_offset=UINT64_MAX, min_transid=0, max_transid=UINT64_MAX, min_type=BTRFS_ROOT_ITEM_KEY, max_type=BTRFS_ROOT_ITEM_KEY, nr_items=1}} => {key={nr_items=1}, ...}) = 0
lstat("/mnt", {st_mode=S_IFDIR|0755, st_size=60, ...}) = 0
lstat("/mnt/intenso_white", {st_mode=S_IFDIR|0777, st_size=24, ...}) = 0
lstat("/mnt/intenso_white/@data", {st_mode=S_IFDIR|0755, st_size=28, ...}) = 0
lstat("/mnt/intenso_white/@data/.snapshots", {st_mode=S_IFDIR|0755, st_size=76, ...}) = 0
lstat("/mnt/intenso_white/@data/.snapshots/test-snapshot", {st_mode=S_IFDIR|0755, st_size=28, ...}) = 0
openat(AT_FDCWD, "/mnt/intenso_white/@data/.snapshots/test-snapshot", O_RDONLY|O_NOATIME) = 5
close(5)                                = 0
openat(AT_FDCWD, "/proc/self/mounts", O_RDONLY|O_CLOEXEC) = 5
fstat(5, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
read(5, "proc /proc proc rw,nosuid,nodev,"..., 1024) = 1024
read(5, "ime,perf_event 0 0\ncgroup /sys/f"..., 1024) = 1024
read(5, "size=2M 0 0\ndebugfs /sys/kernel/"..., 1024) = 318
read(5, "", 1024)                       = 0
close(5)                                = 0
lstat("/mnt", {st_mode=S_IFDIR|0755, st_size=60, ...}) = 0
lstat("/mnt/intenso_white", {st_mode=S_IFDIR|0777, st_size=24, ...}) = 0
openat(4, "/mnt/intenso_white/@data/.snapshots/test-snapshot", O_RDONLY|O_NOATIME) = 5
ioctl(5, BTRFS_IOC_SUBVOL_GETFLAGS, BTRFS_SUBVOL_RDONLY) = 0
close(5)                                = 0
write(2, "At subvol /mnt/intenso_white/@da"..., 60) = 60
lstat("/mnt", {st_mode=S_IFDIR|0755, st_size=60, ...}) = 0
lstat("/mnt/intenso_white", {st_mode=S_IFDIR|0777, st_size=24, ...}) = 0
lstat("/mnt/intenso_white/@data", {st_mode=S_IFDIR|0755, st_size=28, ...}) = 0
lstat("/mnt/intenso_white/@data/.snapshots", {st_mode=S_IFDIR|0755, st_size=76, ...}) = 0
lstat("/mnt/intenso_white/@data/.snapshots/test-snapshot", {st_mode=S_IFDIR|0755, st_size=28, ...}) = 0
openat(4, "/mnt/intenso_white/@data/.snapshots/test-snapshot", O_RDONLY|O_NOATIME) = 5
pipe([6, 7])                            = 0
mmap(NULL, 8392704, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7ffa98b00000
mprotect(0x7ffa98b01000, 8388608, PROT_READ|PROT_WRITE) = 0
clone(child_stack=0x7ffa992ffe30, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7ffa993009d0, tls=0x7ffa99300700, child_tidptr=0x7ffa993009d0) = 3023
ioctl(5, BTRFS_IOC_SEND, {send_fd=7, clone_sources_count=0, clone_sources=NULL, parent_root=0, flags=0}) = ? ERESTARTSYS (To be restarted if SA_RESTART is set)
--- SIGWINCH {si_signo=SIGWINCH, si_code=SI_KERNEL} ---
ioctl(5, BTRFS_IOC_SEND, {send_fd=7, clone_sources_count=0, clone_sources=NULL, parent_root=0, flags=0}

[-- Attachment #3: dmesg.log --]
[-- Type: text/x-log, Size: 34765 bytes --]

[    0.000000] Linux version 4.18.5-arch1-1-ARCH (builduser@heftig-12250) (gcc version 8.2.0 (GCC)) #1 SMP PREEMPT Fri Aug 24 12:48:58 UTC 2018
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-linux root=UUID=a83c9650-c8f8-4afe-90a6-4e80156d523d rw 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] 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-0x00000000dffeffff] usable
[    0.000000] BIOS-e820: [mem 0x00000000dfff0000-0x00000000dfffffff] 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] BIOS-e820: [mem 0x0000000100000000-0x000000011fffffff] usable
[    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] last_pfn = 0x120000 max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR variable ranges disabled:
[    0.000000] 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] last_pfn = 0xdfff0 max_arch_pfn = 0x400000000
[    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 [0x111677000, 0x111677fff] PGTABLE
[    0.000000] BRK [0x111678000, 0x111678fff] PGTABLE
[    0.000000] BRK [0x111679000, 0x111679fff] PGTABLE
[    0.000000] BRK [0x11167a000, 0x11167afff] PGTABLE
[    0.000000] BRK [0x11167b000, 0x11167bfff] PGTABLE
[    0.000000] BRK [0x11167c000, 0x11167cfff] PGTABLE
[    0.000000] BRK [0x11167d000, 0x11167dfff] PGTABLE
[    0.000000] BRK [0x11167e000, 0x11167efff] PGTABLE
[    0.000000] BRK [0x11167f000, 0x11167ffff] PGTABLE
[    0.000000] RAMDISK: [mem 0x37131000-0x3788ffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000E0000 000024 (v02 VBOX  )
[    0.000000] ACPI: XSDT 0x00000000DFFF0030 00003C (v01 VBOX   VBOXXSDT 00000001 ASL  00000061)
[    0.000000] ACPI: FACP 0x00000000DFFF00F0 0000F4 (v04 VBOX   VBOXFACP 00000001 ASL  00000061)
[    0.000000] ACPI: DSDT 0x00000000DFFF0470 0021FF (v02 VBOX   VBOXBIOS 00000002 INTL 20180629)
[    0.000000] ACPI: FACS 0x00000000DFFF0200 000040
[    0.000000] ACPI: FACS 0x00000000DFFF0200 000040
[    0.000000] ACPI: APIC 0x00000000DFFF0240 00005C (v02 VBOX   VBOXAPIC 00000001 ASL  00000061)
[    0.000000] ACPI: SSDT 0x00000000DFFF02A0 0001CC (v01 VBOX   VBOXCPUT 00000002 INTL 20180629)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000011fffffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x11fffc000-0x11fffffff]
[    0.000000] kvm-clock: cpu 0, msr 1:1fff6001, primary cpu clock
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: using sched offset of 5269732913 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-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x000000011fffffff]
[    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-0x00000000dffeffff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x000000011fffffff]
[    0.000000] Reserved but unavailable: 114 pages
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000011fffffff]
[    0.000000] On node 0 totalpages: 1048462
[    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: 14272 pages used for memmap
[    0.000000]   DMA32 zone: 913392 pages, LIFO batch:31
[    0.000000]   Normal zone: 2048 pages used for memmap
[    0.000000]   Normal zone: 131072 pages, LIFO batch:31
[    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] PM: Registered nosave memory: [mem 0xdfff0000-0xdfffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xe0000000-0xfebfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec00000-0xfec00fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec01000-0xfedfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfee00000-0xfee00fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfee01000-0xfffbffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff]
[    0.000000] [mem 0xe0000000-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: 6370452778343963 ns
[    0.000000] random: get_random_bytes called from start_kernel+0x95/0x535 with crng_init=0
[    0.000000] setup_percpu: NR_CPUS:320 nr_cpumask_bits:320 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: 1032057
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-linux root=UUID=a83c9650-c8f8-4afe-90a6-4e80156d523d rw 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: 4031212K/4193848K available (10252K kernel code, 1297K rwdata, 3528K rodata, 1492K init, 612K bss, 162636K 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 33395 entries in 131 pages
[    0.003333] Preemptible hierarchical RCU implementation.
[    0.003333] 	CONFIG_RCU_FANOUT set to non-default value of 32
[    0.003333] 	RCU dyntick-idle grace-period acceleration is enabled.
[    0.003333] 	RCU restricting CPUs from NR_CPUS=320 to nr_cpu_ids=2.
[    0.003333] 	RCU priority boosting: priority 1 delay 500 ms.
[    0.003333] 	Tasks RCU enabled.
[    0.003333] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.003333] NR_IRQS: 20736, nr_irqs: 440, preallocated irqs: 16
[    0.003333] 	Offload RCU callbacks from CPUs: (none).
[    0.003333] Console: colour dummy device 80x25
[    0.003333] console [tty0] enabled
[    0.003333] ACPI: Core revision 20180531
[    0.003333] APIC: Switch to symmetric I/O mode setup
[    0.003333] x2apic enabled
[    0.003333] Switched APIC routing to physical x2apic.
[    0.003333] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.003333] tsc: Detected 2593.992 MHz processor
[    0.003333] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x25641074d3b, max_idle_ns: 440795244898 ns
[    0.003333] Calibrating delay loop (skipped) preset value.. 5190.15 BogoMIPS (lpj=8646640)
[    0.003333] pid_max: default: 32768 minimum: 301
[    0.003333] Security Framework initialized
[    0.003333] Yama: becoming mindful.
[    0.003333] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.003971] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.004014] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.004025] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.004309] mce: CPU supports 0 MCE banks
[    0.004325] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
[    0.004325] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
[    0.004328] Spectre V2 : Mitigation: Full generic retpoline
[    0.004328] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.004329] Speculative Store Bypass: Vulnerable
[    0.004417] Freeing SMP alternatives memory: 28K
[    0.006666] smpboot: CPU0: Intel(R) Core(TM) i5-4310U CPU @ 2.00GHz (family: 0x6, model: 0x45, stepping: 0x1)
[    0.023365] Performance Events: unsupported p6 CPU model 69 no PMU driver, software events only.
[    0.030022] Hierarchical SRCU implementation.
[    0.044332] NMI watchdog: Perf event create on CPU 0 failed with -2
[    0.044336] NMI watchdog: Perf NMI watchdog permanently disabled
[    0.056697] smp: Bringing up secondary CPUs ...
[    0.096727] x86: Booting SMP configuration:
[    0.096731] .... node  #0, CPUs:      #1
[    0.003333] kvm-clock: cpu 1, msr 1:1fff6041, secondary cpu clock
[    0.003333] mce: CPU supports 0 MCE banks
[    0.099385] smp: Brought up 1 node, 2 CPUs
[    0.099385] smpboot: Max logical packages: 1
[    0.099385] smpboot: Total of 2 processors activated (10380.30 BogoMIPS)
[    0.100140] devtmpfs: initialized
[    0.100140] x86/mm: Memory block size: 128MB
[    0.101099] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns
[    0.101099] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.101099] pinctrl core: initialized pinctrl subsystem
[    0.101099] RTC time:  8:32:36, date: 09/06/18
[    0.101099] NET: Registered protocol family 16
[    0.101180] audit: initializing netlink subsys (disabled)
[    0.103375] audit: type=2000 audit(1536222763.843:1): state=initialized audit_enabled=0 res=1
[    0.103791] cpuidle: using governor ladder
[    0.103791] cpuidle: using governor menu
[    0.103791] ACPI: bus type PCI registered
[    0.103791] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.103791] PCI: Using configuration type 1 for base access
[    0.106775] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.106935] ACPI: Added _OSI(Module Device)
[    0.106937] ACPI: Added _OSI(Processor Device)
[    0.106938] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.106939] ACPI: Added _OSI(Processor Aggregator Device)
[    0.106941] ACPI: Added _OSI(Linux-Dell-Video)
[    0.112542] ACPI: 2 ACPI AML tables successfully acquired and loaded
[    0.115629] ACPI: Interpreter enabled
[    0.115639] ACPI: (supports S0 S5)
[    0.115641] ACPI: Using IOAPIC for interrupt routing
[    0.115897] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.116004] ACPI: Enabled 2 GPEs in block 00 to 07
[    0.121998] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.122014] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.122046] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
[    0.122063] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.122567] PCI host bridge to bus 0000:00
[    0.122573] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.122576] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.122577] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.122578] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xfdffffff window]
[    0.122581] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.122629] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.123555] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.124275] pci 0000:00:01.1: [8086:7111] type 00 class 0x01018a
[    0.124606] pci 0000:00:01.1: reg 0x20: [io  0xd000-0xd00f]
[    0.124735] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.124737] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.124738] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.124739] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.125144] pci 0000:00:02.0: [80ee:beef] type 00 class 0x030000
[    0.126910] pci 0000:00:02.0: reg 0x10: [mem 0xe0000000-0xe07fffff pref]
[    0.164284] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    0.170084] pci 0000:00:03.0: reg 0x10: [mem 0xf0000000-0xf001ffff]
[    0.176805] pci 0000:00:03.0: reg 0x18: [io  0xd010-0xd017]
[    0.207840] pci 0000:00:04.0: [80ee:cafe] type 00 class 0x088000
[    0.210134] pci 0000:00:04.0: reg 0x10: [io  0xd020-0xd03f]
[    0.213550] pci 0000:00:04.0: reg 0x14: [mem 0xf0400000-0xf07fffff]
[    0.223363] pci 0000:00:04.0: reg 0x18: [mem 0xf0800000-0xf0803fff pref]
[    0.243975] pci 0000:00:05.0: [8086:2415] type 00 class 0x040100
[    0.244123] pci 0000:00:05.0: reg 0x10: [io  0xd100-0xd1ff]
[    0.244189] pci 0000:00:05.0: reg 0x14: [io  0xd200-0xd23f]
[    0.245644] pci 0000:00:07.0: [8086:7113] type 00 class 0x068000
[    0.247128] pci 0000:00:07.0: quirk: [io  0x4000-0x403f] claimed by PIIX4 ACPI
[    0.247150] pci 0000:00:07.0: quirk: [io  0x4100-0x410f] claimed by PIIX4 SMB
[    0.249317] pci 0000:00:0c.0: [8086:1e31] type 00 class 0x0c0330
[    0.253369] pci 0000:00:0c.0: reg 0x10: [mem 0xf0810000-0xf081ffff]
[    0.302124] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 9 10 *11)
[    0.302675] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 9 *10 11)
[    0.302861] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 *9 10 11)
[    0.303031] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 9 10 *11)
[    0.303482] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    0.303482] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.303482] pci 0000:00:02.0: vgaarb: bridge control possible
[    0.303482] vgaarb: loaded
[    0.303679] pps_core: LinuxPPS API ver. 1 registered
[    0.303680] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.303683] PTP clock support registered
[    0.303697] EDAC MC: Ver: 3.0.0
[    0.304315] PCI: Using ACPI for IRQ routing
[    0.304315] PCI: pci_cache_line_size set to 64 bytes
[    0.304315] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.304315] e820: reserve RAM buffer [mem 0xdfff0000-0xdfffffff]
[    0.304315] NetLabel: Initializing
[    0.304315] NetLabel:  domain hash size = 128
[    0.304315] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.304315] NetLabel:  unlabeled traffic allowed by default
[    0.304315] clocksource: Switched to clocksource kvm-clock
[    0.321206] VFS: Disk quotas dquot_6.6.0
[    0.321225] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.321307] pnp: PnP ACPI init
[    0.321392] pnp 00:00: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.321472] pnp 00:01: Plug and Play ACPI device, IDs PNP0f03 (active)
[    0.322299] pnp: PnP ACPI: found 2 devices
[    0.339603] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.339617] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.339620] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.339622] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.339625] pci_bus 0000:00: resource 7 [mem 0xe0000000-0xfdffffff window]
[    0.339717] NET: Registered protocol family 2
[    0.339945] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes)
[    0.339962] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    0.340053] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    0.340119] TCP: Hash tables configured (established 32768 bind 32768)
[    0.340155] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    0.340169] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    0.340221] NET: Registered protocol family 1
[    0.340228] NET: Registered protocol family 44
[    0.340235] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.340279] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.340343] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.342324] PCI: CLS 0 bytes, default 64
[    0.342428] Unpacking initramfs...
[    0.468721] Freeing initrd memory: 7548K
[    0.468736] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.468739] software IO TLB [mem 0xdbff0000-0xdfff0000] (64MB) mapped at [(____ptrval____)-(____ptrval____)]
[    0.468792] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x25641074d3b, max_idle_ns: 440795244898 ns
[    0.468834] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.468875] Scanning for low memory corruption every 60 seconds
[    0.469442] Initialise system trusted keyrings
[    0.469452] Key type blacklist registered
[    0.469562] workingset: timestamp_bits=41 max_order=20 bucket_order=0
[    0.470683] zbud: loaded
[    0.470999] pstore: using lz4hc compression
[    0.473446] Key type asymmetric registered
[    0.473447] Asymmetric key parser 'x509' registered
[    0.473477] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[    0.477094] io scheduler noop registered
[    0.477098] io scheduler deadline registered
[    0.477270] io scheduler cfq registered (default)
[    0.477272] io scheduler mq-deadline registered
[    0.477274] io scheduler kyber registered
[    0.477324] io scheduler bfq registered
[    0.477881] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    0.478023] vesafb: mode is 640x480x32, linelength=2560, pages=0
[    0.478025] vesafb: scrolling: redraw
[    0.478027] vesafb: Truecolor: size=8:8:8:8, shift=24:16:8:0
[    0.478055] vesafb: framebuffer at 0xe0000000, mapped to 0x(____ptrval____), using 1216k, total 1216k
[    0.480489] Console: switching to colour frame buffer device 80x30
[    0.481244] fb0: VESA VGA frame buffer device
[    0.481294] intel_idle: Please enable MWAIT in BIOS SETUP
[    0.481389] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    0.481414] ACPI: Power Button [PWRF]
[    0.481485] input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input1
[    0.481491] ACPI: Sleep Button [SLPF]
[    0.481593] ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
[    0.481678] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input2
[    0.482373] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.490476] ledtrig-cpu: registered to indicate activity on CPUs
[    0.490710] NET: Registered protocol family 10
[    0.505142] Segment Routing with IPv6
[    0.505185] NET: Registered protocol family 17
[    0.505572] RAS: Correctable Errors collector initialized.
[    0.505588] sched_clock: Marking stable (505509868, 0)->(823528256, -318018388)
[    0.506354] registered taskstats version 1
[    0.506365] Loading compiled-in X.509 certificates
[    0.506387] zswap: loaded using pool lzo/zbud
[    0.515022] Key type big_key registered
[    0.515264]   Magic number: 2:792:522
[    0.515302] hctosys: unable to open rtc device (rtc0)
[    0.525768] Freeing unused kernel image memory: 1492K
[    0.525876] Write protecting the kernel read-only data: 16384k
[    0.527846] Freeing unused kernel image memory: 2016K
[    0.528593] Freeing unused kernel image memory: 568K
[    0.607501] SCSI subsystem initialized
[    0.636094] ACPI: bus type USB registered
[    0.636122] usbcore: registered new interface driver usbfs
[    0.636133] usbcore: registered new interface driver hub
[    0.636341] usbcore: registered new device driver usb
[    0.642061] libata version 3.00 loaded.
[    0.643517] ata_piix 0000:00:01.1: version 2.13
[    0.662205] scsi host0: ata_piix
[    0.664586] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
[    0.665193] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.668522] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.670605] scsi host1: ata_piix
[    0.670700] ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xd000 irq 14
[    0.670703] ata2: PATA max UDMA/33 cmd 0x170 ctl 0x376 bmdma 0xd008 irq 15
[    0.684217] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[    0.684738] xhci_hcd 0000:00:0c.0: xHCI Host Controller
[    0.684747] xhci_hcd 0000:00:0c.0: new USB bus registered, assigned bus number 1
[    1.075827] ata1.01: NODEV after polling detection
[    1.076074] ata1.00: ATA-6: VBOX HARDDISK, 1.0, max UDMA/133
[    1.076076] ata1.00: 41943040 sectors, multi 128: LBA 
[    1.076669] xhci_hcd 0000:00:0c.0: hcc params 0x04000000 hci version 0x100 quirks 0x000000000000b930
[    1.076748] scsi 0:0:0:0: Direct-Access     ATA      VBOX HARDDISK    1.0  PQ: 0 ANSI: 5
[    1.077547] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.18
[    1.077551] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.077553] usb usb1: Product: xHCI Host Controller
[    1.077555] usb usb1: Manufacturer: Linux 4.18.5-arch1-1-ARCH xhci-hcd
[    1.077557] usb usb1: SerialNumber: 0000:00:0c.0
[    1.078078] hub 1-0:1.0: USB hub found
[    1.078187] hub 1-0:1.0: 8 ports detected
[    1.080347] xhci_hcd 0000:00:0c.0: xHCI Host Controller
[    1.080353] xhci_hcd 0000:00:0c.0: new USB bus registered, assigned bus number 2
[    1.080359] xhci_hcd 0000:00:0c.0: Host supports USB 3.0  SuperSpeed
[    1.080645] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.18
[    1.080648] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.080650] usb usb2: Product: xHCI Host Controller
[    1.080652] usb usb2: Manufacturer: Linux 4.18.5-arch1-1-ARCH xhci-hcd
[    1.080653] usb usb2: SerialNumber: 0000:00:0c.0
[    1.080868] hub 2-0:1.0: USB hub found
[    1.080908] hub 2-0:1.0: 6 ports detected
[    1.088766] sd 0:0:0:0: [sda] 41943040 512-byte logical blocks: (21.5 GB/20.0 GiB)
[    1.088781] sd 0:0:0:0: [sda] Write Protect is off
[    1.088783] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    1.088801] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.098859]  sda: sda1 sda2
[    1.099630] sd 0:0:0:0: [sda] Attached SCSI disk
[    1.226504] raid6: sse2x1   gen()  5525 MB/s
[    1.286935] raid6: sse2x1   xor()  3925 MB/s
[    1.351882] raid6: sse2x2   gen()  6373 MB/s
[    1.409812] raid6: sse2x2   xor()  5337 MB/s
[    1.466925] raid6: sse2x4   gen()  7334 MB/s
[    1.530167] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.530205] raid6: sse2x4   xor()  3160 MB/s
[    1.586811] raid6: avx2x1   gen()  2545 MB/s
[    1.647330] raid6: avx2x1   xor()  4374 MB/s
[    1.689474] usb 1-1: New USB device found, idVendor=152d, idProduct=0539, bcdDevice= 2.06
[    1.689478] usb 1-1: New USB device strings: Mfr=10, Product=11, SerialNumber=3
[    1.689480] usb 1-1: Product: External USB 3.0
[    1.689482] usb 1-1: Manufacturer: Intenso
[    1.689484] usb 1-1: SerialNumber: 20120625001B2
[    1.698164] usb-storage 1-1:1.0: USB Mass Storage device detected
[    1.705386] usb-storage 1-1:1.0: Quirks match for vid 152d pid 0539: 4000000
[    1.705467] scsi host2: usb-storage 1-1:1.0
[    1.706162] usbcore: registered new interface driver usb-storage
[    1.711960] raid6: avx2x2   gen() 10713 MB/s
[    1.766779] raid6: avx2x2   xor()  5911 MB/s
[    1.823481] raid6: avx2x4   gen() 12573 MB/s
[    1.882040] raid6: avx2x4   xor()  8853 MB/s
[    1.882046] raid6: using algorithm avx2x4 gen() 12573 MB/s
[    1.882048] raid6: .... xor() 8853 MB/s, rmw enabled
[    1.882051] raid6: using avx2x2 recovery algorithm
[    1.882382] usb 2-1: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[    1.888787] xor: automatically using best checksumming function   avx       
[    1.898493] usb 2-1: New USB device found, idVendor=152d, idProduct=0539, bcdDevice= 2.06
[    1.898496] usb 2-1: New USB device strings: Mfr=10, Product=11, SerialNumber=3
[    1.898498] usb 2-1: Product: External USB 3.0
[    1.898500] usb 2-1: Manufacturer: Intenso
[    1.898502] usb 2-1: SerialNumber: 2012071215002
[    1.899974] usb-storage 2-1:1.0: USB Mass Storage device detected
[    1.903413] usb-storage 2-1:1.0: Quirks match for vid 152d pid 0539: 4000000
[    1.903466] scsi host3: usb-storage 2-1:1.0
[    1.918187] Btrfs loaded, crc32c=crc32c-intel
[    1.918798] BTRFS: device label rootfs devid 1 transid 42 /dev/sda2
[    1.949726] BTRFS info (device sda2): disk space caching is enabled
[    1.949728] BTRFS info (device sda2): has skinny extents
[    2.054552] random: fast init done
[    2.220202] systemd[1]: systemd 239 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.220232] systemd[1]: Detected virtualization oracle.
[    2.220236] systemd[1]: Detected architecture x86-64.
[    2.225882] systemd[1]: Set hostname to <archlinux>.
[    2.356325] random: systemd: uninitialized urandom read (16 bytes read)
[    2.356378] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[    2.356424] random: systemd: uninitialized urandom read (16 bytes read)
[    2.356535] systemd[1]: Listening on Journal Socket.
[    2.356711] random: systemd: uninitialized urandom read (16 bytes read)
[    2.360224] systemd[1]: Mounting Kernel Configuration File System...
[    2.361606] systemd[1]: Listening on udev Kernel Socket.
[    2.361764] systemd[1]: Reached target Remote Encrypted Volumes.
[    2.361941] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    2.422287] BTRFS info (device sda2): disk space caching is enabled
[    2.550188] systemd-journald[216]: Received request to flush runtime journal from PID 1
[    2.662915] systemd-journald[216]: File /var/log/journal/d8b7d823fa4b4e219a8c84bf6d1fe82c/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    2.717817] scsi 2:0:0:0: Direct-Access     Intenso  External USB 3.0 0206 PQ: 0 ANSI: 6
[    2.719008] sd 2:0:0:0: [sdb] 1953523712 512-byte logical blocks: (1.00 TB/932 GiB)
[    2.719866] sd 2:0:0:0: [sdb] Write Protect is off
[    2.719869] sd 2:0:0:0: [sdb] Mode Sense: 27 00 00 00
[    2.721935] sd 2:0:0:0: [sdb] No Caching mode page found
[    2.722009] sd 2:0:0:0: [sdb] Assuming drive cache: write through
[    2.728515]  sdb: sdb1 sdb2
[    2.729066] sdb: p2 size 19914992 extends beyond EOD, enabling native capacity
[    2.733219]  sdb: sdb1 sdb2
[    2.733988] sdb: p2 size 19914992 extends beyond EOD, truncated
[    2.737554] sd 2:0:0:0: [sdb] Attached SCSI disk
[    2.870788] ACPI: Battery Slot [BAT0] (battery present)
[    2.871906] Linux agpgart interface v0.103
[    2.875858] ACPI: AC Adapter [AC] (on-line)
[    2.881081] piix4_smbus 0000:00:07.0: SMBus Host Controller at 0x4100, revision 0
[    2.885979] vboxguest: host-version: 5.2.18r123745 0x1
[    2.886675] vbg_heartbeat_init: Setting up heartbeat to trigger every 2000 milliseconds
[    2.889343] input: VirtualBox mouse integration as /devices/pci0000:00/0000:00:04.0/input/input5
[    2.890385] vboxguest: misc device minor 57, IRQ 20, I/O port d020, MMIO at 0x00000000f0400000 (size 0x0000000000400000)
[    2.905724] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    2.905725] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    2.931231] rtc_cmos rtc_cmos: registered as rtc0
[    2.931249] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram
[    2.934276] scsi 3:0:0:0: Direct-Access     Intenso  External USB 3.0 0206 PQ: 0 ANSI: 6
[    2.934913] sd 3:0:0:0: [sdc] 1953523712 512-byte logical blocks: (1.00 TB/932 GiB)
[    2.935369] sd 3:0:0:0: [sdc] Write Protect is off
[    2.935372] sd 3:0:0:0: [sdc] Mode Sense: 27 00 00 00
[    2.935803] sd 3:0:0:0: [sdc] No Caching mode page found
[    2.935862] sd 3:0:0:0: [sdc] Assuming drive cache: write through
[    2.939364]  sdc: sdc1
[    2.941245] sd 3:0:0:0: [sdc] Attached SCSI disk
[    2.971012] usbcore: registered new interface driver uas
[    2.976712] RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 10737418240 ms ovfl timer
[    2.976713] RAPL PMU: hw unit of domain pp0-core 2^-0 Joules
[    2.976714] RAPL PMU: hw unit of domain package 2^-0 Joules
[    2.976715] RAPL PMU: hw unit of domain dram 2^-0 Joules
[    2.976716] RAPL PMU: hw unit of domain pp1-gpu 2^-0 Joules
[    2.997577] input: PC Speaker as /devices/platform/pcspkr/input/input7
[    3.022025] Adding 2024444k swap on /dev/sda1.  Priority:-2 extents:1 across:2024444k FS
[    3.058370] cryptd: max_cpu_qlen set to 1000
[    3.086189] AVX2 version of gcm_enc/dec engaged.
[    3.086191] AES CTR mode by8 optimization enabled
[    3.089852] mousedev: PS/2 mouse device common for all mice
[    3.168389] vboxvideo: module is from the staging directory, the quality is unknown, you have been warned.
[    3.171996] [drm] VRAM 00800000
[    3.176777] [TTM] Zone  kernel: Available graphics memory: 2021432 kiB
[    3.176779] [TTM] Initializing pool allocator
[    3.176782] [TTM] Initializing DMA pool allocator
[    3.179667] checking generic (e0000000 130000) vs hw (e0000000 800000)
[    3.179668] fb: switching to vboxdrmfb from VESA VGA
[    3.179689] Console: switching to colour dummy device 80x25
[    3.181528] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input6
[    3.181887] fbcon: vboxdrmfb (fb0) is primary device
[    3.182621] Console: switching to colour frame buffer device 100x37
[    3.183900] vboxvideo 0000:00:02.0: fb0: vboxdrmfb frame buffer device
[    3.203426] [drm] Initialized vboxvideo 1.0.0 20130823 for 0000:00:02.0 on minor 0
[    3.262796] BTRFS: device label intenso_usb_black devid 1 transid 57 /dev/sdc1
[    3.272853] BTRFS: device label intenso_white devid 1 transid 4790 /dev/sdb1
[    3.811983] vboxsf: loading out-of-tree module taints kernel.
[    4.009067] random: crng init done
[    4.009080] random: 7 urandom warning(s) missed due to ratelimiting
[    4.020238] snd_intel8x0 0000:00:05.0: intel8x0_measure_ac97_clock: measured 58895 usecs (14336 samples)
[    4.020242] snd_intel8x0 0000:00:05.0: measured clock 243416 rejected
[    4.099036] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 08:00:27:b4:cd:7d
[    4.099043] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[    4.111316] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    4.415332] snd_intel8x0 0000:00:05.0: intel8x0_measure_ac97_clock: measured 57377 usecs (14336 samples)
[    4.415338] snd_intel8x0 0000:00:05.0: measured clock 249856 rejected
[    4.838448] snd_intel8x0 0000:00:05.0: intel8x0_measure_ac97_clock: measured 47261 usecs (11264 samples)
[    4.838452] snd_intel8x0 0000:00:05.0: measured clock 238336 rejected
[    4.838454] snd_intel8x0 0000:00:05.0: clocking to 48000
[    6.299323] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[    6.299751] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   10.588498] audit: type=1006 audit(1536222772.771:2): pid=319 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=1 res=1
[   10.653278] audit: type=1006 audit(1536222772.834:3): pid=322 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=2 res=1
[   10.799356] systemd-journald[216]: File /var/log/journal/d8b7d823fa4b4e219a8c84bf6d1fe82c/user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
[  141.213745] usb 2-1: USB disconnect, device number 2
[  239.329476] audit: type=1006 audit(1536223001.473:4): pid=510 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=3 res=1
[  239.353422] audit: type=1006 audit(1536223001.497:5): pid=513 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=4 res=1
[  260.645105] audit: type=1006 audit(1536223022.793:6): pid=528 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=5 res=1
[  260.666712] audit: type=1006 audit(1536223022.816:7): pid=531 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=6 res=1
[  285.729540] audit: type=1006 audit(1536223047.883:8): pid=2951 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=7 res=1
[  285.750233] audit: type=1006 audit(1536223047.900:9): pid=2954 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=8 res=1
[  302.217171] BTRFS info (device sdb1): disk space caching is enabled
[  302.217173] BTRFS info (device sdb1): has skinny extents
[  631.223843] audit: type=1006 audit(1536223393.390:10): pid=3029 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=9 res=1

^ permalink raw reply	[flat|nested] 17+ messages in thread
end of thread, other threads:[~2018-09-10  7:23 UTC | newest]

Thread overview: 17+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2018-09-06  9:22 btrfs send hung in pipe_wait Stefan Löwen
2018-09-06 15:04 ` Stefan Loewen
2018-09-06 15:48   ` Chris Murphy
2018-09-06 16:03     ` Stefan Löwen
2018-09-06 18:16       ` Chris Murphy
2018-09-06 18:36         ` Stefan Loewen
2018-09-06 19:58           ` Chris Murphy
2018-09-06 20:16             ` Stefan Loewen
2018-09-07  3:29               ` Chris Murphy
2018-09-07 12:47                 ` Stefan Loewen
2018-09-07 15:44                   ` Chris Murphy
2018-09-07 17:07                     ` Stefan Loewen
2018-09-07 19:17                       ` Chris Murphy
     [not found]                         ` <CAHTTHimT7m+S4bm1OgZOfmFkk69fc1SPGEvidxwFCHniKL-w6A@mail.gmail.com>
2018-09-08  9:45                           ` Fwd: " Stefan Loewen
2018-09-09  2:31                             ` Chris Murphy
     [not found]                               ` <CAHTTHinSJy6c7jV1pApeQgnGwMHjd9DEutqxc-T5XjKVbeh1SA@mail.gmail.com>
2018-09-09 23:29                                 ` Chris Murphy
     [not found]                           ` <CAJCQCtQBwvvbYR3u=EGbRR=rsnBaZK5F=mso3SE_kPwtcXyvHg@mail.gmail.com>
2018-09-08  9:47                             ` Fwd: " Stefan Loewen

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.