[10 III] [App Support] App Support fails to start

Bug Reports
#1

REPRODUCIBILITY:
OS VERSION: 4.5.0.24
HARDWARE: Sony Xperia 10 III
UI LANGUAGE:English(UK)
REGRESSION: I don’t know

DESCRIPTION:

Appsupport does not work on my Sony Xperia 10 III.

I thought it might be due to some setting, so I have completely reflashed Sailfishos but appsupport still doesn’t work.

PRECONDITIONS:

You need a paid Sailfish X license for Appsupport.

STEPS TO REPRODUCE:

  1. Install Appsupport from the Jolla Store
  2. Reboot the device
  3. Go to Settings->Appsupport
  4. Press “Start”

EXPECTED RESULT:

The start button should start App support.

ACTUAL RESULT:

When I press the start button I immediately get “unable to start”.

When I install F-Droid from the Jolla Store, no shortcut appears in the app drawer(even after a reboot).

MODIFICATIONS:

The installation is fresh and unmodified. I just checked for updates in the settings.

ADDITIONAL INFORMATION:

I just bought my Sailfish X license. Maybe it takes some time until all functions are activated?

My installed Apps(Jolla Store)
My installed Apps(Jolla Store)1080×2520 617 KB

App Drawer without F-Droid
App Drawer without F-Droid1080×2520 254 KB

App Support Page
App Support Page1080×2520 768 KB

Device Info
Device Info2160×5040 1.71 MB

1 Like
#2

Seems like something’s gone very wrong in there. Any chance you could collect a journal log? Do you know how to do that?

1 Like
#3

I’m still a beginner when it comes to debugging.

Here is the output of Journalctl when I try to start App Support:

39:23 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:24 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:25 Xperia10III kernel: rpmh_rsc_send_data: 19 callbacks suppressed
Feb 26 13:39:25 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:25 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:25 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:25 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:25 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:25 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:25 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:26 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:27 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:28 Xperia10III invoker[6410]: WARNING: An inactive plugin is misbehaving - tried to show a window!
Feb 26 13:39:28 Xperia10III invoker[6410]: WARNING: requestActivate() called for  QQuickView(0x308bc880)  which has Qt:
Feb 26 13:39:28 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:29 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:29 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:30 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:30 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:31 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 13:39:31 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 13:39:31 Xperia10III systemd[1]: Starting Alien Dalvik prepare...
Feb 26 13:39:31 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 13:39:31 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 13:39:31 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 13:39:31 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 13:39:31 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 13:39:31 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:31 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:31 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:31 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:31 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:31 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:31 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:31 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'ANDROID_DATA_ROOT' from environment variables
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'ANDROID_DATA_ROOT' from environment variables
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variabl
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'WAYLAND_SOCKET' from environment variables fo
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variabl
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'BINDER_DEV' from environment variables for te
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'HWBINDER_DEV' from environment variables for
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'VNDBINDER_DEV' from environment variables for
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'persist.multisim.config' from properties for
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'ro.hybris.battery.capacity' from properties f
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'ro.vendor.product.manufacturer' from properti
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'ro.vendor.product.model' from properties for
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'ro.vendor.product.brand' from properties for
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'ro.vendor.product.name' from properties for t
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'ro.vendor.product.device' from properties for
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'ANDROID_DATA_ROOT' from environment variables
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variabl
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'WAYLAND_SOCKET' from environment variables fo
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variabl
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'WIDTH' from environment variables for templat
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'HEIGHT' from environment variables for templa
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'DEPTH' from environment variables for templat
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'DPI_X' from environment variables for templat
Feb 26 13:39:31 Xperia10III prepare-aliendalvik.sh[3056]: Failed to read 'DPI_Y' from environment variables for templat
Feb 26 13:39:31 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:32 Xperia10III systemd[1]: Started Alien Dalvik prepare.
Feb 26 13:39:32 Xperia10III systemd[1]: Starting Alien Dalvik...
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III start-aliendalvik.sh[3190]: lxc-start: aliendalvik: utils.c: mkdir_p: 234 Permission denied
Feb 26 13:39:32 Xperia10III start-aliendalvik.sh[3190]: Failed to create lock for aliendalvik
Feb 26 13:39:32 Xperia10III start-aliendalvik.sh[3190]: lxc-start: aliendalvik: tools/lxc_start.c: main: 208 Failed to
Feb 26 13:39:32 Xperia10III start-aliendalvik.sh[3190]: cat: can't open '/home/.android/data/alien_boot_completed': No
Feb 26 13:39:32 Xperia10III stop-aliendalvik.sh[3275]: lxc-attach: aliendalvik: utils.c: mkdir_p: 234 Permission denied
Feb 26 13:39:32 Xperia10III stop-aliendalvik.sh[3275]: Failed to create lock for aliendalvik
Feb 26 13:39:32 Xperia10III prepare-aliendalvik.sh[3056]: Control code: stop
Feb 26 13:39:32 Xperia10III stop-aliendalvik.sh[3275]: Failed to create lock for aliendalvik
Feb 26 13:39:32 Xperia10III stop-aliendalvik.sh[3275]: Failure to retrieve information on /tmp/appsupport:aliendalvik
Feb 26 13:39:32 Xperia10III systemd[1]: aliendalvik.service: Failed with result 'protocol'.
Feb 26 13:39:32 Xperia10III systemd[1]: Stopped Alien Dalvik.
Feb 26 13:39:32 Xperia10III systemd[1]: Stopping Alien Dalvik prepare...
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[5853]: selinux: Unknown class service
Feb 26 13:39:32 Xperia10III systemd[1]: Stopped Alien Dalvik prepare.
Feb 26 13:39:32 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:32 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:33 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:34 Xperia10III invoker[6410]: WARNING: An inactive plugin is misbehaving - tried to show a window!
Feb 26 13:39:34 Xperia10III invoker[6410]: WARNING: requestActivate() called for  QQuickView(0x308bc880)  which has Qt:
Feb 26 13:39:34 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:35 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 13:39:35 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:36 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:37 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
Feb 26 13:39:38 Xperia10III kernel: binder: 3339:3339 transaction failed 29189/-22, size 32-0 line 3103
#4

Could be that you rebooted while it was setting itself up and you’ve been left with half a directory tree that it can’t recover from. Try removing the /home/.android and /home/defaultuser/android_storage directories, then reboot and see if it comes up then.

2 Likes
#5

Thank you very much for your help. I have taken the following steps:

  1. I deleted the directories.
  2. I uninstalled App Support and F-Droid.
  3. I restarted the device and reinstalled Appsupport.
  4. I tried to press the start button.

It still does not work.

