Skip to content
New issue

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

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

Already on GitHub? Sign in to your account

WSL 2 slow startup (~10s from cold start) #6765

Closed
xquangdang opened this issue Apr 6, 2021 · 24 comments
Closed

WSL 2 slow startup (~10s from cold start) #6765

xquangdang opened this issue Apr 6, 2021 · 24 comments
Labels
needs-investigation likely actionable and/or needs more investigation

Comments

@xquangdang
Copy link

xquangdang commented Apr 6, 2021

Expected behavior

WSL 2 start really slow on my new machine with a Ryzen 7 4800H and 16GB of ram. My prev machine with core i7 8750H start wsl2 almost immediately (~1-2s).

Actual behavior

WSL 2 startup took too long, ~8-10s. If I just leave the Windows terminal open with 1 WSL 2 tab for a few mins, create a new tab that took about 4-5s.

Environment

Microsoft Windows [Version 10.0.21343.1000]

WSL logs:

[ 0.000000] Linux version 5.4.91-microsoft-standard-WSL2 (oe-user@oe-host) (gcc version 9.3.0 (GCC)) #1 SMP Mon Jan 25 18:39:31 UTC 2021
[ 0.000000] Command line: initrd=\initrd.img panic=-1 nr_cpus=16 swiotlb=force pty.legacy_count=0
[ 0.000000] KERNEL supported cpus:
[ 0.000000] Intel GenuineIntel
[ 0.000000] AMD AuthenticAMD
[ 0.000000] Centaur CentaurHauls
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
[ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format.
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
[ 0.000000] BIOS-e820: [mem 0x00000000000e0000-0x00000000000e0fff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000001fffff] ACPI data
[ 0.000000] BIOS-e820: [mem 0x0000000000200000-0x00000000f7ffffff] usable
[ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x00000001c39fffff] usable
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] DMI not present or invalid.
[ 0.000000] Hypervisor detected: Microsoft Hyper-V
[ 0.000000] Hyper-V: features 0xae7f, privilege high: 0x3b8030, hints 0xc2c, misc 0xe0bed7b2
[ 0.000000] Hyper-V Host Build:21343-10.0-1-0.1000
[ 0.000000] Hyper-V: LAPIC Timer Frequency: 0x1e8480
[ 0.000000] tsc: Marking TSC unstable due to running on Hyper-V
[ 0.000000] Hyper-V: Using hypercall for remote TLB flush
[ 0.000000] clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
[ 0.000002] tsc: Detected 2894.463 MHz processor
[ 0.000011] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[ 0.000012] e820: remove [mem 0x000a0000-0x000fffff] usable
[ 0.000019] last_pfn = 0x1c3a00 max_arch_pfn = 0x400000000
[ 0.000055] MTRR default type: uncachable
[ 0.000055] MTRR fixed ranges enabled:
[ 0.000056] 00000-3FFFF write-back
[ 0.000057] 40000-7FFFF uncachable
[ 0.000057] 80000-8FFFF write-back
[ 0.000058] 90000-FFFFF uncachable
[ 0.000058] MTRR variable ranges enabled:
[ 0.000059] 0 base 000000000000 mask FFFF00000000 write-back
[ 0.000060] 1 base 000100000000 mask FFF000000000 write-back
[ 0.000060] 2 disabled
[ 0.000061] 3 disabled
[ 0.000061] 4 disabled
[ 0.000061] 5 disabled
[ 0.000062] 6 disabled
[ 0.000062] 7 disabled
[ 0.000076] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
[ 0.000094] last_pfn = 0xf8000 max_arch_pfn = 0x400000000
[ 0.000109] Using GB pages for direct mapping
[ 0.000570] RAMDISK: [mem 0x03035000-0x03043fff]
[ 0.000575] ACPI: Early table checksum verification disabled
[ 0.000588] ACPI: RSDP 0x00000000000E0000 000024 (v02 VRTUAL)
[ 0.000591] ACPI: XSDT 0x0000000000100000 000044 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000596] ACPI: FACP 0x0000000000101000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000600] ACPI: DSDT 0x00000000001011B8 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000)
[ 0.000602] ACPI: FACS 0x0000000000101114 000040
[ 0.000604] ACPI: OEM0 0x0000000000101154 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000606] ACPI: SRAT 0x000000000011F33C 0003B0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000608] ACPI: APIC 0x000000000011F6EC 0000C8 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000613] ACPI: Local APIC address 0xfee00000
[ 0.000869] Zone ranges:
[ 0.000870] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.000871] DMA32 [mem 0x0000000001000000-0x00000000ffffffff]
[ 0.000872] Normal [mem 0x0000000100000000-0x00000001c39fffff]
[ 0.000873] Movable zone start for each node
[ 0.000873] Early memory node ranges
[ 0.000874] node 0: [mem 0x0000000000001000-0x000000000009ffff]
[ 0.000875] node 0: [mem 0x0000000000200000-0x00000000f7ffffff]
[ 0.000875] node 0: [mem 0x0000000100000000-0x00000001c39fffff]
[ 0.001482] Zeroed struct page in unavailable ranges: 18273 pages
[ 0.001484] Initmem setup node 0 [mem 0x0000000000001000-0x00000001c39fffff]
[ 0.001485] On node 0 totalpages: 1816735
[ 0.001486] DMA zone: 59 pages used for memmap
[ 0.001487] DMA zone: 22 pages reserved
[ 0.001488] DMA zone: 3743 pages, LIFO batch:0
[ 0.001529] DMA32 zone: 16320 pages used for memmap
[ 0.001530] DMA32 zone: 1011712 pages, LIFO batch:63
[ 0.020259] Normal zone: 12520 pages used for memmap
[ 0.020261] Normal zone: 801280 pages, LIFO batch:63
[ 0.020940] ACPI: Local APIC address 0xfee00000
[ 0.020947] ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
[ 0.021917] IOAPIC[0]: apic_id 16, version 17, address 0xfec00000, GSI 0-23
[ 0.021923] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.021925] ACPI: IRQ9 used by override.
[ 0.021927] Using ACPI (MADT) for SMP configuration information
[ 0.021935] smpboot: Allowing 16 CPUs, 0 hotplug CPUs
[ 0.021945] [mem 0xf8000000-0xffffffff] available for PCI devices
[ 0.021946] Booting paravirtualized kernel on Hyper-V
[ 0.021948] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.189816] setup_percpu: NR_CPUS:256 nr_cpumask_bits:256 nr_cpu_ids:16 nr_node_ids:1
[ 0.190924] percpu: Embedded 50 pages/cpu s167256 r8192 d29352 u262144
[ 0.190933] pcpu-alloc: s167256 r8192 d29352 u262144 alloc=1*2097152
[ 0.190934] pcpu-alloc: [0] 00 01 02 03 04 05 06 07 [0] 08 09 10 11 12 13 14 15
[ 0.190952] Built 1 zonelists, mobility grouping on. Total pages: 1787814
[ 0.190954] Kernel command line: initrd=\initrd.img panic=-1 nr_cpus=16 swiotlb=force pty.legacy_count=0
[ 0.192845] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[ 0.193695] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[ 0.193759] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.219409] Memory: 4094392K/7266940K available (16390K kernel code, 1624K rwdata, 3180K rodata, 1572K init, 2216K bss, 229116K reserved, 0K cma-reserved)
[ 0.219786] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=16, Nodes=1
[ 0.219797] ftrace: allocating 44895 entries in 176 pages
[ 0.238233] rcu: Hierarchical RCU implementation.
[ 0.238235] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=16.
[ 0.238236] All grace periods are expedited (rcu_expedited).
[ 0.238236] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.238237] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=16
[ 0.240721] Using NULL legacy PIC
[ 0.240722] NR_IRQS: 16640, nr_irqs: 552, preallocated irqs: 0
[ 0.242121] random: crng done (trusting CPU's manufacturer)
[ 0.242150] Console: colour dummy device 80x25
[ 0.242152] printk: console [tty0] enabled
[ 0.242160] ACPI: Core revision 20190816
[ 0.242301] Failed to register legacy timer interrupt
[ 0.242302] APIC: Switch to symmetric I/O mode setup
[ 0.242303] Switched APIC routing to physical flat.
[ 0.242346] Hyper-V: Using IPI hypercalls
[ 0.242347] Hyper-V: Using enlightened APIC (xapic mode)
[ 0.242684] Calibrating delay loop (skipped), value calculated using timer frequency.. 5788.92 BogoMIPS (lpj=28944630)
[ 0.242687] pid_max: default: 32768 minimum: 301
[ 0.242711] LSM: Security Framework initializing
[ 0.242736] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 0.242749] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 0.243057] x86/cpu: User Mode Instruction Prevention (UMIP) activated
[ 0.243072] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 512
[ 0.243073] Last level dTLB entries: 4KB 2048, 2MB 2048, 4MB 1024, 1GB 0
[ 0.243076] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[ 0.243078] Spectre V2 : Mitigation: Full AMD retpoline
[ 0.243078] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 0.243078] Spectre V2 : Enabling Restricted Speculation for firmware calls
[ 0.243079] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[ 0.243080] Spectre V2 : User space: Mitigation: STIBP via seccomp and prctl
[ 0.243081] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
[ 0.243403] Freeing SMP alternatives memory: 48K
[ 0.243831] smpboot: CPU0: AMD Ryzen 7 4800H with Radeon Graphics (family: 0x17, model: 0x60, stepping: 0x1)
[ 0.243909] Performance Events: PMU not available due to virtualization, using software events only.
[ 0.244028] rcu: Hierarchical SRCU implementation.
[ 0.244288] smp: Bringing up secondary CPUs ...
[ 0.244406] x86: Booting SMP configuration:
[ 0.244408] .... node #0, CPUs: #1 #2 #3 #4 #5 #6 #7 #8 #9 #10 #11 #12 #13 #14 #15
[ 2.493402] smp: Brought up 1 node, 16 CPUs
[ 2.493403] smpboot: Max logical packages: 1
[ 2.493405] smpboot: Total of 16 processors activated (92647.73 BogoMIPS)
[ 2.505012] node 0 initialised, 735858 pages in 20ms
[ 2.513733] devtmpfs: initialized
[ 2.513733] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 2.513733] futex hash table entries: 4096 (order: 6, 262144 bytes, linear)
[ 2.513733] xor: automatically using best checksumming function avx
[ 2.513940] NET: Registered protocol family 16
[ 2.514254] ACPI: bus type PCI registered
[ 2.514254] PCI: Fatal: No config space access function found
[ 2.522787] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 2.532772] raid6: Forced to use recovery algorithm avx2x2
[ 2.532772] raid6: Forced gen() algo avx2x4
[ 2.532772] ACPI: Added _OSI(Module Device)
[ 2.532772] ACPI: Added _OSI(Processor Device)
[ 2.532772] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 2.532772] ACPI: Added _OSI(Processor Aggregator Device)
[ 2.532772] ACPI: Added _OSI(Linux-Dell-Video)
[ 2.532772] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[ 2.532772] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[ 2.538132] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 2.543022] ACPI: Interpreter enabled
[ 2.543027] ACPI: (supports S0 S5)
[ 2.543028] ACPI: Using IOAPIC for interrupt routing
[ 2.543039] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 2.543650] ACPI: Enabled 2 GPEs in block 00 to 0F
[ 2.547213] iommu: Default domain type: Translated
[ 2.547316] SCSI subsystem initialized
[ 2.547384] hv_vmbus: Vmbus version:5.0
[ 2.547384] PCI: Using ACPI for IRQ routing
[ 2.547384] PCI: System does not support PCI
[ 2.547384] hv_vmbus: Unknown GUID: c376c1c3-d276-48d2-90a9-c04748072c60
[ 2.547384] clocksource: Switched to clocksource hyperv_clocksource_tsc_page
[ 2.547384] hv_vmbus: Unknown GUID: 6e382d18-3336-4f4b-acc4-2b7703d4df4a
[ 2.547384] hv_vmbus: Unknown GUID: dde9cbc0-5060-4436-9448-ea1254a5d177
[ 2.679152] VFS: Disk quotas dquot_6.6.0
[ 2.679165] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 2.679183] FS-Cache: Loaded
[ 2.679207] pnp: PnP ACPI init
[ 2.679473] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 2.679527] pnp: PnP ACPI: found 1 devices
[ 2.697879] NET: Registered protocol family 2
[ 2.698253] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
[ 2.698273] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
[ 2.698869] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[ 2.699090] TCP: Hash tables configured (established 65536 bind 65536)
[ 2.699141] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
[ 2.699170] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
[ 2.699250] NET: Registered protocol family 1
[ 2.701654] RPC: Registered named UNIX socket transport module.
[ 2.701656] RPC: Registered udp transport module.
[ 2.701656] RPC: Registered tcp transport module.
[ 2.701657] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 2.701662] PCI: CLS 0 bytes, default 64
[ 2.701816] Trying to unpack rootfs image as initramfs...
[ 2.702330] Freeing initrd memory: 60K
[ 2.702336] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[ 2.702339] software IO TLB: mapped [mem 0xf4000000-0xf8000000] (64MB)
[ 2.710510] kvm: no hardware support
[ 2.715132] kvm: Nested Virtualization enabled
[ 2.715148] kvm: Nested Paging enabled
[ 2.715149] SVM: Virtual VMLOAD VMSAVE supported
[ 2.731630] Initialise system trusted keyrings
[ 2.732168] workingset: timestamp_bits=46 max_order=21 bucket_order=0
[ 2.734223] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 2.734952] NFS: Registering the id_resolver key type
[ 2.734969] Key type id_resolver registered
[ 2.734969] Key type id_legacy registered
[ 2.734973] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[ 2.737346] Key type cifs.idmap registered
[ 2.737692] fuse: init (API version 7.31)
[ 2.738406] SGI XFS with ACLs, security attributes, realtime, scrub, repair, no debug enabled
[ 2.739743] 9p: Installing v9fs 9p2000 file system support
[ 2.739765] FS-Cache: Netfs '9p' registered for caching
[ 2.739842] FS-Cache: Netfs 'ceph' registered for caching
[ 2.739847] ceph: loaded (mds proto 32)
[ 2.743075] NET: Registered protocol family 38
[ 2.743079] Key type asymmetric registered
[ 2.743080] Asymmetric key parser 'x509' registered
[ 2.743095] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 2.748201] hv_vmbus: registering driver hv_pci
[ 2.749643] hv_pci 06ab4550-cdf8-4ac6-a003-0a7163ce9489: PCI VMBus probing: Using version 0x10002
[ 2.752665] hv_pci 06ab4550-cdf8-4ac6-a003-0a7163ce9489: PCI host bridge to bus cdf8:00
[ 2.754436] pci cdf8:00:00.0: [1414:008e] type 00 class 0x030200
[ 2.783346] ACPI: AC Adapter [AC1] (off-line)
[ 2.784538] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 2.786110] battery: ACPI: Battery Slot [BAT1] (battery present)
[ 2.786351] Non-volatile memory driver v1.3
[ 2.798902] brd: module loaded
[ 2.803485] loop: module loaded
[ 2.803563] hv_vmbus: registering driver hv_storvsc
[ 2.803697] Ethernet Channel Bonding Driver: v3.7.1 (April 27, 2011)
[ 2.805092] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[ 2.805094] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld Jason@zx2c4.com. All Rights Reserved.
[ 2.805124] tun: Universal TUN/TAP device driver, 1.6
[ 2.805516] PPP generic driver version 2.4.2
[ 2.805878] PPP BSD Compression module registered
[ 2.805881] PPP Deflate Compression module registered
[ 2.805888] PPP MPPE Compression module registered
[ 2.805889] NET: Registered protocol family 24
[ 2.805901] hv_vmbus: registering driver hv_netvsc
[ 2.806408] VFIO - User Level meta-driver version: 0.3
[ 2.807028] hv_vmbus: registering driver hyperv_keyboard
[ 2.808616] rtc_cmos 00:00: RTC can wake from S4
[ 2.812448] scsi host0: storvsc_host_t
[ 2.840439] rtc_cmos 00:00: registered as rtc0
[ 2.840453] rtc_cmos 00:00: alarms up to one month, 114 bytes nvram
[ 2.841376] device-mapper: ioctl: 4.41.0-ioctl (2019-09-16) initialised: dm-devel@redhat.com
[ 2.841945] device-mapper: raid: Loading target version 1.14.0
[ 2.842271] hv_utils: Registering HyperV Utility Driver
[ 2.842273] hv_vmbus: registering driver hv_utils
[ 2.842393] hv_vmbus: registering driver hv_balloon
[ 2.842413] dxgk:err: dxg_drv_init Version: 5
[ 2.842420] hv_vmbus: registering driver dxgkrnl
[ 2.842492] hv_utils: cannot register PTP clock: 0
[ 2.843855] hv_utils: TimeSync IC version 4.0
[ 2.846112] drop_monitor: Initializing network drop monitor service
[ 2.846136] Mirror/redirect action on
[ 2.847161] IPVS: Registered protocols (TCP, UDP)
[ 2.847197] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[ 2.847368] hv_balloon: Using Dynamic Memory protocol version 2.0
[ 2.850314] hv_balloon: Cold memory discard enabled
[ 2.853475] IPVS: ipvs loaded.
[ 2.853480] IPVS: [rr] scheduler registered.
[ 2.853481] IPVS: [wrr] scheduler registered.
[ 2.853482] IPVS: [sh] scheduler registered.
[ 2.853596] ipip: IPv4 and MPLS over IPv4 tunneling driver
[ 2.859602] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[ 2.862399] Initializing XFRM netlink socket
[ 2.862551] NET: Registered protocol family 10
[ 2.863564] Segment Routing with IPv6
[ 2.871500] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 2.871673] NET: Registered protocol family 17
[ 2.871705] Bridge firewalling registered
[ 2.871717] 8021q: 802.1Q VLAN Support v1.8
[ 2.871751] sctp: Hash tables configured (bind 256/256)
[ 2.871846] 9pnet: Installing 9P2000 support
[ 2.871864] Key type dns_resolver registered
[ 2.872379] Key type ceph registered
[ 2.873033] libceph: loaded (mon/osd proto 15/24)
[ 2.873037] hv_vmbus: registering driver hv_sock
[ 2.873401] NET: Registered protocol family 40
[ 2.873444] IPI shorthand broadcast: enabled
[ 2.873753] registered taskstats version 1
[ 2.873764] Loading compiled-in X.509 certificates
[ 2.874397] Btrfs loaded, crc32c=crc32c-generic
[ 2.877995] rtc_cmos 00:00: setting system clock to 2021-04-06T10:59:59 UTC (1617706799)
[ 2.878037] Unstable clock detected, switching default tracing clock to "global"
If you want to keep using the local clock, then add:
"trace_clock=local"
on the kernel command line
[ 2.886438] Freeing unused kernel image memory: 1572K
[ 2.913165] Write protecting the kernel read-only data: 22528k
[ 2.915765] Freeing unused kernel image memory: 1992K
[ 2.917231] Freeing unused kernel image memory: 916K
[ 2.917234] Run /init as init process
[ 3.623952] scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
[ 3.625255] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 3.629686] sd 0:0:0:0: [sda] 536870912 512-byte logical blocks: (275 GB/256 GiB)
[ 3.629688] sd 0:0:0:0: [sda] 4096-byte physical blocks
[ 3.630122] sd 0:0:0:0: [sda] Write Protect is off
[ 3.630127] sd 0:0:0:0: [sda] Mode Sense: 0f 00 00 00
[ 3.631986] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 3.813038] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 4.033746] EXT4-fs (sda): mounted filesystem with ordered data mode. Opts: (null)
[ 4.183816] sd 0:0:0:0: [sda] Attached SCSI disk
[ 4.496267] Adding 2097152k swap on /swap/file. Priority:-2 extents:1 across:2097152k
[ 5.643442] scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
[ 5.644821] sd 0:0:0:1: Attached scsi generic sg1 type 0
[ 5.645545] scsi 0:0:0:2: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
[ 5.646765] sd 0:0:0:2: Attached scsi generic sg2 type 0
[ 5.648208] sd 0:0:0:1: [sdb] 711544 512-byte logical blocks: (364 MB/347 MiB)
[ 5.648603] sd 0:0:0:1: [sdb] Write Protect is on
[ 5.648608] sd 0:0:0:1: [sdb] Mode Sense: 0f 00 80 00
[ 5.649437] sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ 5.649878] sd 0:0:0:2: [sdc] 536870912 512-byte logical blocks: (275 GB/256 GiB)
[ 5.649882] sd 0:0:0:2: [sdc] 4096-byte physical blocks
[ 5.650129] sd 0:0:0:2: [sdc] Write Protect is off
[ 5.650132] sd 0:0:0:2: [sdc] Mode Sense: 0f 00 00 00
[ 5.651113] sd 0:0:0:2: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 5.661064] sd 0:0:0:1: [sdb] Attached SCSI disk
[ 5.662326] EXT4-fs (sdb): mounted filesystem without journal. Opts: (null)
[ 5.662879] sd 0:0:0:2: [sdc] Attached SCSI disk
[ 6.295724] EXT4-fs (sdc): recovery complete
[ 6.297376] EXT4-fs (sdc): mounted filesystem with ordered data mode. Opts: discard,errors=remount-ro,data=ordered
[ 6.449378] hv_pci 4505998c-7367-4ad0-ba1d-bb85d00b909a: PCI VMBus probing: Using version 0x10002
[ 6.554176] hv_pci 4505998c-7367-4ad0-ba1d-bb85d00b909a: PCI host bridge to bus 7367:00
[ 6.554181] pci_bus 7367:00: root bus resource [mem 0xe00000000-0xe00002fff window]
[ 6.558458] pci 7367:00:00.0: [1af4:1049] type 00 class 0x010000
[ 6.569737] pci 7367:00:00.0: reg 0x10: [mem 0xe00000000-0xe00000fff 64bit]
[ 6.574224] pci 7367:00:00.0: reg 0x18: [mem 0xe00001000-0xe00001fff 64bit]
[ 6.578979] pci 7367:00:00.0: reg 0x20: [mem 0xe00002000-0xe00002fff 64bit]
[ 6.611437] pci 7367:00:00.0: BAR 0: assigned [mem 0xe00000000-0xe00000fff 64bit]
[ 6.614688] pci 7367:00:00.0: BAR 2: assigned [mem 0xe00001000-0xe00001fff 64bit]
[ 6.617984] pci 7367:00:00.0: BAR 4: assigned [mem 0xe00002000-0xe00002fff 64bit]
[ 6.717733] FS-Cache: Duplicate cookie detected
[ 6.717737] FS-Cache: O-cookie c=00000000ec19bcb3 [p=00000000c2e84d59 fl=222 nc=0 na=1]
[ 6.717739] FS-Cache: O-cookie d=00000000b4cb25a7 n=00000000f1bfd785
[ 6.717740] FS-Cache: O-key=[10] '34323934393337393433'
[ 6.717745] FS-Cache: N-cookie c=0000000049829d2e [p=00000000c2e84d59 fl=2 nc=0 na=1]
[ 6.717746] FS-Cache: N-cookie d=00000000b4cb25a7 n=00000000575349de
[ 6.717747] FS-Cache: N-key=[10] '34323934393337393433'
[ 7.292459] hv_pci 841ef9b2-85a0-406b-8cf0-7d306abedad8: PCI VMBus probing: Using version 0x10002
[ 7.296138] 9pnet_virtio: no channels available for device drvfs
[ 7.296148] WARNING: mount: waiting for virtio device...
[ 7.396638] 9pnet_virtio: no channels available for device drvfs
[ 7.440018] hv_pci 841ef9b2-85a0-406b-8cf0-7d306abedad8: PCI host bridge to bus 85a0:00
[ 7.440024] pci_bus 85a0:00: root bus resource [mem 0xe00004000-0xe00006fff window]
[ 7.444863] pci 85a0:00:00.0: [1af4:1049] type 00 class 0x010000
[ 7.456307] pci 85a0:00:00.0: reg 0x10: [mem 0xe00004000-0xe00004fff 64bit]
[ 7.461183] pci 85a0:00:00.0: reg 0x18: [mem 0xe00005000-0xe00005fff 64bit]
[ 7.465977] pci 85a0:00:00.0: reg 0x20: [mem 0xe00006000-0xe00006fff 64bit]
[ 7.490653] pci 85a0:00:00.0: BAR 0: assigned [mem 0xe00004000-0xe00004fff 64bit]
[ 7.492457] pci 85a0:00:00.0: BAR 2: assigned [mem 0xe00005000-0xe00005fff 64bit]
[ 7.495097] pci 85a0:00:00.0: BAR 4: assigned [mem 0xe00006000-0xe00006fff 64bit]
[ 7.497106] 9pnet_virtio: no channels available for device drvfs
[ 7.611616] hv_pci 72eb2353-018f-41cd-9951-192848570d7a: PCI VMBus probing: Using version 0x10002
[ 7.710003] hv_pci 72eb2353-018f-41cd-9951-192848570d7a: PCI host bridge to bus 018f:00
[ 7.710008] pci_bus 018f:00: root bus resource [mem 0xe00008000-0xe0000afff window]
[ 7.715032] pci 018f:00:00.0: [1af4:1049] type 00 class 0x010000
[ 7.721843] pci 018f:00:00.0: reg 0x10: [mem 0xe00008000-0xe00008fff 64bit]
[ 7.724697] pci 018f:00:00.0: reg 0x18: [mem 0xe00009000-0xe00009fff 64bit]
[ 7.727929] pci 018f:00:00.0: reg 0x20: [mem 0xe0000a000-0xe0000afff 64bit]
[ 7.757718] pci 018f:00:00.0: BAR 0: assigned [mem 0xe00008000-0xe00008fff 64bit]
[ 7.762831] pci 018f:00:00.0: BAR 2: assigned [mem 0xe00009000-0xe00009fff 64bit]
[ 7.767145] pci 018f:00:00.0: BAR 4: assigned [mem 0xe0000a000-0xe0000afff 64bit]
[ 7.879553] FS-Cache: Duplicate cookie detected
[ 7.879558] FS-Cache: O-cookie c=000000001751915e [p=00000000c2e84d59 fl=222 nc=0 na=1]
[ 7.879559] FS-Cache: O-cookie d=00000000b4cb25a7 n=00000000ca3f7c5c
[ 7.879560] FS-Cache: O-key=[10] '34323934393338303539'
[ 7.879564] FS-Cache: N-cookie c=0000000006bd3929 [p=00000000c2e84d59 fl=2 nc=0 na=1]
[ 7.879564] FS-Cache: N-cookie d=00000000b4cb25a7 n=00000000de1ce056
[ 7.879565] FS-Cache: N-key=[10] '34323934393338303539'
[ 7.994042] traps: gsettings-helpe[217] trap int3 ip:7f3724f3e215 sp:7ffc6a0d95b0 error:0 in libglib-2.0.so.0.5800.0[7f3724f04000+80000]
[ 7.994050] potentially unexpected fatal signal 5.
[ 7.994052] CPU: 4 PID: 217 Comm: gsettings-helpe Not tainted 5.4.91-microsoft-standard-WSL2 #1
[ 7.994058] RIP: 0033:0x7f3724f3e215
[ 7.994061] Code: 48 83 c4 08 5d 41 5c c3 66 66 2e 0f 1f 84 00 00 00 00 00 66 90 53 89 fb e8 48 f6 01 00 85 c0 75 0b 85 db 0f 84 26 79 fc ff cc <5b> c3 bf 01 00 00 00 e8 cf 6c fc ff 66 66 2e 0f 1f 84 00 00 00 00
[ 7.994065] RSP: 002b:00007ffc6a0d95b0 EFLAGS: 00000202
[ 7.994067] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000
[ 7.994068] RDX: 0000000000000001 RSI: 0000000000000000 RDI: 0000000000000001
[ 7.994070] RBP: 0000000000000006 R08: 0000000000000000 R09: 0000000000000061
[ 7.994072] R10: 000056036261a650 R11: 0000000000000246 R12: 0000000000000002
[ 7.994074] R13: 000056036261a520 R14: 0000000000000004 R15: 00007f372518b05a
[ 7.994075] FS: 00007f37246db500 GS: 0000000000000000
[ 8.032423] hv_pci dd99397f-d558-4788-8c55-75b904ac9cc1: PCI VMBus probing: Using version 0x10002
[ 8.036380] 9pnet_virtio: no channels available for device drvfs
[ 8.036386] WARNING: mount: waiting for virtio device...
[ 8.101287] hv_pci dd99397f-d558-4788-8c55-75b904ac9cc1: PCI host bridge to bus d558:00
[ 8.101290] pci_bus d558:00: root bus resource [mem 0xe0000c000-0xe0000efff window]
[ 8.103797] pci d558:00:00.0: [1af4:1049] type 00 class 0x010000
[ 8.109797] pci d558:00:00.0: reg 0x10: [mem 0xe0000c000-0xe0000cfff 64bit]
[ 8.113479] pci d558:00:00.0: reg 0x18: [mem 0xe0000d000-0xe0000dfff 64bit]
[ 8.115882] pci d558:00:00.0: reg 0x20: [mem 0xe0000e000-0xe0000efff 64bit]
[ 8.133126] pci d558:00:00.0: BAR 0: assigned [mem 0xe0000c000-0xe0000cfff 64bit]
[ 8.134951] pci d558:00:00.0: BAR 2: assigned [mem 0xe0000d000-0xe0000dfff 64bit]
[ 8.136682] pci d558:00:00.0: BAR 4: assigned [mem 0xe0000e000-0xe0000efff 64bit]
[ 8.136870] 9pnet_virtio: no channels available for device drvfs
[ 8.247184] hv_pci 4c574c27-4a97-4f75-a80e-93a827e90f3b: PCI VMBus probing: Using version 0x10002
[ 8.318736] hv_pci 4c574c27-4a97-4f75-a80e-93a827e90f3b: PCI host bridge to bus 4a97:00
[ 8.318739] pci_bus 4a97:00: root bus resource [mem 0xe00010000-0xe00012fff window]
[ 8.321079] pci 4a97:00:00.0: [1af4:1049] type 00 class 0x010000
[ 8.327159] pci 4a97:00:00.0: reg 0x10: [mem 0xe00010000-0xe00010fff 64bit]
[ 8.329777] pci 4a97:00:00.0: reg 0x18: [mem 0xe00011000-0xe00011fff 64bit]
[ 8.332310] pci 4a97:00:00.0: reg 0x20: [mem 0xe00012000-0xe00012fff 64bit]
[ 8.352042] pci 4a97:00:00.0: BAR 0: assigned [mem 0xe00010000-0xe00010fff 64bit]
[ 8.354384] pci 4a97:00:00.0: BAR 2: assigned [mem 0xe00011000-0xe00011fff 64bit]
[ 8.356545] pci 4a97:00:00.0: BAR 4: assigned [mem 0xe00012000-0xe00012fff 64bit]

