Not receiving push notifications (3a)

azraelpwnz · 20 May 2021, 14:55

Hello all,

I recently upgraded from OTA-16 to latest, and appear to not be getting push notifications on my 3a.

I should be getting pushes from TELEports (receiving them on my other android phone), but not on my 3a running UT. I was getting them before the upgrade though.

I tried reinstalling all apps to no avail. Also tried the pushclient app on the OpenStore, and I do not receive those either.

I would like to avoid a full reinstall if possible.

Looking at logs for ubuntu-push-client, I see these messages:

Unsolicited response received on idle HTTP channel starting with "H"; err-<nil>

ERROR while GETting http://start.ubuntu.com/connectivity-check.html: Get https://start.ubuntu.com/connectivity-check.html:n et/http:request canceled while waiting for connection

Both messages appear while my android phone receives its push notification, leading me to believe its related...but I could be wrong. It is only an assumption.

I am able to open http://start.ubuntu.com/connectivity-check.html in Morph with no issues, and I am on a stable wifi connection.

Any ideas on how to troubleshoot?

Note...looking at logs for teleports... I see:
qml: Got push token:
Then
Registering device for push notifications

Followed by:
UNHANDLED: updateChatPinnedMessage

Not sure if the unhandled error is related to push notifications or not.

flohack · 20 May 2021, 15:04

@azraelpwnz said in Not receiving push notifications (3a):

om/connectivity-check.html:

Do you ever see a connectivity-check: passed message in the push client log? It does not seem like you are online at this moment...

azraelpwnz · 20 May 2021, 15:06

@flohack I do not see that message.

Pings from terminal to that domain pass with 0% packet loss, and I am able to GET that page in Morph browser without any issues.

azraelpwnz · 20 May 2021, 15:12

@flohack
Here are my logs for reference. I tried posting links but they were being marked as spam, so my apologies for the dump:

pushclient (app):

