Leap 15.5 horrible slow to start due to postfix

English Network/Internet
1

I have a laptop upgraded from 15.4 to 15.5. Now the boot process is a hit-and-miss. When the boot process is a miss postfix.services takes 3m 813ms to launch. The other blame factor is the network-wait-online.target with 25s. That the network-wait-online.target is taking some time is not unexpected, but should not be the case when only connected by wi-fi. When this does slow boot occurs load openSUSE is not loading the drivers for the audio and wi-fi thus not able to connect and I’m not able to restart these services unless I do a successful reboot.

In the hit scenario I get into KDE plasma without a glitch and am asked to enter the passphrase to access the wi-fi.

I have set the wi-fi to only use IPv4, but have no clue as to why this irregular behavior exists and why postfix would be taking 3m to start.

Any suggestions where to look to debug this?

2

Please post user:
inxi -F

to see your Hardware and maybe drivers loaded.

also as root:
systemd-analyze blame

Do the logs from postfix show any errors?
Or the service:
systemctl status postfix.service

or do you see anything related to postfix in journald?

3

Postfix starts up fast on infamous hosts erlangen and 3400g:

3400g:~ # journalctl -b -u postfix.service -o short-monotonic 
[    7.782045] 3400g systemd[1]: Starting Postfix Mail Transport Agent...
[    7.812221] 3400g echo[991]: Starting mail service (Postfix)
[    7.864264] 3400g (storecon)[1005]: postfix.service: Executable /sbin/restorecon missing, skipping: No such file or directory
[    8.261924] 3400g postfix[1130]: postfix/postlog: starting the Postfix mail system
[    8.262154] 3400g postfix/postfix-script[1130]: starting the Postfix mail system
[    8.267613] 3400g postfix/master[1132]: daemon started -- version 3.8.1, configuration /etc/postfix
[    8.375397] 3400g systemd[1]: Started Postfix Mail Transport Agent.
3400g:~ #

You may show start of units on your system:

3400g:~ # journalctl -b -u init.scope -g Started -o short-monotonic --no-pager
[    2.838096] 3400g systemd[1]: Started Rule-based Manager for Device Events and Files.
[    3.161031] 3400g systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    6.470339] 3400g systemd[1]: Started Rule-based Manager for Device Events and Files.
[    7.328609] 3400g systemd[1]: Started Watch /etc/sysconfig/btrfsmaintenance.
[    7.328926] 3400g systemd[1]: Started Watch for changes in CA certificates.
[    7.329753] 3400g systemd[1]: Started CUPS Scheduler.
[    7.329822] 3400g systemd[1]: Started Watch for changes in issue snippets.
[    7.330030] 3400g systemd[1]: Started Watch for changes in smartmontools sysconfig file.
[    7.330119] 3400g systemd[1]: Started Daily Cleanup of Snapper Snapshots.
[    7.330183] 3400g systemd[1]: Started Daily Cleanup of Temporary Directories.
[    7.332177] 3400g systemd[1]: Started Disable backup HDD.
[    7.339236] 3400g systemd[1]: Started hd-idle disk spindown service.
[    7.340678] 3400g systemd[1]: Started irqbalance daemon.
[    7.396510] 3400g systemd[1]: Started D-Bus System Message Bus.
[    7.398414] 3400g systemd[1]: Started User Login Management.
[    7.557364] 3400g systemd[1]: Started Machine Check Exception Logging Daemon.
[    7.557798] 3400g systemd[1]: Started Avahi mDNS/DNS-SD Stack.
[    7.557913] 3400g systemd[1]: Started Authorization Manager.
[    7.628565] 3400g systemd[1]: Started Network Manager.
[    7.630340] 3400g systemd[1]: Started Modem Manager.
[    7.669643] 3400g systemd[1]: Started MiniDLNA is a DLNA/UPnP-AV server software.
[    7.725843] 3400g systemd[1]: Started Hostname Service.
[    7.754799] 3400g systemd[1]: Started Network Manager Script Dispatcher Service.
[    7.773431] 3400g systemd[1]: Started NTP client/server.
[    7.774146] 3400g systemd[1]: Started Backup of /home.
[    7.774324] 3400g systemd[1]: Started Backup of RPM database.
[    7.774509] 3400g systemd[1]: Started Backup of /etc/sysconfig.
[    7.774746] 3400g systemd[1]: Started Balance block groups on a btrfs filesystem.
[    7.774983] 3400g systemd[1]: Started Defragment file data and/or directory metadata.
[    7.775218] 3400g systemd[1]: Started Scrub btrfs filesystem, verify block checksums.
[    7.775449] 3400g systemd[1]: Started Discard unused blocks on a mounted filesystem.
[    7.776103] 3400g systemd[1]: Started Daily dist-upgrade.
[    7.776330] 3400g systemd[1]: Started Discard unused blocks once a week.
[    7.776504] 3400g systemd[1]: Started Daily rotation of log files.
[    7.776823] 3400g systemd[1]: Started Daily man-db regeneration.
[    7.777004] 3400g systemd[1]: Started Daily locate database update.
[    7.777163] 3400g systemd[1]: Started Timeline of Snapper Snapshots.
[    7.786311] 3400g systemd[1]: Started CUPS Scheduler.
[    7.977555] 3400g systemd[1]: Started OpenSSH Daemon.
[    8.032498] 3400g systemd[1]: Started The Apache Webserver.
[    8.036664] 3400g systemd[1]: Started Getty on tty1.
[    8.375397] 3400g systemd[1]: Started Postfix Mail Transport Agent.
[    8.574744] 3400g systemd[1]: Started X Display Manager.
[    9.274511] 3400g systemd[1]: Started User Manager for UID 477.
[    9.275273] 3400g systemd[1]: Started Session 1 of User sddm.
[    9.840635] 3400g systemd[1]: Started Disk Manager.
[    9.889445] 3400g systemd[1]: Started Daemon for power management.
[   11.852332] 3400g systemd[1]: Started A remote-mail retrieval utility.
[   19.115147] 3400g systemd[1]: Started User Manager for UID 1000.
[   19.115532] 3400g systemd[1]: Started Session 3 of User karl.
...
3400g:~ #
4

