Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

protonvpn-gui: timeout #307462

Open
Atemu opened this issue Apr 28, 2024 · 9 comments
Open

protonvpn-gui: timeout #307462

Atemu opened this issue Apr 28, 2024 · 9 comments
Labels
0.kind: bug

Comments

@Atemu
Copy link
Member

Atemu commented Apr 28, 2024 •
edited

Describe the bug

A clear and concise description of what the bug is.

When attempting to connect, I get a spinner and then an incredibly unhelpful error message: timeout.

(Proton should get into making operating systems, they could easily rival M$ Windows at least in this quality.)

The log is not any more helpful:

2024-04-28T13:00:25.651706 | proton.vpn.app.gtk.widgets.vpn.quick_connect_widget:96 | INFO | UI.TRAY:CONNECT | Connect to fastest server
2024-04-28T13:00:25.700081 | proton.vpn.core.connection:160 | INFO | CONN.CONNECT:START | Server: DE#164 / Domain: node-de-22.protonvpn.net / IP: 149.88.102.97 / OpenVPN Ports: ProtocolPorts(udp=[80, 51820, 4569, 1194, 5060], tcp=[443, 7770, 8443]) / WireGuard Ports: ProtocolPorts(udp=[443, 88, 1224, 51820, 500, 4500], tcp=[443]) / Protocol: openvpn-udp / Backend: None
2024-04-28T13:00:25.700493 | proton.vpn.connection.vpnconnector:238 | INFO | CONN:STATE_CHANGED | Connecting
2024-04-28T13:00:30.898162 | proton.vpn.backend.linux.networkmanager.core.networkmanager:87 | INFO | VPN server NOT reachable.
2024-04-28T13:00:30.898329 | proton.vpn.connection.vpnconnector:238 | INFO | CONN:STATE_CHANGED | Error
2024-04-28T13:00:30.898488 | proton.vpn.connection.states:334 | WARNING | Reached connection error state: Timeout (None)
2024-04-28T13:00:30.901680 | proton.vpn.app.gtk.services.reconnector.reconnector:191 | INFO | VPN connection drop was detected.
2024-04-28T13:00:30.901776 | proton.vpn.app.gtk.services.reconnector.reconnector:133 | INFO | Reconnection attempt #1 scheduled in 2.02 seconds.