@therealkenc
Copy link
Collaborator

Spiritually dupe #5289 unless something else particularly stands out when comparing the dmesg output from your i7 side-by-side with your new Ryzen.

@benhillis
Copy link
Member

Virtio9p (the PCI prints) does take a bit longer than the hvsocket-based 9p. It looks like you also have the GUI apps package installed which does make boot a bit slower. We're working on resolving both of these.

@benhillis
Copy link
Member

@xquangdang - Could you try this on the most recent insider build with the updated wslg package?

@therealkenc
Copy link
Collaborator

therealkenc commented Apr 28, 2021

I happen to have a 3950x. 9 seconds with bleeding edge. Gist here if it helps.

image

6 seconds on 21370 w/ kernel 5.10.16.3.

@benhillis benhillis added the needs-investigation likely actionable and/or needs more investigation label May 5, 2021
@PavelSosin-320
Copy link

PavelSosin-320 commented Jul 4, 2021

FYI ! with systemd-genie systemd-analyze "blames" 2 critical paths:

  1. very slow wslg start - 2sec
  2. extremely slow network stack start - about 2 sec total and holds other components until it is turned "ready". But this is a common Windows problem and gets worse from release to release. The outcome is "temporary failure in DNS resolution" which can take about forever. The impact of VPN has to be checked.
  3. Log content: 1.820182 IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready 2sec after the Kernel load.
  4. Some distros have their own issues with Kernel 5.10.xxx and due to dependency with graphics stack.
  5. Overall Windows boot performance for iGPU + dGPU configuration is hardly acceptable. It takes seconds until Win makes a decision about a preferable GPU.