Feb 26 15:11:45 Xperia10III [RPM][7274]: install apkd8-android-settings-11.0.0.15-1.18.1.jolla.aarch64: success
Feb 26 15:11:45 Xperia10III [RPM][7274]: install apkd8-android-settings-11.0.0.15-1.18.1.jolla.aarch64: success
Feb 26 15:11:45 Xperia10III [RPM][7274]: Transaction ID 65dc9c21 finished: 0
Feb 26 15:11:45 Xperia10III packagekitd[7077]: [packagekit-zypp] Install progress update: 5 of 6
Feb 26 15:11:46 Xperia10III [RPM][7275]: Transaction ID 65dc9c22 started
Feb 26 15:11:46 Xperia10III [RPM][7275]: install aliendalvik-1.5.0-1.4.2.jolla.aarch64: success
Feb 26 15:11:46 Xperia10III [RPM][7275]: install aliendalvik-1.5.0-1.4.2.jolla.aarch64: success
Feb 26 15:11:46 Xperia10III [RPM][7275]: Transaction ID 65dc9c22 finished: 0
Feb 26 15:11:46 Xperia10III packagekitd[7077]: [packagekit-zypp] Install progress update: 6 of 6
Feb 26 15:11:46 Xperia10III PackageKit[7077]: in /670_cdedaead for install-packages package binder-utils;1.0.4-1.3.1.jolla;aarch64;adaptation-common was
Feb 26 15:11:46 Xperia10III PackageKit[7077]: in /670_cdedaead for install-packages package alienaudioservice-plugin-pulseaudio;1.11.0-1.5.1.jolla;aarch6
Feb 26 15:11:46 Xperia10III PackageKit[7077]: in /670_cdedaead for install-packages package alienaudioservice-plugin-sailfish;1.4.0-1.6.1.jolla;aarch64;a
Feb 26 15:11:46 Xperia10III PackageKit[7077]: in /670_cdedaead for install-packages package apkd-plugin-geo-qtpositioning;1.0.0-1.2.1.jolla;aarch64;alien
Feb 26 15:11:46 Xperia10III PackageKit[7077]: in /670_cdedaead for install-packages package apkd8-android-settings;11.0.0.15-1.18.1.jolla;aarch64;alienda
Feb 26 15:11:46 Xperia10III PackageKit[7077]: in /670_cdedaead for install-packages package aliendalvik;1.5.0-1.4.2.jolla;aarch64;aliendalvik was install
Feb 26 15:11:46 Xperia10III PackageKit[7077]: install-packages transaction /670_cdedaead from uid 100000 finished with success after 1299ms
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /671_dceeccec from uid 100000 finished with success after 32ms
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /672_dedebccd from uid 100000 finished with success after 29ms
Feb 26 15:11:46 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x42e08
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /673_eadddcac from uid 100000 finished with success after 27ms
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /674_caaaecab from uid 100000 finished with success after 23ms
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /675_cbecbced from uid 100000 finished with success after 22ms
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /676_bcbbcebd from uid 100000 finished with success after 22ms
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /677_ccdeecbd from uid 100000 finished with success after 21ms
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /678_baeccace from uid 100000 finished with success after 21ms
Feb 26 15:11:46 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /679_cacbcbde from uid 100000 finished with success after 20ms
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /680_aebaaaca from uid 100000 finished with success after 20ms
Feb 26 15:11:46 Xperia10III PackageKit[7077]: repo-enable transaction /681_dcbdbbda from uid 100000 finished with success after 20ms
Feb 26 15:11:47 Xperia10III PackageKit[7077]: repo-enable transaction /682_ecebcbdb from uid 100000 finished with success after 20ms
Feb 26 15:11:47 Xperia10III PackageKit[7077]: get-packages transaction /683_abbcdbea from uid 100000 finished with success after 91ms
Feb 26 15:11:47 Xperia10III kernel: [schedu][0x5d88e514][14:11:47.827610] wlan: [4978:I:OSIF] scan start scan id 40967
Feb 26 15:11:48 Xperia10III lipstick[5582]: [D] onCompleted:264 - coverActionIndicators created store-client.desktop
Feb 26 15:11:49 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:11:49 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:11:50 Xperia10III lipstick[5582]: [W] unknown:0 - Low disk space on path: "/" state: DiskSpaceWatcher::State(Normal) cause: DiskSpaceWatcher::C
Feb 26 15:11:50 Xperia10III lipstick[5582]: [W] unknown:0 - Low disk space on path: "/run" state: DiskSpaceWatcher::State(Normal) cause: DiskSpaceWatcher
Feb 26 15:11:50 Xperia10III lipstick[5582]: [W] unknown:0 - Low disk space on path: "/tmp" state: DiskSpaceWatcher::State(Normal) cause: DiskSpaceWatcher
Feb 26 15:11:50 Xperia10III lipstick[5582]: [W] unknown:0 - Low disk space on path: "/home" state: DiskSpaceWatcher::State(Normal) cause: DiskSpaceWatche
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_startup: capture enter
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_set_fmt: fmt=0x4001
Feb 26 15:11:50 Xperia10III kernel: msm_mi2s_snd_startup: set sysclk failed, err:-524
Feb 26 15:11:50 Xperia10III kernel: __afe_port_start: port id: 0x1001
Feb 26 15:11:50 Xperia10III kernel: afe_find_cal_topo_id_by_port: port id: 0x1001, dev_acdb_id: -22
Feb 26 15:11:50 Xperia10III kernel: afe_find_cal_topo_id_by_port: top_id:1000ff02 acdb_id:102 afe_port:0x1001
Feb 26 15:11:50 Xperia10III kernel: afe_get_cal_topology_id: port_id = 0x1001 acdb_id = 102 topology_id = 0x1000ff02 cal_type_index=8 ret=0
Feb 26 15:11:50 Xperia10III kernel: afe_send_port_topology_id: AFE set topology id 0x1000ff02  enable for port 0x1001 ret 0
Feb 26 15:11:50 Xperia10III kernel: send_afe_cal_type: cal_index is 1
Feb 26 15:11:50 Xperia10III kernel: send_afe_cal_type: dev_acdb_id[41] is -22
Feb 26 15:11:50 Xperia10III kernel: send_afe_cal_type: Sending cal_index cal 1
Feb 26 15:11:50 Xperia10III kernel: afe_send_hw_delay: port_id 0x1001 rate 48000 delay_usec 578 status 0
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_mute: mute state=0
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_startup: playback enter
Feb 26 15:11:50 Xperia10III kernel: __afe_port_start: port id: 0x1000
Feb 26 15:11:50 Xperia10III kernel: afe_find_cal_topo_id_by_port: port id: 0x1000, dev_acdb_id: 124
Feb 26 15:11:50 Xperia10III kernel: afe_find_cal_topo_id_by_port: top_id:1001025f acdb_id:124 afe_port_id:0x1000
Feb 26 15:11:50 Xperia10III kernel: afe_get_cal_topology_id: port_id = 0x1000 acdb_id = 124 topology_id = 0x1001025f cal_type_index=8 ret=0
Feb 26 15:11:50 Xperia10III kernel: afe_send_port_topology_id: AFE set topology id 0x1001025f  enable for port 0x1000 ret 0
Feb 26 15:11:50 Xperia10III kernel: send_afe_cal_type: cal_index is 0
Feb 26 15:11:50 Xperia10III kernel: send_afe_cal_type: dev_acdb_id[40] is 124
Feb 26 15:11:50 Xperia10III kernel: afe_find_cal: cal_index 0 port_id 0x1000 port_index 40
Feb 26 15:11:50 Xperia10III kernel: afe_find_cal: acdb_id 124 dev_acdb_id 124 sample_rate 48000 afe_sample_rates 48000
Feb 26 15:11:50 Xperia10III kernel: afe_find_cal: cal block is a match, size is 868
Feb 26 15:11:50 Xperia10III kernel: send_afe_cal_type: Sending cal_index cal 0
Feb 26 15:11:50 Xperia10III kernel: afe_send_hw_delay: port_id 0x1000 rate 48000 delay_usec 474 status 0
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_mute: mute state=0
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_smartpa_cfg: flag = 1, power status = 0
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_clear_sysint: get_sysint=0x4395
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_clear_sysint: get_sysint=0x0000
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_monitor_start: enter
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_monitor_get_data: vol: ipeak = 0x8, gain = 0x0, vmax = 0x0
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_monitor_get_data: temp: ipeak = 0x8, gain = 0x0, vmax = 0x0
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_monitor_set_ipeak: ipeak = 0x8, no change
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_monitor_set_gain: set db = 0.0 dB, no change
Feb 26 15:11:50 Xperia10III kernel: [aw882xx_smartpa]aw882xx_monitor_set_vmax: set vmax = 0x0
Feb 26 15:11:52 Xperia10III systemd-journald[667]: Suppressed 52 messages from user@100000.service
Feb 26 15:11:52 Xperia10III dbus-daemon[5453]: dbus-daemon[5453]: [session uid=100000 pid=5453] Activating via systemd: service name='org.freedesktop.Tra
Feb 26 15:11:52 Xperia10III systemd[5417]: Starting Tracker metadata extractor...
Feb 26 15:11:52 Xperia10III dbus-daemon[5453]: dbus-daemon[5453]: [session uid=100000 pid=5453] Successfully activated service 'org.freedesktop.Tracker3.
Feb 26 15:11:52 Xperia10III systemd[5417]: Started Tracker metadata extractor.
Feb 26 15:11:52 Xperia10III kernel: [aw882xx_smartpa]aw882xx_mute: mute state=1
Feb 26 15:11:52 Xperia10III kernel: [aw882xx_smartpa]aw882xx_smartpa_cfg: flag = 0, power status = 2
Feb 26 15:11:52 Xperia10III kernel: [aw882xx_smartpa]aw882xx_monitor_stop: enter
Feb 26 15:11:52 Xperia10III kernel: [aw882xx_smartpa]aw882xx_stop: get_sysint=0x0000
Feb 26 15:11:52 Xperia10III kernel: afe_close: port_id = 0x1000
Feb 26 15:11:52 Xperia10III kernel: [aw882xx_smartpa]aw882xx_mute: mute state=1
Feb 26 15:11:52 Xperia10III kernel: afe_close: port_id = 0x1001
Feb 26 15:11:53 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:11:55 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:11:56 Xperia10III kernel: [schedu][0x67d3bc10][14:11:56.821221] wlan: [4978:I:OSIF] vdev 0, scan id 40968 type COMPLETED(1) reason COMPLETED(1)
Feb 26 15:11:56 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:11:57 Xperia10III dbus-daemon[5453]: dbus-daemon[5453]: [session uid=100000 pid=5453] Activating service name='com.jolla.settings' requested by
Feb 26 15:11:57 Xperia10III estart[7075]: [W] unknown:0 - org.nemomobile.dbus import is deprecated. Suggest migrating to Nemo.DBus
Feb 26 15:11:57 Xperia10III dbus-daemon[5453]: dbus-daemon[5453]: [session uid=100000 pid=5453] Successfully activated service 'com.jolla.settings'
Feb 26 15:11:58 Xperia10III lipstick[5582]: [W] unknown:0 - WindowPixmapItem does not have a source texture, cover will be dropped..
Feb 26 15:11:58 Xperia10III dbus-daemon[5453]: QInotifyFileSystemWatcherEngine::addPaths: inotify_add_watch failed: No such file or directory
Feb 26 15:11:58 Xperia10III dbus-daemon[5453]: QInotifyFileSystemWatcherEngine::addPaths: inotify_add_watch failed: No such file or directory
Feb 26 15:11:58 Xperia10III booster-silica-qt5[7293]: [D] unknown:0 - Using Wayland-EGL
Feb 26 15:12:02 Xperia10III systemd[1]: selinux: Unknown permission status for class system
Feb 26 15:12:02 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:02 Xperia10III systemd[1]: selinux: Unknown permission status for class system
Feb 26 15:12:02 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:02 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:02 Xperia10III kernel: [schedu][0x6e1529bd][14:12:02.287444] wlan: [4978:I:OSIF] scan start scan id 40969
Feb 26 15:12:02 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:02 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:03 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:04 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:04 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:04 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:04 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:05 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: rpmh_rsc_send_data: 33 callbacks suppressed
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III kernel: qcom_rpmh DRV:apps_rsc TCS Busy, retrying RPMH message send: addr=0x30010
Feb 26 15:12:10 Xperia10III booster-qt5[5454]: *** signal=17 pid=5454
Feb 26 15:12:12 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[1]: Starting Alien Dalvik prepare...
Feb 26 15:12:12 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_RO
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_RO
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKE
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'WAYLAND_SOCKET' from environment variables for template 'env:WAYLAND_SOCKET'
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKE
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'BINDER_DEV' from environment variables for template 'env:BINDER_DEV'
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'HWBINDER_DEV' from environment variables for template 'env:HWBINDER_DEV'
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'VNDBINDER_DEV' from environment variables for template 'env:VNDBINDER_DEV'
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'persist.multisim.config' from properties for template 'prop:persist.multisim.co
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ro.hybris.battery.capacity' from properties for template 'prop:ro.hybris.batter
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ro.vendor.product.manufacturer' from properties for template 'prop:ro.vendor.pr
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ro.vendor.product.model' from properties for template 'prop:ro.vendor.product.m
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ro.vendor.product.brand' from properties for template 'prop:ro.vendor.product.b
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ro.vendor.product.name' from properties for template 'prop:ro.vendor.product.na
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ro.vendor.product.device' from properties for template 'prop:ro.vendor.product.
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_RO
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_RO
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKE
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'WAYLAND_SOCKET' from environment variables for template 'env:WAYLAND_SOCKET'
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKE
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'WIDTH' from environment variables for template 'env:WIDTH'
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'HEIGHT' from environment variables for template 'env:HEIGHT'
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'DEPTH' from environment variables for template 'env:DEPTH'
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'DPI_X' from environment variables for template 'env:DPI_X'
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Failed to read 'DPI_Y' from environment variables for template 'env:DPI_Y'
Feb 26 15:12:12 Xperia10III systemd[1]: Started Alien Dalvik prepare.
Feb 26 15:12:12 Xperia10III systemd[1]: Starting Alien Dalvik...
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III start-aliendalvik.sh[7798]: lxc-start: aliendalvik: utils.c: mkdir_p: 234 Permission denied - Failed to create directory "/home/appsupport-root/"
Feb 26 15:12:12 Xperia10III start-aliendalvik.sh[7798]: Failed to create lock for aliendalvik
Feb 26 15:12:12 Xperia10III start-aliendalvik.sh[7798]: lxc-start: aliendalvik: tools/lxc_start.c: main: 208 Failed to create lxc_container
Feb 26 15:12:12 Xperia10III start-aliendalvik.sh[7798]: cat: can't open '/home/.android/data/alien_boot_completed': No such file or directory
Feb 26 15:12:12 Xperia10III stop-aliendalvik.sh[7839]: lxc-attach: aliendalvik: utils.c: mkdir_p: 234 Permission denied - Failed to create directory "/home/appsupport-root/"
Feb 26 15:12:12 Xperia10III stop-aliendalvik.sh[7839]: Failed to create lock for aliendalvik
Feb 26 15:12:12 Xperia10III prepare-aliendalvik.sh[7654]: Control code: stop
Feb 26 15:12:12 Xperia10III stop-aliendalvik.sh[7839]: Failed to create lock for aliendalvik
Feb 26 15:12:12 Xperia10III stop-aliendalvik.sh[7839]: Failure to retrieve information on /tmp/appsupport:aliendalvik
Feb 26 15:12:12 Xperia10III systemd[1]: aliendalvik.service: Failed with result 'protocol'.
Feb 26 15:12:12 Xperia10III systemd[1]: Stopped Alien Dalvik.
Feb 26 15:12:12 Xperia10III systemd[1]: Stopping Alien Dalvik prepare...
Feb 26 15:12:12 Xperia10III systemd[1]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:12 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:13 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:13 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:13 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:13 Xperia10III systemd[5417]: selinux: Unknown class service
Feb 26 15:12:13 Xperia10III systemd[1]: Stopped Alien Dalvik prepare.
#6

