Missing Push-Notifications via VPN

dobey · 30 Apr 2019, 18:31

There should be I think ~/.cache/upstart/ubuntu-push-client.log can you check it please?

A Former User · 30 Apr 2019, 20:30

2019/04/30 21:51:56 INFO connectivity check passed.
2019/04/30 22:00:08 ERROR session error'ed out with read tcp 192.168.178.204:41869->188.166.175.79:5001: use of closed network connection
2019/04/30 22:00:11 INFO connectivity check passed.
2019/04/30 22:00:16 ERROR session error'ed out with read tcp 10.146.93.73:35134->188.166.175.79:5001: use of closed network connection
2019/04/30 22:00:28 ERROR while GETting http://start.ubuntu.com/connectivity-check.html: Get http://start.ubuntu.com/connectivity-check.html: read tcp 10.146.93.73:48921->91.189.90.41:80: use of closed network connection
2019/04/30 22:12:30 INFO dekko2.dekkoproject_dekko-notify_0.1.8-21b84 helper output: {"notification":{"card":{"actions":["appid://dekko2.dekkoproject/dekko/current-user-version"],"body":"Test2\n\n\nOutlook für Android<https://aka.ms/ghei36> herunterladen\n\n","persist":true,"popup":true,"summary":"Miriam Schaeffer"},"sound":true,"tag":"dekko-802","vibrate":{"duration":200,"pattern":[200,100],"repeat":2}}}
2019/04/30 22:12:30 ERROR GetWindowStack call returned org.freedesktop.DBus.Error.ServiceUnknown: The name com.canonical.Unity.WindowStack was not provided by any .service files
2019/04/30 22:16:14 INFO connectivity check passed.
2019/04/30 22:16:16 INFO unicast app:com.ubuntu.telegram_telegram msg:JMDrV2uDEemtb4Z7KvMZ8A== payload:{"message":{"loc_key":"MESSAGE_TEXT","loc_args":["Miriam Sch\u00e4ffer","Test1"],"custom":{"msg_id":"1165","from_id":"730800194"}},"notification":{"tag":"chat","card":{"summary":"Miriam Sch\u00e4ffer","body":"Test1","popup":true,"persist":true},"sound":"buzz.mp3","vibrate":{"pattern":[200,100],"duration":200,"repeat":2},"emblem-counter":{"count":1,"visible":true}}}
2019/04/30 22:16:17 INFO com.ubuntu.telegram_push_2.5.5.0 helper output: {
    "notification": {
        "card": {
            "actions": [
                "telegram://chat/730800194"
            ],
            "body": "Test1",
            "icon": "",
            "persist": true,
            "popup": true,
            "summary": "Miriam Schäffer"
        },
        "emblem-counter": {
            "count": 1,
            "visible": true
        },
        "sound": true,
        "tag": "730800194",
        "vibrate": true
    }
}
2019/04/30 22:16:17 ERROR GetWindowStack call returned org.freedesktop.DBus.Error.ServiceUnknown: The name com.canonical.Unity.WindowStack was not provided by any .service files
2019/04/30 22:20:46 INFO unicast app:com.ubuntu.telegram_telegram msg:b28JKWuFEemtb4Z7KvMZ8A== payload:{"message":{"loc_key":"MESSAGE_TEXT","loc_args":["Miriam Sch\u00e4ffer","Test3"],"custom":{"msg_id":"1166","from_id":"730800194"}},"notification":{"tag":"chat","card":{"summary":"Miriam Sch\u00e4ffer","body":"Test3","popup":true,"persist":true},"sound":"buzz.mp3","vibrate":{"pattern":[200,100],"duration":200,"repeat":2},"emblem-counter":{"count":1,"visible":true}}}
2019/04/30 22:20:48 INFO com.ubuntu.telegram_push_2.5.5.0 helper output: {
    "notification": {
        "card": {
            "actions": [
                "telegram://chat/730800194"
            ],
            "body": "Test3",
            "icon": "",
            "persist": true,
            "popup": true,
            "summary": "Miriam Schäffer"
        },
        "emblem-counter": {
            "count": 1,
            "visible": true
        },
        "sound": true,
        "tag": "730800194",
        "vibrate": true
    }
}
2019/04/30 22:20:48 ERROR GetWindowStack call returned org.freedesktop.DBus.Error.ServiceUnknown: The name com.canonical.Unity.WindowStack was not provided by any .service files
2019/04/30 22:21:17 INFO dekko2.dekkoproject_dekko-notify_0.1.8-21b84 helper output: {"notification":{"card":{"actions":["appid://dekko2.dekkoproject/dekko/current-user-version"],"body":"Test4\n","persist":true,"popup":true,"summary":"Miriam Schaeffer"},"sound":true,"tag":"dekko-803","vibrate":{"duration":200,"pattern":[200,100],"repeat":2}}}
2019/04/30 22:21:17 ERROR GetWindowStack call returned org.freedesktop.DBus.Error.ServiceUnknown: The name com.canonical.Unity.WindowStack was not provided by any .service files
2019/04/30 22:21:46 ERROR session error'ed out with read tcp 10.146.93.73:35199->188.166.175.79:5001: use of closed network connection
2019/04/30 22:21:58 ERROR while GETting http://start.ubuntu.com/connectivity-check.html: Get http://start.ubuntu.com/connectivity-check.html: net/http: request canceled while waiting for connection
2019/04/30 22:31:59 INFO connectivity check passed.

