Attachment 876068 Details for Bug 1214353

journalctl_-u_ModemManager_-u_NetworkManager.txt

journalctl_-u_ModemManager_-u_NetworkManager.txt (text/plain), 9.24 KB, created by Holger Sickenberg on 2024-07-16 11:27:28 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Holger Sickenberg

Created: 2024-07-16 11:27:28 UTC

Size: 9.24 KB

patch

obsolete

>Jul 16 13:17:53 t14s NetworkManager[1921]: <info>  [1721128673.4258] manager: rfkill: WWAN hardware radio set disabled
>Jul 16 13:17:53 t14s NetworkManager[1921]: <info>  [1721128673.4259] device (wwan0mbim0): state change: disconnected -> unavailable (reason 'none', sys-iface-state: 'managed')
>Jul 16 13:17:53 t14s NetworkManager[1921]: <info>  [1721128673.4263] audit: op="radio-control" arg="wwan-enabled:off" pid=3250 uid=1000 result="success"
>Jul 16 13:17:53 t14s NetworkManager[1921]: <info>  [1721128673.4272] manager: rfkill: WWAN now disabled by radio killswitch
>Jul 16 13:17:55 t14s NetworkManager[1921]: <info>  [1721128675.4359] manager: rfkill: WWAN hardware radio set enabled
>Jul 16 13:17:55 t14s NetworkManager[1921]: <info>  [1721128675.4361] audit: op="radio-control" arg="wwan-enabled:on" pid=3250 uid=1000 result="success"
>Jul 16 13:17:55 t14s NetworkManager[1921]: <info>  [1721128675.4362] device (wwan0mbim0): state change: unavailable -> disconnected (reason 'modem-available', sys-iface-state: 'managed')
>Jul 16 13:17:55 t14s ModemManager[2193]: <info>  [modem0] state changed (disabled -> enabling)
>Jul 16 13:17:55 t14s NetworkManager[1921]: <info>  [1721128675.4387] manager: rfkill: WWAN now enabled by radio killswitch
>Jul 16 13:17:55 t14s ModemManager[2193]: <warn>  [modem0] Busy
>Jul 16 13:17:55 t14s ModemManager[2193]: <warn>  [modem0] Busy
>Jul 16 13:17:55 t14s ModemManager[2193]: <warn>  [modem0] couldn't enable interface: 'Invalid transition'
>Jul 16 13:17:55 t14s ModemManager[2193]: <info>  [modem0] state changed (enabling -> disabled)
>Jul 16 13:17:55 t14s NetworkManager[1921]: <warn>  [1721128675.6371] modem-broadband[wwan0mbim0]: failed to enable modem: GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Retry: Invalid transition
>Jul 16 13:18:03 t14s NetworkManager[1921]: <info>  [1721128683.0595] device (wwan0mbim0): Activation: starting connection 'Vodafone Default' (2eb9eaa3-9899-4400-8ee2-3b5e01894f7d)
>Jul 16 13:18:03 t14s NetworkManager[1921]: <info>  [1721128683.0595] audit: op="connection-activate" uuid="2eb9eaa3-9899-4400-8ee2-3b5e01894f7d" name="Vodafone Default" pid=3250 uid=1000 result="success"
>Jul 16 13:18:03 t14s NetworkManager[1921]: <info>  [1721128683.0596] device (wwan0mbim0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
>Jul 16 13:18:03 t14s ModemManager[2193]: <info>  [modem0] simple connect started...
>Jul 16 13:18:03 t14s ModemManager[2193]: <info>  [modem0] simple connect state (3/10): enable
>Jul 16 13:18:03 t14s ModemManager[2193]: <info>  [modem0] state changed (disabled -> enabling)
>Jul 16 13:18:03 t14s ModemManager[2193]: <warn>  [modem0] Busy
>Jul 16 13:18:03 t14s ModemManager[2193]: <warn>  [modem0] Busy
>Jul 16 13:18:03 t14s ModemManager[2193]: <warn>  [modem0] couldn't enable interface: 'Invalid transition'
>Jul 16 13:18:03 t14s ModemManager[2193]: <info>  [modem0] state changed (enabling -> disabled)
>Jul 16 13:18:03 t14s NetworkManager[1921]: <info>  [1721128683.2264] device (wwan0mbim0): state change: prepare -> disconnected (reason 'user-requested', sys-iface-state: 'managed')
>Jul 16 13:18:03 t14s ModemManager[2193]: <info>  [modem0] simple connect started...
>Jul 16 13:18:03 t14s ModemManager[2193]: <info>  [modem0] simple connect state (3/10): enable
>Jul 16 13:18:03 t14s ModemManager[2193]: <info>  [modem0] state changed (disabled -> enabling)
>Jul 16 13:18:03 t14s ModemManager[2193]: <warn>  [modem0] Busy
>Jul 16 13:18:03 t14s ModemManager[2193]: <warn>  [modem0] Busy
>Jul 16 13:18:03 t14s ModemManager[2193]: <warn>  [modem0] couldn't enable interface: 'Invalid transition'
>Jul 16 13:18:03 t14s ModemManager[2193]: <info>  [modem0] state changed (enabling -> disabled)
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.1294] device (wwan0mbim0): Activation: starting connection 'Vodafone Default' (2eb9eaa3-9899-4400-8ee2-3b5e01894f7d)
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.1295] audit: op="connection-activate" uuid="2eb9eaa3-9899-4400-8ee2-3b5e01894f7d" name="Vodafone Default" pid=3250 uid=1000 result="success"
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.1296] device (wwan0mbim0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] simple connect started...
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] simple connect state (3/10): enable
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] state changed (disabled -> enabling)
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] power state updated: on
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] simple connect state (4/10): wait to get fully enabled
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] state changed (enabling -> enabled)
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] 3GPP registration state changed (unknown -> registering)
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] simple connect state (5/10): wait after enabled
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] 3GPP registration state changed (registering -> home)
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] state changed (enabled -> registered)
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] simple connect state (6/10): register
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] simple connect state (7/10): wait to get packet service state attached
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] simple connect state (8/10): bearer
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] simple connect state (9/10): connect
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] state changed (registered -> connecting)
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0/bearer1] reloading stats is supported by the device
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] state changed (connecting -> connected)
>Jul 16 13:19:32 t14s ModemManager[2193]: <info>  [modem0] simple connect state (10/10): all done
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3930] device (wwan0mbim0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3932] device (wwan0mbim0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3936] modem-broadband[wwan0mbim0]: IPv4 static configuration:
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3936] modem-broadband[wwan0mbim0]:   address 100.109.192.253/30 brd* 100.109.192.255 lft forever pref forever lifetime 8529-0[0,0] src wwan
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3936] modem-broadband[wwan0mbim0]:   gateway 100.109.192.254
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3936] modem-broadband[wwan0mbim0]:   DNS 139.7.30.125
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3936] modem-broadband[wwan0mbim0]:   DNS 139.7.30.126
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3936] modem-broadband[wwan0mbim0]:   MTU 1500
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3936] modem-broadband[wwan0mbim0]: IPv6 base configuration:
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3937] modem-broadband[wwan0mbim0]:   address 2a00:20:c288:3793:3987:14b4:5664:dedd/64 lft forever pref forever lifetime 8529-0[0,0] src unknown (slaac disabled)
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3937] modem-broadband[wwan0mbim0]:   gateway 2a00:20:c288:3793:808c:de86:f7c5:f492
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3937] modem-broadband[wwan0mbim0]:   DNS 2a01:860:0:300::53
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.3937] modem-broadband[wwan0mbim0]:   DNS 2a01:860:0:300::153
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.5165] device (wwan0mbim0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.5524] device (wwan0mbim0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.5526] device (wwan0mbim0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
>Jul 16 13:19:32 t14s NetworkManager[1921]: <info>  [1721128772.5528] device (wwan0mbim0): Activation: successful, device activated.
>Jul 16 13:19:35 t14s NetworkManager[1921]: <info>  [1721128775.7166] device (wwan0mbim0): state change: activated -> deactivating (reason 'user-requested', sys-iface-state: 'managed')
>Jul 16 13:19:35 t14s NetworkManager[1921]: <info>  [1721128775.7172] audit: op="device-disconnect" interface="wwan0" ifindex=5 pid=3250 uid=1000 result="success"
>Jul 16 13:19:35 t14s ModemManager[2193]: <info>  [modem0] state changed (connected -> disconnecting)
>Jul 16 13:19:35 t14s ModemManager[2193]: <info>  [modem0] state changed (disconnecting -> registered)
>Jul 16 13:19:35 t14s ModemManager[2193]: <info>  [modem0/bearer1] connection #1 finished: duration 3s, tx: 0 bytes, rx: 192 bytes
>Jul 16 13:19:35 t14s NetworkManager[1921]: <info>  [1721128775.7394] device (wwan0mbim0): state change: deactivating -> disconnected (reason 'user-requested', sys-iface-state: 'managed')

Actions: View

Attachments on bug 1214353: 868851 | 868852 | 869786 | 869787 | 876030 | 876031 | 876068