@PavelSosin-320
Copy link

@benhillis I afraid to find at the bottom of the pit the
PowerPlan

SSD may start in a portion of ms, GPU with its cooling system in a portion of seconds, AC/XS WiFi adapter in seconds. Unfortunately, Windows unlike Linux doesn't keep track of subsystems startup time. 😂

@PavelSosin-320
Copy link

@benhillis Yet another critical path detected is LVM startup time. But it looks like a known problem with Kernel 5.10 ad some "Native" Linux distro like Suse and Fedora. WSL2 may need it for wsl -- mount and it is dup for slow mounted via wsl --mountdisks.
With a pre-heated WiFi adapter the massive Fedora34 distro with full OCI dev environment the full startup time takes respectable
systemd-analyze
Startup finished in 1.599s (userspace)
graphical.target reached after 1.457s in userspace.

@mprevot
Copy link

mprevot commented Jun 30, 2022

Does someone need some more investigation about this ? I see 40 seconds between startup and prompt.
How can I investigate this ?

Hardware is 9900k with 128GB RAM, 6 local drives and few network mounts.

[ 0.000000] Linux version 5.10.102.1-microsoft-standard-WSL2 (oe-user@oe-host) (x86_64-msft-linux-gcc (GCC) 9.3.0, GNU ld (GNU Binutils) 2.34.0.20200220) #1 SMP Wed Mar 2 00:30:59 UTC 2022
[ 0.000000] Command line: initrd=\initrd.img panic=-1 pty.legacy_count=0 nr_cpus=16
[ 0.000000] KERNEL supported cpus:
[ 0.000000] Intel GenuineIntel
[ 0.000000] AMD AuthenticAMD
[ 0.000000] Centaur CentaurHauls
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
[ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format.
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
[ 0.000000] BIOS-e820: [mem 0x00000000000e0000-0x00000000000e0fff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000001fffff] ACPI data
[ 0.000000] BIOS-e820: [mem 0x0000000000200000-0x00000000f7ffffff] usable
[ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000bffffffff] usable
[ 0.000000] BIOS-e820: [mem 0x0000001000000000-0x0000001d9d3fffff] usable
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] DMI not present or invalid.
[ 0.000000] Hypervisor detected: Microsoft Hyper-V
[ 0.000000] Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x20c2c, misc 0x20bed7b2
[ 0.000000] Hyper-V Host Build:19041-10.0-1-0.1766
[ 0.000000] Hyper-V: LAPIC Timer Frequency: 0x1e8480
[ 0.000000] Hyper-V: Using hypercall for remote TLB flush
[ 0.000000] clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
[ 0.000000] tsc: Marking TSC unstable due to running on Hyper-V
[ 0.000001] tsc: Detected 3600.010 MHz processor
[ 0.000008] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[ 0.000009] e820: remove [mem 0x000a0000-0x000fffff] usable
[ 0.000011] last_pfn = 0x1d9d400 max_arch_pfn = 0x400000000
[ 0.000023] MTRR default type: uncachable
[ 0.000023] MTRR fixed ranges enabled:
[ 0.000024] 00000-3FFFF write-back
[ 0.000024] 40000-7FFFF uncachable
[ 0.000024] 80000-8FFFF write-back
[ 0.000025] 90000-FFFFF uncachable
[ 0.000025] MTRR variable ranges enabled:
[ 0.000026] 0 base 0000000000 mask 7F00000000 write-back
[ 0.000026] 1 base 0100000000 mask 7000000000 write-back
[ 0.000027] 2 base 1000000000 mask 0000000000 write-back
[ 0.000027] 3 disabled
[ 0.000028] 4 disabled
[ 0.000028] 5 disabled
[ 0.000028] 6 disabled
[ 0.000028] 7 disabled
[ 0.000032] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
[ 0.000039] last_pfn = 0xf8000 max_arch_pfn = 0x400000000
[ 0.000046] Using GB pages for direct mapping
[ 0.000293] RAMDISK: [mem 0x03835000-0x03844fff]
[ 0.000294] ACPI: Early table checksum verification disabled
[ 0.000301] ACPI: RSDP 0x00000000000E0000 000024 (v02 VRTUAL)
[ 0.000304] ACPI: XSDT 0x0000000000100000 000044 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000307] ACPI: FACP 0x0000000000101000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000310] ACPI: DSDT 0x00000000001011B8 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000)
[ 0.000312] ACPI: FACS 0x0000000000101114 000040
[ 0.000313] ACPI: OEM0 0x0000000000101154 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000315] ACPI: SRAT 0x000000000011F33C 000310 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000317] ACPI: APIC 0x000000000011F64C 0000C8 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000318] ACPI: Reserving FACP table memory at [mem 0x101000-0x101113]
[ 0.000319] ACPI: Reserving DSDT table memory at [mem 0x1011b8-0x11f33b]
[ 0.000319] ACPI: Reserving FACS table memory at [mem 0x101114-0x101153]
[ 0.000320] ACPI: Reserving OEM0 table memory at [mem 0x101154-0x1011b7]
[ 0.000320] ACPI: Reserving SRAT table memory at [mem 0x11f33c-0x11f64b]
[ 0.000321] ACPI: Reserving APIC table memory at [mem 0x11f64c-0x11f713]
[ 0.000325] ACPI: Local APIC address 0xfee00000
[ 0.000515] Zone ranges:
[ 0.000516] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.000517] DMA32 [mem 0x0000000001000000-0x00000000ffffffff]
[ 0.000518] Normal [mem 0x0000000100000000-0x0000001d9d3fffff]
[ 0.000519] Device empty
[ 0.000519] Movable zone start for each node
[ 0.000520] Early memory node ranges
[ 0.000520] node 0: [mem 0x0000000000001000-0x000000000009ffff]
[ 0.000521] node 0: [mem 0x0000000000200000-0x00000000f7ffffff]
[ 0.000522] node 0: [mem 0x0000000100000000-0x0000000bffffffff]
[ 0.000523] node 0: [mem 0x0000001000000000-0x0000001d9d3fffff]
[ 0.000526] Initmem setup node 0 [mem 0x0000000000001000-0x0000001d9d3fffff]
[ 0.000527] On node 0 totalpages: 26825375
[ 0.000527] DMA zone: 59 pages used for memmap
[ 0.000528] DMA zone: 22 pages reserved
[ 0.000528] DMA zone: 3743 pages, LIFO batch:0
[ 0.000529] DMA32 zone: 16320 pages used for memmap
[ 0.000529] DMA32 zone: 1011712 pages, LIFO batch:63
[ 0.000530] Normal zone: 403280 pages used for memmap
[ 0.000530] Normal zone: 25809920 pages, LIFO batch:63
[ 0.000673] On node 0, zone DMA: 1 pages in unavailable ranges
[ 0.000692] On node 0, zone DMA: 352 pages in unavailable ranges
[ 0.010651] On node 0, zone Normal: 11264 pages in unavailable ranges
[ 0.010672] ACPI: Local APIC address 0xfee00000
[ 0.010676] ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
[ 0.010854] IOAPIC[0]: apic_id 16, version 17, address 0xfec00000, GSI 0-23
[ 0.010857] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.010858] ACPI: IRQ9 used by override.
[ 0.010859] Using ACPI (MADT) for SMP configuration information
[ 0.010863] smpboot: Allowing 16 CPUs, 0 hotplug CPUs
[ 0.010867] [mem 0xf8000000-0xffffffff] available for PCI devices
[ 0.010868] Booting paravirtualized kernel on Hyper-V
[ 0.010869] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.014235] setup_percpu: NR_CPUS:256 nr_cpumask_bits:256 nr_cpu_ids:16 nr_node_ids:1
[ 0.014711] percpu: Embedded 56 pages/cpu s190040 r8192 d31144 u262144
[ 0.014714] pcpu-alloc: s190040 r8192 d31144 u262144 alloc=1*2097152
[ 0.014716] pcpu-alloc: [0] 00 01 02 03 04 05 06 07 [0] 08 09 10 11 12 13 14 15
[ 0.014728] Hyper-V: PV spinlocks enabled
[ 0.014729] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
[ 0.014731] Built 1 zonelists, mobility grouping on. Total pages: 26405694
[ 0.014732] Kernel command line: initrd=\initrd.img panic=-1 pty.legacy_count=0 nr_cpus=16
[ 0.020981] Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear)
[ 0.024138] Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear)
[ 0.024251] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.041980] Memory: 4085908K/107301500K available (16404K kernel code, 2544K rwdata, 9048K rodata, 1580K init, 2772K bss, 1886388K reserved, 0K cma-reserved)
[ 0.042013] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=16, Nodes=1
[ 0.042018] ftrace: allocating 51665 entries in 202 pages
[ 0.053276] ftrace: allocated 202 pages with 4 groups
[ 0.053473] rcu: Hierarchical RCU implementation.
[ 0.053474] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=16.
[ 0.053475] Rude variant of Tasks RCU enabled.
[ 0.053475] Tracing variant of Tasks RCU enabled.
[ 0.053476] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.053476] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=16
[ 0.055699] Using NULL legacy PIC
[ 0.055700] NR_IRQS: 16640, nr_irqs: 552, preallocated irqs: 0
[ 0.056038] random: crng done (trusting CPU's manufacturer)
[ 0.056054] Console: colour dummy device 80x25
[ 0.056059] printk: console [tty0] enabled
[ 0.056064] ACPI: Core revision 20200925
[ 0.056185] Failed to register legacy timer interrupt
[ 0.056185] APIC: Switch to symmetric I/O mode setup
[ 0.056187] Switched APIC routing to physical flat.
[ 0.056198] Hyper-V: Using IPI hypercalls
[ 0.056198] Hyper-V: Using enlightened APIC (xapic mode)
[ 0.056253] Calibrating delay loop (skipped), value calculated using timer frequency.. 7200.02 BogoMIPS (lpj=36000100)
[ 0.056255] pid_max: default: 32768 minimum: 301
[ 0.056269] LSM: Security Framework initializing
[ 0.056341] Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.056551] Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.056742] Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8
[ 0.056743] Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4
[ 0.056745] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[ 0.056745] Spectre V2 : Mitigation: Full generic retpoline
[ 0.056746] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 0.056746] Spectre V2 : Enabling Restricted Speculation for firmware calls
[ 0.056747] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[ 0.056747] Spectre V2 : User space: Mitigation: STIBP via seccomp and prctl
[ 0.056748] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
[ 0.056749] TAA: Vulnerable: Clear CPU buffers attempted, no microcode
[ 0.056750] SRBDS: Unknown: Dependent on hypervisor status
[ 0.056750] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[ 0.056852] Freeing SMP alternatives memory: 56K
[ 0.057709] smpboot: CPU0: Intel(R) Core(TM) i9-9900K CPU @ 3.60GHz (family: 0x6, model: 0x9e, stepping: 0xc)
[ 0.057763] Performance Events: unsupported p6 CPU model 158 no PMU driver, software events only.
[ 0.057777] rcu: Hierarchical SRCU implementation.
[ 0.058066] smp: Bringing up secondary CPUs ...
[ 0.058104] x86: Booting SMP configuration:
[ 0.058105] .... node #0, CPUs: #1
[ 0.058391] MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details.
[ 0.058391] TAA CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/tsx_async_abort.html for more details.
[ 0.058391] #2 #3 #4 #5 #6 #7 #8 #9 #10 #11 #12 #13 #14 #15
[ 0.058391] smp: Brought up 1 node, 16 CPUs
[ 0.058391] smpboot: Max logical packages: 1
[ 0.058391] smpboot: Total of 16 processors activated (115200.32 BogoMIPS)
[ 0.176277] node 0 deferred pages initialised in 120ms
[ 0.177206] devtmpfs: initialized
[ 0.177206] x86/mm: Memory block size: 128MB
[ 0.178294] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.178294] futex hash table entries: 4096 (order: 6, 262144 bytes, linear)
[ 0.178294] NET: Registered protocol family 16
[ 0.178294] thermal_sys: Registered thermal governor 'step_wise'
[ 0.178294] cpuidle: using governor menu
[ 0.178294] ACPI: bus type PCI registered
[ 0.178294] PCI: Fatal: No config space access function found
[ 0.178294] Kprobes globally optimized
[ 0.178294] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[ 0.178294] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.178294] raid6: skip pq benchmark and using algorithm avx2x4
[ 0.178294] raid6: using avx2x2 recovery algorithm
[ 0.178294] ACPI: Added _OSI(Module Device)
[ 0.178294] ACPI: Added _OSI(Processor Device)
[ 0.178294] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.178294] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.178294] ACPI: Added _OSI(Linux-Dell-Video)
[ 0.178294] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[ 0.178294] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[ 0.187157] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 0.187778] ACPI: Interpreter enabled
[ 0.187780] ACPI: (supports S0 S5)
[ 0.187781] ACPI: Using IOAPIC for interrupt routing
[ 0.187785] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.187890] ACPI: Enabled 1 GPEs in block 00 to 0F
[ 0.188543] iommu: Default domain type: Translated
[ 0.188592] SCSI subsystem initialized
[ 0.188594] ACPI: bus type USB registered
[ 0.188600] usbcore: registered new interface driver usbfs
[ 0.188602] usbcore: registered new interface driver hub
[ 0.188608] usbcore: registered new device driver usb
[ 0.188630] hv_vmbus: Vmbus version:5.2
[ 0.188630] PCI: Using ACPI for IRQ routing
[ 0.188630] PCI: System does not support PCI
[ 0.188630] hv_vmbus: Unknown GUID: c376c1c3-d276-48d2-90a9-c04748072c60
[ 0.188630] clocksource: Switched to clocksource hyperv_clocksource_tsc_page
[ 0.304982] VFS: Disk quotas dquot_6.6.0
[ 0.304989] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.305001] FS-Cache: Loaded
[ 0.305017] pnp: PnP ACPI init
[ 0.305044] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 0.305082] pnp: PnP ACPI: found 1 devices
[ 0.309505] NET: Registered protocol family 2
[ 0.309750] IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[ 0.310889] tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[ 0.311110] TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[ 0.311715] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[ 0.311792] TCP: Hash tables configured (established 524288 bind 65536)
[ 0.311818] UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear)
[ 0.312095] UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear)
[ 0.312388] NET: Registered protocol family 1
[ 0.312939] RPC: Registered named UNIX socket transport module.
[ 0.312939] RPC: Registered udp transport module.
[ 0.312940] RPC: Registered tcp transport module.
[ 0.312940] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.312942] PCI: CLS 0 bytes, default 64
[ 0.312989] Trying to unpack rootfs image as initramfs...
[ 0.313155] Freeing initrd memory: 64K
[ 0.313157] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[ 0.313158] software IO TLB: mapped [mem 0x00000000f4000000-0x00000000f8000000] (64MB)
[ 0.313219] kvm: no hardware support
[ 0.313220] has_svm: not amd or hygon
[ 0.313220] kvm: no hardware support
[ 0.316023] Initialise system trusted keyrings
[ 0.316090] workingset: timestamp_bits=46 max_order=25 bucket_order=0
[ 0.316811] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.316924] NFS: Registering the id_resolver key type
[ 0.316931] Key type id_resolver registered
[ 0.316931] Key type id_legacy registered
[ 0.316933] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 0.316934] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[ 0.316935] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[ 0.317308] Key type cifs.idmap registered
[ 0.317351] fuse: init (API version 7.32)
[ 0.317447] SGI XFS with ACLs, security attributes, realtime, scrub, repair, quota, no debug enabled
[ 0.317650] 9p: Installing v9fs 9p2000 file system support
[ 0.317656] FS-Cache: Netfs '9p' registered for caching
[ 0.317683] FS-Cache: Netfs 'ceph' registered for caching
[ 0.317685] ceph: loaded (mds proto 32)
[ 0.322760] NET: Registered protocol family 38
[ 0.322761] xor: automatically using best checksumming function avx
[ 0.322763] Key type asymmetric registered
[ 0.322764] Asymmetric key parser 'x509' registered
[ 0.322915] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 0.324184] hv_vmbus: registering driver hv_pci
[ 0.324705] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 0.325088] Non-volatile memory driver v1.3
[ 0.327942] brd: module loaded
[ 0.328736] loop: module loaded
[ 0.328765] hv_vmbus: registering driver hv_storvsc
[ 0.329096] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[ 0.329096] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld Jason@zx2c4.com. All Rights Reserved.
[ 0.329105] tun: Universal TUN/TAP device driver, 1.6
[ 0.329176] PPP generic driver version 2.4.2
[ 0.329271] PPP BSD Compression module registered
[ 0.329272] PPP Deflate Compression module registered
[ 0.329274] PPP MPPE Compression module registered
[ 0.329274] NET: Registered protocol family 24
[ 0.329281] usbcore: registered new interface driver cdc_ether
[ 0.329286] usbcore: registered new interface driver cdc_ncm
[ 0.329286] hv_vmbus: registering driver hv_netvsc
[ 0.329374] VFIO - User Level meta-driver version: 0.3
[ 0.329505] usbcore: registered new interface driver cdc_acm
[ 0.329505] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[ 0.329512] usbcore: registered new interface driver ch341
[ 0.329513] usbserial: USB Serial support registered for ch341-uart
[ 0.329516] usbcore: registered new interface driver cp210x
[ 0.329518] usbserial: USB Serial support registered for cp210x
[ 0.329521] usbcore: registered new interface driver ftdi_sio
[ 0.329523] usbserial: USB Serial support registered for FTDI USB Serial Device
[ 0.329671] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[ 0.329673] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1
[ 0.329676] vhci_hcd: created sysfs vhci_hcd.0
[ 0.329878] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[ 0.329879] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.329879] usb usb1: Product: USB/IP Virtual Host Controller
[ 0.329880] usb usb1: Manufacturer: Linux 5.10.102.1-microsoft-standard-WSL2 vhci_hcd
[ 0.329880] usb usb1: SerialNumber: vhci_hcd.0
[ 0.330061] hub 1-0:1.0: USB hub found
[ 0.330117] hub 1-0:1.0: 8 ports detected
[ 0.330454] scsi host0: storvsc_host_t
[ 0.330479] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[ 0.330480] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2
[ 0.330600] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 0.330882] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
[ 0.330883] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.330883] usb usb2: Product: USB/IP Virtual Host Controller
[ 0.330884] usb usb2: Manufacturer: Linux 5.10.102.1-microsoft-standard-WSL2 vhci_hcd
[ 0.330884] usb usb2: SerialNumber: vhci_hcd.0
[ 0.331012] hub 2-0:1.0: USB hub found
[ 0.331056] hub 2-0:1.0: 8 ports detected
[ 0.331246] hv_vmbus: registering driver hyperv_keyboard
[ 0.331370] rtc_cmos 00:00: RTC can wake from S4
[ 0.332522] rtc_cmos 00:00: registered as rtc0
[ 0.332802] rtc_cmos 00:00: setting system clock to 2022-06-30T13:01:27 UTC (1656594087)
[ 0.332810] rtc_cmos 00:00: alarms up to one month, 114 bytes nvram
[ 0.333055] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[ 0.333206] device-mapper: raid: Loading target version 1.15.1
[ 0.333228] usbcore: registered new interface driver usbhid
[ 0.333229] usbhid: USB HID core driver
[ 0.333311] hv_utils: Registering HyperV Utility Driver
[ 0.333312] hv_vmbus: registering driver hv_utils
[ 0.333334] hv_vmbus: registering driver hv_balloon
[ 0.333338] hv_utils: cannot register PTP clock: 0
[ 0.333355] hv_vmbus: registering driver dxgkrnl
[ 0.333359] (NULL device *): dxgk: dxg_drv_init Version: 2216
[ 0.333373] drop_monitor: Initializing network drop monitor service
[ 0.333385] Mirror/redirect action on
[ 0.333767] hv_utils: TimeSync IC version 4.0
[ 0.333800] hv_balloon: Using Dynamic Memory protocol version 2.0
[ 0.333898] IPVS: Registered protocols (TCP, UDP)
[ 0.333924] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[ 0.333952] IPVS: ipvs loaded.
[ 0.333953] IPVS: [rr] scheduler registered.
[ 0.333954] IPVS: [wrr] scheduler registered.
[ 0.333954] IPVS: [sh] scheduler registered.
[ 0.334177] ipip: IPv4 and MPLS over IPv4 tunneling driver
[ 0.334374] Free page reporting enabled
[ 0.334375] hv_balloon: Cold memory discard hint enabled
[ 0.335752] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[ 0.336103] Initializing XFRM netlink socket
[ 0.336140] NET: Registered protocol family 10
[ 0.336341] Segment Routing with IPv6
[ 0.337334] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 0.337384] NET: Registered protocol family 17
[ 0.337396] Bridge firewalling registered
[ 0.337401] 8021q: 802.1Q VLAN Support v1.8
[ 0.337414] sctp: Hash tables configured (bind 2048/2048)
[ 0.337602] 9pnet: Installing 9P2000 support
[ 0.337612] Key type dns_resolver registered
[ 0.337615] Key type ceph registered
[ 0.337695] libceph: loaded (mon/osd proto 15/24)
[ 0.337739] NET: Registered protocol family 40
[ 0.337739] hv_vmbus: registering driver hv_sock
[ 0.337756] IPI shorthand broadcast: enabled
[ 0.337761] sched_clock: Marking stable (337402400, 328500)->(344566800, -6835900)
[ 0.337951] registered taskstats version 1
[ 0.337957] Loading compiled-in X.509 certificates
[ 0.338089] Btrfs loaded, crc32c=crc32c-generic
[ 0.340368] Freeing unused kernel image (initmem) memory: 1580K
[ 0.406412] Write protecting the kernel read-only data: 28672k
[ 0.407103] Freeing unused kernel image (text/rodata gap) memory: 2024K
[ 0.407504] Freeing unused kernel image (rodata/data gap) memory: 1192K
[ 0.407506] Run /init as init process
[ 0.407506] with arguments:
[ 0.407506] /init
[ 0.407507] with environment:
[ 0.407507] HOME=/
[ 0.407507] TERM=linux
[ 38.826209] hv_vmbus: Unknown GUID: 6e382d18-3336-4f4b-acc4-2b7703d4df4a
[ 38.826268] hv_vmbus: Unknown GUID: dde9cbc0-5060-4436-9448-ea1254a5d177
[ 38.828949] hv_pci ff4c2fda-0b6c-4969-aee1-ee6b25a26548: PCI VMBus probing: Using version 0x10003
[ 38.829810] hv_pci ff4c2fda-0b6c-4969-aee1-ee6b25a26548: PCI host bridge to bus 0b6c:00
[ 38.830071] pci 0b6c:00:00.0: [1414:008e] type 00 class 0x030200
[ 38.834793] (NULL device *): dxgk: mmio allocated c00000000 200000000 c00000000 dffffffff
[ 38.957277] scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
[ 38.957777] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 38.958799] sd 0:0:0:0: [sda] 536870912 512-byte logical blocks: (275 GB/256 GiB)
[ 38.958800] sd 0:0:0:0: [sda] 4096-byte physical blocks
[ 38.959023] sd 0:0:0:0: [sda] Write Protect is off
[ 38.959025] sd 0:0:0:0: [sda] Mode Sense: 0f 00 00 00
[ 38.959368] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 38.960707] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 39.242092] EXT4-fs (sda): mounted filesystem with ordered data mode. Opts: discard,errors=remount-ro,data=ordered
[ 39.346759] sd 0:0:0:0: [sda] Attached SCSI disk
[ 40.446435] Adding 27262976k swap on /swap/file. Priority:-2 extents:5 across:27295744k
[ 41.116585] scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
[ 41.117146] sd 0:0:0:1: Attached scsi generic sg1 type 0
[ 41.118205] sd 0:0:0:1: [sdb] 536870912 512-byte logical blocks: (275 GB/256 GiB)
[ 41.118206] sd 0:0:0:1: [sdb] 4096-byte physical blocks
[ 41.118328] sd 0:0:0:1: [sdb] Write Protect is off
[ 41.118329] sd 0:0:0:1: [sdb] Mode Sense: 0f 00 00 00
[ 41.118625] sd 0:0:0:1: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 41.121488] sd 0:0:0:1: [sdb] Attached SCSI disk
[ 41.133377] EXT4-fs (sdb): mounted filesystem with ordered data mode. Opts: discard,errors=remount-ro,data=ordered
[ 41.143652] FS-Cache: Duplicate cookie detected
[ 41.143653] FS-Cache: O-cookie c=00000000dd6b555d [p=000000001bc55327 fl=222 nc=0 na=1]
[ 41.143654] FS-Cache: O-cookie d=0000000083bfbaeb n=000000000f13ab36
[ 41.143654] FS-Cache: O-key=[10] '34323934393431343035'
[ 41.143657] FS-Cache: N-cookie c=0000000089a002d8 [p=000000001bc55327 fl=2 nc=0 na=1]
[ 41.143658] FS-Cache: N-cookie d=0000000083bfbaeb n=00000000a41eebbb
[ 41.143658] FS-Cache: N-key=[10] '34323934393431343035'
[ 41.269501] FS-Cache: Duplicate cookie detected
[ 41.269502] FS-Cache: O-cookie c=0000000068ab73f0 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 41.269503] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000cb304dd0
[ 41.269503] FS-Cache: O-key=[10] '34323934393431343138'
[ 41.269507] FS-Cache: N-cookie c=00000000f8ae9a8b [p=000000001bc55327 fl=2 nc=0 na=1]
[ 41.269507] FS-Cache: N-cookie d=0000000083bfbaeb n=00000000a974287d
[ 41.269507] FS-Cache: N-key=[10] '34323934393431343138'
[ 41.271220] FS-Cache: Duplicate cookie detected
[ 41.271221] FS-Cache: O-cookie c=0000000068ab73f0 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 41.271221] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000cb304dd0
[ 41.271222] FS-Cache: O-key=[10] '34323934393431343138'
[ 41.271225] FS-Cache: N-cookie c=000000008cce34cf [p=000000001bc55327 fl=2 nc=0 na=1]
[ 41.271225] FS-Cache: N-cookie d=0000000083bfbaeb n=000000007debbb98
[ 41.271226] FS-Cache: N-key=[10] '34323934393431343138'
[ 41.272900] FS-Cache: Duplicate cookie detected
[ 41.272901] FS-Cache: O-cookie c=0000000068ab73f0 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 41.272902] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000cb304dd0
[ 41.272902] FS-Cache: O-key=[10] '34323934393431343138'
[ 41.272905] FS-Cache: N-cookie c=00000000119ca479 [p=000000001bc55327 fl=2 nc=0 na=1]
[ 41.272906] FS-Cache: N-cookie d=0000000083bfbaeb n=000000005c433c92
[ 41.272906] FS-Cache: N-key=[10] '34323934393431343138'
[ 41.274450] FS-Cache: Duplicate cookie detected
[ 41.274451] FS-Cache: O-cookie c=0000000068ab73f0 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 41.274451] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000cb304dd0
[ 41.274452] FS-Cache: O-key=[10] '34323934393431343138'
[ 41.274455] FS-Cache: N-cookie c=00000000119ca479 [p=000000001bc55327 fl=2 nc=0 na=1]
[ 41.274456] FS-Cache: N-cookie d=0000000083bfbaeb n=00000000b4b79833
[ 41.274456] FS-Cache: N-key=[10] '34323934393431343138'
[ 41.275958] FS-Cache: Duplicate cookie detected
[ 41.275960] FS-Cache: O-cookie c=0000000068ab73f0 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 41.275960] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000cb304dd0
[ 41.275960] FS-Cache: O-key=[10] '34323934393431343138'
[ 41.275964] FS-Cache: N-cookie c=00000000119ca479 [p=000000001bc55327 fl=2 nc=0 na=1]
[ 41.275964] FS-Cache: N-cookie d=0000000083bfbaeb n=00000000d26055ac
[ 41.275964] FS-Cache: N-key=[10] '34323934393431343138'
[ 49.208537] hv_balloon: Max. dynamic memory size: 104788 MB
[ 68.910114] EXT4-fs (sdb): mounted filesystem with ordered data mode. Opts: discard,errors=remount-ro,data=ordered
[ 68.921484] FS-Cache: Duplicate cookie detected
[ 68.921486] FS-Cache: O-cookie c=00000000bc269de8 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 68.921487] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000c5f50706
[ 68.921487] FS-Cache: O-key=[10] '34323934393434313833'
[ 68.921490] FS-Cache: N-cookie c=00000000de05fe51 [p=000000001bc55327 fl=2 nc=0 na=1]
[ 68.921491] FS-Cache: N-cookie d=0000000083bfbaeb n=00000000d6f5c564
[ 68.921491] FS-Cache: N-key=[10] '34323934393434313833'
[ 69.049679] FS-Cache: Duplicate cookie detected
[ 69.049681] FS-Cache: O-cookie c=00000000720c1e76 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 69.049682] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000d4ae87e6
[ 69.049682] FS-Cache: O-key=[10] '34323934393434313936'
[ 69.049685] FS-Cache: N-cookie c=0000000089a002d8 [p=000000001bc55327 fl=2 nc=0 na=1]
[ 69.049686] FS-Cache: N-cookie d=0000000083bfbaeb n=000000008869727e
[ 69.049686] FS-Cache: N-key=[10] '34323934393434313936'
[ 69.051121] FS-Cache: Duplicate cookie detected
[ 69.051122] FS-Cache: O-cookie c=00000000720c1e76 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 69.051122] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000d4ae87e6
[ 69.051123] FS-Cache: O-key=[10] '34323934393434313936'
[ 69.051126] FS-Cache: N-cookie c=000000008cce34cf [p=000000001bc55327 fl=2 nc=0 na=1]
[ 69.051127] FS-Cache: N-cookie d=0000000083bfbaeb n=00000000080ff813
[ 69.051127] FS-Cache: N-key=[10] '34323934393434313936'
[ 69.052353] FS-Cache: Duplicate cookie detected
[ 69.052354] FS-Cache: O-cookie c=00000000720c1e76 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 69.052355] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000d4ae87e6
[ 69.052355] FS-Cache: O-key=[10] '34323934393434313936'
[ 69.052358] FS-Cache: N-cookie c=00000000f8ae9a8b [p=000000001bc55327 fl=2 nc=0 na=1]
[ 69.052359] FS-Cache: N-cookie d=0000000083bfbaeb n=000000001ecc1257
[ 69.052359] FS-Cache: N-key=[10] '34323934393434313936'
[ 69.053636] FS-Cache: Duplicate cookie detected
[ 69.053637] FS-Cache: O-cookie c=00000000720c1e76 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 69.053637] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000d4ae87e6
[ 69.053638] FS-Cache: O-key=[10] '34323934393434313936'
[ 69.053641] FS-Cache: N-cookie c=00000000f8ae9a8b [p=000000001bc55327 fl=2 nc=0 na=1]
[ 69.053641] FS-Cache: N-cookie d=0000000083bfbaeb n=00000000174e009c
[ 69.053642] FS-Cache: N-key=[10] '34323934393434313936'
[ 69.055108] FS-Cache: Duplicate cookie detected
[ 69.055109] FS-Cache: O-cookie c=00000000720c1e76 [p=000000001bc55327 fl=222 nc=0 na=1]
[ 69.055109] FS-Cache: O-cookie d=0000000083bfbaeb n=00000000d4ae87e6
[ 69.055110] FS-Cache: O-key=[10] '34323934393434313936'
[ 69.055113] FS-Cache: N-cookie c=00000000f8ae9a8b [p=000000001bc55327 fl=2 nc=0 na=1]
[ 69.055113] FS-Cache: N-cookie d=0000000083bfbaeb n=0000000023001571
[ 69.055114] FS-Cache: N-key=[10] '34323934393434313936'
[ 99.961212] WSL2: Performing memory compaction.