dobey · 30 Apr 2019, 20:33

@thilov It looks like you've got a somewhat cropped version of that, but it does seem there is some errors in there with a little more info that didn't get included in your copy/paste.

A Former User · 30 Apr 2019, 20:38

@dobey I fixed my copy and paste mistake.

A Former User · 30 Apr 2019, 20:45

And this is what I did/got:
22:00 start VPN
22:04 sent test mail and telegram-mail
22:08 opend Dekko2
22:12 opend Telegram and got Dekko2-push
22:16 stopped VPN and got Telegram-push
22:19 got same Telegram-push again
22:20 sent testmails again and got the push

dobey · 1 May 2019, 14:18

OK. First, to clarify, dekko does not use push notifications. It has another daemon running locally which polls to download e-mail, and then uses the local push notifications API to pop a notification, but it does not go through the push.ubports.com server.

Telegram does send notifications via the push server, but even when working properly, can be very slow to arrive.

As you see in the logs, there is some connectivity issue when switching between the network interfaces, and it can take many minutes before the client reconnects to the server. I would say that to test it properly, you need to stay connected to the VPN for quite some time, and not use the applications on the device while testing if push works, but instead send some telegram messages to your user on the phone, from a different account on a different device.

If dekko is also having issues, the cause may be somehow related as it's with network issues, but it is probably a separate fix that would be needed, if the problem is in the client software rather than network config.

A Former User · 6 May 2019, 09:50

I tested ProtonVPN with similar results.

Is a hardware issue possible? Sadly I doesn't have a second UT device for more testing.

dobey · 6 May 2019, 12:38

@thilov said in Missing Push-Notifications via VPN:

I tested ProtonVPN with similar results.

Is a hardware issue possible? Sadly I doesn't have a second UT device for more testing.

I doubt it's a hardware issue. It's most likely some configuration issue preventing connection to the services when you connect to VPN.

You don't need a second UT device to test sending messages via telegram. You can have someone else send you messages (probably after 20 min or so), or use a reminder bot like https://t.me/SkeddyBot to schedule a few reminders.

Also note however, that Telegram push notifications can be slow on the Telegram side at times, so to test it would be best to leave the phone connected and not use Telegram on it, for at least an hour, and have multiple reminders set to be sent to you over that time period.

Another option is to install and set up https://caxton.herokuapp.com/ to send push notifications from a PC to test.

A Former User · 5 Jun 2019, 13:04

I tested that at the weekend.
I rebooted the phone, connected to vpn and sent a telegram message from my wife's phone (a message of the UBports News Channel came also in this time).
I waited 5! hours and nothing happend. The .log was similar to the one I posted before.

A Former User · 7 Dec 2021, 13:59

I just did what @circ did here: https://forums.ubports.com/topic/2693/where-are-vpn-logs-solved

I started the log, started the vpn and opend telegram

I can't find any error but I'm just a noob. Might someone tell me if something can be found out here?