Hello,
Have you SD card inserted? Maybe AppSupport was installed on the SD card?

take a look: Android environment programs cannot create any files on external storage (SD Card) - #11 by vige

#7

That’s not a thing.
The thread you linked seems to have nothing to do with starting.

1 Like
#8

I have similiar issue on X10II. I’ve been trying to bring AppSupport back to work since it stopped working in November .
lxc-start: aliendalvik: utils.c: lxc_can_use_pidfd: 1772 Kernel does not support pidfds
lxc-start: aliendalvik: utils.c: safe_mount: 1198 Device or resource busy - Failed to mount “proc” onto “/usr/lib64/lxc/rootfs/proc”
lxc-start: aliendalvik: conf.c: lxc_mount_auto_mounts: 801 Device or resource busy - Failed to mount “proc” on “/usr/lib64/lxc/rootfs/proc” with flags 14
lxc-start: aliendalvik: conf.c: lxc_setup: 4094 Failed to setup first automatic mounts
lxc-start: aliendalvik: start.c: do_start: 1291 Failed to setup container “aliendalvik”
lxc-start: aliendalvik: sync.c: sync_wait: 36 An error occurred in another process (expected sequence number 3)
lxc-start: aliendalvik: start.c: __lxc_start: 2053 Failed to spawn container “aliendalvik”
lxc-start: aliendalvik: tools/lxc_start.c: main: 308 The container failed to start
lxc-start: aliendalvik: tools/lxc_start.c: main: 314 Additional information can be obtained by setting the --logfile and --logpriority options
cat: can’t open ‘/home/.android/data/alien_boot_completed’: No such file or directory

