LKML Archive on lore.kernel.org
help / color / mirror / Atom feed
* r8169: Link only up after 16 s (A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.)
@ 2019-05-23 11:00 Paul Menzel
  2019-05-23 17:44 ` Heiner Kallweit
  0 siblings, 1 reply; 5+ messages in thread
From: Paul Menzel @ 2019-05-23 11:00 UTC (permalink / raw)
  To: Realtek linux nic maintainers, Heiner Kallweit
  Cc: netdev, Linux Kernel Mailing List


[-- Attachment #1.1: Type: text/plain, Size: 1940 bytes --]

Dear Linux folks,


I optimized the Linux kernel configuration on my ASRock E350M1, and it now
boots really fast.

Unfortunately, that seems to cause the network driver to hit some corner
case, so that the link is supposedly down, although it should be up. The
cable is plugged in the whole time.

```
[    2.990757] libphy: r8169: probed
[    2.992661] r8169 0000:03:00.0 eth0: RTL8168e/8111e, bc:5f:f4:c8:d3:98, XID 2c2, IRQ 28
[    2.992669] r8169 0000:03:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[    3.294484] usb 5-2: new low-speed USB device number 2 using ohci-pci
[    3.458711] usb 5-2: New USB device found, idVendor=1241, idProduct=1122, bcdDevice= 1.00
[    3.458718] usb 5-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.485065] input: HID 1241:1122 as /devices/pci0000:00/0000:00:12.0/usb5/5-2/5-2:1.0/0003:1241:1122.0001/input/input14
[    3.485320] hid-generic 0003:1241:1122.0001: input,hidraw0: USB HID v1.00 Mouse [HID 1241:1122] on usb-0000:00:12.0-2/input0
[    3.967622] random: crng init done
[    3.967628] random: 7 urandom warning(s) missed due to ratelimiting
[    4.323449] r8169 0000:03:00.0 enp3s0: renamed from eth0
[    4.363774] RTL8211DN Gigabit Ethernet r8169-300:00: attached PHY driver [RTL8211DN Gigabit Ethernet] (mii_bus:phy_addr=r8169-300:00, irq=IGNORE)
[    4.576887] r8169 0000:03:00.0 enp3s0: Link is Down
[    4.577167] A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.
[   16.377520] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx
[   16.377553] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0: link becomes ready
```

It happens with all Linux kernels I tried. Please find all Linux
messages attached.

Could you please tell me, how this can be debugged and solved?


Kind regards,

Paul

[-- Attachment #1.2: 20190516--asrock-e350m1-dmesg-r8169.txt --]
[-- Type: text/plain, Size: 49638 bytes --]

[    0.000000] Linux version 5.1.0-10952-gd19283a8021c (root@ee888abba79b) (gcc version 8.3.0 (Debian 8.3.0-7)) #39 SMP Wed May 15 22:38:33 UTC 2019
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    0.000000] BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x0000000000000fff] type 16
[    0.000000] BIOS-e820: [mem 0x0000000000001000-0x000000000009ffff] usable
[    0.000000] BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000c7d47fff] usable
[    0.000000] BIOS-e820: [mem 0x00000000c7d48000-0x00000000c7ffffff] type 16
[    0.000000] BIOS-e820: [mem 0x00000000c8000000-0x00000000dfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000f8000000-0x00000000fbffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000011effffff] usable
[    0.000000] kmemleak: Kernel memory leak detector disabled
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.7 present.
[    0.000000] DMI: ASROCK E350M1/E350M1, BIOS 4.9-1671-g6644a75b0e 05/16/2019
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] tsc: Detected 1599.936 MHz processor
[    0.006000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.006006] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.006015] last_pfn = 0x11f000 max_arch_pfn = 0x1000000
[    0.006026] MTRR default type: uncachable
[    0.006028] MTRR fixed ranges enabled:
[    0.006031]   00000-9FFFF write-back
[    0.006034]   A0000-BFFFF uncachable
[    0.006036]   C0000-FFFFF write-back
[    0.006038] MTRR variable ranges enabled:
[    0.006042]   0 base 0FFC00000 mask FFFC00000 write-protect
[    0.006045]   1 base 0C0000000 mask FF8000000 write-back
[    0.006047]   2 base 080000000 mask FC0000000 write-back
[    0.006050]   3 base 000000000 mask F80000000 write-back
[    0.006051]   4 disabled
[    0.006052]   5 disabled
[    0.006054]   6 disabled
[    0.006055]   7 disabled
[    0.006058] TOM2: 000000011f000000 aka 4592M
[    0.006259] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.006556] check: Scanning 1 areas for low memory corruption
[    0.006561] initial memory mapped: [mem 0x00000000-0x163fffff]
[    0.006721] BRK [0x15ee5000, 0x15ee5fff] PGTABLE
[    0.006743] BRK [0x15ee6000, 0x15ee6fff] PGTABLE
[    0.006756] ACPI: Early table checksum verification disabled
[    0.006882] ACPI: RSDP 0x00000000000F0800 000024 (v02 COREv4)
[    0.006889] ACPI: XSDT 0x00000000C7D490E0 000064 (v01 COREv4 COREBOOT 00000000 CORE 20190509)
[    0.006903] ACPI: FACP 0x00000000C7D4B840 0000F4 (v04 COREv4 COREBOOT 00000000 CORE 20190509)
[    0.006916] ACPI: DSDT 0x00000000C7D49280 0025B6 (v02 COREv4 COREBOOT 00010001 INTL 20190509)
[    0.006924] ACPI: FACS 0x00000000C7D49240 000040
[    0.006929] ACPI: FACS 0x00000000C7D49240 000040
[    0.006934] ACPI: SSDT 0x00000000C7D4B940 00008A (v02 COREv4 COREBOOT 0000002A CORE 20190509)
[    0.006941] ACPI: APIC 0x00000000C7D4B9D0 00005C (v02 COREv4 COREBOOT 00000000 CORE 20190509)
[    0.006947] ACPI: HEST 0x00000000C7D4BA30 000028 (v01 COREv4 COREBOOT 00000000 CORE 20190509)
[    0.006953] ACPI: SSDT 0x00000000C7D4BA60 00168E (v02 AMD    ALIB     00000001 MSFT 04000000)
[    0.006960] ACPI: SSDT 0x00000000C7D4D0F0 0003DE (v01 AMD    POWERNOW 00000001 AMD  00000001)
[    0.006967] ACPI: VFCT 0x00000000C7D4D4D0 00E269 (v01 COREv4 COREBOOT 00000000 CORE 20190509)
[    0.006973] ACPI: HPET 0x00000000C7D5B740 000038 (v01 COREv4 COREBOOT 00000000 CORE 20190509)
[    0.006992] ACPI: Local APIC address 0xfee00000
[    0.007000] 3706MB HIGHMEM available.
[    0.007004] 885MB LOWMEM available.
[    0.007006]   mapped low ram: 0 - 375fe000
[    0.007007]   low ram: 0 - 375fe000
[    0.007022] BRK [0x15ee7000, 0x15ee7fff] PGTABLE
[    0.017911] Zone ranges:
[    0.017915]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.017919]   Normal   [mem 0x0000000001000000-0x00000000375fdfff]
[    0.017922]   HighMem  [mem 0x00000000375fe000-0x000000011effffff]
[    0.017924] Movable zone start for each node
[    0.017925] Early memory node ranges
[    0.017928]   node   0: [mem 0x0000000000001000-0x000000000009ffff]
[    0.017930]   node   0: [mem 0x0000000000100000-0x00000000c7d47fff]
[    0.017932]   node   0: [mem 0x0000000100000000-0x000000011effffff]
[    0.022084] Zeroed struct page in unavailable ranges: 99097 pages
[    0.022089] Initmem setup node 0 [mem 0x0000000000001000-0x000000011effffff]
[    0.022095] On node 0 totalpages: 945383
[    0.022098]   DMA zone: 40 pages used for memmap
[    0.022099]   DMA zone: 0 pages reserved
[    0.022101]   DMA zone: 3999 pages, LIFO batch:0
[    0.022314]   Normal zone: 2175 pages used for memmap
[    0.022315]   Normal zone: 222718 pages, LIFO batch:63
[    0.033674]   HighMem zone: 718666 pages, LIFO batch:63
[    0.076252] Using APIC driver default
[    0.076530] ACPI: Local APIC address 0xfee00000
[    0.076555] IOAPIC[0]: apic_id 2, version 33, address 0xfec00000, GSI 0-23
[    0.076562] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.076566] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
[    0.076569] ACPI: IRQ0 used by override.
[    0.076572] ACPI: IRQ9 used by override.
[    0.076576] Using ACPI (MADT) for SMP configuration information
[    0.076580] ACPI: HPET id: 0x43538210 base: 0xfed00000
[    0.076592] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.076618] [mem 0xe0000000-0xf7ffffff] available for PCI devices
[    0.076626] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.076630] After setup_arch
[    0.076631] After mm_init_cpumask
[    0.076642] After setup_command_line
[    0.076643] After setup_nr_cpu_ids
[    0.076646] setup_percpu: NR_CPUS:2 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    0.076909] percpu: Embedded 33 pages/cpu s102668 r0 d32500 u135168
[    0.076922] pcpu-alloc: s102668 r0 d32500 u135168 alloc=33*4096
[    0.076924] pcpu-alloc: [0] 0 [0] 1 
[    0.076951] After setup_per_cpu_areas
[    0.076952] After smp_perpare_boot_cpu
[    0.076954] After boot_cpu_hotplug_init
[    0.076960] Built 1 zonelists, mobility grouping on.  Total pages: 943168
[    0.076965] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-5.1.0-10952-gd19283a8021c root=/dev/sda1 rw noisapnp cryptomgr.notests quiet memory_corruption_check=1 memory_corruption_check_size=512k memory_corruption_check_period=5 iomem=relaxed apparmor=0 selinux=0 kmemleak=off initcall_blacklist=serial8250_init udev.children_max=2 udev.log_priority=info
[    0.077355] blacklisting initcall serial8250_init
[    0.077710] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.077884] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.077894] Initializing CPU#0
[    0.113006] Initializing HighMem for node 0 (000375fe:0011f000)
[    0.113011] add_highpages_with_active_regions: start_pfn = 226814, end_pfn = 1175552
[    0.113015] add_highpages_with_active_regions: for_each_free_mem_range: i = 8589934592, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113019] add_highpages_with_active_regions: for_each_free_mem_range: i = 12884901889, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113021] add_highpages_with_active_regions: for_each_free_mem_range: i = 17179869185, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113024] add_highpages_with_active_regions: for_each_free_mem_range: i = 21474836481, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113026] add_highpages_with_active_regions: for_each_free_mem_range: i = 25769803777, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113029] add_highpages_with_active_regions: for_each_free_mem_range: i = 30064771073, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113031] add_highpages_with_active_regions: for_each_free_mem_range: i = 34359738369, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113034] add_highpages_with_active_regions: for_each_free_mem_range: i = 38654705665, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113036] add_highpages_with_active_regions: for_each_free_mem_range: i = 42949672961, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113039] add_highpages_with_active_regions: for_each_free_mem_range: i = 47244640257, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113041] add_highpages_with_active_regions: for_each_free_mem_range: i = 51539607553, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113043] add_highpages_with_active_regions: for_each_free_mem_range: i = 55834574849, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113046] add_highpages_with_active_regions: for_each_free_mem_range: i = 60129542145, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113049] add_highpages_with_active_regions: for_each_free_mem_range: i = 64424509441, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113051] add_highpages_with_active_regions: for_each_free_mem_range: i = 68719476737, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113054] add_highpages_with_active_regions: for_each_free_mem_range: i = 73014444033, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113056] add_highpages_with_active_regions: for_each_free_mem_range: i = 77309411329, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113059] add_highpages_with_active_regions: for_each_free_mem_range: i = 81604378625, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113061] add_highpages_with_active_regions: for_each_free_mem_range: i = 85899345921, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113063] add_highpages_with_active_regions: for_each_free_mem_range: i = 90194313217, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[    0.113066] add_highpages_with_active_regions: for_each_free_mem_range: i = 90194313218, nid = 0, pfn = 226814, e_pfn = 818504, before for loop
[    0.299829] add_highpages_with_active_regions: for_each_free_mem_range: i = 90194313219, nid = 0, pfn = 1048576, e_pfn = 1175552, before for loop
[    0.339904] Initializing Movable for node 0 (00000000:00000000)
[    0.339908] add_highpages_with_active_regions: start_pfn = 0, end_pfn = 0
[    0.339911] add_highpages_with_active_regions: for_each_free_mem_range: i = 8589934592, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339914] add_highpages_with_active_regions: for_each_free_mem_range: i = 12884901889, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339917] add_highpages_with_active_regions: for_each_free_mem_range: i = 17179869185, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339919] add_highpages_with_active_regions: for_each_free_mem_range: i = 21474836481, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339922] add_highpages_with_active_regions: for_each_free_mem_range: i = 25769803777, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339924] add_highpages_with_active_regions: for_each_free_mem_range: i = 30064771073, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339926] add_highpages_with_active_regions: for_each_free_mem_range: i = 34359738369, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339929] add_highpages_with_active_regions: for_each_free_mem_range: i = 38654705665, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339931] add_highpages_with_active_regions: for_each_free_mem_range: i = 42949672961, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339934] add_highpages_with_active_regions: for_each_free_mem_range: i = 47244640257, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339936] add_highpages_with_active_regions: for_each_free_mem_range: i = 51539607553, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339938] add_highpages_with_active_regions: for_each_free_mem_range: i = 55834574849, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339941] add_highpages_with_active_regions: for_each_free_mem_range: i = 60129542145, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339943] add_highpages_with_active_regions: for_each_free_mem_range: i = 64424509441, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339945] add_highpages_with_active_regions: for_each_free_mem_range: i = 68719476737, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339948] add_highpages_with_active_regions: for_each_free_mem_range: i = 73014444033, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339950] add_highpages_with_active_regions: for_each_free_mem_range: i = 77309411329, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339953] add_highpages_with_active_regions: for_each_free_mem_range: i = 81604378625, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339955] add_highpages_with_active_regions: for_each_free_mem_range: i = 85899345921, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339958] add_highpages_with_active_regions: for_each_free_mem_range: i = 90194313217, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339960] add_highpages_with_active_regions: for_each_free_mem_range: i = 90194313218, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.339963] add_highpages_with_active_regions: for_each_free_mem_range: i = 90194313219, nid = 0, pfn = 0, e_pfn = 0, before for loop
[    0.346229] Memory: 3645508K/3781532K available (8957K kernel code, 746K rwdata, 2908K rodata, 1144K init, 640K bss, 136024K reserved, 0K cma-reserved, 2874664K highmem)
[    0.346241] virtual kernel memory layout:
                   fixmap  : 0xfff91000 - 0xfffff000   ( 440 kB)
                 cpu_entry : 0xffa00000 - 0xffa4d000   ( 308 kB)
                   pkmap   : 0xff600000 - 0xff800000   (2048 kB)
                   vmalloc : 0xf7dfe000 - 0xff5fe000   ( 120 MB)
                   lowmem  : 0xc0000000 - 0xf75fe000   ( 885 MB)
                     .init : 0xd5c6b000 - 0xd5d89000   (1144 kB)
                     .data : 0xd58bf498 - 0xd5c58ac0   (3685 kB)
                     .text : 0xd5000000 - 0xd58bf498   (8957 kB)
[    0.346242] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.346393] random: get_random_u32 called from cache_random_seq_create+0x7e/0x110 with crng_init=0
[    0.346670] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.346688] After mm_init
[    0.346690] ftrace: allocating 34238 entries in 67 pages
[    0.373291] After sched_init
[    0.373351] rcu: Hierarchical RCU implementation.
[    0.373352] rcu: 	RCU event tracing is enabled.
[    0.373357] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    0.373367] After rcu_init
[    0.380615] NR_IRQS: 2304, nr_irqs: 440, preallocated irqs: 16
[    0.381182] After add_latent_entropy
[    0.381203] After add_device_randomness
[    0.381207] After boot_init_stack_canary
[    0.383698] Console: colour VGA+ 80x25
[    0.383712] printk: console [tty0] enabled
[    0.383759] ACPI: Core revision 20190509
[    0.384335] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484873504 ns
[    0.384355] hpet clockevent registered
[    0.384365] APIC: Switch to symmetric I/O mode setup
[    0.384367] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.384876] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=0 pin2=0
[    0.389436] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x170fe898b51, max_idle_ns: 440795260846 ns
[    0.389447] Calibrating delay loop (skipped), value calculated using timer frequency.. 3199.87 BogoMIPS (lpj=1599936)
[    0.389453] pid_max: default: 32768 minimum: 301
[    0.389543] LSM: Security Framework initializing
[    0.389582] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.389590] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.389914] *** VALIDATE proc ***
[    0.390023] *** VALIDATE cgroup1 ***
[    0.390027] *** VALIDATE cgroup2 ***
[    0.390124] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 4
[    0.390126] Last level dTLB entries: 4KB 512, 2MB 8, 4MB 4, 1GB 0
[    0.390133] Spectre V2 : Mitigation: Full AMD retpoline
[    0.390134] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.390136] Speculative Store Bypass: Vulnerable
[    0.390375] Freeing SMP alternatives memory: 32K
[    0.390378] After check_bugs
[    0.390382] After acpi_subsystem_init
[    0.390383] After arch_post_acpi_subsys_init
[    0.390384] After sfi_init_late
[    0.390386] After rcu_scheduler_starting
[    0.390531] After find_task_by_pid_ns
[    0.390534] After numa_default_policy
[    0.390570] After rcu_read_lock
[    0.390571] After rcu_read_unlock
[    0.390577] After kthreadd_done
[    0.391440] random: 1
[    0.391440] random: 2
[    0.391440] random: 3
[    0.391440] random: 4
[    0.410443] random: 1
[    0.410444] random: 2
[    0.410445] random: 3
[    0.410446] random: 4
[    0.474443] random: 1
[    0.474444] random: 2
[    0.474445] random: 3
[    0.474446] random: 4
[    0.492440] smpboot: CPU0: AMD E-350D APU with Radeon(tm) HD Graphics (family: 0x14, model: 0x2, stepping: 0x0)
[    0.492440] Performance Events: AMD PMU driver.
[    0.492440] ... version:                0
[    0.492440] ... bit width:              48
[    0.492440] ... generic registers:      4
[    0.492440] ... value mask:             0000ffffffffffff
[    0.492440] ... max period:             00007fffffffffff
[    0.492440] ... fixed-purpose events:   0
[    0.492440] ... event mask:             000000000000000f
[    0.492440] rcu: Hierarchical SRCU implementation.
[    0.492638] smp: Bringing up secondary CPUs ...
[    0.492897] x86: Booting SMP configuration:
[    0.492900] .... node  #0, CPUs:      #1
[    0.004344] Initializing CPU#1
[    0.493496] After schedule_preempt_disabled
[    0.493518] smp: Brought up 1 node, 2 CPUs
[    0.493518] smpboot: Max logical packages: 1
[    0.493518] smpboot: Total of 2 processors activated (6399.74 BogoMIPS)
[    0.495553] devtmpfs: initialized
[    0.496453] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.496453] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.496453] pinctrl core: initialized pinctrl subsystem
[    0.496453] PM: RTC time: 07:16:03, date: 2019-05-16
[    0.496621] NET: Registered protocol family 16
[    0.497068] audit: initializing netlink subsys (disabled)
[    0.497496] audit: type=2000 audit(1557990963.113:1): state=initialized audit_enabled=0 res=1
[    0.497704] cpuidle: using governor menu
[    0.497748] ACPI: bus type PCI registered
[    0.499017] PCI: Using configuration type 1 for base access
[    0.499019] PCI: Using configuration type 1 for extended access
[    0.499413] mtrr: your CPUs had inconsistent variable MTRR settings
[    0.499414] mtrr: probably your BIOS does not setup all CPUs.
[    0.499415] mtrr: corrected configuration.
[    0.503544] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.504502] alg: self-tests disabled
[    0.506483] cryptd: max_cpu_qlen set to 1000
[    0.508625] ACPI: Added _OSI(Module Device)
[    0.508628] ACPI: Added _OSI(Processor Device)
[    0.508630] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.508632] ACPI: Added _OSI(Processor Aggregator Device)
[    0.508635] ACPI: Added _OSI(Linux-Dell-Video)
[    0.508637] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.508640] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    0.519774] ACPI: 4 ACPI AML tables successfully acquired and loaded
[    0.522855] ACPI: Interpreter enabled
[    0.522903] ACPI: (supports S0 S1 S3 S5)
[    0.522906] ACPI: Using IOAPIC for interrupt routing
[    0.522946] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.523371] ACPI: Enabled 19 GPEs in block 00 to 1F
[    0.533361] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.533376] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
[    0.533544] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability LTR]
[    0.533986] PCI host bridge to bus 0000:00
[    0.533992] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.533995] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.533999] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[    0.534002] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xffffffff]
[    0.534006] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.534025] pci 0000:00:00.0: [1022:1510] type 00 class 0x060000
[    0.534305] pci 0000:00:01.0: [1002:9802] type 00 class 0x030000
[    0.534329] pci 0000:00:01.0: reg 0x10: [mem 0xe0000000-0xefffffff pref]
[    0.534339] pci 0000:00:01.0: reg 0x14: [io  0x2000-0x20ff]
[    0.534349] pci 0000:00:01.0: reg 0x18: [mem 0xf0100000-0xf013ffff]
[    0.534381] pci 0000:00:01.0: enabling Extended Tags
[    0.534432] pci 0000:00:01.0: supports D1 D2
[    0.534678] pci 0000:00:01.1: [1002:1314] type 00 class 0x040300
[    0.534699] pci 0000:00:01.1: reg 0x10: [mem 0xf0140000-0xf0143fff]
[    0.534741] pci 0000:00:01.1: enabling Extended Tags
[    0.534790] pci 0000:00:01.1: supports D1 D2
[    0.535118] pci 0000:00:11.0: [1002:4391] type 00 class 0x010601
[    0.535149] pci 0000:00:11.0: reg 0x10: [io  0x2410-0x2417]
[    0.535162] pci 0000:00:11.0: reg 0x14: [io  0x2420-0x2423]
[    0.535175] pci 0000:00:11.0: reg 0x18: [io  0x2418-0x241f]
[    0.535188] pci 0000:00:11.0: reg 0x1c: [io  0x2424-0x2427]
[    0.535201] pci 0000:00:11.0: reg 0x20: [io  0x2400-0x240f]
[    0.535215] pci 0000:00:11.0: reg 0x24: [mem 0xf014b000-0xf014b3ff]
[    0.535565] pci 0000:00:12.0: [1002:4397] type 00 class 0x0c0310
[    0.535591] pci 0000:00:12.0: reg 0x10: [mem 0xf0148000-0xf0148fff]
[    0.535961] pci 0000:00:12.2: [1002:4396] type 00 class 0x0c0320
[    0.535991] pci 0000:00:12.2: reg 0x10: [mem 0xf014c000-0xf014c0ff]
[    0.536103] pci 0000:00:12.2: supports D1 D2
[    0.536107] pci 0000:00:12.2: PME# supported from D0 D1 D2 D3hot
[    0.536109] pci 0000:00:12.2: pme_poll = true
[    0.536111] pci 0000:00:12.2: after device_set_wakeup_capable()
[    0.536117] pci 0000:00:12.2: after pci_pme_active()
[    0.536417] pci 0000:00:13.0: [1002:4397] type 00 class 0x0c0310
[    0.536450] pci 0000:00:13.0: reg 0x10: [mem 0xf0149000-0xf0149fff]
[    0.536817] pci 0000:00:13.2: [1002:4396] type 00 class 0x0c0320
[    0.536847] pci 0000:00:13.2: reg 0x10: [mem 0xf014d000-0xf014d0ff]
[    0.536959] pci 0000:00:13.2: supports D1 D2
[    0.536962] pci 0000:00:13.2: PME# supported from D0 D1 D2 D3hot
[    0.536964] pci 0000:00:13.2: pme_poll = true
[    0.536966] pci 0000:00:13.2: after device_set_wakeup_capable()
[    0.536971] pci 0000:00:13.2: after pci_pme_active()
[    0.537275] pci 0000:00:14.0: [1002:4385] type 00 class 0x0c0500
[    0.537661] pci 0000:00:14.2: [1002:4383] type 00 class 0x040300
[    0.537696] pci 0000:00:14.2: reg 0x10: [mem 0xf0144000-0xf0147fff 64bit]
[    0.537791] pci 0000:00:14.2: PME# supported from D0 D3hot D3cold
[    0.537793] pci 0000:00:14.2: pme_poll = true
[    0.537795] pci 0000:00:14.2: after device_set_wakeup_capable()
[    0.537801] pci 0000:00:14.2: after pci_pme_active()
[    0.538093] pci 0000:00:14.3: [1002:439d] type 00 class 0x060100
[    0.538456] pci 0000:00:14.4: [1002:4384] type 01 class 0x060401
[    0.538812] pci 0000:00:14.5: [1002:4399] type 00 class 0x0c0310
[    0.538838] pci 0000:00:14.5: reg 0x10: [mem 0xf014a000-0xf014afff]
[    0.539213] pci 0000:00:15.0: [1002:43a0] type 01 class 0x060400
[    0.539275] pci 0000:00:15.0: enabling Extended Tags
[    0.539350] pci 0000:00:15.0: supports D1 D2
[    0.539704] pci 0000:00:15.1: [1002:43a1] type 01 class 0x060400
[    0.539772] pci 0000:00:15.1: enabling Extended Tags
[    0.539848] pci 0000:00:15.1: supports D1 D2
[    0.540175] pci 0000:00:18.0: [1022:1700] type 00 class 0x060000
[    0.540422] pci 0000:00:18.1: [1022:1701] type 00 class 0x060000
[    0.540667] pci 0000:00:18.2: [1022:1702] type 00 class 0x060000
[    0.540907] pci 0000:00:18.3: [1022:1703] type 00 class 0x060000
[    0.541170] pci 0000:00:18.4: [1022:1704] type 00 class 0x060000
[    0.541406] pci 0000:00:18.5: [1022:1718] type 00 class 0x060000
[    0.541653] pci 0000:00:18.6: [1022:1716] type 00 class 0x060000
[    0.541890] pci 0000:00:18.7: [1022:1719] type 00 class 0x060000
[    0.542219] pci_bus 0000:01: extended config space not accessible
[    0.542318] pci 0000:00:14.4: PCI bridge to [bus 01] (subtractive decode)
[    0.542333] pci 0000:00:14.4:   bridge window [io  0x0000-0x0cf7 window] (subtractive decode)
[    0.542336] pci 0000:00:14.4:   bridge window [io  0x0d00-0xffff window] (subtractive decode)
[    0.542339] pci 0000:00:14.4:   bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
[    0.542342] pci 0000:00:14.4:   bridge window [mem 0xe0000000-0xffffffff] (subtractive decode)
[    0.542423] pci 0000:00:15.0: PCI bridge to [bus 02]
[    0.542552] pci 0000:03:00.0: [10ec:8168] type 00 class 0x020000
[    0.542594] pci 0000:03:00.0: reg 0x10: [io  0x1000-0x10ff]
[    0.542633] pci 0000:03:00.0: reg 0x18: [mem 0xf0004000-0xf0004fff 64bit pref]
[    0.542658] pci 0000:03:00.0: reg 0x20: [mem 0xf0000000-0xf0003fff 64bit pref]
[    0.542809] pci 0000:03:00.0: supports D1 D2
[    0.542812] pci 0000:03:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.542814] pci 0000:03:00.0: pme_poll = true
[    0.542816] pci 0000:03:00.0: after device_set_wakeup_capable()
[    0.542823] pci 0000:03:00.0: after pci_pme_active()
[    0.546557] pci 0000:00:15.1: PCI bridge to [bus 03]
[    0.546577] pci 0000:00:15.1:   bridge window [io  0x1000-0x1fff]
[    0.546595] pci 0000:00:15.1:   bridge window [mem 0xf0000000-0xf00fffff 64bit pref]
[    0.546639] pci_bus 0000:00: on NUMA node 0
[    0.548235] ACPI: PCI Interrupt Link [INTA] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.548594] ACPI: PCI Interrupt Link [INTB] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.548907] ACPI: PCI Interrupt Link [INTC] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.549220] ACPI: PCI Interrupt Link [INTD] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.549479] ACPI: PCI Interrupt Link [INTE] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.549793] ACPI: PCI Interrupt Link [INTF] (IRQs 9) *0, disabled.
[    0.550101] ACPI: PCI Interrupt Link [INTG] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.550479] ACPI: PCI Interrupt Link [INTH] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.551500] pci 0000:00:01.0: vgaarb: setting as boot VGA device
[    0.551500] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.551515] pci 0000:00:01.0: vgaarb: bridge control possible
[    0.551518] vgaarb: loaded
[    0.551771] SCSI subsystem initialized
[    0.551852] libata version 3.00 loaded.
[    0.551852] ACPI: bus type USB registered
[    0.551852] usbcore: registered new interface driver usbfs
[    0.551852] usbcore: registered new interface driver hub
[    0.551852] usbcore: registered new device driver usb
[    0.552489] PCI: Using ACPI for IRQ routing
[    0.552493] PCI: pci_cache_line_size set to 64 bytes
[    0.552651] e820: reserve RAM buffer [mem 0xc7d48000-0xc7ffffff]
[    0.552655] e820: reserve RAM buffer [mem 0x11f000000-0x11fffffff]
[    0.552918] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.552918] hpet0: 3 comparators, 32-bit 14.318180 MHz counter
[    0.555521] clocksource: Switched to clocksource tsc-early
[    0.586553] VFS: Disk quotas dquot_6.6.0
[    0.586596] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.586673] *** VALIDATE hugetlbfs ***
[    0.586760] pnp: PnP ACPI init
[    0.587567] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.587672] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 PNP030b (active)
[    0.587761] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.588359] pnp 00:03: Plug and Play ACPI device, IDs PNP0303 PNP030b (active)
[    0.588436] pnp 00:04: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.588466] pnp: PnP ACPI: found 5 devices
[    0.593653] pci 0000:00:14.4: PCI bridge to [bus 01]
[    0.593745] pci 0000:00:15.0: PCI bridge to [bus 02]
[    0.593760] pci 0000:00:15.1: PCI bridge to [bus 03]
[    0.593766] pci 0000:00:15.1:   bridge window [io  0x1000-0x1fff]
[    0.593777] pci 0000:00:15.1:   bridge window [mem 0xf0000000-0xf00fffff 64bit pref]
[    0.593790] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.593793] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.593796] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff]
[    0.593799] pci_bus 0000:00: resource 7 [mem 0xe0000000-0xffffffff]
[    0.593803] pci_bus 0000:01: resource 4 [io  0x0000-0x0cf7 window]
[    0.593806] pci_bus 0000:01: resource 5 [io  0x0d00-0xffff window]
[    0.593809] pci_bus 0000:01: resource 6 [mem 0x000a0000-0x000bffff]
[    0.593812] pci_bus 0000:01: resource 7 [mem 0xe0000000-0xffffffff]
[    0.593815] pci_bus 0000:03: resource 0 [io  0x1000-0x1fff]
[    0.593818] pci_bus 0000:03: resource 2 [mem 0xf0000000-0xf00fffff 64bit pref]
[    0.594020] NET: Registered protocol family 2
[    0.594347] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[    0.594364] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.594393] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.594430] TCP: Hash tables configured (established 8192 bind 8192)
[    0.594492] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.594505] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.594610] NET: Registered protocol family 1
[    0.594651] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.597742] PCI: CLS 64 bytes, default 64
[    0.597862] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.597866] software IO TLB: mapped [mem 0x2f9fd000-0x339fd000] (64MB)
[    0.598146] LVT offset 0 assigned for vector 0x400
[    0.598202] perf: AMD IBS detected (0x000000ff)
[    0.598809] check: Scanning for low memory corruption every 5 seconds
[    0.600102] Initialise system trusted keyrings
[    0.600357] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[    0.603881] zbud: loaded
[    0.604731] NET: Registered protocol family 38
[    0.604743] Key type asymmetric registered
[    0.604746] Asymmetric key parser 'x509' registered
[    0.604775] bounce: pool size: 64 pages
[    0.604796] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.604930] io scheduler mq-deadline registered
[    0.604934] io scheduler kyber registered
[    0.605910] pcieport 0000:00:15.0: PME: Signaling with IRQ 24
[    0.606592] pcieport 0000:00:15.1: PME: Signaling with IRQ 25
[    0.606848] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
[    0.606889] ACPI: Power Button [PWRB]
[    0.606979] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[    0.607001] ACPI: Power Button [PWRF]
[    0.608796] thermal LNXTHERM:00: registered as thermal_zone0
[    0.608801] ACPI: Thermal Zone [TZ00] (45 C)
[    0.609140] initcall serial8250_init blacklisted
[    0.609803] Non-volatile memory driver v1.3
[    0.610114] Linux agpgart interface v0.103
[    0.610803] [drm] radeon kernel modesetting enabled.
[    0.610838] radeon 0000:00:01.0: remove_conflicting_pci_framebuffers: bar 0: 0xe0000000 -> 0xefffffff
[    0.610842] radeon 0000:00:01.0: remove_conflicting_pci_framebuffers: bar 2: 0xf0100000 -> 0xf013ffff
[    0.610846] radeon 0000:00:01.0: vgaarb: deactivate vga console
[    0.611689] Console: switching to colour dummy device 80x25
[    0.612886] [drm] initializing kernel modesetting (PALM 0x1002:0x9802 0x1002:0x9802 0x00).
[    0.612961] ATOM BIOS: AMD
[    0.613066] radeon 0000:00:01.0: VRAM: 384M 0x0000000000000000 - 0x0000000017FFFFFF (384M used)
[    0.613071] radeon 0000:00:01.0: GTT: 1024M 0x0000000018000000 - 0x0000000057FFFFFF
[    0.613079] [drm] Detected VRAM RAM=384M, BAR=256M
[    0.613081] [drm] RAM width 32bits DDR
[    0.613399] [TTM] Zone  kernel: Available graphics memory: 385438 KiB
[    0.613403] [TTM] Zone highmem: Available graphics memory: 1822770 KiB
[    0.613405] [TTM] Initializing pool allocator
[    0.613414] [TTM] Initializing DMA pool allocator
[    0.613453] [drm] radeon: 384M of VRAM memory ready
[    0.613455] [drm] radeon: 1024M of GTT memory ready.
[    0.613477] [drm] Loading PALM Microcode
[    0.613485] [drm] Internal thermal controller without fan control
[    0.613570] [drm] Found smc ucode version: 0x00010601
[    0.613640] [drm] radeon: dpm initialized
[    0.613682] [drm] GART: num cpu pages 262144, num gpu pages 262144
[    0.631373] [drm] GART: Restore entries: num cpu pages 262144, num gpu pages 262144
[    0.636501] [drm] GART: Done restoring entries
[    0.636511] [drm] PCIE GART of 1024M enabled (table at 0x0000000000162000).
[    0.636702] radeon 0000:00:01.0: WB enabled
[    0.636710] radeon 0000:00:01.0: fence driver on ring 0 use gpu addr 0x0000000018000c00 and cpu addr 0x(ptrval)
[    0.636714] radeon 0000:00:01.0: fence driver on ring 3 use gpu addr 0x0000000018000c0c and cpu addr 0x(ptrval)
[    0.637590] radeon 0000:00:01.0: fence driver on ring 5 use gpu addr 0x0000000000072118 and cpu addr 0x(ptrval)
[    0.637594] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    0.637595] [drm] Driver supports precise vblank timestamp query.
[    0.637598] radeon 0000:00:01.0: radeon: MSI limited to 32-bit
[    0.637680] radeon 0000:00:01.0: radeon: using MSI.
[    0.637720] [drm] radeon: irq initialized.
[    0.654339] [drm] ring test on 0 succeeded in 1 usecs
[    0.654348] [drm] ring test on 3 succeeded in 3 usecs
[    0.668410] [drm] ring test on 5 succeeded in 1 usecs
[    0.670429] [drm] UVD initialized successfully.
[    0.670663] [drm] ib test on ring 0 succeeded in 0 usecs
[    0.670721] [drm] ib test on ring 3 succeeded in 0 usecs
[    0.683410] [drm] ib test on ring 5 succeeded
[    0.686143] [drm] Radeon Display Connectors
[    0.686145] [drm] Connector 0:
[    0.686146] [drm]   HDMI-A-1
[    0.686147] [drm]   HPD1
[    0.686151] [drm]   DDC: 0x6430 0x6430 0x6434 0x6434 0x6438 0x6438 0x643c 0x643c
[    0.686152] [drm]   Encoders:
[    0.686154] [drm]     DFP1: INTERNAL_UNIPHY
[    0.686155] [drm] Connector 1:
[    0.686156] [drm]   HDMI-A-2
[    0.686157] [drm]   HPD2
[    0.686160] [drm]   DDC: 0x6440 0x6440 0x6444 0x6444 0x6448 0x6448 0x644c 0x644c
[    0.686161] [drm]   Encoders:
[    0.686162] [drm]     DFP2: INTERNAL_UNIPHY
[    0.686163] [drm] Connector 2:
[    0.686164] [drm]   VGA-1
[    0.686167] [drm]   DDC: 0x64d8 0x64d8 0x64dc 0x64dc 0x64e0 0x64e0 0x64e4 0x64e4
[    0.686167] [drm]   Encoders:
[    0.686169] [drm]     CRT1: INTERNAL_KLDSCP_DAC1
[    0.727331] [drm] fb mappable at 0xE0366000
[    0.727335] [drm] vram apper at 0xE0000000
[    0.727336] [drm] size 5242880
[    0.727337] [drm] fb depth is 24
[    0.727339] [drm]    pitch is 5120
[    0.727749] fbcon: radeondrmfb (fb0) is primary device
[    0.749851] Console: switching to colour frame buffer device 160x64
[    0.760649] radeon 0000:00:01.0: fb0: radeondrmfb frame buffer device
[    0.760785] [drm] Initialized radeon 2.50.0 20080528 for 0000:00:01.0 on minor 0
[    0.761621] ahci 0000:00:11.0: version 3.0
[    0.762391] ahci 0000:00:11.0: AHCI 0001.0200 32 slots 6 ports 6 Gbps 0x3f impl SATA mode
[    0.762397] ahci 0000:00:11.0: flags: 64bit ncq sntf ilck led clo pmp pio slum part 
[    0.764868] scsi host0: ahci
[    0.765529] scsi host1: ahci
[    0.766021] scsi host2: ahci
[    0.766507] scsi host3: ahci
[    0.767039] scsi host4: ahci
[    0.767665] scsi host5: ahci
[    0.767957] ata1: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b100 irq 19
[    0.767973] ata2: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b180 irq 19
[    0.767977] ata3: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b200 irq 19
[    0.767980] ata4: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b280 irq 19
[    0.767984] ata5: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b300 irq 19
[    0.767987] ata6: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b380 irq 19
[    0.768188] libphy: Fixed MDIO Bus: probed
[    0.768261] usbcore: registered new interface driver usbserial_generic
[    0.768273] usbserial: USB Serial support registered for generic
[    0.768325] ata2: SATA link down (SStatus 0 SControl 0)
[    0.768366] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[    0.768497] ata3: SATA link down (SStatus 0 SControl 0)
[    0.768701] ata6: SATA link down (SStatus 0 SControl 0)
[    0.770279] ata4: SATA link down (SStatus 0 SControl 0)
[    0.770338] ata5: SATA link down (SStatus 0 SControl 0)
[    0.772567] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.772597] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.773094] mousedev: PS/2 mouse device common for all mice
[    0.773886] rtc_cmos 00:00: RTC can wake from S4
[    0.774272] rtc_cmos 00:00: registered as rtc0
[    0.774314] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 0)
[    0.774328] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[    0.774379] w83627ehf: Found NCT6775F chip at 0x290
[    0.774626] ata1.00: ATA-9: SanDisk SDSSDP064G, 2.0.0, max UDMA/133
[    0.774630] ata1.00: 125045424 sectors, multi 1: LBA48 NCQ (depth 32)
[    0.774899] w83627ehf w83627ehf.656: hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info().
[    0.775038] device-mapper: uevent: version 1.0.3
[    0.775146] ata1.00: configured for UDMA/133
[    0.775768] device-mapper: ioctl: 4.40.0-ioctl (2019-01-18) initialised: dm-devel@redhat.com
[    0.775918] hidraw: raw HID events driver (C) Jiri Kosina
[    0.775932] scsi 0:0:0:0: Direct-Access     ATA      SanDisk SDSSDP06 0    PQ: 0 ANSI: 5
[    0.776050] usbcore: registered new interface driver usbhid
[    0.776051] usbhid: USB HID core driver
[    0.776415] Initializing XFRM netlink socket
[    0.776671] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    0.776744] NET: Registered protocol family 10
[    0.777085] sd 0:0:0:0: [sda] 125045424 512-byte logical blocks: (64.0 GB/59.6 GiB)
[    0.777215] sd 0:0:0:0: [sda] Write Protect is off
[    0.777221] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    0.777470] Segment Routing with IPv6
[    0.777539] mip6: Mobile IPv6
[    0.777545] NET: Registered protocol family 17
[    0.777569] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    0.778176] mce: Using 6 MCE banks
[    0.778214] Using IPI No-Shortcut mode
[    0.778235] sched_clock: Marking stable (774614318, 3344553)->(882101848, -104142977)
[    0.778916]  sda: sda1
[    0.778929] registered taskstats version 1
[    0.778934] Loading compiled-in X.509 certificates
[    0.778998] zswap: loaded using pool lzo/zbud
[    0.779637] PM:   Magic number: 3:655:268
[    0.779697] sd 0:0:0:0: [sda] Attached SCSI disk
[    0.779892] rtc_cmos 00:00: setting system clock to 2019-05-16T07:16:03 UTC (1557990963)
[    0.801697] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
[    0.867946] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[    0.867990] VFS: Mounted root (ext4 filesystem) on device 8:1.
[    0.868889] random: 1
[    0.868895] random: 2
[    0.868898] random: 3
[    0.868899] random: 4
[    0.868904] random: fast init done
[    0.870164] devtmpfs: mounted
[    0.870199] After kernel_init_freeable
[    0.872355] Freeing unused kernel image memory: 1144K
[    0.875560] Write protecting kernel text and read-only data: 11896k
[    0.875563] NX-protecting the kernel data: 5376k
[    0.876330] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    0.876331] After mark_readonly
[    0.876338] After pti_finalize
[    0.876339] rcu_end_inkernel_boot
[    0.876342] Run /sbin/init as init process
[    1.191254] systemd[1]: systemd 241 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)
[    1.204174] systemd[1]: Detected architecture x86.
[    1.208428] systemd[1]: Set hostname to <kodi>.
[    1.210017] systemd[1]: Failed to bump fs.file-max, ignoring: Invalid argument
[    1.417524] systemd[1]: File /lib/systemd/system/systemd-journald.service:11 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling.
[    1.417534] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
[    1.632561] tsc: Refined TSC clocksource calibration: 1600.000 MHz
[    1.632579] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x171024fa93b, max_idle_ns: 440795253189 ns
[    1.632610] clocksource: Switched to clocksource tsc
[    1.647292] random: systemd: uninitialized urandom read (16 bytes read)
[    1.650086] random: systemd: uninitialized urandom read (16 bytes read)
[    1.650134] systemd[1]: Reached target Network.
[    1.650338] random: systemd: uninitialized urandom read (16 bytes read)
[    1.652519] systemd[1]: Created slice system-getty.slice.
[    1.652732] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    1.653225] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    2.235797] acpi_cpufreq: overriding BIOS provided _PSD data
[    2.539992] 1
[    2.540018] 2
[    2.540357] 3
[    2.540358] 4
[    2.540358] 5
[    2.540359] 7
[    2.540389] 8
[    2.540389] 9
[    2.540740] 1
[    2.540758] 2
[    2.541120] 3
[    2.541121] 4
[    2.541122] 5
[    2.541122] 7
[    2.541127] 8
[    2.541128] 9
[    2.571595] snd_hda_codec_realtek hdaudioC1D0: ALC892: SKU not ready 0x00000100
[    2.572192] snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC892: line_outs=4 (0x14/0x16/0x15/0x17/0x0) type:line
[    2.572196] snd_hda_codec_realtek hdaudioC1D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[    2.572205] snd_hda_codec_realtek hdaudioC1D0:    hp_outs=1 (0x1b/0x0/0x0/0x0/0x0)
[    2.572208] snd_hda_codec_realtek hdaudioC1D0:    mono: mono_out=0x0
[    2.572211] snd_hda_codec_realtek hdaudioC1D0:    dig-out=0x1e/0x0
[    2.572213] snd_hda_codec_realtek hdaudioC1D0:    inputs:
[    2.572218] snd_hda_codec_realtek hdaudioC1D0:      Rear Mic=0x18
[    2.572222] snd_hda_codec_realtek hdaudioC1D0:      Front Mic=0x19
[    2.572225] snd_hda_codec_realtek hdaudioC1D0:      Line=0x1a
[    2.572227] snd_hda_codec_realtek hdaudioC1D0:      CD=0x1c
[    2.577978] input: HD-Audio Generic HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.1/sound/card0/input5
[    2.615956] input: HDA ATI SB Rear Mic as /devices/pci0000:00/0000:00:14.2/sound/card1/input6
[    2.616105] input: HDA ATI SB Front Mic as /devices/pci0000:00/0000:00:14.2/sound/card1/input7
[    2.616324] input: HDA ATI SB Line as /devices/pci0000:00/0000:00:14.2/sound/card1/input8
[    2.617963] input: HDA ATI SB Line Out Front as /devices/pci0000:00/0000:00:14.2/sound/card1/input9
[    2.618186] input: HDA ATI SB Line Out Surround as /devices/pci0000:00/0000:00:14.2/sound/card1/input10
[    2.618392] input: HDA ATI SB Line Out CLFE as /devices/pci0000:00/0000:00:14.2/sound/card1/input11
[    2.619055] input: HDA ATI SB Line Out Side as /devices/pci0000:00/0000:00:14.2/sound/card1/input12
[    2.619210] input: HDA ATI SB Front Headphone as /devices/pci0000:00/0000:00:14.2/sound/card1/input13
[    2.786284] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.788828] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.790064] ehci-pci: EHCI PCI platform driver
[    2.791011] QUIRK: Enable AMD PLL fix
[    2.791015] QUIRK: Enable AMD PLL fix
[    2.791142] ehci-pci 0000:00:13.2: EHCI Host Controller
[    2.791161] ehci-pci 0000:00:13.2: new USB bus registered, assigned bus number 1
[    2.791175] ehci-pci 0000:00:13.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[    2.791259] ehci-pci 0000:00:13.2: debug port 1
[    2.791349] ehci-pci 0000:00:13.2: irq 17, io mem 0xf014d000
[    2.798393] ehci-pci 0000:00:13.2: USB 2.0 started, EHCI 1.00
[    2.799304] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.01
[    2.799309] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.799313] usb usb1: Product: EHCI Host Controller
[    2.799316] usb usb1: Manufacturer: Linux 5.1.0-10952-gd19283a8021c ehci_hcd
[    2.799319] usb usb1: SerialNumber: 0000:00:13.2
[    2.799684] hub 1-0:1.0: USB hub found
[    2.799710] hub 1-0:1.0: 5 ports detected
[    2.801527] ehci-pci 0000:00:12.2: EHCI Host Controller
[    2.801553] ehci-pci 0000:00:12.2: new USB bus registered, assigned bus number 2
[    2.801568] ehci-pci 0000:00:12.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[    2.801655] ehci-pci 0000:00:12.2: debug port 1
[    2.801721] ehci-pci 0000:00:12.2: irq 17, io mem 0xf014c000
[    2.808977] ehci-pci 0000:00:12.2: USB 2.0 started, EHCI 1.00
[    2.809277] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.01
[    2.809281] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.809284] usb usb2: Product: EHCI Host Controller
[    2.809288] usb usb2: Manufacturer: Linux 5.1.0-10952-gd19283a8021c ehci_hcd
[    2.809290] usb usb2: SerialNumber: 0000:00:12.2
[    2.809675] hub 2-0:1.0: USB hub found
[    2.809699] hub 2-0:1.0: 5 ports detected
[    2.810550] ohci-pci: OHCI PCI platform driver
[    2.811841] ohci-pci 0000:00:13.0: OHCI PCI host controller
[    2.811860] ohci-pci 0000:00:13.0: new USB bus registered, assigned bus number 3
[    2.812018] ohci-pci 0000:00:13.0: irq 18, io mem 0xf0149000
[    2.812335] ohci-pci 0000:00:14.5: OHCI PCI host controller
[    2.812362] ohci-pci 0000:00:14.5: new USB bus registered, assigned bus number 4
[    2.812539] ohci-pci 0000:00:14.5: irq 18, io mem 0xf014a000
[    2.813353] ohci-pci 0000:00:12.0: OHCI PCI host controller
[    2.813370] ohci-pci 0000:00:12.0: new USB bus registered, assigned bus number 5
[    2.813420] ohci-pci 0000:00:12.0: irq 18, io mem 0xf0148000
[    2.875934] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.01
[    2.875940] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.875943] usb usb5: Product: OHCI PCI host controller
[    2.875947] usb usb5: Manufacturer: Linux 5.1.0-10952-gd19283a8021c ohci_hcd
[    2.875949] usb usb5: SerialNumber: 0000:00:12.0
[    2.876465] hub 5-0:1.0: USB hub found
[    2.876584] hub 5-0:1.0: 5 ports detected
[    2.877043] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.01
[    2.877046] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.877049] usb usb3: Product: OHCI PCI host controller
[    2.877051] usb usb3: Manufacturer: Linux 5.1.0-10952-gd19283a8021c ohci_hcd
[    2.877054] usb usb3: SerialNumber: 0000:00:13.0
[    2.877298] hub 3-0:1.0: USB hub found
[    2.877319] hub 3-0:1.0: 5 ports detected
[    2.877720] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.01
[    2.877724] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.877726] usb usb4: Product: OHCI PCI host controller
[    2.877729] usb usb4: Manufacturer: Linux 5.1.0-10952-gd19283a8021c ohci_hcd
[    2.877731] usb usb4: SerialNumber: 0000:00:14.5
[    2.877920] hub 4-0:1.0: USB hub found
[    2.878007] hub 4-0:1.0: 2 ports detected
[    2.892129] piix4_smbus 0000:00:14.0: SMBus Host Controller at 0xb00, revision 0
[    2.892145] piix4_smbus 0000:00:14.0: Using register 0x2c for SMBus port selection
[    2.892639] piix4_smbus 0000:00:14.0: Auxiliary SMBus Host Controller at 0x8060
[    2.990757] libphy: r8169: probed
[    2.992661] r8169 0000:03:00.0 eth0: RTL8168e/8111e, bc:5f:f4:c8:d3:98, XID 2c2, IRQ 28
[    2.992669] r8169 0000:03:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[    3.294484] usb 5-2: new low-speed USB device number 2 using ohci-pci
[    3.458711] usb 5-2: New USB device found, idVendor=1241, idProduct=1122, bcdDevice= 1.00
[    3.458718] usb 5-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.485065] input: HID 1241:1122 as /devices/pci0000:00/0000:00:12.0/usb5/5-2/5-2:1.0/0003:1241:1122.0001/input/input14
[    3.485320] hid-generic 0003:1241:1122.0001: input,hidraw0: USB HID v1.00 Mouse [HID 1241:1122] on usb-0000:00:12.0-2/input0
[    3.967622] random: crng init done
[    3.967628] random: 7 urandom warning(s) missed due to ratelimiting
[    4.323449] r8169 0000:03:00.0 enp3s0: renamed from eth0
[    4.363774] RTL8211DN Gigabit Ethernet r8169-300:00: attached PHY driver [RTL8211DN Gigabit Ethernet] (mii_bus:phy_addr=r8169-300:00, irq=IGNORE)
[    4.576887] r8169 0000:03:00.0 enp3s0: Link is Down
[    4.577167] A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.
[   16.377520] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx
[   16.377553] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0: link becomes ready

[-- Attachment #2: S/MIME Cryptographic Signature --]
[-- Type: application/pkcs7-signature, Size: 5174 bytes --]

^ permalink raw reply	[flat|nested] 5+ messages in thread

* Re: r8169: Link only up after 16 s (A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.)
  2019-05-23 11:00 r8169: Link only up after 16 s (A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.) Paul Menzel
@ 2019-05-23 17:44 ` Heiner Kallweit
  2019-05-24 15:14   ` Paul Menzel
  0 siblings, 1 reply; 5+ messages in thread
From: Heiner Kallweit @ 2019-05-23 17:44 UTC (permalink / raw)
  To: Paul Menzel, Realtek linux nic maintainers
  Cc: netdev, Linux Kernel Mailing List

On 23.05.2019 13:00, Paul Menzel wrote:
> Dear Linux folks,
> 
> 
> I optimized the Linux kernel configuration on my ASRock E350M1, and it now
> boots really fast.
> 
> Unfortunately, that seems to cause the network driver to hit some corner
> case, so that the link is supposedly down, although it should be up. The
> cable is plugged in the whole time.
> 
> ```
> [    2.990757] libphy: r8169: probed
> [    2.992661] r8169 0000:03:00.0 eth0: RTL8168e/8111e, bc:5f:f4:c8:d3:98, XID 2c2, IRQ 28
> [    2.992669] r8169 0000:03:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
> [    3.294484] usb 5-2: new low-speed USB device number 2 using ohci-pci
> [    3.458711] usb 5-2: New USB device found, idVendor=1241, idProduct=1122, bcdDevice= 1.00
> [    3.458718] usb 5-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
> [    3.485065] input: HID 1241:1122 as /devices/pci0000:00/0000:00:12.0/usb5/5-2/5-2:1.0/0003:1241:1122.0001/input/input14
> [    3.485320] hid-generic 0003:1241:1122.0001: input,hidraw0: USB HID v1.00 Mouse [HID 1241:1122] on usb-0000:00:12.0-2/input0
> [    3.967622] random: crng init done
> [    3.967628] random: 7 urandom warning(s) missed due to ratelimiting
> [    4.323449] r8169 0000:03:00.0 enp3s0: renamed from eth0
> [    4.363774] RTL8211DN Gigabit Ethernet r8169-300:00: attached PHY driver [RTL8211DN Gigabit Ethernet] (mii_bus:phy_addr=r8169-300:00, irq=IGNORE)
> [    4.576887] r8169 0000:03:00.0 enp3s0: Link is Down
> [    4.577167] A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.
> [   16.377520] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx
> [   16.377553] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0: link becomes ready
> ```
> 
> It happens with all Linux kernels I tried. Please find all Linux
> messages attached.
> 
> Could you please tell me, how this can be debugged and solved?
> 
This warning is triggered by errors in do_setlink() in net/core/rtnetlink.c
I'd say:
1. Which kernel config options did you change as part of the optimization?
   (If I understand you correctly the warning didn't pop up before.)
2. Try to find out which call in do_setlink() fails and which errno is returned.

> 
> Kind regards,
> 
> Paul
> 
Heiner

^ permalink raw reply	[flat|nested] 5+ messages in thread

* Re: r8169: Link only up after 16 s (A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.)
  2019-05-23 17:44 ` Heiner Kallweit
