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

WSL2 - Failed to (auto)start systemd units on boot | /sbin/init failed #11822

Closed
1 of 2 tasks
0Styless opened this issue Jul 22, 2024 · 7 comments
Closed
1 of 2 tasks

Comments

@0Styless
Copy link

Windows Version

Microsoft Windows [Version 10.0.22621.3737]

WSL Version

2.2.4.0

Are you using WSL 1 or WSL 2?

  • WSL 2
  • WSL 1

Kernel Version

5.15.153.1-2

Distro Version

Ubuntu 24.04

Other Software

No response

Repro Steps

Upgrade Ubuntu 22.04 to Ubuntu 24.04 with do-release-upgrade

Expected Behavior

Everything works fine, systemd services would start as before.

Actual Behavior

Systemd services do not start correctly. In dmesg I can see [ 11.253131] WSL (2) ERROR: WaitForBootProcess:3342: /sbin/init failed to start within 10000 (see attached diagnostic logs).

After WSL / distro is started, I tried the following:

  • get the status of a systemd service (pretty sure that worked before without sudo):
$ systemctl status named                                                                                                                                                                                                                    Mon 15:37
Failed to connect to bus: No such file or directory
  • same with sudo:
$ sudo systemctl status named                                                                                                                                                                                                               Mon 15:37
[sudo] password for styless:
○ named.service - BIND Domain Name Server
     Loaded: loaded (/usr/lib/systemd/system/named.service; enabled; preset: enabled)
     Active: inactive (dead)
       Docs: man:named(8)

As you can see, the status is inactive (dead), expected behavior is that the service is up & running.
I also can start the service "per hand":

$ sudo systemctl start named
$ sudo systemctl status named                                                                                                                                                                                                       121ms  Mon 15:39
● named.service - BIND Domain Name Server
     Loaded: loaded (/usr/lib/systemd/system/named.service; disabled; preset: enabled)
     Active: active (running) since Mon 2024-07-22 15:39:26 CEST; 2s ago
       Docs: man:named(8)
   Main PID: 1750 (named)
     Status: "running"
      Tasks: 50 (limit: 19061)
     Memory: 22.1M ()
     CGroup: /system.slice/named.service
             └─1750 /usr/sbin/named -f -u bind

Jul 22 15:39:26 A61001309 named[1750]: zone localhost/IN: loaded serial 2
Jul 22 15:39:26 A61001309 named[1750]: zone local.net/IN: loaded serial 2023060609
Jul 22 15:39:26 A61001309 named[1750]: zone local.net/IN: sending notifies (serial 2023060609)
Jul 22 15:39:26 A61001309 named[1750]: zone wsl.local/IN: loaded serial 2023081501
Jul 22 15:39:26 A61001309 named[1750]: zone wsl.local/IN: sending notifies (serial 2023081501)
Jul 22 15:39:26 A61001309 named[1750]: zone showcaseakdb.net/IN: loaded serial 2024070102
Jul 22 15:39:26 A61001309 named[1750]: zone showcaseakdb.net/IN: sending notifies (serial 2024070102)
Jul 22 15:39:26 A61001309 named[1750]: all zones loaded
Jul 22 15:39:26 A61001309 named[1750]: running
Jul 22 15:39:26 A61001309 systemd[1]: Started named.service - BIND Domain Name Server.

Diagnostic Logs

  • dmesg:
[    0.000000] Linux version 5.15.153.1-microsoft-standard-WSL2 (root@941d701f84f1) (gcc (GCC) 11.2.0, GNU ld (GNU Binutils) 2.37) #1 SMP Fri Mar 29 23:14:13 UTC 2024
[    0.000000] Command line: initrd=\initrd.img WSL_ROOT_INIT=1 panic=-1 nr_cpus=12 hv_utils.timesync_implicit=1 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] 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-0x00000004015fffff] 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 0xa4e24, misc 0xe4bed7b6
[    0.000000] Hyper-V Host Build:22621-10.0-3-0.3733
[    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.000001] tsc: Marking TSC unstable due to running on Hyper-V
[    0.000002] tsc: Detected 2592.008 MHz processor
[    0.000009] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000011] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000013] last_pfn = 0x401600 max_arch_pfn = 0x400000000
[    0.000032] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT
[    0.000041] last_pfn = 0xf8000 max_arch_pfn = 0x400000000
[    0.000047] Using GB pages for direct mapping
[    0.000140] RAMDISK: [mem 0x03dda000-0x03fe1fff]
[    0.000141] ACPI: Early table checksum verification disabled
[    0.000143] ACPI: RSDP 0x00000000000E0000 000024 (v02 VRTUAL)
[    0.000146] ACPI: XSDT 0x0000000000100000 000044 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000149] ACPI: FACP 0x0000000000101000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000153] ACPI: DSDT 0x00000000001011B8 01E191 (v02 MSFTVM DSDT01   00000001 MSFT 05000000)
[    0.000155] ACPI: FACS 0x0000000000101114 000040
[    0.000157] ACPI: OEM0 0x0000000000101154 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000159] ACPI: SRAT 0x000000000011F349 000370 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000161] ACPI: APIC 0x000000000011F6B9 0000A8 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000163] ACPI: Reserving FACP table memory at [mem 0x101000-0x101113]
[    0.000164] ACPI: Reserving DSDT table memory at [mem 0x1011b8-0x11f348]
[    0.000165] ACPI: Reserving FACS table memory at [mem 0x101114-0x101153]
[    0.000166] ACPI: Reserving OEM0 table memory at [mem 0x101154-0x1011b7]
[    0.000166] ACPI: Reserving SRAT table memory at [mem 0x11f349-0x11f6b8]
[    0.000167] ACPI: Reserving APIC table memory at [mem 0x11f6b9-0x11f760]
[    0.000499] Zone ranges:
[    0.000500]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000502]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.000503]   Normal   [mem 0x0000000100000000-0x00000004015fffff]
[    0.000504]   Device   empty
[    0.000505] Movable zone start for each node
[    0.000506] Early memory node ranges
[    0.000506]   node   0: [mem 0x0000000000001000-0x000000000009ffff]
[    0.000507]   node   0: [mem 0x0000000000200000-0x00000000f7ffffff]
[    0.000508]   node   0: [mem 0x0000000100000000-0x00000004015fffff]
[    0.000510] Initmem setup node 0 [mem 0x0000000000001000-0x00000004015fffff]
[    0.000659] On node 0, zone DMA: 1 pages in unavailable ranges
[    0.000677] On node 0, zone DMA: 352 pages in unavailable ranges
[    0.010076] On node 0, zone Normal: 27136 pages in unavailable ranges
[    0.010106] ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
[    0.010450] IOAPIC[0]: apic_id 12, version 17, address 0xfec00000, GSI 0-23
[    0.010454] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.010457] ACPI: Using ACPI (MADT) for SMP configuration information
[    0.010459] smpboot: Allowing 12 CPUs, 0 hotplug CPUs
[    0.010465] [mem 0xf8000000-0xffffffff] available for PCI devices
[    0.010467] Booting paravirtualized kernel on Hyper-V
[    0.010468] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.015670] setup_percpu: NR_CPUS:256 nr_cpumask_bits:256 nr_cpu_ids:12 nr_node_ids:1
[    0.016137] percpu: Embedded 54 pages/cpu s180696 r8192 d32296 u262144
[    0.016142] pcpu-alloc: s180696 r8192 d32296 u262144 alloc=1*2097152
[    0.016144] pcpu-alloc: [0] 00 01 02 03 04 05 06 07 [0] 08 09 10 11 -- -- -- --
[    0.016157] Hyper-V: PV spinlocks enabled
[    0.016158] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.016161] Built 1 zonelists, mobility grouping on.  Total pages: 4101037
[    0.016163] Kernel command line: initrd=\initrd.img WSL_ROOT_INIT=1 panic=-1 nr_cpus=12 hv_utils.timesync_implicit=1 console=hvc0 debug pty.legacy_count=0
[    0.016216] Unknown kernel command line parameters "WSL_ROOT_INIT=1", will be passed to user space.
[    0.017903] Dentry cache hash table entries: 2097152 (order: 12, 16777216 bytes, linear)
[    0.018768] Inode-cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[    0.018861] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.038070] Memory: 4124380K/16667260K available (18452K kernel code, 2628K rwdata, 9648K rodata, 2008K init, 1816K bss, 401452K reserved, 0K cma-reserved)
[    0.038108] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=12, Nodes=1
[    0.038118] ftrace: allocating 52916 entries in 207 pages
[    0.059100] ftrace: allocated 207 pages with 6 groups
[    0.059367] rcu: Hierarchical RCU implementation.
[    0.059368] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=12.
[    0.059369]  Rude variant of Tasks RCU enabled.
[    0.059370]  Tracing variant of Tasks RCU enabled.
[    0.059371] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.059371] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=12
[    0.062655] Using NULL legacy PIC
[    0.062656] NR_IRQS: 16640, nr_irqs: 520, preallocated irqs: 0
[    0.063007] random: crng init done
[    0.063024] Console: colour dummy device 80x25
[    0.063033] ACPI: Core revision 20210730
[    0.063090] Failed to register legacy timer interrupt
[    0.063091] APIC: Switch to symmetric I/O mode setup
[    0.065153] x2apic enabled
[    0.067035] Switched APIC routing to physical x2apic.
[    0.067047] Hyper-V: Using IPI hypercalls
[    0.067089] Calibrating delay loop (skipped), value calculated using timer frequency.. 5184.01 BogoMIPS (lpj=25920080)
[    0.067173] Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8
[    0.067175] Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4
[    0.067178] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.067179] Spectre V2 : Mitigation: Enhanced IBRS
[    0.067180] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.067180] Spectre V2 : Spectre v2 / PBRSB-eIBRS: Retire a single CALL on VMEXIT
[    0.067181] RETBleed: Mitigation: Enhanced IBRS
[    0.067182] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[    0.067183] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
[    0.067187] MMIO Stale Data: Mitigation: Clear CPU buffers
[    0.067188] SRBDS: Unknown: Dependent on hypervisor status
[    0.067188] GDS: Unknown: Dependent on hypervisor status
[    0.067197] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.067198] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.067198] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.067200] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.067201] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format.
[    0.077087] Freeing SMP alternatives memory: 60K
[    0.077087] pid_max: default: 32768 minimum: 301
[    0.077087] LSM: Security Framework initializing
[    0.077087] landlock: Up and running.
[    0.077087] Mount-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.077087] Mountpoint-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.077087] smpboot: CPU0: Intel(R) Core(TM) i7-10750H CPU @ 2.60GHz (family: 0x6, model: 0xa5, stepping: 0x2)
[    0.077087] Performance Events: Skylake events, 32-deep LBR, full-width counters, Intel PMU driver.
[    0.077087] ... version:                4
[    0.077087] ... bit width:              48
[    0.077087] ... generic registers:      4
[    0.077087] ... value mask:             0000ffffffffffff
[    0.077087] ... max period:             00007fffffffffff
[    0.077087] ... fixed-purpose events:   3
[    0.077087] ... event mask:             000000070000000f
[    0.077087] signal: max sigframe size: 1776
[    0.077087] rcu: Hierarchical SRCU implementation.
[    0.077087] smp: Bringing up secondary CPUs ...
[    0.077087] x86: Booting SMP configuration:
[    0.077087] .... node  #0, CPUs:        #1
[    0.077087] 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.077087]   #2  #3  #4  #5  #6  #7  #8  #9 #10 #11
[    0.077087] smp: Brought up 1 node, 12 CPUs
[    0.077087] smpboot: Max logical packages: 1
[    0.077087] smpboot: Total of 12 processors activated (62208.19 BogoMIPS)
[    0.087171] node 0 deferred pages initialised in 20ms
[    0.089141] devtmpfs: initialized
[    0.089141] x86/mm: Memory block size: 128MB
[    0.089141] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.089141] futex hash table entries: 4096 (order: 6, 262144 bytes, linear)
[    0.089141] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.089141] audit: initializing netlink subsys (disabled)
[    0.089141] audit: type=2000 audit(1721654887.020:1): state=initialized audit_enabled=0 res=1
[    0.089141] thermal_sys: Registered thermal governor 'step_wise'
[    0.089141] cpuidle: using governor menu
[    0.089141] ACPI: bus type PCI registered
[    0.089141] PCI: Fatal: No config space access function found
[    0.089141] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[    0.089141] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.089141] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.097156] raid6: skip pq benchmark and using algorithm avx2x4
[    0.097157] raid6: using avx2x2 recovery algorithm
[    0.097166] ACPI: Added _OSI(Module Device)
[    0.097167] ACPI: Added _OSI(Processor Device)
[    0.097168] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.097169] ACPI: Added _OSI(Processor Aggregator Device)
[    0.097169] ACPI: Added _OSI(Linux-Dell-Video)
[    0.097170] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.097171] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    0.100205] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.100857] ACPI: Interpreter enabled
[    0.100857] ACPI: PM: (supports S0 S5)
[    0.100857] ACPI: Using IOAPIC for interrupt routing
[    0.100857] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.100857] ACPI: Enabled 2 GPEs in block 00 to 0F
[    0.100857] iommu: Default domain type: Translated
[    0.100857] iommu: DMA domain TLB invalidation policy: lazy mode
[    0.100857] SCSI subsystem initialized
[    0.100857] ACPI: bus type USB registered
[    0.100857] usbcore: registered new interface driver usbfs
[    0.100857] usbcore: registered new interface driver hub
[    0.100857] usbcore: registered new device driver usb
[    0.100857] pps_core: LinuxPPS API ver. 1 registered
[    0.100857] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.100857] PTP clock support registered
[    0.100857] hv_vmbus: Vmbus version:5.3
[    0.100857] PCI: Using ACPI for IRQ routing
[    0.100857] PCI: System does not support PCI
[    0.100857] clocksource: Switched to clocksource hyperv_clocksource_tsc_page
[    0.100857] hv_vmbus: Unknown GUID: c376c1c3-d276-48d2-90a9-c04748072c60
[    0.100857] hv_vmbus: Unknown GUID: 6e382d18-3336-4f4b-acc4-2b7703d4df4a
[    0.100857] hv_vmbus: Unknown GUID: dde9cbc0-5060-4436-9448-ea1254a5d177
[    0.100857] hv_vmbus: Unknown GUID: 6e382d18-3336-4f4b-acc4-2b7703d4df4a
[    0.105891] VFS: Disk quotas dquot_6.6.0
[    0.105900] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.105912] FS-Cache: Loaded
[    0.105928] pnp: PnP ACPI init
[    0.106044] pnp: PnP ACPI: found 1 devices
[    0.110505] NET: Registered PF_INET protocol family
[    0.110798] IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.111924] tcp_listen_portaddr_hash hash table entries: 8192 (order: 5, 131072 bytes, linear)
[    0.111933] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.111936] TCP established hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.112000] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[    0.112213] TCP: Hash tables configured (established 131072 bind 65536)
[    0.112229] UDP hash table entries: 8192 (order: 6, 262144 bytes, linear)
[    0.112247] UDP-Lite hash table entries: 8192 (order: 6, 262144 bytes, linear)
[    0.112277] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.112758] RPC: Registered named UNIX socket transport module.
[    0.112759] RPC: Registered udp transport module.
[    0.112760] RPC: Registered tcp transport module.
[    0.112761] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.112762] PCI: CLS 0 bytes, default 64
[    0.112780] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.112781] software IO TLB: mapped [mem 0x00000000f4000000-0x00000000f8000000] (64MB)
[    0.112809] Trying to unpack rootfs image as initramfs...
[    0.112812] KVM: vmx: using Hyper-V Enlightened VMCS
[    0.113700] Freeing initrd memory: 2080K
[    0.202294] kvm: already loaded the other module
[    0.204361] Initialise system trusted keyrings
[    0.204457] workingset: timestamp_bits=46 max_order=22 bucket_order=0
[    0.205093] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.205249] NFS: Registering the id_resolver key type
[    0.205253] Key type id_resolver registered
[    0.205254] Key type id_legacy registered
[    0.205256] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.205258] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    0.205258] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.205807] Key type cifs.idmap registered
[    0.205865] fuse: init (API version 7.34)
[    0.205990] SGI XFS with ACLs, security attributes, realtime, scrub, repair, quota, no debug enabled
[    0.206431] 9p: Installing v9fs 9p2000 file system support
[    0.206438] FS-Cache: Netfs '9p' registered for caching
[    0.206467] FS-Cache: Netfs 'ceph' registered for caching
[    0.206469] ceph: loaded (mds proto 32)
[    0.211969] NET: Registered PF_ALG protocol family
[    0.211972] xor: automatically using best checksumming function   avx
[    0.211974] Key type asymmetric registered
[    0.211974] Asymmetric key parser 'x509' registered
[    0.211990] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[    0.212797] hv_vmbus: registering driver hv_pci
[    0.213005] hv_pci 7c9a8597-3d69-4b06-9d72-e06c0c4d8d09: PCI VMBus probing: Using version 0x10004
[    0.213884] hv_pci 7c9a8597-3d69-4b06-9d72-e06c0c4d8d09: PCI host bridge to bus 3d69:00
[    0.213886] pci_bus 3d69:00: root bus resource [mem 0x9ffe00000-0x9ffe02fff window]
[    0.213888] pci_bus 3d69:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.214693] pci 3d69:00:00.0: [1af4:1043] type 00 class 0x010000
[    0.215560] pci 3d69:00:00.0: reg 0x10: [mem 0x9ffe00000-0x9ffe00fff 64bit]
[    0.216143] pci 3d69:00:00.0: reg 0x18: [mem 0x9ffe01000-0x9ffe01fff 64bit]
[    0.216715] pci 3d69:00:00.0: reg 0x20: [mem 0x9ffe02000-0x9ffe02fff 64bit]
[    0.219759] pci_bus 3d69:00: busn_res: [bus 00-ff] end is updated to 00
[    0.219763] pci 3d69:00:00.0: BAR 0: assigned [mem 0x9ffe00000-0x9ffe00fff 64bit]
[    0.220147] pci 3d69:00:00.0: BAR 2: assigned [mem 0x9ffe01000-0x9ffe01fff 64bit]
[    0.220527] pci 3d69:00:00.0: BAR 4: assigned [mem 0x9ffe02000-0x9ffe02fff 64bit]
[    0.221301] hv_pci ababac66-a94a-4e11-952d-d8bd276ed8d3: PCI VMBus probing: Using version 0x10004
[    0.222064] hv_pci ababac66-a94a-4e11-952d-d8bd276ed8d3: PCI host bridge to bus a94a:00
[    0.222066] pci_bus a94a:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.222418] pci a94a:00:00.0: [1414:008e] type 00 class 0x030200
[    0.226518] pci_bus a94a:00: busn_res: [bus 00-ff] end is updated to 00
[    0.226858] hv_pci b3d0b4a7-ad02-4d83-ba99-b495b32e451b: PCI VMBus probing: Using version 0x10004
[    0.227561] hv_pci b3d0b4a7-ad02-4d83-ba99-b495b32e451b: PCI host bridge to bus ad02:00
[    0.227563] pci_bus ad02:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.227912] pci ad02:00:00.0: [1414:008e] type 00 class 0x030200
[    0.231948] pci_bus ad02:00: busn_res: [bus 00-ff] end is updated to 00
[    0.232076] ACPI: AC: AC Adapter [AC1] (on-line)
[    0.232672] ACPI: battery: Slot [BAT1] (battery present)
[    0.236015] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    0.273515] Non-volatile memory driver v1.3
[    0.274086] [drm] Initialized vgem 1.0.0 20120112 for vgem on minor 0
[    0.275119] printk: console [hvc0] enabled
[    0.277004] brd: module loaded
[    0.277868] loop: module loaded
[    0.278111] hv_vmbus: registering driver hv_storvsc
[    0.278604] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[    0.279371] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <Jason@zx2c4.com>. All Rights Reserved.
[    0.280038] tun: Universal TUN/TAP device driver, 1.6
[    0.280362] scsi host0: storvsc_host_t
[    0.280507] PPP generic driver version 2.4.2
[    0.281037] PPP BSD Compression module registered
[    0.281373] PPP Deflate Compression module registered
[    0.281780] PPP MPPE Compression module registered
[    0.282040] NET: Registered PF_PPPOX protocol family
[    0.282318] usbcore: registered new interface driver cdc_ether
[    0.282660] usbcore: registered new interface driver cdc_ncm
[    0.283051] usbcore: registered new interface driver r8153_ecm
[    0.283447] hv_vmbus: registering driver hv_netvsc
[    0.283822] VFIO - User Level meta-driver version: 0.3
[    0.284238] usbcore: registered new interface driver cdc_acm
[    0.284590] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    0.285094] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[    0.285441] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1
[    0.285860] vhci_hcd: created sysfs vhci_hcd.0
[    0.286310] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    0.286754] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.287173] usb usb1: Product: USB/IP Virtual Host Controller
[    0.287517] usb usb1: Manufacturer: Linux 5.15.153.1-microsoft-standard-WSL2 vhci_hcd
[    0.287927] usb usb1: SerialNumber: vhci_hcd.0
[    0.288295] hub 1-0:1.0: USB hub found
[    0.288522] hub 1-0:1.0: 8 ports detected
[    0.288960] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[    0.289308] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2
[    0.289762] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    0.290296] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[    0.290689] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.291079] usb usb2: Product: USB/IP Virtual Host Controller
[    0.291399] usb usb2: Manufacturer: Linux 5.15.153.1-microsoft-standard-WSL2 vhci_hcd
[    0.291813] usb usb2: SerialNumber: vhci_hcd.0
[    0.292165] hub 2-0:1.0: USB hub found
[    0.292386] hub 2-0:1.0: 8 ports detected
[    0.292812] hv_vmbus: registering driver hyperv_keyboard
[    0.293084] rtc_cmos 00:00: RTC can wake from S4
[    0.295112] rtc_cmos 00:00: registered as rtc0
[    0.295719] rtc_cmos 00:00: setting system clock to 2024-07-22T13:28:07 UTC (1721654887)
[    0.296248] rtc_cmos 00:00: alarms up to one month, 114 bytes nvram
[    0.296791] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[    0.297334] device-mapper: raid: Loading target version 1.15.1
[    0.297670] hid: raw HID events driver (C) Jiri Kosina
[    0.297926] usbcore: registered new interface driver usbhid
[    0.298251] usbhid: USB HID core driver
[    0.298485] hv_utils: Registering HyperV Utility Driver
[    0.298807] hv_vmbus: registering driver hv_utils
[    0.299088] hv_vmbus: registering driver hv_balloon
[    0.299576] hv_utils: TimeSync IC version 4.0
[    0.299715] hv_vmbus: registering driver dxgkrnl
[    0.300312] hv_balloon: Using Dynamic Memory protocol version 2.0
[    0.301344] Free page reporting enabled
[    0.301567] hv_balloon: Cold memory discard hint enabled with order 9
[    0.302883] drop_monitor: Initializing network drop monitor service
[    0.303232] Mirror/redirect action on
[    0.303433] u32 classifier
[    0.303562]     Performance counters on
[    0.303760]     input device check on
[    0.303950]     Actions configured
[    0.305006] IPVS: Registered protocols (TCP, UDP)
[    0.305311] IPVS: Connection hash table configured (size=4096, memory=32Kbytes)
[    0.305777] IPVS: ipvs loaded.
[    0.305974] IPVS: [rr] scheduler registered.
[    0.306229] IPVS: [wrr] scheduler registered.
[    0.306500] IPVS: [sh] scheduler registered.
[    0.306797] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[    0.307122] Initializing XFRM netlink socket
[    0.307417] NET: Registered PF_INET6 protocol family
[    0.307947] Segment Routing with IPv6
[    0.308170] In-situ OAM (IOAM) with IPv6
[    0.308397] NET: Registered PF_PACKET protocol family
[    0.308668] Bridge firewalling registered
[    0.308865] 8021q: 802.1Q VLAN Support v1.8
[    0.309072] sctp: Hash tables configured (bind 256/256)
[    0.309376] 9pnet: Installing 9P2000 support
[    0.309648] Key type dns_resolver registered
[    0.309911] Key type ceph registered
[    0.310203] libceph: loaded (mon/osd proto 15/24)
[    0.310544] NET: Registered PF_VSOCK protocol family
[    0.310802] hv_vmbus: registering driver hv_sock
[    0.311088] IPI shorthand broadcast: enabled
[    0.311382] sched_clock: Marking stable (306437500, 4095000)->(332872400, -22339900)
[    0.312275] registered taskstats version 1
[    0.312932] Loading compiled-in X.509 certificates
[    0.314023] Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no
[    0.315180] Freeing unused kernel image (initmem) memory: 2008K
[    0.315502] Write protecting the kernel read-only data: 30720k
[    0.316519] Freeing unused kernel image (text/rodata gap) memory: 2024K
[    0.317195] Freeing unused kernel image (rodata/data gap) memory: 592K
[    0.317541] Run /init as init process
[    0.317724]   with arguments:
[    0.317914]     /init
[    0.318036]   with environment:
[    0.318219]     HOME=/
[    0.318341]     TERM=linux
[    0.318463]     WSL_ROOT_INIT=1
[    0.321479] scsi 0:0:0:0: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
[    0.327481] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    0.328072] sd 0:0:0:0: [sda] 795856 512-byte logical blocks: (407 MB/389 MiB)
[    0.328597] sd 0:0:0:0: [sda] Write Protect is on
[    0.328871] sd 0:0:0:0: [sda] Mode Sense: 0f 00 80 00
[    0.329271] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    0.332281] sd 0:0:0:0: [sda] Attached SCSI disk
[    0.384498] scsi 0:0:0:1: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
[    0.385955] EXT4-fs (sda): mounted filesystem without journal. Opts: (null). Quota mode: none.
[    0.390917] sd 0:0:0:1: Attached scsi generic sg1 type 0
[    0.391717] sd 0:0:0:1: [sdb] 8388616 512-byte logical blocks: (4.29 GB/4.00 GiB)
[    0.392235] sd 0:0:0:1: [sdb] 4096-byte physical blocks
[    0.392684] sd 0:0:0:1: [sdb] Write Protect is off
[    0.393035] sd 0:0:0:1: [sdb] Mode Sense: 0f 00 00 00
[    0.393523] sd 0:0:0:1: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    0.396502] sd 0:0:0:1: [sdb] Attached SCSI disk
[    0.424382] Adding 4194304k swap on /dev/sdb.  Priority:-2 extents:1 across:4194304k
[    0.501142] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    0.622990] hv_pci b9808ac7-2d2a-498a-a6e7-252dc912b701: PCI VMBus probing: Using version 0x10004
[    0.625547] hv_pci b9808ac7-2d2a-498a-a6e7-252dc912b701: PCI host bridge to bus 2d2a:00
[    0.626124] pci_bus 2d2a:00: root bus resource [mem 0xc00000000-0xe00001fff window]
[    0.626606] pci_bus 2d2a:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.628109] pci 2d2a:00:00.0: [1af4:105a] type 00 class 0x088000
[    0.630561] pci 2d2a:00:00.0: reg 0x10: [mem 0xe00000000-0xe00000fff 64bit]
[    0.632561] pci 2d2a:00:00.0: reg 0x18: [mem 0xe00001000-0xe00001fff 64bit]
[    0.634748] pci 2d2a:00:00.0: reg 0x20: [mem 0xc00000000-0xdffffffff 64bit]
[    0.639817] pci_bus 2d2a:00: busn_res: [bus 00-ff] end is updated to 00
[    0.640384] pci 2d2a:00:00.0: BAR 4: assigned [mem 0xc00000000-0xdffffffff 64bit]
[    0.642346] pci 2d2a:00:00.0: BAR 0: assigned [mem 0xe00000000-0xe00000fff 64bit]
[    0.644182] pci 2d2a:00:00.0: BAR 2: assigned [mem 0xe00001000-0xe00001fff 64bit]
[    0.659003] virtiofs virtio1: Cache len: 0x200000000 @ 0xc00000000
[    0.725772] memmap_init_zone_device initialised 2097152 pages in 20ms
[    0.730430] FS-Cache: Duplicate cookie detected