Android App Support does not start
#9

Is this not a bunch of warnings you don’t have enough disk space?
I dare say almost no-one else has this “bug”.

#10

… on Feb 26 15:11:50.

#11

Maybe the initial filesystem expansion that’s supposed to happen after flashing went wrong for some reason. That could be checked with df -h.

1 Like
#12

App support is now working!

The problem was probably that I restarted the device directly after installing App Support from the Jolla store.

Apparently App Support continues to initialise itself in the background after installation and restarting too early leads to a broken installation.

I have no idea about the memory bug. It was a fresh installation with a correspondingly large amount of free memory.

I had not inserted an SD card either.

1 Like
#13

What did you do to fix it? Restarting too early was a suspect, but deleting those directories should have fixed that.

#14

This is an attempt at explanation from a beginner. Take this observation with a pinch of salt. It’s possible that I am following the wrong track.

If I understand this correctly, there are some problems with mounting “proc” onto /usr/lib64/lxc/rootfs/proc which is essential for the further startup process.Without it the process fails.

The question is why can’t “proc” be mounted onto /usr/lib64/lxc/rootfs/proc?

Maybe the thing called “proc” that should be mounted onto /usr/lib64/lxc/rootfs/proc is corrupt?

#15

No, deleting the folders did not solve the problem. As soon as I pressed the start button I got the message “unable to start”. Afterwards I executed journalctl and published the logs here.

I solved the problem by reflashing Sailfishos and reinstalling App Support.

#16

Did you enter fastboot/recovery after first flashing SFOS, that one that AppSupport doesn’t work?

#17

No, but during the first and second installation the device got stuck after flashing (blue LED). I had to enforce a reboot by pressing both the Volume Up and Power button at the same time.

The Sailfish setup wizard(Or whatever you call it) also hung up while it was encrypting /home (the screen suddenly went black. After ~5 minutes I restarted the device using Volume Up and Power Key Kombo).

This could be responsible for the problems I had with the app support.

However, since everything worked fine with the exception of App Support, I assumed that the installation was completed successfully.

As an owner of a Pinephone, I am very familiar with problems like file systems not expanding. That’s why I checked the partition size with lsblk to make sure I don’t have a 2.2GB root partition with only 200MB free space available.