@ 2019-05-24 15:14   ` Paul Menzel
  2019-05-24 17:55     ` Heiner Kallweit
  0 siblings, 1 reply; 5+ messages in thread
From: Paul Menzel @ 2019-05-24 15:14 UTC (permalink / raw)
  To: Heiner Kallweit, Realtek linux nic maintainers
  Cc: netdev, Linux Kernel Mailing List


[-- Attachment #1.1: Type: text/plain, Size: 3604 bytes --]

Dear Heiner,


Thank you for the quick reply.

On 05/23/19 19:44, Heiner Kallweit wrote:
> On 23.05.2019 13:00, Paul Menzel wrote:

>> I optimized the Linux kernel configuration on my ASRock E350M1, and it now
>> boots really fast.
>>
>> Unfortunately, that seems to cause the network driver to hit some corner
>> case, so that the link is supposedly down, although it should be up. The
>> cable is plugged in the whole time.
>>
>> ```
>> [    2.990757] libphy: r8169: probed
>> [    2.992661] r8169 0000:03:00.0 eth0: RTL8168e/8111e, bc:5f:f4:c8:d3:98, XID 2c2, IRQ 28
>> [    2.992669] r8169 0000:03:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
>> [    3.294484] usb 5-2: new low-speed USB device number 2 using ohci-pci
>> [    3.458711] usb 5-2: New USB device found, idVendor=1241, idProduct=1122, bcdDevice= 1.00
>> [    3.458718] usb 5-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
>> [    3.485065] input: HID 1241:1122 as /devices/pci0000:00/0000:00:12.0/usb5/5-2/5-2:1.0/0003:1241:1122.0001/input/input14
>> [    3.485320] hid-generic 0003:1241:1122.0001: input,hidraw0: USB HID v1.00 Mouse [HID 1241:1122] on usb-0000:00:12.0-2/input0
>> [    3.967622] random: crng init done
>> [    3.967628] random: 7 urandom warning(s) missed due to ratelimiting
>> [    4.323449] r8169 0000:03:00.0 enp3s0: renamed from eth0
>> [    4.363774] RTL8211DN Gigabit Ethernet r8169-300:00: attached PHY driver [RTL8211DN Gigabit Ethernet] (mii_bus:phy_addr=r8169-300:00, irq=IGNORE)
>> [    4.576887] r8169 0000:03:00.0 enp3s0: Link is Down
>> [    4.577167] A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.
>> [   16.377520] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx
>> [   16.377553] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0: link becomes ready
>> ```
>>
>> It happens with all Linux kernels I tried. Please find all Linux
>> messages attached.
>>
>> Could you please tell me, how this can be debugged and solved?
>>
> This warning is triggered by errors in do_setlink() in net/core/rtnetlink.c
> I'd say:
> 1. Which kernel config options did you change as part of the optimization?
>    (If I understand you correctly the warning didn't pop up before.)

Sorry for being unclear. The same problem happens with Debian
Sid/unstable’s default Linux kernel 4.19.0-5 (4.19.37) [1]. With the fast
boot (six seconds from pressing power button to Weston) I just noticed, that
the network was not set up when wanting to use it.

> 2. Try to find out which call in do_setlink() fails and which errno
> is returned.

Yeah, that’s where I need help. ;-)

I applied the simple change below to `net/core/rtnetlink.c`.

                if (err < 0)
-                       net_warn_ratelimited("A link change request failed with some changes committed already. Interface %s may have been left with an inconsistent configuration, please check.\n",
-                                            dev->name);
+                       net_warn_ratelimited("A link change request failed with some changes committed already (err = %i). Interface %s may have been left with an inconsistent configuration, please check.\n",
+                                            dev->name, err);

I get different results each time.

-304123904
-332128256

Any idea, how that can happen?

Is there a better way to debug `do_setlink()` in `rtnetlink.c` than
printf debugging?


Kind regards,

Paul

[-- Attachment #1.2: 20190524--asrock-e350m1-linux-4.19.0-5-dmesg-r8169.txt --]
[-- Type: text/plain, Size: 118493 bytes --]

[    0.428964] calling  apparmor_init+0x0/0x25a @ 0
[    0.428967] AppArmor: AppArmor disabled by boot time parameter
[    0.428971] initcall apparmor_init+0x0/0x25a returned 0 after 0 usecs
[    0.428974] calling  integrity_iintcache_init+0x0/0x2c @ 0
[    0.428986] initcall integrity_iintcache_init+0x0/0x2c returned 0 after 0 usecs
[    0.429018] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.429026] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.429476] mce: CPU supports 6 MCE banks
[    0.429501] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 4
[    0.429503] Last level dTLB entries: 4KB 512, 2MB 8, 4MB 4, 1GB 0
[    0.429511] Spectre V2 : Mitigation: Full AMD retpoline
[    0.429513] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.429515] Speculative Store Bypass: Vulnerable
[    0.429794] Freeing SMP alternatives memory: 24K
[    0.432816] smpboot: CPU0: AMD E-350D APU with Radeon(tm) HD Graphics (family: 0x14, model: 0x2, stepping: 0x0)
[    0.432816] calling  trace_init_flags_sys_enter+0x0/0x13 @ 1
[    0.432816] initcall trace_init_flags_sys_enter+0x0/0x13 returned 0 after 0 usecs
[    0.432816] calling  trace_init_flags_sys_exit+0x0/0x13 @ 1
[    0.432816] initcall trace_init_flags_sys_exit+0x0/0x13 returned 0 after 0 usecs
[    0.432816] calling  init_hw_perf_events+0x0/0x58d @ 1
[    0.432816] Performance Events: AMD PMU driver.
[    0.432816] ... version:                0
[    0.432816] ... bit width:              48
[    0.432816] ... generic registers:      4
[    0.432816] ... value mask:             0000ffffffffffff
[    0.432816] ... max period:             00007fffffffffff
[    0.432816] ... fixed-purpose events:   0
[    0.432816] ... event mask:             000000000000000f
[    0.432816] initcall init_hw_perf_events+0x0/0x58d returned 0 after 0 usecs
[    0.432816] calling  init_real_mode+0x0/0x108 @ 1
[    0.432816] initcall init_real_mode+0x0/0x108 returned 0 after 0 usecs
[    0.432816] calling  trace_init_perf_perm_irq_work_exit+0x0/0x16 @ 1
[    0.432816] initcall trace_init_perf_perm_irq_work_exit+0x0/0x16 returned 0 after 0 usecs
[    0.432816] calling  validate_x2apic+0x0/0x4f @ 1
[    0.432816] initcall validate_x2apic+0x0/0x4f returned 0 after 0 usecs
[    0.432816] calling  register_nmi_cpu_backtrace_handler+0x0/0x18 @ 1
[    0.432816] initcall register_nmi_cpu_backtrace_handler+0x0/0x18 returned 0 after 0 usecs
[    0.432816] calling  kvm_setup_vsyscall_timeinfo+0x0/0x82 @ 1
[    0.432816] initcall kvm_setup_vsyscall_timeinfo+0x0/0x82 returned 0 after 0 usecs
[    0.432816] calling  early_efi_map_fb+0x0/0x3a @ 1
[    0.432816] initcall early_efi_map_fb+0x0/0x3a returned 0 after 0 usecs
[    0.432816] calling  spawn_ksoftirqd+0x0/0x39 @ 1
[    0.432816] initcall spawn_ksoftirqd+0x0/0x39 returned 0 after 0 usecs
[    0.432816] calling  migration_init+0x0/0x31 @ 1
[    0.432816] initcall migration_init+0x0/0x31 returned 0 after 0 usecs
[    0.432816] calling  check_cpu_stall_init+0x0/0x1b @ 1
[    0.432816] initcall check_cpu_stall_init+0x0/0x1b returned 0 after 0 usecs
[    0.432816] calling  srcu_bootup_announce+0x0/0x30 @ 1
[    0.432816] rcu: Hierarchical SRCU implementation.
[    0.432816] initcall srcu_bootup_announce+0x0/0x30 returned 0 after 0 usecs
[    0.432816] calling  rcu_spawn_gp_kthread+0x0/0xfc @ 1
[    0.432816] initcall rcu_spawn_gp_kthread+0x0/0xfc returned 0 after 0 usecs
[    0.432816] calling  cpu_stop_init+0x0/0x78 @ 1
[    0.432816] initcall cpu_stop_init+0x0/0x78 returned 0 after 0 usecs
[    0.432816] calling  init_events+0x0/0x3a @ 1
[    0.432816] initcall init_events+0x0/0x3a returned 0 after 0 usecs
[    0.432816] calling  init_trace_printk+0x0/0xf @ 1
[    0.432816] initcall init_trace_printk+0x0/0xf returned 0 after 0 usecs
[    0.432816] calling  event_trace_enable_again+0x0/0x21 @ 1
[    0.432816] initcall event_trace_enable_again+0x0/0x21 returned 0 after 0 usecs
[    0.432816] calling  jump_label_init_module+0x0/0x14 @ 1
[    0.432816] initcall jump_label_init_module+0x0/0x14 returned 0 after 0 usecs
[    0.432816] calling  dynamic_debug_init+0x0/0x1f7 @ 1
[    0.432816] initcall dynamic_debug_init+0x0/0x1f7 returned 0 after 0 usecs
[    0.432816] calling  rand_initialize+0x0/0x120 @ 1
[    0.432816] initcall rand_initialize+0x0/0x120 returned 0 after 0 usecs
[    0.432816] calling  initialize_ptr_random+0x0/0x47 @ 1
[    0.432816] initcall initialize_ptr_random+0x0/0x47 returned 0 after 0 usecs
[    0.432816] NMI watchdog: Enabled. Permanently consumes one hw-PMU counter.
[    0.432816] smp: Bringing up secondary CPUs ...
[    0.432816] CPU 1 irqstacks, hard=(ptrval) soft=(ptrval)
[    0.432816] x86: Booting SMP configuration:
[    0.432816] .... node  #0, CPUs:      #1
[    0.006779] Initializing CPU#1
[    0.432973] smp: Brought up 1 node, 2 CPUs
[    0.432973] smpboot: Max logical packages: 1
[    0.432973] smpboot: Total of 2 processors activated (6400.40 BogoMIPS)
[    0.433615] devtmpfs: initialized
[    0.433615] calling  ipc_ns_init+0x0/0x48 @ 1
[    0.433615] initcall ipc_ns_init+0x0/0x48 returned 0 after 0 usecs
[    0.433615] calling  init_mmap_min_addr+0x0/0x16 @ 1
[    0.433615] initcall init_mmap_min_addr+0x0/0x16 returned 0 after 0 usecs
[    0.433615] calling  net_ns_init+0x0/0xdc @ 1
[    0.433615] initcall net_ns_init+0x0/0xdc returned 0 after 0 usecs
[    0.433615] calling  xen_pvh_gnttab_setup+0x0/0x34 @ 1
[    0.433615] initcall xen_pvh_gnttab_setup+0x0/0x34 returned -19 after 0 usecs
[    0.433615] calling  e820__register_nvs_regions+0x0/0x3e @ 1
[    0.433615] initcall e820__register_nvs_regions+0x0/0x3e returned 0 after 0 usecs
[    0.433615] calling  cpufreq_register_tsc_scaling+0x0/0x2a @ 1
[    0.433615] initcall cpufreq_register_tsc_scaling+0x0/0x2a returned 0 after 0 usecs
[    0.433615] calling  init_cpu_syscore+0x0/0x11 @ 1
[    0.433615] initcall init_cpu_syscore+0x0/0x11 returned 0 after 0 usecs
[    0.433615] calling  reboot_init+0x0/0x42 @ 1
[    0.433615] initcall reboot_init+0x0/0x42 returned 0 after 0 usecs
[    0.433615] calling  init_lapic_sysfs+0x0/0x24 @ 1
[    0.433615] initcall init_lapic_sysfs+0x0/0x24 returned 0 after 0 usecs
[    0.433615] calling  pgd_cache_init+0x0/0x3e @ 1
[    0.433615] initcall pgd_cache_init+0x0/0x3e returned 0 after 0 usecs
[    0.433615] calling  alloc_frozen_cpus+0x0/0xc @ 1
[    0.433615] initcall alloc_frozen_cpus+0x0/0xc returned 0 after 0 usecs
[    0.433615] calling  cpu_hotplug_pm_sync_init+0x0/0x16 @ 1
[    0.433615] initcall cpu_hotplug_pm_sync_init+0x0/0x16 returned 0 after 0 usecs
[    0.433615] calling  wq_sysfs_init+0x0/0x29 @ 1
[    0.433615] initcall wq_sysfs_init+0x0/0x29 returned 0 after 0 usecs
[    0.433615] calling  ksysfs_init+0x0/0x7d @ 1
[    0.433615] initcall ksysfs_init+0x0/0x7d returned 0 after 0 usecs
[    0.433615] calling  pm_init+0x0/0x6d @ 1
[    0.433615] initcall pm_init+0x0/0x6d returned 0 after 0 usecs
[    0.433615] calling  pm_disk_init+0x0/0x19 @ 1
[    0.433615] initcall pm_disk_init+0x0/0x19 returned 0 after 0 usecs
[    0.433615] calling  swsusp_header_init+0x0/0x30 @ 1
[    0.433615] initcall swsusp_header_init+0x0/0x30 returned 0 after 0 usecs
[    0.433615] calling  rcu_set_runtime_mode+0x0/0x16 @ 1
[    0.433615] initcall rcu_set_runtime_mode+0x0/0x16 returned 0 after 0 usecs
[    0.433615] calling  init_jiffies_clocksource+0x0/0x1b @ 1
[    0.433615] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.433615] initcall init_jiffies_clocksource+0x0/0x1b returned 0 after 0 usecs
[    0.433615] calling  futex_init+0x0/0xd2 @ 1
[    0.433615] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.433615] initcall futex_init+0x0/0xd2 returned 0 after 0 usecs
[    0.433615] calling  cgroup_wq_init+0x0/0x2c @ 1
[    0.433615] initcall cgroup_wq_init+0x0/0x2c returned 0 after 0 usecs
[    0.433615] calling  cgroup1_wq_init+0x0/0x2c @ 1
[    0.433615] initcall cgroup1_wq_init+0x0/0x2c returned 0 after 0 usecs
[    0.433615] calling  ftrace_mod_cmd_init+0x0/0xf @ 1
[    0.433615] initcall ftrace_mod_cmd_init+0x0/0xf returned 0 after 0 usecs
[    0.433615] calling  init_graph_trace+0x0/0x5a @ 1
[    0.433615] initcall init_graph_trace+0x0/0x5a returned 0 after 0 usecs
[    0.433615] calling  init_per_zone_wmark_min+0x0/0x66 @ 1
[    0.433615] initcall init_per_zone_wmark_min+0x0/0x66 returned 0 after 0 usecs
[    0.433615] calling  init_zero_pfn+0x0/0x25 @ 1
[    0.433615] initcall init_zero_pfn+0x0/0x25 returned 0 after 0 usecs
[    0.433615] calling  memory_failure_init+0x0/0x84 @ 1
[    0.433615] initcall memory_failure_init+0x0/0x84 returned 0 after 0 usecs
[    0.433615] calling  fsnotify_init+0x0/0x44 @ 1
[    0.433615] initcall fsnotify_init+0x0/0x44 returned 0 after 0 usecs
[    0.433615] calling  filelock_init+0x0/0x7f @ 1
[    0.433615] initcall filelock_init+0x0/0x7f returned 0 after 0 usecs
[    0.433615] calling  init_script_binfmt+0x0/0x18 @ 1
[    0.433615] initcall init_script_binfmt+0x0/0x18 returned 0 after 0 usecs
[    0.433615] calling  init_elf_binfmt+0x0/0x18 @ 1
[    0.433615] initcall init_elf_binfmt+0x0/0x18 returned 0 after 0 usecs
[    0.433615] calling  debugfs_init+0x0/0x4b @ 1
[    0.433615] initcall debugfs_init+0x0/0x4b returned 0 after 0 usecs
[    0.433615] calling  tracefs_init+0x0/0x39 @ 1
[    0.433615] initcall tracefs_init+0x0/0x39 returned 0 after 0 usecs
[    0.433615] calling  securityfs_init+0x0/0x63 @ 1
[    0.433615] initcall securityfs_init+0x0/0x63 returned 0 after 0 usecs
[    0.433615] calling  prandom_init+0x0/0xb1 @ 1
[    0.433615] initcall prandom_init+0x0/0xb1 returned 0 after 0 usecs
[    0.433615] calling  pinctrl_init+0x0/0x9d @ 1
[    0.433615] pinctrl core: initialized pinctrl subsystem
[    0.433615] initcall pinctrl_init+0x0/0x9d returned 0 after 0 usecs
[    0.433617] calling  gpiolib_dev_init+0x0/0xb3 @ 1
[    0.433637] initcall gpiolib_dev_init+0x0/0xb3 returned 0 after 0 usecs
[    0.433641] calling  sfi_sysfs_init+0x0/0xbe @ 1
[    0.433645] initcall sfi_sysfs_init+0x0/0xbe returned 0 after 0 usecs
[    0.433649] calling  regulator_init+0x0/0x7e @ 1
[    0.433780] probe of reg-dummy returned 1 after 0 usecs
[    0.433794] initcall regulator_init+0x0/0x7e returned 0 after 0 usecs
[    0.433797] calling  component_debug_init+0x0/0x1d @ 1
[    0.433804] initcall component_debug_init+0x0/0x1d returned 0 after 0 usecs
[    0.433808] calling  opp_debug_init+0x0/0x39 @ 1
[    0.433813] initcall opp_debug_init+0x0/0x39 returned 0 after 0 usecs
[    0.433817] calling  cpufreq_core_init+0x0/0x46 @ 1
[    0.433824] initcall cpufreq_core_init+0x0/0x46 returned 0 after 0 usecs
[    0.433828] calling  cpuidle_init+0x0/0x3b @ 1
[    0.433836] initcall cpuidle_init+0x0/0x3b returned 0 after 0 usecs
[    0.433841] calling  capsule_reboot_register+0x0/0x14 @ 1
[    0.433845] initcall capsule_reboot_register+0x0/0x14 returned 0 after 0 usecs
[    0.433849] calling  bsp_pm_check_init+0x0/0x16 @ 1
[    0.433853] initcall bsp_pm_check_init+0x0/0x16 returned 0 after 0 usecs
[    0.433858] calling  sock_init+0x0/0x8e @ 1
[    0.434004] initcall sock_init+0x0/0x8e returned 0 after 0 usecs
[    0.434008] calling  net_inuse_init+0x0/0x24 @ 1
[    0.434015] initcall net_inuse_init+0x0/0x24 returned 0 after 0 usecs
[    0.434018] calling  net_defaults_init+0x0/0x24 @ 1
[    0.434021] initcall net_defaults_init+0x0/0x24 returned 0 after 0 usecs
[    0.434025] calling  init_default_flow_dissectors+0x0/0x48 @ 1
[    0.434029] initcall init_default_flow_dissectors+0x0/0x48 returned 0 after 0 usecs
[    0.434032] calling  netpoll_init+0x0/0x34 @ 1
[    0.434035] initcall netpoll_init+0x0/0x34 returned 0 after 0 usecs
[    0.434038] calling  netlink_proto_init+0x0/0x123 @ 1
[    0.434080] NET: Registered protocol family 16
[    0.434104] initcall netlink_proto_init+0x0/0x123 returned 0 after 0 usecs
[    0.434111] calling  __gnttab_init+0x0/0x40 @ 1
[    0.434115] initcall __gnttab_init+0x0/0x40 returned -19 after 0 usecs
[    0.434279] calling  irq_sysfs_init+0x0/0x7e @ 1
[    0.434429] initcall irq_sysfs_init+0x0/0x7e returned 0 after 0 usecs
[    0.434433] calling  audit_init+0x0/0x166 @ 1
[    0.434440] audit: initializing netlink subsys (disabled)
[    0.436880] initcall audit_init+0x0/0x166 returned 0 after 3906 usecs
[    0.436875] audit: type=2000 audit(1558684602.028:1): state=initialized audit_enabled=0 res=1
[    0.436898] calling  release_early_probes+0x0/0x36 @ 1
[    0.436903] initcall release_early_probes+0x0/0x36 returned 0 after 0 usecs
[    0.436907] calling  bdi_class_init+0x0/0x41 @ 1
[    0.436948] initcall bdi_class_init+0x0/0x41 returned 0 after 0 usecs
[    0.436952] calling  mm_sysfs_init+0x0/0x27 @ 1
[    0.436959] initcall mm_sysfs_init+0x0/0x27 returned 0 after 0 usecs
[    0.436965] calling  gpiolib_sysfs_init+0x0/0x85 @ 1
[    0.436979] initcall gpiolib_sysfs_init+0x0/0x85 returned 0 after 0 usecs
[    0.436984] calling  pcibus_class_init+0x0/0x19 @ 1
[    0.436993] initcall pcibus_class_init+0x0/0x19 returned 0 after 0 usecs
[    0.436998] calling  pci_driver_init+0x0/0x22 @ 1
[    0.437029] initcall pci_driver_init+0x0/0x22 returned 0 after 0 usecs
[    0.437033] calling  backlight_class_init+0x0/0x91 @ 1
[    0.437041] initcall backlight_class_init+0x0/0x91 returned 0 after 0 usecs
[    0.437046] calling  xenbus_init+0x0/0x249 @ 1
[    0.437050] initcall xenbus_init+0x0/0x249 returned -19 after 0 usecs
[    0.437054] calling  tty_class_init+0x0/0x30 @ 1
[    0.437063] initcall tty_class_init+0x0/0x30 returned 0 after 0 usecs
[    0.437068] calling  vtconsole_class_init+0x0/0xc0 @ 1
[    0.437115] initcall vtconsole_class_init+0x0/0xc0 returned 0 after 0 usecs
[    0.437120] calling  serdev_init+0x0/0x1f @ 1
[    0.437140] initcall serdev_init+0x0/0x1f returned 0 after 0 usecs
[    0.437146] calling  mipi_dsi_bus_init+0x0/0x14 @ 1
[    0.437160] initcall mipi_dsi_bus_init+0x0/0x14 returned 0 after 0 usecs
[    0.437164] calling  wakeup_sources_debugfs_init+0x0/0x24 @ 1
[    0.437172] initcall wakeup_sources_debugfs_init+0x0/0x24 returned 0 after 0 usecs
[    0.437175] calling  isa_bus_init+0x0/0x38 @ 1
[    0.437203] initcall isa_bus_init+0x0/0x38 returned 0 after 0 usecs
[    0.437207] calling  regmap_initcall+0x0/0x11 @ 1
[    0.437214] initcall regmap_initcall+0x0/0x11 returned 0 after 0 usecs
[    0.437217] calling  spi_init+0x0/0x8b @ 1
[    0.437243] initcall spi_init+0x0/0x8b returned 0 after 0 usecs
[    0.437247] calling  i2c_init+0x0/0xa5 @ 1
[    0.437281] initcall i2c_init+0x0/0xa5 returned 0 after 0 usecs
[    0.437285] calling  init_ladder+0x0/0x27 @ 1
[    0.437311] cpuidle: using governor ladder
[    0.437315] initcall init_ladder+0x0/0x27 returned 0 after 0 usecs
[    0.437318] calling  init_menu+0x0/0x14 @ 1
[    0.437329] cpuidle: using governor menu
[    0.437332] initcall init_menu+0x0/0x14 returned 0 after 0 usecs
[    0.437338] calling  amd_postcore_init+0x0/0xf2 @ 1
[    0.437450] initcall amd_postcore_init+0x0/0xf2 returned 0 after 0 usecs
[    0.437450] calling  kobject_uevent_init+0x0/0xf @ 1
[    0.437450] initcall kobject_uevent_init+0x0/0xf returned 0 after 0 usecs
[    0.437450] calling  bts_init+0x0/0xae @ 1
[    0.437450] initcall bts_init+0x0/0xae returned -19 after 0 usecs
[    0.437450] calling  pt_init+0x0/0x31a @ 1
[    0.437450] initcall pt_init+0x0/0x31a returned -19 after 0 usecs
[    0.437450] calling  boot_params_ksysfs_init+0x0/0x21b @ 1
[    0.437450] initcall boot_params_ksysfs_init+0x0/0x21b returned 0 after 0 usecs
[    0.437450] calling  sbf_init+0x0/0xc0 @ 1
[    0.437450] initcall sbf_init+0x0/0xc0 returned 0 after 0 usecs
[    0.437450] calling  arch_kdebugfs_init+0x0/0x23 @ 1
[    0.437450] initcall arch_kdebugfs_init+0x0/0x23 returned 0 after 0 usecs
[    0.437450] calling  init_pit_clocksource+0x0/0x34 @ 1
[    0.437450] initcall init_pit_clocksource+0x0/0x34 returned 0 after 0 usecs
[    0.437450] calling  intel_pconfig_init+0x0/0xed @ 1
[    0.437450] initcall intel_pconfig_init+0x0/0xed returned 0 after 0 usecs
[    0.437450] calling  mtrr_if_init+0x0/0x51 @ 1
[    0.437450] initcall mtrr_if_init+0x0/0x51 returned 0 after 0 usecs
[    0.437450] calling  ffh_cstate_init+0x0/0x2d @ 1
[    0.437450] initcall ffh_cstate_init+0x0/0x2d returned 0 after 0 usecs
[    0.437450] calling  kdump_buf_page_init+0x0/0x3b @ 1
[    0.437450] initcall kdump_buf_page_init+0x0/0x3b returned 0 after 0 usecs
[    0.437450] calling  activate_jump_labels+0x0/0x35 @ 1
[    0.437450] initcall activate_jump_labels+0x0/0x35 returned 0 after 0 usecs
[    0.437450] calling  kvm_setup_pv_tlb_flush+0x0/0x64 @ 1
[    0.437450] initcall kvm_setup_pv_tlb_flush+0x0/0x64 returned 0 after 0 usecs
[    0.437450] calling  kcmp_cookies_init+0x0/0x2e @ 1
[    0.437450] initcall kcmp_cookies_init+0x0/0x2e returned 0 after 0 usecs
[    0.437450] calling  acpi_pci_init+0x0/0x60 @ 1
[    0.437450] ACPI: bus type PCI registered
[    0.437450] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.437450] initcall acpi_pci_init+0x0/0x60 returned 0 after 0 usecs
[    0.437450] calling  dma_channel_table_init+0x0/0xc2 @ 1
[    0.437450] initcall dma_channel_table_init+0x0/0xc2 returned 0 after 0 usecs
[    0.437450] calling  dma_bus_init+0x0/0x91 @ 1
[    0.437450] initcall dma_bus_init+0x0/0x91 returned 0 after 0 usecs
[    0.437450] calling  setup_vcpu_hotplug_event+0x0/0x2e @ 1
[    0.437450] initcall setup_vcpu_hotplug_event+0x0/0x2e returned -19 after 0 usecs
[    0.437450] calling  register_xen_pci_notifier+0x0/0x2e @ 1
[    0.437450] initcall register_xen_pci_notifier+0x0/0x2e returned 0 after 0 usecs
[    0.437450] calling  xen_pcpu_init+0x0/0xa8 @ 1
[    0.437450] initcall xen_pcpu_init+0x0/0xa8 returned -19 after 0 usecs
[    0.437450] calling  dmi_id_init+0x0/0x2b8 @ 1
[    0.437450] initcall dmi_id_init+0x0/0x2b8 returned 0 after 0 usecs
[    0.437450] calling  pci_arch_init+0x0/0x6b @ 1
[    0.437450] PCI: Using configuration type 1 for base access
[    0.437450] PCI: Using configuration type 1 for extended access
[    0.437451] initcall pci_arch_init+0x0/0x6b returned 0 after 0 usecs
[    0.437611] calling  fixup_ht_bug+0x0/0xb6 @ 1
[    0.437616] initcall fixup_ht_bug+0x0/0xb6 returned 0 after 0 usecs
[    0.437620] calling  topology_init+0x0/0x30 @ 1
[    0.437749] initcall topology_init+0x0/0x30 returned 0 after 0 usecs
[    0.437753] calling  mtrr_init_finialize+0x0/0x41 @ 1
[    0.437755] mtrr: your CPUs had inconsistent variable MTRR settings
[    0.437756] mtrr: probably your BIOS does not setup all CPUs.
[    0.437757] mtrr: corrected configuration.
[    0.437761] initcall mtrr_init_finialize+0x0/0x41 returned 0 after 0 usecs
[    0.437765] calling  uid_cache_init+0x0/0x84 @ 1
[    0.437774] initcall uid_cache_init+0x0/0x84 returned 0 after 0 usecs
[    0.437779] calling  param_sysfs_init+0x0/0x196 @ 1
[    0.439252] initcall param_sysfs_init+0x0/0x196 returned 0 after 0 usecs
[    0.439258] calling  user_namespace_sysctl_init+0x0/0x31 @ 1
[    0.439283] initcall user_namespace_sysctl_init+0x0/0x31 returned 0 after 0 usecs
[    0.439289] calling  proc_schedstat_init+0x0/0x23 @ 1
[    0.439295] initcall proc_schedstat_init+0x0/0x23 returned 0 after 0 usecs
[    0.439298] calling  pm_sysrq_init+0x0/0x1b @ 1
[    0.439328] initcall pm_sysrq_init+0x0/0x1b returned 0 after 0 usecs
[    0.439334] calling  create_proc_profile+0x0/0xe0 @ 1
[    0.439338] initcall create_proc_profile+0x0/0xe0 returned 0 after 0 usecs
[    0.439342] calling  crash_save_vmcoreinfo_init+0x0/0x4b9 @ 1
[    0.439404] initcall crash_save_vmcoreinfo_init+0x0/0x4b9 returned 0 after 0 usecs
[    0.439408] calling  crash_notes_memory_init+0x0/0x36 @ 1
[    0.439414] initcall crash_notes_memory_init+0x0/0x36 returned 0 after 0 usecs
[    0.439418] calling  cgroup_sysfs_init+0x0/0x19 @ 1
[    0.439427] initcall cgroup_sysfs_init+0x0/0x19 returned 0 after 0 usecs
[    0.439431] calling  cgroup_namespaces_init+0x0/0xc @ 1
[    0.439434] initcall cgroup_namespaces_init+0x0/0xc returned 0 after 0 usecs
[    0.439438] calling  user_namespaces_init+0x0/0x2c @ 1
[    0.439456] initcall user_namespaces_init+0x0/0x2c returned 0 after 0 usecs
[    0.439460] calling  hung_task_init+0x0/0x53 @ 1
[    0.439487] initcall hung_task_init+0x0/0x53 returned 0 after 0 usecs
[    0.439487] calling  dev_map_init+0x0/0x16 @ 1
[    0.439487] initcall dev_map_init+0x0/0x16 returned 0 after 0 usecs
[    0.439487] calling  stack_map_init+0x0/0x40 @ 1
[    0.439487] initcall stack_map_init+0x0/0x40 returned 0 after 0 usecs
[    0.439487] calling  oom_init+0x0/0x2e @ 1
[    0.439487] initcall oom_init+0x0/0x2e returned 0 after 0 usecs
[    0.439487] calling  default_bdi_init+0x0/0xa7 @ 1
[    0.439487] initcall default_bdi_init+0x0/0xa7 returned 0 after 0 usecs
[    0.439487] calling  cgwb_init+0x0/0x2c @ 1
[    0.439487] initcall cgwb_init+0x0/0x2c returned 0 after 0 usecs
[    0.439487] calling  percpu_enable_async+0x0/0x13 @ 1
[    0.439487] initcall percpu_enable_async+0x0/0x13 returned 0 after 0 usecs
[    0.439487] calling  kcompactd_init+0x0/0x49 @ 1
[    0.440860] initcall kcompactd_init+0x0/0x49 returned 0 after 3906 usecs
[    0.440860] calling  init_user_reserve+0x0/0x40 @ 1
[    0.440862] initcall init_user_reserve+0x0/0x40 returned 0 after 0 usecs
[    0.440866] calling  init_admin_reserve+0x0/0x40 @ 1
[    0.440870] initcall init_admin_reserve+0x0/0x40 returned 0 after 0 usecs
[    0.440876] calling  init_reserve_notifier+0x0/0x3 @ 1
[    0.440880] initcall init_reserve_notifier+0x0/0x3 returned 0 after 0 usecs
[    0.440885] calling  swap_init_sysfs+0x0/0x5d @ 1
[    0.440901] initcall swap_init_sysfs+0x0/0x5d returned 0 after 0 usecs
[    0.440906] calling  swapfile_init+0x0/0x40 @ 1
[    0.440910] initcall swapfile_init+0x0/0x40 returned 0 after 0 usecs
[    0.440914] calling  hugetlb_init+0x0/0x36a @ 1
[    0.440921] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.440945] initcall hugetlb_init+0x0/0x36a returned 0 after 0 usecs
[    0.440949] calling  ksm_init+0x0/0x149 @ 1
[    0.440974] initcall ksm_init+0x0/0x149 returned 0 after 0 usecs
[    0.440974] calling  hugepage_init+0x0/0x12e @ 1
[    0.440974] initcall hugepage_init+0x0/0x12e returned 0 after 0 usecs
[    0.440974] calling  mem_cgroup_init+0x0/0xcb @ 1
[    0.440974] initcall mem_cgroup_init+0x0/0xcb returned 0 after 0 usecs
[    0.440974] calling  mem_cgroup_swap_init+0x0/0x4e @ 1
[    0.440974] initcall mem_cgroup_swap_init+0x0/0x4e returned 0 after 0 usecs
[    0.440974] calling  sel_ib_pkey_init+0x0/0x30 @ 1
[    0.440974] initcall sel_ib_pkey_init+0x0/0x30 returned 0 after 0 usecs
[    0.440974] calling  crypto_wq_init+0x0/0x2c @ 1
[    0.441023] initcall crypto_wq_init+0x0/0x2c returned 0 after 0 usecs
[    0.441023] calling  cryptomgr_init+0x0/0x14 @ 1
[    0.441023] initcall cryptomgr_init+0x0/0x14 returned 0 after 0 usecs
[    0.441023] calling  init_bio+0x0/0xbe @ 1
[    0.441023] initcall init_bio+0x0/0xbe returned 0 after 0 usecs
[    0.441023] calling  blk_settings_init+0x0/0x22 @ 1
[    0.441023] initcall blk_settings_init+0x0/0x22 returned 0 after 0 usecs
[    0.441023] calling  blk_ioc_init+0x0/0x29 @ 1
[    0.441023] initcall blk_ioc_init+0x0/0x29 returned 0 after 0 usecs
[    0.441023] calling  blk_softirq_init+0x0/0x63 @ 1
[    0.441023] initcall blk_softirq_init+0x0/0x63 returned 0 after 0 usecs
[    0.441023] calling  blk_mq_init+0x0/0x26 @ 1
[    0.441023] initcall blk_mq_init+0x0/0x26 returned 0 after 0 usecs
[    0.441023] calling  genhd_device_init+0x0/0x66 @ 1
[    0.441023] initcall genhd_device_init+0x0/0x66 returned 0 after 0 usecs
[    0.441023] calling  irq_poll_setup+0x0/0x5e @ 1
[    0.441023] initcall irq_poll_setup+0x0/0x5e returned 0 after 0 usecs
[    0.441023] calling  byt_gpio_init+0x0/0x16 @ 1
[    0.441023] initcall byt_gpio_init+0x0/0x16 returned 0 after 0 usecs
[    0.441023] calling  chv_pinctrl_init+0x0/0x16 @ 1
[    0.441023] initcall chv_pinctrl_init+0x0/0x16 returned 0 after 0 usecs
[    0.441023] calling  bxt_pinctrl_init+0x0/0x16 @ 1
[    0.441023] initcall bxt_pinctrl_init+0x0/0x16 returned 0 after 0 usecs
[    0.441023] calling  cdf_pinctrl_init+0x0/0x16 @ 1
[    0.441023] initcall cdf_pinctrl_init+0x0/0x16 returned 0 after 0 usecs
[    0.441023] calling  dnv_pinctrl_init+0x0/0x16 @ 1
[    0.441023] initcall dnv_pinctrl_init+0x0/0x16 returned 0 after 0 usecs
[    0.441023] calling  glk_pinctrl_init+0x0/0x16 @ 1
[    0.441023] initcall glk_pinctrl_init+0x0/0x16 returned 0 after 0 usecs
[    0.441023] calling  spt_pinctrl_init+0x0/0x16 @ 1
[    0.441023] initcall spt_pinctrl_init+0x0/0x16 returned 0 after 0 usecs
[    0.441023] calling  gpiolib_debugfs_init+0x0/0x24 @ 1
[    0.441023] initcall gpiolib_debugfs_init+0x0/0x24 returned 0 after 0 usecs
[    0.441023] calling  pwm_debugfs_init+0x0/0x24 @ 1
[    0.441026] initcall pwm_debugfs_init+0x0/0x24 returned 0 after 0 usecs
[    0.441030] calling  pwm_sysfs_init+0x0/0x19 @ 1
[    0.441040] initcall pwm_sysfs_init+0x0/0x19 returned 0 after 0 usecs
[    0.441046] calling  pci_slot_init+0x0/0x40 @ 1
[    0.441053] initcall pci_slot_init+0x0/0x40 returned 0 after 0 usecs
[    0.441056] calling  fbmem_init+0x0/0xcc @ 1
[    0.441119] initcall fbmem_init+0x0/0xcc returned 0 after 0 usecs
[    0.441123] calling  scan_for_dmi_ipmi+0x0/0x10f @ 1
[    0.441126] initcall scan_for_dmi_ipmi+0x0/0x10f returned 0 after 0 usecs
[    0.441130] calling  acpi_init+0x0/0x2f2 @ 1
[    0.441166] ACPI: Added _OSI(Module Device)
[    0.441168] ACPI: Added _OSI(Processor Device)
[    0.441170] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.441172] ACPI: Added _OSI(Processor Aggregator Device)
[    0.441175] ACPI: Added _OSI(Linux-Dell-Video)
[    0.441177] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.448189] ACPI: 4 ACPI AML tables successfully acquired and loaded
[    0.450905] ACPI: Interpreter enabled
[    0.450953] ACPI: (supports S0 S1 S3 S4 S5)
[    0.450956] ACPI: Using IOAPIC for interrupt routing
[    0.450985] HEST: Table parsing has been initialized.
[    0.450991] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.451351] ACPI: Enabled 18 GPEs in block 00 to 1F
[    0.458281] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.458292] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    0.458398] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR]
[    0.458998] PCI host bridge to bus 0000:00
[    0.459003] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.459007] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.459010] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[    0.459014] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xffffffff]
[    0.459018] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.459034] pci 0000:00:00.0: [1022:1510] type 00 class 0x060000
[    0.459046] pci 0000:00:00.0: calling  quirk_mmio_always_on+0x0/0x10 @ 1
[    0.459052] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[    0.459295] pci 0000:00:01.0: [1002:9802] type 00 class 0x030000
[    0.459319] pci 0000:00:01.0: reg 0x10: [mem 0xe0000000-0xefffffff pref]
[    0.459328] pci 0000:00:01.0: reg 0x14: [io  0x2000-0x20ff]
[    0.459338] pci 0000:00:01.0: reg 0x18: [mem 0xf0100000-0xf013ffff]
[    0.459369] pci 0000:00:01.0: enabling Extended Tags
[    0.459387] pci 0000:00:01.0: calling  quirk_no_pm_reset+0x0/0x20 @ 1
[    0.459393] pci 0000:00:01.0: quirk_no_pm_reset+0x0/0x20 took 0 usecs
[    0.459399] pci 0000:00:01.0: calling  efifb_fixup_resources+0x0/0x150 @ 1
[    0.459404] pci 0000:00:01.0: efifb_fixup_resources+0x0/0x150 took 0 usecs
[    0.459438] pci 0000:00:01.0: supports D1 D2
[    0.459634] pci 0000:00:01.1: [1002:1314] type 00 class 0x040300
[    0.459656] pci 0000:00:01.1: reg 0x10: [mem 0xf0140000-0xf0143fff]
[    0.459698] pci 0000:00:01.1: enabling Extended Tags
[    0.459742] pci 0000:00:01.1: supports D1 D2
[    0.460031] pci 0000:00:11.0: [1002:4391] type 00 class 0x010601
[    0.460061] pci 0000:00:11.0: reg 0x10: [io  0x2410-0x2417]
[    0.460074] pci 0000:00:11.0: reg 0x14: [io  0x2420-0x2423]
[    0.460088] pci 0000:00:11.0: reg 0x18: [io  0x2418-0x241f]
[    0.460101] pci 0000:00:11.0: reg 0x1c: [io  0x2424-0x2427]
[    0.460114] pci 0000:00:11.0: reg 0x20: [io  0x2400-0x240f]
[    0.460127] pci 0000:00:11.0: reg 0x24: [mem 0xf014b000-0xf014b3ff]
[    0.460427] pci 0000:00:12.0: [1002:4397] type 00 class 0x0c0310
[    0.460453] pci 0000:00:12.0: reg 0x10: [mem 0xf0148000-0xf0148fff]
[    0.460782] pci 0000:00:12.2: [1002:4396] type 00 class 0x0c0320
[    0.460846] pci 0000:00:12.2: reg 0x10: [mem 0xf014c000-0xf014c0ff]
[    0.460957] pci 0000:00:12.2: supports D1 D2
[    0.460960] pci 0000:00:12.2: PME# supported from D0 D1 D2 D3hot
[    0.461226] pci 0000:00:13.0: [1002:4397] type 00 class 0x0c0310
[    0.461251] pci 0000:00:13.0: reg 0x10: [mem 0xf0149000-0xf0149fff]
[    0.461575] pci 0000:00:13.2: [1002:4396] type 00 class 0x0c0320
[    0.461604] pci 0000:00:13.2: reg 0x10: [mem 0xf014d000-0xf014d0ff]
[    0.461713] pci 0000:00:13.2: supports D1 D2
[    0.461716] pci 0000:00:13.2: PME# supported from D0 D1 D2 D3hot
[    0.461980] pci 0000:00:14.0: [1002:4385] type 00 class 0x0c0500
[    0.461989] pci 0000:00:14.0: calling  sb600_disable_hpet_bar+0x0/0x70 @ 1
[    0.461995] pci 0000:00:14.0: sb600_disable_hpet_bar+0x0/0x70 took 0 usecs
[    0.462086] pci 0000:00:14.0: calling  force_disable_hpet_msi+0x0/0x20 @ 1
[    0.462091] pci 0000:00:14.0: force_disable_hpet_msi+0x0/0x20 took 0 usecs
[    0.462097] pci 0000:00:14.0: calling  sb600_hpet_quirk+0x0/0x70 @ 1
[    0.462101] pci 0000:00:14.0: sb600_hpet_quirk+0x0/0x70 took 0 usecs
[    0.462349] pci 0000:00:14.2: [1002:4383] type 00 class 0x040300
[    0.462383] pci 0000:00:14.2: reg 0x10: [mem 0xf0144000-0xf0147fff 64bit]
[    0.462476] pci 0000:00:14.2: PME# supported from D0 D3hot D3cold
[    0.462734] pci 0000:00:14.3: [1002:439d] type 00 class 0x060100
[    0.463068] pci 0000:00:14.4: [1002:4384] type 01 class 0x060401
[    0.463372] pci 0000:00:14.5: [1002:4399] type 00 class 0x0c0310
[    0.463397] pci 0000:00:14.5: reg 0x10: [mem 0xf014a000-0xf014afff]
[    0.463730] pci 0000:00:15.0: [1002:43a0] type 01 class 0x060400
[    0.463782] pci 0000:00:15.0: enabling Extended Tags
[    0.463853] pci 0000:00:15.0: supports D1 D2
[    0.464131] pci 0000:00:15.1: [1002:43a1] type 01 class 0x060400
[    0.464191] pci 0000:00:15.1: enabling Extended Tags
[    0.464259] pci 0000:00:15.1: supports D1 D2
[    0.464544] pci 0000:00:18.0: [1022:1700] type 00 class 0x060000
[    0.464554] pci 0000:00:18.0: calling  quirk_mmio_always_on+0x0/0x10 @ 1
[    0.464559] pci 0000:00:18.0: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[    0.464762] pci 0000:00:18.1: [1022:1701] type 00 class 0x060000
[    0.464771] pci 0000:00:18.1: calling  quirk_mmio_always_on+0x0/0x10 @ 1
[    0.464775] pci 0000:00:18.1: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[    0.464996] pci 0000:00:18.2: [1022:1702] type 00 class 0x060000
[    0.465006] pci 0000:00:18.2: calling  quirk_mmio_always_on+0x0/0x10 @ 1
[    0.465010] pci 0000:00:18.2: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[    0.465211] pci 0000:00:18.3: [1022:1703] type 00 class 0x060000
[    0.465220] pci 0000:00:18.3: calling  quirk_mmio_always_on+0x0/0x10 @ 1
[    0.465225] pci 0000:00:18.3: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[    0.465442] pci 0000:00:18.4: [1022:1704] type 00 class 0x060000
[    0.465451] pci 0000:00:18.4: calling  quirk_mmio_always_on+0x0/0x10 @ 1
[    0.465455] pci 0000:00:18.4: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[    0.465658] pci 0000:00:18.5: [1022:1718] type 00 class 0x060000
[    0.465667] pci 0000:00:18.5: calling  quirk_mmio_always_on+0x0/0x10 @ 1
[    0.465672] pci 0000:00:18.5: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[    0.465870] pci 0000:00:18.6: [1022:1716] type 00 class 0x060000
[    0.465879] pci 0000:00:18.6: calling  quirk_mmio_always_on+0x0/0x10 @ 1
[    0.465884] pci 0000:00:18.6: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[    0.466080] pci 0000:00:18.7: [1022:1719] type 00 class 0x060000
[    0.466088] pci 0000:00:18.7: calling  quirk_mmio_always_on+0x0/0x10 @ 1
[    0.466093] pci 0000:00:18.7: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[    0.466384] pci_bus 0000:01: extended config space not accessible
[    0.466477] pci 0000:00:14.4: PCI bridge to [bus 01] (subtractive decode)
[    0.466491] pci 0000:00:14.4:   bridge window [io  0x0000-0x0cf7 window] (subtractive decode)
[    0.466494] pci 0000:00:14.4:   bridge window [io  0x0d00-0xffff window] (subtractive decode)
[    0.466497] pci 0000:00:14.4:   bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
[    0.466500] pci 0000:00:14.4:   bridge window [mem 0xe0000000-0xffffffff] (subtractive decode)
[    0.466582] pci 0000:00:15.0: PCI bridge to [bus 02]
[    0.466691] pci 0000:03:00.0: [10ec:8168] type 00 class 0x020000
[    0.466733] pci 0000:03:00.0: reg 0x10: [io  0x1000-0x10ff]
[    0.466772] pci 0000:03:00.0: reg 0x18: [mem 0xf0004000-0xf0004fff 64bit pref]
[    0.466797] pci 0000:03:00.0: reg 0x20: [mem 0xf0000000-0xf0003fff 64bit pref]
[    0.466935] pci 0000:03:00.0: supports D1 D2
[    0.466938] pci 0000:03:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.477000] pci 0000:00:15.1: PCI bridge to [bus 03]
[    0.477013] pci 0000:00:15.1:   bridge window [io  0x1000-0x1fff]
[    0.477025] pci 0000:00:15.1:   bridge window [mem 0xf0000000-0xf00fffff 64bit pref]
[    0.477064] pci_bus 0000:00: on NUMA node 0
[    0.478101] ACPI: PCI Interrupt Link [INTA] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.478363] ACPI: PCI Interrupt Link [INTB] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.478621] ACPI: PCI Interrupt Link [INTC] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.478801] ACPI: PCI Interrupt Link [INTD] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.478981] ACPI: PCI Interrupt Link [INTE] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.479159] ACPI: PCI Interrupt Link [INTF] (IRQs 9) *0, disabled.
[    0.479333] ACPI: PCI Interrupt Link [INTG] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.479511] ACPI: PCI Interrupt Link [INTH] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[    0.479806] initcall acpi_init+0x0/0x2f2 returned 0 after 35156 usecs
[    0.479813] calling  pnp_init+0x0/0x14 @ 1
[    0.479833] initcall pnp_init+0x0/0x14 returned 0 after 0 usecs
[    0.479838] calling  balloon_init+0x0/0x103 @ 1
[    0.479843] initcall balloon_init+0x0/0x103 returned -19 after 0 usecs
[    0.479850] calling  xen_setup_shutdown_event+0x0/0x40 @ 1
[    0.479854] initcall xen_setup_shutdown_event+0x0/0x40 returned -19 after 0 usecs
[    0.479859] calling  xenbus_probe_backend_init+0x0/0x4d @ 1
[    0.479881] initcall xenbus_probe_backend_init+0x0/0x4d returned 0 after 0 usecs
[    0.479885] calling  xenbus_probe_frontend_init+0x0/0x4d @ 1
[    0.479901] initcall xenbus_probe_frontend_init+0x0/0x4d returned 0 after 0 usecs
[    0.479905] calling  xen_acpi_pad_init+0x0/0x46 @ 1
[    0.479909] initcall xen_acpi_pad_init+0x0/0x46 returned -19 after 0 usecs
[    0.479914] calling  misc_init+0x0/0xae @ 1
[    0.479933] initcall misc_init+0x0/0xae returned 0 after 0 usecs
[    0.479936] calling  vga_arb_device_init+0x0/0x232 @ 1
[    0.479991] pci 0000:00:01.0: vgaarb: setting as boot VGA device
[    0.479991] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.479991] pci 0000:00:01.0: vgaarb: bridge control possible
[    0.479991] vgaarb: loaded
[    0.479991] initcall vga_arb_device_init+0x0/0x232 returned 0 after 0 usecs
[    0.479991] calling  cn_init+0x0/0xc0 @ 1
[    0.479991] initcall cn_init+0x0/0xc0 returned 0 after 0 usecs
[    0.479991] calling  dax_fs_init+0x0/0xab @ 1
[    0.479991] initcall dax_fs_init+0x0/0xab returned 0 after 0 usecs
[    0.479991] calling  dma_buf_init+0x0/0x99 @ 1
[    0.479991] initcall dma_buf_init+0x0/0x99 returned 0 after 0 usecs
[    0.479991] calling  serio_init+0x0/0x2d @ 1
[    0.479991] initcall serio_init+0x0/0x2d returned 0 after 0 usecs
[    0.479991] calling  input_init+0x0/0xe3 @ 1
[    0.479991] initcall input_init+0x0/0xe3 returned 0 after 0 usecs
[    0.479991] calling  rtc_init+0x0/0x47 @ 1
[    0.479991] initcall rtc_init+0x0/0x47 returned 0 after 0 usecs
[    0.479991] calling  dw_i2c_init_driver+0x0/0x16 @ 1
[    0.479991] initcall dw_i2c_init_driver+0x0/0x16 returned 0 after 0 usecs
[    0.479991] calling  cec_devnode_init+0x0/0x9a @ 1
[    0.479991] initcall cec_devnode_init+0x0/0x9a returned 0 after 0 usecs
[    0.479991] calling  power_supply_class_init+0x0/0x3a @ 1
[    0.479991] initcall power_supply_class_init+0x0/0x3a returned 0 after 0 usecs
[    0.479991] calling  hwmon_init+0x0/0xd5 @ 1
[    0.479991] initcall hwmon_init+0x0/0xd5 returned 0 after 0 usecs
[    0.479991] calling  edac_init+0x0/0x6f @ 1
[    0.479991] EDAC MC: Ver: 3.0.0
[    0.481004] initcall edac_init+0x0/0x6f returned 0 after 3906 usecs
[    0.481004] calling  leds_init+0x0/0x37 @ 1
[    0.481004] initcall leds_init+0x0/0x37 returned 0 after 0 usecs
[    0.481004] calling  dmi_init+0x0/0xf8 @ 1
[    0.481004] initcall dmi_init+0x0/0xf8 returned 0 after 0 usecs
[    0.481004] calling  efisubsys_init+0x0/0x291 @ 1
[    0.481004] initcall efisubsys_init+0x0/0x291 returned 0 after 0 usecs
[    0.481004] calling  devfreq_init+0x0/0x90 @ 1
[    0.481022] initcall devfreq_init+0x0/0x90 returned 0 after 0 usecs
[    0.481027] calling  ras_init+0x0/0x14 @ 1
[    0.481041] initcall ras_init+0x0/0x14 returned 0 after 0 usecs
[    0.481045] calling  nvmem_init+0x0/0x14 @ 1
[    0.481066] initcall nvmem_init+0x0/0x14 returned 0 after 0 usecs
[    0.481071] calling  pci_subsys_init+0x0/0x65 @ 1
[    0.481073] PCI: Using ACPI for IRQ routing
[    0.481077] PCI: pci_cache_line_size set to 64 bytes
[    0.481232] e820: reserve RAM buffer [mem 0xc7d46000-0xc7ffffff]
[    0.481235] e820: reserve RAM buffer [mem 0x11f000000-0x11fffffff]
[    0.481241] initcall pci_subsys_init+0x0/0x65 returned 0 after 0 usecs
[    0.481246] calling  proto_init+0x0/0x14 @ 1
[    0.481256] initcall proto_init+0x0/0x14 returned 0 after 0 usecs
[    0.481259] calling  net_dev_init+0x0/0x200 @ 1
[    0.481448] initcall net_dev_init+0x0/0x200 returned 0 after 0 usecs
[    0.481451] calling  neigh_init+0x0/0x78 @ 1
[    0.481456] initcall neigh_init+0x0/0x78 returned 0 after 0 usecs
[    0.481459] calling  fib_notifier_init+0x0/0x14 @ 1
[    0.481463] initcall fib_notifier_init+0x0/0x14 returned 0 after 0 usecs
[    0.481466] calling  fib_rules_init+0x0/0xa6 @ 1
[    0.481473] initcall fib_rules_init+0x0/0xa6 returned 0 after 0 usecs
[    0.481476] calling  init_cgroup_netprio+0x0/0x16 @ 1
[    0.481480] initcall init_cgroup_netprio+0x0/0x16 returned 0 after 0 usecs
[    0.481483] calling  bpf_lwt_init+0x0/0x19 @ 1
[    0.481486] initcall bpf_lwt_init+0x0/0x19 returned 0 after 0 usecs
[    0.481489] calling  pktsched_init+0x0/0xf6 @ 1
[    0.481498] initcall pktsched_init+0x0/0xf6 returned 0 after 0 usecs
[    0.481501] calling  tc_filter_init+0x0/0xe3 @ 1
[    0.481516] initcall tc_filter_init+0x0/0xe3 returned 0 after 0 usecs
[    0.481520] calling  tc_action_init+0x0/0x65 @ 1
[    0.481528] initcall tc_action_init+0x0/0x65 returned 0 after 0 usecs
[    0.481531] calling  genl_init+0x0/0x33 @ 1
[    0.481548] initcall genl_init+0x0/0x33 returned 0 after 0 usecs
[    0.481552] calling  wireless_nlevent_init+0x0/0x38 @ 1
[    0.481558] initcall wireless_nlevent_init+0x0/0x38 returned 0 after 0 usecs
[    0.481563] calling  xen_mcfg_late+0x0/0xd6 @ 1
[    0.481566] initcall xen_mcfg_late+0x0/0xd6 returned 0 after 0 usecs
[    0.481570] calling  watchdog_init+0x0/0x70 @ 1
[    0.481582] initcall watchdog_init+0x0/0x70 returned 0 after 0 usecs
[    0.481582] calling  nmi_warning_debugfs+0x0/0x26 @ 1
[    0.481582] initcall nmi_warning_debugfs+0x0/0x26 returned 0 after 0 usecs
[    0.481582] calling  save_microcode_in_initrd+0x0/0x94 @ 1
[    0.481582] initcall save_microcode_in_initrd+0x0/0x94 returned -22 after 0 usecs
[    0.481582] calling  hpet_late_init+0x0/0xf6 @ 1
[    0.481582] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.481582] hpet0: 3 comparators, 32-bit 14.318180 MHz counter
[    0.483224] initcall hpet_late_init+0x0/0xf6 returned 0 after 0 usecs
[    0.483228] calling  init_amd_nbs+0x0/0x103 @ 1
[    0.483280] initcall init_amd_nbs+0x0/0x103 returned 0 after 0 usecs
[    0.483285] calling  sugov_register+0x0/0x14 @ 1
[    0.483290] initcall sugov_register+0x0/0x14 returned 0 after 0 usecs
[    0.483294] calling  clocksource_done_booting+0x0/0x40 @ 1
[    0.483362] clocksource: Switched to clocksource tsc-early
[    0.483366] initcall clocksource_done_booting+0x0/0x40 returned 0 after 13 usecs
[    0.483372] calling  tracer_init_tracefs+0x0/0x178 @ 1
[    0.506021] initcall tracer_init_tracefs+0x0/0x178 returned 0 after 24577 usecs
[    0.506032] calling  init_trace_printk_function_export+0x0/0x2d @ 1
[    0.506044] initcall init_trace_printk_function_export+0x0/0x2d returned 0 after 7 usecs
[    0.506048] calling  init_graph_tracefs+0x0/0x2d @ 1
[    0.506055] initcall init_graph_tracefs+0x0/0x2d returned 0 after 2 usecs
[    0.506060] calling  init_kprobe_trace+0x0/0x83 @ 1
[    0.506068] initcall init_kprobe_trace+0x0/0x83 returned 0 after 4 usecs
[    0.506073] calling  init_uprobe_trace+0x0/0x4c @ 1
[    0.506080] initcall init_uprobe_trace+0x0/0x4c returned 0 after 3 usecs
[    0.506085] calling  bpf_init+0x0/0x42 @ 1
[    0.506102] initcall bpf_init+0x0/0x42 returned 0 after 12 usecs
[    0.506108] calling  init_pipe_fs+0x0/0x42 @ 1
[    0.506146] initcall init_pipe_fs+0x0/0x42 returned 0 after 32 usecs
[    0.506150] calling  cgroup_writeback_init+0x0/0x2c @ 1
[    0.506193] initcall cgroup_writeback_init+0x0/0x2c returned 0 after 37 usecs
[    0.506197] calling  inotify_user_setup+0x0/0x4a @ 1
[    0.506227] initcall inotify_user_setup+0x0/0x4a returned 0 after 26 usecs
[    0.506230] calling  eventpoll_init+0x0/0xab @ 1
[    0.506248] initcall eventpoll_init+0x0/0xab returned 0 after 14 usecs
[    0.506251] calling  anon_inode_init+0x0/0x4e @ 1
[    0.506267] initcall anon_inode_init+0x0/0x4e returned 0 after 11 usecs
[    0.506271] calling  init_dax_wait_table+0x0/0x2f @ 1
[    0.506307] initcall init_dax_wait_table+0x0/0x2f returned 0 after 32 usecs
[    0.506311] calling  proc_locks_init+0x0/0x23 @ 1
[    0.506318] initcall proc_locks_init+0x0/0x23 returned 0 after 4 usecs
[    0.506321] calling  dquot_init+0x0/0xfa @ 1
[    0.506323] VFS: Disk quotas dquot_6.6.0
[    0.506359] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.506363] initcall dquot_init+0x0/0xfa returned 0 after 37 usecs
[    0.506367] calling  quota_init+0x0/0x25 @ 1
[    0.506393] initcall quota_init+0x0/0x25 returned 0 after 21 usecs
[    0.506396] calling  proc_cmdline_init+0x0/0x21 @ 1
[    0.506402] initcall proc_cmdline_init+0x0/0x21 returned 0 after 2 usecs
[    0.506405] calling  proc_consoles_init+0x0/0x23 @ 1
[    0.506410] initcall proc_consoles_init+0x0/0x23 returned 0 after 1 usecs
[    0.506413] calling  proc_cpuinfo_init+0x0/0x1f @ 1
[    0.506418] initcall proc_cpuinfo_init+0x0/0x1f returned 0 after 1 usecs
[    0.506421] calling  proc_devices_init+0x0/0x23 @ 1
[    0.506425] initcall proc_devices_init+0x0/0x23 returned 0 after 1 usecs
[    0.506429] calling  proc_interrupts_init+0x0/0x23 @ 1
[    0.506433] initcall proc_interrupts_init+0x0/0x23 returned 0 after 1 usecs
[    0.506436] calling  proc_loadavg_init+0x0/0x21 @ 1
[    0.506440] initcall proc_loadavg_init+0x0/0x21 returned 0 after 1 usecs
[    0.506444] calling  proc_meminfo_init+0x0/0x21 @ 1
[    0.506448] initcall proc_meminfo_init+0x0/0x21 returned 0 after 1 usecs
[    0.506451] calling  proc_stat_init+0x0/0x1f @ 1
[    0.506455] initcall proc_stat_init+0x0/0x1f returned 0 after 1 usecs
[    0.506459] calling  proc_uptime_init+0x0/0x21 @ 1
[    0.506463] initcall proc_uptime_init+0x0/0x21 returned 0 after 1 usecs
[    0.506466] calling  proc_version_init+0x0/0x21 @ 1
[    0.506471] initcall proc_version_init+0x0/0x21 returned 0 after 1 usecs
[    0.506474] calling  proc_softirqs_init+0x0/0x21 @ 1
[    0.506478] initcall proc_softirqs_init+0x0/0x21 returned 0 after 1 usecs
[    0.506482] calling  proc_kcore_init+0x0/0x6e @ 1
[    0.506488] initcall proc_kcore_init+0x0/0x6e returned 0 after 3 usecs
[    0.506491] calling  vmcore_init+0x0/0x17e @ 1
[    0.506495] initcall vmcore_init+0x0/0x17e returned 0 after 0 usecs
[    0.506498] calling  proc_kmsg_init+0x0/0x22 @ 1
[    0.506502] initcall proc_kmsg_init+0x0/0x22 returned 0 after 1 usecs
[    0.506506] calling  proc_page_init+0x0/0x4e @ 1
[    0.506512] initcall proc_page_init+0x0/0x4e returned 0 after 3 usecs
[    0.506515] calling  init_ramfs_fs+0x0/0x23 @ 1
[    0.506519] initcall init_ramfs_fs+0x0/0x23 returned 0 after 0 usecs
[    0.506522] calling  init_hugetlbfs_fs+0x0/0x13c @ 1
[    0.506564] initcall init_hugetlbfs_fs+0x0/0x13c returned 0 after 36 usecs
[    0.506569] calling  tomoyo_initerface_init+0x0/0x135 @ 1
[    0.506573] initcall tomoyo_initerface_init+0x0/0x135 returned 0 after 0 usecs
[    0.506577] calling  aa_create_aafs+0x0/0x35a @ 1
[    0.506581] initcall aa_create_aafs+0x0/0x35a returned 0 after 0 usecs
[    0.506586] calling  blk_scsi_ioctl_init+0x0/0x352 @ 1
[    0.506591] initcall blk_scsi_ioctl_init+0x0/0x352 returned 0 after 1 usecs
[    0.506595] calling  dynamic_debug_init_debugfs+0x0/0x5f @ 1
[    0.506619] initcall dynamic_debug_init_debugfs+0x0/0x5f returned 0 after 18 usecs
[    0.506623] calling  acpi_event_init+0x0/0x31 @ 1
[    0.506635] initcall acpi_event_init+0x0/0x31 returned 0 after 8 usecs
[    0.506639] calling  pnp_system_init+0x0/0x14 @ 1
[    0.506668] initcall pnp_system_init+0x0/0x14 returned 0 after 25 usecs
[    0.506672] calling  pnpacpi_init+0x0/0x67 @ 1
[    0.506674] pnp: PnP ACPI init
[    0.507121] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.507209] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 PNP030b (active)
[    0.507275] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.507648] pnp 00:03: Plug and Play ACPI device, IDs PNP0303 PNP030b (active)
[    0.507718] pnp 00:04: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.507739] pnp: PnP ACPI: found 5 devices
[    0.507745] initcall pnpacpi_init+0x0/0x67 returned 0 after 1042 usecs
[    0.507750] calling  pnpbios_init+0x0/0x341 @ 1
[    0.507754] PnPBIOS: Disabled by ACPI PNP
[    0.507758] initcall pnpbios_init+0x0/0x341 returned -19 after 4 usecs
[    0.507763] calling  chr_dev_init+0x0/0x9b @ 1
[    0.513897] initcall chr_dev_init+0x0/0x9b returned 0 after 5976 usecs
[    0.513906] calling  firmware_class_init+0x0/0xe9 @ 1
[    0.513924] initcall firmware_class_init+0x0/0xe9 returned 0 after 13 usecs
[    0.513929] calling  thermal_init+0x0/0xce @ 1
[    0.513966] initcall thermal_init+0x0/0xce returned 0 after 32 usecs
[    0.513971] calling  cpufreq_gov_performance_init+0x0/0x14 @ 1
[    0.513976] initcall cpufreq_gov_performance_init+0x0/0x14 returned 0 after 1 usecs
[    0.513980] calling  cpufreq_gov_dbs_init+0x0/0x14 @ 1
[    0.513983] initcall cpufreq_gov_dbs_init+0x0/0x14 returned 0 after 0 usecs
[    0.513988] calling  map_properties+0x0/0x453 @ 1
[    0.513992] initcall map_properties+0x0/0x453 returned 0 after 0 usecs
[    0.513997] calling  init_acpi_pm_clocksource+0x0/0x190 @ 1
[    0.549195] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.549203] initcall init_acpi_pm_clocksource+0x0/0x190 returned 0 after 34377 usecs
[    0.549208] calling  pcibios_assign_resources+0x0/0x9f @ 1
[    0.549260] pci 0000:00:14.4: PCI bridge to [bus 01]
[    0.549279] pci 0000:00:15.0: PCI bridge to [bus 02]
[    0.549294] pci 0000:00:15.1: PCI bridge to [bus 03]
[    0.549299] pci 0000:00:15.1:   bridge window [io  0x1000-0x1fff]
[    0.549309] pci 0000:00:15.1:   bridge window [mem 0xf0000000-0xf00fffff 64bit pref]
[    0.549321] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.549324] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.549327] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff]
[    0.549331] pci_bus 0000:00: resource 7 [mem 0xe0000000-0xffffffff]
[    0.549334] pci_bus 0000:01: resource 4 [io  0x0000-0x0cf7 window]
[    0.549337] pci_bus 0000:01: resource 5 [io  0x0d00-0xffff window]
[    0.549341] pci_bus 0000:01: resource 6 [mem 0x000a0000-0x000bffff]
[    0.549344] pci_bus 0000:01: resource 7 [mem 0xe0000000-0xffffffff]
[    0.549347] pci_bus 0000:03: resource 0 [io  0x1000-0x1fff]
[    0.549350] pci_bus 0000:03: resource 2 [mem 0xf0000000-0xf00fffff 64bit pref]
[    0.549416] initcall pcibios_assign_resources+0x0/0x9f returned 0 after 198 usecs
[    0.549420] calling  sysctl_core_init+0x0/0x28 @ 1
[    0.549450] initcall sysctl_core_init+0x0/0x28 returned 0 after 27 usecs
[    0.549454] calling  eth_offload_init+0x0/0x16 @ 1
[    0.549458] initcall eth_offload_init+0x0/0x16 returned 0 after 0 usecs
[    0.549462] calling  ipv4_offload_init+0x0/0x6e @ 1
[    0.549466] initcall ipv4_offload_init+0x0/0x6e returned 0 after 1 usecs
[    0.549469] calling  inet_init+0x0/0x240 @ 1
[    0.549517] NET: Registered protocol family 2
[    0.550111] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[    0.550125] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.550153] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.550188] TCP: Hash tables configured (established 8192 bind 8192)
[    0.550254] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.550265] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.550349] initcall inet_init+0x0/0x240 returned 0 after 850 usecs
[    0.550356] calling  af_unix_init+0x0/0x4c @ 1
[    0.550370] NET: Registered protocol family 1
[    0.550386] initcall af_unix_init+0x0/0x4c returned 0 after 24 usecs
[    0.550391] calling  ipv6_offload_init+0x0/0x77 @ 1
[    0.550396] initcall ipv6_offload_init+0x0/0x77 returned 0 after 1 usecs
[    0.550400] calling  xsk_init+0x0/0x3a @ 1
[    0.550402] NET: Registered protocol family 44
[    0.550405] initcall xsk_init+0x0/0x3a returned 0 after 1 usecs
[    0.550411] calling  pci_apply_final_quirks+0x0/0x118 @ 1
[    0.550431] pci 0000:00:01.0: calling  pci_fixup_video+0x0/0x110 @ 1
[    0.550441] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.550445] pci 0000:00:01.0: pci_fixup_video+0x0/0x110 took 8 usecs
[    0.550455] pci 0000:00:01.1: calling  quirk_gpu_hda+0x0/0xa0 @ 1
[    0.550463] pci 0000:00:01.1: Linked as a consumer to 0000:00:01.0
[    0.550484] pci 0000:00:01.1: quirk_gpu_hda+0x0/0xa0 took 23 usecs
[    0.550492] pci 0000:00:11.0: calling  quirk_msi_intx_disable_ati_bug+0x0/0x50 @ 1
[    0.550499] pci 0000:00:11.0: quirk_msi_intx_disable_ati_bug+0x0/0x50 took 2 usecs
[    0.550580] pci 0000:00:12.0: calling  quirk_usb_early_handoff+0x0/0x6e7 @ 1
[    0.551195] pci 0000:00:12.0: quirk_usb_early_handoff+0x0/0x6e7 took 592 usecs
[    0.551275] pci 0000:00:12.2: calling  quirk_usb_early_handoff+0x0/0x6e7 @ 1
[    0.551696] pci 0000:00:12.2: quirk_usb_early_handoff+0x0/0x6e7 took 403 usecs
[    0.551775] pci 0000:00:13.0: calling  quirk_usb_early_handoff+0x0/0x6e7 @ 1
[    0.552191] pci 0000:00:13.0: quirk_usb_early_handoff+0x0/0x6e7 took 399 usecs
[    0.552270] pci 0000:00:13.2: calling  quirk_usb_early_handoff+0x0/0x6e7 @ 1
[    0.552686] pci 0000:00:13.2: quirk_usb_early_handoff+0x0/0x6e7 took 399 usecs
[    0.552770] pci 0000:00:14.2: calling  quirk_gpu_hda+0x0/0xa0 @ 1
[    0.552774] pci 0000:00:14.2: quirk_gpu_hda+0x0/0xa0 took 0 usecs
[    0.552794] pci 0000:00:14.5: calling  quirk_usb_early_handoff+0x0/0x6e7 @ 1
[    0.553255] pci 0000:00:14.5: quirk_usb_early_handoff+0x0/0x6e7 took 443 usecs
[    0.553377] PCI: CLS 64 bytes, default 64
[    0.553383] initcall pci_apply_final_quirks+0x0/0x118 returned 0 after 2896 usecs
[    0.553387] calling  acpi_reserve_resources+0x0/0xcd @ 1
[    0.553395] initcall acpi_reserve_resources+0x0/0xcd returned 0 after 4 usecs
[    0.553400] calling  populate_rootfs+0x0/0xf9 @ 1
[    0.553502] Unpacking initramfs...
[    0.630323] Freeing initrd memory: 8080K
[    0.630500] initcall populate_rootfs+0x0/0xf9 returned 0 after 75281 usecs
[    0.630507] calling  pci_iommu_init+0x0/0x3a @ 1
[    0.630509] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.630513] software IO TLB: mapped [mem 0x2fa43000-0x33a43000] (64MB)
[    0.630516] initcall pci_iommu_init+0x0/0x3a returned 0 after 5 usecs
[    0.630688] calling  amd_uncore_init+0x0/0x255 @ 1
[    0.630692] initcall amd_uncore_init+0x0/0x255 returned -19 after 0 usecs
[    0.630696] calling  amd_ibs_init+0x0/0x17f @ 1
[    0.630700] LVT offset 0 assigned for vector 0x400
[    0.630921] perf: AMD IBS detected (0x000000ff)
[    0.630935] initcall amd_ibs_init+0x0/0x17f returned 0 after 222 usecs
[    0.630941] calling  msr_init+0x0/0xc3 @ 1
[    0.630948] initcall msr_init+0x0/0xc3 returned 0 after 3 usecs
[    0.630954] calling  register_kernel_offset_dumper+0x0/0x1b @ 1
[    0.630959] initcall register_kernel_offset_dumper+0x0/0x1b returned 0 after 0 usecs
[    0.630964] calling  i8259A_init_ops+0x0/0x25 @ 1
[    0.630969] initcall i8259A_init_ops+0x0/0x25 returned 0 after 0 usecs
[    0.630972] calling  init_tsc_clocksource+0x0/0xa4 @ 1
[    0.630982] initcall init_tsc_clocksource+0x0/0xa4 returned 0 after 5 usecs
[    0.630985] calling  add_rtc_cmos+0x0/0xa6 @ 1
[    0.630989] initcall add_rtc_cmos+0x0/0xa6 returned 0 after 0 usecs
[    0.630992] calling  i8237A_init_ops+0x0/0x3c @ 1
[    0.631079] initcall i8237A_init_ops+0x0/0x3c returned 0 after 80 usecs
[    0.631083] calling  thermal_throttle_init_device+0x0/0x43 @ 1
[    0.631087] initcall thermal_throttle_init_device+0x0/0x43 returned 0 after 0 usecs
[    0.631091] calling  ioapic_init_ops+0x0/0x16 @ 1
[    0.631094] initcall ioapic_init_ops+0x0/0x16 returned 0 after 0 usecs
[    0.631097] calling  register_e820_pmem+0x0/0x45 @ 1
[    0.631105] initcall register_e820_pmem+0x0/0x45 returned 0 after 4 usecs
[    0.631108] calling  add_pcspkr+0x0/0x61 @ 1
[    0.631185] initcall add_pcspkr+0x0/0x61 returned 0 after 71 usecs
[    0.631189] calling  sysfb_init+0x0/0x88 @ 1
[    0.631258] initcall sysfb_init+0x0/0x88 returned 0 after 62 usecs
[    0.631262] calling  pt_dump_init+0x0/0x65 @ 1
[    0.631266] initcall pt_dump_init+0x0/0x65 returned 0 after 0 usecs
[    0.631270] calling  iosf_mbi_init+0x0/0x1b @ 1
[    0.631307] initcall iosf_mbi_init+0x0/0x1b returned 0 after 32 usecs
[    0.631312] calling  proc_execdomains_init+0x0/0x21 @ 1
[    0.631324] initcall proc_execdomains_init+0x0/0x21 returned 0 after 8 usecs
[    0.631329] calling  register_warn_debugfs+0x0/0x24 @ 1
[    0.631349] initcall register_warn_debugfs+0x0/0x24 returned 0 after 16 usecs
[    0.631353] calling  cpuhp_sysfs_init+0x0/0x79 @ 1
[    0.631373] initcall cpuhp_sysfs_init+0x0/0x79 returned 0 after 16 usecs
[    0.631378] calling  ioresources_init+0x0/0x40 @ 1
[    0.631385] initcall ioresources_init+0x0/0x40 returned 0 after 3 usecs
[    0.631390] calling  init_sched_debug_procfs+0x0/0x2f @ 1
[    0.631396] initcall init_sched_debug_procfs+0x0/0x2f returned 0 after 1 usecs
[    0.631400] calling  snapshot_device_init+0x0/0x14 @ 1
[    0.631621] initcall snapshot_device_init+0x0/0x14 returned 0 after 203 usecs
[    0.631629] calling  irq_gc_init_ops+0x0/0x16 @ 1
[    0.631633] initcall irq_gc_init_ops+0x0/0x16 returned 0 after 0 usecs
[    0.631637] calling  irq_pm_init_ops+0x0/0x16 @ 1
[    0.631640] initcall irq_pm_init_ops+0x0/0x16 returned 0 after 0 usecs
[    0.631644] calling  timekeeping_init_ops+0x0/0x16 @ 1
[    0.631647] initcall timekeeping_init_ops+0x0/0x16 returned 0 after 0 usecs
[    0.631651] calling  init_clocksource_sysfs+0x0/0x24 @ 1
[    0.631748] initcall init_clocksource_sysfs+0x0/0x24 returned 0 after 90 usecs
[    0.631752] calling  init_timer_list_procfs+0x0/0x2f @ 1
[    0.631762] initcall init_timer_list_procfs+0x0/0x2f returned 0 after 6 usecs
[    0.631766] calling  alarmtimer_init+0x0/0x114 @ 1
[    0.631844] probe of alarmtimer returned 1 after 17 usecs
[    0.631851] initcall alarmtimer_init+0x0/0x114 returned 0 after 79 usecs
[    0.631855] calling  init_posix_timers+0x0/0x29 @ 1
[    0.631887] initcall init_posix_timers+0x0/0x29 returned 0 after 28 usecs
[    0.631891] calling  clockevents_init_sysfs+0x0/0x9e @ 1
[    0.631992] initcall clockevents_init_sysfs+0x0/0x9e returned 0 after 94 usecs
[    0.631997] calling  proc_dma_init+0x0/0x21 @ 1
[    0.632002] initcall proc_dma_init+0x0/0x21 returned 0 after 2 usecs
[    0.632006] calling  proc_modules_init+0x0/0x1f @ 1
[    0.632011] initcall proc_modules_init+0x0/0x1f returned 0 after 1 usecs
[    0.632015] calling  kallsyms_init+0x0/0x22 @ 1
[    0.632020] initcall kallsyms_init+0x0/0x22 returned 0 after 1 usecs
[    0.632024] calling  pid_namespaces_init+0x0/0x3b @ 1
[    0.632040] initcall pid_namespaces_init+0x0/0x3b returned 0 after 11 usecs
[    0.632044] calling  audit_watch_init+0x0/0x38 @ 1
[    0.632049] initcall audit_watch_init+0x0/0x38 returned 0 after 1 usecs
[    0.632052] calling  audit_fsnotify_init+0x0/0x38 @ 1
[    0.632056] initcall audit_fsnotify_init+0x0/0x38 returned 0 after 0 usecs
[    0.632060] calling  audit_tree_init+0x0/0x40 @ 1
[    0.632065] initcall audit_tree_init+0x0/0x40 returned 0 after 1 usecs
[    0.632069] calling  init_kprobes+0x0/0x191 @ 1
[    0.632364] initcall init_kprobes+0x0/0x191 returned 0 after 284 usecs
[    0.632368] calling  seccomp_sysctl_init+0x0/0x2a @ 1
[    0.632378] initcall seccomp_sysctl_init+0x0/0x2a returned 0 after 6 usecs
[    0.632383] calling  utsname_sysctl_init+0x0/0x16 @ 1
[    0.632394] initcall utsname_sysctl_init+0x0/0x16 returned 0 after 7 usecs
[    0.632398] calling  init_tracepoints+0x0/0x2b @ 1
[    0.632402] initcall init_tracepoints+0x0/0x2b returned 0 after 0 usecs
[    0.632406] calling  stack_trace_init+0x0/0x9e @ 1
[    0.632430] initcall stack_trace_init+0x0/0x9e returned 0 after 19 usecs
[    0.632434] calling  init_mmio_trace+0x0/0xf @ 1
[    0.632441] initcall init_mmio_trace+0x0/0xf returned 0 after 3 usecs
[    0.632445] calling  init_blk_tracer+0x0/0x4d @ 1
[    0.632459] initcall init_blk_tracer+0x0/0x4d returned 0 after 9 usecs
[    0.632465] calling  perf_event_sysfs_init+0x0/0x78 @ 1
[    0.632708] initcall perf_event_sysfs_init+0x0/0x78 returned 0 after 232 usecs
[    0.632711] calling  init_uprobes+0x0/0x56 @ 1
[    0.632717] initcall init_uprobes+0x0/0x56 returned 0 after 3 usecs
[    0.632720] calling  padata_driver_init+0x0/0x35 @ 1
[    0.632725] initcall padata_driver_init+0x0/0x35 returned 0 after 1 usecs
[    0.632728] calling  system_trusted_keyring_init+0x0/0xd3 @ 1
[    0.632729] Initialise system trusted keyrings
[    0.632756] initcall system_trusted_keyring_init+0x0/0xd3 returned 0 after 24 usecs
[    0.632760] calling  blacklist_init+0x0/0x87 @ 1
[    0.632762] Key type blacklist registered
[    0.632768] initcall blacklist_init+0x0/0x87 returned 0 after 5 usecs
[    0.632772] calling  kswapd_init+0x0/0x3b @ 1
[    0.633008] initcall kswapd_init+0x0/0x3b returned 0 after 215 usecs
[    0.633017] calling  extfrag_debug_init+0x0/0x71 @ 1
[    0.633064] initcall extfrag_debug_init+0x0/0x71 returned 0 after 41 usecs
[    0.633068] calling  mm_compute_batch_init+0x0/0x3b @ 1
[    0.633072] initcall mm_compute_batch_init+0x0/0x3b returned 0 after 0 usecs
[    0.633076] calling  slab_proc_init+0x0/0x22 @ 1
[    0.633087] initcall slab_proc_init+0x0/0x22 returned 0 after 7 usecs
[    0.633092] calling  workingset_init+0x0/0x88 @ 1
[    0.633094] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[    0.633108] initcall workingset_init+0x0/0x88 returned 0 after 12 usecs
[    0.633112] calling  proc_vmalloc_init+0x0/0x26 @ 1
[    0.633118] initcall proc_vmalloc_init+0x0/0x26 returned 0 after 2 usecs
[    0.633186] calling  procswaps_init+0x0/0x1f @ 1
[    0.633358] initcall procswaps_init+0x0/0x1f returned 0 after 163 usecs
[    0.633363] calling  init_frontswap+0x0/0x82 @ 1
[    0.633377] initcall init_frontswap+0x0/0x82 returned 0 after 10 usecs
[    0.633382] calling  slab_sysfs_init+0x0/0xd0 @ 1
[    0.636163] initcall slab_sysfs_init+0x0/0xd0 returned 0 after 2710 usecs
[    0.636169] calling  init_zbud+0x0/0x20 @ 1
[    0.636170] zbud: loaded
[    0.636175] initcall init_zbud+0x0/0x20 returned 0 after 1 usecs
[    0.636179] calling  fcntl_init+0x0/0x29 @ 1
[    0.636189] initcall fcntl_init+0x0/0x29 returned 0 after 5 usecs
[    0.636194] calling  proc_filesystems_init+0x0/0x21 @ 1
[    0.636201] initcall proc_filesystems_init+0x0/0x21 returned 0 after 2 usecs
[    0.636205] calling  start_dirtytime_writeback+0x0/0x2c @ 1
[    0.636211] initcall start_dirtytime_writeback+0x0/0x2c returned 0 after 2 usecs
[    0.636214] calling  blkdev_init+0x0/0x20 @ 1
[    0.636231] initcall blkdev_init+0x0/0x20 returned 0 after 13 usecs
[    0.636234] calling  dio_init+0x0/0x2c @ 1
[    0.636296] initcall dio_init+0x0/0x2c returned 0 after 56 usecs
[    0.636299] calling  dnotify_init+0x0/0x6f @ 1
[    0.636312] initcall dnotify_init+0x0/0x6f returned 0 after 9 usecs
[    0.636315] calling  fanotify_user_setup+0x0/0x6c @ 1
[    0.636373] initcall fanotify_user_setup+0x0/0x6c returned 0 after 53 usecs
[    0.636377] calling  userfaultfd_init+0x0/0x2c @ 1
[    0.636417] initcall userfaultfd_init+0x0/0x2c returned 0 after 35 usecs
[    0.636420] calling  aio_setup+0x0/0x6e @ 1
[    0.636449] initcall aio_setup+0x0/0x6e returned 0 after 25 usecs
[    0.636454] calling  init_devpts_fs+0x0/0x28 @ 1
[    0.636474] initcall init_devpts_fs+0x0/0x28 returned 0 after 16 usecs
[    0.636477] calling  init_pstore_fs+0x0/0x47 @ 1
[    0.636479] pstore: using deflate compression
[    0.636485] initcall init_pstore_fs+0x0/0x47 returned 0 after 4 usecs
[    0.636488] calling  ipc_init+0x0/0x27 @ 1
[    0.636504] initcall ipc_init+0x0/0x27 returned 0 after 11 usecs
[    0.636507] calling  ipc_sysctl_init+0x0/0x16 @ 1
[    0.636523] initcall ipc_sysctl_init+0x0/0x16 returned 0 after 12 usecs
[    0.636527] calling  init_mqueue_fs+0x0/0xee @ 1
[    0.636620] initcall init_mqueue_fs+0x0/0xee returned 0 after 85 usecs
[    0.636624] calling  key_proc_init+0x0/0x5c @ 1
[    0.636631] initcall key_proc_init+0x0/0x5c returned 0 after 4 usecs
[    0.636635] calling  selinux_nf_ip_init+0x0/0x49 @ 1
[    0.636639] initcall selinux_nf_ip_init+0x0/0x49 returned 0 after 0 usecs
[    0.636642] calling  init_sel_fs+0x0/0xfa @ 1
[    0.636646] initcall init_sel_fs+0x0/0xfa returned 0 after 0 usecs
[    0.636649] calling  selnl_init+0x0/0x6e @ 1
[    0.636666] initcall selnl_init+0x0/0x6e returned 0 after 12 usecs
[    0.636669] calling  sel_netif_init+0x0/0x36 @ 1
[    0.636673] initcall sel_netif_init+0x0/0x36 returned 0 after 0 usecs
[    0.636676] calling  sel_netnode_init+0x0/0x30 @ 1
[    0.636680] initcall sel_netnode_init+0x0/0x30 returned 0 after 0 usecs
[    0.636683] calling  sel_netport_init+0x0/0x30 @ 1
[    0.636687] initcall sel_netport_init+0x0/0x30 returned 0 after 0 usecs
[    0.636691] calling  aurule_init+0x0/0x2a @ 1
[    0.636694] initcall aurule_init+0x0/0x2a returned 0 after 0 usecs
[    0.636698] calling  fips_init+0x0/0x1b @ 1
[    0.636705] initcall fips_init+0x0/0x1b returned 0 after 3 usecs
[    0.636709] calling  crypto_algapi_init+0x0/0x11 @ 1
[    0.636714] initcall crypto_algapi_init+0x0/0x11 returned 0 after 1 usecs
[    0.636719] calling  dh_init+0x0/0x20 @ 1
[    0.637017] alg: self-tests disabled
[    0.637105] initcall dh_init+0x0/0x20 returned 0 after 358 usecs
[    0.637113] calling  rsa_init+0x0/0x40 @ 1
[    0.637477] initcall rsa_init+0x0/0x40 returned 0 after 336 usecs
[    0.637488] calling  hmac_module_init+0x0/0x14 @ 1
[    0.637492] initcall hmac_module_init+0x0/0x14 returned 0 after 0 usecs
[    0.637497] calling  crypto_null_mod_init+0x0/0x46 @ 1
[    0.637949] initcall crypto_null_mod_init+0x0/0x46 returned 0 after 424 usecs
[    0.637958] calling  md5_mod_init+0x0/0x14 @ 1
[    0.638023] initcall md5_mod_init+0x0/0x14 returned 0 after 59 usecs
[    0.638028] calling  sha1_generic_mod_init+0x0/0x14 @ 1
[    0.638089] initcall sha1_generic_mod_init+0x0/0x14 returned 0 after 55 usecs
[    0.638094] calling  sha256_generic_mod_init+0x0/0x19 @ 1
[    0.638221] initcall sha256_generic_mod_init+0x0/0x19 returned 0 after 119 usecs
[    0.638226] calling  aes_init+0x0/0x14 @ 1
[    0.638283] initcall aes_init+0x0/0x14 returned 0 after 51 usecs
[    0.638287] calling  deflate_mod_init+0x0/0x3d @ 1
[    0.638469] initcall deflate_mod_init+0x0/0x3d returned 0 after 172 usecs
[    0.638474] calling  crct10dif_mod_init+0x0/0x14 @ 1
[    0.638539] initcall crct10dif_mod_init+0x0/0x14 returned 0 after 59 usecs
[    0.638543] calling  lzo_mod_init+0x0/0x38 @ 1
[    0.638666] initcall lzo_mod_init+0x0/0x38 returned 0 after 115 usecs
[    0.638671] calling  asymmetric_key_init+0x0/0x14 @ 1
[    0.638674] Key type asymmetric registered
[    0.638677] initcall asymmetric_key_init+0x0/0x14 returned 0 after 2 usecs
[    0.638681] calling  x509_key_init+0x0/0x14 @ 1
[    0.638685] Asymmetric key parser 'x509' registered
[    0.638688] initcall x509_key_init+0x0/0x14 returned 0 after 3 usecs
[    0.638693] calling  proc_genhd_init+0x0/0x3a @ 1
[    0.638704] initcall proc_genhd_init+0x0/0x3a returned 0 after 7 usecs
[    0.638709] calling  init_emergency_pool+0x0/0x52 @ 1
[    0.638735] bounce: pool size: 64 pages
[    0.638744] initcall init_emergency_pool+0x0/0x52 returned 0 after 30 usecs
[    0.638748] calling  bsg_init+0x0/0x12c @ 1
[    0.638775] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    0.638780] initcall bsg_init+0x0/0x12c returned 0 after 27 usecs
[    0.638784] calling  throtl_init+0x0/0x3c @ 1
[    0.638865] initcall throtl_init+0x0/0x3c returned 0 after 75 usecs
[    0.638870] calling  noop_init+0x0/0x14 @ 1
[    0.638873] io scheduler noop registered
[    0.638876] initcall noop_init+0x0/0x14 returned 0 after 2 usecs
[    0.638880] calling  deadline_init+0x0/0x14 @ 1
[    0.638882] io scheduler deadline registered
[    0.638885] initcall deadline_init+0x0/0x14 returned 0 after 1 usecs
[    0.638889] calling  cfq_init+0x0/0x6e @ 1
[    0.639014] io scheduler cfq registered (default)
[    0.639019] initcall cfq_init+0x0/0x6e returned 0 after 122 usecs
[    0.639023] calling  deadline_init+0x0/0x14 @ 1
[    0.639025] io scheduler mq-deadline registered
[    0.639029] initcall deadline_init+0x0/0x14 returned 0 after 1 usecs
[    0.639033] calling  btree_module_init+0x0/0x24 @ 1
[    0.639042] initcall btree_module_init+0x0/0x24 returned 0 after 4 usecs
[    0.639046] calling  crc_t10dif_mod_init+0x0/0x35 @ 1
[    0.639054] initcall crc_t10dif_mod_init+0x0/0x35 returned 0 after 3 usecs
[    0.639059] calling  percpu_counter_startup+0x0/0x50 @ 1
[    0.639131] initcall percpu_counter_startup+0x0/0x50 returned 0 after 59 usecs
[    0.639139] calling  audit_classes_init+0x0/0x4f @ 1
[    0.639152] initcall audit_classes_init+0x0/0x4f returned 0 after 8 usecs
[    0.639156] calling  digsig_init+0x0/0x34 @ 1
[    0.639165] initcall digsig_init+0x0/0x34 returned 0 after 4 usecs
[    0.639170] calling  sg_pool_init+0x0/0xab @ 1
[    0.639213] initcall sg_pool_init+0x0/0xab returned 0 after 37 usecs
[    0.639218] calling  phy_core_init+0x0/0x44 @ 1
[    0.639232] initcall phy_core_init+0x0/0x44 returned 0 after 9 usecs
[    0.639236] calling  amd_gpio_driver_init+0x0/0x16 @ 1
[    0.639266] initcall amd_gpio_driver_init+0x0/0x16 returned 0 after 24 usecs
[    0.639271] calling  cnl_pinctrl_driver_init+0x0/0x16 @ 1
[    0.639287] initcall cnl_pinctrl_driver_init+0x0/0x16 returned 0 after 11 usecs
[    0.639292] calling  icl_pinctrl_driver_init+0x0/0x16 @ 1
[    0.639311] initcall icl_pinctrl_driver_init+0x0/0x16 returned 0 after 14 usecs
[    0.639316] calling  lbg_pinctrl_driver_init+0x0/0x16 @ 1
[    0.639330] initcall lbg_pinctrl_driver_init+0x0/0x16 returned 0 after 10 usecs
[    0.639335] calling  pci_proc_init+0x0/0x63 @ 1
[    0.639402] initcall pci_proc_init+0x0/0x63 returned 0 after 60 usecs
[    0.639407] calling  pcie_portdrv_init+0x0/0x34 @ 1
[    0.640107] probe of 0000:00:15.0 returned 1 after 683 usecs
[    0.640560] probe of 0000:00:15.1 returned 1 after 440 usecs
[    0.640585] initcall pcie_portdrv_init+0x0/0x34 returned 0 after 1145 usecs
[    0.640591] calling  aer_service_init+0x0/0x3c @ 1
[    0.640605] initcall aer_service_init+0x0/0x3c returned 0 after 10 usecs
[    0.640610] calling  pcie_pme_service_init+0x0/0x14 @ 1
[    0.640726] pcieport 0000:00:15.0: Signaling PME with IRQ 24
[    0.640737] probe of 0000:00:15.0:pcie001 returned 1 after 120 usecs
[    0.640784] pcieport 0000:00:15.1: Signaling PME with IRQ 25
[    0.640795] probe of 0000:00:15.1:pcie001 returned 1 after 43 usecs
[    0.640807] initcall pcie_pme_service_init+0x0/0x14 returned 0 after 187 usecs
[    0.640812] calling  dpc_service_init+0x0/0x14 @ 1
[    0.640825] initcall dpc_service_init+0x0/0x14 returned 0 after 8 usecs
[    0.640830] calling  pci_hotplug_init+0x0/0x35 @ 1
[    0.640834] initcall pci_hotplug_init+0x0/0x35 returned 0 after 0 usecs
[    0.640839] calling  pcied_init+0x0/0x56 @ 1
[    0.640853] initcall pcied_init+0x0/0x56 returned 0 after 9 usecs
[    0.640857] calling  shpcd_init+0x0/0x53 @ 1
[    0.640888] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    0.640892] initcall shpcd_init+0x0/0x53 returned 0 after 29 usecs
[    0.640895] calling  xenfb_init+0x0/0x43 @ 1
[    0.640899] initcall xenfb_init+0x0/0x43 returned -19 after 1 usecs
[    0.640902] calling  vesafb_driver_init+0x0/0x16 @ 1
[    0.640919] initcall vesafb_driver_init+0x0/0x16 returned 0 after 12 usecs
[    0.640922] calling  efifb_driver_init+0x0/0x16 @ 1
[    0.640936] initcall efifb_driver_init+0x0/0x16 returned 0 after 10 usecs
[    0.640939] calling  intel_idle_init+0x0/0x517 @ 1
[    0.640943] initcall intel_idle_init+0x0/0x517 returned -19 after 1 usecs
[    0.640948] calling  acpi_processor_driver_init+0x0/0x8d @ 1
[    0.641395] probe of cpu0 returned 1 after 435 usecs
[    0.641659] probe of cpu1 returned 1 after 251 usecs
[    0.641683] initcall acpi_processor_driver_init+0x0/0x8d returned 0 after 708 usecs
[    0.641689] calling  acpi_hed_driver_init+0x0/0x14 @ 1
[    0.641738] initcall acpi_hed_driver_init+0x0/0x14 returned 0 after 42 usecs
[    0.641742] calling  erst_init+0x0/0x2d6 @ 1
[    0.641747] initcall erst_init+0x0/0x2d6 returned 0 after 1 usecs
[    0.641751] calling  ghes_init+0x0/0x129 @ 1
[    0.641792] GHES: Failed to enable APEI firmware first mode.
[    0.641797] initcall ghes_init+0x0/0x129 returned 0 after 40 usecs
[    0.641801] calling  extlog_init+0x0/0x367 @ 1
[    0.641805] initcall extlog_init+0x0/0x367 returned -19 after 0 usecs
[    0.641809] calling  pnpbios_thread_init+0x0/0x62 @ 1
[    0.641813] initcall pnpbios_thread_init+0x0/0x62 returned 0 after 0 usecs
[    0.641817] calling  isapnp_init+0x0/0xae6 @ 1
[    0.641818] isapnp: ISA Plug & Play support disabled
[    0.641822] initcall isapnp_init+0x0/0xae6 returned 0 after 2 usecs
[    0.641826] calling  gpio_clk_driver_init+0x0/0x16 @ 1
[    0.641843] initcall gpio_clk_driver_init+0x0/0x16 returned 0 after 12 usecs
[    0.641847] calling  plt_clk_driver_init+0x0/0x16 @ 1
[    0.641862] initcall plt_clk_driver_init+0x0/0x16 returned 0 after 10 usecs
[    0.641866] calling  st_clk_driver_init+0x0/0x16 @ 1
[    0.641880] initcall st_clk_driver_init+0x0/0x16 returned 0 after 9 usecs
[    0.641885] calling  xenbus_probe_initcall+0x0/0x45 @ 1
[    0.641889] initcall xenbus_probe_initcall+0x0/0x45 returned -19 after 0 usecs
[    0.641893] calling  xenbus_init+0x0/0x39 @ 1
[    0.641897] initcall xenbus_init+0x0/0x39 returned -19 after 0 usecs
[    0.641901] calling  xenbus_backend_init+0x0/0x42 @ 1
[    0.641905] initcall xenbus_backend_init+0x0/0x42 returned -19 after 0 usecs
[    0.641910] calling  hyper_sysfs_init+0x0/0x143 @ 1
[    0.641914] initcall hyper_sysfs_init+0x0/0x143 returned -19 after 0 usecs
[    0.641918] calling  hypervisor_subsys_init+0x0/0x26 @ 1
[    0.641922] initcall hypervisor_subsys_init+0x0/0x26 returned -19 after 0 usecs
[    0.641926] calling  platform_driver_init+0x0/0x1b @ 1
[    0.641958] initcall platform_driver_init+0x0/0x1b returned 0 after 26 usecs
[    0.641962] calling  n_null_init+0x0/0x21 @ 1
[    0.641966] initcall n_null_init+0x0/0x21 returned 0 after 0 usecs
[    0.641971] calling  pty_init+0x0/0x1d6 @ 1
[    0.642155] initcall pty_init+0x0/0x1d6 returned 0 after 167 usecs
[    0.642165] calling  sysrq_init+0x0/0x68 @ 1
[    0.642178] initcall sysrq_init+0x0/0x68 returned 0 after 9 usecs
[    0.642183] calling  xen_hvc_init+0x0/0x203 @ 1
[    0.642187] initcall xen_hvc_init+0x0/0x203 returned -19 after 0 usecs
[    0.642192] initcall serial8250_init blacklisted
[    0.642197] calling  serial_pci_driver_init+0x0/0x1b @ 1
[    0.642287] initcall serial_pci_driver_init+0x0/0x1b returned 0 after 83 usecs
[    0.642293] calling  dw8250_platform_driver_init+0x0/0x16 @ 1
[    0.642319] initcall dw8250_platform_driver_init+0x0/0x16 returned 0 after 21 usecs
[    0.642324] calling  mid8250_pci_driver_init+0x0/0x1b @ 1
[    0.642347] initcall mid8250_pci_driver_init+0x0/0x1b returned 0 after 18 usecs
[    0.642352] calling  hpet_init+0x0/0x5c @ 1
[    0.642490] initcall hpet_init+0x0/0x5c returned 0 after 130 usecs
[    0.642495] calling  agp_init+0x0/0x29 @ 1
[    0.642497] Linux agpgart interface v0.103
[    0.642500] initcall agp_init+0x0/0x29 returned 0 after 1 usecs
[    0.642505] calling  agp_ali_init+0x0/0x2a @ 1
[    0.642528] initcall agp_ali_init+0x0/0x2a returned 0 after 17 usecs
[    0.642532] calling  agp_ati_init+0x0/0x2a @ 1
[    0.642559] initcall agp_ati_init+0x0/0x2a returned 0 after 21 usecs
[    0.642564] calling  agp_amdk7_init+0x0/0x2a @ 1
[    0.642585] initcall agp_amdk7_init+0x0/0x2a returned 0 after 16 usecs
[    0.642590] calling  agp_amd64_mod_init+0x0/0xf @ 1
[    0.642688] probe of 0000:00:00.0 returned 0 after 32 usecs
[    0.642708] probe of 0000:00:01.0 returned 0 after 14 usecs
[    0.642792] probe of 0000:00:01.1 returned 0 after 80 usecs
[    0.642879] probe of 0000:00:11.0 returned 0 after 82 usecs
[    0.642892] probe of 0000:00:12.0 returned 0 after 9 usecs
[    0.642909] probe of 0000:00:12.2 returned 0 after 13 usecs
[    0.642922] probe of 0000:00:13.0 returned 0 after 9 usecs
[    0.642939] probe of 0000:00:13.2 returned 0 after 13 usecs
[    0.642951] probe of 0000:00:14.0 returned 0 after 8 usecs
[    0.642967] probe of 0000:00:14.2 returned 0 after 12 usecs
[    0.642980] probe of 0000:00:14.3 returned 0 after 9 usecs
[    0.642993] probe of 0000:00:14.4 returned 0 after 9 usecs
[    0.643008] probe of 0000:00:14.5 returned 0 after 10 usecs
[    0.643022] probe of 0000:00:18.0 returned 0 after 9 usecs
[    0.643034] probe of 0000:00:18.1 returned 0 after 9 usecs
[    0.643047] probe of 0000:00:18.2 returned 0 after 8 usecs
[    0.643060] probe of 0000:00:18.3 returned 0 after 10 usecs
[    0.643073] probe of 0000:00:18.4 returned 0 after 8 usecs
[    0.643085] probe of 0000:00:18.5 returned 0 after 8 usecs
[    0.643097] probe of 0000:00:18.6 returned 0 after 8 usecs
[    0.643109] probe of 0000:00:18.7 returned 0 after 8 usecs
[    0.643209] probe of 0000:03:00.0 returned 0 after 96 usecs
[    0.643229] initcall agp_amd64_mod_init+0x0/0xf returned -19 after 618 usecs
[    0.643234] calling  agp_efficeon_init+0x0/0x3e @ 1
[    0.643257] initcall agp_efficeon_init+0x0/0x3e returned 0 after 18 usecs
[    0.643262] calling  agp_intel_init+0x0/0x2a @ 1
[    0.643289] initcall agp_intel_init+0x0/0x2a returned 0 after 22 usecs
[    0.643294] calling  agp_nvidia_init+0x0/0x2a @ 1
[    0.643316] initcall agp_nvidia_init+0x0/0x2a returned 0 after 16 usecs
[    0.643320] calling  agp_sis_init+0x0/0x2a @ 1
[    0.643344] initcall agp_sis_init+0x0/0x2a returned 0 after 19 usecs
[    0.643349] calling  agp_serverworks_init+0x0/0x2a @ 1
[    0.643371] initcall agp_serverworks_init+0x0/0x2a returned 0 after 17 usecs
[    0.643376] calling  agp_via_init+0x0/0x2a @ 1
[    0.643400] initcall agp_via_init+0x0/0x2a returned 0 after 19 usecs
[    0.643404] calling  cn_proc_init+0x0/0x35 @ 1
[    0.643409] initcall cn_proc_init+0x0/0x35 returned 0 after 2 usecs
[    0.643415] calling  topology_sysfs_init+0x0/0x30 @ 1
[    0.643440] initcall topology_sysfs_init+0x0/0x30 returned 0 after 20 usecs
[    0.643443] calling  cacheinfo_sysfs_init+0x0/0x2a @ 1
[    0.643892] initcall cacheinfo_sysfs_init+0x0/0x2a returned 171 after 423 usecs
[    0.643900] calling  devcoredump_init+0x0/0x19 @ 1
[    0.643920] initcall devcoredump_init+0x0/0x19 returned 0 after 15 usecs
[    0.643923] calling  cht_wc_driver_init+0x0/0x16 @ 1
[    0.643942] initcall cht_wc_driver_init+0x0/0x16 returned 0 after 14 usecs
[    0.643946] calling  spidev_init+0x0/0xa4 @ 1
[    0.643968] initcall spidev_init+0x0/0xa4 returned 0 after 18 usecs
[    0.643972] calling  net_olddevs_init+0x0/0x53 @ 1
[    0.643981] initcall net_olddevs_init+0x0/0x53 returned 0 after 5 usecs
[    0.643985] calling  i8042_init+0x0/0x41e @ 1
[    0.644024] probe of 00:01 returned 1 after 23 usecs
[    0.644041] probe of 00:03 returned 1 after 10 usecs
[    0.644070] probe of 00:02 returned 1 after 13 usecs
[    0.644084] probe of 00:04 returned 1 after 9 usecs
[    0.644093] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[    0.648088] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.648116] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.648296] probe of i8042 returned 1 after 3911 usecs
[    0.648317] initcall i8042_init+0x0/0x41e returned 0 after 4222 usecs
[    0.648323] calling  input_leds_init+0x0/0x14 @ 1
[    0.648327] initcall input_leds_init+0x0/0x14 returned 0 after 1 usecs
[    0.648331] calling  mousedev_init+0x0/0x7a @ 1
[    0.648827] mousedev: PS/2 mouse device common for all mice
[    0.648839] initcall mousedev_init+0x0/0x7a returned 0 after 487 usecs
[    0.648845] calling  atkbd_init+0x0/0x25 @ 1
[    0.648888] initcall atkbd_init+0x0/0x25 returned 0 after 37 usecs
[    0.648892] calling  xenkbd_init+0x0/0x43 @ 1
[    0.648896] initcall xenkbd_init+0x0/0x43 returned -19 after 0 usecs
[    0.648899] calling  cmos_init+0x0/0x65 @ 1
[    0.649044] rtc_cmos 00:00: RTC can wake from S4
[    0.649432] rtc_cmos 00:00: registered as rtc0
[    0.649470] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[    0.649478] probe of 00:00 returned 1 after 460 usecs
[    0.649493] initcall cmos_init+0x0/0x65 returned 0 after 575 usecs
[    0.649498] calling  watchdog_gov_noop_register+0x0/0x14 @ 1
[    0.649503] initcall watchdog_gov_noop_register+0x0/0x14 returned 0 after 1 usecs
[    0.649507] calling  intel_pstate_init+0x0/0x327 @ 1
[    0.649512] initcall intel_pstate_init+0x0/0x327 returned -19 after 1 usecs
[    0.649516] calling  ledtrig_disk_init+0x0/0x48 @ 1
[    0.649523] initcall ledtrig_disk_init+0x0/0x48 returned 0 after 4 usecs
[    0.649527] calling  ledtrig_mtd_init+0x0/0x2a @ 1
[    0.649532] initcall ledtrig_mtd_init+0x0/0x2a returned 0 after 1 usecs
[    0.649535] calling  ledtrig_cpu_init+0x0/0xb3 @ 1
[    0.649645] ledtrig-cpu: registered to indicate activity on CPUs
[    0.649649] initcall ledtrig_cpu_init+0x0/0xb3 returned 0 after 107 usecs
[    0.649653] calling  ledtrig_panic_init+0x0/0x34 @ 1
[    0.649659] initcall ledtrig_panic_init+0x0/0x34 returned 0 after 2 usecs
[    0.649663] calling  dmi_sysfs_init+0x0/0xc1 @ 1
[    0.649755] initcall dmi_sysfs_init+0x0/0xc1 returned 0 after 85 usecs
[    0.649760] calling  efi_load_efivars+0x0/0x64 @ 1
[    0.649764] initcall efi_load_efivars+0x0/0x64 returned 0 after 0 usecs
[    0.649768] calling  esrt_sysfs_init+0x0/0x2ab @ 1
[    0.649772] initcall esrt_sysfs_init+0x0/0x2ab returned -38 after 0 usecs
[    0.649777] calling  efi_capsule_loader_init+0x0/0x39 @ 1
[    0.649781] initcall efi_capsule_loader_init+0x0/0x39 returned -19 after 0 usecs
[    0.649785] calling  pmc_atom_init+0x0/0x226 @ 1
[    0.649806] initcall pmc_atom_init+0x0/0x226 returned -19 after 16 usecs
[    0.649810] calling  powercap_init+0x0/0x1f9 @ 1
[    0.649885] initcall powercap_init+0x0/0x1f9 returned 0 after 68 usecs
[    0.649891] calling  pm_check_save_msr+0x0/0x20 @ 1
[    0.649895] initcall pm_check_save_msr+0x0/0x20 returned 0 after 0 usecs
[    0.649898] calling  sock_diag_init+0x0/0x34 @ 1
[    0.649944] initcall sock_diag_init+0x0/0x34 returned 0 after 40 usecs
[    0.649948] calling  blackhole_init+0x0/0x14 @ 1
[    0.649952] initcall blackhole_init+0x0/0x14 returned 0 after 1 usecs
[    0.649956] calling  gre_offload_init+0x0/0x47 @ 1
[    0.649960] initcall gre_offload_init+0x0/0x47 returned 0 after 0 usecs
[    0.649964] calling  sysctl_ipv4_init+0x0/0x45 @ 1
[    0.650056] initcall sysctl_ipv4_init+0x0/0x45 returned 0 after 86 usecs
[    0.650060] calling  cubictcp_register+0x0/0x71 @ 1
[    0.650064] initcall cubictcp_register+0x0/0x71 returned 0 after 1 usecs
[    0.650068] calling  inet6_init+0x0/0x32d @ 1
[    0.650344] NET: Registered protocol family 10
[    0.671024] Segment Routing with IPv6
[    0.671085] initcall inet6_init+0x0/0x32d returned 0 after 20511 usecs
[    0.671092] calling  mip6_init+0x0/0xac @ 1
[    0.671093] mip6: Mobile IPv6
[    0.671098] initcall mip6_init+0x0/0xac returned 0 after 2 usecs
[    0.671102] calling  packet_init+0x0/0x3e @ 1
[    0.671105] NET: Registered protocol family 17
[    0.671116] initcall packet_init+0x0/0x3e returned 0 after 10 usecs
[    0.671120] calling  strp_mod_init+0x0/0x2b @ 1
[    0.671234] initcall strp_mod_init+0x0/0x2b returned 0 after 106 usecs
[    0.671239] calling  dcbnl_init+0x0/0x4a @ 1
[    0.671244] initcall dcbnl_init+0x0/0x4a returned 0 after 1 usecs
[    0.671248] calling  mpls_gso_init+0x0/0x2a @ 1
[    0.671249] mpls_gso: MPLS GSO support
[    0.671253] initcall mpls_gso_init+0x0/0x2a returned 0 after 2 usecs
[    0.671258] calling  mcheck_init_device+0x0/0x105 @ 1
[    0.671767] initcall mcheck_init_device+0x0/0x105 returned 0 after 480 usecs
[    0.671944] calling  mcheck_late_init+0x0/0x69 @ 1
[    0.671969] initcall mcheck_late_init+0x0/0x69 returned 0 after 20 usecs
[    0.671973] calling  severities_debugfs_init+0x0/0x3a @ 1
[    0.671979] initcall severities_debugfs_init+0x0/0x3a returned 0 after 2 usecs
[    0.671983] calling  threshold_init_device+0x0/0x47 @ 1
[    0.671988] initcall threshold_init_device+0x0/0x47 returned 0 after 1 usecs
[    0.671992] calling  microcode_init+0x0/0x1c1 @ 1
[    0.672097] microcode: CPU0: patch_level=0x05000119
[    0.672183] microcode: CPU1: patch_level=0x05000119
[    0.672382] microcode: Microcode Update Driver: v2.2.
[    0.672388] initcall microcode_init+0x0/0x1c1 returned 0 after 381 usecs
[    0.672393] calling  hpet_insert_resource+0x0/0x24 @ 1
[    0.672400] initcall hpet_insert_resource+0x0/0x24 returned 0 after 3 usecs
[    0.672405] calling  update_mp_table+0x0/0x4d1 @ 1
[    0.672410] initcall update_mp_table+0x0/0x4d1 returned 0 after 0 usecs
[    0.672415] calling  lapic_insert_resource+0x0/0x4b @ 1
[    0.672419] initcall lapic_insert_resource+0x0/0x4b returned 0 after 0 usecs
[    0.672422] calling  print_ICs+0x0/0x17b @ 1
[    0.672425] initcall print_ICs+0x0/0x17b returned 0 after 0 usecs
[    0.672428] calling  print_ipi_mode+0x0/0x2b @ 1
[    0.672430] Using IPI No-Shortcut mode
[    0.672433] initcall print_ipi_mode+0x0/0x2b returned 0 after 1 usecs
[    0.672437] calling  pat_memtype_list_init+0x0/0x36 @ 1
[    0.672456] initcall pat_memtype_list_init+0x0/0x36 returned 0 after 14 usecs
[    0.672460] calling  create_tlb_single_page_flush_ceiling+0x0/0x28 @ 1
[    0.672468] initcall create_tlb_single_page_flush_ceiling+0x0/0x28 returned 0 after 4 usecs
[    0.672473] calling  init_oops_id+0x0/0x50 @ 1
[    0.672479] initcall init_oops_id+0x0/0x50 returned 0 after 3 usecs
[    0.672485] calling  sched_clock_init_late+0x0/0x93 @ 1
[    0.672489] sched_clock: Marking stable (669586494, 2779812)->(784037211, -111670905)
[    0.672659] initcall sched_clock_init_late+0x0/0x93 returned 0 after 166 usecs
[    0.672664] calling  sched_init_debug+0x0/0x3a @ 1
[    0.672675] initcall sched_init_debug+0x0/0x3a returned 0 after 6 usecs
[    0.672680] calling  pm_qos_power_init+0x0/0x9a @ 1
[    0.673215] initcall pm_qos_power_init+0x0/0x9a returned 0 after 514 usecs
[    0.673222] calling  pm_debugfs_init+0x0/0x24 @ 1
[    0.673231] initcall pm_debugfs_init+0x0/0x24 returned 0 after 4 usecs
[    0.673234] calling  printk_late_init+0x0/0x106 @ 1
[    0.673239] initcall printk_late_init+0x0/0x106 returned 0 after 1 usecs
[    0.673243] calling  tk_debug_sleep_time_init+0x0/0x3c @ 1
[    0.673249] initcall tk_debug_sleep_time_init+0x0/0x3c returned 0 after 2 usecs
[    0.673254] calling  debugfs_kprobe_init+0x0/0xb7 @ 1
[    0.673277] initcall debugfs_kprobe_init+0x0/0xb7 returned 0 after 18 usecs
[    0.673281] calling  taskstats_init+0x0/0x38 @ 1
[    0.673299] registered taskstats version 1
[    0.673303] initcall taskstats_init+0x0/0x38 returned 0 after 18 usecs
[    0.673307] calling  load_system_certificate_list+0x0/0xc4 @ 1
[    0.673309] Loading compiled-in X.509 certificates
[    0.675554] Loaded X.509 cert 'Debian Secure Boot CA: 6ccece7e4c6c0d1f6149f3dd27dfcc5cbb419ea1'
[    0.675610] Loaded X.509 cert 'Debian Secure Boot Signer: 00a7468def'
[    0.675616] initcall load_system_certificate_list+0x0/0xc4 returned 0 after 2249 usecs
[    0.675620] calling  load_uefi_certs+0x0/0x283 @ 1
[    0.675624] initcall load_uefi_certs+0x0/0x283 returned 0 after 0 usecs
[    0.675629] calling  fault_around_debugfs+0x0/0x35 @ 1
[    0.675637] initcall fault_around_debugfs+0x0/0x35 returned 0 after 4 usecs
[    0.675642] calling  max_swapfiles_check+0x0/0xc @ 1
[    0.675646] initcall max_swapfiles_check+0x0/0xc returned 0 after 0 usecs
[    0.675650] calling  init_zswap+0x0/0x3c3 @ 1
[    0.675683] zswap: loaded using pool lzo/zbud
[    0.675788] initcall init_zswap+0x0/0x3c3 returned 0 after 130 usecs
[    0.675794] calling  split_huge_pages_debugfs+0x0/0x35 @ 1
[    0.675800] initcall split_huge_pages_debugfs+0x0/0x35 returned 0 after 2 usecs
[    0.675805] calling  check_early_ioremap_leak+0x0/0x38 @ 1
[    0.675810] initcall check_early_ioremap_leak+0x0/0x38 returned 0 after 0 usecs
[    0.675815] calling  set_hardened_usercopy+0x0/0x28 @ 1
[    0.675819] initcall set_hardened_usercopy+0x0/0x28 returned 1 after 0 usecs
[    0.675823] calling  init_root_keyring+0x0/0xf @ 1
[    0.675839] initcall init_root_keyring+0x0/0xf returned 0 after 13 usecs
[    0.675843] calling  init_profile_hash+0x0/0x72 @ 1
[    0.675847] initcall init_profile_hash+0x0/0x72 returned 0 after 0 usecs
[    0.675851] calling  integrity_fs_init+0x0/0x48 @ 1
[    0.675859] initcall integrity_fs_init+0x0/0x48 returned 0 after 4 usecs
[    0.675863] calling  prandom_reseed+0x0/0x27 @ 1
[    0.675873] initcall prandom_reseed+0x0/0x27 returned 0 after 5 usecs
[    0.675877] calling  init_error_injection+0x0/0x61 @ 1
[    0.676011] initcall init_error_injection+0x0/0x61 returned 0 after 126 usecs
[    0.676017] calling  pci_resource_alignment_sysfs_init+0x0/0x23 @ 1
[    0.676025] initcall pci_resource_alignment_sysfs_init+0x0/0x23 returned 0 after 3 usecs
[    0.676029] calling  pci_sysfs_init+0x0/0x47 @ 1
[    0.676240] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    0.676276] initcall pci_sysfs_init+0x0/0x47 returned 0 after 235 usecs
[    0.676281] calling  bert_init+0x0/0x1cf @ 1
[    0.676285] initcall bert_init+0x0/0x1cf returned 0 after 1 usecs
[    0.676289] calling  clk_debug_init+0x0/0xe7 @ 1
[    0.676304] initcall clk_debug_init+0x0/0xe7 returned 0 after 10 usecs
[    0.676309] calling  boot_wait_for_devices+0x0/0x2d @ 1
[    0.676313] initcall boot_wait_for_devices+0x0/0x2d returned -19 after 0 usecs
[    0.676319] calling  deferred_probe_initcall+0x0/0x90 @ 1
[    0.676352] initcall deferred_probe_initcall+0x0/0x90 returned 0 after 27 usecs
[    0.676356] calling  genpd_power_off_unused+0x0/0x71 @ 1
[    0.676360] initcall genpd_power_off_unused+0x0/0x71 returned 0 after 0 usecs
[    0.676363] calling  genpd_debug_init+0x0/0x141 @ 1
[    0.676371] initcall genpd_debug_init+0x0/0x141 returned 0 after 4 usecs
[    0.676375] calling  rtc_hctosys+0x0/0xf1 @ 1
[    0.676389] probe of serio0 returned 1 after 27475 usecs
[    0.676520] rtc_cmos 00:00: setting system clock to 2019-05-24 07:56:43 UTC (1558684603)
[    0.676524] initcall rtc_hctosys+0x0/0xf1 returned 0 after 142 usecs
[    0.676529] calling  firmware_memmap_init+0x0/0x2d @ 1
[    0.676582] initcall firmware_memmap_init+0x0/0x2d returned 0 after 47 usecs
[    0.676589] calling  register_update_efi_random_seed+0x0/0x30 @ 1
[    0.676593] initcall register_update_efi_random_seed+0x0/0x30 returned 0 after 0 usecs
[    0.676597] calling  efi_shutdown_init+0x0/0x3c @ 1
[    0.676602] initcall efi_shutdown_init+0x0/0x3c returned -19 after 0 usecs
[    0.676607] calling  pci_mmcfg_late_insert_resources+0x0/0x49 @ 1
[    0.676611] initcall pci_mmcfg_late_insert_resources+0x0/0x49 returned 0 after 0 usecs
[    0.676615] calling  tcp_congestion_default+0x0/0x19 @ 1
[    0.676620] initcall tcp_congestion_default+0x0/0x19 returned 0 after 1 usecs
[    0.676624] calling  software_resume+0x0/0x210 @ 1
[    0.676629] initcall software_resume+0x0/0x210 returned -2 after 1 usecs
[    0.676635] calling  clear_boot_tracer+0x0/0x2a @ 1
[    0.676639] initcall clear_boot_tracer+0x0/0x2a returned 0 after 0 usecs
[    0.676644] calling  tracing_set_default_clock+0x0/0x36 @ 1
[    0.676647] initcall tracing_set_default_clock+0x0/0x36 returned 0 after 0 usecs
[    0.676653] calling  acpi_gpio_handle_deferred_request_irqs+0x0/0x80 @ 1
[    0.676657] initcall acpi_gpio_handle_deferred_request_irqs+0x0/0x80 returned 0 after 0 usecs
[    0.676662] calling  clk_disable_unused+0x0/0x100 @ 1
[    0.676668] initcall clk_disable_unused+0x0/0x100 returned 0 after 1 usecs
[    0.676672] calling  regulator_init_complete+0x0/0x45 @ 1
[    0.676679] initcall regulator_init_complete+0x0/0x45 returned 0 after 2 usecs
[    0.679057] Freeing unused kernel image memory: 880K
[    0.693703] Write protecting the kernel text: 6752k
[    0.693901] Write protecting the kernel read-only data: 2076k
[    0.693903] NX-protecting the kernel data: 5536k
[    0.695068] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    0.695076] Run /init as init process
[    0.720058] probe of serio1 returned 0 after 43651 usecs
[    0.858785] calling  acpi_thermal_init+0x0/0x1000 [thermal] @ 91
[    0.868288] thermal LNXTHERM:00: registered as thermal_zone0
[    0.868294] ACPI: Thermal Zone [TZ00] (52 C)
[    0.868328] probe of LNXTHERM:00 returned 1 after 637 usecs
[    0.868381] initcall acpi_thermal_init+0x0/0x1000 [thermal] returned 0 after 9357 usecs
[    0.894875] calling  init_scsi+0x0/0x80 [scsi_mod] @ 91
[    0.895024] SCSI subsystem initialized
[    0.895049] initcall init_scsi+0x0/0x80 [scsi_mod] returned 0 after 143 usecs
[    0.896474] calling  serio_raw_drv_init+0x0/0x1000 [serio_raw] @ 90
[    0.896514] initcall serio_raw_drv_init+0x0/0x1000 [serio_raw] returned 0 after 29 usecs
[    0.909645] calling  evdev_init+0x0/0x1000 [evdev] @ 90
[    0.909821] initcall evdev_init+0x0/0x1000 [evdev] returned 0 after 162 usecs
[    0.911974] calling  ata_init+0x0/0x2a5 [libata] @ 91
[    0.912145] libata version 3.00 loaded.
[    0.912172] initcall ata_init+0x0/0x2a5 [libata] returned 0 after 164 usecs
[    0.922150] calling  ahci_pci_driver_init+0x0/0x1000 [ahci] @ 91
[    0.922198] ahci 0000:00:11.0: version 3.0
[    0.922864] ahci 0000:00:11.0: AHCI 0001.0200 32 slots 6 ports 6 Gbps 0x3f impl SATA mode
[    0.922870] ahci 0000:00:11.0: flags: 64bit ncq sntf ilck led clo pmp pio slum part 
[    0.925137] scsi host0: ahci
[    0.925994] calling  psmouse_init+0x0/0x81 [psmouse] @ 90
[    0.926087] initcall psmouse_init+0x0/0x81 [psmouse] returned 0 after 70 usecs
[    0.926771] scsi host1: ahci
[    0.927811] scsi host2: ahci
[    0.928322] scsi host3: ahci
[    0.928791] scsi host4: ahci
[    0.933500] scsi host5: ahci
[    0.933792] ata1: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b100 irq 19
[    0.933797] ata2: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b180 irq 19
[    0.933800] ata3: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b200 irq 19
[    0.933803] ata4: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b280 irq 19
[    0.933807] ata5: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b300 irq 19
[    0.933810] ata6: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b380 irq 19
[    0.933856] probe of 0000:00:11.0 returned 1 after 11677 usecs
[    0.933936] initcall ahci_pci_driver_init+0x0/0x1000 [ahci] returned 0 after 7737 usecs
[    0.937376] probe of serio1 returned 0 after 11264 usecs
[    1.249394] ata2: SATA link down (SStatus 0 SControl 300)
[    1.261401] ata5: SATA link down (SStatus 0 SControl 300)
[    1.261452] ata3: SATA link down (SStatus 0 SControl 300)
[    1.261490] ata6: SATA link down (SStatus 0 SControl 300)
[    1.261534] ata4: SATA link down (SStatus 0 SControl 300)
[    1.417374] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    1.418261] ata1.00: ATA-9: SanDisk SDSSDP064G, 2.0.0, max UDMA/133
[    1.418272] ata1.00: 125045424 sectors, multi 1: LBA48 NCQ (depth 32)
[    1.419057] ata1.00: configured for UDMA/133
[    1.419584] scsi 0:0:0:0: Direct-Access     ATA      SanDisk SDSSDP06 0    PQ: 0 ANSI: 5
[    1.437920] calling  init_sd+0x0/0x1000 [sd_mod] @ 91
[    1.438214] sd 0:0:0:0: [sda] 125045424 512-byte logical blocks: (64.0 GB/59.6 GiB)
[    1.438232] sd 0:0:0:0: [sda] Write Protect is off
[    1.438236] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    1.438260] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.438886] probe of 0:0:0:0 returned 1 after 911 usecs
[    1.438923] initcall init_sd+0x0/0x1000 [sd_mod] returned 0 after 967 usecs
[    1.439739]  sda: sda1
[    1.440316] sd 0:0:0:0: [sda] Attached SCSI disk
[    1.605148] calling  aes_init+0x0/0x1000 [aes_i586] @ 132
[    1.605286] initcall aes_init+0x0/0x1000 [aes_i586] returned 0 after 125 usecs
[    1.609031] calling  cryptd_init+0x0/0x1000 [cryptd] @ 132
[    1.609042] cryptd: max_cpu_qlen set to 1000
[    1.609050] initcall cryptd_init+0x0/0x1000 [cryptd] returned 0 after 10 usecs
[    1.614975] calling  aesni_init+0x0/0x112 [aesni_intel] @ 132
[    1.614984] initcall aesni_init+0x0/0x112 [aesni_intel] returned -19 after 1 usecs
[    1.648093] calling  padlock_init+0x0/0x1000 [padlock_aes] @ 132
[    1.648102] initcall padlock_init+0x0/0x1000 [padlock_aes] returned -19 after 1 usecs
[    1.653022] tsc: Refined TSC clocksource calibration: 1599.999 MHz
[    1.653037] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x171023f09ff, max_idle_ns: 440795233615 ns
[    1.653171] clocksource: Switched to clocksource tsc
[    1.683909] calling  crypto_ecb_module_init+0x0/0x1000 [ecb] @ 132
[    1.683918] initcall crypto_ecb_module_init+0x0/0x1000 [ecb] returned 0 after 1 usecs
[    1.688126] calling  fscrypt_init+0x0/0x1000 [fscrypto] @ 132
[    1.688358] initcall fscrypt_init+0x0/0x1000 [fscrypto] returned 0 after 216 usecs
[    1.690970] calling  crc32c_intel_mod_init+0x0/0x1000 [crc32c_intel] @ 132
[    1.690976] initcall crc32c_intel_mod_init+0x0/0x1000 [crc32c_intel] returned -19 after 0 usecs
[    1.719832] calling  crc32c_mod_init+0x0/0x1000 [crc32c_generic] @ 132
[    1.720707] initcall crc32c_mod_init+0x0/0x1000 [crc32c_generic] returned 0 after 834 usecs
[    1.729909] calling  journal_init+0x0/0x7dd [jbd2] @ 132
[    1.730193] initcall journal_init+0x0/0x7dd [jbd2] returned 0 after 260 usecs
[    1.733213] calling  mbcache_init+0x0/0x1000 [mbcache] @ 132
[    1.733236] initcall mbcache_init+0x0/0x1000 [mbcache] returned 0 after 15 usecs
[    1.776530] calling  ext4_init_fs+0x0/0x166 [ext4] @ 132
[    1.776811] initcall ext4_init_fs+0x0/0x166 [ext4] returned 0 after 229 usecs
[    1.782619] random: fast init done
[    1.788197] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[    1.968915] random: systemd-bootcha: uninitialized urandom read (16 bytes read)
[    2.197281] calling  init_autofs_fs+0x0/0x28 [autofs4] @ 1
[    2.197676] initcall init_autofs_fs+0x0/0x28 [autofs4] returned 0 after 366 usecs
[    2.197820] systemd[1]: Inserted module 'autofs4'
[    2.224621] calling  xt_init+0x0/0x1000 [x_tables] @ 1
[    2.224636] initcall xt_init+0x0/0x1000 [x_tables] returned 0 after 4 usecs
[    2.238268] calling  ip_tables_init+0x0/0x1000 [ip_tables] @ 1
[    2.238291] initcall ip_tables_init+0x0/0x1000 [ip_tables] returned 0 after 14 usecs
[    2.254651] systemd[1]: systemd 241 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.273349] systemd[1]: Detected architecture x86.
[    2.278039] systemd[1]: Set hostname to <kodi>.
[    2.697975] random: systemd: uninitialized urandom read (16 bytes read)
[    2.701164] random: systemd: uninitialized urandom read (16 bytes read)
[    2.701425] systemd[1]: Listening on Journal Audit Socket.
[    2.701794] systemd[1]: Listening on Journal Socket (/dev/log).
[    2.701854] systemd[1]: Reached target Local File Systems.
[    2.701992] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    2.702273] systemd[1]: Listening on udev Control Socket.
[    2.702732] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    2.704299] systemd[1]: Created slice User and Session Slice.
[    3.208545] calling  acpi_cpufreq_init+0x0/0x1000 [acpi_cpufreq] @ 176
[    3.208675] acpi_cpufreq: overriding BIOS provided _PSD data
[    3.208821] initcall acpi_cpufreq_init+0x0/0x1000 [acpi_cpufreq] returned 0 after 258 usecs
[    3.216675] calling  pcc_cpufreq_init+0x0/0xebc [pcc_cpufreq] @ 177
[    3.216686] initcall pcc_cpufreq_init+0x0/0xebc [pcc_cpufreq] returned 0 after 1 usecs
[    3.231087] calling  acpi_button_driver_init+0x0/0x1000 [button] @ 176
[    3.238374] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
[    3.238723] ACPI: Power Button [PWRF]
[    3.238805] probe of LNXPWRBN:00 returned 1 after 7660 usecs
[    3.238839] initcall acpi_button_driver_init+0x0/0x1000 [button] returned 0 after 7555 usecs
[    3.454701] calling  init_soundcore+0x0/0x1000 [soundcore] @ 177
[    3.454725] initcall init_soundcore+0x0/0x1000 [soundcore] returned 0 after 14 usecs
[    3.466393] calling  alsa_sound_init+0x0/0x82 [snd] @ 177
[    3.466437] initcall alsa_sound_init+0x0/0x82 [snd] returned 0 after 26 usecs
[    3.481616] calling  alsa_timer_init+0x0/0x1000 [snd_timer] @ 177
[    3.481772] initcall alsa_timer_init+0x0/0x1000 [snd_timer] returned 0 after 140 usecs
[    3.492955] calling  alsa_pcm_init+0x0/0x1000 [snd_pcm] @ 177
[    3.492982] initcall alsa_pcm_init+0x0/0x1000 [snd_pcm] returned 0 after 9 usecs
[    3.504289] calling  alsa_hwdep_init+0x0/0x1000 [snd_hwdep] @ 177
[    3.504307] initcall alsa_hwdep_init+0x0/0x1000 [snd_hwdep] returned 0 after 8 usecs
[    3.518666] calling  drm_core_init+0x0/0xd1 [drm] @ 176
[    3.518740] initcall drm_core_init+0x0/0xd1 [drm] returned 0 after 28 usecs
[    3.531305] calling  hda_bus_init+0x0/0x14 [snd_hda_core] @ 177
[    3.531345] initcall hda_bus_init+0x0/0x14 [snd_hda_core] returned 0 after 22 usecs
[    3.563915] calling  drm_kms_helper_init+0x0/0x18 [drm_kms_helper] @ 176
[    3.563962] initcall drm_kms_helper_init+0x0/0x18 [drm_kms_helper] returned 0 after 18 usecs
[    3.579688] calling  ttm_init+0x0/0x1000 [ttm] @ 176
[    3.579761] initcall ttm_init+0x0/0x1000 [ttm] returned 0 after 52 usecs
[    3.583276] calling  azx_driver_init+0x0/0xfe4 [snd_hda_intel] @ 177
[    3.593815] probe of 0000:00:01.1 returned 1 after 10508 usecs
[    3.594346] probe of 0000:00:14.2 returned 1 after 510 usecs
[    3.594414] initcall azx_driver_init+0x0/0xfe4 [snd_hda_intel] returned 0 after 10854 usecs
[    3.658800] calling  generic_driver_init+0x0/0x1000 [snd_hda_codec_generic] @ 191
[    3.658847] initcall generic_driver_init+0x0/0x1000 [snd_hda_codec_generic] returned 0 after 31 usecs
[    3.658859] calling  hdmi_driver_init+0x0/0x1000 [snd_hda_codec_hdmi] @ 189
[    3.679588] calling  realtek_driver_init+0x0/0x1000 [snd_hda_codec_realtek] @ 191
[    3.679999] snd_hda_codec_realtek hdaudioC1D0: ALC892: SKU not ready 0x00000100
[    3.680561] snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC892: line_outs=4 (0x14/0x16/0x15/0x17/0x0) type:line
[    3.680565] snd_hda_codec_realtek hdaudioC1D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[    3.680569] snd_hda_codec_realtek hdaudioC1D0:    hp_outs=1 (0x1b/0x0/0x0/0x0/0x0)
[    3.680572] snd_hda_codec_realtek hdaudioC1D0:    mono: mono_out=0x0
[    3.680575] snd_hda_codec_realtek hdaudioC1D0:    dig-out=0x1e/0x0
[    3.680577] snd_hda_codec_realtek hdaudioC1D0:    inputs:
[    3.680581] snd_hda_codec_realtek hdaudioC1D0:      Rear Mic=0x18
[    3.680584] snd_hda_codec_realtek hdaudioC1D0:      Front Mic=0x19
[    3.680588] snd_hda_codec_realtek hdaudioC1D0:      Line=0x1a
[    3.680590] snd_hda_codec_realtek hdaudioC1D0:      CD=0x1c
[    3.687311] probe of hdaudioC0D0 returned 1 after 28413 usecs
[    3.687374] initcall hdmi_driver_init+0x0/0x1000 [snd_hda_codec_hdmi] returned 0 after 7583 usecs
[    3.688296] input: HD-Audio Generic HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.1/sound/card0/input4
[    3.721558] calling  radeon_init+0x0/0xa3 [radeon] @ 176
[    3.721563] [drm] radeon kernel modesetting enabled.
[    3.738445] [drm] initializing kernel modesetting (PALM 0x1002:0x9802 0x1002:0x9802 0x00).
[    3.738528] ATOM BIOS: AMD
[    3.738608] radeon 0000:00:01.0: VRAM: 384M 0x0000000000000000 - 0x0000000017FFFFFF (384M used)
[    3.738612] radeon 0000:00:01.0: GTT: 1024M 0x0000000018000000 - 0x0000000057FFFFFF
[    3.738620] [drm] Detected VRAM RAM=384M, BAR=256M
[    3.738622] [drm] RAM width 32bits DDR
[    3.747470] [TTM] Zone  kernel: Available graphics memory: 388568 kiB
[    3.747475] [TTM] Zone highmem: Available graphics memory: 1828968 kiB
[    3.747476] [TTM] Initializing pool allocator
[    3.747488] [TTM] Initializing DMA pool allocator
[    3.747537] [drm] radeon: 384M of VRAM memory ready
[    3.747540] [drm] radeon: 1024M of GTT memory ready.
[    3.747568] [drm] Loading PALM Microcode
[    3.749233] probe of hdaudioC1D0 returned 1 after 69621 usecs
[    3.749296] initcall realtek_driver_init+0x0/0x1000 [snd_hda_codec_realtek] returned 0 after 27063 usecs
[    3.750028] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:14.2/sound/card1/input5
[    3.750671] input: HDA ATI SB Rear Mic as /devices/pci0000:00/0000:00:14.2/sound/card1/input6
[    3.750794] input: HDA ATI SB Front Mic as /devices/pci0000:00/0000:00:14.2/sound/card1/input7
[    3.750927] input: HDA ATI SB Line as /devices/pci0000:00/0000:00:14.2/sound/card1/input8
[    3.751045] input: HDA ATI SB Line Out Front as /devices/pci0000:00/0000:00:14.2/sound/card1/input9
[    3.751165] input: HDA ATI SB Line Out Surround as /devices/pci0000:00/0000:00:14.2/sound/card1/input10
[    3.751285] input: HDA ATI SB Line Out CLFE as /devices/pci0000:00/0000:00:14.2/sound/card1/input11
[    3.751404] input: HDA ATI SB Line Out Side as /devices/pci0000:00/0000:00:14.2/sound/card1/input12
[    3.751521] input: HDA ATI SB Front Headphone as /devices/pci0000:00/0000:00:14.2/sound/card1/input13
[    3.753370] radeon 0000:00:01.0: firmware: direct-loading firmware radeon/PALM_pfp.bin
[    3.755621] calling  init_sg+0x0/0x1000 [sg] @ 177
[    3.756814] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    3.756878] initcall init_sg+0x0/0x1000 [sg] returned 0 after 1213 usecs
[    3.760789] radeon 0000:00:01.0: firmware: direct-loading firmware radeon/PALM_me.bin
[    3.763158] radeon 0000:00:01.0: firmware: direct-loading firmware radeon/SUMO_rlc.bin
[    3.763173] [drm] Internal thermal controller without fan control
[    3.763285] [drm] Found smc ucode version: 0x00010601
[    3.763362] [drm] radeon: dpm initialized
[    3.765881] radeon 0000:00:01.0: firmware: direct-loading firmware radeon/SUMO_uvd.bin
[    3.765966] [drm] GART: num cpu pages 262144, num gpu pages 262144
[    3.783764] [drm] PCIE GART of 1024M enabled (table at 0x0000000000162000).
[    3.797875] radeon 0000:00:01.0: WB enabled
[    3.797912] radeon 0000:00:01.0: fence driver on ring 0 use gpu addr 0x0000000018000c00 and cpu addr 0x(ptrval)
[    3.797917] radeon 0000:00:01.0: fence driver on ring 3 use gpu addr 0x0000000018000c0c and cpu addr 0x(ptrval)
[    3.798792] radeon 0000:00:01.0: fence driver on ring 5 use gpu addr 0x0000000000072118 and cpu addr 0x(ptrval)
[    3.801669] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    3.801672] [drm] Driver supports precise vblank timestamp query.
[    3.801679] radeon 0000:00:01.0: radeon: MSI limited to 32-bit
[    3.801770] radeon 0000:00:01.0: radeon: using MSI.
[    3.801809] [drm] radeon: irq initialized.
[    3.831841] [drm] ring test on 0 succeeded in 1 usecs
[    3.831853] [drm] ring test on 3 succeeded in 2 usecs
[    3.878155] [drm] ring test on 5 succeeded in 1 usecs
[    3.899965] [drm] UVD initialized successfully.
[    3.901616] [drm] ib test on ring 0 succeeded in 0 usecs
[    3.902470] [drm] ib test on ring 3 succeeded in 0 usecs
[    4.437038] calling  ledtrig_usb_init+0x0/0x1000 [usb_common] @ 177
[    4.437054] initcall ledtrig_usb_init+0x0/0x1000 [usb_common] returned 0 after 5 usecs
[    4.461357] calling  usb_init+0x0/0x121 [usbcore] @ 177
[    4.461384] ACPI: bus type USB registered
[    4.461437] usbcore: registered new interface driver usbfs
[    4.461458] usbcore: registered new interface driver hub
[    4.461516] usbcore: registered new device driver usb
[    4.461545] initcall usb_init+0x0/0x121 [usbcore] returned 0 after 152 usecs
[    4.469003] [drm] ib test on ring 5 succeeded
[    4.482847] calling  ehci_hcd_init+0x0/0x1000 [ehci_hcd] @ 177
[    4.482852] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    4.482878] initcall ehci_hcd_init+0x0/0x1000 [ehci_hcd] returned 0 after 20 usecs
[    4.499395] calling  ohci_hcd_mod_init+0x0/0x1000 [ohci_hcd] @ 177
[    4.499401] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    4.499437] initcall ohci_hcd_mod_init+0x0/0x1000 [ohci_hcd] returned 0 after 29 usecs
[    4.499949] [drm] Radeon Display Connectors
[    4.499953] [drm] Connector 0:
[    4.499954] [drm]   HDMI-A-1
[    4.499955] [drm]   HPD1
[    4.499959] [drm]   DDC: 0x6430 0x6430 0x6434 0x6434 0x6438 0x6438 0x643c 0x643c
[    4.499960] [drm]   Encoders:
[    4.499961] [drm]     DFP1: INTERNAL_UNIPHY
[    4.499963] [drm] Connector 1:
[    4.499964] [drm]   HDMI-A-2
[    4.499965] [drm]   HPD2
[    4.499968] [drm]   DDC: 0x6440 0x6440 0x6444 0x6444 0x6448 0x6448 0x644c 0x644c
[    4.499969] [drm]   Encoders:
[    4.499970] [drm]     DFP2: INTERNAL_UNIPHY
[    4.499971] [drm] Connector 2:
[    4.499972] [drm]   VGA-1
[    4.499975] [drm]   DDC: 0x64d8 0x64d8 0x64dc 0x64dc 0x64e0 0x64e0 0x64e4 0x64e4
[    4.499976] [drm]   Encoders:
[    4.499978] [drm]     CRT1: INTERNAL_KLDSCP_DAC1
[    4.507538] calling  ehci_pci_init+0x0/0x1000 [ehci_pci] @ 177
[    4.507543] ehci-pci: EHCI PCI platform driver
[    4.508030] QUIRK: Enable AMD PLL fix
[    4.508153] ehci-pci 0000:00:12.2: EHCI Host Controller
[    4.508167] ehci-pci 0000:00:12.2: new USB bus registered, assigned bus number 1
[    4.508178] ehci-pci 0000:00:12.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[    4.508263] ehci-pci 0000:00:12.2: debug port 1
[    4.508342] ehci-pci 0000:00:12.2: irq 17, io mem 0xf014c000
[    4.547644] ehci-pci 0000:00:12.2: USB 2.0 started, EHCI 1.00
[    4.547815] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[    4.547819] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.547822] usb usb1: Product: EHCI Host Controller
[    4.547825] usb usb1: Manufacturer: Linux 4.19.0-5-686-pae ehci_hcd
[    4.547828] usb usb1: SerialNumber: 0000:00:12.2
[    4.548205] hub 1-0:1.0: USB hub found
[    4.548228] hub 1-0:1.0: 5 ports detected
[    4.548552] probe of 1-0:1.0 returned 1 after 361 usecs
[    4.548604] probe of usb1 returned 1 after 508 usecs
[    4.548676] probe of 0000:00:12.2 returned 1 after 41108 usecs
[    4.549208] ehci-pci 0000:00:13.2: EHCI Host Controller
[    4.549222] ehci-pci 0000:00:13.2: new USB bus registered, assigned bus number 2
[    4.549232] ehci-pci 0000:00:13.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[    4.549317] ehci-pci 0000:00:13.2: debug port 1
[    4.549375] ehci-pci 0000:00:13.2: irq 17, io mem 0xf014d000
[    4.560874] [drm] fb mappable at 0xE0366000
[    4.560879] [drm] vram apper at 0xE0000000
[    4.560881] [drm] size 5242880
[    4.560882] [drm] fb depth is 24
[    4.560883] [drm]    pitch is 5120
[    4.561148] fbcon: radeondrmfb (fb0) is primary device
[    4.561417] Console: switching to colour frame buffer device 160x64
[    4.561437] radeon 0000:00:01.0: fb0: radeondrmfb frame buffer device
[    4.565147] ehci-pci 0000:00:13.2: USB 2.0 started, EHCI 1.00
[    4.565430] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[    4.565435] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.565438] usb usb2: Product: EHCI Host Controller
[    4.565442] usb usb2: Manufacturer: Linux 4.19.0-5-686-pae ehci_hcd
[    4.565445] usb usb2: SerialNumber: 0000:00:13.2
[    4.569116] hub 2-0:1.0: USB hub found
[    4.569145] hub 2-0:1.0: 5 ports detected
[    4.569519] probe of 2-0:1.0 returned 1 after 422 usecs
[    4.569585] probe of usb2 returned 1 after 639 usecs
[    4.569673] probe of 0000:00:13.2 returned 1 after 20990 usecs
[    4.569722] initcall ehci_pci_init+0x0/0x1000 [ehci_pci] returned 0 after 60711 usecs
[    4.574066] calling  ohci_pci_init+0x0/0x1000 [ohci_pci] @ 177
[    4.574072] ohci-pci: OHCI PCI platform driver
[    4.574592] ohci-pci 0000:00:12.0: OHCI PCI host controller
[    4.574609] ohci-pci 0000:00:12.0: new USB bus registered, assigned bus number 3
[    4.574723] ohci-pci 0000:00:12.0: irq 18, io mem 0xf0148000
[    4.581187] [drm] Initialized radeon 2.50.0 20080528 for 0000:00:01.0 on minor 0
[    4.583353] probe of 0000:00:01.0 returned 1 after 854163 usecs
[    4.583582] initcall radeon_init+0x0/0xa3 [radeon] returned 0 after 9208 usecs
[    4.637226] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
[    4.637233] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.637236] usb usb3: Product: OHCI PCI host controller
[    4.637239] usb usb3: Manufacturer: Linux 4.19.0-5-686-pae ohci_hcd
[    4.637242] usb usb3: SerialNumber: 0000:00:12.0
[    4.643665] hub 3-0:1.0: USB hub found
[    4.643759] hub 3-0:1.0: 5 ports detected
[    4.643992] probe of 3-0:1.0 returned 1 after 347 usecs
[    4.644055] probe of usb3 returned 1 after 525 usecs
[    4.644221] probe of 0000:00:12.0 returned 1 after 70124 usecs
[    4.644668] ohci-pci 0000:00:13.0: OHCI PCI host controller
[    4.644681] ohci-pci 0000:00:13.0: new USB bus registered, assigned bus number 4
[    4.644740] ohci-pci 0000:00:13.0: irq 18, io mem 0xf0149000
[    4.715984] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
[    4.715989] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.715992] usb usb4: Product: OHCI PCI host controller
[    4.715995] usb usb4: Manufacturer: Linux 4.19.0-5-686-pae ohci_hcd
[    4.715998] usb usb4: SerialNumber: 0000:00:13.0
[    4.729821] hub 4-0:1.0: USB hub found
[    4.729927] hub 4-0:1.0: 5 ports detected
[    4.730255] probe of 4-0:1.0 returned 1 after 455 usecs
[    4.730309] probe of usb4 returned 1 after 3359 usecs
[    4.730461] probe of 0000:00:13.0 returned 1 after 86233 usecs
[    4.730914] ohci-pci 0000:00:14.5: OHCI PCI host controller
[    4.730927] ohci-pci 0000:00:14.5: new USB bus registered, assigned bus number 5
[    4.730984] ohci-pci 0000:00:14.5: irq 18, io mem 0xf014a000
[    4.798139] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
[    4.798150] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.798156] usb usb5: Product: OHCI PCI host controller
[    4.798161] usb usb5: Manufacturer: Linux 4.19.0-5-686-pae ohci_hcd
[    4.798167] usb usb5: SerialNumber: 0000:00:14.5
[    4.798642] hub 5-0:1.0: USB hub found
[    4.798736] hub 5-0:1.0: 2 ports detected
[    4.798900] probe of 5-0:1.0 returned 1 after 274 usecs
[    4.798982] probe of usb5 returned 1 after 450 usecs
[    4.799116] probe of 0000:00:14.5 returned 1 after 68646 usecs
[    4.799166] initcall ohci_pci_init+0x0/0x1000 [ohci_pci] returned 0 after 219807 usecs
[    4.824757] calling  piix4_driver_init+0x0/0x1000 [i2c_piix4] @ 177
[    4.825367] piix4_smbus 0000:00:14.0: SMBus Host Controller at 0xb00, revision 0
[    4.825379] piix4_smbus 0000:00:14.0: Using register 0x2c for SMBus port selection
[    4.825727] piix4_smbus 0000:00:14.0: Auxiliary SMBus Host Controller at 0x8060
[    4.825826] probe of 0000:00:14.0 returned 1 after 1035 usecs
[    4.825880] initcall piix4_driver_init+0x0/0x1000 [i2c_piix4] returned 0 after 1083 usecs
[    4.839031] calling  sp5100_tco_init+0x0/0x1000 [sp5100_tco] @ 177
[    4.839047] sp5100_tco: SP5100/SB800 TCO WatchDog Timer Driver
[    4.839302] sp5100-tco sp5100-tco: Using 0xfed80b00 for watchdog MMIO address
[    4.839679] sp5100-tco sp5100-tco: initialized. heartbeat=60 sec (nowayout=0)
[    4.839717] probe of sp5100-tco returned 1 after 555 usecs
[    4.839730] initcall sp5100_tco_init+0x0/0x1000 [sp5100_tco] returned 0 after 671 usecs
[    4.978876] calling  phy_init+0x0/0x52 [libphy] @ 177
[    4.978976] initcall phy_init+0x0/0x52 [libphy] returned 0 after 79 usecs
[    4.984921] calling  phy_module_init+0x0/0x1000 [realtek] @ 177
[    4.985106] initcall phy_module_init+0x0/0x1000 [realtek] returned 0 after 170 usecs
[    5.003767] calling  rtl8169_pci_driver_init+0x0/0x1000 [r8169] @ 177
[    5.021236] probe of r8169-300:00 returned 1 after 30 usecs
[    5.021252] libphy: r8169: probed
[    5.022169] r8169 0000:03:00.0 eth0: RTL8168e/8111e, bc:5f:f4:c8:d3:98, XID 2c200000, IRQ 28
[    5.022173] r8169 0000:03:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[    5.022195] probe of 0000:03:00.0 returned 1 after 18385 usecs
[    5.022238] initcall rtl8169_pci_driver_init+0x0/0x1000 [r8169] returned 0 after 18016 usecs
[    5.051650] calling  k10temp_driver_init+0x0/0x1000 [k10temp] @ 176
[    5.051839] probe of 0000:00:18.3 returned 1 after 150 usecs
[    5.051879] initcall k10temp_driver_init+0x0/0x1000 [k10temp] returned 0 after 211 usecs
[    5.148931] usb 3-2: new low-speed USB device number 2 using ohci-pci
[    5.160442] calling  pcspkr_platform_driver_init+0x0/0x1000 [pcspkr] @ 177
[    5.160595] input: PC Speaker as /devices/platform/pcspkr/input/input14
[    5.163133] probe of pcspkr returned 1 after 2656 usecs
[    5.163193] initcall pcspkr_platform_driver_init+0x0/0x1000 [pcspkr] returned 0 after 2671 usecs
[    5.347173] usb 3-2: New USB device found, idVendor=1241, idProduct=1122, bcdDevice= 1.00
[    5.347184] usb 3-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    5.350887] probe of 3-2 returned 1 after 3330 usecs
[    5.449279] calling  svm_init+0x0/0xd22 [kvm_amd] @ 177
[    5.450383] kvm: Nested Virtualization enabled
[    5.450391] kvm: Nested Paging enabled
[    5.451126] initcall svm_init+0x0/0xd22 [kvm_amd] returned 0 after 1779 usecs
[    6.544393] r8169 0000:03:00.0 enp3s0: renamed from eth0
[    6.595238] r8169 0000:03:00.0: firmware: direct-loading firmware rtl_nic/rtl8168e-2.fw
[    6.595749] RTL8211DN Gigabit Ethernet r8169-300:00: attached PHY driver [RTL8211DN Gigabit Ethernet] (mii_bus:phy_addr=r8169-300:00, irq=IGNORE)
[    6.628961] calling  hid_init+0x0/0x5d [hid] @ 176
[    6.629415] hidraw: raw HID events driver (C) Jiri Kosina
[    6.629448] initcall hid_init+0x0/0x5d [hid] returned 0 after 459 usecs
[    6.647214] calling  hid_init+0x0/0x1000 [usbhid] @ 176
[    6.653306] probe of 3-2:1.0 returned 1 after 6049 usecs
[    6.653340] usbcore: registered new interface driver usbhid
[    6.653341] usbhid: USB HID core driver
[    6.653358] initcall hid_init+0x0/0x1000 [usbhid] returned 0 after 5981 usecs
[    6.688392] calling  hid_generic_init+0x0/0x1000 [hid_generic] @ 177
[    6.689103] input: HID 1241:1122 as /devices/pci0000:00/0000:00:12.0/usb3/3-2/3-2:1.0/0003:1241:1122.0001/input/input15
[    6.698445] hid-generic 0003:1241:1122.0001: input,hidraw0: USB HID v1.00 Mouse [HID 1241:1122] on usb-0000:00:12.0-2/input0
[    6.698477] probe of 0003:1241:1122.0001 returned 1 after 10061 usecs
[    6.698522] initcall hid_generic_init+0x0/0x1000 [hid_generic] returned 0 after 9878 usecs
[    6.827991] IPv6: ADDRCONF(NETDEV_UP): enp3s0: link is not ready
[    6.828004] A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.
[   18.474067] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx
[   18.474095] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0: link becomes ready
[   24.437991] random: crng init done
[   24.438004] random: 7 urandom warning(s) missed due to ratelimiting