@moreje
Copy link

moreje commented Dec 22, 2022

same here, > 1 min to get the prompt at first start!
I can see warning in dmesg...

dmesg
[ 0.000000] Linux version 5.15.79.1-microsoft-standard-WSL2 (oe-user@oe-host) (x86_64-msft-linux-gcc (GCC) 9.3.0, GNU ld (GNU Binutils) 2.34.0.20200220) #1 SMP Wed Nov 23 01:01:46 UTC 2022
[ 0.000000] Command line: initrd=\initrd.img WSL_ROOT_INIT=1 panic=-1 nr_cpus=8 swiotlb=force console=hvc0 debug pty.legacy_count=0
[ 0.000000] KERNEL supported cpus:
[ 0.000000] Intel GenuineIntel
[ 0.000000] AMD AuthenticAMD
[ 0.000000] Centaur CentaurHauls
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'
[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
[ 0.000000] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64
[ 0.000000] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512
[ 0.000000] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024
[ 0.000000] x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format.
[ 0.000000] signal: max sigframe size: 3632
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
[ 0.000000] BIOS-e820: [mem 0x00000000000e0000-0x00000000000e0fff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000001fffff] ACPI data
[ 0.000000] BIOS-e820: [mem 0x0000000000200000-0x00000000f7ffffff] usable
[ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x00000001faffffff] usable
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] DMI not present or invalid.
[ 0.000000] Hypervisor detected: Microsoft Hyper-V
[ 0.000000] Hyper-V: privilege flags low 0xae7f, high 0x3b8030, hints 0xa4e24, misc 0xe4bed7b6
[ 0.000000] Hyper-V Host Build:22621-10.0-1-0.1037
[ 0.000000] Hyper-V: Nested features: 0x3e0101
[ 0.000000] Hyper-V: LAPIC Timer Frequency: 0x1e8480
[ 0.000000] Hyper-V: Using hypercall for remote TLB flush
[ 0.000000] clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
[ 0.000003] tsc: Detected 1497.603 MHz processor
[ 0.000014] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[ 0.000017] e820: remove [mem 0x000a0000-0x000fffff] usable
[ 0.000021] last_pfn = 0x1fb000 max_arch_pfn = 0x400000000
[ 0.000052] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
[ 0.000064] last_pfn = 0xf8000 max_arch_pfn = 0x400000000
[ 0.000074] Using GB pages for direct mapping
[ 0.000509] RAMDISK: [mem 0x03a35000-0x03c0efff]
[ 0.000512] ACPI: Early table checksum verification disabled
[ 0.000532] ACPI: RSDP 0x00000000000E0000 000024 (v02 VRTUAL)
[ 0.000537] ACPI: XSDT 0x0000000000100000 000044 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000544] ACPI: FACP 0x0000000000101000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000550] ACPI: DSDT 0x00000000001011B8 01E191 (v02 MSFTVM DSDT01 00000001 MSFT 05000000)
[ 0.000555] ACPI: FACS 0x0000000000101114 000040
[ 0.000558] ACPI: OEM0 0x0000000000101154 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000562] ACPI: SRAT 0x000000000011F349 000330 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000567] ACPI: APIC 0x000000000011F679 000088 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
[ 0.000570] ACPI: Reserving FACP table memory at [mem 0x101000-0x101113]
[ 0.000572] ACPI: Reserving DSDT table memory at [mem 0x1011b8-0x11f348]
[ 0.000573] ACPI: Reserving FACS table memory at [mem 0x101114-0x101153]
[ 0.000574] ACPI: Reserving OEM0 table memory at [mem 0x101154-0x1011b7]
[ 0.000575] ACPI: Reserving SRAT table memory at [mem 0x11f349-0x11f678]
[ 0.000576] ACPI: Reserving APIC table memory at [mem 0x11f679-0x11f700]
[ 0.000875] Zone ranges:
[ 0.000877] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.000880] DMA32 [mem 0x0000000001000000-0x00000000ffffffff]
[ 0.000882] Normal [mem 0x0000000100000000-0x00000001faffffff]
[ 0.000883] Device empty
[ 0.000885] Movable zone start for each node
[ 0.000886] Early memory node ranges
[ 0.000886] node 0: [mem 0x0000000000001000-0x000000000009ffff]
[ 0.000888] node 0: [mem 0x0000000000200000-0x00000000f7ffffff]
[ 0.000890] node 0: [mem 0x0000000100000000-0x00000001faffffff]
[ 0.000891] Initmem setup node 0 [mem 0x0000000000001000-0x00000001faffffff]
[ 0.001138] On node 0, zone DMA: 1 pages in unavailable ranges
[ 0.001163] On node 0, zone DMA: 352 pages in unavailable ranges
[ 0.017073] On node 0, zone Normal: 20480 pages in unavailable ranges
[ 0.017142] ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
[ 0.017649] IOAPIC[0]: apic_id 8, version 17, address 0xfec00000, GSI 0-23
[ 0.017657] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.017664] ACPI: Using ACPI (MADT) for SMP configuration information
[ 0.017666] TSC deadline timer available
[ 0.017668] smpboot: Allowing 8 CPUs, 0 hotplug CPUs
[ 0.017711] [mem 0xf8000000-0xffffffff] available for PCI devices
[ 0.017714] Booting paravirtualized kernel on Hyper-V
[ 0.017717] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.027084] setup_percpu: NR_CPUS:256 nr_cpumask_bits:256 nr_cpu_ids:8 nr_node_ids:1
[ 0.027613] percpu: Embedded 53 pages/cpu s177496 r8192 d31400 u262144
[ 0.027622] pcpu-alloc: s177496 r8192 d31400 u262144 alloc=1*2097152
[ 0.027646] pcpu-alloc: [0] 0 1 2 3 4 5 6 7
[ 0.027692] Hyper-V: PV spinlocks enabled
[ 0.027695] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
[ 0.027700] Built 1 zonelists, mobility grouping on. Total pages: 2010949
[ 0.027703] Kernel command line: initrd=\initrd.img WSL_ROOT_INIT=1 panic=-1 nr_cpus=8 swiotlb=force console=hvc0 debug pty.legacy_count=0
[ 0.027810] Unknown kernel command line parameters "WSL_ROOT_INIT=1", will be passed to user space.
[ 0.030229] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[ 0.031093] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[ 0.031263] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.056435] Memory: 4081508K/8174204K available (18452K kernel code, 2627K rwdata, 9760K rodata, 2028K init, 1876K bss, 254960K reserved, 0K cma-reserved)
[ 0.056493] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
[ 0.056507] ftrace: allocating 54585 entries in 214 pages
[ 0.103570] ftrace: allocated 214 pages with 5 groups
[ 0.103890] rcu: Hierarchical RCU implementation.
[ 0.103892] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8.
[ 0.103893] Rude variant of Tasks RCU enabled.
[ 0.103894] Tracing variant of Tasks RCU enabled.
[ 0.103895] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.103896] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8
[ 0.108021] Using NULL legacy PIC
[ 0.108024] NR_IRQS: 16640, nr_irqs: 488, preallocated irqs: 0
[ 0.108459] random: crng init done
[ 0.108484] Console: colour dummy device 80x25
[ 0.108493] ACPI: Core revision 20210730
[ 0.108569] Failed to register legacy timer interrupt
[ 0.108570] APIC: Switch to symmetric I/O mode setup
[ 0.111038] x2apic enabled
[ 0.114302] Switched APIC routing to physical x2apic.
[ 0.114330] Hyper-V: Using IPI hypercalls
[ 0.114392] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x15964a3c5e7, max_idle_ns: 440795236379 ns
[ 0.114401] Calibrating delay loop (skipped), value calculated using timer frequency.. 2995.20 BogoMIPS (lpj=14976030)
[ 0.114404] pid_max: default: 32768 minimum: 301
[ 0.114420] LSM: Security Framework initializing
[ 0.114426] landlock: Up and running.
[ 0.114472] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 0.114482] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 0.114719] x86/cpu: User Mode Instruction Prevention (UMIP) activated
[ 0.114773] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.114774] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.114778] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[ 0.114781] Spectre V2 : Mitigation: Enhanced IBRS
[ 0.114782] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 0.114782] Spectre V2 : Spectre v2 / PBRSB-eIBRS: Retire a single CALL on VMEXIT
[ 0.114783] RETBleed: Mitigation: Enhanced IBRS
[ 0.114784] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[ 0.114786] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
[ 0.114791] MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode
[ 0.114792] SRBDS: Unknown: Dependent on hypervisor status
[ 0.124397] Freeing SMP alternatives memory: 60K
[ 0.124397] smpboot: CPU0: Intel(R) Core(TM) i7-1065G7 CPU @ 1.30GHz (family: 0x6, model: 0x7e, stepping: 0x5)
[ 0.124397] Performance Events: AnyThread deprecated, Icelake events, 32-deep LBR, full-width counters, Intel PMU driver.
[ 0.124397] ... version: 5
[ 0.124397] ... bit width: 48
[ 0.124397] ... generic registers: 8
[ 0.124397] ... value mask: 0000ffffffffffff
[ 0.124397] ... max period: 00007fffffffffff
[ 0.124397] ... fixed-purpose events: 4
[ 0.124397] ... event mask: 0001000f000000ff
[ 0.124397] rcu: Hierarchical SRCU implementation.
[ 0.124397] smp: Bringing up secondary CPUs ...
[ 0.124397] x86: Booting SMP configuration:
[ 0.124397] .... node #0, CPUs: #1
[ 0.124397] MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details.
[ 0.124397] #2 #3 #4 #5 #6 #7
[ 0.124397] smp: Brought up 1 node, 8 CPUs
[ 0.124397] smpboot: Max logical packages: 1
[ 0.124397] smpboot: Total of 8 processors activated (23961.64 BogoMIPS)
[ 0.130886] node 0 deferred pages initialised in 10ms
[ 0.130941] devtmpfs: initialized
[ 0.130941] x86/mm: Memory block size: 128MB
[ 0.130941] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.130941] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
[ 0.130941] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 0.130941] thermal_sys: Registered thermal governor 'step_wise'
[ 0.134426] cpuidle: using governor menu
[ 0.134443] ACPI: bus type PCI registered
[ 0.134456] PCI: Fatal: No config space access function found
[ 0.135848] Kprobes globally optimized
[ 0.135879] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[ 0.135879] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.144460] raid6: skip pq benchmark and using algorithm avx512x4
[ 0.144460] raid6: using avx512x2 recovery algorithm
[ 0.144460] ACPI: Added _OSI(Module Device)
[ 0.144460] ACPI: Added _OSI(Processor Device)
[ 0.144460] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.144460] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.144460] ACPI: Added _OSI(Linux-Dell-Video)
[ 0.144460] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[ 0.144460] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[ 0.154713] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 0.155587] ACPI: Interpreter enabled
[ 0.155590] ACPI: PM: (supports S0 S5)
[ 0.155591] ACPI: Using IOAPIC for interrupt routing
[ 0.155598] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.155734] ACPI: Enabled 2 GPEs in block 00 to 0F
[ 0.156950] iommu: Default domain type: Translated
[ 0.156952] iommu: DMA domain TLB invalidation policy: lazy mode
[ 0.157030] SCSI subsystem initialized
[ 0.157039] ACPI: bus type USB registered
[ 0.157047] usbcore: registered new interface driver usbfs
[ 0.157051] usbcore: registered new interface driver hub
[ 0.157055] usbcore: registered new device driver usb
[ 0.157063] pps_core: LinuxPPS API ver. 1 registered
[ 0.157063] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it
[ 0.157065] PTP clock support registered
[ 0.157091] hv_vmbus: Vmbus version:5.3
[ 0.157091] PCI: Using ACPI for IRQ routing
[ 0.157091] PCI: System does not support PCI
[ 0.157091] hv_vmbus: Unknown GUID: c376c1c3-d276-48d2-90a9-c04748072c60
[ 0.157091] clocksource: Switched to clocksource tsc-early
[ 0.164741] VFS: Disk quotas dquot_6.6.0
[ 0.164751] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.164766] FS-Cache: Loaded
[ 0.164787] pnp: PnP ACPI init
[ 0.164968] pnp: PnP ACPI: found 1 devices
[ 0.173289] NET: Registered PF_INET protocol family
[ 0.173656] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.174685] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
[ 0.174695] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.174698] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
[ 0.174757] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[ 0.175087] TCP: Hash tables configured (established 65536 bind 65536)
[ 0.175113] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
[ 0.175126] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
[ 0.175157] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 0.175971] RPC: Registered named UNIX socket transport module.
[ 0.175974] RPC: Registered udp transport module.
[ 0.175975] RPC: Registered tcp transport module.
[ 0.175976] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.175979] PCI: CLS 0 bytes, default 64
[ 0.176007] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[ 0.176009] software IO TLB: mapped [mem 0x00000000f4000000-0x00000000f8000000] (64MB)
[ 0.176050] KVM: vmx: using Hyper-V Enlightened VMCS
[ 0.176053] Trying to unpack rootfs image as initramfs...
[ 0.177304] Freeing initrd memory: 1896K
[ 0.514393] kvm: already loaded the other module
[ 0.518938] Initialise system trusted keyrings
[ 0.519261] workingset: timestamp_bits=46 max_order=21 bucket_order=0
[ 0.520297] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.520791] NFS: Registering the id_resolver key type
[ 0.520811] Key type id_resolver registered
[ 0.520813] Key type id_legacy registered
[ 0.520816] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 0.520819] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[ 0.520821] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[ 0.522733] Key type cifs.idmap registered
[ 0.523000] fuse: init (API version 7.34)
[ 0.523527] SGI XFS with ACLs, security attributes, realtime, scrub, repair, quota, no debug enabled
[ 0.524487] 9p: Installing v9fs 9p2000 file system support
[ 0.524556] FS-Cache: Netfs '9p' registered for caching
[ 0.524622] FS-Cache: Netfs 'ceph' registered for caching
[ 0.524625] ceph: loaded (mds proto 32)
[ 0.539214] NET: Registered PF_ALG protocol family
[ 0.539219] xor: automatically using best checksumming function avx
[ 0.539222] Key type asymmetric registered
[ 0.539223] Asymmetric key parser 'x509' registered
[ 0.539267] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[ 0.540689] hv_vmbus: registering driver hv_pci
[ 0.541589] hv_pci 7a122d1d-f71d-44d4-a8c0-74567279369b: PCI VMBus probing: Using version 0x10004
[ 0.543467] hv_pci 7a122d1d-f71d-44d4-a8c0-74567279369b: PCI host bridge to bus f71d:00
[ 0.543469] pci_bus f71d:00: root bus resource [mem 0x9ffe00000-0x9ffe02fff window]
[ 0.543472] pci_bus f71d:00: No busn resource found for root bus, will use [bus 00-ff]
[ 0.544934] pci f71d:00:00.0: [1af4:1043] type 00 class 0x010000
[ 0.546493] pci f71d:00:00.0: reg 0x10: [mem 0x9ffe00000-0x9ffe00fff 64bit]
[ 0.547505] pci f71d:00:00.0: reg 0x18: [mem 0x9ffe01000-0x9ffe01fff 64bit]
[ 0.548531] pci f71d:00:00.0: reg 0x20: [mem 0x9ffe02000-0x9ffe02fff 64bit]
[ 0.554856] pci_bus f71d:00: busn_res: [bus 00-ff] end is updated to 00
[ 0.554865] pci f71d:00:00.0: BAR 0: assigned [mem 0x9ffe00000-0x9ffe00fff 64bit]
[ 0.555577] pci f71d:00:00.0: BAR 2: assigned [mem 0x9ffe01000-0x9ffe01fff 64bit]
[ 0.556099] pci f71d:00:00.0: BAR 4: assigned [mem 0x9ffe02000-0x9ffe02fff 64bit]
[ 0.556732] ACPI: AC: AC Adapter [AC1] (off-line)
[ 0.557638] ACPI: battery: Slot [BAT1] (battery present)
[ 0.561559] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 0.618353] Non-volatile memory driver v1.3
[ 0.618580] [drm] Initialized vgem 1.0.0 20120112 for vgem on minor 0
[ 0.620471] printk: console [hvc0] enabled
[ 0.622282] brd: module loaded
[ 0.623971] loop: module loaded
[ 0.624393] hv_vmbus: registering driver hv_storvsc
[ 0.625609] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[ 0.626665] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld Jason@zx2c4.com. All Rights Reserved.
[ 0.626745] scsi host0: storvsc_host_t
[ 0.627671] tun: Universal TUN/TAP device driver, 1.6
[ 0.628737] PPP generic driver version 2.4.2
[ 0.629441] PPP BSD Compression module registered
[ 0.629865] PPP Deflate Compression module registered
[ 0.630282] PPP MPPE Compression module registered
[ 0.630765] NET: Registered PF_PPPOX protocol family
[ 0.631273] usbcore: registered new interface driver cdc_ether
[ 0.631958] usbcore: registered new interface driver cdc_ncm
[ 0.632494] usbcore: registered new interface driver r8153_ecm
[ 0.633092] hv_vmbus: registering driver hv_netvsc
[ 0.634595] VFIO - User Level meta-driver version: 0.3
[ 0.635631] usbcore: registered new interface driver cdc_acm
[ 0.636379] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[ 0.637196] usbcore: registered new interface driver ch341
[ 0.637743] usbserial: USB Serial support registered for ch341-uart
[ 0.638397] usbcore: registered new interface driver cp210x
[ 0.638787] usbserial: USB Serial support registered for cp210x
[ 0.639261] usbcore: registered new interface driver ftdi_sio
[ 0.639772] usbserial: USB Serial support registered for FTDI USB Serial Device
[ 0.640850] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[ 0.641762] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1
[ 0.642733] vhci_hcd: created sysfs vhci_hcd.0
[ 0.643926] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[ 0.644906] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.645567] usb usb1: Product: USB/IP Virtual Host Controller
[ 0.646127] usb usb1: Manufacturer: Linux 5.15.79.1-microsoft-standard-WSL2 vhci_hcd
[ 0.646790] usb usb1: SerialNumber: vhci_hcd.0
[ 0.647472] hub 1-0:1.0: USB hub found
[ 0.647878] hub 1-0:1.0: 8 ports detected
[ 0.649168] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[ 0.649918] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2
[ 0.651469] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 0.653319] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[ 0.654036] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.654792] usb usb2: Product: USB/IP Virtual Host Controller
[ 0.655566] usb usb2: Manufacturer: Linux 5.15.79.1-microsoft-standard-WSL2 vhci_hcd
[ 0.656333] usb usb2: SerialNumber: vhci_hcd.0
[ 0.657379] hub 2-0:1.0: USB hub found
[ 0.657796] hub 2-0:1.0: 8 ports detected
[ 0.660544] hv_vmbus: registering driver hyperv_keyboard
[ 0.661497] rtc_cmos 00:00: RTC can wake from S4
[ 0.664442] rtc_cmos 00:00: registered as rtc0
[ 0.665434] rtc_cmos 00:00: setting system clock to 2022-12-22T18:57:57 UTC (1671735477)
[ 0.666057] rtc_cmos 00:00: alarms up to one month, 114 bytes nvram
[ 0.667268] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[ 0.668657] device-mapper: raid: Loading target version 1.15.1
[ 0.669432] usbcore: registered new interface driver usbhid
[ 0.670021] usbhid: USB HID core driver
[ 0.670499] hv_utils: Registering HyperV Utility Driver
[ 0.671035] hv_vmbus: registering driver hv_utils
[ 0.671557] hv_vmbus: registering driver hv_balloon
[ 0.672104] hv_utils: TimeSync IC version 4.0
[ 0.672393] hv_vmbus: registering driver dxgkrnl
[ 0.673088] hv_balloon: Using Dynamic Memory protocol version 2.0
[ 0.674331] drop_monitor: Initializing network drop monitor service
[ 0.675992] Mirror/redirect action on
[ 0.676756] Free page reporting enabled
[ 0.676831] u32 classifier
[ 0.677142] hv_balloon: Cold memory discard hint enabled with order 9
[ 0.677395] Performance counters on
[ 0.678366] input device check on
[ 0.678709] Actions configured
[ 0.680139] IPVS: Registered protocols (TCP, UDP)
[ 0.680685] IPVS: Connection hash table configured (size=4096, memory=32Kbytes)
[ 0.681481] IPVS: ipvs loaded.
[ 0.681911] IPVS: [rr] scheduler registered.
[ 0.682398] IPVS: [wrr] scheduler registered.
[ 0.682873] IPVS: [sh] scheduler registered.
[ 0.683956] ipip: IPv4 and MPLS over IPv4 tunneling driver
[ 0.684983] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[ 0.685686] Initializing XFRM netlink socket
[ 0.686421] NET: Registered PF_INET6 protocol family
[ 0.687744] Segment Routing with IPv6
[ 0.688236] In-situ OAM (IOAM) with IPv6
[ 0.688687] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 0.689475] NET: Registered PF_PACKET protocol family
[ 0.690106] Bridge firewalling registered
[ 0.690483] 8021q: 802.1Q VLAN Support v1.8
[ 0.690849] sctp: Hash tables configured (bind 256/256)
[ 0.691407] 9pnet: Installing 9P2000 support
[ 0.692164] Key type dns_resolver registered
[ 0.692685] Key type ceph registered
[ 0.693348] libceph: loaded (mon/osd proto 15/24)
[ 0.694137] NET: Registered PF_VSOCK protocol family
[ 0.694755] hv_vmbus: registering driver hv_sock
[ 0.695423] IPI shorthand broadcast: enabled
[ 0.695963] sched_clock: Marking stable (688735411, 5982800)->(723128700, -28410489)
[ 0.697569] registered taskstats version 1
[ 0.698896] Loading compiled-in X.509 certificates
[ 0.700860] Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no
[ 0.705077] Freeing unused kernel image (initmem) memory: 2028K
[ 0.774589] Write protecting the kernel read-only data: 30720k
[ 0.776029] Freeing unused kernel image (text/rodata gap) memory: 2024K
[ 0.776883] Freeing unused kernel image (rodata/data gap) memory: 480K
[ 0.777396] Run /init as init process
[ 0.777680] with arguments:
[ 0.777961] /init
[ 0.778150] with environment:
[ 0.778427] HOME=/
[ 0.778613] TERM=linux
[ 0.778802] WSL_ROOT_INIT=1
[ 0.977668] hv_vmbus: Unknown GUID: 6e382d18-3336-4f4b-acc4-2b7703d4df4a
[ 0.981164] hv_vmbus: Unknown GUID: dde9cbc0-5060-4436-9448-ea1254a5d177
[ 0.981971] hv_pci 5418748e-f074-4752-be2b-5543a744b4e8: PCI VMBus probing: Using version 0x10004
[ 0.984631] hv_pci 5418748e-f074-4752-be2b-5543a744b4e8: PCI host bridge to bus f074:00
[ 0.985653] pci_bus f074:00: No busn resource found for root bus, will use [bus 00-ff]
[ 0.987182] pci f074:00:00.0: [1414:008e] type 00 class 0x030200
[ 0.999045] pci_bus f074:00: busn_res: [bus 00-ff] end is updated to 00
[ 1.001699] hv_vmbus: Unknown GUID: 6e382d18-3336-4f4b-acc4-2b7703d4df4a
[ 1.002907] scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
[ 1.003346] hv_pci 58f09c14-6325-4782-804b-bebc0eceadfb: PCI VMBus probing: Using version 0x10004
[ 1.007452] hv_pci 58f09c14-6325-4782-804b-bebc0eceadfb: PCI host bridge to bus 6325:00
[ 1.007659] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 1.009006] pci_bus 6325:00: No busn resource found for root bus, will use [bus 00-ff]
[ 1.010443] sd 0:0:0:0: [sda] 743696 512-byte logical blocks: (381 MB/363 MiB)
[ 1.012653] sd 0:0:0:0: [sda] Write Protect is on
[ 1.012966] pci 6325:00:00.0: [1414:008e] type 00 class 0x030200
[ 1.014161] sd 0:0:0:0: [sda] Mode Sense: 0f 00 80 00
[ 1.015194] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ 1.026476] pci_bus 6325:00: busn_res: [bus 00-ff] end is updated to 00
[ 1.127481] hv_pci cb79be22-476b-4e83-a06e-ccaf26e88b73: PCI VMBus probing: Using version 0x10004
[ 1.130728] hv_pci cb79be22-476b-4e83-a06e-ccaf26e88b73: PCI host bridge to bus 476b:00
[ 1.131511] pci_bus 476b:00: root bus resource [mem 0x9ffe04000-0x9ffe06fff window]
[ 1.132267] pci_bus 476b:00: No busn resource found for root bus, will use [bus 00-ff]
[ 1.135433] pci 476b:00:00.0: [1af4:1049] type 00 class 0x010000
[ 1.140152] pci 476b:00:00.0: reg 0x10: [mem 0x9ffe04000-0x9ffe04fff 64bit]
[ 1.147541] pci 476b:00:00.0: reg 0x18: [mem 0x9ffe05000-0x9ffe05fff 64bit]
[ 1.154689] pci 476b:00:00.0: reg 0x20: [mem 0x9ffe06000-0x9ffe06fff 64bit]
[ 1.177070] pci_bus 476b:00: busn_res: [bus 00-ff] end is updated to 00
[ 1.178992] pci 476b:00:00.0: BAR 0: assigned [mem 0x9ffe04000-0x9ffe04fff 64bit]
[ 1.184625] pci 476b:00:00.0: BAR 2: assigned [mem 0x9ffe05000-0x9ffe05fff 64bit]
[ 1.188138] pci 476b:00:00.0: BAR 4: assigned [mem 0x9ffe06000-0x9ffe06fff 64bit]
[ 1.554535] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x15964a3c5e7, max_idle_ns: 440795236379 ns
[ 1.560652] clocksource: Switched to clocksource tsc
[ 1.635519] EXT4-fs (sda): mounted filesystem without journal. Opts: (null). Quota mode: none.
[ 1.745782] sd 0:0:0:0: [sda] Attached SCSI disk
[ 2.783452] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 3.225207] scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
[ 3.278172] sd 0:0:0:1: Attached scsi generic sg1 type 0
[ 3.303039] sd 0:0:0:1: [sdb] 4194312 512-byte logical blocks: (2.15 GB/2.00 GiB)
[ 3.340927] sd 0:0:0:1: [sdb] 4096-byte physical blocks
[ 3.347097] sd 0:0:0:1: [sdb] Write Protect is off
[ 3.349100] sd 0:0:0:1: [sdb] Mode Sense: 0f 00 00 00
[ 3.353032] sd 0:0:0:1: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 3.419991] sd 0:0:0:1: [sdb] Attached SCSI disk
[ 3.499583] hv_pci b8b20fd0-37e5-4d1c-a211-c0f9c0f1aa90: PCI VMBus probing: Using version 0x10004
[ 3.508791] hv_pci b8b20fd0-37e5-4d1c-a211-c0f9c0f1aa90: PCI host bridge to bus 37e5:00
[ 3.510806] pci_bus 37e5:00: root bus resource [mem 0xc00000000-0xe00001fff window]
[ 3.513310] pci_bus 37e5:00: No busn resource found for root bus, will use [bus 00-ff]
[ 3.520663] pci 37e5:00:00.0: [1af4:105a] type 00 class 0x088000
[ 3.523352] Adding 2097152k swap on /dev/sdb. Priority:-2 extents:1 across:2097152k
[ 3.532004] pci 37e5:00:00.0: reg 0x10: [mem 0xe00000000-0xe00000fff 64bit]
[ 3.543128] pci 37e5:00:00.0: reg 0x18: [mem 0xe00001000-0xe00001fff 64bit]
[ 3.551756] pci 37e5:00:00.0: reg 0x20: [mem 0xc00000000-0xdffffffff 64bit]
[ 3.569549] pci_bus 37e5:00: busn_res: [bus 00-ff] end is updated to 00
[ 3.571275] pci 37e5:00:00.0: BAR 4: assigned [mem 0xc00000000-0xdffffffff 64bit]
[ 3.579733] pci 37e5:00:00.0: BAR 0: assigned [mem 0xe00000000-0xe00000fff 64bit]
[ 3.589274] pci 37e5:00:00.0: BAR 2: assigned [mem 0xe00001000-0xe00001fff 64bit]
[ 3.632777] virtiofs virtio2: Cache len: 0x200000000 @ 0xc00000000
[ 3.721220] memmap_init_zone_device initialised 2097152 pages in 40ms
[ 3.758195] FS-Cache: Duplicate cookie detected
[ 3.759185] FS-Cache: O-cookie c=00000005 [p=00000002 fl=222 nc=0 na=1]
[ 3.760257] FS-Cache: O-cookie d=00000000c0c3f43d{9P.session} n=000000008230811e
[ 3.761728] FS-Cache: O-key=[10] '34323934393337363630'
[ 3.763261] FS-Cache: N-cookie c=00000006 [p=00000002 fl=2 nc=0 na=1]
[ 3.764843] FS-Cache: N-cookie d=00000000c0c3f43d{9P.session} n=00000000d4ee9470
[ 3.769920] FS-Cache: N-key=[10] '34323934393337363630'
[ 3.792831] scsi 0:0:0:2: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
[ 3.796511] sd 0:0:0:2: Attached scsi generic sg2 type 0
[ 3.799087] sd 0:0:0:2: [sdc] 536870912 512-byte logical blocks: (275 GB/256 GiB)
[ 3.801713] sd 0:0:0:2: [sdc] 4096-byte physical blocks
[ 3.803989] sd 0:0:0:2: [sdc] Write Protect is off
[ 3.805439] sd 0:0:0:2: [sdc] Mode Sense: 0f 00 00 00
[ 3.807079] sd 0:0:0:2: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 3.817341] sd 0:0:0:2: [sdc] Attached SCSI disk
[ 3.828241] EXT4-fs (sdc): mounted filesystem with ordered data mode. Opts: discard,errors=remount-ro,data=ordered. Quota mode: none.
[ 49.524010] hv_balloon: Max. dynamic memory size: 7984 MB
[ 151.995166] hv_pci ace788eb-48f3-4551-b73e-00cb068b60f5: PCI VMBus probing: Using version 0x10004
[ 151.998021] hv_pci ace788eb-48f3-4551-b73e-00cb068b60f5: PCI host bridge to bus 48f3:00
[ 151.998787] pci_bus 48f3:00: root bus resource [mem 0x9ffe08000-0x9ffe0afff window]
[ 151.999520] pci_bus 48f3:00: No busn resource found for root bus, will use [bus 00-ff]
[ 152.001553] pci 48f3:00:00.0: [1af4:1049] type 00 class 0x010000
[ 152.003512] pci 48f3:00:00.0: reg 0x10: [mem 0x9ffe08000-0x9ffe08fff 64bit]
[ 152.005090] pci 48f3:00:00.0: reg 0x18: [mem 0x9ffe09000-0x9ffe09fff 64bit]
[ 152.007605] pci 48f3:00:00.0: reg 0x20: [mem 0x9ffe0a000-0x9ffe0afff 64bit]
[ 152.013383] pci_bus 48f3:00: busn_res: [bus 00-ff] end is updated to 00
[ 152.014336] pci 48f3:00:00.0: BAR 0: assigned [mem 0x9ffe08000-0x9ffe08fff 64bit]
[ 152.015809] pci 48f3:00:00.0: BAR 2: assigned [mem 0x9ffe09000-0x9ffe09fff 64bit]
[ 152.017878] pci 48f3:00:00.0: BAR 4: assigned [mem 0x9ffe0a000-0x9ffe0afff 64bit]
[ 152.193635] hv_pci 30ae5498-226c-4f9a-8b89-4076026ba326: PCI VMBus probing: Using version 0x10004
[ 152.197243] hv_pci 30ae5498-226c-4f9a-8b89-4076026ba326: PCI host bridge to bus 226c:00
[ 152.198289] pci_bus 226c:00: root bus resource [mem 0x9ffe0c000-0x9ffe0efff window]
[ 152.199105] pci_bus 226c:00: No busn resource found for root bus, will use [bus 00-ff]
[ 152.201432] pci 226c:00:00.0: [1af4:1049] type 00 class 0x010000
[ 152.204814] pci 226c:00:00.0: reg 0x10: [mem 0x9ffe0c000-0x9ffe0cfff 64bit]
[ 152.207861] pci 226c:00:00.0: reg 0x18: [mem 0x9ffe0d000-0x9ffe0dfff 64bit]
[ 152.210252] pci 226c:00:00.0: reg 0x20: [mem 0x9ffe0e000-0x9ffe0efff 64bit]
[ 152.220100] pci_bus 226c:00: busn_res: [bus 00-ff] end is updated to 00
[ 152.221113] pci 226c:00:00.0: BAR 0: assigned [mem 0x9ffe0c000-0x9ffe0cfff 64bit]
[ 152.223297] pci 226c:00:00.0: BAR 2: assigned [mem 0x9ffe0d000-0x9ffe0dfff 64bit]
[ 152.225860] pci 226c:00:00.0: BAR 4: assigned [mem 0x9ffe0e000-0x9ffe0efff 64bit]
[ 152.432007] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[ 152.433098] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[ 152.433886] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[ 152.434944] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 152.436587] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[ 152.437515] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[ 152.438483] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[ 152.439373] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 155.706041] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.724883] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.726034] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.727046] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.728095] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.729057] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.730220] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.731184] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.732531] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.733987] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 156.735097] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[ 158.360930] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2