[boot]
[    0.730885] FS-Cache: O-cookie c=00000004 [p=00000002 fl=222 nc=0 na=1]
[    0.731410] FS-Cache: O-cookie d=000000003f20f2be{9P.session} n=00000000d7074507
[    0.732126] FS-Cache: O-key=[10] '34323934393337333631'
[    0.732486] FS-Cache: N-cookie c=00000005 [p=00000002 fl=2 nc=0 na=1]
[    0.732911] FS-Cache: N-cookie d=000000003f20f2be{9P.session} n=00000000323e64f8
[    0.733371] FS-Cache: N-key=[10] '34323934393337333631'
[    0.737048] FS-Cache: Duplicate cookie detected
[    0.737387] FS-Cache: O-cookie c=00000006 [p=00000002 fl=222 nc=0 na=1]
[    0.737727] FS-Cache: O-cookie d=000000003f20f2be{9P.session} n=00000000bf88b9be
[    0.738144] FS-Cache: O-key=[10] '34323934393337333632'
[    0.738426] FS-Cache: N-cookie c=00000007 [p=00000002 fl=2 nc=0 na=1]
[    0.738758] FS-Cache: N-cookie d=000000003f20f2be{9P.session} n=0000000023761fcc
[    0.739173] FS-Cache: N-key=[10] '34323934393337333632'
[    0.760266] scsi 0:0:0:2: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
[    0.765514] sd 0:0:0:2: Attached scsi generic sg2 type 0
[    0.766312] sd 0:0:0:2: [sdc] 2147483648 512-byte logical blocks: (1.10 TB/1.00 TiB)