From file /home/phablet/.cache/upstart/application-click-pushclient.christianpauly_pushclient_1.0.log:
Loading module: 'libubuntu_application_api_touch_mirclient.so.3.0.0'
Creating a QMirClientScreen now
error calling result "An AppArmor policy prevents this sender from sending this message to this recipient; type=\"method_call\", sender=\":1.124\" (uid=32011 pid=9694 comm=\"/usr/lib/qt5/bin/qmlscene qml/Main.qml \") interface=\"com.canonical.libertine.Service.Operations\" member=\"list\" error name=\"(unset)\" requested_reply=\"0\" destination=\"com.canonical.libertine.Service\" (uid=32011 pid=4658 comm=\"/usr/bin/python3 /usr/bin/libertined \")"
error calling result "An AppArmor policy prevents this sender from sending this message to this recipient; type=\"method_call\", sender=\":1.124\" (uid=32011 pid=9694 comm=\"/usr/lib/qt5/bin/qmlscene qml/Main.qml \") interface=\"com.canonical.libertine.Service.OperationsMonitor\" member=\"running\" error name=\"(unset)\" requested_reply=\"0\" destination=\"com.canonical.libertine.Service\" (uid=32011 pid=4658 comm=\"/usr/bin/python3 /usr/bin/libertined \")"
lastError - no arguments?
error calling result "An AppArmor policy prevents this sender from sending this message to this recipient; type=\"method_call\", sender=\":1.124\" (uid=32011 pid=9694 comm=\"/usr/lib/qt5/bin/qmlscene qml/Main.qml \") interface=\"com.canonical.libertine.Service.OperationsMonitor\" member=\"last_error\" error name=\"(unset)\" requested_reply=\"0\" destination=\"com.canonical.libertine.Service\" (uid=32011 pid=4658 comm=\"/usr/bin/python3 /usr/bin/libertined \")"
lastError - no arguments?
error calling result "An AppArmor policy prevents this sender from sending this message to this recipient; type=\"method_call\", sender=\":1.124\" (uid=32011 pid=9694 comm=\"/usr/lib/qt5/bin/qmlscene qml/Main.qml \") interface=\"com.canonical.libertine.Service.OperationsMonitor\" member=\"result\" error name=\"(unset)\" requested_reply=\"0\" destination=\"com.canonical.libertine.Service\" (uid=32011 pid=4658 comm=\"/usr/bin/python3 /usr/bin/libertined \")"
lastError - no arguments?
qml: 🤖 ============PUSHCLIENT STARTED============
QObject::startTimer: Timers cannot be started from another thread
qml: 👍 Token changed to: cHVzaGNsaWVudC5jaHJpc3RpYW5wYXVseV9wdXNoY2xpZW50OjpiRnF3SDZNR3dkeExkcVZQMlZ3T2N1WDdaZ2gzRE5ZVjZ5TEEwUT09
[PERFORMANCE]: Last frame took 51 ms to render.
[PERFORMANCE]: Last frame took 43 ms to render.
[PERFORMANCE]: Last frame took 40 ms to render.
QObject::startTimer: Timers cannot be started from another thread
qml: 💬 Sending push notification ...
propsReply "An AppArmor policy prevents this sender from sending this message to this recipient; type=\"method_call\", sender=\":1.294\" (uid=32011 pid=9694 comm=\"/usr/lib/qt5/bin/qmlscene qml/Main.qml \") interface=\"org.freedesktop.DBus.Properties\" member=\"GetAll\" error name=\"(unset)\" requested_reply=\"0\" destination=\"org.freedesktop.NetworkManager\" (uid=0 pid=1384 comm=\"NetworkManager \")"
nmReply "An AppArmor policy prevents this sender from sending this message to this recipient; type=\"method_call\", sender=\":1.294\" (uid=32011 pid=9694 comm=\"/usr/lib/qt5/bin/qmlscene qml/Main.qml \") interface=\"org.freedesktop.NetworkManager\" member=\"GetDevices\" error name=\"(unset)\" requested_reply=\"0\" destination=\"org.freedesktop.NetworkManager\" (uid=0 pid=1384 comm=\"NetworkManager \")"
"Object path cannot be empty"
QObject::startTimer: Timers cannot be started from another thread
qml: ✍ Answer from push service: {"ok":true}
qml: 💬 Sending push notification ...
qml: 💬 Sending push notification ...
qml: ✍ Answer from push service: {"ok":true}
qml: ✍ Answer from push service: {"ok":true}
QObject::killTimer: Timers cannot be stopped from another thread
QObject::startTimer: Timers cannot be started from another thread
qml: 💬 Sending push notification ...
qml: ✍ Answer from push service: {"ok":true}
QObject::killTimer: Timers cannot be stopped from another thread
QObject::startTimer: Timers cannot be started from another thread

ubuntu-push-client:

From file /home/phablet/.cache/upstart/ubuntu-push-client.log:
2021/05/20 10:32:14 Unsolicited response received on idle HTTP channel starting with "H"; err=<nil>
2021/05/20 10:37:25.029321 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
2021/05/20 10:42:25 Unsolicited response received on idle HTTP channel starting with "H"; err=<nil>
2021/05/20 10:47:35.033831 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
2021/05/20 10:52:35 Unsolicited response received on idle HTTP channel starting with "H"; err=<nil>
2021/05/20 10:57:45.035909 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
2021/05/20 11:02:44 Unsolicited response received on idle HTTP channel starting with "H"; err=<nil>
2021/05/20 11:07:55.040633 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

flohack · 20 May 2021, 16:20

@azraelpwnz Well you can clearly see that the push client has some issues there, unfortunately idk which. I will ask a bit around whats going on here, maybe we find a clue. Please open a ticket in https://github.com/ubports/ubuntu-push with the last log detail that you uploaded.

azraelpwnz · 20 May 2021, 16:24

@flohack will do, thanks Florian!

