search

ancwrd1 / snx-rs

Open source Linux client for Checkpoint VPN tunnels
GNU Affero General Public License v3.0
57 stars 5 forks source link

Certificate VPN. Error: builder error: expected PKCS#8 PEM #7

Closed cubukun closed 5 months ago

cubukun commented 5 months ago

Hi, After using checkpoint client on MacOS to enroll using the enroll key from security dept, I have saved a pfx certificate. Then using OpenSSL to convert to a pem file but it returns "builder error: expected PKCS#8 PEM"

The command is sudo ./snx-rs -s vpn.domain.com -H true -o Certificate -y file.combo.pem

ancwrd1 commented 5 months ago

I have tried it with the following command: openssl pkcs12 -in keychain.pfx -nodes -out keychain.pem, then opened the file in the editor and removed all the garbage from it like Bag Attributes, Key Attributes, etc, leaving only private key and certificate sections:

-----BEGIN PRIVATE KEY-----
<PK>
-----END PRIVATE KEY-----
-----BEGIN CERTIFICATE-----
<CERT>
-----END CERTIFICATE-----
cubukun commented 5 months ago

I have tried your openssl command to convert the pfx file to pem. opened pem file with the editor and removed all the garbage, moved the PRIVATE KEY part to the top (same error as before without moving the PRIVATE part to the top). The error is as follows.

Error: builder error: error:05800074:x509 certificate routines:(unknown function):key values mismatch:crypto/x509/x509_cmp.c:416:

Caused by:
    error:05800074:x509 certificate routines:(unknown function):key values mismatch:crypto/x509/x509_cmp.c:416:
ancwrd1 commented 5 months ago

That usually means there is a mismatch between the private key and the certificate, or the list of certificates is not in the correct order. You should normally have the following order in the PEM file:

<private key for leaf cert>
<leaf certificate>
<intermediate CA..1>
<intermediate CA..N>
<root CA>
ancwrd1 commented 5 months ago

I can also add PFX/PKCS12 support, it requires an additional parameter to specify password.

cubukun commented 5 months ago

Thanks and appreciate your efforts. I have successfully connected to the VPN after changing the order of certificates. but still figuring out why it did not create new routes like using the Checkpoint client. any advice would be nice.

ancwrd1 commented 5 months ago

Routes are acquired either from hello_reply response (for SSL tunnel) or from ClientSettings response (in case of IPSec). You could enable trace log level where all requests and responses are logged and paste the response here, eventually redacting any sensitive information from it. It may look like this (for IPSec). Those IP ranges are automatically added as routes.

2024-01-17T11:10:25.332322Z TRACE snx_rs::http: Reply from server: (CCCserverResponse
        :ResponseHeader (
                :id (4)
                :type (ClientSettings)
                :session_id (xx)
                :return_code (600)
        )
        :ResponseData (
                :updated_policies (
                        :range (
                                :settings (
                                        : (
                                                :from (10.0.0.0)
                                                :to (10.255.255.255)
                                        )
                                        : (
                                                :from (172.16.0.0)
                                                :to (172.16.255.255)
                                        )
                                )
                                :expiry (0)
                                :id (xx)
                                :name (range)
                        )
                )
                :unchanged_policies ()
                :unsupported_policies ()
                :gateway_policy_version (65a05724)
                :gw_internal_ip (xx)
        )
)

There is also a manual option add-routes to manually add them either via command line of config file.

cubukun commented 5 months ago

yes, I received the response same as your sample with an error at the end. without -e option, it did not return anything like these. 

2024-01-17T11:15:45.022523Z TRACE snx_rs::util: Exec: "ip" ["route", "show", "default"]
Error: Cannot determine default IP!
ancwrd1 commented 5 months ago

Can you run this command manually? ip route show default.

it should output something like: default via x.x.x.x.x dev <devname> proto dhcp src y.y.y.y metric 600

And that y.y.y.y after src is the one which it uses as a default IP address of the computer.

cubukun commented 5 months ago