[boot]
[    0.766692] sd 0:0:0:2: [sdc] 4096-byte physical blocks
[    0.767072] sd 0:0:0:2: [sdc] Write Protect is off
[    0.767347] sd 0:0:0:2: [sdc] Mode Sense: 0f 00 00 00
[    0.767736] sd 0:0:0:2: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    0.770234] sd 0:0:0:2: [sdc] Attached SCSI disk
[    0.782040] EXT4-fs (sdc): mounted filesystem with ordered data mode. Opts: discard,errors=remount-ro,data=ordered. Quota mode: none.
[    1.238882] WSL (2) WARNING: /etc/resolv.conf updating disabled in /etc/wsl.conf
[    1.286923] misc dxg: dxgk: dxgkio_is_feature_enabled: Ioctl failed: -22
[    1.291512] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[    1.292352] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[    1.292879] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[    1.293799] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[    1.297124] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[    1.297680] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[    1.298177] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -22
[    1.298784] misc dxg: dxgk: dxgkio_query_adapter_info: Ioctl failed: -2
[    1.534005] systemd-journald[59]: Collecting audit messages is disabled.
[    1.551993] EXT4-fs (sdc): re-mounted. Opts: (null). Quota mode: none.
[    1.567176] systemd-journald[59]: Received client request to flush runtime journal.
[    1.568717] systemd-journald[59]: File /var/log/journal/3f96830cb09f4b8bb6546fef03eb1932/system.journal corrupted or uncleanly shut down, renaming and replacing.
[   11.253131] WSL (2) ERROR: WaitForBootProcess:3342: /sbin/init failed to start within 10000
[   11.253133] ms
[   11.253898] WSL (2) WARNING: /etc/resolv.conf updating disabled in /etc/wsl.conf
[   11.259741] WSL (2): Creating login session for doerflt
[   24.822001] systemd-journald[59]: File /var/log/journal/3f96830cb09f4b8bb6546fef03eb1932/user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
Copy link