@dfoxg
Copy link

dfoxg commented Jan 4, 2023

Same here. Look at the delay in the unshorted output of dmesg after Adding 4194304k swap on /dev/sdb.

[    2.613965] EXT4-fs (sdc): mounted filesystem with ordered data mode. Opts: discard,errors=remount-ro,data=ordered. Quota mode: none.
[    2.619164] Adding 4194304k swap on /dev/sdb.  Priority:-2 extents:1 across:4194304k
[   49.196057] hv_balloon: Max. dynamic memory size: 16344 MB
[   65.950886] /sbin/ldconfig:
[   65.950889] /usr/lib/wsl/lib/libcuda.so.1 is not a symbolic link
[   66.025550] hv_pci b5a1914e-7eff-475d-aede-30c08f905603: PCI VMBus probing: Using version 0x10004
[   66.026447] 9pnet_virtio: no channels available for device drvfs

@ilya-bobyr
Copy link

ilya-bobyr commented Jan 5, 2023

Same here. Look at the delay in the unshorted output of dmesg after Adding 4194304k swap on /dev/sdb.

[    2.613965] EXT4-fs (sdc): mounted filesystem with ordered data mode. Opts: discard,errors=remount-ro,data=ordered. Quota mode: none.
[    2.619164] Adding 4194304k swap on /dev/sdb.  Priority:-2 extents:1 across:4194304k
[   49.196057] hv_balloon: Max. dynamic memory size: 16344 MB
[   65.950886] /sbin/ldconfig:
[   65.950889] /usr/lib/wsl/lib/libcuda.so.1 is not a symbolic link
[   66.025550] hv_pci b5a1914e-7eff-475d-aede-30c08f905603: PCI VMBus probing: Using version 0x10004
[   66.026447] 9pnet_virtio: no channels available for device drvfs