the output is: default via 192.168.68.100 dev eno1 proto static

ancwrd1 commented 5 months ago

Ok I think I need to change the way to acquire a default IP address from the interface.

ancwrd1 commented 5 months ago

I have added a fix, you could try it if you can build the project yourself.

cubukun commented 5 months ago

The error is as below after building the project

2024-01-18T15:27:36.294490Z TRACE snx_rs::util: Exec: "ip" ["-4", "route", "show", "default"]
2024-01-18T15:27:36.300072Z TRACE snx_rs::util: Exec: "ip" ["-4", "-o", "addr", "show", "dev", "eno1"]
2024-01-18T15:27:36.302606Z DEBUG snx_rs::platform::linux::xfrm: Source IP: <srcIP>
2024-01-18T15:27:36.302679Z DEBUG snx_rs::platform::linux::xfrm: Target IP: <dstIP>
2024-01-18T15:27:36.302763Z TRACE snx_rs::util: Exec: "ip" ["xfrm", "state", "del", "src", "<srcIP>", "dst", "<dstIP>", "proto", "esp", "spi", "0xe0c07a70"]
2024-01-18T15:27:36.305776Z TRACE snx_rs::util: Exec: "ip" ["xfrm", "state", "del", "src", "<dstIP>", "dst", "<srcIP>", "proto", "esp", "spi", "0x201c5686"]
2024-01-18T15:27:36.307661Z TRACE snx_rs::util: Exec: "ip" ["xfrm", "policy", "del", "dir", "out", "index", "422400001", "mark", "1650000"]
2024-01-18T15:27:36.309565Z TRACE snx_rs::util: Exec: "ip" ["xfrm", "policy", "del", "dir", "in", "index", "422400000", "mark", "1650000"]
2024-01-18T15:27:36.311390Z TRACE snx_rs::util: Exec: "ip" ["link", "del", "name", "snx-vti"]
2024-01-18T15:27:36.319529Z TRACE snx_rs::util: Exec: "ip" ["rule", "del", "to", "<dstIP>", "ipproto", "udp", "dport", "18234", "table", "18234"]
2024-01-18T15:27:36.322072Z DEBUG snx_rs::platform::linux::xfrm: Sending isakmp probe to <dstIP>
2024-01-18T15:27:41.323578Z DEBUG snx_rs::platform::linux::xfrm: Sending isakmp probe to <dstIP>
2024-01-18T15:27:46.325891Z DEBUG snx_rs::platform::linux::xfrm: Sending isakmp probe to <dstIP>
2024-01-18T15:27:51.327463Z DEBUG snx_rs::platform::linux::xfrm: Sending isakmp probe to <dstIP>
2024-01-18T15:27:56.329920Z DEBUG snx_rs::platform::linux::xfrm: Sending isakmp probe to <dstIP>
Error: Probing failed, server is not reachable via ESPinUDP tunnel!
ancwrd1 commented 5 months ago

May be your server does not support IPSec or doesn't have it enabled. Try with ssl tunnel (-e ssl)

cubukun commented 5 months ago

with -e ssl the log is as below. However, I have to manually add route sudo route add <IP> dev snx-vti to access the resource.