This is what happens inside NM during that time:

Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4740] manager: (pvpnksintrf0): new Dummy device (/org/freedesktop/NetworkManager/Devices/25)
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4745] device (pvpnksintrf0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4748] audit: op="connection-add" uuid="1f2cc9c8-9801-4900-8f2c-18a0d1475ac9" name="pvpn-killswitch" pid=406998 uid=1000 result="success"
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4751] device (pvpnksintrf0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4752] policy: auto-activating connection 'pvpn-killswitch' (1f2cc9c8-9801-4900-8f2c-18a0d1475ac9)
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4754] device (pvpnksintrf0): Activation: starting connection 'pvpn-killswitch' (1f2cc9c8-9801-4900-8f2c-18a0d1475ac9)
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4754] device (pvpnksintrf0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4755] device (pvpnksintrf0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4755] device (pvpnksintrf0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4757] policy: set 'pvpn-killswitch' (pvpnksintrf0) as default for IPv4 routing and DNS
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4758] manager: NetworkManager state is now CONNECTING
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4758] policy: set 'pvpn-killswitch' (pvpnksintrf0) as default for IPv6 routing and DNS
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.4759] dns-mgr: Writing DNS information to /nix/store/fdakwgqga16npcaafjgjfa6fl3q1ga9x-openresolv-3.13.2/bin/resolvconf
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Stopped target Host and Network Name Lookups.
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Stopping Host and Network Name Lookups...
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Stopped target User and Group Name Lookups.
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Stopping User and Group Name Lookups...
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
Apr 28 14:51:15 HEPHAISTOS systemd[1]: nscd.service: Deactivated successfully.
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Stopped Name Service Cache Daemon (nsncd).
Apr 28 14:51:15 HEPHAISTOS avahi-daemon[1736]: Got SIGHUP, reloading.
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5070] device (pvpnksintrf0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Starting Name Service Cache Daemon (nsncd)...
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Starting Network Manager Script Dispatcher Service...
Apr 28 14:51:15 HEPHAISTOS nsncd[407873]: Apr 28 12:51:15.548 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Started Name Service Cache Daemon (nsncd).
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Reached target Host and Network Name Lookups.
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Reached target User and Group Name Lookups.
Apr 28 14:51:15 HEPHAISTOS systemd[1]: Started Network Manager Script Dispatcher Service.
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5585] device (pvpnksintrf0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5586] device (pvpnksintrf0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5587] manager: NetworkManager state is now CONNECTED_GLOBAL
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5587] device (pvpnksintrf0): Activation: successful, device activated.
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5858] manager: (pvpnrouteintrf0): new Dummy device (/org/freedesktop/NetworkManager/Devices/26)
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5863] device (pvpnrouteintrf0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5866] audit: op="connection-add" uuid="d1b2da81-4251-4a19-aae5-40eed2d3db09" name="pvpn-routed-killswitch" pid=406998 uid=1000 result="success"
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5869] device (pvpnrouteintrf0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5871] policy: auto-activating connection 'pvpn-routed-killswitch' (d1b2da81-4251-4a19-aae5-40eed2d3db09)
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5872] device (pvpnrouteintrf0): Activation: starting connection 'pvpn-routed-killswitch' (d1b2da81-4251-4a19-aae5-40eed2d3db09)
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5872] device (pvpnrouteintrf0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5873] device (pvpnrouteintrf0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5873] device (pvpnrouteintrf0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5890] dns-mgr: Writing DNS information to /nix/store/fdakwgqga16npcaafjgjfa6fl3q1ga9x-openresolv-3.13.2/bin/resolvconf
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.5995] device (pvpnrouteintrf0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6051] device (pvpnrouteintrf0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6051] device (pvpnrouteintrf0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6053] device (pvpnrouteintrf0): Activation: successful, device activated.
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6124] device (pvpnksintrf0): state change: activated -> deactivating (reason 'connection-removed', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6127] audit: op="connection-delete" uuid="1f2cc9c8-9801-4900-8f2c-18a0d1475ac9" name="pvpn-killswitch" pid=406998 uid=1000 result="success"
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6129] device (pvpnksintrf0): state change: deactivating -> disconnected (reason 'connection-removed', sys-iface-state: 'managed')
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6149] dns-mgr: Writing DNS information to /nix/store/fdakwgqga16npcaafjgjfa6fl3q1ga9x-openresolv-3.13.2/bin/resolvconf
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6765] policy: set 'Wired connection 1' (enp13s0) as default for IPv4 routing and DNS
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6766] policy: set 'pvpn-routed-killswitch' (pvpnrouteintrf0) as default for IPv6 routing and DNS
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.6784] dns-mgr: Writing DNS information to /nix/store/fdakwgqga16npcaafjgjfa6fl3q1ga9x-openresolv-3.13.2/bin/resolvconf
Apr 28 14:51:15 HEPHAISTOS NetworkManager[386489]: <info>  [1714308675.7525] device (pvpnksintrf0): state change: disconnected -> unmanaged (reason 'user-requested', sys-iface-state: 'managed')
Apr 28 14:51:20 HEPHAISTOS NetworkManager[386489]: <info>  [1714308680.6854] device (pvpnrouteintrf0): state change: activated -> deactivating (reason 'connection-removed', sys-iface-state: 'managed')
Apr 28 14:51:20 HEPHAISTOS NetworkManager[386489]: <info>  [1714308680.6861] audit: op="connection-delete" uuid="d1b2da81-4251-4a19-aae5-40eed2d3db09" name="pvpn-routed-killswitch" pid=406998 uid=1000 result="success"
Apr 28 14:51:20 HEPHAISTOS NetworkManager[386489]: <info>  [1714308680.6936] device (pvpnrouteintrf0): state change: deactivating -> disconnected (reason 'connection-removed', sys-iface-state: 'managed')
Apr 28 14:51:20 HEPHAISTOS NetworkManager[386489]: <info>  [1714308680.6938] dns-mgr: Writing DNS information to /nix/store/fdakwgqga16npcaafjgjfa6fl3q1ga9x-openresolv-3.13.2/bin/resolvconf
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Stopped target Host and Network Name Lookups.
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Stopping Host and Network Name Lookups...
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Stopped target User and Group Name Lookups.
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Stopping User and Group Name Lookups...
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
Apr 28 14:51:20 HEPHAISTOS systemd[1]: nscd.service: Deactivated successfully.
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Stopped Name Service Cache Daemon (nsncd).
Apr 28 14:51:20 HEPHAISTOS avahi-daemon[1736]: Got SIGHUP, reloading.
Apr 28 14:51:20 HEPHAISTOS NetworkManager[386489]: <info>  [1714308680.7237] policy: set 'Wired connection 1' (enp13s0) as default for IPv6 routing and DNS
Apr 28 14:51:20 HEPHAISTOS NetworkManager[386489]: <info>  [1714308680.7238] dns-mgr: Writing DNS information to /nix/store/fdakwgqga16npcaafjgjfa6fl3q1ga9x-openresolv-3.13.2/bin/resolvconf
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Starting Name Service Cache Daemon (nsncd)...
Apr 28 14:51:20 HEPHAISTOS nsncd[408014]: Apr 28 12:51:20.768 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Started Name Service Cache Daemon (nsncd).
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Reached target Host and Network Name Lookups.
Apr 28 14:51:20 HEPHAISTOS systemd[1]: Reached target User and Group Name Lookups.
Apr 28 14:51:20 HEPHAISTOS NetworkManager[386489]: <info>  [1714308680.7845] device (pvpnrouteintrf0): state change: disconnected -> unmanaged (reason 'user-requested', sys-iface-state: 'managed')
Apr 28 14:51:30 HEPHAISTOS systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.