Surprisingly, in my case, addressing the /usr/lib/wsl/lib/libcuda.so.1 is not a symbolic link error helped speed up the startup time.
Here are the instructions: #5663 (comment)

I still see about ~40s between the addition of the swap and the hv_balloon messages.
I've tried reducing both memory and swap sizes for the VM, but it did not seem to make any differences.
.wslconfig is where those settings are specified.
I was wondering if the slowdown might have something to do with inaccessible mounted network devices that I have mapped to some of my disk drives.
It looks like there is another config file, wsl.config that is responsible for populating initial /mnt/ content.

It would be great to understand why that 40s delay is there, for sure.

@dfoxg
Copy link

dfoxg commented Jan 5, 2023

Thanks for the hint with the network share! After enabling the Network-Share server the startup of wsl2 takes only 5.8 seconds!

Did you figured out how you can ignore only one specific drive for automount? As far I can see, you are only be able to completely disable automount for all drives.

Also I will give the libcuba a try, maybe I'm able to speedup the boot even more.

@guaycuru
Copy link

guaycuru commented Jan 13, 2023

Thanks for the hint with the network share! After enabling the Network-Share server the startup of wsl2 takes only 5.8 seconds!

Did you figured out how you can ignore only one specific drive for automount? As far I can see, you are only be able to completely disable automount for all drives.