Logs are required for review from WSL team

If this a feature request, please reply with '/feature'. If this is a question, reply with '/question'.
Otherwise please attach logs by following the instructions below, your issue will not be reviewed unless they are added. These logs will help us understand what is going on in your machine.

How to collect WSL logs

Download and execute collect-wsl-logs.ps1 in an administrative powershell prompt:

Invoke-WebRequest -UseBasicParsing "https://raw.githubusercontent.com/microsoft/WSL/master/diagnostics/collect-wsl-logs.ps1" -OutFile collect-wsl-logs.ps1
Set-ExecutionPolicy Bypass -Scope Process -Force
.\collect-wsl-logs.ps1

The script will output the path of the log file once done.

If this is a networking issue, please use collect-networking-logs.ps1, following the instructions here

Once completed please upload the output files to this Github issue.

Click here for more info on logging
If you choose to email these logs instead of attaching to the bug, please send them to wsl-gh-logs@microsoft.com with the number of the github issue in the subject, and in the message a link to your comment in the github issue and reply with '/emailed-logs'.

View similar issues

Please view the issues below to see if they solve your problem, and if the issue describes your problem please consider closing this one and thumbs upping the other issue to help us prioritize it!

Closed similar issues:

Note: You can give me feedback by thumbs upping or thumbs downing this comment.

@0Styless
Copy link
Author

Copy link

Diagnostic information
Detected appx version: 2.2.4.0

@Stanzilla
Copy link

See #8879

@OneBlue
Copy link
Collaborator

OneBlue commented Jul 30, 2024

Thank you @0Styless. Let's see if this is a timeout issue. Can you add:

[boot]
initTimeout=60000

To /etc/wsl.conf and see if that solves the issue ?

Copy link
Contributor

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

Thank you!

@satmandu
Copy link

satmandu commented Dec 6, 2024

Thank you @0Styless. Let's see if this is a timeout issue. Can you add:

[boot]
initTimeout=60000

To /etc/wsl.conf and see if that solves the issue ?

That settting gave me this:

The operation timed out because a response was not received from the virtual machine or container.
Error code: Wsl/Service/HCS_E_CONNECTION_TIMEOUT
Press any key to continue...

Setting initTimeout=20000 in /etc/wsl.conf worked for me though:

[boot]
initTimeout=20000

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

4 participants