[-- Attachment #2: S/MIME Cryptographic Signature --]
[-- Type: application/pkcs7-signature, Size: 5174 bytes --]

^ permalink raw reply	[flat|nested] 5+ messages in thread

* Re: r8169: Link only up after 16 s (A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.)
  2019-05-24 15:14   ` Paul Menzel
@ 2019-05-24 17:55     ` Heiner Kallweit
  2019-05-24 18:04       ` Joe Perches
  0 siblings, 1 reply; 5+ messages in thread
From: Heiner Kallweit @ 2019-05-24 17:55 UTC (permalink / raw)
  To: Paul Menzel, Realtek linux nic maintainers
  Cc: netdev, Linux Kernel Mailing List

On 24.05.2019 17:14, Paul Menzel wrote:
> Dear Heiner,
> 
> 
> Thank you for the quick reply.
> 
> On 05/23/19 19:44, Heiner Kallweit wrote:
>> On 23.05.2019 13:00, Paul Menzel wrote:
> 
>>> I optimized the Linux kernel configuration on my ASRock E350M1, and it now
>>> boots really fast.
>>>
>>> Unfortunately, that seems to cause the network driver to hit some corner
>>> case, so that the link is supposedly down, although it should be up. The
>>> cable is plugged in the whole time.
>>>
>>> ```
>>> [    2.990757] libphy: r8169: probed
>>> [    2.992661] r8169 0000:03:00.0 eth0: RTL8168e/8111e, bc:5f:f4:c8:d3:98, XID 2c2, IRQ 28
>>> [    2.992669] r8169 0000:03:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
>>> [    3.294484] usb 5-2: new low-speed USB device number 2 using ohci-pci
>>> [    3.458711] usb 5-2: New USB device found, idVendor=1241, idProduct=1122, bcdDevice= 1.00
>>> [    3.458718] usb 5-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
>>> [    3.485065] input: HID 1241:1122 as /devices/pci0000:00/0000:00:12.0/usb5/5-2/5-2:1.0/0003:1241:1122.0001/input/input14
>>> [    3.485320] hid-generic 0003:1241:1122.0001: input,hidraw0: USB HID v1.00 Mouse [HID 1241:1122] on usb-0000:00:12.0-2/input0
>>> [    3.967622] random: crng init done
>>> [    3.967628] random: 7 urandom warning(s) missed due to ratelimiting
>>> [    4.323449] r8169 0000:03:00.0 enp3s0: renamed from eth0
>>> [    4.363774] RTL8211DN Gigabit Ethernet r8169-300:00: attached PHY driver [RTL8211DN Gigabit Ethernet] (mii_bus:phy_addr=r8169-300:00, irq=IGNORE)
>>> [    4.576887] r8169 0000:03:00.0 enp3s0: Link is Down
>>> [    4.577167] A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.
>>> [   16.377520] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx
>>> [   16.377553] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0: link becomes ready
>>> ```
>>>
>>> It happens with all Linux kernels I tried. Please find all Linux
>>> messages attached.
>>>
>>> Could you please tell me, how this can be debugged and solved?
>>>
>> This warning is triggered by errors in do_setlink() in net/core/rtnetlink.c
>> I'd say:
>> 1. Which kernel config options did you change as part of the optimization?
>>    (If I understand you correctly the warning didn't pop up before.)
> 
> Sorry for being unclear. The same problem happens with Debian
> Sid/unstable’s default Linux kernel 4.19.0-5 (4.19.37) [1]. With the fast
> boot (six seconds from pressing power button to Weston) I just noticed, that
> the network was not set up when wanting to use it.
> 
>> 2. Try to find out which call in do_setlink() fails and which errno
>> is returned.
> 
> Yeah, that’s where I need help. ;-)
> 
> I applied the simple change below to `net/core/rtnetlink.c`.
> 
>                 if (err < 0)
> -                       net_warn_ratelimited("A link change request failed with some changes committed already. Interface %s may have been left with an inconsistent configuration, please check.\n",
> -                                            dev->name);
> +                       net_warn_ratelimited("A link change request failed with some changes committed already (err = %i). Interface %s may have been left with an inconsistent configuration, please check.\n",
> +                                            dev->name, err);
> 
> I get different results each time.
> 
> -304123904
> -332128256
> 
> Any idea, how that can happen?
> 
Instead of %i you should use %d, and the order of arguments needs to be reversed.
But this won't help you to find out which call in do_setlink() failed.
There are several occurrences of code like this:

err = fct();
if (err < 0)
	goto errout;

Best change each such occurrence to the following to find out which call failed.

err = fct();
if (err < 0) {
	pr_err("do_setlink: <fct>: err %d\n", err)
	goto errout;
}

> Is there a better way to debug `do_setlink()` in `rtnetlink.c` than
> printf debugging?
> 
Not really
> 
> Kind regards,
> 
> Paul
> 


^ permalink raw reply	[flat|nested] 5+ messages in thread

* Re: r8169: Link only up after 16 s (A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.)
  2019-05-24 17:55     ` Heiner Kallweit
@ 2019-05-24 18:04       ` Joe Perches
  0 siblings, 0 replies; 5+ messages in thread
From: Joe Perches @ 2019-05-24 18:04 UTC (permalink / raw)
  To: Heiner Kallweit, Paul Menzel, Realtek linux nic maintainers
  Cc: netdev, Linux Kernel Mailing List

On Fri, 2019-05-24 at 19:55 +0200, Heiner Kallweit wrote:
> On 24.05.2019 17:14, Paul Menzel wrote:
> > I applied the simple change below to `net/core/rtnetlink.c`.
> > 
> >                 if (err < 0)
> > -                       net_warn_ratelimited("A link change request failed with some changes committed already. Interface %s may have been left with an inconsistent configuration, please check.\n",
> > -                                            dev->name);
> > +                       net_warn_ratelimited("A link change request failed with some changes committed already (err = %i). Interface %s may have been left with an inconsistent configuration, please check.\n",
> > +                                            dev->name, err);
> > 
> > I get different results each time.
> > 
> > -304123904
> > -332128256
> > 
> > Any idea, how that can happen?
> > 
> Instead of %i you should use %d, and the order of arguments needs to be reversed.

Doesn't the patch generate a compilation warning?



^ permalink raw reply	[flat|nested] 5+ messages in thread

end of thread, other threads:[~2019-05-24 18:04 UTC | newest]

Thread overview: 5+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2019-05-23 11:00 r8169: Link only up after 16 s (A link change request failed with some changes committed already. Interface enp3s0 may have been left with an inconsistent configuration, please check.) Paul Menzel
2019-05-23 17:44 ` Heiner Kallweit
2019-05-24 15:14   ` Paul Menzel
2019-05-24 17:55     ` Heiner Kallweit
2019-05-24 18:04       ` Joe Perches

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).