Debian 10 Hyper-V arm64 VM boot log

bootlog.log

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x51df804e]
[    0.000000] Linux version 4.19.84-microsoft-standard+ (imbushuo@BEN-SURFACEPROX) (gcc version 8.3.0 (Debian 8.3.0-6)) #2 SMP Sun Feb 23 18:32:21 EST 2020
[    0.000000] earlycon: pl11 at MMIO 0x00000000effec000 (options '')
[    0.000000] bootconsole [pl11] enabled
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: EFI v2.70 by EDK II
[    0.000000] efi:  ACPI=0x3fffa000  ACPI 2.0=0x3fffa014  SMBIOS=0x3ffd3000  SMBIOS 3.0=0x3ffd1000  MEMATTR=0x3f022018  RNG=0x3ffd7e18
[    0.000000] efi: seeding entropy pool
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL)
[    0.000000] ACPI: XSDT 0x000000003FFF90E8 00005C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000000] ACPI: FACP 0x000000003FFF7000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000000] ACPI: DSDT 0x000000003EDCB000 01DEAC (v02 MSFTVM DSDT01   00000001 MSFT 05000000)
[    0.000000] ACPI: DBG2 0x000000003FFF8000 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000000] ACPI: GTDT 0x000000003FFF6000 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000000] ACPI: OEM0 0x000000003FFF5000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000000] ACPI: APIC 0x000000003FFF4000 000184 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000000] ACPI: SRAT 0x000000003FFF3000 0001B8 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000000] ACPI: BGRT 0x000000003EB1B000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[    0.000000] On node 0 totalpages: 262144
[    0.000000]   Normal zone: 4096 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 262144 pages, LIFO batch:63
[    0.000000] psci: probing for conduit method from ACPI.
[    0.000000] psci: PSCIv1.0 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: MIGRATE_INFO_TYPE not supported.
[    0.000000] psci: SMC Calling Convention v1.0
[    0.000000] random: get_random_bytes called from start_kernel+0x9c/0x45c with crng_init=0
[    0.000000] percpu: Embedded 23 pages/cpu s55704 r8192 d30312 u94208
[    0.000000] pcpu-alloc: s55704 r8192 d30312 u94208 alloc=23*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] spectrev2 mitigation disabled by kernel configuration
[    0.000000] CPU features: kernel page table isolation disabled by kernel configuration
[    0.000000] CPU features: detected: Hardware dirty bit management
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 258048
[    0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-4.19.84-microsoft-standard+ root=/dev/sda3 earlycon=pl011,0xeffec000
[    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.000000] Memory: 1002220K/1048576K available (10812K kernel code, 1344K rwdata, 2452K rodata, 1280K init, 793K bss, 46356K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 36904 entries in 145 pages
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000]  All grace periods are expedited (rcu_expedited).
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: Distributor has no Range Selector support
[    0.000000] GICv3: no VLPI support, no direct LPI support
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000
[    0.000000] Hyper-V: Features 0x2eff, hints 0xe
[    0.000000] Hyper-V: Host Build 10.0.19569.1000-1-0
[    0.000001] sched_clock: 64 bits at 10MHz, resolution 100ns, wraps every 4398046511100ns
[    0.000003] clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
[    0.000018] arch_timer: No interrupt available, giving up
[    0.000051] Console: colour dummy device 80x25
[    0.000052] console [tty0] enabled
[    0.000053] bootconsole [pl11] disabled
[    0.000058] ACPI: Core revision 20180810
[    0.000185] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800)
[    0.000188] pid_max: default: 32768 minimum: 301
[    0.000205] Security Framework initialized
[    0.000214] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.000216] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.000400] ACPI PPTT: No PPTT table found, cpu topology may be inaccurate
[    0.000469] ASID allocator initialised with 65536 entries
[    0.000487] rcu: Hierarchical SRCU implementation.
[    0.000547] Remapping and enabling EFI services.
[    0.000666] smp: Bringing up secondary CPUs ...
[    0.000823] Detected PIPT I-cache on CPU1
[    0.000840] GICv3: CPU1: found redistributor 1 region 0:0x00000000effee000
[    0.000891] CPU1: Booted secondary processor 0x0000000001 [0x51df804e]
[    0.001184] Detected PIPT I-cache on CPU2
[    0.001198] GICv3: CPU2: found redistributor 2 region 0:0x00000000effee000
[    0.001248] CPU2: Booted secondary processor 0x0000000002 [0x51df804e]
[    0.001555] Detected PIPT I-cache on CPU3
[    0.001568] GICv3: CPU3: found redistributor 3 region 0:0x00000000effee000
[    0.001617] CPU3: Booted secondary processor 0x0000000003 [0x51df804e]
[    0.001754] smp: Brought up 1 node, 4 CPUs
[    0.001756] SMP: Total of 4 processors activated.
[    0.001757] CPU features: detected: GIC system register CPU interface
[    0.001758] CPU features: detected: Privileged Access Never
[    0.001758] CPU features: detected: LSE atomic instructions
[    0.001759] CPU features: detected: 32-bit EL0 Support
[    0.002155] CPU: All CPU(s) started at EL1
[    0.002245] alternatives: patching kernel code
[    0.004587] devtmpfs: initialized
[    0.004979] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.004983] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.005230] NET: Registered protocol family 16
[    0.005525] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____))
[    0.005527] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.005919] DMA: preallocated 256 KiB pool for atomic allocations
[    0.005921] ACPI: bus type PCI registered
[    0.005923] Serial: AMBA PL011 UART driver
[    0.007154] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.008247] ACPI: Added _OSI(Module Device)
[    0.008248] ACPI: Added _OSI(Processor Device)
[    0.008249] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.008250] ACPI: Added _OSI(Processor Aggregator Device)
[    0.008251] ACPI: Added _OSI(Linux-Dell-Video)
[    0.008252] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.012585] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.012965] ACPI: Interpreter enabled
[    0.012967] ACPI: Using GIC for interrupt routing
[    0.012975] ACPI: Failed to parse MCFG (-19)
[    0.013589] ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 1, base_baud = 0) is a SBSA
[    0.013908] ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 2, base_baud = 0) is a SBSA
[    0.014972] SCSI subsystem initialized
[    0.015010] Registered efivars operations
[    0.016310] hv_vmbus: Vmbus version:5.0
[    0.016318] No ACPI PMU IRQ for CPU0
[    0.016319] No ACPI PMU IRQ for CPU1
[    0.016320] No ACPI PMU IRQ for CPU2
[    0.016320] No ACPI PMU IRQ for CPU3
[    0.016845] clocksource: Switched to clocksource hyperv_clocksource_tsc_page
[    0.027228] VFS: Disk quotas dquot_6.6.0
[    0.027241] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.027288] FS-Cache: Loaded
[    0.027322] pnp: PnP ACPI init
[    0.027522] pnp: PnP ACPI: found 0 devices
[    0.033368] NET: Registered protocol family 2
[    0.033501] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes)
[    0.033504] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[    0.033513] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[    0.033532] TCP: Hash tables configured (established 8192 bind 8192)
[    0.033553] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.033557] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.033579] NET: Registered protocol family 1
[    0.034040] RPC: Registered named UNIX socket transport module.
[    0.034040] RPC: Registered udp transport module.
[    0.034041] RPC: Registered tcp transport module.
[    0.034041] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.034044] PCI: CLS 0 bytes, default 64
[    0.034079] Trying to unpack rootfs image as initramfs...
[    0.142315] Freeing initrd memory: 9948K
[    0.142815] hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available
[    0.143082] hw perfevents: enabled with armv8_pmuv3_1 PMU driver, 1 counters available
[    0.143290] Initialise system trusted keyrings
[    0.143419] workingset: timestamp_bits=46 max_order=18 bucket_order=0
[    0.144070] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.144368] NFS: Registering the id_resolver key type
[    0.144372] Key type id_resolver registered
[    0.144373] Key type id_legacy registered
[    0.144376] Installing knfsd (copyright (C) 1996 [email protected]).
[    0.145004] Key type cifs.idmap registered
[    0.145102] fuse init (API version 7.27)
[    0.145300] SGI XFS with ACLs, security attributes, realtime, scrub, no debug enabled
[    0.145947] 9p: Installing v9fs 9p2000 file system support
[    0.145955] FS-Cache: Netfs '9p' registered for caching
[    0.145989] FS-Cache: Netfs 'ceph' registered for caching
[    0.145991] ceph: loaded (mds proto 32)
[    0.150662] NET: Registered protocol family 38
[    0.150664] Key type asymmetric registered
[    0.150665] Asymmetric key parser 'x509' registered
[    0.150675] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    0.150676] io scheduler noop registered (default)
[    0.150929] hv_vmbus: registering driver hyperv_fb
[    0.150976] efifb: probing for efifb
[    0.150988] efifb: framebuffer at 0xe0000000, using 3072k, total 3072k
[    0.150988] efifb: mode is 1024x768x32, linelength=4096, pages=1
[    0.150989] efifb: scrolling: redraw
[    0.150990] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
[    0.156214] Console: switching to colour frame buffer device 128x48
[    0.157313] fb0: EFI VGA frame buffer device
[    0.157314] checking generic (e0000000 300000) vs hw (e0000000 300000)
[    0.157315] fb: switching to hyperv_fb from EFI VGA
[    0.157329] Console: switching to colour dummy device 80x25
[    0.157479] hyperv_fb: Screen resolution: 1152x864, Color depth: 32
[    0.202161] Console: switching to colour frame buffer device 144x54
[    0.218485] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    0.218959] ACPI PPTT: No PPTT table found, cache topology may be inaccurate
[    0.218963] ACPI PPTT: No PPTT table found, cache topology may be inaccurate
[    0.218965] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    0.221265] brd: module loaded
[    0.222518] loop: module loaded
[    0.222619] hv_vmbus: registering driver hv_storvsc
[    0.222680] Ethernet Channel Bonding Driver: v3.7.1 (April 27, 2011)
[    0.223082] tun: Universal TUN/TAP device driver, 1.6
[    0.223342] PPP generic driver version 2.4.2
[    0.223538] PPP BSD Compression module registered
[    0.223539] PPP Deflate Compression module registered
[    0.223546] PPP MPPE Compression module registered
[    0.223548] NET: Registered protocol family 24
[    0.223554] hv_vmbus: registering driver hv_netvsc
[    0.226166] scsi host0: storvsc_host_t
[    0.240499] scsi 0:0:0:0: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
[    0.242121] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    0.243381] hv_vmbus: registering driver hyperv_keyboard
[    0.243634] sd 0:0:0:0: [sda] 33554432 512-byte logical blocks: (17.2 GB/16.0 GiB)
[    0.243636] sd 0:0:0:0: [sda] 4096-byte physical blocks
[    0.243650] mousedev: PS/2 mouse device common for all mice
[    0.244033] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: [email protected]
[    0.244407] input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0
[    0.244536] hv_vmbus: registering driver hid_hyperv
[    0.244686] hv_utils: Registering HyperV Utility Driver
[    0.244687] hv_vmbus: registering driver hv_util
[    0.244728] hv_vmbus: registering driver hv_balloon
[    0.244792] drop_monitor: Initializing network drop monitor service
[    0.246174] sd 0:0:0:0: [sda] Write Protect is off
[    0.246176] sd 0:0:0:0: [sda] Mode Sense: 0f 00 00 00
[    0.246734] hv_utils: Heartbeat IC version 3.0
[    0.246844] hv_balloon: Using Dynamic Memory protocol version 2.0
[    0.247146] input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1
[    0.247587] hid-generic 0006:045E:0621.0001: input: <UNKNOWN> HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on
[    0.247602] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    0.247954] hv_balloon: cold discard hint not supported
[    0.248042] hv_utils: cannot register PTP clock: 0
[    0.248223] hv_utils: Shutdown IC version 3.0
[    0.249170] hv_utils: TimeSync IC version 4.0
[    0.249880] Mirror/redirect action on
[    0.250038] IPVS: Registered protocols (TCP, UDP)
[    0.250054] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[    0.250130] IPVS: ipvs loaded.
[    0.250132] IPVS: [rr] scheduler registered.
[    0.250133] IPVS: [wrr] scheduler registered.
[    0.250134] IPVS: [sh] scheduler registered.
[    0.250255] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[    0.250279] Initializing XFRM netlink socket
[    0.250341] NET: Registered protocol family 10
[    0.250823] Segment Routing with IPv6
[    0.250957] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    0.251044] NET: Registered protocol family 17
[    0.251061] Bridge firewalling registered
[    0.251071] 8021q: 802.1Q VLAN Support v1.8
[    0.251088] sctp: Hash tables configured (bind 256/256)
[    0.251133] 9pnet: Installing 9P2000 support
[    0.251140] Key type dns_resolver registered
[    0.251148] Key type ceph registered
[    0.251538] libceph: loaded (mon/osd proto 15/24)
[    0.251551] hv_vmbus: registering driver hv_sock
[    0.251675] NET: Registered protocol family 40
[    0.252084] registered taskstats version 1
[    0.252097] Loading compiled-in X.509 certificates
[    0.252149] hctosys: unable to open rtc device (rtc0)
[    0.279793]  sda: sda1 sda2 sda3
[    0.282511] sd 0:0:0:0: [sda] Attached SCSI disk
[    0.283356] Freeing unused kernel memory: 1280K
[    0.302376] Run /init as init process
[    0.340826] random: fast init done
[    0.531389] EXT4-fs (sda3): mounted filesystem with ordered data mode. Opts: (null)
[    0.684298] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[    0.684409] systemd[1]: Detected architecture arm64.
[    0.690816] systemd[1]: Set hostname to <hvminlinux>.
[    0.890779] random: systemd: uninitialized urandom read (16 bytes read)
[    0.892367] random: systemd: uninitialized urandom read (16 bytes read)
[    0.892554] systemd[1]: Listening on fsck to fsckd communication Socket.
[    0.892756] random: systemd: uninitialized urandom read (16 bytes read)
[    0.893014] systemd[1]: Listening on Journal Socket.
[    0.894051] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    0.897559] systemd[1]: Starting Load Kernel Modules...
[    0.898008] systemd[1]: Listening on Journal Socket (/dev/log).
[    0.899691] systemd[1]: Starting Nameserver information manager...
[    0.901543] systemd[1]: Mounting POSIX Message Queue File System...
[    0.931143] EXT4-fs (sda3): re-mounted. Opts: (null)
[    0.987562] systemd-journald[189]: Received request to flush runtime journal from PID 1
[    1.823354] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    1.823369] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    5.755454] random: crng init done
[    5.755634] random: 7 urandom warning(s) missed due to ratelimiting
[   48.183862] hv_balloon: Max. dynamic memory size: 2048 MB

