Jolla Store App freezes when updating apps

leon · 3 April 2023 11:52

REPRODUCIBILITY: 95%
OS VERSION: Since SFOS4
HARDWARE: Xperia 10 II
UI LANGUAGE: DE
REGRESSION: yes

DESCRIPTION:

As reported by me here back in 2021:

Everytime (mostly) when i update an app the store app freezes. An overlay UI appears asking to wait or to close the app. The update is started via pulley menu from the individual app site in the store (where the changelog is listed).

When updating from the site where all installed apps are listed (also via pulley menu) then the update sometimes works.

This is issue is still omnipresent (since around first SFOS4 release).

PRECONDITIONS:

Install apps from Jolla Store and via UI.

STEPS TO REPRODUCE:

it does not matter which app is installed
wait for an update
try to update the app on the content store page of the app via pulley menu

EXPECTED RESULT:

No freezes of the store app and the update process finishes successfully

ACTUAL RESULT:

Freezes and no finished update process

MODIFICATIONS:

NONE

ADDITIONAL INFORMATION:

The context when updating is: Just one native app is running (clock app) and in the background the AOSP layer.

leon · 4 April 2023 20:18

throwaway69 · 4 April 2023 20:39

You could try rebuilding app registry from Utilities? No idea if it would help but maybe (mentions ‘problems with Store’ so hopefully)

leon · 30 April 2023 19:45

Happens practically every time when updating an app …

pherjung · 30 April 2023 20:02

Strange. I never saw this error. Did you some modifications? Like installing OpenRepos, Chum, etc.?

leon · 30 April 2023 20:30