systemd-analyze blame:

3min 884ms postfix.service
25.077s NetworkManager-wait-online.service
8.072s dev-ttyS19.device
8.072s sys-devices-platform-serial8250-tty-ttyS19.device
8.069s dev-ttyS15.device
8.069s sys-devices-platform-serial8250-tty-ttyS15.device
8.064s sys-devices-platform-serial8250-tty-ttyS1.device
8.064s dev-ttyS1.device

Inxi output (had to terminate it with CTRL+C)

System:
Host: Laptop Kernel: 5.14.21-150500.53-default arch: x86_64 bits: 64
Desktop: KDE Plasma v: 5.27.4 Distro: openSUSE Leap 15.5
Machine:
Type: Laptop System: LENOVO product: 80E3 v: Lenovo G50-45
serial: 1073408000062
Mobo: LENOVO model: Lancer 5B2 serial: PF0EZRGV UEFI: LENOVO
v: A2CN40WW(V2.08) date: 08/21/2015
Battery:
ID-1: BAT1 charge: 22.2 Wh (100.0%) condition: 22.2/28.5 Wh (78.0%)
CPU:
Info: quad core model: AMD A8-6410 APU with AMD Radeon R5 Graphics bits: 64
type: MCP cache: L2: 2 MiB
Speed (MHz): avg: 1250 min/max: 1000/2000 cores: 1: 1000 2: 1000 3: 1000
4: 2000
Graphics:
Device-1: AMD Mullins [Radeon R4/R5 Graphics] driver: radeon v: kernel
Device-2: IMC Networks Lenovo EasyCamera type: USB driver: uvcvideo
Display: x11 server: X.Org v: 1.21.1.4 with: Xwayland v: 22.1.5 driver: X:
loaded: radeon unloaded: fbdev,modesetting,vesa dri: radeonsi gpu: radeon
resolution: 1366x768~60Hz
API: OpenGL v: 4.5 Mesa 22.3.5 renderer: KABINI ( LLVM 15.0.7 DRM 2.50
5.14.21-150500.53-default)

Postfix status:

× postfix.service - Postfix Mail Transport Agent
Loaded: loaded (/usr/lib/systemd/system/postfix.service; enabled; vendor preset: enabled)
Active: failed (Result: timeout) since Thu 2023-06-22 13:41:22 CST; 2min 14s ago
Process: 1299 ExecStartPre=/bin/echo Starting mail service (Postfix) (code=exited, status=0/SUCCESS)
Process: 1332 ExecStartPre=/usr/lib/postfix/systemd/config_postfix (code=exited, status=0/SUCCESS)
Process: 1350 ExecStartPre=/usr/lib/postfix/systemd/update_chroot (code=exited, status=0/SUCCESS)
Process: 1362 ExecStartPre=/usr/lib/postfix/systemd/update_postmaps (code=killed, signal=TERM)
Process: 2998 ExecStopPost=/usr/lib/postfix/systemd/cond_slp deregister (code=killed, signal=TERM)
Tasks: 2 (limit: 3993)
CGroup: /system.slice/postfix.service
├─ 1374 postconf default_database_type
└─ 3008 /usr/sbin/postconf -h inet_interfaces