2024-01-19T04:16:41.846238Z DEBUG snx_rs::tunnel::ssl: Tunnel connected
2024-01-19T04:16:41.846615Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:16:41.846680Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:16:41.847182Z TRACE perform: zbus::handshake: Initializing
2024-01-19T04:16:41.848061Z TRACE perform: zbus::handshake: Waiting for DATA or OK from server
2024-01-19T04:16:41.848294Z TRACE perform: zbus::handshake: Received OK from server
2024-01-19T04:16:41.848663Z TRACE perform: zbus::handshake: Waiting for Unix FD passing agreement from server
2024-01-19T04:16:41.848855Z TRACE perform: zbus::handshake: Unix FD passing agreed by server
2024-01-19T04:16:41.849152Z TRACE perform: zbus::handshake: Handshake done
2024-01-19T04:16:41.849715Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:16:41.850578Z TRACE zbus::connection: Sending message: Msg { type: MethodCall, path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("Hello"))) }
2024-01-19T04:16:41.851169Z TRACE zbus::connection: Sent message with serial: 1
2024-01-19T04:16:41.852584Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") }
2024-01-19T04:16:41.852945Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:16:41.853382Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") })
2024-01-19T04:16:41.853790Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:16:41.854294Z DEBUG snx_rs::tunnel::ssl: Running SSL tunnel for session 1ec9f400826705f341f59cfca0e7ccf2
2024-01-19T04:16:41.854283Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") }
2024-01-19T04:16:41.855532Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:16:41.855747Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::connection: Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.176"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("AddMatch"))), body: Signature("s") }
2024-01-19T04:16:41.855997Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") })
2024-01-19T04:16:41.856553Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:16:41.856844Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::connection: Sent message with serial: 2
2024-01-19T04:16:41.857375Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:16:41.857543Z TRACE tokio_util::codec::framed_impl: writing; remaining=257
2024-01-19T04:16:41.857141Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 2 }
2024-01-19T04:16:41.857717Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:16:41.858104Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 2 })
2024-01-19T04:16:41.858396Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:16:41.858650Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:16:41.859769Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::connection: Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.176"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("GetNameOwner"))), body: Signature("s") }
2024-01-19T04:16:41.860560Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::connection: Sent message with serial: 3
2024-01-19T04:16:41.860880Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 3, body: Signature("s") }
2024-01-19T04:16:41.861306Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:16:41.861610Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 3, body: Signature("s") })
2024-01-19T04:16:41.861891Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:16:41.862739Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::connection: Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.176"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("AddMatch"))), body: Signature("s") }
2024-01-19T04:16:41.863157Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::connection: Sent message with serial: 4
2024-01-19T04:16:41.863613Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 4 }
2024-01-19T04:16:41.863970Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:16:41.864244Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 4 })
2024-01-19T04:16:41.864497Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:16:41.865176Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::connection: Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.176"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("GetAll"))), body: Signature("s") }
2024-01-19T04:16:41.865574Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::connection: Sent message with serial: 5
2024-01-19T04:16:41.865744Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:16:41.865770Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:16:41.866202Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:16:41.867438Z TRACE tokio_util::codec::framed_impl: frame decoded from buffer
2024-01-19T04:16:41.867611Z TRACE snx_rs::tunnel::ssl: Hello reply: HelloReply { version: 1, protocol_version: 1, office_mode: OfficeMode { ipaddr: "10.0.10.10", keep_address: None, dns_servers: None, dns_suffix: Some("") }, range: [NetworkRange { NetworkRange { from: 10.0.0.0, to: 10.255.255.255 }], timeouts: Timeouts { authentication: 14392, keepalive: 20 }, optional: Some(OptionalResponse { subnet: "255.255.255.0" }) }
2024-01-19T04:16:41.873915Z DEBUG snx_rs::tunnel::ssl::device: Created tun device: snx-vti
2024-01-19T04:16:41.874010Z DEBUG snx_rs::tunnel::ssl::device: Adding acquired DNS suffixes: 
2024-01-19T04:16:41.874038Z DEBUG snx_rs::tunnel::ssl::device: Adding provided DNS suffixes: []
2024-01-19T04:16:41.874096Z TRACE snx_rs::util: Exec: "resolvectl" ["domain", "snx-vti", "~"]
2024-01-19T04:16:41.874407Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:16:41.875022Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.10"))), reply-serial: 5, body: Signature("a{sv}") }
2024-01-19T04:16:41.875660Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:16:41.876012Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.10"))), reply-serial: 5, body: Signature("a{sv}") })
2024-01-19T04:16:41.876316Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:16:41.876650Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:16:41.877228Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:16:41.877569Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:16:41.877894Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:16:41.878657Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:16:41.881627Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:16:41.882012Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:16:41.882358Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:16:41.883309Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.WwanEnabled` updated
2024-01-19T04:16:41.883439Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.Metered` updated
2024-01-19T04:16:41.884795Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.WwanHardwareEnabled` updated
2024-01-19T04:16:41.884940Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.NetworkingEnabled` updated
2024-01-19T04:16:41.885068Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.State` updated
2024-01-19T04:16:41.885306Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.Connectivity` updated
2024-01-19T04:16:41.885430Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.ConnectivityCheckEnabled` updated
2024-01-19T04:16:41.885546Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.GlobalDnsConfiguration` updated
2024-01-19T04:16:41.885696Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.Checkpoints` updated
2024-01-19T04:16:41.885810Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.ActivatingConnection` updated
2024-01-19T04:16:41.885937Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.WirelessEnabled` updated
2024-01-19T04:16:41.886050Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.WirelessHardwareEnabled` updated
2024-01-19T04:16:41.886173Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.WimaxHardwareEnabled` updated
2024-01-19T04:16:41.886293Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.Startup` updated
2024-01-19T04:16:41.886418Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.ActiveConnections` updated
2024-01-19T04:16:41.886562Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.AllDevices` updated
2024-01-19T04:16:41.886728Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.ConnectivityCheckAvailable` updated
2024-01-19T04:16:41.886834Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.WimaxEnabled` updated
2024-01-19T04:16:41.889328Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.PrimaryConnection` updated
2024-01-19T04:16:41.889580Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.Capabilities` updated
2024-01-19T04:16:41.889726Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.Devices` updated
2024-01-19T04:16:41.890736Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.PrimaryConnectionType` updated
2024-01-19T04:16:41.890858Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.Version` updated
2024-01-19T04:16:41.890976Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.ConnectivityCheckUri` updated
2024-01-19T04:16:41.891549Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}: zbus::proxy: Property `org.freedesktop.NetworkManager.AllDevices` updated
2024-01-19T04:16:41.891933Z DEBUG snx_rs::platform::linux::net: NetworkManager state changed to ConnectedGlobal
2024-01-19T04:16:41.892113Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Listening for property changes on org.freedesktop.NetworkManager...
2024-01-19T04:16:41.892664Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.Devices` updated
2024-01-19T04:16:41.911083Z TRACE snx_rs::util: Exec: "nmcli" ["device", "set", "snx-vti", "managed", "no"]
2024-01-19T04:16:41.971939Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:16:41.972022Z TRACE tokio_util::codec::framed_impl: frame decoded from buffer
2024-01-19T04:16:41.972062Z TRACE snx_rs::tunnel::ssl: snx-vti => snx: 48
2024-01-19T04:16:41.972122Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:16:41.972273Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:16:41.972302Z TRACE tokio_util::codec::framed_impl: writing; remaining=56
2024-01-19T04:16:41.972425Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:16:46.225830Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:16:46.225967Z TRACE tokio_util::codec::framed_impl: frame decoded from buffer
2024-01-19T04:16:46.226083Z TRACE snx_rs::tunnel::ssl: snx-vti => snx: 48
2024-01-19T04:16:46.226269Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:16:46.226494Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:16:46.226599Z TRACE tokio_util::codec::framed_impl: writing; remaining=56
2024-01-19T04:16:46.226826Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:16:54.673849Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:16:54.673987Z TRACE tokio_util::codec::framed_impl: frame decoded from buffer
2024-01-19T04:16:54.674106Z TRACE snx_rs::tunnel::ssl: snx-vti => snx: 48
2024-01-19T04:16:54.674326Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:16:54.674624Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:16:54.674724Z TRACE tokio_util::codec::framed_impl: writing; remaining=56
2024-01-19T04:16:54.674949Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:17:02.488932Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:02.489551Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:02.489804Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:02.490073Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:02.490600Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.AllDevices` updated
2024-01-19T04:17:02.492111Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:02.492690Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:02.492947Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:02.493218Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:02.493798Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.Devices` updated
2024-01-19T04:17:02.501865Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:02.502511Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:02.502790Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:02.503066Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:02.503607Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.AllDevices` updated
2024-01-19T04:17:02.507747Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:02.508329Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:02.508583Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:02.509641Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:02.510347Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.Devices` updated
2024-01-19T04:17:02.845466Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:02.846628Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:02.847273Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:02.848019Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:02.849145Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.Devices` updated
2024-01-19T04:17:02.851279Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:02.851989Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:02.852325Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:02.852670Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:02.853218Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.AllDevices` updated
2024-01-19T04:17:08.772917Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:08.773527Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:08.773780Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:08.774050Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:08.774573Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.AllDevices` updated
2024-01-19T04:17:08.778863Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:08.779435Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:08.779688Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:08.779956Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:08.780451Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.Devices` updated
2024-01-19T04:17:08.803393Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:08.803988Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:08.804231Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:08.804500Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:08.805013Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.Devices` updated
2024-01-19T04:17:08.805998Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:08.806771Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:08.807161Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:08.807558Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:08.808176Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.Devices` updated
2024-01-19T04:17:08.811030Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:08.811603Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:08.811857Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:08.812129Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:08.812712Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.AllDevices` updated
2024-01-19T04:17:08.816560Z TRACE socket reader: zbus::socket_reader: Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") }
2024-01-19T04:17:08.817167Z TRACE socket reader: zbus::socket_reader: Error broadcasting message to stream for `None`: SendError(..)
2024-01-19T04:17:08.817436Z TRACE socket reader: zbus::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed(":1.10"))), path: ObjectPath("/org/freedesktop/NetworkManager"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus.Properties"))), member: MemberName(Str(Borrowed("PropertiesChanged"))), body: Signature("sa{sv}as") })
2024-01-19T04:17:08.817704Z TRACE socket reader: zbus::socket_reader: Waiting for message on the socket..
2024-01-19T04:17:08.818215Z TRACE new:{}{task_name="org.freedesktop.NetworkManager proxy caching"}:keep_updated: zbus::proxy: Property `org.freedesktop.NetworkManager.AllDevices` updated
2024-01-19T04:17:11.569909Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:17:11.570049Z TRACE tokio_util::codec::framed_impl: frame decoded from buffer
2024-01-19T04:17:11.570161Z TRACE snx_rs::tunnel::ssl: snx-vti => snx: 48
2024-01-19T04:17:11.570350Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:17:11.570579Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:17:11.570653Z TRACE tokio_util::codec::framed_impl: writing; remaining=56
2024-01-19T04:17:11.570940Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:17:31.572756Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:17:31.572878Z TRACE tokio_util::codec::framed_impl: writing; remaining=29
2024-01-19T04:17:31.573142Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:17:31.579773Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:17:31.579917Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:17:31.580114Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:17:31.580480Z TRACE tokio_util::codec::framed_impl: frame decoded from buffer
2024-01-19T04:17:31.580744Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:17:31.580950Z DEBUG snx_rs::tunnel::ssl: Control packet received: keepalive
2024-01-19T04:17:44.337843Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:17:44.337968Z TRACE tokio_util::codec::framed_impl: frame decoded from buffer
2024-01-19T04:17:44.338076Z TRACE snx_rs::tunnel::ssl: snx-vti => snx: 48
2024-01-19T04:17:44.338253Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:17:44.338448Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:17:44.338515Z TRACE tokio_util::codec::framed_impl: writing; remaining=56
2024-01-19T04:17:44.338708Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:18:04.340154Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:18:04.340259Z TRACE tokio_util::codec::framed_impl: writing; remaining=29
2024-01-19T04:18:04.340517Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:18:04.346139Z TRACE tokio_util::codec::framed_impl: flushing framed transport
2024-01-19T04:18:04.346264Z TRACE tokio_util::codec::framed_impl: framed transport flushed
2024-01-19T04:18:04.346439Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:18:04.346734Z TRACE tokio_util::codec::framed_impl: frame decoded from buffer
2024-01-19T04:18:04.346828Z TRACE tokio_util::codec::framed_impl: attempting to decode a frame
2024-01-19T04:18:04.346990Z DEBUG snx_rs::tunnel::ssl: Control packet received: keepalive
ancwrd1 commented 5 months ago

Do you perhaps use no-routing parameter in the config file or from the command line (-n or --no-routing)?

cubukun commented 5 months ago

It works with 0.9.0 Version. Thank you very much.