VPN: Timeout exceeded when connecting to VPN for the second+ time
Summary
When I try to connect to a VPN connection that was previously activated, the activation takes very long. Sometimes, the re-connect hits DBUS_DEFAULT_TIMEOUT_MSEC
.
After re-establishing internet connection (nmcli con down WIFI; nmcli con up WIFI
), VPN activation takes place within a normal time frame.
I tried to analyze the problem further, seeing some additional RTM_NEWROUTE
debug log statements when connecting for the second time. Therefore, I played around with routing, setting ipv4.auto-route-ext-gw
and/or ipv4.ipv4.ignore-auto-routes
, but only re-establishing the main connection had an impact on the activation time.
After first deactivation, WIFI connection doesn't change much: After first deactivation of VPN connection, the order of routes changes (Which shouldn't affect the connection, I assume).
Version affected
network-manager/mantic-updates,now 1.44.2-1ubuntu1.2 network-manager-openconnect/mantic,now 1.2.10-1 amd64 network-manager-openconnect-gnome/mantic,now openconnect/mantic,now 9.12-1 amd64
Steps to reproduce
- Connect to internet, e.g. with wlp0s20f3
- Activate VPN connection
- Authentication via
openconnect
takes ~4s (on every activation) - Activation of VPN connection via
nmcli con up VPN passwd-file $file
takes ~2s
- Authentication via
- Deactivate VPN connection
- Activate VPN connection again
Actual result
Second (+n) activation hits timeout or takes very long
Expected result
Second (+n) activation is as fast as first activation
Relevant logs
Before / after VPN connection:
default via 192.168.1.1 dev wlp0s20f3 proto dhcp src 192.168.1.228 metric 600
169.254.0.0/16 dev wlp0s20f3 scope link metric 1000
192.168.1.0/24 dev wlp0s20f3 proto kernel scope link src 192.168.1.228 metric 600
local 127.0.0.0/8 dev lo table local proto kernel scope host src 127.0.0.1
local 127.0.0.1 dev lo table local proto kernel scope host src 127.0.0.1
broadcast 127.255.255.255 dev lo table local proto kernel scope link src 127.0.0.1
local 192.168.1.228 dev wlp0s20f3 table local proto kernel scope host src 192.168.1.228
broadcast 192.168.1.255 dev wlp0s20f3 table local proto kernel scope link src 192.168.1.228
First activation:
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4595] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: update-from-dbus: update profile "corporate-vpn"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4595] settings: storage[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,da22c03c33c85daa/keyfile]: change event with connection "corporate-vpn" (file "/run/NetworkManager/system-connections/netplan-NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c.nmconnection")
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4595] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: updating connection "corporate-vpn" (da22c03c33c85daa/keyfile), new version-id 3
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4598] ++ connection 'update connection' (0x5cf9fd8da810/NMSimpleConnection/"vpn" < 0x5cf9fd7c5e60/NMSimpleConnection/"vpn") [/org/freedesktop/NetworkManager/Settings/4]:
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4598] ++ connection [ 0x5cf9fd8d93d0 < 0x5cf9fd7c5ff0 ]
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4598] ++ connection.timestamp = 1711841211
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4598] policy: block-autoconnect: unblocking port profiles for controller ifname=(null), uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4600] Saving secrets for connection /org/freedesktop/NetworkManager/Settings/4 (corporate-vpn)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4600] agent-manager: agent[096f246c3faf7f97,:1.109/org.gnome.Shell.NetworkAgent/946889485]: agent ignored for secrets request [cd1dbcdf539b1650/"corporate-vpn"sav] (uid 946889485 not required 0)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <info> [1711922171.4601] audit: op="connection-update" uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c" name="corporate-vpn" args="connection.timestamp" pid=5380 uid=0 result="success"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4602] device[2b39ee29e019c16f] (lo): add_pending_action (1): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4602] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): add_pending_action (1): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4603] device[2b39ee29e019c16f] (lo): remove_pending_action (0): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4603] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): remove_pending_action (0): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4727] secret-agent[8bfd90cc313c77bf]: constructed: :1.175/nmcli-connect/0, owner="root" (unix-process[pid=5391, uid=0, start=9483]), unique-name=":1.166", capabilities=vpn-hints
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4728] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: requesting permissions
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4728] auth: call[73]: CheckAuthorization(org.freedesktop.NetworkManager.wifi.share.protected), subject=unix-process[pid=5391, uid=0, start=9483] (succeeding for root)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4728] auth: call[74]: CheckAuthorization(org.freedesktop.NetworkManager.wifi.share.open), subject=unix-process[pid=5391, uid=0, start=9483] (succeeding for root)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4728] auth: call[73]: completed: authorized=1, challenge=0 (simulated)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4728] auth: call[74]: completed: authorized=1, challenge=0 (simulated)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <info> [1711922171.4728] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: agent registered
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4728] policy: re-enabling autoconnect for all connections (only clear no-secrets flag)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4729] secret-agent[8bfd90cc313c77bf]: name-owner-changed: has :1.175
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4731] active-connection[2252af6eb7508cc4]: creating
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4731] active-connection[2252af6eb7508cc4]: set device "enxf4a80d0c2bfb" [0x5cf9fd7f9500]
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4731] device[f46cd55144f63e9a] (enxf4a80d0c2bfb): add_pending_action (1): 'activation-4'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4732] active-connection[2252af6eb7508cc4]: constructed (NMVpnConnection, version-id 4, type managed)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4732] auth: call[75]: CheckAuthorization(org.freedesktop.NetworkManager.network-control), subject=unix-process[pid=5391, uid=0, start=9483] (succeeding for root)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4732] auth: call[75]: completed: authorized=1, challenge=0 (simulated)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4732] dbus-object[2252af6eb7508cc4]: export: "/org/freedesktop/NetworkManager/ActiveConnection/4"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <info> [1711922171.4733] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: starting openconnect
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4733] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: starting: watch D-Bus service org.freedesktop.NetworkManager.openconnect.Connection_4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4733] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: set state: prepare (was waiting)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4733] active-connection[2252af6eb7508cc4]: set state activating (was unknown)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4734] active-connection[2252af6eb7508cc4]: check-master-ready: not signalling (state activating, no master)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <info> [1711922171.4735] audit: op="connection-activate" uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c" name="corporate-vpn" pid=5391 uid=0 result="success"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4735] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: no name owner for org.freedesktop.NetworkManager.openconnect.Connection_4 (start VPN service)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4825] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: starting: VPN service has PID 5397
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4893] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: name owner :1.176 for org.freedesktop.NetworkManager.openconnect.Connection_4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4893] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: set state: need-auth (was prepare)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4894] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: requesting VPN secrets pass #1
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4896] Secrets requested for connection /org/freedesktop/NetworkManager/Settings/4 (corporate-vpn/vpn)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4896] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd770c20) secrets requested flags 0x80000004 hints '(none)'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4897] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) system settings secrets sufficient
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4898] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd83f280) existing secrets returned
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4898] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd83f280) secrets request completed
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4898] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd83f280) new agent secrets processed
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4899] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: get-new-secrets: update profile "corporate-vpn" (not persisted)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4899] settings: storage[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,da22c03c33c85daa/keyfile]: change event with connection "corporate-vpn" (file "/run/NetworkManager/system-connections/netplan-NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c.nmconnection")
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4899] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: updating connection "corporate-vpn" (da22c03c33c85daa/keyfile), new version-id 4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4900] policy: block-autoconnect: unblocking port profiles for controller ifname=(null), uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4902] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: asking service if additional secrets are required
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4902] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call NeedSecrets on org.freedesktop.NetworkManager.openconnect.Connection_4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4903] device[2b39ee29e019c16f] (lo): add_pending_action (1): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4903] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): add_pending_action (1): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4903] device[2b39ee29e019c16f] (lo): remove_pending_action (0): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4903] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): remove_pending_action (0): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4911] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: service indicated additional secrets required
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4911] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: requesting VPN secrets pass #2
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4913] Secrets requested for connection /org/freedesktop/NetworkManager/Settings/4 (corporate-vpn/vpn)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4915] agent-manager: agent[096f246c3faf7f97,:1.109/org.gnome.Shell.NetworkAgent/946889485]: agent ignored for secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"] (uid 946889485 not required 0)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4915] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: agent allowed for secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4915] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd770c20) secrets requested flags 0x4 hints '(none)'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4916] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) system settings secrets insufficient, asking agents
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4916] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: agent getting secrets for request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4916] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) request has system secrets; checking agent :1.175 for MODIFY
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4916] auth: call[76]: CheckAuthorization(org.freedesktop.NetworkManager.settings.modify.system), subject=unix-process[pid=5391, uid=0, start=9483] (succeeding for root)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4916] auth: call[76]: completed: authorized=1, challenge=0 (simulated)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4916] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: agent [cd1dbcdf539b1650/"corporate-vpn"/"vpn"] MODIFY check result YES
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4918] secret-agent[8bfd90cc313c77bf] request [bd3290c3bec7a0e8,GetSecrets,"/org/freedesktop/NetworkManager/Settings/4"]: new request...
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4924] secret-agent[8bfd90cc313c77bf] request [bd3290c3bec7a0e8,GetSecrets,"/org/freedesktop/NetworkManager/Settings/4"]: completed with failure: Stored passwords not supported
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4924] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: agent failed secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]: Stored passwords not supported
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4924] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd82d640) secrets request error: No agents were available for this request.
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4925] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: asking service if additional secrets are required
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4925] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call NeedSecrets on org.freedesktop.NetworkManager.openconnect.Connection_4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4929] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: service indicated additional secrets required
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4929] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: requesting VPN secrets pass #3
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4930] Secrets requested for connection /org/freedesktop/NetworkManager/Settings/4 (corporate-vpn/vpn)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4932] agent-manager: agent[096f246c3faf7f97,:1.109/org.gnome.Shell.NetworkAgent/946889485]: agent ignored for secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"] (uid 946889485 not required 0)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4932] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: agent allowed for secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4932] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd770c20) secrets requested flags 0x5 hints '(none)'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4933] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) system settings secrets insufficient, asking agents
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4933] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: agent getting secrets for request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4933] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) request has system secrets; checking agent :1.175 for MODIFY
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4933] auth: call[77]: CheckAuthorization(org.freedesktop.NetworkManager.settings.modify.system), subject=unix-process[pid=5391, uid=0, start=9483] (succeeding for root)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4933] auth: call[77]: completed: authorized=1, challenge=0 (simulated)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4933] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: agent [cd1dbcdf539b1650/"corporate-vpn"/"vpn"] MODIFY check result YES
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4934] secret-agent[8bfd90cc313c77bf] request [48b9cf8bb42ca602,GetSecrets,"/org/freedesktop/NetworkManager/Settings/4"]: new request...
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.4944] secret-agent[8bfd90cc313c77bf] request [48b9cf8bb42ca602,GetSecrets,"/org/freedesktop/NetworkManager/Settings/4"]: completed successfully
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4944] agent-manager: agent[8bfd90cc313c77bf,:1.175/nmcli-connect/0]: agent returned secrets for request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4946] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd8bf8e0) secrets returned from agent :1.175
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4946] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd8bf8e0) secrets request completed
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.4947] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd8bf8e0) saving new secrets to backing storage
Mär 31 23:56:11 Christophs-TP14 generate[5405]: nm-device: NM-0033a43d-af0c-42a6-9269-0c533b49777d: the renderer for nm-devices must be NetworkManager, it will be used instead of the defined one.
Mär 31 23:56:11 Christophs-TP14 generate[5405]: nm-device: NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c: the renderer for nm-devices must be NetworkManager, it will be used instead of the defined one.
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5908] keyfile: commit: "/run/NetworkManager/system-connections/netplan-NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c.nmconnection": profile dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c (corporate-vpn) written
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5908] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: get-new-secrets: update profile "corporate-vpn"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5909] settings: storage[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,da22c03c33c85daa/keyfile]: change event with connection "corporate-vpn" (file "/run/NetworkManager/system-connections/netplan-NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c.nmconnection")
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5909] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: updating connection "corporate-vpn" (da22c03c33c85daa/keyfile), new version-id 5
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5911] policy: block-autoconnect: unblocking port profiles for controller ifname=(null), uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5913] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: asking service if additional secrets are required
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5913] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call NeedSecrets on org.freedesktop.NetworkManager.openconnect.Connection_4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5915] device[2b39ee29e019c16f] (lo): add_pending_action (1): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5915] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): add_pending_action (1): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5915] device[2b39ee29e019c16f] (lo): remove_pending_action (0): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5915] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): remove_pending_action (0): 'autoactivate'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5925] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: service indicated no additional secrets required
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5927] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: connect: allowing interactive secrets as all agents have that capability
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5927] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call ConnectInteractive on org.freedesktop.NetworkManager.openconnect.Connection_4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5928] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: set state: connect (was need-auth)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5932] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: connect: falling back to non-interactive connect
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5932] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call Connect on org.freedesktop.NetworkManager.openconnect.Connection_4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5938] platform-linux: event-notification: RTM_NEWLINK, flags 0, seq 0: 4: vpn0 <NOARP,DOWN;pointopoint,multicast,noarp> mtu 1500 arp 65534 tun* not-init tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:0,0 tx:0,0; tun
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5938] platform: (vpn0) signal: link added: 4: vpn0 <NOARP,DOWN;pointopoint,multicast,noarp> mtu 1500 arp 65534 tun* not-init driver tun tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:0,0 tx:0,0
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5939] l3cfg[d8a326c4446fd973,ifindex=4]: created (netns=f4d1c87eb4fa46c2)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5939] l3cfg[d8a326c4446fd973,ifindex=4]: link ifname changed: "vpn0" (initial)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5939] l3cfg[d8a326c4446fd973,ifindex=4]: commit type register (type "none", source "device", existing 9a285cef907f83a3) -> 9a285cef907f83a3
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5939] device[904d833593993b18] (vpn0): ifindex: set ifindex 4 (l3cfg: d8a326c4446fd973)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5939] dbus-object[f06587a02afa30f9]: export: "/org/freedesktop/NetworkManager/IP4Config/4"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5940] dbus-object[931629d4e2dbfc82]: export: "/org/freedesktop/NetworkManager/IP6Config/4"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5941] device[904d833593993b18] (vpn0): constructed (NMDeviceTun)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5941] device[904d833593993b18] (vpn0): start setup of NMDeviceTun, kernel ifindex 4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5942] platform-linux: error reading net:/sys/class/net/vpn0/phys_port_id: error reading 4096 bytes from file descriptor: Vorgang wird nicht unterstützt
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5942] platform-linux: sysctl: reading 'net:/sys/class/net/vpn0/dev_id': '0x0'
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5942] ethtool[4]: ETHTOOL_GDRVINFO, vpn0: success
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5943] platform-linux: error reading net:/sys/class/net/vpn0/device/sriov_numvfs: Failed to open file "device/sriov_numvfs" with openat: Datei oder Verzeichnis nicht gefunden
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5943] device[904d833593993b18] (vpn0): unmanaged: flags set to [platform-init,external-down=0x104/0x104/unmanaged/unrealized], set-unmanaged [external-down=0x100])
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5943] device[904d833593993b18] (vpn0): unmanaged: flags set to [platform-init,external-down,!sleeping=0x104/0x105/unmanaged/unrealized], set-managed [sleeping=0x1])
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5944] dbus-object[904d833593993b18]: export: "/org/freedesktop/NetworkManager/Devices/5"
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <info> [1711922171.5945] manager: (vpn0): new Tun device (/org/freedesktop/NetworkManager/Devices/5)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5989] platform-linux: UDEV event: action 'add' subsys 'net' device 'vpn0' (4); seqnum=5068
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5989] platform-linux: udev-add[vpn0,4]: device added
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5991] platform: (vpn0) signal: link changed: 4: vpn0 <NOARP,DOWN;pointopoint,multicast,noarp> mtu 1500 arp 65534 tun* init driver tun tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:0,0 tx:0,0
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5991] l3cfg[d8a326c4446fd973,ifindex=4]: emit signal (platform-change, obj-type=link, change=changed, obj=4: vpn0 <NOARP,DOWN;pointopoint,multicast,noarp> mtu 1500 arp 65534 tun* init driver tun tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:0,0 tx:0,0; tun)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5991] device[904d833593993b18] (vpn0): queued link change for ifindex 4
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <trace> [1711922171.5991] l3cfg[d8a326c4446fd973,ifindex=4]: emit signal (platform-change-on-idle, obj-type-flags=0x2)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5992] device[904d833593993b18] (vpn0): unmanaged: flags set to [platform-init,external-down,!sleeping,!user-settings=0x104/0x115/unmanaged], set-managed [user-settings=0x10])
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5992] device[904d833593993b18] (vpn0): unmanaged: flags set to [external-down,!sleeping,!platform-init,!user-settings=0x100/0x115/manageable], set-managed [platform-init=0x4], reason managed)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.5992] manager: (vpn0): assume: don't assume because device is not managed
Mär 31 23:56:11 Christophs-TP14 NetworkManager[4548]: <debug> [1711922171.6054] vpn[0x5cf9fd8276a0,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: state changed: starting (3)
Mär 31 23:56:11 Christophs-TP14 NetworkManager[5409]: Verbunden mit corparate.ip:443
Second activation, platform-linux: event-notification: RTM_NEWROUTE
logs visible; vpn
hits timeout:
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7313] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: update-from-dbus: update profile "corporate-vpn"
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7314] settings: storage[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,da22c03c33c85daa/keyfile]: change event with connection "corporate-vpn" (file "/run/NetworkManager/system-connections/netplan-NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c.nmconnection")
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7314] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: updating connection "corporate-vpn" (da22c03c33c85daa/keyfile), new version-id 6
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7317] ++ connection 'update connection' (0x5cf9fd89e460/NMSimpleConnection/"vpn" < 0x5cf9fd8da810/NMSimpleConnection/"vpn") [/org/freedesktop/NetworkManager/Settings/4]:
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7317] ++ connection [ 0x5cf9fd899570 < 0x5cf9fd8d93d0 ]
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7317] ++ connection.timestamp = 1711922485 < 1711841211
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7318] policy: block-autoconnect: unblocking port profiles for controller ifname=(null), uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c"
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7318] Saving secrets for connection /org/freedesktop/NetworkManager/Settings/4 (corporate-vpn)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7319] agent-manager: agent[096f246c3faf7f97,:1.109/org.gnome.Shell.NetworkAgent/946889485]: agent ignored for secrets request [cd1dbcdf539b1650/"corporate-vpn"sav] (uid 946889485 not required 0)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <info> [1711922491.7319] audit: op="connection-update" uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c" name="corporate-vpn" args="connection.timestamp" pid=6577 uid=0 result="success"
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7320] device[2b39ee29e019c16f] (lo): add_pending_action (1): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7320] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): add_pending_action (1): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7320] device[2b39ee29e019c16f] (lo): remove_pending_action (0): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7321] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): remove_pending_action (0): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7452] secret-agent[f0267f4497eefe1b]: constructed: :1.201/nmcli-connect/0, owner="root" (unix-process[pid=6588, uid=0, start=41544]), unique-name=":1.166", capabilities=vpn-hints
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7453] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: requesting permissions
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7453] auth: call[80]: CheckAuthorization(org.freedesktop.NetworkManager.wifi.share.protected), subject=unix-process[pid=6588, uid=0, start=41544] (succeeding for root)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7453] auth: call[81]: CheckAuthorization(org.freedesktop.NetworkManager.wifi.share.open), subject=unix-process[pid=6588, uid=0, start=41544] (succeeding for root)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7453] auth: call[80]: completed: authorized=1, challenge=0 (simulated)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7453] auth: call[81]: completed: authorized=1, challenge=0 (simulated)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <info> [1711922491.7453] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: agent registered
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7454] policy: re-enabling autoconnect for all connections (only clear no-secrets flag)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7454] secret-agent[f0267f4497eefe1b]: name-owner-changed: has :1.201
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7457] active-connection[b55398fad7013952]: creating
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7457] active-connection[b55398fad7013952]: set device "enxf4a80d0c2bfb" [0x5cf9fd7f9500]
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7457] device[f46cd55144f63e9a] (enxf4a80d0c2bfb): add_pending_action (1): 'activation-6'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7458] active-connection[b55398fad7013952]: constructed (NMVpnConnection, version-id 6, type managed)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7458] auth: call[82]: CheckAuthorization(org.freedesktop.NetworkManager.network-control), subject=unix-process[pid=6588, uid=0, start=41544] (succeeding for root)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7458] auth: call[82]: completed: authorized=1, challenge=0 (simulated)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7459] dbus-object[b55398fad7013952]: export: "/org/freedesktop/NetworkManager/ActiveConnection/6"
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <info> [1711922491.7460] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: starting openconnect
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7460] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: starting: watch D-Bus service org.freedesktop.NetworkManager.openconnect.Connection_6
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7461] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: set state: prepare (was waiting)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7461] active-connection[b55398fad7013952]: set state activating (was unknown)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7461] active-connection[b55398fad7013952]: check-master-ready: not signalling (state activating, no master)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <info> [1711922491.7462] audit: op="connection-activate" uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c" name="corporate-vpn" pid=6588 uid=0 result="success"
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7463] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: no name owner for org.freedesktop.NetworkManager.openconnect.Connection_6 (start VPN service)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7480] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: starting: VPN service has PID 6594
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7550] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: name owner :1.202 for org.freedesktop.NetworkManager.openconnect.Connection_6
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7550] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: set state: need-auth (was prepare)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7551] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: requesting VPN secrets pass #1
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7554] Secrets requested for connection /org/freedesktop/NetworkManager/Settings/4 (corporate-vpn/vpn)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7554] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd770c20) secrets requested flags 0x80000004 hints '(none)'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7555] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) system settings secrets sufficient
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7557] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd818ec0) existing secrets returned
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7557] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd818ec0) secrets request completed
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7557] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd818ec0) new agent secrets processed
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7557] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: get-new-secrets: update profile "corporate-vpn" (not persisted)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7557] settings: storage[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,da22c03c33c85daa/keyfile]: change event with connection "corporate-vpn" (file "/run/NetworkManager/system-connections/netplan-NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c.nmconnection")
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7557] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: updating connection "corporate-vpn" (da22c03c33c85daa/keyfile), new version-id 7
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7560] policy: block-autoconnect: unblocking port profiles for controller ifname=(null), uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c"
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7561] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: asking service if additional secrets are required
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7561] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call NeedSecrets on org.freedesktop.NetworkManager.openconnect.Connection_6
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7563] device[2b39ee29e019c16f] (lo): add_pending_action (1): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7563] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): add_pending_action (1): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7564] device[2b39ee29e019c16f] (lo): remove_pending_action (0): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7564] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): remove_pending_action (0): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7574] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: service indicated additional secrets required
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7574] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: requesting VPN secrets pass #2
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7575] Secrets requested for connection /org/freedesktop/NetworkManager/Settings/4 (corporate-vpn/vpn)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7577] agent-manager: agent[096f246c3faf7f97,:1.109/org.gnome.Shell.NetworkAgent/946889485]: agent ignored for secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"] (uid 946889485 not required 0)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7577] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: agent allowed for secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7578] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd770c20) secrets requested flags 0x4 hints '(none)'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7578] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) system settings secrets insufficient, asking agents
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7579] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: agent getting secrets for request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7579] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) request has system secrets; checking agent :1.201 for MODIFY
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7579] auth: call[83]: CheckAuthorization(org.freedesktop.NetworkManager.settings.modify.system), subject=unix-process[pid=6588, uid=0, start=41544] (succeeding for root)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7579] auth: call[83]: completed: authorized=1, challenge=0 (simulated)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7579] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: agent [cd1dbcdf539b1650/"corporate-vpn"/"vpn"] MODIFY check result YES
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7580] secret-agent[f0267f4497eefe1b] request [05d8aa4048900183,GetSecrets,"/org/freedesktop/NetworkManager/Settings/4"]: new request...
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7588] secret-agent[f0267f4497eefe1b] request [05d8aa4048900183,GetSecrets,"/org/freedesktop/NetworkManager/Settings/4"]: completed with failure: Stored passwords not supported
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7588] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: agent failed secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]: Stored passwords not supported
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7588] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd829b40) secrets request error: No agents were available for this request.
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7589] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: asking service if additional secrets are required
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7589] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call NeedSecrets on org.freedesktop.NetworkManager.openconnect.Connection_6
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7594] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: service indicated additional secrets required
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7595] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: requesting VPN secrets pass #3
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7596] Secrets requested for connection /org/freedesktop/NetworkManager/Settings/4 (corporate-vpn/vpn)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7598] agent-manager: agent[096f246c3faf7f97,:1.109/org.gnome.Shell.NetworkAgent/946889485]: agent ignored for secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"] (uid 946889485 not required 0)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7598] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: agent allowed for secrets request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7598] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd770c20) secrets requested flags 0x5 hints '(none)'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7599] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) system settings secrets insufficient, asking agents
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7599] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: agent getting secrets for request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7599] agent-manager: ([cd1dbcdf539b1650/"corporate-vpn"/"vpn"]) request has system secrets; checking agent :1.201 for MODIFY
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7599] auth: call[84]: CheckAuthorization(org.freedesktop.NetworkManager.settings.modify.system), subject=unix-process[pid=6588, uid=0, start=41544] (succeeding for root)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7599] auth: call[84]: completed: authorized=1, challenge=0 (simulated)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7599] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: agent [cd1dbcdf539b1650/"corporate-vpn"/"vpn"] MODIFY check result YES
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7600] secret-agent[f0267f4497eefe1b] request [6379c5574e0fa0ac,GetSecrets,"/org/freedesktop/NetworkManager/Settings/4"]: new request...
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.7609] secret-agent[f0267f4497eefe1b] request [6379c5574e0fa0ac,GetSecrets,"/org/freedesktop/NetworkManager/Settings/4"]: completed successfully
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7609] agent-manager: agent[f0267f4497eefe1b,:1.201/nmcli-connect/0]: agent returned secrets for request [cd1dbcdf539b1650/"corporate-vpn"/"vpn"]
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7610] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd89c510) secrets returned from agent :1.201
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7610] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd89c510) secrets request completed
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.7611] settings-connection[fd350c035f7238a8,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: (vpn:0x5cf9fd89c510) saving new secrets to backing storage
Apr 01 00:01:31 Christophs-TP14 generate[6601]: nm-device: NM-0033a43d-af0c-42a6-9269-0c533b49777d: the renderer for nm-devices must be NetworkManager, it will be used instead of the defined one.
Apr 01 00:01:31 Christophs-TP14 generate[6601]: nm-device: NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c: the renderer for nm-devices must be NetworkManager, it will be used instead of the defined one.
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8568] keyfile: commit: "/run/NetworkManager/system-connections/netplan-NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c.nmconnection": profile dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c (corporate-vpn) written
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8568] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: get-new-secrets: update profile "corporate-vpn"
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8569] settings: storage[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,da22c03c33c85daa/keyfile]: change event with connection "corporate-vpn" (file "/run/NetworkManager/system-connections/netplan-NM-dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c.nmconnection")
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8569] settings: update[dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c]: updating connection "corporate-vpn" (da22c03c33c85daa/keyfile), new version-id 8
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8571] policy: block-autoconnect: unblocking port profiles for controller ifname=(null), uuid="dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c"
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8574] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: secrets: asking service if additional secrets are required
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8574] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call NeedSecrets on org.freedesktop.NetworkManager.openconnect.Connection_6
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8576] device[2b39ee29e019c16f] (lo): add_pending_action (1): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8576] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): add_pending_action (1): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8576] device[2b39ee29e019c16f] (lo): remove_pending_action (0): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8576] device[f038d335667e1bfa] (p2p-dev-wlp0s20f3): remove_pending_action (0): 'autoactivate'
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8587] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: service indicated no additional secrets required
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8589] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: connect: allowing interactive secrets as all agents have that capability
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8589] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call ConnectInteractive on org.freedesktop.NetworkManager.openconnect.Connection_6
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8590] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: set state: connect (was need-auth)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8594] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: connect: falling back to non-interactive connect
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8594] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: dbus: call Connect on org.freedesktop.NetworkManager.openconnect.Connection_6
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8599] platform-linux: event-notification: RTM_NEWLINK, flags 0, seq 0: 4: vpn0 <NOARP,UP;pointopoint,multicast,noarp,up> mtu 1300 arp 65534 tun* not-init tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373; tun persist owner 135
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8600] platform-linux: event-notification: RTM_NEWLINK, flags 0, seq 0: 4: vpn0 <NOARP,UP,LOWER_UP;pointopoint,multicast,noarp,up,running,lowerup> mtu 1300 arp 65534 tun* not-init tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373; tun persist owner 135
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8600] platform: (vpn0) signal: link changed: 4: vpn0 <NOARP,UP,LOWER_UP;pointopoint,multicast,noarp,up,running,lowerup> mtu 1300 arp 65534 tun* init driver tun tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8600] l3cfg[d8a326c4446fd973,ifindex=4]: emit signal (platform-change, obj-type=link, change=changed, obj=4: vpn0 <NOARP,UP,LOWER_UP;pointopoint,multicast,noarp,up,running,lowerup> mtu 1300 arp 65534 tun* init driver tun tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373; tun persist owner 135)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8600] device[904d833593993b18] (vpn0): queued link change for ifindex 4
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8600] platform-linux: event-notification: RTM_NEWLINK, flags 0, seq 0: 4: vpn0 <NOARP,UP,LOWER_UP;pointopoint,multicast,noarp,up,running,lowerup> mtu 1300 arp 65534 tun* not-init tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373; tun persist owner 135
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8600] platform-linux: event-notification: RTM_NEWLINK, flags 0, seq 0: 4: vpn0 <NOARP,UP;pointopoint,multicast,noarp,up,running> mtu 1300 arp 65534 tun* not-init tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373; tun persist owner 135
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8600] platform: (vpn0) signal: link changed: 4: vpn0 <NOARP,UP;pointopoint,multicast,noarp,up,running> mtu 1300 arp 65534 tun* init driver tun tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8600] l3cfg[d8a326c4446fd973,ifindex=4]: emit signal (platform-change, obj-type=link, change=changed, obj=4: vpn0 <NOARP,UP;pointopoint,multicast,noarp,up,running> mtu 1300 arp 65534 tun* init driver tun tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373; tun persist owner 135)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8601] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type unicast 0.0.0.0/0 via 192.168.1.1 dev 2 metric 100 mss 0 rt-src rt-dhcp scope global pref-src 192.168.1.243
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8601] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type unicast 0.0.0.0/0 via 192.168.1.1 dev 3 metric 600 mss 0 rt-src rt-dhcp scope global pref-src 192.168.1.228
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8601] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type unicast 169.254.0.0/16 dev 2 metric 1000 mss 0 rt-src rt-boot scope link
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8601] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type unicast 192.168.1.0/24 dev 2 metric 100 mss 0 rt-src rt-kernel scope link pref-src 192.168.1.243
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8601] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type unicast 192.168.1.0/24 dev 3 metric 600 mss 0 rt-src rt-kernel scope link pref-src 192.168.1.228
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8601] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type local table 255 127.0.0.0/8 dev 1 metric 0 mss 0 rt-src rt-kernel scope host pref-src 127.0.0.1
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8601] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type local table 255 127.0.0.1/32 dev 1 metric 0 mss 0 rt-src rt-kernel scope host pref-src 127.0.0.1
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type broadcast table 255 127.255.255.255/32 dev 1 metric 0 mss 0 rt-src rt-kernel scope link pref-src 127.0.0.1
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type local table 255 192.168.1.228/32 dev 3 metric 0 mss 0 rt-src rt-kernel scope host pref-src 192.168.1.228
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type local table 255 192.168.1.243/32 dev 2 metric 0 mss 0 rt-src rt-kernel scope host pref-src 192.168.1.243
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type broadcast table 255 192.168.1.255/32 dev 2 metric 0 mss 0 rt-src rt-kernel scope link pref-src 192.168.1.243
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 42, in-dump: type broadcast table 255 192.168.1.255/32 dev 3 metric 0 mss 0 rt-src rt-kernel scope link pref-src 192.168.1.228
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type unicast 0.0.0.0/0 via 192.168.1.1 dev 2 metric 100 mss 0 rt-src rt-dhcp scope global pref-src 192.168.1.243
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type unicast 0.0.0.0/0 via 192.168.1.1 dev 3 metric 600 mss 0 rt-src rt-dhcp scope global pref-src 192.168.1.228
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type unicast 169.254.0.0/16 dev 2 metric 1000 mss 0 rt-src rt-boot scope link
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type unicast 192.168.1.0/24 dev 2 metric 100 mss 0 rt-src rt-kernel scope link pref-src 192.168.1.243
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type unicast 192.168.1.0/24 dev 3 metric 600 mss 0 rt-src rt-kernel scope link pref-src 192.168.1.228
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type local table 255 127.0.0.0/8 dev 1 metric 0 mss 0 rt-src rt-kernel scope host pref-src 127.0.0.1
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type local table 255 127.0.0.1/32 dev 1 metric 0 mss 0 rt-src rt-kernel scope host pref-src 127.0.0.1
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type broadcast table 255 127.255.255.255/32 dev 1 metric 0 mss 0 rt-src rt-kernel scope link pref-src 127.0.0.1
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type local table 255 192.168.1.228/32 dev 3 metric 0 mss 0 rt-src rt-kernel scope host pref-src 192.168.1.228
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type local table 255 192.168.1.243/32 dev 2 metric 0 mss 0 rt-src rt-kernel scope host pref-src 192.168.1.243
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8602] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type broadcast table 255 192.168.1.255/32 dev 2 metric 0 mss 0 rt-src rt-kernel scope link pref-src 192.168.1.243
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8603] platform-linux: event-notification: RTM_NEWROUTE, flags multi, seq 43: type broadcast table 255 192.168.1.255/32 dev 3 metric 0 mss 0 rt-src rt-kernel scope link pref-src 192.168.1.228
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <trace> [1711922491.8603] l3cfg[d8a326c4446fd973,ifindex=4]: emit signal (platform-change-on-idle, obj-type-flags=0x2)
Apr 01 00:01:31 Christophs-TP14 NetworkManager[4548]: <debug> [1711922491.8603] manager: (vpn0): assume: don't assume because device is not managed
Apr 01 00:01:32 Christophs-TP14 NetworkManager[4548]: <trace> [1711922492.8696] platform-linux: event-notification: RTM_NEWLINK, flags 0, seq 0: 4: vpn0 <NOARP,UP;pointopoint,multicast,noarp,up> mtu 1300 arp 65534 tun* not-init tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373; tun persist owner 135
Apr 01 00:01:32 Christophs-TP14 NetworkManager[4548]: <debug> [1711922492.8697] platform: (vpn0) signal: link changed: 4: vpn0 <NOARP,UP;pointopoint,multicast,noarp,up> mtu 1300 arp 65534 tun* init driver tun tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373
Apr 01 00:01:32 Christophs-TP14 NetworkManager[4548]: <trace> [1711922492.8697] l3cfg[d8a326c4446fd973,ifindex=4]: emit signal (platform-change, obj-type=link, change=changed, obj=4: vpn0 <NOARP,UP;pointopoint,multicast,noarp,up> mtu 1300 arp 65534 tun* init driver tun tx-queue-len 500 gso-max-size 65536 gso-max-segs 65535 gro-max-size 65536 rx:2036,1546814 tx:1800,206373; tun persist owner 135)
Apr 01 00:01:32 Christophs-TP14 NetworkManager[4548]: <debug> [1711922492.8697] device[904d833593993b18] (vpn0): queued link change for ifindex 4
Apr 01 00:01:32 Christophs-TP14 NetworkManager[4548]: <trace> [1711922492.8698] l3cfg[d8a326c4446fd973,ifindex=4]: emit signal (platform-change-on-idle, obj-type-flags=0x2)
Apr 01 00:01:32 Christophs-TP14 NetworkManager[4548]: <debug> [1711922492.8698] manager: (vpn0): assume: don't assume because device is not managed
Apr 01 00:01:41 Christophs-TP14 NetworkManager[4548]: <warn> [1711922501.8622] vpn[0x5cf9fd89e540,dd3926fe-a28c-4c38-a0aa-e1f4fcc3832c,"corporate-vpn"]: failed to connect: 'Zeitüberschreitung wurde erreicht'
Happens with nmcli connection up corporate-vpn passwd-file $file
as well as with standalone connection which has gateway / certificate information set. But for the sake of completeness:
connection.id: corporate-vpn
connection.uuid: 6b237dc3-e2f9-498f-a5ce-dc925940f209
connection.stable-id: --
connection.type: vpn
connection.interface-name: --
connection.autoconnect: ja
connection.autoconnect-priority: 0
connection.autoconnect-retries: -1 (default)
connection.multi-connect: 0 (default)
connection.auth-retries: -1
connection.timestamp: 1712004968
connection.permissions: --
connection.zone: --
connection.master: --
connection.slave-type: --
connection.autoconnect-slaves: -1 (default)
connection.secondaries: --
connection.gateway-ping-timeout: 0
connection.metered: unbekannt
connection.lldp: default
connection.mdns: -1 (default)
connection.llmnr: -1 (default)
connection.dns-over-tls: -1 (default)
connection.mptcp-flags: 0x0 (default)
connection.wait-device-timeout: -1
connection.wait-activation-delay: -1
ipv4.method: auto
ipv4.dns: --
ipv4.dns-search: --
ipv4.dns-options: --
ipv4.dns-priority: 0
ipv4.addresses: --
ipv4.gateway: --
ipv4.routes: --
ipv4.route-metric: -1
ipv4.route-table: 0 (unspec)
ipv4.routing-rules: --
ipv4.replace-local-rule: -1 (default)
ipv4.ignore-auto-routes: nein
ipv4.ignore-auto-dns: nein
ipv4.dhcp-client-id: --
ipv4.dhcp-iaid: --
ipv4.dhcp-timeout: 0 (default)
ipv4.dhcp-send-hostname: ja
ipv4.dhcp-hostname: --
ipv4.dhcp-fqdn: --
ipv4.dhcp-hostname-flags: 0x0 (none)
ipv4.never-default: nein
ipv4.may-fail: ja
ipv4.required-timeout: -1 (default)
ipv4.dad-timeout: -1 (default)
ipv4.dhcp-vendor-class-identifier: --
ipv4.link-local: 0 (default)
ipv4.dhcp-reject-servers: --
ipv4.auto-route-ext-gw: -1 (default)
ipv6.method: auto
ipv6.dns: --
ipv6.dns-search: --
ipv6.dns-options: --
ipv6.dns-priority: 0
ipv6.addresses: --
ipv6.gateway: --
ipv6.routes: --
ipv6.route-metric: -1
ipv6.route-table: 0 (unspec)
ipv6.routing-rules: --
ipv6.replace-local-rule: -1 (default)
ipv6.ignore-auto-routes: nein
ipv6.ignore-auto-dns: nein
ipv6.never-default: nein
ipv6.may-fail: ja
ipv6.required-timeout: -1 (default)
ipv6.ip6-privacy: -1 (unknown)
ipv6.addr-gen-mode: default
ipv6.ra-timeout: 0 (default)
ipv6.mtu: auto
ipv6.dhcp-pd-hint: --
ipv6.dhcp-duid: --
ipv6.dhcp-iaid: --
ipv6.dhcp-timeout: 0 (default)
ipv6.dhcp-send-hostname: ja
ipv6.dhcp-hostname: --
ipv6.dhcp-hostname-flags: 0x0 (none)
ipv6.auto-route-ext-gw: -1 (default)
ipv6.token: --
vpn.service-type: org.freedesktop.NetworkManager.openconnect
vpn.user-name: --
vpn.data: authtype = password, autoconnect-flags = 0, certsigs-flags = 0, cookie-flags = 2, disable_udp = no, enable_csd_trojan = no, gateway-flags = 2, gwcert-flags = 2, lasthost-flags = 0, pem_passphrase_fsid = no, prevent_invalid_cert = yes, protocol = anyconnect, resolve-flags = 2, stoken_source = disabled, xmlconfig-flags = 0
vpn.secrets: <hidden>
vpn.persistent: nein
vpn.timeout: 0
proxy.method: none
proxy.browser-only: nein
proxy.pac-url: --
proxy.pac-script: --
WIFI connection:
IP4.ROUTE[1]: dst = 192.168.1.0/24, nh = 0.0.0.0, mt = 600
IP4.ROUTE[2]: dst = 0.0.0.0/0, nh = 192.168.1.1, mt = 600
IP4.ROUTE[3]: dst = 169.254.0.0/16, nh = 0.0.0.0, mt = 1000
After first deactivation:
IP4.ROUTE[1]: dst = 0.0.0.0/0, nh = 192.168.1.1, mt = 600
IP4.ROUTE[2]: dst = 169.254.0.0/16, nh = 0.0.0.0, mt = 1000
IP4.ROUTE[3]: dst = 192.168.1.0/24, nh = 0.0.0.0, mt = 600
Thanks for having a look into this!