So it appears to be doing something; setting up the leak protection and the tunnel interface(?).

What I find weird is that systemd/NM stops those targets and also nsncd. That might be part of the problem? No nsncd, no DNS. Though I don't know how pvpn is supposed to work here?

I'd really like to see the diff to a successful log here because, apparently, it's working for others in #302011.

Steps To Reproduce

Steps to reproduce the behavior:

  1. Set up a secret service
  2. protonvpn-app
  3. "Quick connect"

Expected behavior

A clear and concise description of what you expected to happen.

Screenshots

If applicable, add screenshots to help explain your problem.

Additional context

Add any other context about the problem here.

Running i3 on X11 but that shouldn't matter.

For testing, my secret service implementation is keepassxc's. It's unlocked and working (I can see ProtonVPN adding its entries).

Notify maintainers

@WolfangAukang (also CC @JohnRTitor @pbsds @Andreas02-dev)

Metadata

Please run nix-shell -p nix-info --run "nix-info -m" and paste the result.

7bb2ccd

Add a 👍 reaction to issues you find important.
@Atemu Atemu mentioned this issue Apr 28, 2024
Open
@Andreas02-dev
Copy link

Hi @Atemu,

Thanks for pinging me. Could you show me the output of nmcli --version?

@Atemu
Copy link
Member Author

Atemu commented Apr 28, 2024

It's 1.46.0; that's the only NM in current Nixpkgs.

@Atemu
Copy link
Member Author

Atemu commented Apr 28, 2024

Could you share your logs when doing a quick connect?

@Andreas02-dev
Copy link

Andreas02-dev commented Apr 28, 2024 •
edited

It's 1.46.0; that's the only NM in current Nixpkgs.

Gotcha, that might be the issue. As quoted in #302011 (comment) , ProtonVPN uses a NetworkManager Connection property that's introduced in NetworkManager 1.46, which is in NixOS Unstable and will be used with Gnome 46 when that releases on NixOS.

Could you try to switch to Unstable for NetworkManager and see if that works for you?

@Atemu
Copy link
Member Author

Atemu commented Apr 28, 2024

I am on unstable, I linked the Nixpkgs revision I'm using.

@Andreas02-dev
Copy link

I am on unstable, I linked the Nixpkgs revision I'm using.

Sorry about that, I misread the version. I'll post my logs. Are you using Gnome Keyring?

@Atemu
Copy link
Member Author

Atemu commented Apr 28, 2024

Should have noted that down. I've updated the additional context section.

@JohnRTitor
Copy link
Contributor

Note: PR title should be updated to actually describe the issue in short. "Timeout"? What kind, how/when does it occur?
This helps people to discover the issue.

@Andreas02-dev
Copy link

Andreas02-dev commented Apr 28, 2024 •
edited

@Atemu

My logs from Unstable are as follows:

┌─ ~ 
└─> protonvpn-app
2024-04-28T13:52:06.436852 | proton.vpn.connection.vpnconnector:168 | INFO | CONN:STATE_CHANGED | Disconnected (initial state)
2024-04-28T13:52:06.437412 | proton.vpn.app.gtk.app:57 | INFO | APP:PROCESS_START | self=<app.App object at 0x7f957fe1d500 (proton+vpn+app+gtk+app+App at 0x17faa70)>
2024-04-28T13:52:06.885795 | proton.vpn.app.gtk.services.reconnector.reconnector:96 | INFO | VPN reconnector enabled.
2024-04-28T13:52:08.558234 | proton.vpn.app.gtk.controller:138 | INFO | APP.STARTUP:STARTUP_ACTIONS | Running startup actions
2024-04-28T13:52:08.558429 | proton.vpn.app.gtk.widgets.vpn.vpn_widget:174 | INFO | APP.VPN:WIDGET_READY | VPN widget is ready (load time: 1.67 seconds)
2024-04-28T13:52:08.558536 | proton.vpn.app.gtk.services.refresher.vpn_data_refresher:148 | INFO | APP.VPN_DATA_REFRESHER:ENABLE | VPN data refresher service enabled.
2024-04-28T13:52:08.558605 | proton.vpn.app.gtk.services.refresher.client_config_refresher:68 | INFO | Client config refresher enabled.
2024-04-28T13:52:08.558714 | proton.vpn.app.gtk.services.refresher.client_config_refresher:107 | INFO | Next client config refresh scheduled in 0:00:00
2024-04-28T13:52:08.558778 | proton.vpn.app.gtk.services.refresher.server_list_refresher:74 | INFO | Server list refresher enabled.
2024-04-28T13:52:08.559629 | proton.vpn.session.utils:25 | INFO | API:REQUEST | '/vpn/logicals?SecureCoreFilter=all'
2024-04-28T13:52:10.148770 | proton.vpn.session.utils:25 | INFO | API:REQUEST | '/vpn/v2/clientconfig'
2024-04-28T13:52:10.245791 | proton.vpn.app.gtk.services.reconnector.reconnector:176 | INFO | Network connectivity was detected.
2024-04-28T13:52:10.254346 | proton.vpn.session.utils:29 | INFO | API:RESPONSE | '/vpn/v2/clientconfig'
2024-04-28T13:52:10.255023 | proton.vpn.app.gtk.services.refresher.client_config_refresher:107 | INFO | Next client config refresh scheduled in 2:45:54.054664
2024-04-28T13:52:10.835357 | proton.vpn.session.utils:29 | INFO | API:RESPONSE | '/vpn/logicals?SecureCoreFilter=all'
2024-04-28T13:52:14.985510 | proton.vpn.app.gtk.services.refresher.server_list_refresher:126 | INFO | Next server list refresh scheduled in 0:14:00.487715
2024-04-28T13:52:18.267703 | proton.vpn.app.gtk.widgets.vpn.quick_connect_widget:96 | INFO | UI.TRAY:CONNECT | Connect to fastest server
2024-04-28T13:52:18.374888 | proton.vpn.core.connection:149 | INFO | CONN.CONNECT:START | Server: 185.107.44.110 / Protocol: openvpn-udp / Ports: [80, 51820, 4569, 1194, 5060] / Backend: None
2024-04-28T13:52:18.438021 | proton.vpn.connection.vpnconnector:168 | INFO | CONN:STATE_CHANGED | Connecting
2024-04-28T13:52:20.570052 | proton.vpn.backend.linux.networkmanager.core.networkmanager:90 | INFO | VPN server REACHABLE.
2024-04-28T13:52:22.209135 | proton.vpn.connection.vpnconnector:168 | INFO | CONN:STATE_CHANGED | Connected

My systemd logs with sudo journalctl -f are as follows:

apr 28 16:01:20 xps systemd[1]: Stopped target Host and Network Name Lookups.
apr 28 16:01:20 xps systemd[1]: Stopping Host and Network Name Lookups...
apr 28 16:01:20 xps systemd[1]: Stopped target User and Group Name Lookups.
apr 28 16:01:20 xps systemd[1]: Stopping User and Group Name Lookups...
apr 28 16:01:20 xps systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
apr 28 16:01:20 xps systemd[1]: nscd.service: Deactivated successfully.
apr 28 16:01:20 xps avahi-daemon[1163]: Got SIGHUP, reloading.
apr 28 16:01:20 xps avahi-daemon[1163]: No service file found in /etc/avahi/services.
apr 28 16:01:20 xps systemd[1]: Stopped Name Service Cache Daemon (nsncd).
apr 28 16:01:20 xps systemd[1]: nscd.service: Consumed 63ms CPU time, received 10.4K IP traffic, sent 6.1K IP traffic.
apr 28 16:01:20 xps systemd[1]: Starting Network Manager Script Dispatcher Service...
apr 28 16:01:20 xps systemd[1]: Starting Name Service Cache Daemon (nsncd)...
apr 28 16:01:20 xps systemd[1]: Started Network Manager Script Dispatcher Service.
apr 28 16:01:20 xps nsncd[29649]: Apr 28 14:01:20.758 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"
apr 28 16:01:20 xps systemd[1]: Started Name Service Cache Daemon (nsncd).
apr 28 16:01:20 xps systemd[1]: Reached target Host and Network Name Lookups.
apr 28 16:01:20 xps systemd[1]: Reached target User and Group Name Lookups.
apr 28 16:01:22 xps protonvpn-app.desktop[29549]: 2024-04-28T14:01:22.657315 | proton.vpn.backend.linux.networkmanager.core.networkmanager:90 | INFO | VPN server REACHABLE.
apr 28 16:01:22 xps nm-openvpn[29702]: OpenVPN 2.6.10 x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [MH/PKTINFO] [AEAD] [DCO]
apr 28 16:01:22 xps nm-openvpn[29702]: library versions: OpenSSL 3.0.13 30 Jan 2024, LZO 2.10
apr 28 16:01:22 xps nm-openvpn[29702]: DCO version: N/A
apr 28 16:01:23 xps nm-openvpn[29702]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
apr 28 16:01:23 xps nm-openvpn[29702]: TCP/UDP: Preserving recently used remote address: [AF_INET]185.107.44.110:51820
apr 28 16:01:23 xps nm-openvpn[29702]: UDPv4 link local: (not bound)
apr 28 16:01:23 xps nm-openvpn[29702]: UDPv4 link remote: [AF_INET]185.107.44.110:51820
apr 28 16:01:23 xps nm-openvpn[29702]: NOTE: UID/GID downgrade will be delayed because of --client, --pull, or --up-delay
apr 28 16:01:23 xps nm-openvpn[29702]: [node-nl-209.protonvpn.net] Peer Connection Initiated with [AF_INET]185.107.44.110:51820
apr 28 16:01:24 xps nm-openvpn[29702]: NOTE: setsockopt TCP_NODELAY=1 failed
apr 28 16:01:24 xps nm-openvpn[29702]: TUN/TAP device tun0 opened
apr 28 16:01:24 xps nm-openvpn[29702]: /nix/store/g4gh7zmah5vi6dsmkibr67sshb0hrxfj-NetworkManager-openvpn-1.10.2/libexec/nm-openvpn-service-openvpn-helper --debug 0 29695 --bus-name org.freedesktop.NetworkManager.openvpn.Connection_10 --tun -- tun0 1500 0 10.96.0.22 255.255.0.0 init
apr 28 16:01:24 xps nm-openvpn[29702]: UID set to nm-openvpn
apr 28 16:01:24 xps nm-openvpn[29702]: GID set to nm-openvpn
apr 28 16:01:24 xps nm-openvpn[29702]: Capabilities retained: CAP_NET_ADMIN
apr 28 16:01:24 xps nm-openvpn[29702]: Initialization Sequence Completed
apr 28 16:01:24 xps protonvpn-app.desktop[29549]: 2024-04-28T14:01:24.302338 | proton.vpn.connection.vpnconnector:168 | INFO | CONN:STATE_CHANGED | Connected
apr 28 16:01:24 xps systemd[1]: Stopped target Host and Network Name Lookups.
apr 28 16:01:24 xps systemd[1]: Stopping Host and Network Name Lookups...
apr 28 16:01:24 xps systemd[1]: Stopped target User and Group Name Lookups.
apr 28 16:01:24 xps systemd[1]: Stopping User and Group Name Lookups...
apr 28 16:01:24 xps systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
apr 28 16:01:24 xps systemd[1]: nscd.service: Deactivated successfully.
apr 28 16:01:24 xps systemd[1]: Stopped Name Service Cache Daemon (nsncd).
apr 28 16:01:24 xps systemd[1]: nscd.service: Consumed 84ms CPU time, received 0B IP traffic, sent 10.8K IP traffic.
apr 28 16:01:24 xps avahi-daemon[1163]: Got SIGHUP, reloading.
apr 28 16:01:24 xps avahi-daemon[1163]: No service file found in /etc/avahi/services.
apr 28 16:01:24 xps systemd[1]: Starting Name Service Cache Daemon (nsncd)...
apr 28 16:01:24 xps nsncd[29759]: Apr 28 14:01:24.429 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"
apr 28 16:01:24 xps systemd[1]: Started Name Service Cache Daemon (nsncd).
apr 28 16:01:24 xps systemd[1]: Reached target Host and Network Name Lookups.
apr 28 16:01:24 xps systemd[1]: Reached target User and Group Name Lookups.
apr 28 16:01:34 xps systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.

Could you try to use Gnome Keyring (or KWallet and clear all configuration of ProtonVPN and see if the issue persists?

Proton lists these 2 as required dependencies: https://protonvpn.com/support/official-linux-vpn-debian/

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
0.kind: bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@Atemu @JohnRTitor @Andreas02-dev