Missing Push-Notifications via VPN
-
I have a weird problem.
While my OPO (16.04 RC) is connected via VPN to my PiVPN at home, I get no push notifications until I leave the VPN or open the app (that is too late
).
Having the apps open in the background (UTTT no background suspending enabled) doesn't work either.
Tested with: Dekko 2, Teleports and Telegram.
Astonishingly Signal works while open in the background.
VPN works fine on my Ubuntu 18.04 PC at work and my wife's linageos Phone.
Has anybody the same problem or a solution? -
@thilov Hi. I can only say that when i use a VPN installed with VPN editor on any UT phone, i get DEKKO 2 notifications.
-
@domubpkm Are you also using your own VPN Server?
-
@thilov NO it's not my own VPN server. I didn't well read your post, SORRY. Is it a wrong idea : maybe could you do something with VPN editor and your own VPN ? I don't know.
-
-
If I'm connected with wlan (same network) I get notifications.
-
Can you route to
push.ubports.comwhile on the VPN? Are any outgoing ports blocked for addresses on the VPN? -
phablet@ubuntu-phablet:~$ ping push.ubports.com PING push.ubports.com (188.166.175.79) 56(84) bytes of data. 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=1 ttl=49 time=25.6 ms 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=2 ttl=49 time=44.2 ms 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=3 ttl=49 time=55.0 ms 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=4 ttl=49 time=40.3 ms 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=5 ttl=49 time=43.2 ms 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=6 ttl=49 time=49.9 ms ^C --- push.ubports.com ping statistics --- 6 packets transmitted, 6 received, 0% packet loss, time 5009ms rtt min/avg/max/mdev = 25.679/43.086/55.058/9.150 ms phablet@ubuntu-phablet:~$ ping push.ubports.com PING push.ubports.com (188.166.175.79) 56(84) bytes of data. 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=1 ttl=48 time=86.2 ms 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=2 ttl=48 time=99.6 ms 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=3 ttl=48 time=418 ms 64 bytes from 0.push.ubports.com (188.166.175.79): icmp_seq=4 ttl=48 time=138 ms ^C --- push.ubports.com ping statistics --- 5 packets transmitted, 4 received, 20% packet loss, time 4002ms rtt min/avg/max/mdev = 86.287/185.727/418.352/135.674 ms phablet@ubuntu-phablet:~$Ping looks fine.
I have a standard PiVPN setup without any modifications. I didn't block ports and even don't know how to do this.
-
Well, I also use Pihole. I will add push.ubports.com to the whitelist and test it tomorrow.
-
Adding push.ubports.com to the Pihole's whitelist doesn't help.
I just recognised, that I don't get any mails (only tested with dekko 2) after disconnecting the VPN even if I open the app.
Enable/disable fligtmode just helps for a second. I have to reboot the phone to get back to the expected bahavior. -
There should be I think
~/.cache/upstart/ubuntu-push-client.logcan you check it please? -
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. -
@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.
-
@dobey I fixed my copy and paste mistake.
-
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 -
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.comserver.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.
-
I tested ProtonVPN with similar results.
Is a hardware issue possible? Sadly I doesn't have a second UT device for more testing.
-
@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.
-
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. -
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