dobey · 20 May 2021, 16:52

@azraelpwnz @Flohack That should be https://gitlab.com/ubports/core/lomiri-push-service now instead, BTW.

azraelpwnz · 20 May 2021, 16:55

@dobey @Flohack issue created:
https://gitlab.com/ubports/core/lomiri-push-service/-/issues/33

Let me know if I can be of any help. Trying to get started with contributions, but am just starting out and have only just begun looking through repos.

StandUpMobile · 21 May 2021, 11:16

I have the same issue sometimes. I have noticed a pattern that it happens when I am on 4G ... and NOT when I am on 3G ...** even if I have good working connectivity via WIFI and/or 4g cell data **. Very strange. Additionally, (but perhaps unrelated) my SMS only comes in on 3G. I am pretty sure there were some changes in OTA17 related to this ... because my 4G was hard to connect in the past and now it connects regularly. (In the past, it would connect, but then some workaround script would tear everything down to try to start over ... the cycle repeating until eventually it settled showing a good connection, but in reality, it was still missing the proper default route entry in the route table to have anything work. Hope this helps!

azraelpwnz · 21 May 2021, 12:59

@standupmobile interesting that you point all of that out.

On OTA16, I forced 3G because I was having issues with 4G...and at that time I had no major issues with consistent SMS. Now I am on solid 4G running OTA17...and can only send....not receive SMS.

As for push notifications...it is odd that you found you had better luck with push notifications on 3G, but not on 4G or even wifi. If I wasnt using my pixel 3a as my DD, and didnt want to risk mucking up my APN and losing 4G...I would totally force 3G to see if push notifications work.

I have been looking through the repos as of late...but have had no major findings that could cause the connectivity check to fail on 4G & wifi, but not 3G.

I did notice the move from TLSv1 to TLS1_2 in this commit, but the connectivity check endpoint is http, not https...so wouldnt think it would matter.

https://gitlab.com/ubports/core/lomiri-push-service/-/commit/7d7f8c2ef4283526587eb496c9964185f46e2f7c

dobey · 21 May 2021, 15:10

@azraelpwnz said in Not receiving push notifications (3a):

I did notice the move from TLSv1 to TLS1_2 in this commit, but the connectivity check endpoint is http, not https...so wouldnt think it would matter.

This is in the main branch, which is not what is currently shipped on devices. The ubports/xenial branch is what is currently being used. And no, the changes in main wouldn't affect the connectivity check.

I wonder if other Halium 9 based devices are also having this issue when on 4G. Perhaps changes to get data working better there, are causing some memory corruption which is causing this failure. @fredldotme might have some insight here?

azraelpwnz · 21 May 2021, 15:21

@dobey I figured it wouldn't have a negative effect, but thanks for the clarification on which branch is currently being used!

StandUpMobile · 21 May 2021, 17:27

@azraelpwnz

Just to be 100% clear:

3G - Good SMS, good notifications
3G Wifi - Good SMS, good notifications
4G - Inbound SMS not working1, sketchy notifications
4G Wifi - Inbound SMS not working1, sketchy notifications

**1 Didn't thoroughly test outbound SMS ...

I am on RC (should be close to OTA17).

I am using as a daily as well (with a few inconveniences)... have flipped back and forth quite a bit recently between 3G and 4G - pretty consistent: you should have no issues getting back to 4G ... would be interesting to confirm my observation...

azraelpwnz · 21 May 2021, 18:42

@standupmobile Thank you!!

Set to 3G with wifi on, rebooted...and I now receive notifications!

I then asked someone to send me an SMS...AND YES! I can now send and receive SMS!

Would like to have LTE...but I am fine with 3G and wifi 100%. Love it! Thanks for finding that. Now I can stick with UT for good! You will see me on the OpenStore soon

StandUpMobile · 21 May 2021, 20:48

@azraelpwnz

Glad I could help! ... Very weird bug. Now if only we could find a workaround for MMS!