Jun 22 13:38:21 JK-Laptop systemd[1]: Starting Postfix Mail Transport Agent…
Jun 22 13:38:21 JK-Laptop echo[1299]: Starting mail service (Postfix)
Jun 22 13:39:51 JK-Laptop systemd[1]: postfix.service: start-pre operation timed out. Terminating.
Jun 22 13:39:51 JK-Laptop systemd[1]: postfix.service: Control process exited, code=killed, status=15/TERM
Jun 22 13:41:22 JK-Laptop systemd[1]: postfix.service: State ‘stop-post’ timed out. Terminating.
Jun 22 13:41:22 JK-Laptop systemd[1]: postfix.service: Control process exited, code=killed, status=15/TERM
Jun 22 13:41:22 JK-Laptop systemd[1]: postfix.service: Failed with result ‘timeout’.
Jun 22 13:41:22 JK-Laptop systemd[1]: postfix.service: Unit process 1374 (postconf) remains running after unit stopped.
Jun 22 13:41:22 JK-Laptop systemd[1]: postfix.service: Unit process 3008 (postconf) remains running after unit stopped.
Jun 22 13:41:22 JK-Laptop systemd[1]: Failed to start Postfix Mail Transport Agent.

It seems 1 in 2 boot sequences does give me the bad behavior.

5

Inxi output (had to terminate it with CTRL+C)

Why that?
Audio or Network Problem?
Post:
/sbin/lspci -nnk | grep -iA3 net
lsusb

6

lsusb

Bus 002 Device 005: ID 0cf3:3004 Qualcomm Atheros Communications AR3012 Bluetooth 4.0
Bus 002 Device 003: ID 13d3:5727 IMC Networks Lenovo EasyCamera
Bus 002 Device 002: ID 0438:7900 Advanced Micro Devices, Inc. Root Hub
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 001 Device 003: ID 0bda:0129 Realtek Semiconductor Corp. RTS5129 Card Reader Controller
Bus 001 Device 002: ID 0438:7900 Advanced Micro Devices, Inc. Root Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 004 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 003 Device 002: ID 093a:2510 Pixart Imaging, Inc. Optical Mouse
Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

/sbin/lspci -nnk | grep -iA3 net

01:00.0 Network controller [0280]: Qualcomm Atheros QCA9565 / AR9565 Wireless Network Adapter [168c:0036] (rev 01)
        Subsystem: Lenovo Device [17aa:4026]
        Kernel driver in use: ath9k
        Kernel modules: ath9k
02:00.0 Ethernet controller [0200]: Realtek Semiconductor Co., Ltd. RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller [10ec:8168] (rev 10)
        Subsystem: Lenovo Device [17aa:3812]
        Kernel driver in use: r8169
        Kernel modules: r8169

I just rebooted 6-times without issue. The above is the result of a successful boot. When I revert back to 15.4 through snapper all issues are resolved. So it has something to do with 15.5 in combination with the hardware/ driver, but I’m not sure where to look.

7

Post from Leap 15.5
zypper lr -d
zypper se -si | grep -Ei 'systemp|system_p|system-p'

8

Start with providing full output as root of journalctl -b --no-pager --full after boot with this problem. Upload to https://susepaste.org/

9

Reviving my thread as it has been happening again.
Journalctl output paste: openSUSE Paste

10 
Aug 23 09:41:28 JK-Laptop kernel: BUG: kernel NULL pointer dereference, address: 0000000000000000
Aug 23 09:41:28 JK-Laptop kernel: #PF: supervisor write access in kernel mode
Aug 23 09:41:28 JK-Laptop kernel: #PF: error_code(0x0002) - not-present page
Aug 23 09:41:28 JK-Laptop kernel: PGD 0 P4D 0 
Aug 23 09:41:28 JK-Laptop kernel: Oops: 0002 [#1] PREEMPT SMP NOPTI
Aug 23 09:41:28 JK-Laptop kernel: CPU: 1 PID: 1232 Comm: NetworkManager Tainted: G        W         N 5.14.21-150500.55.19-default #1 SLE15-SP5 a29285bac85e8baa0757c512c91f36229b4faaae

The stack trace points to the ath9k kernel driver. Briefly looking at kernel git log, there are some recent fixes that could be related.

Try the latest stable kernel: https://download.opensuse.org/repositories/Kernel:/stable/standard/

If it works, open bug report on https://bugzilla.opensuse.org/ (same user/password as here).

11

For Leap 15.5, it might be better to try: http://download.opensuse.org/repositories/Kernel:/stable:/Backport/standard/

12

@t_neo if your only using ipv4, then edit the /etc/postfix/main.cf file and edit the line inet_protocols equals all to ipv4 eg inet_protocols = ipv4.