t@t-ESPRIMO:~$ adb shell
* daemon not running; starting now at tcp:5037
* daemon started successfully
phablet@ubuntu-phablet:~$ tail -f  /var/log/syslog
May 10 08:21:35 ubuntu-phablet repowerd[905]: LibsuspendSuspendControl: allow_suspend(UPowerPowerSource121)
May 10 08:21:37 ubuntu-phablet repowerd[905]: DefaultStateMachine: handle_user_activity_extending_power_state
May 10 08:21:39 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: new_autobrightness_value(0.17)
May 10 08:21:39 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.14 => 0.17 in 3.00 steps 33333.33us each
May 10 08:21:39 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.14 => 0.17 done
May 10 08:21:39 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_emit_brightness(0.166031), brightness_value=42
May 10 08:21:43 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: new_autobrightness_value(0.10)
May 10 08:21:43 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.17 => 0.10 in 7.00 steps 14285.71us each
May 10 08:21:43 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.17 => 0.10 done
May 10 08:21:44 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_emit_brightness(0.103096), brightness_value=26
May 10 08:22:03 ubuntu-phablet repowerd[905]: DefaultStateMachine: handle_user_activity_extending_power_state
May 10 08:22:03 ubuntu-phablet repowerd[905]: DefaultStateMachine: handle_user_activity_extending_power_state
May 10 08:22:04 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: new_autobrightness_value(0.06)
May 10 08:22:04 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.10 => 0.06 in 4.00 steps 25000.00us each
May 10 08:22:04 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.10 => 0.06 done
May 10 08:22:04 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_emit_brightness(0.063242), brightness_value=16
May 10 08:22:08 ubuntu-phablet repowerd[905]: DefaultStateMachine: handle_user_activity_extending_power_state
May 10 08:22:08 ubuntu-phablet NetworkManager[1978]: <info>  [1557469328.6664] audit: op="connection-activate" uuid="1b6418e0-1eaa-479c-8e6d-4610961953a5" name="Thilo" pid=3512 uid=32011 result="success"
May 10 08:22:08 ubuntu-phablet NetworkManager[1978]: <info>  [1557469328.6984] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",0]: Started the VPN service, PID 9099
May 10 08:22:08 ubuntu-phablet NetworkManager[1978]: <info>  [1557469328.7825] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",0]: Saw the service appear; activating connection
May 10 08:22:09 ubuntu-phablet NetworkManager[1978]: <info>  [1557469329.0924] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",0]: VPN plugin: state changed: starting (3)
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: OpenVPN 2.3.10 arm-unknown-linux-gnueabihf [SSL (OpenSSL)] [LZO] [EPOLL] [PKCS11] [MH] [IPv6] built on Jun 22 2017
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: library versions: OpenSSL 1.0.2g  1 Mar 2016, LZO 2.08
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: WARNING: file '/home/phablet/Downloads/openvpn/Thilo.key' is group or others accessible
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: WARNING: file '/home/phablet/Downloads/openvpn/ta.key' is group or others accessible
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: Control Channel Authentication: using '/home/phablet/Downloads/openvpn/ta.key' as a OpenVPN static key file
May 10 08:22:09 ubuntu-phablet repowerd[905]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=1, percentage=99.00, temperature=23.80
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: NOTE: chroot will be delayed because of --client, --pull, or --up-delay
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: NOTE: UID/GID downgrade will be delayed because of --client, --pull, or --up-delay
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: UDPv4 link local: [undef]
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: UDPv4 link remote: [AF_INET]77.7.9.186:1194
May 10 08:22:09 ubuntu-phablet nm-openvpn[9126]: [server_wOT8t17Vb1mk0jke] Peer Connection Initiated with [AF_INET]77.7.9.186:1194
May 10 08:22:12 ubuntu-phablet nm-openvpn[9126]: Options error: Unrecognized option or missing parameter(s) in [PUSH-OPTIONS]:3: block-outside-dns (2.3.10)
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: nm_device_get_device_type: assertion 'NM_IS_DEVICE (self)' failed
May 10 08:22:12 ubuntu-phablet nm-openvpn[9126]: TUN/TAP device tun0 opened
May 10 08:22:12 ubuntu-phablet nm-openvpn[9126]: /usr/lib/NetworkManager/nm-openvpn-service-openvpn-helper --bus-name org.freedesktop.NetworkManager.openvpn.Connection_8 --tun -- tun0 1500 1572 10.8.0.4 255.255.255.0 init
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.2322] manager: (tun0): new Tun device (/org/freedesktop/NetworkManager/Devices/4)
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.2926] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",0]: VPN connection: (IP Config Get) reply received.
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3019] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: VPN connection: (IP4 Config Get) reply received
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3044] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data: VPN Gateway: 77.7.9.186
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3046] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data: Tunnel Device: tun0
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3047] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data: IPv4 configuration:
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3048] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data:   Internal Gateway: 10.8.0.1
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3050] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data:   Internal Address: 10.8.0.4
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3051] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data:   Internal Prefix: 24
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3052] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data:   Internal Point-to-Point Address: 10.8.0.4
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3053] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data:   Maximum Segment Size (MSS): 0
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3054] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data:   Static Route: 192.168.178.1/24   Next Hop: 10.8.0.1
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3056] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data:   Forbid Default Route: no
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3057] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data:   Internal DNS: 192.168.178.1
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3058] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data:   DNS Domain: '(none)'
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3059] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: Data: No IPv6 configuration
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <error> [1557469332.3109] platform-linux: do-add-ip4-address[2: 10.69.85.211/24,0.0.0.0]: failure 17 (File exists)
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3120] platform-linux: do_add_addroute: seq_restul == -EEXISTS; treating as OK
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3130] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: VPN connection: (IP Config Get) complete
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3141] vpn-connection[0x1d09108,1b6418e0-1eaa-479c-8e6d-4610961953a5,"Thilo",27:(tun0)]: VPN plugin: state changed: started (4)
May 10 08:22:12 ubuntu-phablet nm-openvpn[9126]: chroot to '/var/lib/openvpn/chroot' and cd to '/' succeeded
May 10 08:22:12 ubuntu-phablet nm-openvpn[9126]: GID set to nm-openvpn
May 10 08:22:12 ubuntu-phablet nm-openvpn[9126]: UID set to nm-openvpn
May 10 08:22:12 ubuntu-phablet nm-openvpn[9126]: Initialization Sequence Completed
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3289] manager: NetworkManager state is now CONNECTED_LOCAL
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3293] manager: NetworkManager state is now CONNECTED_GLOBAL
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3299] dns-mgr: Writing DNS information to /sbin/resolvconf
May 10 08:22:12 ubuntu-phablet dnsmasq[3202]: setting upstream servers from DBus
May 10 08:22:12 ubuntu-phablet dnsmasq[3202]: using nameserver 192.168.178.1#53
May 10 08:22:12 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_clearWakeup(:1.100,248)
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3831] devices added (path: /sys/devices/virtual/net/tun0, iface: tun0)
May 10 08:22:12 ubuntu-phablet dbus[762]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3862] device added (path: /sys/devices/virtual/net/tun0, iface: tun0): no ifupdown configuration found.
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.3910] device (tun0): state change: unmanaged -> unavailable (reason 'connection-assumed') [10 20 41]
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4016] keyfile: add connection in-memory (5f6fe794-ab81-44ae-9800-7f6b27290ca0,"tun0")
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4073] device (tun0): state change: unavailable -> disconnected (reason 'connection-assumed') [20 30 41]
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4084] policy: Device 'tun0' has no connection; scheduling activate_check in 0 seconds.
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4139] device (tun0): Activation: starting connection 'tun0' (5f6fe794-ab81-44ae-9800-7f6b27290ca0)
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4172] device (tun0): state change: disconnected -> prepare (reason 'none') [30 40 0]
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4196] device (tun0): state change: prepare -> config (reason 'none') [40 50 0]
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4214] device (tun0): state change: config -> ip-config (reason 'none') [50 70 0]
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <error> [1557469332.4237] platform-linux: do-add-ip4-address[27: 10.8.0.4/24]: failure 17 (File exists)
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4238] platform-linux: do_add_addroute: seq_restul == -EEXISTS; treating as OK
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4239] device (tun0): state change: ip-config -> ip-check (reason 'none') [70 80 0]
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4260] device (tun0): state change: ip-check -> secondaries (reason 'none') [80 90 0]
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4277] device (tun0): state change: secondaries -> activated (reason 'none') [90 100 0]
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4372] manager: NetworkManager state is now CONNECTED_LOCAL
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4377] manager: NetworkManager state is now CONNECTED_GLOBAL
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4381] policy: set 'tun0' (tun0) as default for IPv4 routing and DNS
May 10 08:22:12 ubuntu-phablet NetworkManager[1978]: <info>  [1557469332.4385] device (tun0): Activation: successful, device activated.
May 10 08:22:12 ubuntu-phablet dbus[762]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 10 08:22:12 ubuntu-phablet nm-dispatcher: req:1 'vpn-up' [tun0]: new request (3 scripts)
May 10 08:22:12 ubuntu-phablet nm-dispatcher: req:1 'vpn-up' [tun0]: start running ordered scripts...
May 10 08:22:12 ubuntu-phablet nm-dispatcher: req:2 'up' [tun0]: new request (3 scripts)
May 10 08:22:12 ubuntu-phablet root: 02default_route_workaround: (tun0): vpn-up: flushing routing table (proto boot)
May 10 08:22:12 ubuntu-phablet nm-dispatcher: req:2 'up' [tun0]: start running ordered scripts...
May 10 08:22:13 ubuntu-phablet root: 02default_route_workaround: (tun0): up: flushing routing table (proto boot)
May 10 08:22:14 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: new_autobrightness_value(0.11)
May 10 08:22:14 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.06 => 0.11 in 5.00 steps 20000.00us each
May 10 08:22:14 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestWakeup(:1.100,ubuntu push client,1557469337)
May 10 08:22:14 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestWakeup(:1.100,ubuntu push client,1557469337) => 249
May 10 08:22:14 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.06 => 0.11 done
May 10 08:22:14 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_emit_brightness(0.105389), brightness_value=27
May 10 08:22:17 ubuntu-phablet repowerd[905]: LibsuspendSuspendControl: disallow_suspend(Wakeup_249122)
May 10 08:22:17 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_emit_Wakeup()
May 10 08:22:17 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_clearWakeup(:1.84,247)
May 10 08:22:17 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestWakeup(:1.84,indicator-datetime,1557478800)
May 10 08:22:17 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestWakeup(:1.84,indicator-datetime,1557478800) => 250
May 10 08:22:17 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestSysState(:1.100,ubuntu push client,1)
May 10 08:22:17 ubuntu-phablet repowerd[905]: LibsuspendSuspendControl: disallow_suspend(UnityScreenService)
May 10 08:22:17 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestSysState(:1.100,ubuntu push client,1) => 138
May 10 08:22:18 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: new_autobrightness_value(0.09)
May 10 08:22:18 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.11 => 0.09 in 2.00 steps 50000.00us each
May 10 08:22:19 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.11 => 0.09 done
May 10 08:22:19 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_emit_brightness(0.087549), brightness_value=22
May 10 08:22:20 ubuntu-phablet repowerd[905]: LibsuspendSuspendControl: allow_suspend(Wakeup_249122)
May 10 08:22:21 ubuntu-phablet ntpdate[9205]: adjust time server 91.189.89.199 offset -0.258938 sec
May 10 08:22:25 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestWakeup(:1.100,ubuntu push client,1557469645)
May 10 08:22:25 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestWakeup(:1.100,ubuntu push client,1557469645) => 251
May 10 08:22:25 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_clearSysState(:1.100,138)
May 10 08:22:25 ubuntu-phablet repowerd[905]: LibsuspendSuspendControl: allow_suspend(UnityScreenService)
May 10 08:22:47 ubuntu-phablet signond[9298]: QObject::disconnect: Unexpected null parameter
May 10 08:22:49 ubuntu-phablet repowerd[905]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=1, percentage=99.00, temperature=24.00
May 10 08:23:04 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: new_autobrightness_value(0.11)
May 10 08:23:04 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.09 => 0.11 in 2.00 steps 50000.00us each
May 10 08:23:04 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.09 => 0.11 done
May 10 08:23:04 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_emit_brightness(0.105905), brightness_value=27
May 10 08:23:29 ubuntu-phablet repowerd[905]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=1, percentage=99.00, temperature=24.10
May 10 08:24:09 ubuntu-phablet repowerd[905]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=1, percentage=99.00, temperature=24.30
May 10 08:24:30 ubuntu-phablet repowerd[905]: DefaultStateMachine: handle_user_activity_extending_power_state
May 10 08:24:30 ubuntu-phablet repowerd[905]: DefaultStateMachine: handle_user_activity_extending_power_state
May 10 08:24:38 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: new_autobrightness_value(0.09)
May 10 08:24:38 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.11 => 0.09 in 2.00 steps 50000.00us each
May 10 08:24:38 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.11 => 0.09 done
May 10 08:24:38 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_emit_brightness(0.087364), brightness_value=22
May 10 08:24:39 ubuntu-phablet repowerd[905]: DefaultStateMachine: handle_user_activity_extending_power_state
May 10 08:24:39 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestSysState(:1.77,active,1)
May 10 08:24:39 ubuntu-phablet repowerd[905]: LibsuspendSuspendControl: disallow_suspend(UnityScreenService)
May 10 08:24:39 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_requestSysState(:1.77,active,1) => 139
May 10 08:24:41 ubuntu-phablet dbus[2190]: apparmor="DENIED" operation="dbus_method_call"  bus="session" path="/com/ubuntu/connectivity1/Private" interface="org.freedesktop.DBus.Properties" member="GetAll" mask="send" name="com.ubuntu.connectivity1" pid=9870 label="com.ubuntu.telegram_telegram_2.5.5.0" peer_pid=3512 peer_label="unconfined"
May 10 08:24:41 ubuntu-phablet dbus[2190]: apparmor="DENIED" operation="dbus_method_call"  bus="session" path="/com/canonical/libertine/Service/Operations" interface="com.canonical.libertine.Service.Operations" member="list" mask="send" name="com.canonical.libertine.Service" pid=9870 label="com.ubuntu.telegram_telegram_2.5.5.0" peer_pid=3363 peer_label="unconfined"
May 10 08:24:41 ubuntu-phablet dbus[2190]: apparmor="DENIED" operation="dbus_method_call"  bus="session" path="/com/canonical/libertine/Service/OperationsMonitor" interface="com.canonical.libertine.Service.OperationsMonitor" member="running" mask="send" name="com.canonical.libertine.Service" pid=9870 label="com.ubuntu.telegram_telegram_2.5.5.0" peer_pid=3363 peer_label="unconfined"
May 10 08:24:41 ubuntu-phablet dbus[2190]: apparmor="DENIED" operation="dbus_method_call"  bus="session" path="/com/canonical/libertine/Service/OperationsMonitor" interface="com.canonical.libertine.Service.OperationsMonitor" member="last_error" mask="send" name="com.canonical.libertine.Service" pid=9870 label="com.ubuntu.telegram_telegram_2.5.5.0" peer_pid=3363 peer_label="unconfined"
May 10 08:24:41 ubuntu-phablet dbus[2190]: apparmor="DENIED" operation="dbus_method_call"  bus="session" path="/com/canonical/libertine/Service/OperationsMonitor" interface="com.canonical.libertine.Service.OperationsMonitor" member="result" mask="send" name="com.canonical.libertine.Service" pid=9870 label="com.ubuntu.telegram_telegram_2.5.5.0" peer_pid=3363 peer_label="unconfined"
May 10 08:24:42 ubuntu-phablet dbus[2190]: apparmor="DENIED" operation="dbus_method_call"  bus="session" path="/com/ubuntu/connectivity1/Private" interface="org.freedesktop.DBus.Properties" member="GetAll" mask="send" name="com.ubuntu.connectivity1" pid=9870 label="com.ubuntu.telegram_telegram_2.5.5.0" peer_pid=3512 peer_label="unconfined"
May 10 08:24:45 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: new_autobrightness_value(0.06)
May 10 08:24:45 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.09 => 0.06 in 3.00 steps 33333.33us each
May 10 08:24:45 ubuntu-phablet repowerd[905]: BacklightBrightnessControl: Transitioning brightness 0.09 => 0.06 done
May 10 08:24:45 ubuntu-phablet repowerd[905]: UnityScreenService: dbus_emit_brightness(0.062878), brightness_value=16
May 10 08:24:49 ubuntu-phablet repowerd[905]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=1, percentage=99.00, temperature=24.40
May 10 08:25:14 ubuntu-phablet signond[9941]: QObject::disconnect: Unexpected null parameter
May 10 08:25:29 ubuntu-phablet repowerd[905]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=1, percentage=99.00, temperature=24.60