Also I will give the libcuba a try, maybe I'm able to speedup the boot even more.

How did you enable the Network-Share server?

Edit: Do you mean that after enabling the server that serves your network mapped drive wsl2 started to boot faster?

@dfoxg
Copy link

dfoxg commented Jan 13, 2023

Thanks for the hint with the network share! After enabling the Network-Share server the startup of wsl2 takes only 5.8 seconds!
Did you figured out how you can ignore only one specific drive for automount? As far I can see, you are only be able to completely disable automount for all drives.
Also I will give the libcuba a try, maybe I'm able to speedup the boot even more.

How did you enable the Network-Share server?

Edit: Do you mean that after enabling the server that serves your network mapped drive wsl2 started to boot faster?

Yes, exactly. I booted my local server which acts as a smb-share. After that, the wsl startup was much faster!

Currently I disabled automount in wsl.conf since I don't need my C-Drive in wsl.

@ilya-bobyr
Copy link

I guess, it would be great if mounts would not pause the WSL boot process.
Should it be possible to run them in parallel to the rest of the actions.
I wonder if there is already a matching issue.

@ilya-bobyr
Copy link

Seems like issues with automount and inaccessible drives is a common source of problems.
Here are just a few other issues: #8953, #7565

This comment explains how to disable automount for all drives, but instead mount just those that you care about:
#7565 (comment)

