* What happens before or in `schedule_preempt_disabled()`?
@ 2018-10-17 15:50 Paul Menzel
2018-10-17 16:39 ` Thomas Gleixner
0 siblings, 1 reply; 3+ messages in thread
From: Paul Menzel @ 2018-10-17 15:50 UTC (permalink / raw)
To: x86, linux-kernel
[-- Attachment #1.1: Type: text/plain, Size: 3262 bytes --]
Dear Linux folks,
On the ASRock E350M1, I am trying to profile and decrease the boot time of
the Linux kernel.
Currently, my kernel needs 790 ms, and 100 ms are spent in the section below.
```
[ 0.000000] Linux version 4.19.0-rc8-00045-gc92161e11c2a (root@5661fb7fd9d5) (gcc version 8.1.0 (Debian 8.1.0-11)) #226 SMP Tue Oct 16 07:15:09 UTC 2018
[…]
[ 0.132977] mce: CPU supports 6 MCE banks
[ 0.132996] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 4
[ 0.132998] Last level dTLB entries: 4KB 512, 2MB 8, 4MB 4, 1GB 0
[ 0.133005] Spectre V2 : Mitigation: Full AMD retpoline
[ 0.133006] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 0.133008] Speculative Store Bypass: Vulnerable
[ 0.133244] Freeing SMP alternatives memory: 32K
[ 0.133247] After check_bugs
[ 0.133251] After acpi_subsystem_init
[ 0.133252] After arch_post_acpi_subsys_init
[ 0.133253] After sfi_init_late
[ 0.133255] After rcu_scheduler_starting
[ 0.133392] After find_task_by_pid_ns
[ 0.133394] After numa_default_policy
[ 0.133430] After rcu_read_lock
[ 0.133431] After rcu_read_unlock
[ 0.133437] After kthreadd_done
[ 0.134354] random: 1
[ 0.134354] random: 2
[ 0.134354] random: 3
[ 0.134354] random: 4
[ 0.153357] random: 1
[ 0.153358] random: 2
[ 0.153359] random: 3
[ 0.153360] random: 4
[ 0.217357] random: 1
[ 0.217358] random: 2
[ 0.217359] random: 3
[ 0.217360] random: 4
[ 0.235354] smpboot: CPU0: AMD E-350D APU with Radeon(tm) HD Graphics (family: 0x14, model: 0x2, stepping: 0x0)
[ 0.235354] calling trace_init_flags_sys_enter+0x0/0x13 @ 1
[ 0.235354] initcall trace_init_flags_sys_enter+0x0/0x13 returned 0 after 0 usecs
[ 0.235354] calling trace_init_flags_sys_exit+0x0/0x13 @ 1
[ 0.235354] initcall trace_init_flags_sys_exit+0x0/0x13 returned 0 after 0 usecs
[ 0.235354] calling init_hw_perf_events+0x0/0x5bd @ 1
[ 0.235354] Performance Events: AMD PMU driver.
[…]
[ 0.235720] smp: Bringing up secondary CPUs ...
[ 0.235953] CPU 1 irqstacks, hard=(ptrval) soft=(ptrval)
[ 0.235956] x86: Booting SMP configuration:
[ 0.235958] .... node #0, CPUs: #1
[ 0.004323] Initializing CPU#1
[ 0.236418] After schedule_preempt_disabled
[…]
```
Please find the debug patches attached. The `random: %i` messages are from
`crng_fast_load()`.
My question is, how can I figure out, what happens in the code below?
complete(&kthreadd_done);
pr_info("After kthreadd_done\n");
/*
* The boot idle thread must execute schedule()
* at least once to get things moving:
*/
schedule_preempt_disabled();
pr_info("After schedule_preempt_disabled\n");
Looking at `schedule_preempt_disabled()`, it shouldn’t take much time, and I
was unable to find out where `crng_fast_load()` is called from.
I guess threading is somehow working at that point, and I would though that
it is the `complete()` call, but wonder why the `After kthreadd_done` is
printed earlier.
Could you recommend easier ways than using printk debugging to figure this
out?
Kind regards,
Paul
[-- Attachment #1.2: 20181017–kodi–linux–4.19-rc8–dmesg.txt --]
[-- Type: text/plain, Size: 127080 bytes --]
[ 0.000000] Linux version 4.19.0-rc8-00045-gc92161e11c2a (root@5661fb7fd9d5) (gcc version 8.1.0 (Debian 8.1.0-11)) #226 SMP Tue Oct 16 07:15:09 UTC 2018
[ 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-0x00000000c7d3bfff] usable
[ 0.000000] BIOS-e820: [mem 0x00000000c7d3c000-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.8-1785-g672d5ad20b 10/14/2018
[ 0.000000] tsc: Fast TSC calibration using PIT
[ 0.000000] tsc: Detected 1599.974 MHz processor
[ 0.006507] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[ 0.006514] e820: remove [mem 0x000a0000-0x000fffff] usable
[ 0.006524] last_pfn = 0x11f000 max_arch_pfn = 0x1000000
[ 0.006535] MTRR default type: uncachable
[ 0.006537] MTRR fixed ranges enabled:
[ 0.006540] 00000-9FFFF write-back
[ 0.006542] A0000-BFFFF uncachable
[ 0.006545] C0000-FFFFF write-back
[ 0.006547] MTRR variable ranges enabled:
[ 0.006550] 0 base 0FFC00000 mask FFFC00000 write-protect
[ 0.006553] 1 base 0C0000000 mask FF8000000 write-back
[ 0.006556] 2 base 080000000 mask FC0000000 write-back
[ 0.006558] 3 base 000000000 mask F80000000 write-back
[ 0.006560] 4 disabled
[ 0.006561] 5 disabled
[ 0.006562] 6 disabled
[ 0.006564] 7 disabled
[ 0.006566] TOM2: 000000011f000000 aka 4592M
[ 0.006758] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
[ 0.006855] initial memory mapped: [mem 0x00000000-0x0b3fffff]
[ 0.006866] Base memory trampoline at [(ptrval)] 9b000 size 16384
[ 0.007019] BRK [0x0ae38000, 0x0ae38fff] PGTABLE
[ 0.007041] BRK [0x0ae39000, 0x0ae39fff] PGTABLE
[ 0.007054] ACPI: Early table checksum verification disabled
[ 0.007184] ACPI: RSDP 0x00000000000F0800 000024 (v02 CORE )
[ 0.007193] ACPI: XSDT 0x00000000C7D4D0E0 00006C (v01 CORE COREBOOT 00000000 CORE 00000000)
[ 0.007206] ACPI: FACP 0x00000000C7D4F840 0000F4 (v04 CORE COREBOOT 00000000 CORE 00000000)
[ 0.007220] ACPI: DSDT 0x00000000C7D4D280 0025B6 (v02 ASROCK COREBOOT 00010001 INTL 20180810)
[ 0.007228] ACPI: FACS 0x00000000C7D4D240 000040
[ 0.007233] ACPI: FACS 0x00000000C7D4D240 000040
[ 0.007239] ACPI: SSDT 0x00000000C7D4F940 00008A (v02 CORE COREBOOT 0000002A CORE 0000002A)
[ 0.007245] ACPI: TCPA 0x00000000C7D4F9D0 000032 (v02 CORE COREBOOT 00000000 CORE 00000000)
[ 0.007252] ACPI: APIC 0x00000000C7D4FA10 00005C (v02 CORE COREBOOT 00000000 CORE 00000000)
[ 0.007258] ACPI: HEST 0x00000000C7D4FA70 000028 (v01 CORE COREBOOT 00000000 CORE 00000000)
[ 0.007265] ACPI: SSDT 0x00000000C7D4FAA0 00168E (v02 AMD ALIB 00000001 MSFT 04000000)
[ 0.007272] ACPI: SSDT 0x00000000C7D51130 0003DE (v01 AMD POWERNOW 00000001 AMD 00000001)
[ 0.007279] ACPI: VFCT 0x00000000C7D51510 00E269 (v01 CORE COREBOOT 00000000 CORE 00000000)
[ 0.007285] ACPI: HPET 0x00000000C7D5F780 000038 (v01 CORE COREBOOT 00000000 CORE 00000000)
[ 0.007305] ACPI: Local APIC address 0xfee00000
[ 0.007312] 3706MB HIGHMEM available.
[ 0.007316] 885MB LOWMEM available.
[ 0.007317] mapped low ram: 0 - 375fe000
[ 0.007319] low ram: 0 - 375fe000
[ 0.007333] BRK [0x0ae3a000, 0x0ae3afff] PGTABLE
[ 0.018133] Zone ranges:
[ 0.018136] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.018141] Normal [mem 0x0000000001000000-0x00000000375fdfff]
[ 0.018144] HighMem [mem 0x00000000375fe000-0x000000011effffff]
[ 0.018146] Movable zone start for each node
[ 0.018147] Early memory node ranges
[ 0.018149] node 0: [mem 0x0000000000001000-0x000000000009ffff]
[ 0.018151] node 0: [mem 0x0000000000100000-0x00000000c7d3bfff]
[ 0.018153] node 0: [mem 0x0000000100000000-0x000000011effffff]
[ 0.022138] Reserved but unavailable: 99109 pages
[ 0.022143] Initmem setup node 0 [mem 0x0000000000001000-0x000000011effffff]
[ 0.022147] On node 0 totalpages: 945371
[ 0.022150] DMA zone: 40 pages used for memmap
[ 0.022151] DMA zone: 0 pages reserved
[ 0.022153] DMA zone: 3999 pages, LIFO batch:0
[ 0.022370] Normal zone: 2175 pages used for memmap
[ 0.022372] Normal zone: 222718 pages, LIFO batch:63
[ 0.033934] HighMem zone: 718654 pages, LIFO batch:63
[ 0.077462] Using APIC driver default
[ 0.077741] ACPI: Local APIC address 0xfee00000
[ 0.077766] IOAPIC[0]: apic_id 2, version 33, address 0xfec00000, GSI 0-23
[ 0.077770] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.077774] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
[ 0.077779] ACPI: IRQ0 used by override.
[ 0.077781] ACPI: IRQ9 used by override.
[ 0.077786] Using ACPI (MADT) for SMP configuration information
[ 0.077789] ACPI: HPET id: 0x43538210 base: 0xfed00000
[ 0.077801] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[ 0.077828] [mem 0xe0000000-0xf7ffffff] available for PCI devices
[ 0.077835] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[ 0.077838] After setup_arch
[ 0.077839] After add_latent_entropy
[ 0.077860] After add_device_randomness
[ 0.077871] random: get_random_bytes called from start_kernel+0x9b/0x509 with crng_init=0
[ 0.077875] After boot_init_stack_canary
[ 0.077876] After mm_init_cpumask
[ 0.077887] After setup_command_line
[ 0.077888] After setup_nr_cpu_ids
[ 0.077891] setup_percpu: NR_CPUS:2 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[ 0.078130] percpu: Embedded 29 pages/cpu @(ptrval) s89996 r0 d28788 u118784
[ 0.078143] pcpu-alloc: s89996 r0 d28788 u118784 alloc=29*4096
[ 0.078146] pcpu-alloc: [0] 0 [0] 1
[ 0.078173] After setup_per_cpu_areas
[ 0.078175] After smp_perpare_boot_cpu
[ 0.078176] After boot_cpu_hotplug_init
[ 0.078182] Built 1 zonelists, mobility grouping on. Total pages: 943156
[ 0.078186] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.19.0-rc8-00045-gc92161e11c2a root=/dev/sda1 rw noisapnp cryptomgr.notests quiet initcall_debug init=/lib/systemd/systemd-bootchart iomem=relaxed apparmor=0 selinux=0 kmemleak=off initcall_blacklist=serial8250_init,init_graph_trace udev.children_max=2 udev.log_priority=info
[ 0.078456] blacklisting initcall serial8250_init
[ 0.078464] blacklisting initcall init_graph_trace
[ 0.078814] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[ 0.079007] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.079018] Initializing CPU#0
[ 0.079031] Initializing HighMem for node 0 (000375fe:0011f000)
[ 0.079034] add_highpages_with_active_regions: start_pfn = 226814, end_pfn = 1175552
[ 0.079038] add_highpages_with_active_regions: for_each_free_mem_range: i = 8589934592, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079041] add_highpages_with_active_regions: for_each_free_mem_range: i = 12884901889, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079043] add_highpages_with_active_regions: for_each_free_mem_range: i = 17179869185, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079046] add_highpages_with_active_regions: for_each_free_mem_range: i = 21474836481, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079048] add_highpages_with_active_regions: for_each_free_mem_range: i = 25769803777, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079050] add_highpages_with_active_regions: for_each_free_mem_range: i = 30064771073, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079052] add_highpages_with_active_regions: for_each_free_mem_range: i = 34359738369, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079054] add_highpages_with_active_regions: for_each_free_mem_range: i = 38654705665, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079056] add_highpages_with_active_regions: for_each_free_mem_range: i = 42949672961, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079059] add_highpages_with_active_regions: for_each_free_mem_range: i = 47244640257, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079061] add_highpages_with_active_regions: for_each_free_mem_range: i = 51539607553, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079063] add_highpages_with_active_regions: for_each_free_mem_range: i = 55834574849, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079065] add_highpages_with_active_regions: for_each_free_mem_range: i = 60129542145, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079067] add_highpages_with_active_regions: for_each_free_mem_range: i = 64424509441, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079069] add_highpages_with_active_regions: for_each_free_mem_range: i = 68719476737, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079072] add_highpages_with_active_regions: for_each_free_mem_range: i = 73014444033, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079074] add_highpages_with_active_regions: for_each_free_mem_range: i = 77309411329, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079076] add_highpages_with_active_regions: for_each_free_mem_range: i = 81604378625, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079078] add_highpages_with_active_regions: for_each_free_mem_range: i = 85899345921, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079080] add_highpages_with_active_regions: for_each_free_mem_range: i = 90194313217, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079083] add_highpages_with_active_regions: for_each_free_mem_range: i = 94489280513, nid = 0, pfn = 226814, e_pfn = 226814, before for loop
[ 0.079085] add_highpages_with_active_regions: for_each_free_mem_range: i = 94489280514, nid = 0, pfn = 226814, e_pfn = 818492, before for loop
[ 0.079087] add_highpages_with_active_regions: for_each_free_mem_range: i = 98784247811, nid = 0, pfn = 1048576, e_pfn = 1175552, before for loop
[ 0.079089] Initializing Movable for node 0 (00000000:00000000)
[ 0.079090] add_highpages_with_active_regions: start_pfn = 0, end_pfn = 0
[ 0.079093] add_highpages_with_active_regions: for_each_free_mem_range: i = 8589934592, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079095] add_highpages_with_active_regions: for_each_free_mem_range: i = 12884901889, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079097] add_highpages_with_active_regions: for_each_free_mem_range: i = 17179869185, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079099] add_highpages_with_active_regions: for_each_free_mem_range: i = 21474836481, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079101] add_highpages_with_active_regions: for_each_free_mem_range: i = 25769803777, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079103] add_highpages_with_active_regions: for_each_free_mem_range: i = 30064771073, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079106] add_highpages_with_active_regions: for_each_free_mem_range: i = 34359738369, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079108] add_highpages_with_active_regions: for_each_free_mem_range: i = 38654705665, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079110] add_highpages_with_active_regions: for_each_free_mem_range: i = 42949672961, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079112] add_highpages_with_active_regions: for_each_free_mem_range: i = 47244640257, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079114] add_highpages_with_active_regions: for_each_free_mem_range: i = 51539607553, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079116] add_highpages_with_active_regions: for_each_free_mem_range: i = 55834574849, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079118] add_highpages_with_active_regions: for_each_free_mem_range: i = 60129542145, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079120] add_highpages_with_active_regions: for_each_free_mem_range: i = 64424509441, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079122] add_highpages_with_active_regions: for_each_free_mem_range: i = 68719476737, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079124] add_highpages_with_active_regions: for_each_free_mem_range: i = 73014444033, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079126] add_highpages_with_active_regions: for_each_free_mem_range: i = 77309411329, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079128] add_highpages_with_active_regions: for_each_free_mem_range: i = 81604378625, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079131] add_highpages_with_active_regions: for_each_free_mem_range: i = 85899345921, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079133] add_highpages_with_active_regions: for_each_free_mem_range: i = 90194313217, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079135] add_highpages_with_active_regions: for_each_free_mem_range: i = 94489280513, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079137] add_highpages_with_active_regions: for_each_free_mem_range: i = 94489280514, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.079139] add_highpages_with_active_regions: for_each_free_mem_range: i = 98784247811, nid = 0, pfn = 0, e_pfn = 0, before for loop
[ 0.088566] Memory: 837936K/3781484K available (8706K kernel code, 756K rwdata, 2840K rodata, 1112K init, 644K bss, 2943548K reserved, 0K cma-reserved, 0K highmem)
[ 0.088577] virtual kernel memory layout:
fixmap : 0xfff8f000 - 0xfffff000 ( 448 kB)
cpu_entry : 0xffa00000 - 0xffa4f000 ( 316 kB)
pkmap : 0xff600000 - 0xff800000 (2048 kB)
vmalloc : 0xf7dfe000 - 0xff5fe000 ( 120 MB)
lowmem : 0xc0000000 - 0xf75fe000 ( 885 MB)
.init : 0xcac1d000 - 0xcad33000 (1112 kB)
.data : 0xca880b9c - 0xcac0b000 (3625 kB)
.text : 0xca000000 - 0xca880b9c (8706 kB)
[ 0.088578] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[ 0.088890] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.088894] After mm_init
[ 0.088896] ftrace: allocating 33663 entries in 66 pages
[ 0.117268] After sched_init
[ 0.117334] rcu: Hierarchical RCU implementation.
[ 0.117336] rcu: RCU event tracing is enabled.
[ 0.117350] After rcu_init
[ 0.123427] NR_IRQS: 2304, nr_irqs: 440, preallocated irqs: 16
[ 0.124009] CPU 0 irqstacks, hard=(ptrval) soft=(ptrval)
[ 0.124076] calling con_init+0x0/0x1e3 @ 0
[ 0.126623] Console: colour VGA+ 80x25
[ 0.126635] console [tty0] enabled
[ 0.126640] initcall con_init+0x0/0x1e3 returned 0 after 0 usecs
[ 0.126643] calling univ8250_console_init+0x0/0x2a @ 0
[ 0.126652] initcall univ8250_console_init+0x0/0x2a returned 0 after 0 usecs
[ 0.126694] ACPI: Core revision 20180810
[ 0.127247] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484873504 ns
[ 0.127268] hpet clockevent registered
[ 0.127277] APIC: Switch to symmetric I/O mode setup
[ 0.127279] Enabling APIC mode: Flat. Using 1 I/O APICs
[ 0.127901] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=0 pin2=0
[ 0.132351] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x17100c6f8d5, max_idle_ns: 440795227657 ns
[ 0.132369] Calibrating delay loop (skipped), value calculated using timer frequency.. 3199.94 BogoMIPS (lpj=1599974)
[ 0.132376] pid_max: default: 32768 minimum: 301
[ 0.132466] Security Framework initialized
[ 0.132469] Yama: becoming mindful.
[ 0.132483] calling selinux_init+0x0/0x1c3 @ 0
[ 0.132485] SELinux: Disabled at boot.
[ 0.132489] initcall selinux_init+0x0/0x1c3 returned 0 after 0 usecs
[ 0.132520] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.132527] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.132977] mce: CPU supports 6 MCE banks
[ 0.132996] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 4
[ 0.132998] Last level dTLB entries: 4KB 512, 2MB 8, 4MB 4, 1GB 0
[ 0.133005] Spectre V2 : Mitigation: Full AMD retpoline
[ 0.133006] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 0.133008] Speculative Store Bypass: Vulnerable
[ 0.133244] Freeing SMP alternatives memory: 32K
[ 0.133247] After check_bugs
[ 0.133251] After acpi_subsystem_init
[ 0.133252] After arch_post_acpi_subsys_init
[ 0.133253] After sfi_init_late
[ 0.133255] After rcu_scheduler_starting
[ 0.133392] After find_task_by_pid_ns
[ 0.133394] After numa_default_policy
[ 0.133430] After rcu_read_lock
[ 0.133431] After rcu_read_unlock
[ 0.133437] After kthreadd_done
[ 0.134354] random: 1
[ 0.134354] random: 2
[ 0.134354] random: 3
[ 0.134354] random: 4
[ 0.153357] random: 1
[ 0.153358] random: 2
[ 0.153359] random: 3
[ 0.153360] random: 4
[ 0.217357] random: 1
[ 0.217358] random: 2
[ 0.217359] random: 3
[ 0.217360] random: 4
[ 0.235354] smpboot: CPU0: AMD E-350D APU with Radeon(tm) HD Graphics (family: 0x14, model: 0x2, stepping: 0x0)
[ 0.235354] calling trace_init_flags_sys_enter+0x0/0x13 @ 1
[ 0.235354] initcall trace_init_flags_sys_enter+0x0/0x13 returned 0 after 0 usecs
[ 0.235354] calling trace_init_flags_sys_exit+0x0/0x13 @ 1
[ 0.235354] initcall trace_init_flags_sys_exit+0x0/0x13 returned 0 after 0 usecs
[ 0.235354] calling init_hw_perf_events+0x0/0x5bd @ 1
[ 0.235354] Performance Events: AMD PMU driver.
[ 0.235354] ... version: 0
[ 0.235354] ... bit width: 48
[ 0.235354] ... generic registers: 4
[ 0.235354] ... value mask: 0000ffffffffffff
[ 0.235354] ... max period: 00007fffffffffff
[ 0.235354] ... fixed-purpose events: 0
[ 0.235354] ... event mask: 000000000000000f
[ 0.235354] initcall init_hw_perf_events+0x0/0x5bd returned 0 after 0 usecs
[ 0.235354] calling init_real_mode+0x0/0x108 @ 1
[ 0.235354] initcall init_real_mode+0x0/0x108 returned 0 after 0 usecs
[ 0.235354] calling trace_init_perf_perm_irq_work_exit+0x0/0x16 @ 1
[ 0.235354] initcall trace_init_perf_perm_irq_work_exit+0x0/0x16 returned 0 after 0 usecs
[ 0.235354] calling validate_x2apic+0x0/0x49 @ 1
[ 0.235354] initcall validate_x2apic+0x0/0x49 returned 0 after 0 usecs
[ 0.235354] calling register_nmi_cpu_backtrace_handler+0x0/0x18 @ 1
[ 0.235354] initcall register_nmi_cpu_backtrace_handler+0x0/0x18 returned 0 after 0 usecs
[ 0.235354] calling spawn_ksoftirqd+0x0/0x39 @ 1
[ 0.235354] initcall spawn_ksoftirqd+0x0/0x39 returned 0 after 0 usecs
[ 0.235354] calling migration_init+0x0/0x31 @ 1
[ 0.235354] initcall migration_init+0x0/0x31 returned 0 after 0 usecs
[ 0.235354] calling check_cpu_stall_init+0x0/0x1b @ 1
[ 0.235354] initcall check_cpu_stall_init+0x0/0x1b returned 0 after 0 usecs
[ 0.235354] calling srcu_bootup_announce+0x0/0x30 @ 1
[ 0.235354] rcu: Hierarchical SRCU implementation.
[ 0.235354] initcall srcu_bootup_announce+0x0/0x30 returned 0 after 0 usecs
[ 0.235354] calling rcu_spawn_gp_kthread+0x0/0xfc @ 1
[ 0.235354] initcall rcu_spawn_gp_kthread+0x0/0xfc returned 0 after 0 usecs
[ 0.235354] calling cpu_stop_init+0x0/0x78 @ 1
[ 0.235354] initcall cpu_stop_init+0x0/0x78 returned 0 after 0 usecs
[ 0.235354] calling init_events+0x0/0x3a @ 1
[ 0.235354] initcall init_events+0x0/0x3a returned 0 after 0 usecs
[ 0.235354] calling init_trace_printk+0x0/0xf @ 1
[ 0.235354] initcall init_trace_printk+0x0/0xf returned 0 after 0 usecs
[ 0.235354] calling event_trace_enable_again+0x0/0x21 @ 1
[ 0.235354] initcall event_trace_enable_again+0x0/0x21 returned 0 after 0 usecs
[ 0.235354] calling jump_label_init_module+0x0/0x14 @ 1
[ 0.235354] initcall jump_label_init_module+0x0/0x14 returned 0 after 0 usecs
[ 0.235354] calling dynamic_debug_init+0x0/0x211 @ 1
[ 0.235529] initcall dynamic_debug_init+0x0/0x211 returned 0 after 976 usecs
[ 0.235534] calling rand_initialize+0x0/0x110 @ 1
[ 0.235613] initcall rand_initialize+0x0/0x110 returned 0 after 0 usecs
[ 0.235618] calling initialize_ptr_random+0x0/0x47 @ 1
[ 0.235623] initcall initialize_ptr_random+0x0/0x47 returned 0 after 0 usecs
[ 0.235720] smp: Bringing up secondary CPUs ...
[ 0.235953] CPU 1 irqstacks, hard=(ptrval) soft=(ptrval)
[ 0.235956] x86: Booting SMP configuration:
[ 0.235958] .... node #0, CPUs: #1
[ 0.004323] Initializing CPU#1
[ 0.236418] After schedule_preempt_disabled
[ 0.236448] smp: Brought up 1 node, 2 CPUs
[ 0.236448] smpboot: Max logical packages: 1
[ 0.236448] smpboot: Total of 2 processors activated (6399.89 BogoMIPS)
[ 0.238403] devtmpfs: initialized
[ 0.238900] calling ipc_ns_init+0x0/0x48 @ 1
[ 0.238920] initcall ipc_ns_init+0x0/0x48 returned 0 after 0 usecs
[ 0.238924] calling init_mmap_min_addr+0x0/0x27 @ 1
[ 0.238927] initcall init_mmap_min_addr+0x0/0x27 returned 0 after 0 usecs
[ 0.238932] calling net_ns_init+0x0/0xdc @ 1
[ 0.239095] initcall net_ns_init+0x0/0xdc returned 0 after 0 usecs
[ 0.239098] calling e820__register_nvs_regions+0x0/0x3e @ 1
[ 0.239098] initcall e820__register_nvs_regions+0x0/0x3e returned 0 after 0 usecs
[ 0.239098] calling cpufreq_register_tsc_scaling+0x0/0x2a @ 1
[ 0.239098] initcall cpufreq_register_tsc_scaling+0x0/0x2a returned 0 after 0 usecs
[ 0.239098] calling init_cpu_syscore+0x0/0x11 @ 1
[ 0.239098] initcall init_cpu_syscore+0x0/0x11 returned 0 after 0 usecs
[ 0.239098] calling reboot_init+0x0/0x22 @ 1
[ 0.239098] initcall reboot_init+0x0/0x22 returned 0 after 0 usecs
[ 0.239098] calling init_lapic_sysfs+0x0/0x24 @ 1
[ 0.239098] initcall init_lapic_sysfs+0x0/0x24 returned 0 after 0 usecs
[ 0.239098] calling pgd_cache_init+0x0/0x35 @ 1
[ 0.239098] initcall pgd_cache_init+0x0/0x35 returned 0 after 0 usecs
[ 0.239098] calling alloc_frozen_cpus+0x0/0xc @ 1
[ 0.239098] initcall alloc_frozen_cpus+0x0/0xc returned 0 after 0 usecs
[ 0.239098] calling cpu_hotplug_pm_sync_init+0x0/0x16 @ 1
[ 0.239098] initcall cpu_hotplug_pm_sync_init+0x0/0x16 returned 0 after 0 usecs
[ 0.239098] calling wq_sysfs_init+0x0/0x29 @ 1
[ 0.239098] initcall wq_sysfs_init+0x0/0x29 returned 0 after 0 usecs
[ 0.239098] calling ksysfs_init+0x0/0x7d @ 1
[ 0.239098] initcall ksysfs_init+0x0/0x7d returned 0 after 0 usecs
[ 0.239098] calling pm_init+0x0/0x63 @ 1
[ 0.239367] initcall pm_init+0x0/0x63 returned 0 after 0 usecs
[ 0.239367] calling rcu_set_runtime_mode+0x0/0x16 @ 1
[ 0.239367] initcall rcu_set_runtime_mode+0x0/0x16 returned 0 after 0 usecs
[ 0.239367] calling init_jiffies_clocksource+0x0/0x1b @ 1
[ 0.239367] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[ 0.239367] initcall init_jiffies_clocksource+0x0/0x1b returned 0 after 0 usecs
[ 0.239367] calling futex_init+0x0/0xd5 @ 1
[ 0.239367] futex hash table entries: 512 (order: 3, 32768 bytes)
[ 0.239367] initcall futex_init+0x0/0xd5 returned 0 after 0 usecs
[ 0.239367] calling cgroup_wq_init+0x0/0x2c @ 1
[ 0.239367] initcall cgroup_wq_init+0x0/0x2c returned 0 after 0 usecs
[ 0.239367] calling cgroup1_wq_init+0x0/0x2c @ 1
[ 0.239367] initcall cgroup1_wq_init+0x0/0x2c returned 0 after 0 usecs
[ 0.239367] calling ftrace_mod_cmd_init+0x0/0xf @ 1
[ 0.239367] initcall ftrace_mod_cmd_init+0x0/0xf returned 0 after 0 usecs
[ 0.239367] calling init_wakeup_tracer+0x0/0x2b @ 1
[ 0.239367] initcall init_wakeup_tracer+0x0/0x2b returned 0 after 0 usecs
[ 0.239367] initcall init_graph_trace blacklisted
[ 0.239367] calling init_per_zone_wmark_min+0x0/0x66 @ 1
[ 0.239367] initcall init_per_zone_wmark_min+0x0/0x66 returned 0 after 0 usecs
[ 0.239367] calling init_zero_pfn+0x0/0x47 @ 1
[ 0.239367] initcall init_zero_pfn+0x0/0x47 returned 0 after 0 usecs
[ 0.239367] calling cma_init_reserved_areas+0x0/0x170 @ 1
[ 0.239367] initcall cma_init_reserved_areas+0x0/0x170 returned 0 after 0 usecs
[ 0.239367] calling fsnotify_init+0x0/0x44 @ 1
[ 0.239367] initcall fsnotify_init+0x0/0x44 returned 0 after 0 usecs
[ 0.239367] calling filelock_init+0x0/0x7f @ 1
[ 0.239367] initcall filelock_init+0x0/0x7f returned 0 after 0 usecs
[ 0.239367] calling init_script_binfmt+0x0/0x18 @ 1
[ 0.239367] initcall init_script_binfmt+0x0/0x18 returned 0 after 0 usecs
[ 0.239367] calling init_elf_binfmt+0x0/0x18 @ 1
[ 0.239367] initcall init_elf_binfmt+0x0/0x18 returned 0 after 0 usecs
[ 0.239367] calling configfs_init+0x0/0x88 @ 1
[ 0.239367] initcall configfs_init+0x0/0x88 returned 0 after 0 usecs
[ 0.239367] calling debugfs_init+0x0/0x4b @ 1
[ 0.239367] initcall debugfs_init+0x0/0x4b returned 0 after 0 usecs
[ 0.239367] calling tracefs_init+0x0/0x39 @ 1
[ 0.239367] initcall tracefs_init+0x0/0x39 returned 0 after 0 usecs
[ 0.239367] calling securityfs_init+0x0/0x63 @ 1
[ 0.239367] initcall securityfs_init+0x0/0x63 returned 0 after 0 usecs
[ 0.239367] calling prandom_init+0x0/0xb1 @ 1
[ 0.239367] initcall prandom_init+0x0/0xb1 returned 0 after 0 usecs
[ 0.239367] calling pinctrl_init+0x0/0x9d @ 1
[ 0.239367] pinctrl core: initialized pinctrl subsystem
[ 0.239367] initcall pinctrl_init+0x0/0x9d returned 0 after 0 usecs
[ 0.239367] calling gpiolib_dev_init+0x0/0xb3 @ 1
[ 0.239367] initcall gpiolib_dev_init+0x0/0xb3 returned 0 after 0 usecs
[ 0.239367] calling component_debug_init+0x0/0x1d @ 1
[ 0.239367] initcall component_debug_init+0x0/0x1d returned 0 after 0 usecs
[ 0.239367] calling early_resume_init+0x0/0xf0 @ 1
[ 0.239367] RTC time: 22:20:28, date: 10/16/18
[ 0.239367] initcall early_resume_init+0x0/0xf0 returned 0 after 0 usecs
[ 0.239367] calling opp_debug_init+0x0/0x39 @ 1
[ 0.239367] initcall opp_debug_init+0x0/0x39 returned 0 after 0 usecs
[ 0.239367] calling cpufreq_core_init+0x0/0x46 @ 1
[ 0.239367] initcall cpufreq_core_init+0x0/0x46 returned 0 after 0 usecs
[ 0.239367] calling cpuidle_init+0x0/0x3b @ 1
[ 0.239367] initcall cpuidle_init+0x0/0x3b returned 0 after 0 usecs
[ 0.239367] calling bsp_pm_check_init+0x0/0x16 @ 1
[ 0.239367] initcall bsp_pm_check_init+0x0/0x16 returned 0 after 0 usecs
[ 0.239367] calling sock_init+0x0/0x8e @ 1
[ 0.239425] initcall sock_init+0x0/0x8e returned 0 after 0 usecs
[ 0.239430] calling net_inuse_init+0x0/0x24 @ 1
[ 0.239437] initcall net_inuse_init+0x0/0x24 returned 0 after 0 usecs
[ 0.239441] calling net_defaults_init+0x0/0x24 @ 1
[ 0.239445] initcall net_defaults_init+0x0/0x24 returned 0 after 0 usecs
[ 0.239449] calling init_default_flow_dissectors+0x0/0x48 @ 1
[ 0.239453] initcall init_default_flow_dissectors+0x0/0x48 returned 0 after 0 usecs
[ 0.239457] calling netpoll_init+0x0/0x34 @ 1
[ 0.239460] initcall netpoll_init+0x0/0x34 returned 0 after 0 usecs
[ 0.239464] calling netlink_proto_init+0x0/0x123 @ 1
[ 0.239506] NET: Registered protocol family 16
[ 0.239530] initcall netlink_proto_init+0x0/0x123 returned 0 after 976 usecs
[ 0.239721] calling irq_sysfs_init+0x0/0x7e @ 1
[ 0.239887] initcall irq_sysfs_init+0x0/0x7e returned 0 after 0 usecs
[ 0.239892] calling audit_init+0x0/0x164 @ 1
[ 0.239899] audit: initializing netlink subsys (disabled)
[ 0.240427] initcall audit_init+0x0/0x164 returned 0 after 0 usecs
[ 0.240427] audit: type=2000 audit(1539728428.113:1): state=initialized audit_enabled=0 res=1
[ 0.240427] calling release_early_probes+0x0/0x36 @ 1
[ 0.240431] initcall release_early_probes+0x0/0x36 returned 0 after 0 usecs
[ 0.240436] calling bdi_class_init+0x0/0x41 @ 1
[ 0.240470] initcall bdi_class_init+0x0/0x41 returned 0 after 0 usecs
[ 0.240474] calling mm_sysfs_init+0x0/0x27 @ 1
[ 0.240481] initcall mm_sysfs_init+0x0/0x27 returned 0 after 0 usecs
[ 0.240485] calling gpiolib_sysfs_init+0x0/0x85 @ 1
[ 0.240499] initcall gpiolib_sysfs_init+0x0/0x85 returned 0 after 0 usecs
[ 0.240504] calling pcibus_class_init+0x0/0x19 @ 1
[ 0.240512] initcall pcibus_class_init+0x0/0x19 returned 0 after 0 usecs
[ 0.240516] calling pci_driver_init+0x0/0x22 @ 1
[ 0.240547] initcall pci_driver_init+0x0/0x22 returned 0 after 0 usecs
[ 0.240552] calling backlight_class_init+0x0/0x91 @ 1
[ 0.240561] initcall backlight_class_init+0x0/0x91 returned 0 after 0 usecs
[ 0.240565] calling tty_class_init+0x0/0x30 @ 1
[ 0.240573] initcall tty_class_init+0x0/0x30 returned 0 after 0 usecs
[ 0.240577] calling vtconsole_class_init+0x0/0xc0 @ 1
[ 0.240625] initcall vtconsole_class_init+0x0/0xc0 returned 0 after 0 usecs
[ 0.240630] calling wakeup_sources_debugfs_init+0x0/0x24 @ 1
[ 0.240638] initcall wakeup_sources_debugfs_init+0x0/0x24 returned 0 after 0 usecs
[ 0.240642] calling regmap_initcall+0x0/0x11 @ 1
[ 0.240648] initcall regmap_initcall+0x0/0x11 returned 0 after 0 usecs
[ 0.240653] calling i2c_init+0x0/0xa5 @ 1
[ 0.240689] initcall i2c_init+0x0/0xa5 returned 0 after 0 usecs
[ 0.240695] calling init_menu+0x0/0x14 @ 1
[ 0.240721] cpuidle: using governor menu
[ 0.240721] initcall init_menu+0x0/0x14 returned 0 after 0 usecs
[ 0.240721] calling pcc_init+0x0/0x2e4 @ 1
[ 0.240721] initcall pcc_init+0x0/0x2e4 returned -19 after 0 usecs
[ 0.240721] calling amd_postcore_init+0x0/0xf2 @ 1
[ 0.241372] initcall amd_postcore_init+0x0/0xf2 returned 0 after 0 usecs
[ 0.241378] calling kobject_uevent_init+0x0/0xf @ 1
[ 0.241400] initcall kobject_uevent_init+0x0/0xf returned 0 after 0 usecs
[ 0.241602] calling bts_init+0x0/0xae @ 1
[ 0.241606] initcall bts_init+0x0/0xae returned -19 after 0 usecs
[ 0.241609] calling pt_init+0x0/0x2f9 @ 1
[ 0.241613] initcall pt_init+0x0/0x2f9 returned -19 after 0 usecs
[ 0.241618] calling boot_params_ksysfs_init+0x0/0x216 @ 1
[ 0.241638] initcall boot_params_ksysfs_init+0x0/0x216 returned 0 after 0 usecs
[ 0.241642] calling sbf_init+0x0/0xc8 @ 1
[ 0.241645] initcall sbf_init+0x0/0xc8 returned 0 after 0 usecs
[ 0.241650] calling arch_kdebugfs_init+0x0/0x23 @ 1
[ 0.241668] initcall arch_kdebugfs_init+0x0/0x23 returned 0 after 0 usecs
[ 0.241673] calling init_pit_clocksource+0x0/0x37 @ 1
[ 0.241676] initcall init_pit_clocksource+0x0/0x37 returned 0 after 0 usecs
[ 0.241681] calling intel_pconfig_init+0x0/0xbb @ 1
[ 0.241685] initcall intel_pconfig_init+0x0/0xbb returned 0 after 0 usecs
[ 0.241690] calling mtrr_if_init+0x0/0x51 @ 1
[ 0.241699] initcall mtrr_if_init+0x0/0x51 returned 0 after 0 usecs
[ 0.241703] calling ffh_cstate_init+0x0/0x2d @ 1
[ 0.241715] initcall ffh_cstate_init+0x0/0x2d returned 0 after 0 usecs
[ 0.241720] calling kdump_buf_page_init+0x0/0x3b @ 1
[ 0.241724] initcall kdump_buf_page_init+0x0/0x3b returned 0 after 0 usecs
[ 0.241729] calling acpi_pci_init+0x0/0x5b @ 1
[ 0.241731] ACPI: bus type PCI registered
[ 0.241736] initcall acpi_pci_init+0x0/0x5b returned 0 after 0 usecs
[ 0.241740] calling dma_channel_table_init+0x0/0xc2 @ 1
[ 0.241752] initcall dma_channel_table_init+0x0/0xc2 returned 0 after 0 usecs
[ 0.241755] calling dma_bus_init+0x0/0x91 @ 1
[ 0.241790] initcall dma_bus_init+0x0/0x91 returned 0 after 0 usecs
[ 0.241794] calling dmi_id_init+0x0/0x2b8 @ 1
[ 0.241868] initcall dmi_id_init+0x0/0x2b8 returned 0 after 0 usecs
[ 0.241872] calling pci_arch_init+0x0/0x6b @ 1
[ 0.242285] PCI: Using configuration type 1 for base access
[ 0.242286] PCI: Using configuration type 1 for extended access
[ 0.242300] initcall pci_arch_init+0x0/0x6b returned 0 after 976 usecs
[ 0.242496] calling fixup_ht_bug+0x0/0x8e @ 1
[ 0.242500] initcall fixup_ht_bug+0x0/0x8e returned 0 after 0 usecs
[ 0.242504] calling topology_init+0x0/0x30 @ 1
[ 0.242641] initcall topology_init+0x0/0x30 returned 0 after 976 usecs
[ 0.242646] calling mtrr_init_finialize+0x0/0x41 @ 1
[ 0.242648] mtrr: your CPUs had inconsistent variable MTRR settings
[ 0.242650] mtrr: probably your BIOS does not setup all CPUs.
[ 0.242650] mtrr: corrected configuration.
[ 0.242654] initcall mtrr_init_finialize+0x0/0x41 returned 0 after 0 usecs
[ 0.242658] calling uid_cache_init+0x0/0x7b @ 1
[ 0.242666] initcall uid_cache_init+0x0/0x7b returned 0 after 0 usecs
[ 0.242670] calling param_sysfs_init+0x0/0x196 @ 1
[ 0.246172] initcall param_sysfs_init+0x0/0x196 returned 0 after 2929 usecs
[ 0.246177] calling user_namespace_sysctl_init+0x0/0x40 @ 1
[ 0.246200] initcall user_namespace_sysctl_init+0x0/0x40 returned 0 after 0 usecs
[ 0.246204] calling proc_schedstat_init+0x0/0x23 @ 1
[ 0.246211] initcall proc_schedstat_init+0x0/0x23 returned 0 after 0 usecs
[ 0.246215] calling pm_sysrq_init+0x0/0x1b @ 1
[ 0.246414] initcall pm_sysrq_init+0x0/0x1b returned 0 after 0 usecs
[ 0.246414] calling create_proc_profile+0x0/0x100 @ 1
[ 0.246414] initcall create_proc_profile+0x0/0x100 returned 0 after 0 usecs
[ 0.246414] calling crash_save_vmcoreinfo_init+0x0/0x4e1 @ 1
[ 0.246432] initcall crash_save_vmcoreinfo_init+0x0/0x4e1 returned 0 after 0 usecs
[ 0.246436] calling crash_notes_memory_init+0x0/0x36 @ 1
[ 0.246452] initcall crash_notes_memory_init+0x0/0x36 returned 0 after 0 usecs
[ 0.246457] calling cgroup_sysfs_init+0x0/0x19 @ 1
[ 0.246466] initcall cgroup_sysfs_init+0x0/0x19 returned 0 after 0 usecs
[ 0.246471] calling cgroup_namespaces_init+0x0/0xc @ 1
[ 0.246475] initcall cgroup_namespaces_init+0x0/0xc returned 0 after 0 usecs
[ 0.246479] calling user_namespaces_init+0x0/0x2c @ 1
[ 0.246502] initcall user_namespaces_init+0x0/0x2c returned 0 after 0 usecs
[ 0.246506] calling dev_map_init+0x0/0x16 @ 1
[ 0.246511] initcall dev_map_init+0x0/0x16 returned 0 after 0 usecs
[ 0.246514] calling stack_map_init+0x0/0x40 @ 1
[ 0.246517] initcall stack_map_init+0x0/0x40 returned 0 after 0 usecs
[ 0.246521] calling oom_init+0x0/0x2e @ 1
[ 0.247415] initcall oom_init+0x0/0x2e returned 0 after 0 usecs
[ 0.247415] calling default_bdi_init+0x0/0xa7 @ 1
[ 0.247456] initcall default_bdi_init+0x0/0xa7 returned 0 after 0 usecs
[ 0.247461] calling cgwb_init+0x0/0x2c @ 1
[ 0.247475] initcall cgwb_init+0x0/0x2c returned 0 after 0 usecs
[ 0.247479] calling percpu_enable_async+0x0/0x13 @ 1
[ 0.247483] initcall percpu_enable_async+0x0/0x13 returned 0 after 0 usecs
[ 0.247488] calling kcompactd_init+0x0/0x49 @ 1
[ 0.247500] initcall kcompactd_init+0x0/0x49 returned 0 after 0 usecs
[ 0.247500] calling init_user_reserve+0x0/0x40 @ 1
[ 0.247500] initcall init_user_reserve+0x0/0x40 returned 0 after 0 usecs
[ 0.247500] calling init_admin_reserve+0x0/0x40 @ 1
[ 0.247500] initcall init_admin_reserve+0x0/0x40 returned 0 after 0 usecs
[ 0.247500] calling init_reserve_notifier+0x0/0x3 @ 1
[ 0.247500] initcall init_reserve_notifier+0x0/0x3 returned 0 after 0 usecs
[ 0.247500] calling swap_init_sysfs+0x0/0x5d @ 1
[ 0.247500] initcall swap_init_sysfs+0x0/0x5d returned 0 after 0 usecs
[ 0.247500] calling swapfile_init+0x0/0x40 @ 1
[ 0.247500] initcall swapfile_init+0x0/0x40 returned 0 after 0 usecs
[ 0.247500] calling hugetlb_init+0x0/0x3ba @ 1
[ 0.247500] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.247500] initcall hugetlb_init+0x0/0x3ba returned 0 after 0 usecs
[ 0.247500] calling ksm_init+0x0/0x154 @ 1
[ 0.247500] initcall ksm_init+0x0/0x154 returned 0 after 0 usecs
[ 0.247500] calling hugepage_init+0x0/0x12e @ 1
[ 0.247500] initcall hugepage_init+0x0/0x12e returned 0 after 0 usecs
[ 0.247500] calling mem_cgroup_init+0x0/0xc0 @ 1
[ 0.247500] initcall mem_cgroup_init+0x0/0xc0 returned 0 after 0 usecs
[ 0.247500] calling mem_cgroup_swap_init+0x0/0x4e @ 1
[ 0.247500] initcall mem_cgroup_swap_init+0x0/0x4e returned 0 after 0 usecs
[ 0.247500] calling sel_ib_pkey_init+0x0/0x30 @ 1
[ 0.247500] initcall sel_ib_pkey_init+0x0/0x30 returned 0 after 0 usecs
[ 0.247500] calling crypto_wq_init+0x0/0x2c @ 1
[ 0.247523] initcall crypto_wq_init+0x0/0x2c returned 0 after 0 usecs
[ 0.247527] calling cryptomgr_init+0x0/0x14 @ 1
[ 0.247530] initcall cryptomgr_init+0x0/0x14 returned 0 after 0 usecs
[ 0.247533] calling cryptd_init+0x0/0xa2 @ 1
[ 0.247538] cryptd: max_cpu_qlen set to 1000
[ 0.247541] initcall cryptd_init+0x0/0xa2 returned 0 after 0 usecs
[ 0.247545] calling init_bio+0x0/0xbe @ 1
[ 0.247598] initcall init_bio+0x0/0xbe returned 0 after 0 usecs
[ 0.247598] calling blk_settings_init+0x0/0x22 @ 1
[ 0.247598] initcall blk_settings_init+0x0/0x22 returned 0 after 0 usecs
[ 0.247598] calling blk_ioc_init+0x0/0x29 @ 1
[ 0.247598] initcall blk_ioc_init+0x0/0x29 returned 0 after 0 usecs
[ 0.247598] calling blk_softirq_init+0x0/0x63 @ 1
[ 0.247598] initcall blk_softirq_init+0x0/0x63 returned 0 after 0 usecs
[ 0.247598] calling blk_mq_init+0x0/0x26 @ 1
[ 0.247598] initcall blk_mq_init+0x0/0x26 returned 0 after 0 usecs
[ 0.247598] calling genhd_device_init+0x0/0x66 @ 1
[ 0.248420] initcall genhd_device_init+0x0/0x66 returned 0 after 976 usecs
[ 0.248420] calling irq_poll_setup+0x0/0x5e @ 1
[ 0.248420] initcall irq_poll_setup+0x0/0x5e returned 0 after 0 usecs
[ 0.248420] calling byt_gpio_init+0x0/0x16 @ 1
[ 0.248420] initcall byt_gpio_init+0x0/0x16 returned 0 after 0 usecs
[ 0.248424] calling gpiolib_debugfs_init+0x0/0x24 @ 1
[ 0.248432] initcall gpiolib_debugfs_init+0x0/0x24 returned 0 after 0 usecs
[ 0.248436] calling pwm_debugfs_init+0x0/0x24 @ 1
[ 0.248441] initcall pwm_debugfs_init+0x0/0x24 returned 0 after 0 usecs
[ 0.248445] calling pwm_sysfs_init+0x0/0x19 @ 1
[ 0.248454] initcall pwm_sysfs_init+0x0/0x19 returned 0 after 0 usecs
[ 0.248460] calling pci_slot_init+0x0/0x40 @ 1
[ 0.248467] initcall pci_slot_init+0x0/0x40 returned 0 after 0 usecs
[ 0.248471] calling fbmem_init+0x0/0xcc @ 1
[ 0.248526] initcall fbmem_init+0x0/0xcc returned 0 after 0 usecs
[ 0.248531] calling scan_for_dmi_ipmi+0x0/0x10f @ 1
[ 0.248535] initcall scan_for_dmi_ipmi+0x0/0x10f returned 0 after 0 usecs
[ 0.248540] calling acpi_init+0x0/0x2e2 @ 1
[ 0.248572] ACPI: Added _OSI(Module Device)
[ 0.248574] ACPI: Added _OSI(Processor Device)
[ 0.248575] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.248577] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.248580] ACPI: Added _OSI(Linux-Dell-Video)
[ 0.248583] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[ 0.259842] ACPI: 4 ACPI AML tables successfully acquired and loaded
[ 0.263742] ACPI: Interpreter enabled
[ 0.263787] ACPI: (supports S0 S1 S3 S5)
[ 0.263790] ACPI: Using IOAPIC for interrupt routing
[ 0.263829] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.264229] ACPI: Enabled 19 GPEs in block 00 to 1F
[ 0.274304] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.274319] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[ 0.274494] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability LTR]
[ 0.274933] PCI host bridge to bus 0000:00
[ 0.274939] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.274942] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.274945] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[ 0.274948] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xffffffff]
[ 0.274952] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.274971] pci 0000:00:00.0: [1022:1510] type 00 class 0x060000
[ 0.274983] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x10 @ 1
[ 0.274989] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[ 0.275266] pci 0000:00:01.0: [1002:9802] type 00 class 0x030000
[ 0.275290] pci 0000:00:01.0: reg 0x10: [mem 0xe0000000-0xefffffff pref]
[ 0.275299] pci 0000:00:01.0: reg 0x14: [io 0x2000-0x20ff]
[ 0.275309] pci 0000:00:01.0: reg 0x18: [mem 0xf0100000-0xf013ffff]
[ 0.275340] pci 0000:00:01.0: enabling Extended Tags
[ 0.275372] pci 0000:00:01.0: calling quirk_no_pm_reset+0x0/0x20 @ 1
[ 0.275377] pci 0000:00:01.0: quirk_no_pm_reset+0x0/0x20 took 0 usecs
[ 0.275411] pci 0000:00:01.0: supports D1 D2
[ 0.275646] pci 0000:00:01.1: [1002:1314] type 00 class 0x040300
[ 0.275668] pci 0000:00:01.1: reg 0x10: [mem 0xf0140000-0xf0143fff]
[ 0.275710] pci 0000:00:01.1: enabling Extended Tags
[ 0.275757] pci 0000:00:01.1: supports D1 D2
[ 0.276085] pci 0000:00:11.0: [1002:4391] type 00 class 0x010601
[ 0.276114] pci 0000:00:11.0: reg 0x10: [io 0x2410-0x2417]
[ 0.276127] pci 0000:00:11.0: reg 0x14: [io 0x2420-0x2423]
[ 0.276141] pci 0000:00:11.0: reg 0x18: [io 0x2418-0x241f]
[ 0.276154] pci 0000:00:11.0: reg 0x1c: [io 0x2424-0x2427]
[ 0.276168] pci 0000:00:11.0: reg 0x20: [io 0x2400-0x240f]
[ 0.276181] pci 0000:00:11.0: reg 0x24: [mem 0xf014b000-0xf014b3ff]
[ 0.276531] pci 0000:00:12.0: [1002:4397] type 00 class 0x0c0310
[ 0.276556] pci 0000:00:12.0: reg 0x10: [mem 0xf0148000-0xf0148fff]
[ 0.276926] pci 0000:00:12.2: [1002:4396] type 00 class 0x0c0320
[ 0.276956] pci 0000:00:12.2: reg 0x10: [mem 0xf014c000-0xf014c0ff]
[ 0.277067] pci 0000:00:12.2: supports D1 D2
[ 0.277070] pci 0000:00:12.2: PME# supported from D0 D1 D2 D3hot
[ 0.277073] pci 0000:00:12.2: pme_poll = true
[ 0.277075] pci 0000:00:12.2: after device_set_wakeup_capable()
[ 0.277081] pci 0000:00:12.2: after pci_pme_active()
[ 0.277380] pci 0000:00:13.0: [1002:4397] type 00 class 0x0c0310
[ 0.277405] pci 0000:00:13.0: reg 0x10: [mem 0xf0149000-0xf0149fff]
[ 0.277772] pci 0000:00:13.2: [1002:4396] type 00 class 0x0c0320
[ 0.277801] pci 0000:00:13.2: reg 0x10: [mem 0xf014d000-0xf014d0ff]
[ 0.277911] pci 0000:00:13.2: supports D1 D2
[ 0.277914] pci 0000:00:13.2: PME# supported from D0 D1 D2 D3hot
[ 0.277917] pci 0000:00:13.2: pme_poll = true
[ 0.277919] pci 0000:00:13.2: after device_set_wakeup_capable()
[ 0.277924] pci 0000:00:13.2: after pci_pme_active()
[ 0.278223] pci 0000:00:14.0: [1002:4385] type 00 class 0x0c0500
[ 0.278233] pci 0000:00:14.0: calling sb600_disable_hpet_bar+0x0/0x80 @ 1
[ 0.278239] pci 0000:00:14.0: sb600_disable_hpet_bar+0x0/0x80 took 0 usecs
[ 0.278331] pci 0000:00:14.0: calling force_disable_hpet_msi+0x0/0x20 @ 1
[ 0.278336] pci 0000:00:14.0: force_disable_hpet_msi+0x0/0x20 took 0 usecs
[ 0.278341] pci 0000:00:14.0: calling sb600_hpet_quirk+0x0/0x80 @ 1
[ 0.278364] pci 0000:00:14.0: sb600_hpet_quirk+0x0/0x80 took 0 usecs
[ 0.278651] pci 0000:00:14.2: [1002:4383] type 00 class 0x040300
[ 0.278685] pci 0000:00:14.2: reg 0x10: [mem 0xf0144000-0xf0147fff 64bit]
[ 0.278781] pci 0000:00:14.2: PME# supported from D0 D3hot D3cold
[ 0.278783] pci 0000:00:14.2: pme_poll = true
[ 0.278785] pci 0000:00:14.2: after device_set_wakeup_capable()
[ 0.278790] pci 0000:00:14.2: after pci_pme_active()
[ 0.279078] pci 0000:00:14.3: [1002:439d] type 00 class 0x060100
[ 0.279463] pci 0000:00:14.4: [1002:4384] type 01 class 0x060401
[ 0.279813] pci 0000:00:14.5: [1002:4399] type 00 class 0x0c0310
[ 0.279838] pci 0000:00:14.5: reg 0x10: [mem 0xf014a000-0xf014afff]
[ 0.280211] pci 0000:00:15.0: [1002:43a0] type 01 class 0x060400
[ 0.280263] pci 0000:00:15.0: enabling Extended Tags
[ 0.280334] pci 0000:00:15.0: supports D1 D2
[ 0.280659] pci 0000:00:15.1: [1002:43a1] type 01 class 0x060400
[ 0.280719] pci 0000:00:15.1: enabling Extended Tags
[ 0.280789] pci 0000:00:15.1: supports D1 D2
[ 0.281116] pci 0000:00:18.0: [1022:1700] type 00 class 0x060000
[ 0.281126] pci 0000:00:18.0: calling quirk_mmio_always_on+0x0/0x10 @ 1
[ 0.281130] pci 0000:00:18.0: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[ 0.281378] pci 0000:00:18.1: [1022:1701] type 00 class 0x060000
[ 0.281387] pci 0000:00:18.1: calling quirk_mmio_always_on+0x0/0x10 @ 1
[ 0.281392] pci 0000:00:18.1: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[ 0.281628] pci 0000:00:18.2: [1022:1702] type 00 class 0x060000
[ 0.281637] pci 0000:00:18.2: calling quirk_mmio_always_on+0x0/0x10 @ 1
[ 0.281642] pci 0000:00:18.2: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[ 0.281878] pci 0000:00:18.3: [1022:1703] type 00 class 0x060000
[ 0.281887] pci 0000:00:18.3: calling quirk_mmio_always_on+0x0/0x10 @ 1
[ 0.281891] pci 0000:00:18.3: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[ 0.282146] pci 0000:00:18.4: [1022:1704] type 00 class 0x060000
[ 0.282155] pci 0000:00:18.4: calling quirk_mmio_always_on+0x0/0x10 @ 1
[ 0.282160] pci 0000:00:18.4: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[ 0.282402] pci 0000:00:18.5: [1022:1718] type 00 class 0x060000
[ 0.282411] pci 0000:00:18.5: calling quirk_mmio_always_on+0x0/0x10 @ 1
[ 0.282416] pci 0000:00:18.5: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[ 0.282650] pci 0000:00:18.6: [1022:1716] type 00 class 0x060000
[ 0.282658] pci 0000:00:18.6: calling quirk_mmio_always_on+0x0/0x10 @ 1
[ 0.282663] pci 0000:00:18.6: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[ 0.282893] pci 0000:00:18.7: [1022:1719] type 00 class 0x060000
[ 0.282902] pci 0000:00:18.7: calling quirk_mmio_always_on+0x0/0x10 @ 1
[ 0.282907] pci 0000:00:18.7: quirk_mmio_always_on+0x0/0x10 took 0 usecs
[ 0.283235] pci_bus 0000:01: extended config space not accessible
[ 0.283414] pci 0000:00:14.4: PCI bridge to [bus 01] (subtractive decode)
[ 0.283428] pci 0000:00:14.4: bridge window [io 0x0000-0x0cf7 window] (subtractive decode)
[ 0.283431] pci 0000:00:14.4: bridge window [io 0x0d00-0xffff window] (subtractive decode)
[ 0.283434] pci 0000:00:14.4: bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
[ 0.283437] pci 0000:00:14.4: bridge window [mem 0xe0000000-0xffffffff] (subtractive decode)
[ 0.283520] pci 0000:00:15.0: PCI bridge to [bus 02]
[ 0.283633] pci 0000:03:00.0: [10ec:8168] type 00 class 0x020000
[ 0.283676] pci 0000:03:00.0: reg 0x10: [io 0x1000-0x10ff]
[ 0.283715] pci 0000:03:00.0: reg 0x18: [mem 0xf0004000-0xf0004fff 64bit pref]
[ 0.283740] pci 0000:03:00.0: reg 0x20: [mem 0xf0000000-0xf0003fff 64bit pref]
[ 0.283884] pci 0000:03:00.0: supports D1 D2
[ 0.283887] pci 0000:03:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.283889] pci 0000:03:00.0: pme_poll = true
[ 0.283891] pci 0000:03:00.0: after device_set_wakeup_capable()
[ 0.283898] pci 0000:03:00.0: after pci_pme_active()
[ 0.283953] pci 0000:03:00.0: 2.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s x1 link at 0000:00:15.1 (capable of 7.876 Gb/s with 8 GT/s x1 link)
[ 0.287454] pci 0000:00:15.1: PCI bridge to [bus 03]
[ 0.287468] pci 0000:00:15.1: bridge window [io 0x1000-0x1fff]
[ 0.287484] pci 0000:00:15.1: bridge window [mem 0xf0000000-0xf00fffff 64bit pref]
[ 0.287527] pci_bus 0000:00: on NUMA node 0
[ 0.288991] ACPI: PCI Interrupt Link [INTA] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[ 0.289321] ACPI: PCI Interrupt Link [INTB] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[ 0.289682] ACPI: PCI Interrupt Link [INTC] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[ 0.290007] ACPI: PCI Interrupt Link [INTD] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[ 0.290331] ACPI: PCI Interrupt Link [INTE] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[ 0.290670] ACPI: PCI Interrupt Link [INTF] (IRQs 9) *0, disabled.
[ 0.290990] ACPI: PCI Interrupt Link [INTG] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[ 0.291313] ACPI: PCI Interrupt Link [INTH] (IRQs 3 4 5 7 10 11 12 15) *0, disabled.
[ 0.292358] initcall acpi_init+0x0/0x2e2 returned 0 after 42968 usecs
[ 0.292358] calling pnp_init+0x0/0x14 @ 1
[ 0.292358] initcall pnp_init+0x0/0x14 returned 0 after 0 usecs
[ 0.292358] calling misc_init+0x0/0xae @ 1
[ 0.292358] initcall misc_init+0x0/0xae returned 0 after 0 usecs
[ 0.292358] calling vga_arb_device_init+0x0/0x231 @ 1
[ 0.292435] pci 0000:00:01.0: vgaarb: setting as boot VGA device
[ 0.292435] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[ 0.292435] pci 0000:00:01.0: vgaarb: bridge control possible
[ 0.292435] vgaarb: loaded
[ 0.292443] initcall vga_arb_device_init+0x0/0x231 returned 0 after 0 usecs
[ 0.292452] calling cn_init+0x0/0xd0 @ 1
[ 0.292490] initcall cn_init+0x0/0xd0 returned 0 after 0 usecs
[ 0.292495] calling dax_fs_init+0x0/0xab @ 1
[ 0.292560] initcall dax_fs_init+0x0/0xab returned 0 after 0 usecs
[ 0.292564] calling dma_buf_init+0x0/0x99 @ 1
[ 0.292582] initcall dma_buf_init+0x0/0x99 returned 0 after 0 usecs
[ 0.292586] calling init_scsi+0x0/0x90 @ 1
[ 0.292681] SCSI subsystem initialized
[ 0.292681] initcall init_scsi+0x0/0x90 returned 0 after 0 usecs
[ 0.292681] calling ata_init+0x0/0x2a7 @ 1
[ 0.293388] libata version 3.00 loaded.
[ 0.293393] initcall ata_init+0x0/0x2a7 returned 0 after 976 usecs
[ 0.293397] calling phy_init+0x0/0x4c @ 1
[ 0.293439] initcall phy_init+0x0/0x4c returned 0 after 0 usecs
[ 0.293443] calling usb_init+0x0/0x11b @ 1
[ 0.293452] ACPI: bus type USB registered
[ 0.293497] usbcore: registered new interface driver usbfs
[ 0.293516] usbcore: registered new interface driver hub
[ 0.293549] usbcore: registered new device driver usb
[ 0.293554] initcall usb_init+0x0/0x11b returned 0 after 0 usecs
[ 0.293558] calling serio_init+0x0/0x2d @ 1
[ 0.293579] initcall serio_init+0x0/0x2d returned 0 after 0 usecs
[ 0.293583] calling input_init+0x0/0xe3 @ 1
[ 0.293596] initcall input_init+0x0/0xe3 returned 0 after 0 usecs
[ 0.293601] calling rtc_init+0x0/0x47 @ 1
[ 0.293611] initcall rtc_init+0x0/0x47 returned 0 after 0 usecs
[ 0.293616] calling power_supply_class_init+0x0/0x3a @ 1
[ 0.293626] initcall power_supply_class_init+0x0/0x3a returned 0 after 0 usecs
[ 0.293630] calling hwmon_init+0x0/0xd5 @ 1
[ 0.293642] initcall hwmon_init+0x0/0xd5 returned 0 after 0 usecs
[ 0.293647] calling leds_init+0x0/0x37 @ 1
[ 0.293656] initcall leds_init+0x0/0x37 returned 0 after 0 usecs
[ 0.293661] calling dmi_init+0x0/0xf8 @ 1
[ 0.293689] initcall dmi_init+0x0/0xf8 returned 0 after 0 usecs
[ 0.293693] calling devfreq_init+0x0/0x90 @ 1
[ 0.293753] initcall devfreq_init+0x0/0x90 returned 0 after 0 usecs
[ 0.293753] calling ras_init+0x0/0x14 @ 1
[ 0.293753] initcall ras_init+0x0/0x14 returned 0 after 0 usecs
[ 0.293753] calling nvmem_init+0x0/0x14 @ 1
[ 0.293753] initcall nvmem_init+0x0/0x14 returned 0 after 0 usecs
[ 0.293753] calling pci_subsys_init+0x0/0x65 @ 1
[ 0.293753] PCI: Using ACPI for IRQ routing
[ 0.293753] PCI: pci_cache_line_size set to 64 bytes
[ 0.293753] e820: reserve RAM buffer [mem 0xc7d3c000-0xc7ffffff]
[ 0.293753] e820: reserve RAM buffer [mem 0x11f000000-0x11fffffff]
[ 0.293753] initcall pci_subsys_init+0x0/0x65 returned 0 after 0 usecs
[ 0.293753] calling proto_init+0x0/0x14 @ 1
[ 0.293753] initcall proto_init+0x0/0x14 returned 0 after 0 usecs
[ 0.293753] calling net_dev_init+0x0/0x1e9 @ 1
[ 0.293753] initcall net_dev_init+0x0/0x1e9 returned 0 after 0 usecs
[ 0.293753] calling neigh_init+0x0/0x78 @ 1
[ 0.293753] initcall neigh_init+0x0/0x78 returned 0 after 0 usecs
[ 0.293753] calling fib_notifier_init+0x0/0x14 @ 1
[ 0.293753] initcall fib_notifier_init+0x0/0x14 returned 0 after 0 usecs
[ 0.293753] calling fib_rules_init+0x0/0xa6 @ 1
[ 0.293753] initcall fib_rules_init+0x0/0xa6 returned 0 after 0 usecs
[ 0.293753] calling init_cgroup_netprio+0x0/0x16 @ 1
[ 0.293753] initcall init_cgroup_netprio+0x0/0x16 returned 0 after 0 usecs
[ 0.293753] calling bpf_lwt_init+0x0/0x19 @ 1
[ 0.293753] initcall bpf_lwt_init+0x0/0x19 returned 0 after 0 usecs
[ 0.293753] calling pktsched_init+0x0/0xf6 @ 1
[ 0.293753] initcall pktsched_init+0x0/0xf6 returned 0 after 0 usecs
[ 0.293753] calling tc_filter_init+0x0/0xe3 @ 1
[ 0.294360] initcall tc_filter_init+0x0/0xe3 returned 0 after 0 usecs
[ 0.294360] calling tc_action_init+0x0/0x65 @ 1
[ 0.294360] initcall tc_action_init+0x0/0x65 returned 0 after 0 usecs
[ 0.294360] calling genl_init+0x0/0x33 @ 1
[ 0.294360] initcall genl_init+0x0/0x33 returned 0 after 0 usecs
[ 0.294360] calling nmi_warning_debugfs+0x0/0x26 @ 1
[ 0.294360] initcall nmi_warning_debugfs+0x0/0x26 returned 0 after 0 usecs
[ 0.294360] calling hpet_late_init+0x0/0xf6 @ 1
[ 0.294360] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[ 0.294360] hpet0: 3 comparators, 32-bit 14.318180 MHz counter
[ 0.296361] initcall hpet_late_init+0x0/0xf6 returned 0 after 1953 usecs
[ 0.296365] calling init_amd_nbs+0x0/0x103 @ 1
[ 0.296404] initcall init_amd_nbs+0x0/0x103 returned 0 after 0 usecs
[ 0.296408] calling sugov_register+0x0/0x14 @ 1
[ 0.296412] initcall sugov_register+0x0/0x14 returned 0 after 0 usecs
[ 0.296417] calling clocksource_done_booting+0x0/0x40 @ 1
[ 0.296442] clocksource: Switched to clocksource tsc-early
[ 0.296447] initcall clocksource_done_booting+0x0/0x40 returned 0 after 10 usecs
[ 0.296451] calling tracer_init_tracefs+0x0/0x178 @ 1
[ 0.322720] initcall tracer_init_tracefs+0x0/0x178 returned 0 after 25717 usecs
[ 0.322737] calling init_trace_printk_function_export+0x0/0x2d @ 1
[ 0.322745] initcall init_trace_printk_function_export+0x0/0x2d returned 0 after 3 usecs
[ 0.322748] calling init_graph_tracefs+0x0/0x2d @ 1
[ 0.322754] initcall init_graph_tracefs+0x0/0x2d returned 0 after 2 usecs
[ 0.322758] calling init_kprobe_trace+0x0/0x83 @ 1
[ 0.322766] initcall init_kprobe_trace+0x0/0x83 returned 0 after 4 usecs
[ 0.322770] calling init_uprobe_trace+0x0/0x4c @ 1
[ 0.322777] initcall init_uprobe_trace+0x0/0x4c returned 0 after 4 usecs
[ 0.322781] calling bpf_init+0x0/0x42 @ 1
[ 0.322797] initcall bpf_init+0x0/0x42 returned 0 after 13 usecs
[ 0.322802] calling init_pipe_fs+0x0/0x42 @ 1
[ 0.322835] initcall init_pipe_fs+0x0/0x42 returned 0 after 28 usecs
[ 0.322839] calling cgroup_writeback_init+0x0/0x2c @ 1
[ 0.322886] initcall cgroup_writeback_init+0x0/0x2c returned 0 after 41 usecs
[ 0.322890] calling inotify_user_setup+0x0/0x4a @ 1
[ 0.322918] initcall inotify_user_setup+0x0/0x4a returned 0 after 23 usecs
[ 0.322922] calling eventpoll_init+0x0/0xab @ 1
[ 0.322941] initcall eventpoll_init+0x0/0xab returned 0 after 15 usecs
[ 0.322945] calling anon_inode_init+0x0/0x4e @ 1
[ 0.322961] initcall anon_inode_init+0x0/0x4e returned 0 after 12 usecs
[ 0.322966] calling init_dax_wait_table+0x0/0x2f @ 1
[ 0.323003] initcall init_dax_wait_table+0x0/0x2f returned 0 after 33 usecs
[ 0.323007] calling proc_locks_init+0x0/0x23 @ 1
[ 0.323016] initcall proc_locks_init+0x0/0x23 returned 0 after 5 usecs
[ 0.323020] calling dquot_init+0x0/0xfa @ 1
[ 0.323022] VFS: Disk quotas dquot_6.6.0
[ 0.323059] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.323063] initcall dquot_init+0x0/0xfa returned 0 after 38 usecs
[ 0.323067] calling quota_init+0x0/0x25 @ 1
[ 0.323091] initcall quota_init+0x0/0x25 returned 0 after 20 usecs
[ 0.323095] calling proc_cmdline_init+0x0/0x21 @ 1
[ 0.323101] initcall proc_cmdline_init+0x0/0x21 returned 0 after 2 usecs
[ 0.323105] calling proc_consoles_init+0x0/0x23 @ 1
[ 0.323110] initcall proc_consoles_init+0x0/0x23 returned 0 after 2 usecs
[ 0.323114] calling proc_cpuinfo_init+0x0/0x1f @ 1
[ 0.323119] initcall proc_cpuinfo_init+0x0/0x1f returned 0 after 1 usecs
[ 0.323123] calling proc_devices_init+0x0/0x23 @ 1
[ 0.323128] initcall proc_devices_init+0x0/0x23 returned 0 after 2 usecs
[ 0.323132] calling proc_interrupts_init+0x0/0x23 @ 1
[ 0.323137] initcall proc_interrupts_init+0x0/0x23 returned 0 after 1 usecs
[ 0.323141] calling proc_loadavg_init+0x0/0x21 @ 1
[ 0.323149] initcall proc_loadavg_init+0x0/0x21 returned 0 after 5 usecs
[ 0.323153] calling proc_meminfo_init+0x0/0x21 @ 1
[ 0.323158] initcall proc_meminfo_init+0x0/0x21 returned 0 after 2 usecs
[ 0.323162] calling proc_stat_init+0x0/0x1f @ 1
[ 0.323167] initcall proc_stat_init+0x0/0x1f returned 0 after 1 usecs
[ 0.323171] calling proc_uptime_init+0x0/0x21 @ 1
[ 0.323175] initcall proc_uptime_init+0x0/0x21 returned 0 after 1 usecs
[ 0.323179] calling proc_version_init+0x0/0x21 @ 1
[ 0.323184] initcall proc_version_init+0x0/0x21 returned 0 after 1 usecs
[ 0.323188] calling proc_softirqs_init+0x0/0x21 @ 1
[ 0.323192] initcall proc_softirqs_init+0x0/0x21 returned 0 after 1 usecs
[ 0.323196] calling proc_kcore_init+0x0/0x6e @ 1
[ 0.323203] initcall proc_kcore_init+0x0/0x6e returned 0 after 3 usecs
[ 0.323207] calling vmcore_init+0x0/0x679 @ 1
[ 0.323211] initcall vmcore_init+0x0/0x679 returned 0 after 0 usecs
[ 0.323215] calling proc_kmsg_init+0x0/0x22 @ 1
[ 0.323219] initcall proc_kmsg_init+0x0/0x22 returned 0 after 1 usecs
[ 0.323223] calling proc_page_init+0x0/0x4e @ 1
[ 0.323230] initcall proc_page_init+0x0/0x4e returned 0 after 3 usecs
[ 0.323235] calling init_ramfs_fs+0x0/0x23 @ 1
[ 0.323239] initcall init_ramfs_fs+0x0/0x23 returned 0 after 0 usecs
[ 0.323244] calling init_hugetlbfs_fs+0x0/0x142 @ 1
[ 0.323290] initcall init_hugetlbfs_fs+0x0/0x142 returned 0 after 40 usecs
[ 0.323295] calling blk_scsi_ioctl_init+0x0/0x352 @ 1
[ 0.323299] initcall blk_scsi_ioctl_init+0x0/0x352 returned 0 after 1 usecs
[ 0.323304] calling dynamic_debug_init_debugfs+0x0/0x5f @ 1
[ 0.323326] initcall dynamic_debug_init_debugfs+0x0/0x5f returned 0 after 17 usecs
[ 0.323332] calling acpi_event_init+0x0/0x31 @ 1
[ 0.323344] initcall acpi_event_init+0x0/0x31 returned 0 after 8 usecs
[ 0.323347] calling pnp_system_init+0x0/0x14 @ 1
[ 0.323375] initcall pnp_system_init+0x0/0x14 returned 0 after 24 usecs
[ 0.323379] calling pnpacpi_init+0x0/0x67 @ 1
[ 0.323380] pnp: PnP ACPI init
[ 0.324200] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 0.324304] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 PNP030b (active)
[ 0.324386] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[ 0.325066] pnp 00:03: Plug and Play ACPI device, IDs PNP0303 PNP030b (active)
[ 0.325144] pnp 00:04: Plug and Play ACPI device, IDs PNP0f13 (active)
[ 0.325175] pnp: PnP ACPI: found 5 devices
[ 0.325180] initcall pnpacpi_init+0x0/0x67 returned 0 after 1755 usecs
[ 0.325185] calling chr_dev_init+0x0/0x9b @ 1
[ 0.330926] initcall chr_dev_init+0x0/0x9b returned 0 after 5594 usecs
[ 0.330936] calling firmware_class_init+0x0/0xe0 @ 1
[ 0.330942] initcall firmware_class_init+0x0/0xe0 returned 0 after 2 usecs
[ 0.330947] calling thermal_init+0x0/0xce @ 1
[ 0.330987] initcall thermal_init+0x0/0xce returned 0 after 34 usecs
[ 0.330992] calling cpufreq_gov_performance_init+0x0/0x14 @ 1
[ 0.330997] initcall cpufreq_gov_performance_init+0x0/0x14 returned 0 after 1 usecs
[ 0.331002] calling cpufreq_gov_dbs_init+0x0/0x14 @ 1
[ 0.331006] initcall cpufreq_gov_dbs_init+0x0/0x14 returned 0 after 0 usecs
[ 0.331010] calling pcibios_assign_resources+0x0/0x96 @ 1
[ 0.331129] pci 0000:00:14.4: PCI bridge to [bus 01]
[ 0.331149] pci 0000:00:15.0: PCI bridge to [bus 02]
[ 0.331164] pci 0000:00:15.1: PCI bridge to [bus 03]
[ 0.331169] pci 0000:00:15.1: bridge window [io 0x1000-0x1fff]
[ 0.331179] pci 0000:00:15.1: bridge window [mem 0xf0000000-0xf00fffff 64bit pref]
[ 0.331193] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
[ 0.331196] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
[ 0.331199] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff]
[ 0.331202] pci_bus 0000:00: resource 7 [mem 0xe0000000-0xffffffff]
[ 0.331206] pci_bus 0000:01: resource 4 [io 0x0000-0x0cf7 window]
[ 0.331209] pci_bus 0000:01: resource 5 [io 0x0d00-0xffff window]
[ 0.331212] pci_bus 0000:01: resource 6 [mem 0x000a0000-0x000bffff]
[ 0.331215] pci_bus 0000:01: resource 7 [mem 0xe0000000-0xffffffff]
[ 0.331218] pci_bus 0000:03: resource 0 [io 0x1000-0x1fff]
[ 0.331221] pci_bus 0000:03: resource 2 [mem 0xf0000000-0xf00fffff 64bit pref]
[ 0.331311] initcall pcibios_assign_resources+0x0/0x96 returned 0 after 290 usecs
[ 0.331315] calling sysctl_core_init+0x0/0x28 @ 1
[ 0.331345] initcall sysctl_core_init+0x0/0x28 returned 0 after 25 usecs
[ 0.331350] calling eth_offload_init+0x0/0x16 @ 1
[ 0.331354] initcall eth_offload_init+0x0/0x16 returned 0 after 0 usecs
[ 0.331358] calling ipv4_offload_init+0x0/0x6e @ 1
[ 0.331362] initcall ipv4_offload_init+0x0/0x6e returned 0 after 1 usecs
[ 0.331366] calling inet_init+0x0/0x240 @ 1
[ 0.331419] NET: Registered protocol family 2
[ 0.331771] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[ 0.331780] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.331808] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.331844] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.331905] UDP hash table entries: 512 (order: 2, 16384 bytes)
[ 0.331917] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[ 0.332011] initcall inet_init+0x0/0x240 returned 0 after 621 usecs
[ 0.332016] calling af_unix_init+0x0/0x4c @ 1
[ 0.332031] NET: Registered protocol family 1
[ 0.332047] initcall af_unix_init+0x0/0x4c returned 0 after 25 usecs
[ 0.332052] calling ipv6_offload_init+0x0/0x77 @ 1
[ 0.332057] initcall ipv6_offload_init+0x0/0x77 returned 0 after 1 usecs
[ 0.332063] calling pci_apply_final_quirks+0x0/0x118 @ 1
[ 0.332082] pci 0000:00:01.0: calling pci_fixup_video+0x0/0x110 @ 1
[ 0.332091] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 0.332096] pci 0000:00:01.0: pci_fixup_video+0x0/0x110 took 8 usecs
[ 0.332105] pci 0000:00:01.1: calling quirk_gpu_hda+0x0/0xb0 @ 1
[ 0.332113] pci 0000:00:01.1: Linked as a consumer to 0000:00:01.0
[ 0.332128] pci 0000:00:01.1: quirk_gpu_hda+0x0/0xb0 took 18 usecs
[ 0.332136] pci 0000:00:11.0: calling quirk_msi_intx_disable_ati_bug+0x0/0x50 @ 1
[ 0.332143] pci 0000:00:11.0: quirk_msi_intx_disable_ati_bug+0x0/0x50 took 2 usecs
[ 0.332223] pci 0000:00:12.0: calling quirk_usb_early_handoff+0x0/0x6d5 @ 1
[ 0.332955] pci 0000:00:12.0: quirk_usb_early_handoff+0x0/0x6d5 took 704 usecs
[ 0.333035] pci 0000:00:12.2: calling quirk_usb_early_handoff+0x0/0x6d5 @ 1
[ 0.333522] pci 0000:00:12.2: quirk_usb_early_handoff+0x0/0x6d5 took 468 usecs
[ 0.333600] pci 0000:00:13.0: calling quirk_usb_early_handoff+0x0/0x6d5 @ 1
[ 0.334092] pci 0000:00:13.0: quirk_usb_early_handoff+0x0/0x6d5 took 473 usecs
[ 0.334170] pci 0000:00:13.2: calling quirk_usb_early_handoff+0x0/0x6d5 @ 1
[ 0.334651] pci 0000:00:13.2: quirk_usb_early_handoff+0x0/0x6d5 took 463 usecs
[ 0.334742] pci 0000:00:14.2: calling quirk_gpu_hda+0x0/0xb0 @ 1
[ 0.334746] pci 0000:00:14.2: quirk_gpu_hda+0x0/0xb0 took 0 usecs
[ 0.334763] pci 0000:00:14.5: calling quirk_usb_early_handoff+0x0/0x6d5 @ 1
[ 0.335243] pci 0000:00:14.5: quirk_usb_early_handoff+0x0/0x6d5 took 462 usecs
[ 0.335360] PCI: CLS 64 bytes, default 64
[ 0.335364] initcall pci_apply_final_quirks+0x0/0x118 returned 0 after 3220 usecs
[ 0.335370] calling acpi_reserve_resources+0x0/0xcd @ 1
[ 0.335379] initcall acpi_reserve_resources+0x0/0xcd returned 0 after 5 usecs
[ 0.335383] calling populate_rootfs+0x0/0xf9 @ 1
[ 0.335494] initcall populate_rootfs+0x0/0xf9 returned 0 after 104 usecs
[ 0.335500] calling pci_iommu_init+0x0/0x3a @ 1
[ 0.335504] initcall pci_iommu_init+0x0/0x3a returned 0 after 1 usecs
[ 0.335708] calling amd_uncore_init+0x0/0x255 @ 1
[ 0.335712] initcall amd_uncore_init+0x0/0x255 returned -19 after 0 usecs
[ 0.335715] calling amd_ibs_init+0x0/0x196 @ 1
[ 0.335730] LVT offset 0 assigned for vector 0x400
[ 0.335791] perf: AMD IBS detected (0x000000ff)
[ 0.335795] initcall amd_ibs_init+0x0/0x196 returned 0 after 74 usecs
[ 0.335798] calling msr_init+0x0/0xb9 @ 1
[ 0.335805] initcall msr_init+0x0/0xb9 returned 0 after 3 usecs
[ 0.335809] calling register_kernel_offset_dumper+0x0/0x1b @ 1
[ 0.335814] initcall register_kernel_offset_dumper+0x0/0x1b returned 0 after 1 usecs
[ 0.335818] calling i8259A_init_ops+0x0/0x25 @ 1
[ 0.335822] initcall i8259A_init_ops+0x0/0x25 returned 0 after 0 usecs
[ 0.335826] calling init_tsc_clocksource+0x0/0xa4 @ 1
[ 0.335834] initcall init_tsc_clocksource+0x0/0xa4 returned 0 after 4 usecs
[ 0.335839] calling add_rtc_cmos+0x0/0xa6 @ 1
[ 0.335844] initcall add_rtc_cmos+0x0/0xa6 returned 0 after 1 usecs
[ 0.335849] calling i8237A_init_ops+0x0/0x3c @ 1
[ 0.335934] initcall i8237A_init_ops+0x0/0x3c returned 0 after 79 usecs
[ 0.335938] calling msr_init+0x0/0xc9 @ 1
[ 0.336251] initcall msr_init+0x0/0xc9 returned 0 after 292 usecs
[ 0.336260] calling cpuid_init+0x0/0xc9 @ 1
[ 0.336506] initcall cpuid_init+0x0/0xc9 returned 0 after 230 usecs
[ 0.336513] calling ioapic_init_ops+0x0/0x16 @ 1
[ 0.336518] initcall ioapic_init_ops+0x0/0x16 returned 0 after 0 usecs
[ 0.336522] calling add_pcspkr+0x0/0x61 @ 1
[ 0.336588] initcall add_pcspkr+0x0/0x61 returned 0 after 59 usecs
[ 0.336594] calling start_periodic_check_for_corruption+0x0/0x40 @ 1
[ 0.336598] initcall start_periodic_check_for_corruption+0x0/0x40 returned 0 after 0 usecs
[ 0.336602] calling sysfb_init+0x0/0x83 @ 1
[ 0.336644] initcall sysfb_init+0x0/0x83 returned 0 after 36 usecs
[ 0.336650] calling pt_dump_init+0x0/0x57 @ 1
[ 0.336654] initcall pt_dump_init+0x0/0x57 returned 0 after 0 usecs
[ 0.336658] calling pt_dump_debug_init+0x0/0x9b @ 1
[ 0.336679] initcall pt_dump_debug_init+0x0/0x9b returned 0 after 15 usecs
[ 0.336683] calling proc_execdomains_init+0x0/0x21 @ 1
[ 0.336694] initcall proc_execdomains_init+0x0/0x21 returned 0 after 7 usecs
[ 0.336699] calling register_warn_debugfs+0x0/0x24 @ 1
[ 0.336706] initcall register_warn_debugfs+0x0/0x24 returned 0 after 2 usecs
[ 0.336711] calling cpuhp_sysfs_init+0x0/0x79 @ 1
[ 0.336734] initcall cpuhp_sysfs_init+0x0/0x79 returned 0 after 18 usecs
[ 0.336737] calling ioresources_init+0x0/0x40 @ 1
[ 0.336744] initcall ioresources_init+0x0/0x40 returned 0 after 4 usecs
[ 0.336749] calling init_sched_debug_procfs+0x0/0x2f @ 1
[ 0.336754] initcall init_sched_debug_procfs+0x0/0x2f returned 0 after 1 usecs
[ 0.336757] calling irq_pm_init_ops+0x0/0x16 @ 1
[ 0.336761] initcall irq_pm_init_ops+0x0/0x16 returned 0 after 0 usecs
[ 0.336765] calling timekeeping_init_ops+0x0/0x16 @ 1
[ 0.336769] initcall timekeeping_init_ops+0x0/0x16 returned 0 after 0 usecs
[ 0.336773] calling init_clocksource_sysfs+0x0/0x24 @ 1
[ 0.336839] initcall init_clocksource_sysfs+0x0/0x24 returned 0 after 59 usecs
[ 0.336843] calling init_timer_list_procfs+0x0/0x2f @ 1
[ 0.336849] initcall init_timer_list_procfs+0x0/0x2f returned 0 after 1 usecs
[ 0.336853] calling alarmtimer_init+0x0/0x114 @ 1
[ 0.336956] probe of alarmtimer returned 1 after 17 usecs
[ 0.336962] initcall alarmtimer_init+0x0/0x114 returned 0 after 102 usecs
[ 0.336966] calling init_posix_timers+0x0/0x29 @ 1
[ 0.337000] initcall init_posix_timers+0x0/0x29 returned 0 after 28 usecs
[ 0.337004] calling clockevents_init_sysfs+0x0/0x9e @ 1
[ 0.337106] initcall clockevents_init_sysfs+0x0/0x9e returned 0 after 94 usecs
[ 0.337110] calling proc_dma_init+0x0/0x21 @ 1
[ 0.337116] initcall proc_dma_init+0x0/0x21 returned 0 after 2 usecs
[ 0.337120] calling proc_modules_init+0x0/0x1f @ 1
[ 0.337126] initcall proc_modules_init+0x0/0x1f returned 0 after 2 usecs
[ 0.337130] calling kallsyms_init+0x0/0x22 @ 1
[ 0.337135] initcall kallsyms_init+0x0/0x22 returned 0 after 1 usecs
[ 0.337140] calling pid_namespaces_init+0x0/0x2c @ 1
[ 0.337147] initcall pid_namespaces_init+0x0/0x2c returned 0 after 3 usecs
[ 0.337152] calling audit_watch_init+0x0/0x38 @ 1
[ 0.337156] initcall audit_watch_init+0x0/0x38 returned 0 after 1 usecs
[ 0.337161] calling audit_fsnotify_init+0x0/0x38 @ 1
[ 0.337165] initcall audit_fsnotify_init+0x0/0x38 returned 0 after 0 usecs
[ 0.337170] calling audit_tree_init+0x0/0x40 @ 1
[ 0.337175] initcall audit_tree_init+0x0/0x40 returned 0 after 1 usecs
[ 0.337179] calling init_kprobes+0x0/0x172 @ 1
[ 0.337324] initcall init_kprobes+0x0/0x172 returned 0 after 137 usecs
[ 0.337328] calling seccomp_sysctl_init+0x0/0x31 @ 1
[ 0.337343] initcall seccomp_sysctl_init+0x0/0x31 returned 0 after 10 usecs
[ 0.337348] calling utsname_sysctl_init+0x0/0x16 @ 1
[ 0.337360] initcall utsname_sysctl_init+0x0/0x16 returned 0 after 8 usecs
[ 0.337365] calling init_tracepoints+0x0/0x2b @ 1
[ 0.337369] initcall init_tracepoints+0x0/0x2b returned 0 after 0 usecs
[ 0.337372] calling stack_trace_init+0x0/0x9e @ 1
[ 0.337387] initcall stack_trace_init+0x0/0x9e returned 0 after 11 usecs
[ 0.337390] calling init_mmio_trace+0x0/0xf @ 1
[ 0.337396] initcall init_mmio_trace+0x0/0xf returned 0 after 3 usecs
[ 0.337399] calling init_blk_tracer+0x0/0x4d @ 1
[ 0.337412] initcall init_blk_tracer+0x0/0x4d returned 0 after 9 usecs
[ 0.337416] calling perf_event_sysfs_init+0x0/0x78 @ 1
[ 0.337680] initcall perf_event_sysfs_init+0x0/0x78 returned 0 after 254 usecs
[ 0.337684] calling init_uprobes+0x0/0x56 @ 1
[ 0.337691] initcall init_uprobes+0x0/0x56 returned 0 after 4 usecs
[ 0.337695] calling padata_driver_init+0x0/0x35 @ 1
[ 0.337699] initcall padata_driver_init+0x0/0x35 returned 0 after 1 usecs
[ 0.337703] calling system_trusted_keyring_init+0x0/0x55 @ 1
[ 0.337705] Initialise system trusted keyrings
[ 0.337718] initcall system_trusted_keyring_init+0x0/0x55 returned 0 after 11 usecs
[ 0.337723] calling kswapd_init+0x0/0x3b @ 1
[ 0.337897] initcall kswapd_init+0x0/0x3b returned 0 after 163 usecs
[ 0.337902] calling extfrag_debug_init+0x0/0x71 @ 1
[ 0.337919] initcall extfrag_debug_init+0x0/0x71 returned 0 after 13 usecs
[ 0.337924] calling mm_compute_batch_init+0x0/0x3e @ 1
[ 0.337928] initcall mm_compute_batch_init+0x0/0x3e returned 0 after 0 usecs
[ 0.337933] calling slab_proc_init+0x0/0x22 @ 1
[ 0.337950] initcall slab_proc_init+0x0/0x22 returned 0 after 13 usecs
[ 0.337955] calling workingset_init+0x0/0x88 @ 1
[ 0.337957] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[ 0.337966] initcall workingset_init+0x0/0x88 returned 0 after 7 usecs
[ 0.337971] calling proc_vmalloc_init+0x0/0x26 @ 1
[ 0.337976] initcall proc_vmalloc_init+0x0/0x26 returned 0 after 2 usecs
[ 0.337981] calling procswaps_init+0x0/0x1f @ 1
[ 0.337987] initcall procswaps_init+0x0/0x1f returned 0 after 2 usecs
[ 0.337992] calling init_frontswap+0x0/0x82 @ 1
[ 0.338008] initcall init_frontswap+0x0/0x82 returned 0 after 12 usecs
[ 0.338011] calling slab_sysfs_init+0x0/0xd0 @ 1
[ 0.341227] initcall slab_sysfs_init+0x0/0xd0 returned 0 after 3133 usecs
[ 0.341233] calling init_cleancache+0x0/0x82 @ 1
[ 0.341257] initcall init_cleancache+0x0/0x82 returned 0 after 20 usecs
[ 0.341261] calling init_zbud+0x0/0x20 @ 1
[ 0.341262] zbud: loaded
[ 0.341265] initcall init_zbud+0x0/0x20 returned 0 after 1 usecs
[ 0.341268] calling zs_init+0x0/0x6d @ 1
[ 0.341298] initcall zs_init+0x0/0x6d returned 0 after 26 usecs
[ 0.341301] calling init_z3fold+0x0/0x16 @ 1
[ 0.341304] initcall init_z3fold+0x0/0x16 returned 0 after 0 usecs
[ 0.341307] calling fcntl_init+0x0/0x29 @ 1
[ 0.341316] initcall fcntl_init+0x0/0x29 returned 0 after 5 usecs
[ 0.341320] calling proc_filesystems_init+0x0/0x21 @ 1
[ 0.341327] initcall proc_filesystems_init+0x0/0x21 returned 0 after 3 usecs
[ 0.341331] calling start_dirtytime_writeback+0x0/0x2c @ 1
[ 0.341337] initcall start_dirtytime_writeback+0x0/0x2c returned 0 after 2 usecs
[ 0.341341] calling blkdev_init+0x0/0x20 @ 1
[ 0.341358] initcall blkdev_init+0x0/0x20 returned 0 after 13 usecs
[ 0.341362] calling dio_init+0x0/0x2c @ 1
[ 0.341421] initcall dio_init+0x0/0x2c returned 0 after 54 usecs
[ 0.341425] calling dnotify_init+0x0/0x6f @ 1
[ 0.341438] initcall dnotify_init+0x0/0x6f returned 0 after 9 usecs
[ 0.341441] calling fanotify_user_setup+0x0/0x6c @ 1
[ 0.341500] initcall fanotify_user_setup+0x0/0x6c returned 0 after 53 usecs
[ 0.341504] calling userfaultfd_init+0x0/0x2c @ 1
[ 0.341551] initcall userfaultfd_init+0x0/0x2c returned 0 after 41 usecs
[ 0.341554] calling aio_setup+0x0/0x6e @ 1
[ 0.341577] initcall aio_setup+0x0/0x6e returned 0 after 18 usecs
[ 0.341581] calling mbcache_init+0x0/0x31 @ 1
[ 0.341637] initcall mbcache_init+0x0/0x31 returned 0 after 51 usecs
[ 0.341641] calling init_v2_quota_format+0x0/0x22 @ 1
[ 0.341645] initcall init_v2_quota_format+0x0/0x22 returned 0 after 0 usecs
[ 0.341650] calling init_devpts_fs+0x0/0x28 @ 1
[ 0.341670] initcall init_devpts_fs+0x0/0x28 returned 0 after 16 usecs
[ 0.341676] calling ext4_init_fs+0x0/0x166 @ 1
[ 0.341903] initcall ext4_init_fs+0x0/0x166 returned 0 after 217 usecs
[ 0.341909] calling journal_init+0x0/0xfc @ 1
[ 0.342033] initcall journal_init+0x0/0xfc returned 0 after 116 usecs
[ 0.342038] calling init_nls_cp437+0x0/0x16 @ 1
[ 0.342042] initcall init_nls_cp437+0x0/0x16 returned 0 after 0 usecs
[ 0.342047] calling init_nls_ascii+0x0/0x16 @ 1
[ 0.342050] initcall init_nls_ascii+0x0/0x16 returned 0 after 0 usecs
[ 0.342055] calling init_autofs_fs+0x0/0x29 @ 1
[ 0.342190] initcall init_autofs_fs+0x0/0x29 returned 0 after 127 usecs
[ 0.342194] calling init_pstore_fs+0x0/0x47 @ 1
[ 0.342197] pstore: using deflate compression
[ 0.342204] initcall init_pstore_fs+0x0/0x47 returned 0 after 6 usecs
[ 0.342206] calling ipc_init+0x0/0x27 @ 1
[ 0.342224] initcall ipc_init+0x0/0x27 returned 0 after 14 usecs
[ 0.342227] calling ipc_sysctl_init+0x0/0x16 @ 1
[ 0.342241] initcall ipc_sysctl_init+0x0/0x16 returned 0 after 10 usecs
[ 0.342244] calling init_mqueue_fs+0x0/0xee @ 1
[ 0.342326] initcall init_mqueue_fs+0x0/0xee returned 0 after 75 usecs
[ 0.342329] calling key_proc_init+0x0/0x5c @ 1
[ 0.342335] initcall key_proc_init+0x0/0x5c returned 0 after 3 usecs
[ 0.342339] calling selinux_nf_ip_init+0x0/0x49 @ 1
[ 0.342342] initcall selinux_nf_ip_init+0x0/0x49 returned 0 after 0 usecs
[ 0.342345] calling init_sel_fs+0x0/0xfa @ 1
[ 0.342348] initcall init_sel_fs+0x0/0xfa returned 0 after 0 usecs
[ 0.342351] calling selnl_init+0x0/0x6e @ 1
[ 0.342367] initcall selnl_init+0x0/0x6e returned 0 after 12 usecs
[ 0.342370] calling sel_netif_init+0x0/0x36 @ 1
[ 0.342373] initcall sel_netif_init+0x0/0x36 returned 0 after 0 usecs
[ 0.342376] calling sel_netnode_init+0x0/0x30 @ 1
[ 0.342379] initcall sel_netnode_init+0x0/0x30 returned 0 after 0 usecs
[ 0.342383] calling sel_netport_init+0x0/0x30 @ 1
[ 0.342386] initcall sel_netport_init+0x0/0x30 returned 0 after 0 usecs
[ 0.342389] calling aurule_init+0x0/0x2a @ 1
[ 0.342392] initcall aurule_init+0x0/0x2a returned 0 after 0 usecs
[ 0.342396] calling crypto_algapi_init+0x0/0x11 @ 1
[ 0.342401] initcall crypto_algapi_init+0x0/0x11 returned 0 after 1 usecs
[ 0.342404] calling seqiv_module_init+0x0/0x14 @ 1
[ 0.342407] initcall seqiv_module_init+0x0/0x14 returned 0 after 0 usecs
[ 0.342413] calling dh_init+0x0/0x20 @ 1
[ 0.342621] alg: self-tests disabled
[ 0.342654] initcall dh_init+0x0/0x20 returned 0 after 217 usecs
[ 0.342665] calling rsa_init+0x0/0x40 @ 1
[ 0.342769] initcall rsa_init+0x0/0x40 returned 0 after 95 usecs
[ 0.342774] calling hmac_module_init+0x0/0x14 @ 1
[ 0.342778] initcall hmac_module_init+0x0/0x14 returned 0 after 0 usecs
[ 0.342781] calling crypto_null_mod_init+0x0/0x46 @ 1
[ 0.343085] initcall crypto_null_mod_init+0x0/0x46 returned 0 after 285 usecs
[ 0.343092] calling md5_mod_init+0x0/0x14 @ 1
[ 0.343161] initcall md5_mod_init+0x0/0x14 returned 0 after 62 usecs
[ 0.343165] calling sha1_generic_mod_init+0x0/0x14 @ 1
[ 0.343235] initcall sha1_generic_mod_init+0x0/0x14 returned 0 after 63 usecs
[ 0.343241] calling sha256_generic_mod_init+0x0/0x19 @ 1
[ 0.343369] initcall sha256_generic_mod_init+0x0/0x19 returned 0 after 120 usecs
[ 0.343373] calling crypto_ecb_module_init+0x0/0x14 @ 1
[ 0.343376] initcall crypto_ecb_module_init+0x0/0x14 returned 0 after 0 usecs
[ 0.343380] calling crypto_cbc_module_init+0x0/0x14 @ 1
[ 0.343383] initcall crypto_cbc_module_init+0x0/0x14 returned 0 after 0 usecs
[ 0.343387] calling crypto_module_init+0x0/0x14 @ 1
[ 0.343390] initcall crypto_module_init+0x0/0x14 returned 0 after 0 usecs
[ 0.343393] calling crypto_module_init+0x0/0x14 @ 1
[ 0.343396] initcall crypto_module_init+0x0/0x14 returned 0 after 0 usecs
[ 0.343399] calling crypto_ctr_module_init+0x0/0x38 @ 1
[ 0.343403] initcall crypto_ctr_module_init+0x0/0x38 returned 0 after 0 usecs
[ 0.343406] calling crypto_gcm_module_init+0x0/0xa7 @ 1
[ 0.343411] initcall crypto_gcm_module_init+0x0/0xa7 returned 0 after 1 usecs
[ 0.343414] calling aes_init+0x0/0x14 @ 1
[ 0.343488] initcall aes_init+0x0/0x14 returned 0 after 67 usecs
[ 0.343492] calling deflate_mod_init+0x0/0x3d @ 1
[ 0.343874] initcall deflate_mod_init+0x0/0x3d returned 0 after 355 usecs
[ 0.343916] calling crc32c_mod_init+0x0/0x14 @ 1
[ 0.344040] initcall crc32c_mod_init+0x0/0x14 returned 0 after 106 usecs
[ 0.344049] calling crct10dif_mod_init+0x0/0x14 @ 1
[ 0.344119] initcall crct10dif_mod_init+0x0/0x14 returned 0 after 63 usecs
[ 0.344123] calling lzo_mod_init+0x0/0x38 @ 1
[ 0.344251] initcall lzo_mod_init+0x0/0x38 returned 0 after 119 usecs
[ 0.344255] calling drbg_init+0x0/0x71 @ 1
[ 0.345964] initcall drbg_init+0x0/0x71 returned 0 after 1648 usecs
[ 0.345973] calling jent_mod_init+0x0/0x31 @ 1
[ 0.347020] initcall jent_mod_init+0x0/0x31 returned 0 after 1005 usecs
[ 0.347030] calling ghash_mod_init+0x0/0x14 @ 1
[ 0.347103] initcall ghash_mod_init+0x0/0x14 returned 0 after 66 usecs
[ 0.347107] calling af_alg_init+0x0/0x3a @ 1
[ 0.347111] NET: Registered protocol family 38
[ 0.347113] initcall af_alg_init+0x0/0x3a returned 0 after 3 usecs
[ 0.347117] calling algif_hash_init+0x0/0x14 @ 1
[ 0.347121] initcall algif_hash_init+0x0/0x14 returned 0 after 1 usecs
[ 0.347125] calling algif_skcipher_init+0x0/0x14 @ 1
[ 0.347129] initcall algif_skcipher_init+0x0/0x14 returned 0 after 0 usecs
[ 0.347132] calling rng_init+0x0/0x14 @ 1
[ 0.347135] initcall rng_init+0x0/0x14 returned 0 after 0 usecs
[ 0.347138] calling algif_aead_init+0x0/0x14 @ 1
[ 0.347141] initcall algif_aead_init+0x0/0x14 returned 0 after 0 usecs
[ 0.347145] calling asymmetric_key_init+0x0/0x14 @ 1
[ 0.347148] Key type asymmetric registered
[ 0.347151] initcall asymmetric_key_init+0x0/0x14 returned 0 after 3 usecs
[ 0.347154] calling x509_key_init+0x0/0x14 @ 1
[ 0.347156] Asymmetric key parser 'x509' registered
[ 0.347159] initcall x509_key_init+0x0/0x14 returned 0 after 2 usecs
[ 0.347163] calling proc_genhd_init+0x0/0x3a @ 1
[ 0.347175] initcall proc_genhd_init+0x0/0x3a returned 0 after 8 usecs
[ 0.347179] calling init_emergency_pool+0x0/0x9a @ 1
[ 0.347201] bounce: pool size: 64 pages
[ 0.347214] initcall init_emergency_pool+0x0/0x9a returned 0 after 31 usecs
[ 0.347218] calling bsg_init+0x0/0x12c @ 1
[ 0.347245] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[ 0.347248] initcall bsg_init+0x0/0x12c returned 0 after 26 usecs
[ 0.347252] calling throtl_init+0x0/0x3c @ 1
[ 0.347453] initcall throtl_init+0x0/0x3c returned 0 after 180 usecs
[ 0.347461] calling noop_init+0x0/0x14 @ 1
[ 0.347464] io scheduler noop registered
[ 0.347468] initcall noop_init+0x0/0x14 returned 0 after 3 usecs
[ 0.347472] calling deadline_init+0x0/0x14 @ 1
[ 0.347473] io scheduler deadline registered
[ 0.347476] initcall deadline_init+0x0/0x14 returned 0 after 1 usecs
[ 0.347480] calling cfq_init+0x0/0x6e @ 1
[ 0.347645] io scheduler cfq registered (default)
[ 0.347649] initcall cfq_init+0x0/0x6e returned 0 after 161 usecs
[ 0.347653] calling deadline_init+0x0/0x14 @ 1
[ 0.347655] io scheduler mq-deadline registered
[ 0.347658] initcall deadline_init+0x0/0x14 returned 0 after 1 usecs
[ 0.347662] calling kyber_init+0x0/0x14 @ 1
[ 0.347664] io scheduler kyber registered
[ 0.347667] initcall kyber_init+0x0/0x14 returned 0 after 2 usecs
[ 0.347671] calling crc_t10dif_mod_init+0x0/0x35 @ 1
[ 0.347682] initcall crc_t10dif_mod_init+0x0/0x35 returned 0 after 8 usecs
[ 0.347686] calling libcrc32c_mod_init+0x0/0x25 @ 1
[ 0.347692] initcall libcrc32c_mod_init+0x0/0x25 returned 0 after 2 usecs
[ 0.347696] calling percpu_counter_startup+0x0/0x50 @ 1
[ 0.347755] initcall percpu_counter_startup+0x0/0x50 returned 0 after 53 usecs
[ 0.347760] calling audit_classes_init+0x0/0x4f @ 1
[ 0.347768] initcall audit_classes_init+0x0/0x4f returned 0 after 5 usecs
[ 0.347772] calling sg_pool_init+0x0/0xab @ 1
[ 0.347811] initcall sg_pool_init+0x0/0xab returned 0 after 33 usecs
[ 0.347814] calling phy_core_init+0x0/0x44 @ 1
[ 0.347828] initcall phy_core_init+0x0/0x44 returned 0 after 9 usecs
[ 0.347832] calling pci_proc_init+0x0/0x63 @ 1
[ 0.347900] initcall pci_proc_init+0x0/0x63 returned 0 after 62 usecs
[ 0.347904] calling pcie_portdrv_init+0x0/0x34 @ 1
[ 0.348671] probe of 0000:00:15.0 returned 1 after 748 usecs
[ 0.349298] probe of 0000:00:15.1 returned 1 after 615 usecs
[ 0.349325] initcall pcie_portdrv_init+0x0/0x34 returned 0 after 1383 usecs
[ 0.349330] calling aer_service_init+0x0/0x33 @ 1
[ 0.349344] initcall aer_service_init+0x0/0x33 returned 0 after 10 usecs
[ 0.349349] calling pcie_pme_service_init+0x0/0x14 @ 1
[ 0.349466] pcieport 0000:00:15.0: Signaling PME with IRQ 24
[ 0.349478] probe of 0000:00:15.0:pcie001 returned 1 after 123 usecs
[ 0.349522] pcieport 0000:00:15.1: Signaling PME with IRQ 25
[ 0.349533] probe of 0000:00:15.1:pcie001 returned 1 after 41 usecs
[ 0.349545] initcall pcie_pme_service_init+0x0/0x14 returned 0 after 187 usecs
[ 0.349549] calling dpc_service_init+0x0/0x14 @ 1
[ 0.349562] initcall dpc_service_init+0x0/0x14 returned 0 after 9 usecs
[ 0.349566] calling pci_stub_init+0x0/0x133 @ 1
[ 0.349588] initcall pci_stub_init+0x0/0x133 returned 0 after 17 usecs
[ 0.349594] calling acpi_button_driver_init+0x0/0x20 @ 1
[ 0.349733] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
[ 0.349763] ACPI: Power Button [PWRB]
[ 0.349779] probe of PNP0C0C:00 returned 1 after 144 usecs
[ 0.349855] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[ 0.349871] ACPI: Power Button [PWRF]
[ 0.349963] probe of LNXPWRBN:00 returned 1 after 176 usecs
[ 0.349976] initcall acpi_button_driver_init+0x0/0x20 returned 0 after 368 usecs
[ 0.349981] calling acpi_fan_driver_init+0x0/0x16 @ 1
[ 0.350003] initcall acpi_fan_driver_init+0x0/0x16 returned 0 after 17 usecs
[ 0.350007] calling acpi_processor_driver_init+0x0/0x8d @ 1
[ 0.350289] probe of cpu0 returned 1 after 275 usecs
[ 0.350509] probe of cpu1 returned 1 after 216 usecs
[ 0.350525] initcall acpi_processor_driver_init+0x0/0x8d returned 0 after 502 usecs
[ 0.350529] calling acpi_thermal_init+0x0/0x78 @ 1
[ 0.351578] thermal LNXTHERM:00: registered as thermal_zone0
[ 0.351583] ACPI: Thermal Zone [TZ00] (52 C)
[ 0.351601] probe of LNXTHERM:00 returned 1 after 913 usecs
[ 0.351645] initcall acpi_thermal_init+0x0/0x78 returned 0 after 1081 usecs
[ 0.351651] calling acpi_hed_driver_init+0x0/0x14 @ 1
[ 0.351677] initcall acpi_hed_driver_init+0x0/0x14 returned 0 after 21 usecs
[ 0.351681] calling gpio_clk_driver_init+0x0/0x16 @ 1
[ 0.351703] initcall gpio_clk_driver_init+0x0/0x16 returned 0 after 18 usecs
[ 0.351706] calling plt_clk_driver_init+0x0/0x16 @ 1
[ 0.351719] initcall plt_clk_driver_init+0x0/0x16 returned 0 after 9 usecs
[ 0.351722] calling dw_pci_driver_init+0x0/0x1b @ 1
[ 0.351760] initcall dw_pci_driver_init+0x0/0x1b returned 0 after 33 usecs
[ 0.351763] calling n_null_init+0x0/0x21 @ 1
[ 0.351766] initcall n_null_init+0x0/0x21 returned 0 after 0 usecs
[ 0.351769] calling pty_init+0x0/0x1d6 @ 1
[ 0.351877] initcall pty_init+0x0/0x1d6 returned 0 after 101 usecs
[ 0.351899] calling sysrq_init+0x0/0x68 @ 1
[ 0.351909] initcall sysrq_init+0x0/0x68 returned 0 after 6 usecs
[ 0.351913] initcall serial8250_init blacklisted
[ 0.351917] calling serial_pci_driver_init+0x0/0x1b @ 1
[ 0.351983] initcall serial_pci_driver_init+0x0/0x1b returned 0 after 61 usecs
[ 0.351987] calling dw8250_platform_driver_init+0x0/0x16 @ 1
[ 0.352005] initcall dw8250_platform_driver_init+0x0/0x16 returned 0 after 13 usecs
[ 0.352009] calling lpss8250_pci_driver_init+0x0/0x1b @ 1
[ 0.352029] initcall lpss8250_pci_driver_init+0x0/0x1b returned 0 after 16 usecs
[ 0.352033] calling mid8250_pci_driver_init+0x0/0x1b @ 1
[ 0.352052] initcall mid8250_pci_driver_init+0x0/0x1b returned 0 after 15 usecs
[ 0.352056] calling raw_init+0x0/0x12d @ 1
[ 0.352323] initcall raw_init+0x0/0x12d returned 0 after 248 usecs
[ 0.352333] calling hpet_init+0x0/0x5c @ 1
[ 0.352560] initcall hpet_init+0x0/0x5c returned 0 after 217 usecs
[ 0.352564] calling nvram_init+0x0/0x75 @ 1
[ 0.352698] Non-volatile memory driver v1.3
[ 0.352702] initcall nvram_init+0x0/0x75 returned 0 after 131 usecs
[ 0.352706] calling hwrng_modinit+0x0/0x71 @ 1
[ 0.352786] initcall hwrng_modinit+0x0/0x71 returned 0 after 73 usecs
[ 0.352789] calling mod_init+0x0/0x94 @ 1
[ 0.352808] initcall mod_init+0x0/0x94 returned -19 after 15 usecs
[ 0.352812] calling agp_init+0x0/0x29 @ 1
[ 0.352814] Linux agpgart interface v0.103
[ 0.352817] initcall agp_init+0x0/0x29 returned 0 after 2 usecs
[ 0.352820] calling agp_amd64_mod_init+0x0/0xf @ 1
[ 0.352996] probe of 0000:00:00.0 returned 0 after 69 usecs
[ 0.353015] probe of 0000:00:01.0 returned 0 after 14 usecs
[ 0.353099] probe of 0000:00:01.1 returned 0 after 80 usecs
[ 0.353186] probe of 0000:00:11.0 returned 0 after 83 usecs
[ 0.353199] probe of 0000:00:12.0 returned 0 after 9 usecs
[ 0.353216] probe of 0000:00:12.2 returned 0 after 13 usecs
[ 0.353228] probe of 0000:00:13.0 returned 0 after 9 usecs
[ 0.353245] probe of 0000:00:13.2 returned 0 after 13 usecs
[ 0.353257] probe of 0000:00:14.0 returned 0 after 9 usecs
[ 0.353273] probe of 0000:00:14.2 returned 0 after 12 usecs
[ 0.353286] probe of 0000:00:14.3 returned 0 after 9 usecs
[ 0.353299] probe of 0000:00:14.4 returned 0 after 9 usecs
[ 0.353311] probe of 0000:00:14.5 returned 0 after 9 usecs
[ 0.353324] probe of 0000:00:18.0 returned 0 after 9 usecs
[ 0.353335] probe of 0000:00:18.1 returned 0 after 8 usecs
[ 0.353347] probe of 0000:00:18.2 returned 0 after 8 usecs
[ 0.353359] probe of 0000:00:18.3 returned 0 after 9 usecs
[ 0.353371] probe of 0000:00:18.4 returned 0 after 8 usecs
[ 0.353382] probe of 0000:00:18.5 returned 0 after 8 usecs
[ 0.353394] probe of 0000:00:18.6 returned 0 after 8 usecs
[ 0.353405] probe of 0000:00:18.7 returned 0 after 8 usecs
[ 0.353499] probe of 0000:03:00.0 returned 0 after 90 usecs
[ 0.353517] initcall agp_amd64_mod_init+0x0/0xf returned -19 after 676 usecs
[ 0.353521] calling agp_intel_init+0x0/0x2a @ 1
[ 0.353548] initcall agp_intel_init+0x0/0x2a returned 0 after 23 usecs
[ 0.353552] calling agp_sis_init+0x0/0x2a @ 1
[ 0.353576] initcall agp_sis_init+0x0/0x2a returned 0 after 20 usecs
[ 0.353579] calling agp_via_init+0x0/0x2a @ 1
[ 0.353604] initcall agp_via_init+0x0/0x2a returned 0 after 20 usecs
[ 0.353607] calling drm_kms_helper_init+0x0/0x18 @ 1
[ 0.353623] initcall drm_kms_helper_init+0x0/0x18 returned 0 after 12 usecs
[ 0.353627] calling drm_core_init+0x0/0xd1 @ 1
[ 0.353657] initcall drm_core_init+0x0/0xd1 returned 0 after 26 usecs
[ 0.353661] calling ttm_init+0x0/0x5f @ 1
[ 0.353688] initcall ttm_init+0x0/0x5f returned 0 after 23 usecs
[ 0.353692] calling radeon_init+0x0/0x9b @ 1
[ 0.353694] [drm] radeon kernel modesetting enabled.
[ 0.353730] initcall radeon_init+0x0/0x9b returned 0 after 33 usecs
[ 0.353734] calling cn_proc_init+0x0/0x35 @ 1
[ 0.353740] initcall cn_proc_init+0x0/0x35 returned 0 after 2 usecs
[ 0.353745] calling topology_sysfs_init+0x0/0x40 @ 1
[ 0.353768] initcall topology_sysfs_init+0x0/0x40 returned 0 after 19 usecs
[ 0.353772] calling cacheinfo_sysfs_init+0x0/0x2a @ 1
[ 0.354641] initcall cacheinfo_sysfs_init+0x0/0x2a returned 173 after 843 usecs
[ 0.354646] calling init_sd+0x0/0x136 @ 1
[ 0.354694] initcall init_sd+0x0/0x136 returned 0 after 43 usecs
[ 0.354698] calling init_sr+0x0/0x42 @ 1
[ 0.354710] initcall init_sr+0x0/0x42 returned 0 after 8 usecs
[ 0.354714] calling init_sg+0x0/0x18d @ 1
[ 0.354741] initcall init_sg+0x0/0x18d returned 0 after 22 usecs
[ 0.354746] calling ahci_pci_driver_init+0x0/0x1b @ 1
[ 0.354774] ahci 0000:00:11.0: version 3.0
[ 0.354921] [drm] initializing kernel modesetting (PALM 0x1002:0x9802 0x1002:0x9802 0x00).
[ 0.355009] ATOM BIOS: AMD
[ 0.355088] radeon 0000:00:01.0: VRAM: 384M 0x0000000000000000 - 0x0000000017FFFFFF (384M used)
[ 0.355092] radeon 0000:00:01.0: GTT: 1024M 0x0000000018000000 - 0x0000000057FFFFFF
[ 0.355099] [drm] Detected VRAM RAM=384M, BAR=256M
[ 0.355100] [drm] RAM width 32bits DDR
[ 0.355231] [TTM] Zone kernel: Available graphics memory: 418984 kiB
[ 0.355232] [TTM] Initializing pool allocator
[ 0.355248] [TTM] Initializing DMA pool allocator
[ 0.355287] [drm] radeon: 384M of VRAM memory ready
[ 0.355290] [drm] radeon: 1024M of GTT memory ready.
[ 0.355306] [drm] Loading PALM Microcode
[ 0.355314] [drm] Internal thermal controller without fan control
[ 0.355455] [drm] Found smc ucode version: 0x00010601
[ 0.355539] ahci 0000:00:11.0: AHCI 0001.0200 32 slots 6 ports 6 Gbps 0x3f impl SATA mode
[ 0.355546] ahci 0000:00:11.0: flags: 64bit ncq sntf ilck led clo pmp pio slum part
[ 0.355557] [drm] radeon: dpm initialized
[ 0.355593] [drm] GART: num cpu pages 262144, num gpu pages 262144
[ 0.358748] scsi host0: ahci
[ 0.362128] scsi host1: ahci
[ 0.362318] scsi host2: ahci
[ 0.362507] scsi host3: ahci
[ 0.362681] scsi host4: ahci
[ 0.362859] scsi host5: ahci
[ 0.363024] ata1: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b100 irq 19
[ 0.363028] ata2: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b180 irq 19
[ 0.363032] ata3: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b200 irq 19
[ 0.363035] ata4: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b280 irq 19
[ 0.363038] ata5: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b300 irq 19
[ 0.363042] ata6: SATA max UDMA/133 abar m1024@0xf014b000 port 0xf014b380 irq 19
[ 0.363069] probe of 0000:00:11.0 returned 1 after 8306 usecs
[ 0.363144] initcall ahci_pci_driver_init+0x0/0x1b returned 0 after 8192 usecs
[ 0.363151] calling net_olddevs_init+0x0/0x53 @ 1
[ 0.363162] initcall net_olddevs_init+0x0/0x53 returned 0 after 6 usecs
[ 0.363166] calling fixed_mdio_bus_init+0x0/0xd8 @ 1
[ 0.363245] libphy: Fixed MDIO Bus: probed
[ 0.363250] initcall fixed_mdio_bus_init+0x0/0xd8 returned 0 after 77 usecs
[ 0.363255] calling cdrom_init+0x0/0x1d @ 1
[ 0.363269] initcall cdrom_init+0x0/0x1d returned 0 after 11 usecs
[ 0.363274] calling usb_serial_init+0x0/0x143 @ 1
[ 0.363323] usbcore: registered new interface driver usbserial_generic
[ 0.363336] usbserial: USB Serial support registered for generic
[ 0.363341] initcall usb_serial_init+0x0/0x143 returned 0 after 61 usecs
[ 0.363345] calling i8042_init+0x0/0x41a @ 1
[ 0.363379] probe of 00:01 returned 1 after 20 usecs
[ 0.363395] probe of 00:03 returned 1 after 10 usecs
[ 0.363420] probe of 00:02 returned 1 after 10 usecs
[ 0.363433] probe of 00:04 returned 1 after 9 usecs
[ 0.363442] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[ 0.367361] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 0.367372] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 0.367383] probe of i8042 returned 1 after 3663 usecs
[ 0.367397] initcall i8042_init+0x0/0x41a returned 0 after 3952 usecs
[ 0.367402] calling serport_init+0x0/0x30 @ 1
[ 0.367406] initcall serport_init+0x0/0x30 returned 0 after 0 usecs
[ 0.367410] calling input_leds_init+0x0/0x14 @ 1
[ 0.367416] initcall input_leds_init+0x0/0x14 returned 0 after 2 usecs
[ 0.367420] calling mousedev_init+0x0/0x56 @ 1
[ 0.367745] mousedev: PS/2 mouse device common for all mice
[ 0.367751] initcall mousedev_init+0x0/0x56 returned 0 after 318 usecs
[ 0.367755] calling evdev_init+0x0/0x14 @ 1
[ 0.367872] initcall evdev_init+0x0/0x14 returned 0 after 109 usecs
[ 0.367889] calling atkbd_init+0x0/0x25 @ 1
[ 0.367922] initcall atkbd_init+0x0/0x25 returned 0 after 28 usecs
[ 0.367926] calling psmouse_init+0x0/0x7e @ 1
[ 0.367972] initcall psmouse_init+0x0/0x7e returned 0 after 40 usecs
[ 0.367976] calling cmos_init+0x0/0x65 @ 1
[ 0.368088] rtc_cmos 00:00: RTC can wake from S4
[ 0.368396] rtc_cmos 00:00: registered as rtc0
[ 0.368434] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[ 0.368440] probe of 00:00 returned 1 after 456 usecs
[ 0.368455] initcall cmos_init+0x0/0x65 returned 0 after 463 usecs
[ 0.368461] calling sensors_w83627ehf_init+0x0/0x16f @ 1
[ 0.368567] w83627ehf: Found NCT6775F chip at 0x290
[ 0.369187] w83627ehf w83627ehf.656: hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info().
[ 0.369222] probe of w83627ehf.656 returned 1 after 600 usecs
[ 0.369228] initcall sensors_w83627ehf_init+0x0/0x16f returned 0 after 744 usecs
[ 0.369232] calling dm_init+0x0/0x3c @ 1
[ 0.369312] device-mapper: uevent: version 1.0.3
[ 0.369445] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com
[ 0.369450] initcall dm_init+0x0/0x3c returned 0 after 208 usecs
[ 0.369455] calling dm_crypt_init+0x0/0x2d @ 1
[ 0.369459] initcall dm_crypt_init+0x0/0x2d returned 0 after 0 usecs
[ 0.369464] calling cpufreq_gov_powersave_init+0x0/0x14 @ 1
[ 0.369470] initcall cpufreq_gov_powersave_init+0x0/0x14 returned 0 after 1 usecs
[ 0.369475] calling cpufreq_gov_userspace_init+0x0/0x14 @ 1
[ 0.369479] initcall cpufreq_gov_userspace_init+0x0/0x14 returned 0 after 0 usecs
[ 0.369484] calling cpufreq_gov_dbs_init+0x0/0x14 @ 1
[ 0.369488] initcall cpufreq_gov_dbs_init+0x0/0x14 returned 0 after 0 usecs
[ 0.369493] calling intel_pstate_init+0x0/0x340 @ 1
[ 0.369498] initcall intel_pstate_init+0x0/0x340 returned -19 after 1 usecs
[ 0.369503] calling ledtrig_disk_init+0x0/0x48 @ 1
[ 0.369515] initcall ledtrig_disk_init+0x0/0x48 returned 0 after 7 usecs
[ 0.369519] calling ledtrig_panic_init+0x0/0x34 @ 1
[ 0.369525] initcall ledtrig_panic_init+0x0/0x34 returned 0 after 1 usecs
[ 0.369527] calling dmi_sysfs_init+0x0/0xc1 @ 1
[ 0.369631] initcall dmi_sysfs_init+0x0/0xc1 returned 0 after 97 usecs
[ 0.369634] calling hid_init+0x0/0x5d @ 1
[ 0.369659] hidraw: raw HID events driver (C) Jiri Kosina
[ 0.369670] initcall hid_init+0x0/0x5d returned 0 after 31 usecs
[ 0.369673] calling hid_generic_init+0x0/0x1b @ 1
[ 0.369694] initcall hid_generic_init+0x0/0x1b returned 0 after 16 usecs
[ 0.369697] calling ite_driver_init+0x0/0x1b @ 1
[ 0.369711] initcall ite_driver_init+0x0/0x1b returned 0 after 11 usecs
[ 0.369714] calling magicmouse_driver_init+0x0/0x1b @ 1
[ 0.369735] initcall magicmouse_driver_init+0x0/0x1b returned 0 after 17 usecs
[ 0.369739] calling ntrig_driver_init+0x0/0x1b @ 1
[ 0.369759] initcall ntrig_driver_init+0x0/0x1b returned 0 after 16 usecs
[ 0.369762] calling hid_init+0x0/0x58 @ 1
[ 0.369786] usbcore: registered new interface driver usbhid
[ 0.369787] usbhid: USB HID core driver
[ 0.369791] initcall hid_init+0x0/0x58 returned 0 after 25 usecs
[ 0.369794] calling pmc_atom_init+0x0/0x226 @ 1
[ 0.369813] initcall pmc_atom_init+0x0/0x226 returned -19 after 15 usecs
[ 0.369816] calling extcon_class_init+0x0/0x1b @ 1
[ 0.369826] initcall extcon_class_init+0x0/0x1b returned 0 after 6 usecs
[ 0.369829] calling powercap_init+0x0/0x1fa @ 1
[ 0.369940] initcall powercap_init+0x0/0x1fa returned 0 after 104 usecs
[ 0.369947] calling pm_check_save_msr+0x0/0x20 @ 1
[ 0.369951] initcall pm_check_save_msr+0x0/0x20 returned 0 after 1 usecs
[ 0.369956] calling sock_diag_init+0x0/0x34 @ 1
[ 0.370003] initcall sock_diag_init+0x0/0x34 returned 0 after 41 usecs
[ 0.370007] calling blackhole_init+0x0/0x14 @ 1
[ 0.370012] initcall blackhole_init+0x0/0x14 returned 0 after 1 usecs
[ 0.370016] calling fq_codel_module_init+0x0/0x14 @ 1
[ 0.370020] initcall fq_codel_module_init+0x0/0x14 returned 0 after 0 usecs
[ 0.370024] calling init_cgroup_cls+0x0/0x14 @ 1
[ 0.370028] initcall init_cgroup_cls+0x0/0x14 returned 0 after 0 usecs
[ 0.370031] calling xt_init+0x0/0xb2 @ 1
[ 0.370036] initcall xt_init+0x0/0xb2 returned 0 after 1 usecs
[ 0.370040] calling tcpudp_mt_init+0x0/0x19 @ 1
[ 0.370044] initcall tcpudp_mt_init+0x0/0x19 returned 0 after 1 usecs
[ 0.370049] calling gre_offload_init+0x0/0x47 @ 1
[ 0.370054] initcall gre_offload_init+0x0/0x47 returned 0 after 1 usecs
[ 0.370058] calling sysctl_ipv4_init+0x0/0x45 @ 1
[ 0.370152] initcall sysctl_ipv4_init+0x0/0x45 returned 0 after 88 usecs
[ 0.370157] calling ip_tables_init+0x0/0x84 @ 1
[ 0.370171] initcall ip_tables_init+0x0/0x84 returned 0 after 10 usecs
[ 0.370175] calling iptable_filter_init+0x0/0x45 @ 1
[ 0.370412] initcall iptable_filter_init+0x0/0x45 returned 0 after 225 usecs
[ 0.370417] calling reject_tg_init+0x0/0x14 @ 1
[ 0.370420] initcall reject_tg_init+0x0/0x14 returned 0 after 0 usecs
[ 0.370425] calling cubictcp_register+0x0/0x71 @ 1
[ 0.370429] initcall cubictcp_register+0x0/0x71 returned 0 after 1 usecs
[ 0.370433] calling xfrm_user_init+0x0/0x45 @ 1
[ 0.370435] Initializing XFRM netlink socket
[ 0.370443] initcall xfrm_user_init+0x0/0x45 returned 0 after 6 usecs
[ 0.370448] calling inet6_init+0x0/0x32d @ 1
[ 0.370723] NET: Registered protocol family 10
[ 0.371183] Segment Routing with IPv6
[ 0.371221] initcall inet6_init+0x0/0x32d returned 0 after 749 usecs
[ 0.371226] calling mip6_init+0x0/0xac @ 1
[ 0.371227] mip6: Mobile IPv6
[ 0.371232] initcall mip6_init+0x0/0xac returned 0 after 2 usecs
[ 0.371236] calling packet_init+0x0/0x3e @ 1
[ 0.371238] NET: Registered protocol family 17
[ 0.371245] initcall packet_init+0x0/0x3e returned 0 after 4 usecs
[ 0.371251] calling mcheck_init_device+0x0/0x105 @ 1
[ 0.371536] ata2: SATA link down (SStatus 0 SControl 0)
[ 0.371546] [drm] GART: Restore entries: num cpu pages 262144, num gpu pages 262144
[ 0.371564] initcall mcheck_init_device+0x0/0x105 returned 0 after 300 usecs
[ 0.371770] calling mcheck_late_init+0x0/0x69 @ 1
[ 0.371792] initcall mcheck_late_init+0x0/0x69 returned 0 after 17 usecs
[ 0.371797] calling severities_debugfs_init+0x0/0x3a @ 1
[ 0.371805] initcall severities_debugfs_init+0x0/0x3a returned 0 after 3 usecs
[ 0.371810] calling threshold_init_device+0x0/0x47 @ 1
[ 0.371814] initcall threshold_init_device+0x0/0x47 returned 0 after 0 usecs
[ 0.371817] calling hpet_insert_resource+0x0/0x24 @ 1
[ 0.371824] initcall hpet_insert_resource+0x0/0x24 returned 0 after 3 usecs
[ 0.371829] calling lapic_insert_resource+0x0/0x4b @ 1
[ 0.371833] initcall lapic_insert_resource+0x0/0x4b returned 0 after 0 usecs
[ 0.371836] calling print_ICs+0x0/0x17b @ 1
[ 0.371840] initcall print_ICs+0x0/0x17b returned 0 after 0 usecs
[ 0.371844] calling print_ipi_mode+0x0/0x2b @ 1
[ 0.371846] Using IPI No-Shortcut mode
[ 0.371849] initcall print_ipi_mode+0x0/0x2b returned 0 after 1 usecs
[ 0.371854] calling pat_memtype_list_init+0x0/0x36 @ 1
[ 0.371862] initcall pat_memtype_list_init+0x0/0x36 returned 0 after 3 usecs
[ 0.371867] calling create_tlb_single_page_flush_ceiling+0x0/0x28 @ 1
[ 0.371902] initcall create_tlb_single_page_flush_ceiling+0x0/0x28 returned 0 after 28 usecs
[ 0.371910] calling init_oops_id+0x0/0x50 @ 1
[ 0.371917] initcall init_oops_id+0x0/0x50 returned 0 after 2 usecs
[ 0.371921] calling sched_clock_init_late+0x0/0x89 @ 1
[ 0.371925] sched_clock: Marking stable (368562160, 3323605)->(476327428, -104441663)
[ 0.371997] initcall sched_clock_init_late+0x0/0x89 returned 0 after 69 usecs
[ 0.372000] calling sched_init_debug+0x0/0x3a @ 1
[ 0.372009] initcall sched_init_debug+0x0/0x3a returned 0 after 6 usecs
[ 0.372013] calling pm_qos_power_init+0x0/0x9a @ 1
[ 0.372167] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 0)
[ 0.372422] initcall pm_qos_power_init+0x0/0x9a returned 0 after 395 usecs
[ 0.372426] calling pm_debugfs_init+0x0/0x24 @ 1
[ 0.372433] initcall pm_debugfs_init+0x0/0x24 returned 0 after 3 usecs
[ 0.372437] calling printk_late_init+0x0/0x106 @ 1
[ 0.372442] initcall printk_late_init+0x0/0x106 returned 0 after 1 usecs
[ 0.372447] calling tk_debug_sleep_time_init+0x0/0x3c @ 1
[ 0.372454] initcall tk_debug_sleep_time_init+0x0/0x3c returned 0 after 2 usecs
[ 0.372458] calling debugfs_kprobe_init+0x0/0xb7 @ 1
[ 0.372482] initcall debugfs_kprobe_init+0x0/0xb7 returned 0 after 19 usecs
[ 0.372487] calling taskstats_init+0x0/0x38 @ 1
[ 0.372499] registered taskstats version 1
[ 0.372503] initcall taskstats_init+0x0/0x38 returned 0 after 12 usecs
[ 0.372508] calling load_system_certificate_list+0x0/0xc4 @ 1
[ 0.372509] Loading compiled-in X.509 certificates
[ 0.372513] initcall load_system_certificate_list+0x0/0xc4 returned 0 after 1 usecs
[ 0.372518] calling fault_around_debugfs+0x0/0x35 @ 1
[ 0.372525] initcall fault_around_debugfs+0x0/0x35 returned 0 after 2 usecs
[ 0.372530] calling max_swapfiles_check+0x0/0xc @ 1
[ 0.372533] initcall max_swapfiles_check+0x0/0xc returned 0 after 0 usecs
[ 0.372538] calling init_zswap+0x0/0x3a7 @ 1
[ 0.372578] zswap: loaded using pool lzo/zbud
[ 0.372663] initcall init_zswap+0x0/0x3a7 returned 0 after 116 usecs
[ 0.372667] calling split_huge_pages_debugfs+0x0/0x35 @ 1
[ 0.372673] initcall split_huge_pages_debugfs+0x0/0x35 returned 0 after 2 usecs
[ 0.372676] calling kmemleak_late_init+0x0/0x8d @ 1
[ 0.372683] initcall kmemleak_late_init+0x0/0x8d returned -12 after 3 usecs
[ 0.372686] calling check_early_ioremap_leak+0x0/0x38 @ 1
[ 0.372690] initcall check_early_ioremap_leak+0x0/0x38 returned 0 after 0 usecs
[ 0.372693] calling set_hardened_usercopy+0x0/0x28 @ 1
[ 0.372697] initcall set_hardened_usercopy+0x0/0x28 returned 1 after 0 usecs
[ 0.372700] calling init_root_keyring+0x0/0xf @ 1
[ 0.372724] initcall init_root_keyring+0x0/0xf returned 0 after 20 usecs
[ 0.372727] calling big_key_init+0x0/0xa8 @ 1
[ 0.372745] ata1.00: ATA-9: SanDisk SDSSDP064G, 2.0.0, max UDMA/133
[ 0.372749] ata1.00: 125045424 sectors, multi 1: LBA48 NCQ (depth 32)
[ 0.373052] ata3: SATA link down (SStatus 0 SControl 0)
[ 0.373203] ata4: SATA link down (SStatus 0 SControl 0)
[ 0.373287] ata1.00: configured for UDMA/133
[ 0.373405] ata5: SATA link down (SStatus 0 SControl 0)
[ 0.373579] ata6: SATA link down (SStatus 0 SControl 0)
[ 0.374257] Key type big_key registered
[ 0.374266] initcall big_key_init+0x0/0xa8 returned 0 after 1495 usecs
[ 0.374272] calling prandom_reseed+0x0/0x27 @ 1
[ 0.374281] initcall prandom_reseed+0x0/0x27 returned 0 after 6 usecs
[ 0.374285] calling init_error_injection+0x0/0x61 @ 1
[ 0.374428] initcall init_error_injection+0x0/0x61 returned 0 after 135 usecs
[ 0.374433] calling pci_resource_alignment_sysfs_init+0x0/0x23 @ 1
[ 0.374444] initcall pci_resource_alignment_sysfs_init+0x0/0x23 returned 0 after 7 usecs
[ 0.374448] calling pci_sysfs_init+0x0/0x47 @ 1
[ 0.374732] initcall pci_sysfs_init+0x0/0x47 returned 0 after 272 usecs
[ 0.374737] calling clk_debug_init+0x0/0xe7 @ 1
[ 0.374764] initcall clk_debug_init+0x0/0xe7 returned 0 after 23 usecs
[ 0.374769] calling deferred_probe_initcall+0x0/0x90 @ 1
[ 0.374804] initcall deferred_probe_initcall+0x0/0x90 returned 0 after 29 usecs
[ 0.374810] calling late_resume_init+0x0/0xf9 @ 1
[ 0.374812] Magic number: 6:832:350
[ 0.374973] initcall late_resume_init+0x0/0xf9 returned 0 after 153 usecs
[ 0.374978] calling sync_debugfs_init+0x0/0x51 @ 1
[ 0.374990] initcall sync_debugfs_init+0x0/0x51 returned 0 after 7 usecs
[ 0.374994] calling rtc_hctosys+0x0/0xee @ 1
[ 0.375104] rtc_cmos 00:00: setting system clock to 2018-10-16 22:20:28 UTC (1539728428)
[ 0.375108] initcall rtc_hctosys+0x0/0xee returned 0 after 107 usecs
[ 0.375111] calling firmware_memmap_init+0x0/0x2d @ 1
[ 0.375170] initcall firmware_memmap_init+0x0/0x2d returned 0 after 54 usecs
[ 0.375174] calling pci_mmcfg_late_insert_resources+0x0/0x49 @ 1
[ 0.375178] initcall pci_mmcfg_late_insert_resources+0x0/0x49 returned 0 after 0 usecs
[ 0.375183] calling tcp_congestion_default+0x0/0x19 @ 1
[ 0.375188] initcall tcp_congestion_default+0x0/0x19 returned 0 after 1 usecs
[ 0.375194] calling clear_boot_tracer+0x0/0x2a @ 1
[ 0.375199] initcall clear_boot_tracer+0x0/0x2a returned 0 after 0 usecs
[ 0.375204] calling tracing_set_default_clock+0x0/0x36 @ 1
[ 0.375208] initcall tracing_set_default_clock+0x0/0x36 returned 0 after 0 usecs
[ 0.375214] calling acpi_gpio_handle_deferred_request_interrupts+0x0/0xb0 @ 1
[ 0.375218] initcall acpi_gpio_handle_deferred_request_interrupts+0x0/0xb0 returned 0 after 0 usecs
[ 0.375223] calling clk_disable_unused+0x0/0x100 @ 1
[ 0.375228] initcall clk_disable_unused+0x0/0x100 returned 0 after 1 usecs
[ 0.376811] [drm] GART: Done restoring entries
[ 0.376820] [drm] PCIE GART of 1024M enabled (table at 0x0000000000162000).
[ 0.377012] radeon 0000:00:01.0: WB enabled
[ 0.377020] radeon 0000:00:01.0: fence driver on ring 0 use gpu addr 0x0000000018000c00 and cpu addr 0x(ptrval)
[ 0.377024] radeon 0000:00:01.0: fence driver on ring 3 use gpu addr 0x0000000018000c0c and cpu addr 0x(ptrval)
[ 0.377919] radeon 0000:00:01.0: fence driver on ring 5 use gpu addr 0x0000000000072118 and cpu addr 0x(ptrval)
[ 0.377923] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 0.377924] [drm] Driver supports precise vblank timestamp query.
[ 0.377927] radeon 0000:00:01.0: radeon: MSI limited to 32-bit
[ 0.378020] radeon 0000:00:01.0: radeon: using MSI.
[ 0.378064] [drm] radeon: irq initialized.
[ 0.394721] [drm] ring test on 0 succeeded in 1 usecs
[ 0.394729] [drm] ring test on 3 succeeded in 3 usecs
[ 0.396650] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
[ 0.396905] probe of serio0 returned 1 after 28613 usecs
[ 0.408793] [drm] ring test on 5 succeeded in 1 usecs
[ 0.410815] [drm] UVD initialized successfully.
[ 0.411106] [drm] ib test on ring 0 succeeded in 0 usecs
[ 0.411218] [drm] ib test on ring 3 succeeded in 0 usecs
[ 0.440372] probe of serio1 returned 0 after 43450 usecs
[ 0.451625] probe of serio1 returned 0 after 11228 usecs
[ 0.464839] [drm] ib test on ring 5 succeeded
[ 0.467664] [drm] Radeon Display Connectors
[ 0.467666] [drm] Connector 0:
[ 0.467667] [drm] HDMI-A-1
[ 0.467668] [drm] HPD1
[ 0.467671] [drm] DDC: 0x6430 0x6430 0x6434 0x6434 0x6438 0x6438 0x643c 0x643c
[ 0.467672] [drm] Encoders:
[ 0.467674] [drm] DFP1: INTERNAL_UNIPHY
[ 0.467675] [drm] Connector 1:
[ 0.467676] [drm] HDMI-A-2
[ 0.467677] [drm] HPD2
[ 0.467679] [drm] DDC: 0x6440 0x6440 0x6444 0x6444 0x6448 0x6448 0x644c 0x644c
[ 0.467680] [drm] Encoders:
[ 0.467681] [drm] DFP2: INTERNAL_UNIPHY
[ 0.467683] [drm] Connector 2:
[ 0.467683] [drm] VGA-1
[ 0.467686] [drm] DDC: 0x64d8 0x64d8 0x64dc 0x64dc 0x64e0 0x64e0 0x64e4 0x64e4
[ 0.467687] [drm] Encoders:
[ 0.467704] [drm] CRT1: INTERNAL_KLDSCP_DAC1
[ 0.508931] [drm] fb mappable at 0xE0366000
[ 0.508932] [drm] vram apper at 0xE0000000
[ 0.508933] [drm] size 5242880
[ 0.508934] [drm] fb depth is 24
[ 0.508936] [drm] pitch is 5120
[ 0.509166] fbcon: radeondrmfb (fb0) is primary device
[ 0.553077] Console: switching to colour frame buffer device 160x64
[ 0.563843] radeon 0000:00:01.0: fb0: radeondrmfb frame buffer device
[ 0.563979] [drm] Initialized radeon 2.50.0 20080528 for 0000:00:01.0 on minor 0
[ 0.564027] probe of 0000:00:01.0 returned 1 after 210111 usecs
[ 0.564764] scsi 0:0:0:0: Direct-Access ATA SanDisk SDSSDP06 0 PQ: 0 ANSI: 5
[ 0.565273] probe of 0:0:0:0 returned 1 after 96 usecs
[ 0.565411] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 0.565501] sd 0:0:0:0: [sda] 125045424 512-byte logical blocks: (64.0 GB/59.6 GiB)
[ 0.565578] sd 0:0:0:0: [sda] Write Protect is off
[ 0.565583] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 0.565625] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 0.566774] sda: sda1
[ 0.567407] sd 0:0:0:0: [sda] Attached SCSI disk
[ 0.572462] random: 1
[ 0.572465] random: 2
[ 0.572468] random: 3
[ 0.572469] random: 4
[ 0.572473] random: fast init done
[ 0.579108] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[ 0.579143] VFS: Mounted root (ext4 filesystem) on device 8:1.
[ 0.581242] devtmpfs: mounted
[ 0.582800] Freeing init [mem 0xcac1d000-0xcad32fff]
[ 0.583774] Freeing unused kernel image memory: 1112K
[ 0.587966] RO text: 0xca000000 - 0xca880fff
[ 0.588143] Write protecting the kernel text: 8708k
[ 0.588145] RO data: 0xca881000 - 0xcab4cfff
[ 0.588429] Write protecting the kernel read-only data: 2864k
[ 0.588431] NX data: 0xca881000 - 0xcadfffff
[ 0.588432] NX-protecting the kernel data: 5628k
[ 0.589160] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[ 0.589170] Run /lib/systemd/systemd-bootchart as init process
[ 0.698300] random: systemd-bootcha: uninitialized urandom read (16 bytes read)
[ 0.862579] systemd[1]: systemd 239 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[ 0.862958] systemd[1]: Detected architecture x86.
[ 0.867270] systemd[1]: Set hostname to <kodi>.
[ 1.246694] random: systemd: uninitialized urandom read (16 bytes read)
[ 1.248532] systemd[1]: Created slice system-getty.slice.
[ 1.248867] random: systemd: uninitialized urandom read (16 bytes read)
[ 1.249164] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 1.249327] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 1.249768] systemd[1]: Listening on Journal Socket (/dev/log).
[ 1.249830] systemd[1]: Reached target Network.
[ 1.249877] systemd[1]: Reached target Remote File Systems.
[ 1.250176] systemd[1]: Listening on Network Service Netlink Socket.
[ 1.375544] tsc: Refined TSC clocksource calibration: 1600.001 MHz
[ 1.375560] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x17102604879, max_idle_ns: 440795272763 ns
[ 1.375601] clocksource: Switched to clocksource tsc
[ 1.760900] calling coreboot_bus_init+0x0/0x1000 [coreboot_table] @ 143
[ 1.760934] initcall coreboot_bus_init+0x0/0x1000 [coreboot_table] returned 0 after 24 usecs
[ 1.762852] calling coreboot_table_acpi_init+0x0/0x1000 [coreboot_table_acpi] @ 143
[ 1.765186] probe of BOOT0000:00 returned 1 after 2301 usecs
[ 1.765233] initcall coreboot_table_acpi_init+0x0/0x1000 [coreboot_table_acpi] returned 0 after 2312 usecs
[ 1.789869] calling acpi_cpufreq_init+0x0/0x1000 [acpi_cpufreq] @ 143
[ 1.790039] acpi_cpufreq: overriding BIOS provided _PSD data
[ 1.790264] initcall acpi_cpufreq_init+0x0/0x1000 [acpi_cpufreq] returned 0 after 374 usecs
[ 1.792987] calling pcc_cpufreq_init+0x0/0xebc [pcc_cpufreq] @ 143
[ 1.792998] initcall pcc_cpufreq_init+0x0/0xebc [pcc_cpufreq] returned 0 after 1 usecs
[ 2.022807] calling init_soundcore+0x0/0x1000 [soundcore] @ 144
[ 2.022843] initcall init_soundcore+0x0/0x1000 [soundcore] returned 0 after 27 usecs
[ 2.028416] calling alsa_sound_init+0x0/0x82 [snd] @ 144
[ 2.028482] initcall alsa_sound_init+0x0/0x82 [snd] returned 0 after 48 usecs
[ 2.030960] calling alsa_timer_init+0x0/0x1000 [snd_timer] @ 144
[ 2.031635] initcall alsa_timer_init+0x0/0x1000 [snd_timer] returned 0 after 645 usecs
[ 2.037508] calling alsa_pcm_init+0x0/0x1000 [snd_pcm] @ 144
[ 2.037532] initcall alsa_pcm_init+0x0/0x1000 [snd_pcm] returned 0 after 9 usecs
[ 2.042908] calling hda_bus_init+0x0/0x14 [snd_hda_core] @ 144
[ 2.042950] initcall hda_bus_init+0x0/0x14 [snd_hda_core] returned 0 after 27 usecs
[ 2.083459] calling azx_driver_init+0x0/0xfe4 [snd_hda_intel] @ 144
[ 2.083529] initcall azx_driver_init+0x0/0xfe4 [snd_hda_intel] returned 0 after 56 usecs
[ 2.083597] 1
[ 2.083633] 2
[ 2.083972] 3
[ 2.083973] 4
[ 2.083974] 5
[ 2.083975] 7
[ 2.083981] 8
[ 2.083982] 9
[ 2.084034] probe of 0000:00:01.1 returned 1 after 518 usecs
[ 2.084057] 1
[ 2.084069] 2
[ 2.084338] 3
[ 2.084339] 4
[ 2.084340] 5
[ 2.084341] 7
[ 2.084342] 8
[ 2.084343] 9
[ 2.084401] probe of 0000:00:14.2 returned 1 after 353 usecs
[ 2.106135] calling generic_driver_init+0x0/0x1000 [snd_hda_codec_generic] @ 161
[ 2.106181] initcall generic_driver_init+0x0/0x1000 [snd_hda_codec_generic] returned 0 after 32 usecs
[ 2.110716] calling hdmi_driver_init+0x0/0x1000 [snd_hda_codec_hdmi] @ 160
[ 2.116098] calling realtek_driver_init+0x0/0x1000 [snd_hda_codec_realtek] @ 161
[ 2.116749] snd_hda_codec_realtek hdaudioC1D0: ALC892: SKU not ready 0x00000100
[ 2.117328] snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC892: line_outs=4 (0x14/0x16/0x15/0x17/0x0) type:line
[ 2.117331] snd_hda_codec_realtek hdaudioC1D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[ 2.117335] snd_hda_codec_realtek hdaudioC1D0: hp_outs=1 (0x1b/0x0/0x0/0x0/0x0)
[ 2.117337] snd_hda_codec_realtek hdaudioC1D0: mono: mono_out=0x0
[ 2.117340] snd_hda_codec_realtek hdaudioC1D0: dig-out=0x1e/0x0
[ 2.117342] snd_hda_codec_realtek hdaudioC1D0: inputs:
[ 2.117347] snd_hda_codec_realtek hdaudioC1D0: Rear Mic=0x18
[ 2.117351] snd_hda_codec_realtek hdaudioC1D0: Front Mic=0x19
[ 2.117354] snd_hda_codec_realtek hdaudioC1D0: Line=0x1a
[ 2.117393] snd_hda_codec_realtek hdaudioC1D0: CD=0x1c
[ 2.128352] probe of hdaudioC0D0 returned 1 after 17609 usecs
[ 2.128474] initcall hdmi_driver_init+0x0/0x1000 [snd_hda_codec_hdmi] returned 0 after 12064 usecs
[ 2.129335] input: HD-Audio Generic HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.1/sound/card0/input5
[ 2.158728] probe of hdaudioC1D0 returned 1 after 42600 usecs
[ 2.158799] initcall realtek_driver_init+0x0/0x1000 [snd_hda_codec_realtek] returned 0 after 41671 usecs
[ 2.161716] input: HDA ATI SB Rear Mic as /devices/pci0000:00/0000:00:14.2/sound/card1/input6
[ 2.161856] input: HDA ATI SB Front Mic as /devices/pci0000:00/0000:00:14.2/sound/card1/input7
[ 2.161977] input: HDA ATI SB Line as /devices/pci0000:00/0000:00:14.2/sound/card1/input8
[ 2.162106] input: HDA ATI SB Line Out Front as /devices/pci0000:00/0000:00:14.2/sound/card1/input9
[ 2.162228] input: HDA ATI SB Line Out Surround as /devices/pci0000:00/0000:00:14.2/sound/card1/input10
[ 2.162765] input: HDA ATI SB Line Out CLFE as /devices/pci0000:00/0000:00:14.2/sound/card1/input11
[ 2.162893] input: HDA ATI SB Line Out Side as /devices/pci0000:00/0000:00:14.2/sound/card1/input12
[ 2.163022] input: HDA ATI SB Front Headphone as /devices/pci0000:00/0000:00:14.2/sound/card1/input13
[ 2.168271] calling ehci_hcd_init+0x0/0x1000 [ehci_hcd] @ 144
[ 2.168277] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 2.168310] initcall ehci_hcd_init+0x0/0x1000 [ehci_hcd] returned 0 after 24 usecs
[ 2.176579] calling ohci_hcd_mod_init+0x0/0x1000 [ohci_hcd] @ 144
[ 2.176585] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 2.176615] initcall ohci_hcd_mod_init+0x0/0x1000 [ohci_hcd] returned 0 after 25 usecs
[ 2.187005] calling ehci_pci_init+0x0/0x1000 [ehci_pci] @ 144
[ 2.187017] ehci-pci: EHCI PCI platform driver
[ 2.187097] initcall ehci_pci_init+0x0/0x1000 [ehci_pci] returned 0 after 74 usecs
[ 2.188126] QUIRK: Enable AMD PLL fix
[ 2.188248] ehci-pci 0000:00:12.2: EHCI Host Controller
[ 2.188264] ehci-pci 0000:00:12.2: new USB bus registered, assigned bus number 1
[ 2.188276] ehci-pci 0000:00:12.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[ 2.188393] ehci-pci 0000:00:12.2: debug port 1
[ 2.188481] ehci-pci 0000:00:12.2: irq 17, io mem 0xf014c000
[ 2.195494] ehci-pci 0000:00:12.2: USB 2.0 started, EHCI 1.00
[ 2.195766] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[ 2.195770] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.195779] usb usb1: Product: EHCI Host Controller
[ 2.195782] usb usb1: Manufacturer: Linux 4.19.0-rc8-00045-gc92161e11c2a ehci_hcd
[ 2.195784] usb usb1: SerialNumber: 0000:00:12.2
[ 2.196329] hub 1-0:1.0: USB hub found
[ 2.196416] hub 1-0:1.0: 5 ports detected
[ 2.196786] probe of 1-0:1.0 returned 1 after 478 usecs
[ 2.196854] probe of usb1 returned 1 after 672 usecs
[ 2.197049] probe of 0000:00:12.2 returned 1 after 9485 usecs
[ 2.197656] ehci-pci 0000:00:13.2: EHCI Host Controller
[ 2.197679] ehci-pci 0000:00:13.2: new USB bus registered, assigned bus number 2
[ 2.197687] ehci-pci 0000:00:13.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[ 2.197715] ehci-pci 0000:00:13.2: debug port 1
[ 2.197791] ehci-pci 0000:00:13.2: irq 17, io mem 0xf014d000
[ 2.204478] ehci-pci 0000:00:13.2: USB 2.0 started, EHCI 1.00
[ 2.204694] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[ 2.204697] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.204701] usb usb2: Product: EHCI Host Controller
[ 2.204704] usb usb2: Manufacturer: Linux 4.19.0-rc8-00045-gc92161e11c2a ehci_hcd
[ 2.204706] usb usb2: SerialNumber: 0000:00:13.2
[ 2.205072] hub 2-0:1.0: USB hub found
[ 2.205093] hub 2-0:1.0: 5 ports detected
[ 2.205539] probe of 2-0:1.0 returned 1 after 481 usecs
[ 2.205592] probe of usb2 returned 1 after 619 usecs
[ 2.205662] probe of 0000:00:13.2 returned 1 after 8605 usecs
[ 2.208993] calling ohci_pci_init+0x0/0x1000 [ohci_pci] @ 144
[ 2.208998] ohci-pci: OHCI PCI platform driver
[ 2.209687] ohci-pci 0000:00:12.0: OHCI PCI host controller
[ 2.209704] ohci-pci 0000:00:12.0: new USB bus registered, assigned bus number 3
[ 2.209803] ohci-pci 0000:00:12.0: irq 18, io mem 0xf0148000
[ 2.210083] initcall ohci_pci_init+0x0/0x1000 [ohci_pci] returned 0 after 1051 usecs
[ 2.268492] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
[ 2.268499] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.268502] usb usb3: Product: OHCI PCI host controller
[ 2.268505] usb usb3: Manufacturer: Linux 4.19.0-rc8-00045-gc92161e11c2a ohci_hcd
[ 2.268507] usb usb3: SerialNumber: 0000:00:12.0
[ 2.271715] hub 3-0:1.0: USB hub found
[ 2.271807] hub 3-0:1.0: 5 ports detected
[ 2.272032] probe of 3-0:1.0 returned 1 after 338 usecs
[ 2.272089] probe of usb3 returned 1 after 500 usecs
[ 2.272253] probe of 0000:00:12.0 returned 1 after 63193 usecs
[ 2.273944] ohci-pci 0000:00:13.0: OHCI PCI host controller
[ 2.273969] ohci-pci 0000:00:13.0: new USB bus registered, assigned bus number 4
[ 2.274108] ohci-pci 0000:00:13.0: irq 18, io mem 0xf0149000
[ 2.329783] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
[ 2.329789] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.329792] usb usb4: Product: OHCI PCI host controller
[ 2.329795] usb usb4: Manufacturer: Linux 4.19.0-rc8-00045-gc92161e11c2a ohci_hcd
[ 2.329798] usb usb4: SerialNumber: 0000:00:13.0
[ 2.330132] hub 4-0:1.0: USB hub found
[ 2.330221] hub 4-0:1.0: 5 ports detected
[ 2.333390] probe of 4-0:1.0 returned 1 after 3271 usecs
[ 2.333468] probe of usb4 returned 1 after 3425 usecs
[ 2.333638] probe of 0000:00:13.0 returned 1 after 61378 usecs
[ 2.334141] ohci-pci 0000:00:14.5: OHCI PCI host controller
[ 2.334155] ohci-pci 0000:00:14.5: new USB bus registered, assigned bus number 5
[ 2.334217] ohci-pci 0000:00:14.5: irq 18, io mem 0xf014a000
[ 2.389660] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
[ 2.389666] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.389669] usb usb5: Product: OHCI PCI host controller
[ 2.389672] usb usb5: Manufacturer: Linux 4.19.0-rc8-00045-gc92161e11c2a ohci_hcd
[ 2.389674] usb usb5: SerialNumber: 0000:00:14.5
[ 2.392649] hub 5-0:1.0: USB hub found
[ 2.392711] hub 5-0:1.0: 2 ports detected
[ 2.392851] probe of 5-0:1.0 returned 1 after 224 usecs
[ 2.392914] probe of usb5 returned 1 after 408 usecs
[ 2.393075] probe of 0000:00:14.5 returned 1 after 59426 usecs
[ 2.413073] calling piix4_driver_init+0x0/0x1000 [i2c_piix4] @ 143
[ 2.413224] piix4_smbus 0000:00:14.0: SMBus Host Controller at 0xb00, revision 0
[ 2.413235] piix4_smbus 0000:00:14.0: Using register 0x2c for SMBus port selection
[ 2.414925] piix4_smbus 0000:00:14.0: Auxiliary SMBus Host Controller at 0x8060
[ 2.414993] probe of 0000:00:14.0 returned 1 after 1890 usecs
[ 2.415040] initcall piix4_driver_init+0x0/0x1000 [i2c_piix4] returned 0 after 1908 usecs
[ 2.464953] calling rtl8169_pci_driver_init+0x0/0x1000 [r8169] @ 144
[ 2.472719] calling k10temp_driver_init+0x0/0x1000 [k10temp] @ 143
[ 2.472901] probe of 0000:00:18.3 returned 1 after 142 usecs
[ 2.472948] initcall k10temp_driver_init+0x0/0x1000 [k10temp] returned 0 after 212 usecs
[ 2.476329] calling phy_module_init+0x0/0x1000 [realtek] @ 165
[ 2.476579] initcall phy_module_init+0x0/0x1000 [realtek] returned 0 after 234 usecs
[ 2.477229] probe of r8169-300:00 returned 1 after 52 usecs
[ 2.477246] libphy: r8169: probed
[ 2.478329] r8169 0000:03:00.0 eth0: RTL8168e/8111e, bc:5f:f4:c8:d3:98, XID 2c200000, IRQ 28
[ 2.478335] r8169 0000:03:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[ 2.478416] probe of 0000:03:00.0 returned 1 after 13424 usecs
[ 2.478470] initcall rtl8169_pci_driver_init+0x0/0x1000 [r8169] returned 0 after 2069 usecs
[ 2.677415] usb 3-2: new low-speed USB device number 2 using ohci-pci
[ 2.869665] usb 3-2: New USB device found, idVendor=1241, idProduct=1122, bcdDevice= 1.00
[ 2.869672] usb 3-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 2.879346] input: HID 1241:1122 as /devices/pci0000:00/0000:00:12.0/usb3/3-2/3-2:1.0/0003:1241:1122.0001/input/input14
[ 2.883355] hid-generic 0003:1241:1122.0001: input,hidraw0: USB HID v1.00 Mouse [HID 1241:1122] on usb-0000:00:12.0-2/input0
[ 2.883396] probe of 0003:1241:1122.0001 returned 1 after 4404 usecs
[ 2.883426] probe of 3-2:1.0 returned 1 after 11761 usecs
[ 2.883492] probe of 3-2 returned 1 after 13572 usecs
[ 3.118169] r8169 0000:03:00.0 enp3s0: renamed from eth0
[ 3.134086] r8169 0000:03:00.0: Direct firmware load for rtl_nic/rtl8168e-2.fw failed with error -2
[ 3.134098] r8169 0000:03:00.0 enp3s0: unable to load firmware patch rtl_nic/rtl8168e-2.fw (-2)
[ 3.134609] RTL8211DN Gigabit Ethernet r8169-300:00: attached PHY driver [RTL8211DN Gigabit Ethernet] (mii_bus:phy_addr=r8169-300:00, irq=IGNORE)
[ 3.232946] IPv6: ADDRCONF(NETDEV_UP): enp3s0: link is not ready
[ 3.234827] r8169 0000:03:00.0 enp3s0: Link is Down
[ 4.585880] random: crng init done
[ 4.585890] random: 7 urandom warning(s) missed due to ratelimiting
[ 5.741952] r8169 0000:03:00.0 enp3s0: Link is Up - 1Gbps/Full - flow control rx/tx
[ 5.741975] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0: link becomes ready
[-- Warning: decoded text below may be mangled, UTF-8 assumed --]
[-- Attachment #1.3: 0001-init-main.c-Add-time-stamps.patch --]
[-- Type: text/x-patch; name="0001-init-main.c-Add-time-stamps.patch", Size: 4445 bytes --]
From 3ebdcee064f301de1fe635a50e5f941fd2050094 Mon Sep 17 00:00:00 2001
From: Paul Menzel <pmenzel@molgen.mpg.de>
Date: Mon, 8 Oct 2018 00:38:21 +0200
Subject: [PATCH] init/main.c: Add time stamps
Signed-off-by: Paul Menzel <pmenzel@molgen.mpg.de>
---
init/main.c | 26 ++++++++++++++++++++++++++
1 file changed, 26 insertions(+)
diff --git a/init/main.c b/init/main.c
index 18f8f0140fa0..60f35c716552 100644
--- a/init/main.c
+++ b/init/main.c
@@ -400,6 +400,7 @@ static noinline void __ref rest_init(void)
int pid;
rcu_scheduler_starting();
+ pr_info("After rcu_scheduler_starting\n");
/*
* We need to spawn init first so that it obtains pid 1, however
* the init task will end up wanting to create kthreads, which, if
@@ -413,14 +414,18 @@ static noinline void __ref rest_init(void)
*/
rcu_read_lock();
tsk = find_task_by_pid_ns(pid, &init_pid_ns);
+ pr_info("After find_task_by_pid_ns\n");
set_cpus_allowed_ptr(tsk, cpumask_of(smp_processor_id()));
rcu_read_unlock();
numa_default_policy();
+ pr_info("After numa_default_policy\n");
pid = kernel_thread(kthreadd, NULL, CLONE_FS | CLONE_FILES);
rcu_read_lock();
+ pr_info("After rcu_read_lock\n");
kthreadd_task = find_task_by_pid_ns(pid, &init_pid_ns);
rcu_read_unlock();
+ pr_info("After rcu_read_unlock\n");
/*
* Enable might_sleep() and smp_processor_id() checks.
@@ -432,14 +437,17 @@ static noinline void __ref rest_init(void)
system_state = SYSTEM_SCHEDULING;
complete(&kthreadd_done);
+ pr_info("After kthreadd_done\n");
/*
* The boot idle thread must execute schedule()
* at least once to get things moving:
*/
schedule_preempt_disabled();
+ pr_info("After schedule_preempt_disabled\n");
/* Call into cpu_idle with preempt disabled */
cpu_startup_entry(CPUHP_ONLINE);
+ pr_info("After cpu_startup_entry\n");
}
/* Check for early params. */
@@ -550,19 +558,29 @@ asmlinkage __visible void __init start_kernel(void)
page_address_init();
pr_notice("%s", linux_banner);
setup_arch(&command_line);
+ pr_info("After setup_arch\n");
/*
* Set up the the initial canary and entropy after arch
* and after adding latent and command line entropy.
*/
add_latent_entropy();
+ pr_info("After add_latent_entropy\n");
add_device_randomness(command_line, strlen(command_line));
+ pr_info("After add_device_randomness\n");
boot_init_stack_canary();
+ pr_info("After boot_init_stack_canary\n");
mm_init_cpumask(&init_mm);
+ pr_info("After mm_init_cpumask\n");
setup_command_line(command_line);
+ pr_info("After setup_command_line\n");
setup_nr_cpu_ids();
+ pr_info("After setup_nr_cpu_ids\n");
setup_per_cpu_areas();
+ pr_info("After setup_per_cpu_areas\n");
smp_prepare_boot_cpu(); /* arch-specific boot-cpu hooks */
+ pr_info("After smp_perpare_boot_cpu\n");
boot_cpu_hotplug_init();
+ pr_info("After boot_cpu_hotplug_init\n");
build_all_zonelists(NULL);
page_alloc_init();
@@ -588,6 +606,7 @@ asmlinkage __visible void __init start_kernel(void)
sort_main_extable();
trap_init();
mm_init();
+ pr_info("After mm_init\n");
ftrace_init();
@@ -600,6 +619,7 @@ asmlinkage __visible void __init start_kernel(void)
* time - but meanwhile we still have a functioning scheduler.
*/
sched_init();
+ pr_info("After sched_init\n");
/*
* Disable preemption - early bootup scheduling is extremely
* fragile until we cpu_idle() for the first time.
@@ -624,6 +644,7 @@ asmlinkage __visible void __init start_kernel(void)
workqueue_init_early();
rcu_init();
+ pr_info("After rcu_init\n");
/* Trace events are available after this */
trace_init();
@@ -726,10 +747,14 @@ asmlinkage __visible void __init start_kernel(void)
delayacct_init();
check_bugs();
+ pr_info("After check_bugs\n");
acpi_subsystem_init();
+ pr_info("After acpi_subsystem_init\n");
arch_post_acpi_subsys_init();
+ pr_info("After arch_post_acpi_subsys_init\n");
sfi_init_late();
+ pr_info("After sfi_init_late\n");
if (efi_enabled(EFI_RUNTIME_SERVICES)) {
efi_free_boot_services();
@@ -737,6 +762,7 @@ asmlinkage __visible void __init start_kernel(void)
/* Do the rest non-__init'ed, we're now alive */
rest_init();
+ pr_info("After rest_init\n");
}
/* Call all constructor functions linked into the kernel. */
--
2.17.1
[-- Warning: decoded text below may be mangled, UTF-8 assumed --]
[-- Attachment #1.4: 0001-random-Debug-fast-init.patch --]
[-- Type: text/x-patch; name="0001-random-Debug-fast-init.patch", Size: 1224 bytes --]
From 256a313f4d92f3e45d526f048d54f23c077f11bc Mon Sep 17 00:00:00 2001
From: Paul Menzel <pmenzel@molgen.mpg.de>
Date: Tue, 3 Jul 2018 07:32:45 +0200
Subject: [PATCH] random: Debug fast init
---
drivers/char/random.c | 5 +++++
1 file changed, 5 insertions(+)
diff --git a/drivers/char/random.c b/drivers/char/random.c
index c75b6cdf0053..a28d0a23625e 100644
--- a/drivers/char/random.c
+++ b/drivers/char/random.c
@@ -855,6 +855,8 @@ static int crng_fast_load(const char *cp, size_t len)
unsigned long flags;
char *p;
+ pr_notice("random: 1\n");
+
if (!spin_trylock_irqsave(&primary_crng.lock, flags))
return 0;
if (crng_init != 0) {
@@ -862,11 +864,14 @@ static int crng_fast_load(const char *cp, size_t len)
return 0;
}
p = (unsigned char *) &primary_crng.state[4];
+ pr_notice("random: 2\n");
while (len > 0 && crng_init_cnt < CRNG_INIT_CNT_THRESH) {
p[crng_init_cnt % CHACHA20_KEY_SIZE] ^= *cp;
cp++; crng_init_cnt++; len--;
}
+ pr_notice("random: 3\n");
spin_unlock_irqrestore(&primary_crng.lock, flags);
+ pr_notice("random: 4\n");
if (crng_init_cnt >= CRNG_INIT_CNT_THRESH) {
invalidate_batched_entropy();
crng_init = 1;
--
2.17.1
[-- Attachment #2: S/MIME Cryptographic Signature --]
[-- Type: application/pkcs7-signature, Size: 5174 bytes --]
^ permalink raw reply related [flat|nested] 3+ messages in thread
* Re: What happens before or in `schedule_preempt_disabled()`?
2018-10-17 15:50 What happens before or in `schedule_preempt_disabled()`? Paul Menzel
@ 2018-10-17 16:39 ` Thomas Gleixner
2018-10-17 16:47 ` Paul Menzel
0 siblings, 1 reply; 3+ messages in thread
From: Thomas Gleixner @ 2018-10-17 16:39 UTC (permalink / raw)
To: Paul Menzel; +Cc: x86, linux-kernel
[-- Attachment #1: Type: text/plain, Size: 1602 bytes --]
Paul,
On Wed, 17 Oct 2018, Paul Menzel wrote:
>
> Please find the debug patches attached. The `random: %i` messages are from
> `crng_fast_load()`.
>
> My question is, how can I figure out, what happens in the code below?
>
> complete(&kthreadd_done);
> pr_info("After kthreadd_done\n");
>
> /*
> * The boot idle thread must execute schedule()
> * at least once to get things moving:
> */
> schedule_preempt_disabled();
> pr_info("After schedule_preempt_disabled\n");
>
> Looking at `schedule_preempt_disabled()`, it shouldn’t take much time, and I
> was unable to find out where `crng_fast_load()` is called from.
>
> I guess threading is somehow working at that point, and I would though that
> it is the `complete()` call, but wonder why the `After kthreadd_done` is
> printed earlier.
This creates the init thread from the idle thread with preemption
disabled. To get the init thread on the CPU the idle thread must schedule
voluntary, which it does by calling schedule_preempt_disabled(). So the
scheduler picks the init thread and that executes the init callbacks.
The random calls are invoked by add_interrupt_randomness() or
add_hwgenerator_randomness(). But they are probably not consuming all the
time.
The time is consumed by the initcalls which are required before bringing up
the (non boot) CPUs. See kernel_init_freeable() which is called from
kernel_init(). If you add initcall_debug to the command line you will get
more information at least about the functions which are marked with
initcall.
Thanks,
tglx
^ permalink raw reply [flat|nested] 3+ messages in thread
* Re: What happens before or in `schedule_preempt_disabled()`?
2018-10-17 16:39 ` Thomas Gleixner
@ 2018-10-17 16:47 ` Paul Menzel
0 siblings, 0 replies; 3+ messages in thread
From: Paul Menzel @ 2018-10-17 16:47 UTC (permalink / raw)
To: Thomas Gleixner; +Cc: x86, linux-kernel
[-- Attachment #1: Type: text/plain, Size: 2400 bytes --]
Dear Thomas,
As always thank you for the quick reply.
On 10/17/18 18:39, Thomas Gleixner wrote:
> On Wed, 17 Oct 2018, Paul Menzel wrote:
>>
>> Please find the debug patches attached. The `random: %i` messages are from
>> `crng_fast_load()`.
>>
>> My question is, how can I figure out, what happens in the code below?
>>
>> complete(&kthreadd_done);
>> pr_info("After kthreadd_done\n");
>>
>> /*
>> * The boot idle thread must execute schedule()
>> * at least once to get things moving:
>> */
>> schedule_preempt_disabled();
>> pr_info("After schedule_preempt_disabled\n");
>>
>> Looking at `schedule_preempt_disabled()`, it shouldn’t take much time, and I
>> was unable to find out where `crng_fast_load()` is called from.
>>
>> I guess threading is somehow working at that point, and I would though that
>> it is the `complete()` call, but wonder why the `After kthreadd_done` is
>> printed earlier.
>
> This creates the init thread from the idle thread with preemption
> disabled. To get the init thread on the CPU the idle thread must schedule
> voluntary, which it does by calling schedule_preempt_disabled(). So the
> scheduler picks the init thread and that executes the init callbacks.
>
> The random calls are invoked by add_interrupt_randomness() or
> add_hwgenerator_randomness(). But they are probably not consuming all the
> time.
>
> The time is consumed by the initcalls which are required before bringing up
> the (non boot) CPUs. See kernel_init_freeable() which is called from
> kernel_init(). If you add initcall_debug to the command line you will get
> more information at least about the functions which are marked with
> initcall.
Thank you for the quick reply. Just a note, that `initcall_debug` is present
on the Linux kernel command line. (I didn’t mention that the full output of
`dmesg` was attached to my message.
[ 0.078186] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.19.0-rc8-00045-gc92161e11c2a root=/dev/sda1 rw noisapnp cryptomgr.notests quiet initcall_debug init=/lib/systemd/systemd-bootchart iomem=relaxed apparmor=0 selinux=0 kmemleak=off initcall_blacklist=serial8250_init,init_graph_trace udev.children_max=2 udev.log_priority=info
Thank you again for the pointers. I’ll look into those.
Kind regards,
Paul
[-- Attachment #2: S/MIME Cryptographic Signature --]
[-- Type: application/pkcs7-signature, Size: 5174 bytes --]
^ permalink raw reply [flat|nested] 3+ messages in thread
end of thread, other threads:[~2018-10-17 16:47 UTC | newest]
Thread overview: 3+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2018-10-17 15:50 What happens before or in `schedule_preempt_disabled()`? Paul Menzel
2018-10-17 16:39 ` Thomas Gleixner
2018-10-17 16:47 ` Paul Menzel
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).