ubportsuser · 7 Dec 2021, 13:59

@thilov I am not sure if your issue is solved, but I think I noticed something similar. When you connect to the vpn and especially use a kill switch, the ubuntu push notification ( and also pantalimon and may be other processess unrelated to notifications) are still associated with the old gateway and not the VPN gateway. You can can do the following:
sudo netstat -tunlpaw
and look for the processess ESTABLISHED with your old gateway address (for ex: 192.168.1.xx)
Then use sudo kill -HUP xxxx where xxxx is the PID number of the process identified in the previous process.
This should reassociate the process to the new gateway. If not try sudo kill xxxx without -HUP option. Notification process will reassociate after a while. Hope this helps.

nero355 · 7 Dec 2021, 16:48

@thilov
That is not fine at all :

The last ping shows packetloss ?!
It's just ping... not traceroute!
So how do you know for sure all the traffic is going via your VPN ?!

Also : How is your PiVPN setup exactly ??
Is it Bridged with your local network or NAT behind the NAT of your local network ?

AppLee · 7 Dec 2021, 17:00

Hi and welcome @ubportsuser

For sake of keeping up with the various topic I'll lock this thread.
I suggest you create a new one and refer to this one.
It will avoid confusion for what might have been an old issue fix years ago.

Thanks for understanding.