Setup has only minimal adaption if one at all. No, chum or 3rd party repos. Plain jolla repos only. One app downloaded and installed manually from chum (as rpm) only. Android layer has 6 apps installed only via apk manually. Some config tweaks via manual patches but nothing wild (one line adaption on qml files like Clock app missing remorse timer when deleting - #8 by leon).

Or rephrased differently: If an update (SFOS) is available, I do not need to do some preparation because of some adaption. I go straight to update process and after reboot the phone is up to date. This works since a couple of years this way. Except this issue here …

pherjung · 6 May 2023 08:12

How many apps are open when this bug occurs? Can you reproduce it when there are no apps open and Android App Support is disabled?

leon · 6 May 2023 19:15

No apps open (only the store app of course), Android App Support also disabled:

The same as above happens again. The journal shows:

Mai 06 21:10:48 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:49 x2m [6529]: [D] refresh:112 - Refreshing ApplicationData
Mai 06 21:10:49 x2m lipstick[5492]: [D] onDestruction:268 - coverActionIndicators destroyed store-client.desktop
Mai 06 21:10:49 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:49 x2m PackageKit[6426]: get-repo-list transaction /1240_eadbabaa from uid 100000 finished with success after 27ms
Mai 06 21:10:49 x2m PackageKit[6426]: resolve transaction /1241_becebabe from uid 100000 finished with success after 6ms
Mai 06 21:10:50 x2m PackageKit[6426]: resolve transaction /1242_aadededa from uid 100000 finished with success after 2ms
Mai 06 21:10:50 x2m PackageKit[6426]: new install-packages transaction /1243_ccabdadb scheduled from uid 100000
Mai 06 21:10:50 x2m mce[1692]: modules/display.c: mdy_datapipe_packagekit_locked_cb(): packagekit_locked = 1
Mai 06 21:10:50 x2m packagekitd[6426]: [packagekit-zypp] Before commit: 1 downloads, 0 installs, 0 removals
Mai 06 21:10:50 x2m packagekitd[6426]: [packagekit-zypp] Byte sizes: 121376 download, 0 install, 0 remove, 0 cached
Mai 06 21:10:50 x2m packagekitd[6426]: [packagekit-zypp] Download space required 121376 bytes, available 2368847872 bytes
Mai 06 21:10:50 x2m packagekitd[6426]: [packagekit-zypp] Installation space required 121376 bytes, available 2368847872 bytes
Mai 06 21:10:50 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:51 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:52 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:53 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:54 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:55 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:56 x2m packagekitd[6426]: [packagekit-zypp] Entering download phase
Mai 06 21:10:56 x2m packagekitd[6426]: [packagekit-zypp] Download progress update: 1 of 1
Mai 06 21:10:56 x2m mce[1692]: modules/display.c: mdy_datapipe_packagekit_locked_cb(): packagekit_locked = 0
Mai 06 21:10:56 x2m PackageKit[6426]: install-packages transaction /1243_ccabdadb from uid 100000 finished with success after 6198ms
Mai 06 21:10:56 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:57 x2m kernel: i2c_geni 4a88000.i2c: i2c error :-107
Mai 06 21:10:57 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:58 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:10:59 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:11:00 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:11:01 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:11:02 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:11:03 x2m lipstick[5492]: [D] onTriggered:102 - Application "Store" with PID 6529 is not responding
Mai 06 21:11:03 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096

leon · 6 May 2023 19:22

The following is how it looks like when the retry is successful:

Mai 06 21:16:41 x2m [7213]: [D] refresh:112 - Refreshing ApplicationData
Mai 06 21:16:41 x2m lipstick[5492]: [D] onDestruction:268 - coverActionIndicators destroyed store-client.desktop
Mai 06 21:16:41 x2m kernel: binder: 2753:2753 transaction failed 29189/-22, size 32-0 line 3096
Mai 06 21:16:41 x2m PackageKit[6426]: get-repo-list transaction /1245_acaeddaa from uid 100000 finished with success after 24ms
Mai 06 21:16:41 x2m PackageKit[6426]: resolve transaction /1246_acebbccb from uid 100000 finished with success after 2ms
Mai 06 21:16:41 x2m PackageKit[6426]: resolve transaction /1247_bbbeeeae from uid 100000 finished with success after 2ms
Mai 06 21:16:41 x2m PackageKit[6426]: new install-packages transaction /1248_bdceeabd scheduled from uid 100000
Mai 06 21:16:41 x2m packagekitd[6426]: [packagekit-zypp] Before commit: 1 downloads, 0 installs, 0 removals
Mai 06 21:16:41 x2m packagekitd[6426]: [packagekit-zypp] Byte sizes: 0 download, 0 install, 0 remove, 121376 cached
Mai 06 21:16:41 x2m packagekitd[6426]: [packagekit-zypp] Download space required 0 bytes, available 2368847872 bytes
Mai 06 21:16:41 x2m packagekitd[6426]: [packagekit-zypp] Installation space required 0 bytes, available 2368847872 bytes
Mai 06 21:16:41 x2m PackageKit[6426]: install-packages transaction /1248_bdceeabd from uid 100000 finished with success after 225ms
Mai 06 21:16:42 x2m PackageKit[6426]: new update-packages transaction /1249_cdcdbbbd scheduled from uid 100000
Mai 06 21:16:42 x2m packagekitd[6426]: [packagekit-zypp] Before commit: 1 downloads, 1 installs, 0 removals
Mai 06 21:16:42 x2m packagekitd[6426]: [packagekit-zypp] Byte sizes: 0 download, 232235 install, 0 remove, 121376 cached
Mai 06 21:16:42 x2m packagekitd[6426]: [packagekit-zypp] Download space required 0 bytes, available 2368847872 bytes
Mai 06 21:16:42 x2m packagekitd[6426]: [packagekit-zypp] Installation space required 232235 bytes, available 2368847872 bytes
Mai 06 21:16:42 x2m [RPM][7979]: Transaction ID 6456a79a started
Mai 06 21:16:42 x2m [RPM][7979]: erase harbour-qrclip-1.0.10-1.aarch64: success
Mai 06 21:16:42 x2m lipstick[5492]: QInotifyFileSystemWatcherEngine::addPaths: inotify_add_watch failed: Keine Berechtigung
Mai 06 21:16:42 x2m lipstick[5492]: QInotifyFileSystemWatcherEngine::addPaths: inotify_add_watch failed: Datei oder Verzeichnis nicht gefunden
Mai 06 21:16:42 x2m lipstick[5492]: QInotifyFileSystemWatcherEngine::addPaths: inotify_add_watch failed: Keine Berechtigung
Mai 06 21:16:42 x2m [RPM][7979]: install harbour-qrclip-1.0.11-1.aarch64: success
Mai 06 21:16:42 x2m [RPM][7979]: erase harbour-qrclip-1.0.10-1.aarch64: success
Mai 06 21:16:42 x2m dbus-daemon[5326]: dbus-daemon[5326]: [session uid=100000 pid=5326] Activating via systemd: service name='org.freedesktop.Tracker3.Miner.Extract' unit='tracker-extract-3.service' requested by ':1.58' (uid=100000 pid=6133 comm="/usr/libexec/tracker-miner-fs-3" label="u:r:kernel:s0")
Mai 06 21:16:42 x2m systemd[5317]: Starting Tracker metadata extractor...
Mai 06 21:16:43 x2m [RPM][7979]: install harbour-qrclip-1.0.11-1.aarch64: success
Mai 06 21:16:43 x2m [RPM][7979]: Transaction ID 6456a79a finished: 0
Mai 06 21:16:43 x2m dbus-daemon[5326]: dbus-daemon[5326]: [session uid=100000 pid=5326] Successfully activated service 'org.freedesktop.Tracker3.Miner.Extract'
Mai 06 21:16:43 x2m systemd[5317]: Started Tracker metadata extractor.
Mai 06 21:16:43 x2m packagekitd[6426]: [packagekit-zypp] Entering install phase
Mai 06 21:16:43 x2m packagekitd[6426]: [packagekit-zypp] Install progress update: 1 of 1
Mai 06 21:16:43 x2m PackageKit[6426]: in /1249_cdcdbbbd for update-packages package harbour-qrclip;1.0.11-1;aarch64;store was installing for uid 100000
Mai 06 21:16:43 x2m PackageKit[6426]: update-packages transaction /1249_cdcdbbbd from uid 100000 finished with success after 1235ms
Mai 06 21:16:43 x2m PackageKit[6426]: get-packages transaction /1250_bcaecdbb from uid 100000 finished with success after 129ms
Mai 06 21:16:45 x2m lipstick[5492]: [D] onCompleted:264 - coverActionIndicators created store-client.desktop