Hi, Diederik de Haas wrote: > On Sunday, 6 March 2022 21:05:10 CET Petra R.-P. wrote: > > > Update for linux-image-5.16.0-3-686 : > > Indeed. Somehow I missed it, sorry. JFYI: 5.6.11-1 still hangs for me as well. Screenshot attached. > I did notice there were some big time gaps in your kernel log: I also seem to have gaps, but smaller ones. The examples below come from dmesg when booting with 5.15.15-2, i.e. 5.15.0-3-686-pae and only lists those gaps which are over 1 second. I also found quite some gaps of around 0.5 seconds, but I'd consider that normal. Full dmesg output is attached as well, but here are the gaps I noticed: […] [ 0.102123] microcode: microcode updated early to revision 0x20, date = 2003-06-05 [ 0.102153] mem auto-init: CONFIG_PAGE_POISONING is on, will take precedence over init_on_alloc and init_on_free [ 0.102175] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.102186] Initializing HighMem for node 0 (00036ffe:0003ff60) [ 0.414993] Initializing Movable for node 0 (00000000:00000000) [ 2.033733] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 2.033793] Memory: 1005888K/1047544K available (8584K kernel code, 1254K rwdata, 2712K rodata, 936K init, 488K bss, 41656K reserved, 0K cma-reserved, 146824K highmem) [ 2.033805] ********************************************************** [ 2.033809] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** […] [ 2.189808] cpuidle: using governor ladder [ 2.190078] Simple Boot Flag at 0x35 set to 0x1 [ 2.191133] clocksource: pit: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1601818034827 ns [ 2.191232] ACPI: bus type PCI registered [ 2.191280] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 2.191752] PCI: Using configuration type 1 for base access [ 2.195125] audit: type=2000 audit(1646673781.060:1): state=initialized audit_enabled=0 res=1 [ 2.205431] Kprobes globally optimized [ 2.206285] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 3.151075] random: fast init done [ 4.296383] ACPI: Added _OSI(Module Device) [ 4.296445] ACPI: Added _OSI(Processor Device) [ 4.296491] ACPI: Added _OSI(3.0 _SCP Extensions) […] [ 9.679230] async_tx: api initialized (async) [ 9.686639] xor: measuring software checksum speed [ 9.690621] pIII_sse : 2613 MB/sec [ 9.693566] prefetch64-sse : 3470 MB/sec [ 9.693658] xor: using function: prefetch64-sse (3470 MB/sec) [ 10.118527] PM: Image not found (code -22) [ 10.707861] EXT4-fs (sda5): mounting ext3 file system using the ext4 subsystem [ 11.001037] EXT4-fs (sda5): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. [ 11.404831] Not activating Mandatory Access Control as /sbin/tomoyo-init does not exist. [ 11.714778] random: crng init done [ 31.532783] cpufreq: CPU0: Fast frequency switching not enabled [ 31.539524] cpufreq: Registered transition notifiers: [ 31.546840] cpufreq: time_cpufreq_notifier+0x0/0x1a0 [ 32.023172] i801_smbus 0000:00:1f.3: SMBus using polling [ 32.032782] i2c i2c-4: 2/2 memory slots populated (from DMI) […] [ 35.450597] pcmcia_socket pcmcia_socket1: cs: IO port probe 0x4000-0x8fff: [ 35.459776] excluding 0x4000-0x40ff 0x4400-0x44ff 0x4800-0x48ff 0x4c00-0x4cff 0x8000-0x803f [ 35.495530] yenta_cardbus 0000:02:00.1: pcmcia: parent PCI bridge window: [mem 0xd0200000-0xdfffffff] [ 35.503256] pcmcia_socket pcmcia_socket1: cs: memory probe 0xd0200000-0xdfffffff: [ 35.511045] excluding 0xd0200000-0xd09fffff 0xd3a00000-0xdc1fffff 0xdfa00000-0xe01fffff [ 35.519037] yenta_cardbus 0000:02:00.1: pcmcia: parent PCI bridge window: [mem 0xf0000000-0xf7ffffff pref] [ 35.560928] pcmcia_socket pcmcia_socket1: cs: memory probe 0xf0000000-0xf7ffffff: [ 35.569254] excluding 0xf0000000-0xf7ffffff [ 37.435830] snd_intel8x0 0000:00:1f.5: intel8x0_measure_ac97_clock: measured 57937 usecs (2784 samples) [ 37.444010] snd_intel8x0 0000:00:1f.5: clocking to 48000 [ 39.404876] ppdev: user-space parallel port driver [ 40.001371] iTCO_vendor_support: vendor-support=0 [ 40.377988] iTCO_wdt iTCO_wdt.1.auto: Found a ICH3-M TCO device (Version=1, TCOBASE=0x1060) [ 40.381142] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) [ 41.474051] pcmcia_socket pcmcia_socket0: cs: IO port probe 0x100-0x3af: [ 41.475754] excluding 0x170-0x177 0x1f0-0x1f7 0x2f8-0x2ff 0x370-0x377 [ 41.476998] pcmcia_socket pcmcia_socket0: cs: IO port probe 0x3e0-0x4ff: [ 41.477750] excluding 0x3f0-0x3ff 0x4d0-0x4d7 [ 41.478520] pcmcia_socket pcmcia_socket0: cs: IO port probe 0x820-0x8ff: [ 41.479555] clean […] [ 41.740158] pcmcia_socket pcmcia_socket1: cs: memory probe 0x60000000-0x60ffffff: [ 41.750484] clean [ 41.759629] pcmcia_socket pcmcia_socket1: cs: IO port probe 0xa00-0xaff: [ 41.769811] clean [ 43.319745] e100 0000:02:08.0 eth3: renamed from eth0 [ 47.615381] Adding 996024k swap on /dev/sda3. Priority:-2 extents:1 across:996024k FS [ 47.814540] EXT4-fs (sda5): re-mounted. Opts: errors=remount-ro. Quota mode: none. [ 49.287323] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. [ 49.297454] device-mapper: uevent: version 1.0.3 [ 49.315332] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com [ 53.431209] EXT4-fs (sda1): mounting ext3 file system using the ext4 subsystem [ 53.533585] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. [ 53.543153] ext3 filesystem being mounted at /boot supports timestamps until 2038 (0x7fffffff) [ 68.767855] IPMI message handler: version 39.2 [ 68.807226] ipmi device interface [ 68.960101] loop: module loaded [ 87.835613] alg: No test for fips(ansi_cprng) (fips_ansi_cprng) [ 89.176702] Bluetooth: Core ver 2.22 [ 89.199553] NET: Registered PF_BLUETOOTH protocol family [ 89.208479] Bluetooth: HCI device and connection manager initialized [ 89.239463] Bluetooth: HCI socket layer initialized [ 89.255159] Bluetooth: L2CAP socket layer initialized [ 89.275287] Bluetooth: SCO socket layer initialized [ 90.371994] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 90.381094] Bluetooth: BNEP filters: protocol multicast [ 90.390193] Bluetooth: BNEP socket layer initialized [ 163.081514] NET: Registered PF_APPLETALK protocol family [ 170.183260] e100 0000:02:08.0 eth3: NIC Link is Up 100 Mbps Full Duplex [ 170.183434] IPv6: ADDRCONF(NETDEV_CHANGE): eth3: link becomes ready [ 174.811369] e100 0000:02:08.0 eth3: NIC Link is Up 100 Mbps Full Duplex [ 174.811658] IPv6: ADDRCONF(NETDEV_CHANGE): eth3: link becomes ready [ 176.127265] e100 0000:02:08.0 eth3: NIC Link is Up 100 Mbps Full Duplex [ 176.127483] IPv6: ADDRCONF(NETDEV_CHANGE): eth3: link becomes ready And this is where dmesg output ends anyway. The last gap until when network is up seems to be normal to me. Regards, Axel -- ,''`. | Axel Beckert <abe@debian.org>, https://people.debian.org/~abe/ : :' : | Debian Developer, ftp.ch.debian.org Admin `. `' | 4096R: 2517 B724 C5F6 CA99 5329 6E61 2FF9 CD59 6126 16B5 `- | 1024D: F067 EA27 26B9 C3FC 1486 202E C09E 1D89 9593 0EDE
Attachment:
boot-hangs_686-pae_5.6.11-1.jpg
Description: JPEG image
Attachment:
5.15.15-2.dmesg.xz
Description: application/xz