Are you able to login onto the console?
I can see that the base_baud is 0
[ 0.013589] ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 1, base_baud = 0) is a SBSA
[ 0.013908] ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 2, base_baud = 0) is a SBSA

@poppinsahu not really tried login via console (maybe okay for virtual console), since I just use the FrameBuffer. It can print stuffs though.

Could you please try typing something on console after logging attempt?
Infact i am not able to loggin on console and i suspect because of baud rate(base_baud = 0) issue on ARM64

The same if i am trying on x64 then i am able to log in and the base_baud = 115200, which is being set from kernel command line parameters

@imbushuo need some help of yours in login via console. Do you have any suggestions/comments on this?

I haven't yet tested this one. I would assume this might be something intended by the Hyper-V team (if that's really set to zero.) Actually the emulated serial on x86 is 16550-based, while PL011 emulation is Hyper-V ARM exclusive.

…

--- Thanks, Bingxing Wang

________________________________ From: poppinsahu <[email protected]> Sent: Thursday, January 21, 2021 10:13 PM To: imbushuo <[email protected]> Cc: Bingxing Wang <[email protected]>; Mention <[email protected]> Subject: Re: imbushuo/bootlog.log @poppinsahu commented on this gist.

________________________________ @imbushuo<https://github.com/imbushuo> need some help of yours in login via console. Do you have any suggestions/comments on this? — You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub<https://gist.github.com/c39ddd76ae3334b03118a63c2879b644#gistcomment-3603029>, or unsubscribe<https://github.com/notifications/unsubscribe-auth/ABP6IQJHQGUVTIIG6UIKTR3S3EJR7ANCNFSM4WMMJAKQ>.