May 03 12:14:22 NetworkManager[913]: [1683108862.6782] NetworkManager (version 1.42.6-1.fc38) is starting... (boot:ee6e9163-b11b-4e58-87fa-b2e40972141e) May 03 12:14:22 NetworkManager[913]: [1683108862.6784] Read config: /etc/NetworkManager/NetworkManager.conf (run: 15-carrier-timeout.conf) May 03 12:14:22 NetworkManager[913]: [1683108862.6797] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager" May 03 12:14:22 NetworkManager[913]: [1683108862.6843] manager[0x5582848613a0]: monitoring kernel firmware directory '/lib/firmware'. May 03 12:14:22 NetworkManager[913]: [1683108862.6863] hostname: hostname: using hostnamed May 03 12:14:22 NetworkManager[913]: [1683108862.6863] hostname: static hostname changed from (none) to "p15lnx-fsway" May 03 12:14:22 NetworkManager[913]: [1683108862.6867] dns-mgr: init: dns=systemd-resolved rc-manager=unmanaged (auto), plugin=systemd-resolved May 03 12:14:22 NetworkManager[913]: [1683108862.6879] rfkill2: found Wi-Fi radio killswitch (at /sys/devices/pci0000:00/0000:00:14.3/ieee80211/phy0/rfkill2) (driver iwlwifi) May 03 12:14:22 NetworkManager[913]: [1683108862.6880] manager[0x5582848613a0]: rfkill: Wi-Fi hardware radio set enabled May 03 12:14:22 NetworkManager[913]: [1683108862.6881] manager[0x5582848613a0]: rfkill: WWAN hardware radio set enabled May 03 12:14:22 NetworkManager[913]: [1683108862.6907] Loaded device plugin: NMWifiFactory (/usr/lib64/NetworkManager/1.42.6-1.fc38/libnm-device-plugin-wifi.so) May 03 12:14:22 NetworkManager[913]: [1683108862.6911] Loaded device plugin: NMAtmManager (/usr/lib64/NetworkManager/1.42.6-1.fc38/libnm-device-plugin-adsl.so) May 03 12:14:22 NetworkManager[913]: [1683108862.6922] Loaded device plugin: NMTeamFactory (/usr/lib64/NetworkManager/1.42.6-1.fc38/libnm-device-plugin-team.so) May 03 12:14:22 NetworkManager[913]: [1683108862.6947] Loaded device plugin: NMBluezManager (/usr/lib64/NetworkManager/1.42.6-1.fc38/libnm-device-plugin-bluetooth.so) May 03 12:14:22 NetworkManager[913]: [1683108862.6952] Loaded device plugin: NMWwanFactory (/usr/lib64/NetworkManager/1.42.6-1.fc38/libnm-device-plugin-wwan.so) May 03 12:14:22 NetworkManager[913]: [1683108862.6953] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled by state file May 03 12:14:22 NetworkManager[913]: [1683108862.6954] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file May 03 12:14:22 NetworkManager[913]: [1683108862.6954] manager: Networking is enabled by state file May 03 12:14:22 NetworkManager[913]: [1683108862.6958] settings: Loaded settings plugin: keyfile (internal) May 03 12:14:22 NetworkManager[913]: [1683108862.6972] dhcp: init: Using DHCP client 'internal' May 03 12:14:22 NetworkManager[913]: [1683108862.6974] manager: (lo): new Loopback device (/org/freedesktop/NetworkManager/Devices/1) May 03 12:14:22 NetworkManager[913]: [1683108862.6981] device (lo): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external') May 03 12:14:22 NetworkManager[913]: [1683108862.6986] device (lo): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external') May 03 12:14:22 NetworkManager[913]: [1683108862.6991] device (lo): Activation: starting connection 'lo' (b0fb2a17-f7bc-4790-9185-4c649526d0f3) May 03 12:14:22 NetworkManager[913]: [1683108862.6996] manager: (enp0s31f6): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2) May 03 12:14:22 NetworkManager[913]: [1683108862.7002] settings: (enp0s31f6): created default wired connection 'Wired connection 1' May 03 12:14:22 NetworkManager[913]: [1683108862.7002] device (enp0s31f6): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 03 12:14:22 NetworkManager[913]: [1683108862.8886] manager: (enp44s0u1u1): new Ethernet device (/org/freedesktop/NetworkManager/Devices/3) May 03 12:14:22 NetworkManager[913]: [1683108862.8896] settings: (enp44s0u1u1): created default wired connection 'Wired connection 2' May 03 12:14:22 NetworkManager[913]: [1683108862.8897] device (enp44s0u1u1): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 03 12:14:22 NetworkManager[913]: [1683108862.9219] device (wlp0s20f3): driver supports Access Point (AP) mode May 03 12:14:22 NetworkManager[913]: [1683108862.9224] manager: (wlp0s20f3): new 802.11 Wi-Fi device (/org/freedesktop/NetworkManager/Devices/4) May 03 12:14:22 NetworkManager[913]: [1683108862.9234] device (wlp0s20f3): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 03 12:14:23 NetworkManager[913]: [1683108863.1434] device (wlp0s20f3): set-hw-addr: set MAC address to $wlp0s20f3_mac_address (scanning) May 03 12:14:23 NetworkManager[913]: [1683108863.3374] device (lo): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external') May 03 12:14:23 NetworkManager[913]: [1683108863.3375] device (lo): state change: prepare -> config (reason 'none', sys-iface-state: 'external') May 03 12:14:23 NetworkManager[913]: [1683108863.3376] device (lo): state change: config -> ip-config (reason 'none', sys-iface-state: 'external') May 03 12:14:23 NetworkManager[913]: [1683108863.3379] device (enp44s0u1u1): carrier: link connected May 03 12:14:23 NetworkManager[913]: [1683108863.3414] modem-manager: ModemManager available May 03 12:14:23 NetworkManager[913]: [1683108863.3417] device (lo): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external') May 03 12:14:23 NetworkManager[913]: [1683108863.3420] device (enp44s0u1u1): state change: unavailable -> disconnected (reason 'carrier-changed', sys-iface-state: 'managed') May 03 12:14:23 NetworkManager[913]: [1683108863.3423] policy: auto-activating connection 'Wired connection 2' (68b90275-8b2d-326e-8cc4-73bc3c68b722) May 03 12:14:23 NetworkManager[913]: [1683108863.3426] device (enp44s0u1u1): Activation: starting connection 'Wired connection 2' (68b90275-8b2d-326e-8cc4-73bc3c68b722) May 03 12:14:23 NetworkManager[913]: [1683108863.3426] device (enp44s0u1u1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') May 03 12:14:23 NetworkManager[913]: [1683108863.3427] manager: NetworkManager state is now CONNECTING May 03 12:14:23 NetworkManager[913]: [1683108863.3428] device (enp44s0u1u1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') May 03 12:14:23 NetworkManager[913]: [1683108863.3432] device (lo): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external') May 03 12:14:23 NetworkManager[913]: [1683108863.3433] device (lo): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external') May 03 12:14:23 NetworkManager[913]: [1683108863.3436] device (lo): Activation: successful, device activated. May 03 12:14:23 NetworkManager[913]: [1683108863.3518] device (enp44s0u1u1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') May 03 12:14:23 NetworkManager[913]: [1683108863.3522] dhcp4 (enp44s0u1u1): activation: beginning transaction (timeout in 45 seconds) May 03 12:14:23 NetworkManager[913]: [1683108863.3880] device (wlp0s20f3): supplicant interface state: internal-starting -> disconnected May 03 12:14:23 NetworkManager[913]: [1683108863.3881] Wi-Fi P2P device controlled by interface wlp0s20f3 created May 03 12:14:23 NetworkManager[913]: [1683108863.3883] manager: (p2p-dev-wlp0s20f3): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/5) May 03 12:14:23 NetworkManager[913]: [1683108863.3885] device (p2p-dev-wlp0s20f3): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 03 12:14:23 NetworkManager[913]: [1683108863.3890] device (wlp0s20f3): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed') May 03 12:14:23 NetworkManager[913]: [1683108863.3894] device (p2p-dev-wlp0s20f3): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed') May 03 12:14:25 NetworkManager[913]: [1683108865.3536] dhcp4 (enp44s0u1u1): state changed new lease, address=192.168.1.32 May 03 12:14:25 NetworkManager[913]: [1683108865.3538] policy: set 'Wired connection 2' (enp44s0u1u1) as default for IPv4 routing and DNS May 03 12:14:25 NetworkManager[913]: [1683108865.3577] device (enp44s0u1u1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed') May 03 12:14:25 NetworkManager[913]: [1683108865.3588] device (enp44s0u1u1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed') May 03 12:14:25 NetworkManager[913]: [1683108865.3590] device (enp44s0u1u1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed') May 03 12:14:25 NetworkManager[913]: [1683108865.3593] manager: NetworkManager state is now CONNECTED_SITE May 03 12:14:25 NetworkManager[913]: [1683108865.3595] device (enp44s0u1u1): Activation: successful, device activated. May 03 12:14:25 NetworkManager[913]: [1683108865.3598] manager: NetworkManager state is now CONNECTED_GLOBAL May 03 12:14:28 NetworkManager[913]: [1683108868.8863] manager: startup complete May 03 12:14:33 NetworkManager[913]: [1683108873.2144] agent-manager: agent[f3652666921a71f8,:1.52/org.freedesktop.nm-applet/1000]: agent registered May 03 12:15:44 NetworkManager[913]: [1683108944.4493] agent-manager: agent[9b9a154462a897d4,:1.62/nmcli-connect/1000]: agent registered May 03 12:15:44 NetworkManager[913]: [1683108944.4627] vpn[0x55828493cf30,852aaffa-938e-451a-b623-514482ced0d6,"$vpn_con_name"]: starting l2tp May 03 12:15:44 NetworkManager[913]: [1683108944.4629] audit: op="connection-activate" uuid="852aaffa-938e-451a-b623-514482ced0d6" name="$vpn_con_name" pid=1745 uid=1000 result="success" May 03 12:15:44 nm-l2tp-service[1760]: Check port 1701 May 03 12:15:44 NetworkManager[1767]: whack: Pluto is not running (no "/run/pluto/pluto.ctl") May 03 12:15:44 NetworkManager[1770]: Redirecting to: systemctl restart ipsec.service May 03 12:15:45 NetworkManager[913]: [1683108945.0677] manager: (ip_vti0): new IPTunnel device (/org/freedesktop/NetworkManager/Devices/6) May 03 12:15:45 NetworkManager[2072]: 002 listening for IKE messages May 03 12:15:45 NetworkManager[2072]: 002 forgetting secrets May 03 12:15:45 NetworkManager[2072]: 002 loading secrets from "/etc/ipsec.secrets" May 03 12:15:45 NetworkManager[2072]: 002 loading secrets from "/etc/ipsec.d/ipsec.nm-l2tp.secrets" May 03 12:15:45 NetworkManager[2076]: debugging mode enabled May 03 12:15:45 NetworkManager[2076]: end of file /run/nm-l2tp-852aaffa-938e-451a-b623-514482ced0d6/ipsec.conf May 03 12:15:45 NetworkManager[2076]: Loading conn 852aaffa-938e-451a-b623-514482ced0d6 May 03 12:15:45 NetworkManager[2076]: starter: left is KH_DEFAULTROUTE May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" modecfgdns= May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" modecfgdomains= May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" modecfgbanner= May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" mark= May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" mark-in= May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" mark-out= May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" vti_iface= May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" redirect-to= May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" accept-redirect-to= May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" esp=aes256-sha1,aes128-sha1,3des-sha1 May 03 12:15:45 NetworkManager[2076]: conn: "852aaffa-938e-451a-b623-514482ced0d6" ike=aes256-sha2_256-modp2048,aes256-sha2_256-modp1536,aes256-sha1-modp2048,aes256-sha1-modp1536,aes256-sha1-ecp_384,aes128-sha1-ecp_256,3des-sha1-modp2048 May 03 12:15:45 NetworkManager[2076]: opening file: /run/nm-l2tp-852aaffa-938e-451a-b623-514482ced0d6/ipsec.conf May 03 12:15:45 NetworkManager[2076]: loading named conns: 852aaffa-938e-451a-b623-514482ced0d6 May 03 12:15:45 NetworkManager[2076]: resolving family=IPv4 src= gateway= peer $vpn_host May 03 12:15:45 NetworkManager[2076]: seeking GATEWAY May 03 12:15:45 NetworkManager[2076]: query GETROUTE+REQUEST+ROOT+MATCH May 03 12:15:45 NetworkManager[2076]: add RTA_DST $vpn_host (peer->addr) May 03 12:15:45 NetworkManager[2076]: query returned 456 bytes May 03 12:15:45 NetworkManager[2076]: processing response May 03 12:15:45 NetworkManager[2076]: parsing route entry (RTA payloads) May 03 12:15:45 NetworkManager[2076]: RTA_TABLE=254 May 03 12:15:45 NetworkManager[2076]: RTA_PRIORITY=100 May 03 12:15:45 NetworkManager[2076]: RTA_PREFSRC=192.168.1.32 May 03 12:15:45 NetworkManager[2076]: RTA_GATEWAY=192.168.1.1 May 03 12:15:45 NetworkManager[2076]: using src= prefsrc=192.168.1.32 gateway=192.168.1.1 dst= dev='enp44s0u1u1' priority=100 pref=-1 table=254 May 03 12:15:45 NetworkManager[2076]: found gateway(host_nexthop): 192.168.1.1 May 03 12:15:45 NetworkManager[2076]: please-call-again: src= gateway=192.168.1.1 May 03 12:15:45 NetworkManager[2076]: resolving family=IPv4 src= gateway=192.168.1.1 peer $vpn_host May 03 12:15:45 NetworkManager[2076]: seeking PREFSRC May 03 12:15:45 NetworkManager[2076]: query GETROUTE+REQUEST May 03 12:15:45 NetworkManager[2076]: add RTA_DST 192.168.1.1 (host->nexthop) May 03 12:15:45 NetworkManager[2076]: query returned 104 bytes May 03 12:15:45 NetworkManager[2076]: processing response May 03 12:15:45 NetworkManager[2076]: parsing route entry (RTA payloads) May 03 12:15:45 NetworkManager[2076]: RTA_TABLE=254 May 03 12:15:45 NetworkManager[2076]: RTA_DST=192.168.1.1 May 03 12:15:45 NetworkManager[2076]: RTA_PREFSRC=192.168.1.32 May 03 12:15:45 NetworkManager[2076]: using src= prefsrc=192.168.1.32 gateway= dst=192.168.1.1 dev='enp44s0u1u1' priority=-1 pref=-1 table=254 +cacheinfo +uid May 03 12:15:45 NetworkManager[2076]: found prefsrc(host_addr): 192.168.1.32 May 03 12:15:45 NetworkManager[2076]: success: src=192.168.1.32 gateway=192.168.1.1 May 03 12:15:45 NetworkManager[2076]: resolving family=IPv4 src=$vpn_host gateway= peer 192.168.1.32 May 03 12:15:45 NetworkManager[2076]: seeking NOTHING May 03 12:15:45 NetworkManager[2078]: 002 "852aaffa-938e-451a-b623-514482ced0d6" #1: initiating IKEv1 Main Mode connection May 03 12:15:45 NetworkManager[2078]: 102 "852aaffa-938e-451a-b623-514482ced0d6" #1: sent Main Mode request May 03 12:15:45 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: ignoring informational payload NO_PROPOSAL_CHOSEN, msgid=00000000, length=36 May 03 12:15:45 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: received and ignored notification payload: NO_PROPOSAL_CHOSEN May 03 12:15:45 NetworkManager[2078]: 010 "852aaffa-938e-451a-b623-514482ced0d6" #1: STATE_MAIN_I1: retransmission; will wait 0.5 seconds for response May 03 12:15:46 NetworkManager[2078]: 010 "852aaffa-938e-451a-b623-514482ced0d6" #1: STATE_MAIN_I1: retransmission; will wait 1 seconds for response May 03 12:15:46 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: ignoring informational payload NO_PROPOSAL_CHOSEN, msgid=00000000, length=36 May 03 12:15:46 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: received and ignored notification payload: NO_PROPOSAL_CHOSEN May 03 12:15:47 NetworkManager[2078]: 010 "852aaffa-938e-451a-b623-514482ced0d6" #1: STATE_MAIN_I1: retransmission; will wait 2 seconds for response May 03 12:15:47 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: ignoring informational payload NO_PROPOSAL_CHOSEN, msgid=00000000, length=36 May 03 12:15:47 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: received and ignored notification payload: NO_PROPOSAL_CHOSEN May 03 12:15:49 NetworkManager[2078]: 010 "852aaffa-938e-451a-b623-514482ced0d6" #1: STATE_MAIN_I1: retransmission; will wait 4 seconds for response May 03 12:15:49 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: ignoring informational payload NO_PROPOSAL_CHOSEN, msgid=00000000, length=36 May 03 12:15:49 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: received and ignored notification payload: NO_PROPOSAL_CHOSEN May 03 12:15:53 NetworkManager[2078]: 010 "852aaffa-938e-451a-b623-514482ced0d6" #1: STATE_MAIN_I1: retransmission; will wait 8 seconds for response May 03 12:15:53 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: ignoring informational payload NO_PROPOSAL_CHOSEN, msgid=00000000, length=36 May 03 12:15:53 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: received and ignored notification payload: NO_PROPOSAL_CHOSEN May 03 12:15:54 NetworkManager[913]: [1683108954.4791] vpn[0x55828493cf30,852aaffa-938e-451a-b623-514482ced0d6,"$vpn_con_name"]: failed to connect: 'Timeout was reached' May 03 12:16:01 nm-l2tp-service[1760]: Could not establish IPsec connection. May 03 12:16:01 nm-l2tp-service[1760]: mg_dbus_method_invocation_take_error: assertion 'error != NULL' failed May 03 12:16:01 NetworkManager[2078]: 010 "852aaffa-938e-451a-b623-514482ced0d6" #1: STATE_MAIN_I1: retransmission; will wait 16 seconds for response May 03 12:16:01 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: ignoring informational payload NO_PROPOSAL_CHOSEN, msgid=00000000, length=36 May 03 12:16:01 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: received and ignored notification payload: NO_PROPOSAL_CHOSEN May 03 12:16:17 NetworkManager[2078]: 010 "852aaffa-938e-451a-b623-514482ced0d6" #1: STATE_MAIN_I1: retransmission; will wait 32 seconds for response May 03 12:16:17 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: ignoring informational payload NO_PROPOSAL_CHOSEN, msgid=00000000, length=36 May 03 12:16:17 NetworkManager[2078]: 003 "852aaffa-938e-451a-b623-514482ced0d6" #1: received and ignored notification payload: NO_PROPOSAL_CHOSEN May 03 12:16:49 NetworkManager[2078]: 031 "852aaffa-938e-451a-b623-514482ced0d6" #1: STATE_MAIN_I1: 60 second timeout exceeded after 7 retransmits. No response (or no acceptable response) to our first IKEv1 message May 03 12:16:49 NetworkManager[2078]: 000 "852aaffa-938e-451a-b623-514482ced0d6" #1: starting keying attempt 2 of an unlimited number, but releasing whack May 03 12:21:14 NetworkManager[913]: [1683109274.4936] device (wlp0s20f3): set-hw-addr: set MAC address to $wlp0s20f3_mac_address (scanning) May 03 12:21:14 NetworkManager[913]: [1683109274.4962] device (wlp0s20f3): supplicant interface state: disconnected -> inactive May 03 12:21:14 NetworkManager[913]: [1683109274.4962] device (p2p-dev-wlp0s20f3): supplicant management interface state: disconnected -> inactive