@guaycuru
Copy link

I disabled automount but I still see a long pause ( ~30s) when first booting WSL if a network drive in my host machine is not available, even though that drive is not set to mount in fstab

@rybacki78
Copy link

Is seems like the issue is related to network drives. I have business machine which has few mapped drives. When I'm at work WSL start within 4 sec. When I'm at home and all my work drives are unavailable it starts in 50 sec. When at home connected to VPN (drives are online) again it starts in 4 sec. I have disabled automount in wsl.conf. Using Debian distro in WSL.

@einarpersson
Copy link

I am also experiencing slow startup times. If mounting of C:\ is causing the delay, it would really be great if the mounting could be done in parallell/lazily. It it very rare that I need immediate access to C:\ (but I want the prompt to be available asap)

I guess, it would be great if mounts would not pause the WSL boot process. Should it be possible to run them in parallel to the rest of the actions. I wonder if there is already a matching issue.

@asolopovas
Copy link

asolopovas commented Mar 17, 2023

I bought Ryzen 5800X3D thinking it will speed things up. Sometimes it feels like stuff got slower than it was back in XP times, Or Windows 2000 when I got my Pentium 4 it was flying now you buy latest and greatest and everything is still slow, like all computers became gaming consoles... Exactly same issue around ~10s from cold start, world is unfear you can't fully switch to linux and same time can't live in windows, why life always has to be a dilema?

@moreje
Copy link

moreje commented Mar 22, 2023

Is seems like the issue is related to network drives. I have business machine which has few mapped drives. When I'm at work WSL start within 4 sec. When I'm at home and all my work drives are unavailable it starts in 50 sec. When at home connected to VPN (drives are online) again it starts in 4 sec. I have disabled automount in wsl.conf. Using Debian distro in WSL.

I have exactly the same behavior!

I can add that it also applies to FileExplorer which remains unresponsive at first launch in the similar situation. It's incredible that mount trials can't be done in background , letting the main process responsive and usable even if this fails.

@tilenkranjc
Copy link

I'm not sure if it's related, but disconnecting inaccessible mapped network drives solved it for me. Here's an issue -> #9358

@oomek
Copy link

oomek commented Apr 22, 2023

I'm not sure if it's related, but disconnecting inaccessible mapped network drives solved it for me. Here's an issue -> #9358

Again, this is not a solution, but a workaround with tradeoffs.
For me personally disconnecting mapped drives is not an option.
I have 6 mapped drives that are offline for most of the time.

Copy link
Contributor

This issue has been automatically closed since it has not had any activity for the past year. If you're still experiencing this issue please re-file this as a new issue or feature request.

Thank you!

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
needs-investigation likely actionable and/or needs more investigation
Projects
None yet
Development

No branches or pull requests