Android app support doesn't start after long uptime

direc85 · 8 October 2023 09:27

REPRODUCIBILITY: >75% (hard to estimate)
OS VERSION: 4.5.0.24
HARDWARE: Sony Xperia 10 III
UI LANGUAGE: Finnish
REGRESSION: Unknown

DESCRIPTION:

I’ve noticed that after a long-ish uptime (say, two weeks) Android app support doesn’t start again if it is stopped. The support has no issues starting after reboot, and no issues stopping and starting after a short uptime. (I’ll have to double check that later though.)

Edit: The current uptime is just over 29 days currently, and I stopped the app support at around 28 days 10h uptime.

I’m suspecting it’s a buggy driver or service glitching out after some uptime. This may also be not X10III-only, but I can’t tell currently.

PRECONDITIONS:

Have Android app support installed and running
Keep the device running for a few week

STEPS TO REPRODUCE:

Go to Settings
Stop Alien app support
Start Alien app support

EXPECTED RESULT:

The service starts

ACTUAL RESULT:

The service doesn’t start, but is stuck at starting, with a spinner shown.

MODIFICATIONS:

Patchmanager (installed but inactive)
MicroG

ADDITIONAL INFORMATION:

There doesn’t seem to be any other issues with Android support, it functions without issues otherwise.

One thing I had to do prior to this (that was around a week ago) was restarting pulseaudio because I lost audio after prolonged uptime. Audio has worked fine since, and it may or may not be related to this issue.

I logged the attempt of starting the service when it gets stuck (cleaned up a bit).

Alien app support start attempt

-- Logs begin at Sun 2023-03-05 22:23:05 EET. --
11:46:12 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:13 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:14 lipstick[6598]: unhandled ansi sequence 'l' (2004) "?"
11:46:14 sudo[15201]: defaultuser : TTY=pts/0 ; PWD=/home/defaultuser ; USER=root ; COMMAND=/usr/bin/journalctl -xe
11:46:14 sudo[15201]: pam_systemd(sudo:session): Using 600s D-Bus method call timeout
11:46:14 sudo[15201]: pam_systemd(sudo:session): Cannot create session: Already running in a session
11:46:14 sudo[15201]: pam_unix(sudo:session): session opened for user root by (uid=100000)
11:46:14 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:15 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:16 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:17 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:18 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:19 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:20 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:21 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:22 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:23 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:24 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:25 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:26 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:27 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:28 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:29 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:30 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:31 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:32 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:33 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:34 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:35 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:36 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:37 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:38 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:39 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:40 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:41 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:42 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:43 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:44 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:45 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:46 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:47 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:48 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:49 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:50 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:51 sudo[15201]: pam_unix(sudo:session): session closed for user root
11:46:51 lipstick[6598]: unhandled ansi sequence 'h' (2004) "?"
11:46:51 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:52 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:53 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:54 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:55 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:56 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:57 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:58 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:46:59 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:00 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:01 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:02 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:03 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:04 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:05 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:06 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:07 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:08 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:09 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:10 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:11 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:11 lipstick[6598]: unhandled ansi sequence 'l' (2004) "?"
11:47:11 sudo[18725]: defaultuser : TTY=pts/0 ; PWD=/home/defaultuser ; USER=root ; COMMAND=/usr/bin/journalctl --follow --since -1min
11:47:11 sudo[18725]: pam_systemd(sudo:session): Using 600s D-Bus method call timeout
11:47:11 sudo[18725]: pam_systemd(sudo:session): Cannot create session: Already running in a session
11:47:11 sudo[18725]: pam_unix(sudo:session): session opened for user root by (uid=100000)
11:47:12 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:13 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:14 invoker[7297]: WARNING: An inactive plugin is misbehaving - tried to show a window!
11:47:14 invoker[7297]: WARNING: requestActivate() called for  QQuickView(0x3b630370)  which has Qt::WindowDoesNotAcceptFocus set.
11:47:14 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:15 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:15 systemd[1]: selinux: Unknown class service
11:47:15 systemd[1]: selinux: Unknown class service
11:47:15 systemd[1]: Starting Alien Dalvik prepare...
11:47:15 systemd[1]: selinux: Unknown class service
11:47:15 systemd[1]: selinux: Unknown class service
11:47:15 systemd[1]: selinux: Unknown class service
11:47:15 systemd[1]: selinux: Unknown class service
11:47:15 systemd[1]: selinux: Unknown class service
11:47:15 prepare-aliendalvik.sh[19005]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
11:47:15 prepare-aliendalvik.sh[19005]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
11:47:15 prepare-aliendalvik.sh[19005]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKET_PATH'
11:47:15 prepare-aliendalvik.sh[19005]: Failed to read 'WAYLAND_SOCKET' from environment variables for template 'env:WAYLAND_SOCKET'
11:47:15 prepare-aliendalvik.sh[19005]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKET_PATH'
11:47:15 prepare-aliendalvik.sh[19005]: Failed to read 'BINDER_DEV' from environment variables for template 'env:BINDER_DEV'
11:47:15 prepare-aliendalvik.sh[19005]: Failed to read 'HWBINDER_DEV' from environment variables for template 'env:HWBINDER_DEV'
11:47:15 prepare-aliendalvik.sh[19005]: Failed to read 'VNDBINDER_DEV' from environment variables for template 'env:VNDBINDER_DEV'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'persist.multisim.config' from properties for template 'prop:persist.multisim.config'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'ro.hybris.battery.capacity' from properties for template 'prop:ro.hybris.battery.capacity'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'ro.vendor.product.manufacturer' from properties for template 'prop:ro.vendor.product.manufacturer'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'ro.vendor.product.model' from properties for template 'prop:ro.vendor.product.model'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'ro.vendor.product.brand' from properties for template 'prop:ro.vendor.product.brand'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'ro.vendor.product.name' from properties for template 'prop:ro.vendor.product.name'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'ro.vendor.product.device' from properties for template 'prop:ro.vendor.product.device'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKET_PATH'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'WAYLAND_SOCKET' from environment variables for template 'env:WAYLAND_SOCKET'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKET_PATH'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'WIDTH' from environment variables for template 'env:WIDTH'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'HEIGHT' from environment variables for template 'env:HEIGHT'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'DEPTH' from environment variables for template 'env:DEPTH'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'DPI_X' from environment variables for template 'env:DPI_X'
11:47:16 prepare-aliendalvik.sh[19005]: Failed to read 'DPI_Y' from environment variables for template 'env:DPI_Y'
11:47:16 systemd[1]: Started Alien Dalvik prepare.
11:47:16 systemd[1]: Starting Alien Dalvik...
11:47:16 kernel: binder: 3388:3388 transaction failed 29189/-22, size 32-0 line 3103
11:47:16 systemd[6379]: selinux: Unknown class service
11:47:16 systemd[1]: selinux: Unknown class service
11:47:16 start-aliendalvik.sh[19152]: lxc-start: aliendalvik: utils.c: lxc_can_use_pidfd: 1772 Kernel does not support pidfds
11:47:16 start-aliendalvik.sh[19152]: lxc-attach: aliendalvik: af_unix.c: lxc_abstract_unix_recv_fds_iov: 318 Invalid argument - Received no file descriptors
11:47:16 start-aliendalvik.sh[19152]: lxc-attach: aliendalvik: commands.c: lxc_cmd_rsp_recv_fds: 129 Failed to receive file descriptors
11:47:16 start-aliendalvik.sh[19152]: lxc-attach: aliendalvik: attach.c: lxc_attach_run_command: 1808 No such file or directory - Failed to exec "/system/bin/getprop"
11:47:16 start-aliendalvik.sh[19152]: Supplementary services waiting for hwservicemanager...
11:47:16 kernel: new mount options do not match the existing superblock, will be ignored
11:47:17 kernel: binder: 19223:19223 transaction failed 29189/-22, size 0-0 line 3103
11:47:17 ofonod[3388]: [gbinder] Service manager /dev/hwpuddlejumper has appeared
11:47:17 start-aliendalvik.sh[19152]: lxc-attach: aliendalvik: af_unix.c: lxc_abstract_unix_recv_fds_iov: 318 Invalid argument - Received no file descriptors
11:47:17 start-aliendalvik.sh[19152]: lxc-attach: aliendalvik: commands.c: lxc_cmd_rsp_recv_fds: 129 Failed to receive file descriptors
11:47:17 systemd[6379]: selinux: Unknown class service
11:47:17 systemd[6379]: selinux: Unknown class service
11:47:17 systemd[6379]: Started Alien Audio Service.
11:47:17 systemd[6379]: selinux: Unknown class service
11:47:17 systemd[6379]: selinux: Unknown class service
11:47:17 systemd[6379]: selinux: Unknown class service
11:47:17 systemd[6379]: selinux: Unknown class service
11:47:17 systemd[6379]: Started Alien Keyboard Service.
11:47:17 systemd[6379]: selinux: Unknown class service
11:47:17 systemd[6379]: selinux: Unknown class service
11:47:18 systemd[6379]: selinux: Unknown class service
11:47:18 systemd[6379]: selinux: Unknown class service
11:47:18 systemd[6379]: Starting Bridge native data to Alien Dalvik...
11:47:18 systemd[6379]: selinux: Unknown class service
11:47:18 systemd[6379]: selinux: Unknown class service
11:47:18 invoker[19413]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
11:47:18 invoker[19413]: Failed to read 'BINDER_DEV' from environment variables for template 'env:BINDER_DEV'
11:47:18 invoker[19413]: Failed to read 'HWBINDER_DEV' from environment variables for template 'env:HWBINDER_DEV'
11:47:18 invoker[19413]: Failed to read 'APKD_PACKAGE_BLACKLIST_PATH' from environment variables for template 'env:APKD_PACKAGE_BLACKLIST_PATH'
11:47:18 invoker[19413]: Failed to read 'APKD_NOTIF_BLACKLIST_PATH' from environment variables for template 'env:APKD_NOTIF_BLACKLIST_PATH'
11:47:18 systemd[1]: selinux: Unknown permission status for class system
11:47:18 systemd[1]: selinux: Unknown class service
11:47:18 systemd[1]: selinux: Unknown class service
11:47:18 systemd[6379]: Started Bridge native data to Alien Dalvik.
11:47:18 systemd[6379]: selinux: Unknown class service
11:47:18 systemd[6379]: selinux: Unknown class service
11:47:18 systemd[6379]: Started Bridge native data to Alien Dalvik.
11:47:18 systemd[6379]: selinux: Unknown class service
11:47:18 systemd[6379]: selinux: Unknown class service
11:47:19 invoker[19456]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
11:47:19 invoker[19456]: Failed to read 'BINDER_DEV' from environment variables for template 'env:BINDER_DEV'
11:47:19 invoker[19456]: Failed to read 'HWBINDER_DEV' from environment variables for template 'env:HWBINDER_DEV'
11:47:19 invoker[19456]: Failed to read 'APKD_PACKAGE_BLACKLIST_PATH' from environment variables for template 'env:APKD_PACKAGE_BLACKLIST_PATH'
11:47:19 invoker[19456]: Failed to read 'APKD_NOTIF_BLACKLIST_PATH' from environment variables for template 'env:APKD_NOTIF_BLACKLIST_PATH'
11:47:19 [19417]: [W] unknown:0 - Sensor not supported ( QCompass )
11:47:19 [19417]: [W] unknown:0 - Sensor not supported ( QAmbientLightSensor )
11:47:19 [19417]: [W] unknown:0 - Sensor not supported ( QRotationSensor )
11:47:19 systemd[1]: selinux: Unknown permission status for class system
11:47:19 systemd[1]: selinux: Unknown class service
11:47:19 systemd[1]: selinux: Unknown class service
11:47:19 invoker[19456]: Starting AlienMediaBrowserService
11:47:19 invoker[19456]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
11:47:19 invoker[19456]: Failed to read 'BINDER_DEV' from environment variables for template 'env:BINDER_DEV'
11:47:19 invoker[19456]: Failed to read 'HWBINDER_DEV' from environment variables for template 'env:HWBINDER_DEV'
11:47:19 invoker[19456]: Failed to read 'APKD_PACKAGE_BLACKLIST_PATH' from environment variables for template 'env:APKD_PACKAGE_BLACKLIST_PATH'
11:47:19 invoker[19456]: Failed to read 'APKD_NOTIF_BLACKLIST_PATH' from environment variables for template 'env:APKD_NOTIF_BLACKLIST_PATH'
11:47:19 [19417]: [D] unknown:0 - Starting bridge for "default" ("android.hardware.graphics.allocator@4.0::IAllocator", "android.hardware.graphics.allocator@3.0::IAllocator", "android.hardware.graphics.allocator@2.0::IAllocator") on /dev/hwpuddlejumper from /dev/hwbinder
11:47:19 [19417]: [D] unknown:0 - Starting bridge for "default" ("android.hardware.power@1.0::IPower") on /dev/hwpuddlejumper from /dev/hwbinder
11:47:19 droid-hal-init: Control message: Could not find 'android.hardware.graphics.allocator@2.0::IAllocator/default' for ctl.interface_start from pid: 3253 (/system/bin/hwservicemanager)
11:47:19 [19417]: [D] unknown:0 - Starting bridge for "legacy/0" ("android.hardware.camera.provider@2.4::ICameraProvider") on /dev/hwpuddlejumper from /dev/hwbinder
11:47:19 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=44 --signal=6 --name=android.hardwar disabled
11:47:19 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=42 --signal=6 --name=android.hardwar disabled
11:47:19 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=61 --signal=6 --name=android.hardwar disabled
11:47:23 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=193 --signal=6 --name=android.hardwar disabled
11:47:23 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=192 --signal=6 --name=android.hardwar disabled
11:47:23 kernel: binder: undelivered death notification, b4000075d3525060
11:47:23 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=203 --signal=6 --name=android.hardwar disabled
11:47:23 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=208 --signal=6 --name=android.hardwar disabled
11:47:24 invoker[7297]: WARNING: An inactive plugin is misbehaving - tried to show a window!
11:47:24 invoker[7297]: WARNING: requestActivate() called for  QQuickView(0x3b630370)  which has Qt::WindowDoesNotAcceptFocus set.
11:47:28 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=304 --signal=6 --name=android.hardwar disabled
11:47:28 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=307 --signal=6 --name=android.hardwar disabled
11:47:28 kernel: binder: undelivered death notification, b400006f27019980
11:47:28 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=311 --signal=6 --name=android.hardwar disabled
11:47:28 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=321 --signal=6 --name=android.hardwar disabled
11:47:29 toeterm[29016]: [D] unknown:0 - unknown special key:  67108864

I’m noticing a lot of binder and selinux errors in the logs, as well as some environment variable errors.

Restarting apkd or re-restarting pulseaudio doesn’t help the situation.

I’m currently hanging on to not restarting the device so I can provide extra logs, but because there’s a few banking apps that I will require soon, I will have to restart at some point.

ziellos · 8 October 2023 10:36

Same here. In fact, it happened today.
My uprecords show that I never get beyond 25 days of uptime since Linux 4.19.248. After that period, Alien Dalvik refuses to start.
Maybe the issue isn’t related to uptime, but to the number of service restarts.
Additional information: I use Situations to stop AD service during the night.

direc85 · 8 October 2023 11:08

My gut feeling is that it’s not service restart dependent… I hadn’t restarted android app support in this time, so that would be the stop-start for me after the last device reboot.

ziellos · 8 October 2023 12:09

But could he number of +/- 25 days of uptime be some pattern?

Seven.of.nine · 8 October 2023 12:12

OT: I made similar experiences, but not with Android support but with network services. Resetting network with SFOS utilities didn’t help, but a full reboot did. There seems something to be unstable in the whole SFOS.

direc85 · 8 October 2023 13:50

I tried to start Android app support again, this time there are a lot of crashes:

Another service start-stuck-stop

-- Logs begin at Sun 2023-03-05 22:23:05 EET. --
16:21:38 kernel: binder: release 27486:27601 transaction 64604622 out, still active
16:21:38 alien-post-startup.sh[28638]: lxc-attach: aliendalvik: af_unix.c: lxc_abstract_unix_recv_fds_iov: 318 Invalid argument - Received no file descriptors
16:21:38 alien-post-startup.sh[28638]: lxc-attach: aliendalvik: commands.c: lxc_cmd_rsp_recv_fds: 129 Failed to receive file descriptors
16:21:39 alien-post-startup.sh[28638]: cmd: Can't find service: settings
16:21:39 alien-post-startup.sh[28638]: cmd: Can't find service: settings
16:21:39 alien-post-startup.sh[28638]: cmd: Can't find service: settings
16:21:39 alien-post-startup.sh[28638]: cmd: Can't find service: settings
16:21:39 alien-post-startup.sh[28638]: cmd: Can't find service: package
16:21:39 alien-post-startup.sh[28638]: cmd: Can't find service: device_config
16:21:39 systemd[1]: aliendalvik.service: Control process exited, code=exited status=20
16:21:39 kernel: binder_thread_write: 5 callbacks suppressed
16:21:39 kernel: binder: 27273:27273 BC_DEAD_BINDER_DONE 0000000000000000 not found
16:21:39 alienaudioservice[27273]: [gbinder] WARNING: Service manager /dev/vndpuddlejumper has died
16:21:39 alienaudioservice[27273]: [AlienAudioService] service: Service manager has died.
16:21:39 kernel: binder: release 27379:27379 transaction 64600269 out, still active
16:21:39 kernel: binder: undelivered TRANSACTION_COMPLETE
16:21:39 kernel: binder: 27312:27312 BC_CLEAR_DEATH_NOTIFICATION invalid ref 5
16:21:39 alienkeyboardservice[27294]: [gbinder] WARNING: Service manager /dev/puddlejumper has died
16:21:39 kernel: binder: 27294:27294 BC_DEAD_BINDER_DONE 0000000000000000 not found
16:21:39 kernel: binder: 27312:27312 BC_DEAD_BINDER_DONE 0000000000000000 not found
16:21:39 kernel: binder: 27273:27273 BC_DEAD_BINDER_DONE 0000000000000000 not found
16:21:39 alienaudioservice[27273]: [gbinder] WARNING: Service manager /dev/puddlejumper has died
16:21:39 kernel: binder: 27312:27312 BC_DEAD_BINDER_DONE 0000000000000000 not found
16:21:39 kernel: binder: 27273:27273 BC_DEAD_BINDER_DONE 0000000000000000 not found
16:21:39 alienaudioservice[27273]: [gbinder] WARNING: Service manager /dev/hwpuddlejumper has died
16:21:39 ofonod[3388]: [gbinder] WARNING! Service manager /dev/hwpuddlejumper has died
16:21:39 kernel: binder: release 27388:27464 transaction 64600269 in, still active
16:21:39 kernel: binder: send failed reply for transaction 64600269, target dead
16:21:39 kernel: binder: undelivered death notification, b400007a6ffe9140
16:21:39 stop-aliendalvik.sh[28676]: lxc-attach: aliendalvik: af_unix.c: lxc_abstract_unix_recv_fds_iov: 318 Invalid argument - Received no file descriptors
16:21:39 stop-aliendalvik.sh[28676]: lxc-attach: aliendalvik: commands.c: lxc_cmd_rsp_recv_fds: 129 Failed to receive file descriptors
16:21:39 kernel: binder: 27273:27273 transaction failed 29189/-22, size 0-0 line 3103
16:21:39 stop-aliendalvik.sh[28676]: Done
16:21:39 kernel: binder: 27294:27294 transaction failed 29189/-22, size 0-0 line 3103
16:21:39 kernel: binder: 27312:27312 transaction failed 29189/-22, size 0-0 line 3103
16:21:39 kernel: binder: 27273:27273 transaction failed 29189/-22, size 0-0 line 3103
16:21:39 kernel: binder: 27273:27273 transaction failed 29189/-22, size 32-0 line 3103
16:21:39 kernel: binder: 27312:27312 transaction failed 29189/-22, size 32-0 line 3103
16:21:39 stop-aliendalvik.sh[28676]: Waiting for container to exit.
16:21:39 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=1 --signal=11 --name=init disabled
16:21:39 kernel: binder: 27273:27273 transaction failed 29189/-22, size 0-0 line 3103
16:21:39 kernel: binder: 27294:27294 transaction failed 29189/-22, size 0-0 line 3103
16:21:39 kernel: binder: 27312:27312 transaction failed 29189/-22, size 0-0 line 3103
16:21:39 toeterm[26475]: [D] unknown:0 - unknown special key:  67108864
16:21:40 systemd[1]: aliendalvik.service: Failed with result 'exit-code'.
16:21:40 systemd[1]: Stopped Alien Dalvik.
16:21:40 systemd[1]: Stopping Alien Dalvik prepare...
16:21:40 systemd[6379]: Stopping Alien Audio Service...
16:21:40 systemd[6379]: Stopped Alien Audio Service.
16:21:40 systemd[6379]: Stopping Alien Keyboard Service...
16:21:40 systemd[6379]: Stopped Alien Keyboard Service.
16:21:40 systemd[6379]: Stopping Bridge native data to Alien Dalvik...
16:21:40 /usr/libexec/mapplauncherd/booster-qt5[6416]: warning: terminating: booster:26124 invoker:27311 socket:13
16:21:40 /usr/libexec/mapplauncherd/booster-qt5[6416]: warning: Daemon: sending exit(1) to invoker(27311)
16:21:40 /usr/libexec/mapplauncherd/booster-qt5[6416]: warning: sending SIGTERM to booster (pid=26124)
16:21:40 systemd[6379]: Stopped Bridge native data to Alien Dalvik.
16:21:40 booster-qt5[6416]: *** signal=17 pid=6416
16:21:40 systemd[6379]: Stopping Bridge native data to Alien Dalvik...
16:21:40 /usr/libexec/mapplauncherd/booster-qt5[6416]: warning: terminating: booster:27312 invoker:27339 socket:19
16:21:40 /usr/libexec/mapplauncherd/booster-qt5[6416]: warning: Daemon: sending exit(1) to invoker(27339)
16:21:40 /usr/libexec/mapplauncherd/booster-qt5[6416]: warning: sending SIGTERM to booster (pid=27312)
16:21:40 systemd[6379]: Stopped Bridge native data to Alien Dalvik.
16:21:40 kernel: binder: release 27312:27459 transaction 64604622 in, still active
16:21:40 kernel: binder: send failed reply for transaction 64604622, target dead
16:21:40 sensorfwd[3315]: [SocketHandler]: Socket error:  QLocalSocket::PeerClosedError
16:21:40 sensorfwd[3315]: [SocketHandler]: Noticed lost session:  96
16:21:40 sensorfwd[3315]: [SocketHandler]: Socket error:  QLocalSocket::PeerClosedError
16:21:40 sensorfwd[3315]: [SocketHandler]: Noticed lost session:  98
16:21:40 sensorfwd[3315]: [SocketHandler]: Socket error:  QLocalSocket::PeerClosedError
16:21:40 booster-qt5[6416]: *** signal=17 pid=6416
16:21:40 sensorfwd[3315]: [SocketHandler]: Noticed lost session:  100
16:21:40 sensorfwd[3315]: [SocketHandler]: Socket error:  QLocalSocket::PeerClosedError
16:21:40 sensorfwd[3315]: [SocketHandler]: Noticed lost session:  97
16:21:40 sensorfwd[3315]: [SocketHandler]: Socket error:  QLocalSocket::PeerClosedError
16:21:40 sensorfwd[3315]: [SocketHandler]: Noticed lost session:  101
16:21:40 sensorfwd[3315]: [SocketHandler]: Socket error:  QLocalSocket::PeerClosedError
16:21:40 sensorfwd[3315]: [SocketHandler]: Noticed lost session:  99
16:21:40 systemd[1]: Stopped Alien Dalvik prepare.
16:21:42 mce[2052]: tklock.c: tklock_datapipe_uiexception_type_cb(): uiexception_type = notif -> none
16:21:44 kernel: rpmh_rsc_send_data: 77 callbacks suppressed
16:21:44 kernel: binder_transaction: 23 callbacks suppressed
16:21:46 sudo[28792]: defaultuser : TTY=pts/1 ; PWD=/home/defaultuser ; USER=root ; COMMAND=/usr/bin/journalctl --follow --since -1min
16:21:46 sudo[28792]: pam_systemd(sudo:session): Using 600s D-Bus method call timeout
16:21:46 sudo[28792]: pam_systemd(sudo:session): Cannot create session: Already running in a session
16:21:46 sudo[28792]: pam_unix(sudo:session): session opened for user root by (uid=100000)
16:21:47 mce[2052]: modules/proximity.c: mp_datapipe_set_proximity_sensor_actual(): state: OPEN -> UNDEF
16:21:51 kernel: rpmh_rsc_send_data: 29 callbacks suppressed
16:21:58 mce[2052]: tklock.c: tklock_dbus_notification_beg_cb(): notification begin from name=:1.73 owner=:1.73 pid=6598 uid=100000 gid=996 priv=1 cmd=/usr/bin/lipstick -plugin evdevtouch -plugin evdevmouse -plugin
16:21:58 mce[2052]: tklock.c: tklock_datapipe_uiexception_type_cb(): uiexception_type = none -> notif
16:21:58 kernel: [aw882xx_smartpa]aw882xx_startup: capture enter
16:21:58 kernel: [aw882xx_smartpa]aw882xx_set_fmt: fmt=0x4001
16:21:58 kernel: msm_mi2s_snd_startup: set sysclk failed, err:-524
16:21:58 kernel: __afe_port_start: port id: 0x1001
16:21:58 kernel: afe_find_cal_topo_id_by_port: port id: 0x1001, dev_acdb_id: -22
16:21:58 kernel: afe_find_cal_topo_id_by_port: top_id:1000ff02 acdb_id:102 afe_port:0x1001
16:21:58 kernel: afe_get_cal_topology_id: port_id = 0x1001 acdb_id = 102 topology_id = 0x1000ff02 cal_type_index=8 ret=0
16:21:58 kernel: afe_send_port_topology_id: AFE set topology id 0x1000ff02  enable for port 0x1001 ret 0
16:21:58 kernel: send_afe_cal_type: cal_index is 1
16:21:58 kernel: send_afe_cal_type: dev_acdb_id[41] is -22
16:21:58 kernel: send_afe_cal_type: Sending cal_index cal 1
16:21:58 kernel: afe_send_hw_delay: port_id 0x1001 rate 48000 delay_usec 578 status 0
16:21:58 kernel: [aw882xx_smartpa]aw882xx_mute: mute state=0
16:21:58 kernel: [aw882xx_smartpa]aw882xx_startup: playback enter
16:21:58 kernel: __afe_port_start: port id: 0x1000
16:21:58 kernel: afe_find_cal_topo_id_by_port: port id: 0x1000, dev_acdb_id: 124
16:21:58 kernel: afe_find_cal_topo_id_by_port: top_id:1001025f acdb_id:124 afe_port_id:0x1000
16:21:58 kernel: afe_get_cal_topology_id: port_id = 0x1000 acdb_id = 124 topology_id = 0x1001025f cal_type_index=8 ret=0
16:21:58 kernel: afe_send_port_topology_id: AFE set topology id 0x1001025f  enable for port 0x1000 ret 0
16:21:58 kernel: send_afe_cal_type: cal_index is 0
16:21:58 kernel: send_afe_cal_type: dev_acdb_id[40] is 124
16:21:58 kernel: afe_find_cal: cal_index 0 port_id 0x1000 port_index 40
16:21:58 kernel: afe_find_cal: acdb_id 124 dev_acdb_id 124 sample_rate 48000 afe_sample_rates 48000
16:21:58 kernel: afe_find_cal: cal block is a match, size is 868
16:21:58 kernel: send_afe_cal_type: Sending cal_index cal 0
16:21:58 kernel: afe_send_hw_delay: port_id 0x1000 rate 48000 delay_usec 474 status 0
16:21:58 kernel: [aw882xx_smartpa]aw882xx_mute: mute state=0
16:21:58 kernel: [aw882xx_smartpa]aw882xx_smartpa_cfg: flag = 1, power status = 0
16:21:58 kernel: [aw882xx_smartpa]aw882xx_clear_sysint: get_sysint=0x4395
16:21:58 kernel: [aw882xx_smartpa]aw882xx_clear_sysint: get_sysint=0x0000
16:21:58 kernel: afe_callback: cmd = 0x100fa returned error = 0x1
16:21:58 kernel: afe_apr_send_pkt: DSP returned error[ADSP_EFAILED]
16:21:58 kernel: [aw882xx_smartpa]aw882xx_set_cali_re_to_dsp : set cali re to dsp failed
16:21:58 kernel: [aw882xx_smartpa]aw882xx_monitor_start: enter
16:21:58 kernel: [aw882xx_smartpa]aw882xx_monitor_get_data: vol: ipeak = 0x8, gain = 0x0, vmax = 0x0
16:21:58 kernel: [aw882xx_smartpa]aw882xx_monitor_get_data: temp: ipeak = 0x8, gain = 0x0, vmax = 0x0
16:21:58 kernel: [aw882xx_smartpa]aw882xx_monitor_set_ipeak: ipeak = 0x8, no change
16:21:58 kernel: [aw882xx_smartpa]aw882xx_monitor_set_gain: set db = 0.0 dB, no change
16:21:58 kernel: afe_callback: cmd = 0x100fa returned error = 0x1
16:21:58 kernel: afe_apr_send_pkt: DSP returned error[ADSP_EFAILED]
16:21:58 kernel: [aw882xx_smartpa]aw882xx_monitor_set_vmax: dsp_msg_write error
16:21:58 kernel: [aw882xx_smartpa]aw882xx_monitor_set_vmax: set vmax = 0x0
16:21:59 mce[2052]: modules/proximity.c: mp_datapipe_set_proximity_sensor_actual(): state: UNDEF -> OPEN
16:22:01 kernel: rpmh_rsc_send_data: 118 callbacks suppressed
16:22:02 kernel: [aw882xx_smartpa]aw882xx_mute: mute state=1
16:22:02 kernel: [aw882xx_smartpa]aw882xx_smartpa_cfg: flag = 0, power status = 2
16:22:02 kernel: [aw882xx_smartpa]aw882xx_monitor_stop: enter
16:22:02 kernel: [aw882xx_smartpa]aw882xx_stop: get_sysint=0x0000
16:22:02 kernel: afe_close: port_id = 0x1000
16:22:02 kernel: [aw882xx_smartpa]aw882xx_mute: mute state=1
16:22:02 kernel: afe_close: port_id = 0x1001
16:22:05 mce[2052]: tklock.c: tklock_datapipe_uiexception_type_cb(): uiexception_type = notif -> none
16:22:07 kernel: rpmh_rsc_send_data: 130 callbacks suppressed
16:22:10 mce[2052]: modules/proximity.c: mp_datapipe_set_proximity_sensor_actual(): state: OPEN -> UNDEF
16:22:17 kernel: rpmh_rsc_send_data: 168 callbacks suppressed
16:22:22 kernel: rpmh_rsc_send_data: 32 callbacks suppressed
16:22:28 kernel: rpmh_rsc_send_data: 20 callbacks suppressed
16:22:28 toeterm[26475]: [D] unknown:0 - unknown special key:  67108864
16:22:28 sudo[28792]: pam_unix(sudo:session): session closed for user root
16:22:33 sudo[28937]: defaultuser : TTY=pts/1 ; PWD=/home/defaultuser ; USER=root ; COMMAND=/usr/bin/systemctl stop aliendalvik.service
16:22:33 sudo[28937]: pam_systemd(sudo:session): Using 600s D-Bus method call timeout
16:22:33 sudo[28937]: pam_systemd(sudo:session): Cannot create session: Already running in a session
16:22:33 sudo[28937]: pam_unix(sudo:session): session opened for user root by (uid=100000)
16:22:33 sudo[28937]: pam_unix(sudo:session): session closed for user root
16:22:33 kernel: rpmh_rsc_send_data: 6 callbacks suppressed
16:22:38 sudo[28944]: defaultuser : TTY=pts/1 ; PWD=/home/defaultuser ; USER=root ; COMMAND=/usr/bin/journalctl --follow --since -1min
16:22:38 sudo[28944]: pam_systemd(sudo:session): Using 600s D-Bus method call timeout
16:22:38 sudo[28944]: pam_systemd(sudo:session): Cannot create session: Already running in a session
16:22:38 sudo[28944]: pam_unix(sudo:session): session opened for user root by (uid=100000)
16:22:39 kernel: rpmh_rsc_send_data: 45 callbacks suppressed
16:22:41 invoker[7297]: WARNING: An inactive plugin is misbehaving - tried to show a window!
16:22:41 invoker[7297]: WARNING: requestActivate() called for  QQuickView(0x3b630370)  which has Qt::WindowDoesNotAcceptFocus set.
16:22:44 kernel: rpmh_rsc_send_data: 14 callbacks suppressed
16:22:47 dbus-daemon[6405]: dbus-daemon[6405]: [session uid=100000 pid=6405] Activating service name='com.jolla.settings' requested by ':1.4859' (uid=100000 pid=28982 comm="/usr/libexec/mliteremoteaction com.jolla.settings " label="u:r:kernel:s0")
16:22:47 dbus-daemon[6405]: dbus-daemon[6405]: [session uid=100000 pid=6405] Successfully activated service 'com.jolla.settings'
16:22:48 invoker[7297]: WARNING: An inactive plugin is misbehaving - tried to show a window!
16:22:48 invoker[7297]: WARNING: requestActivate() called for  QQuickView(0x3b630370)  which has Qt::WindowDoesNotAcceptFocus set.
16:22:48 dbus-daemon[6405]: QInotifyFileSystemWatcherEngine::addPaths: inotify_add_watch failed: Tiedostoa tai hakemistoa ei ole
16:22:48 booster-silica-qt5[28986]: [D] unknown:0 - Using Wayland-EGL
16:22:48 dbus-daemon[6405]: QInotifyFileSystemWatcherEngine::addPaths: inotify_add_watch failed: Tiedostoa tai hakemistoa ei ole
16:22:50 systemd[1]: selinux: Unknown permission status for class system
16:22:50 systemd[1]: selinux: Unknown permission status for class system
16:22:51 kernel: rpmh_rsc_send_data: 46 callbacks suppressed
16:22:51 systemd[1]: Starting Alien Dalvik prepare...
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKET_PATH'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'WAYLAND_SOCKET' from environment variables for template 'env:WAYLAND_SOCKET'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKET_PATH'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'BINDER_DEV' from environment variables for template 'env:BINDER_DEV'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'HWBINDER_DEV' from environment variables for template 'env:HWBINDER_DEV'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'VNDBINDER_DEV' from environment variables for template 'env:VNDBINDER_DEV'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'persist.multisim.config' from properties for template 'prop:persist.multisim.config'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'ro.hybris.battery.capacity' from properties for template 'prop:ro.hybris.battery.capacity'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'ro.vendor.product.manufacturer' from properties for template 'prop:ro.vendor.product.manufacturer'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'ro.vendor.product.model' from properties for template 'prop:ro.vendor.product.model'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'ro.vendor.product.brand' from properties for template 'prop:ro.vendor.product.brand'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'ro.vendor.product.name' from properties for template 'prop:ro.vendor.product.name'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'ro.vendor.product.device' from properties for template 'prop:ro.vendor.product.device'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKET_PATH'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'WAYLAND_SOCKET' from environment variables for template 'env:WAYLAND_SOCKET'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'WAYLAND_SOCKET_PATH' from environment variables for template 'env:WAYLAND_SOCKET_PATH'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'WIDTH' from environment variables for template 'env:WIDTH'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'HEIGHT' from environment variables for template 'env:HEIGHT'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'DEPTH' from environment variables for template 'env:DEPTH'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'DPI_X' from environment variables for template 'env:DPI_X'
16:22:52 prepare-aliendalvik.sh[29006]: Failed to read 'DPI_Y' from environment variables for template 'env:DPI_Y'
16:22:52 systemd[1]: Started Alien Dalvik prepare.
16:22:52 systemd[1]: Starting Alien Dalvik...
16:22:52 start-aliendalvik.sh[29133]: lxc-start: aliendalvik: utils.c: lxc_can_use_pidfd: 1772 Kernel does not support pidfds
16:22:52 start-aliendalvik.sh[29133]: lxc-attach: aliendalvik: af_unix.c: lxc_abstract_unix_recv_fds_iov: 318 Invalid argument - Received no file descriptors
16:22:52 start-aliendalvik.sh[29133]: lxc-attach: aliendalvik: commands.c: lxc_cmd_rsp_recv_fds: 129 Failed to receive file descriptors
16:22:52 start-aliendalvik.sh[29133]: lxc-attach: aliendalvik: attach.c: lxc_attach_run_command: 1808 No such file or directory - Failed to exec "/system/bin/getprop"
16:22:52 start-aliendalvik.sh[29133]: Supplementary services waiting for hwservicemanager...
16:22:52 kernel: new mount options do not match the existing superblock, will be ignored
16:22:53 kernel: binder: 29197:29197 transaction failed 29189/-22, size 0-0 line 3103
16:22:53 start-aliendalvik.sh[29133]: lxc-attach: aliendalvik: af_unix.c: lxc_abstract_unix_recv_fds_iov: 318 Invalid argument - Received no file descriptors
16:22:53 start-aliendalvik.sh[29133]: lxc-attach: aliendalvik: commands.c: lxc_cmd_rsp_recv_fds: 129 Failed to receive file descriptors
16:22:53 systemd[6379]: Started Alien Audio Service.
16:22:53 ofonod[3388]: [gbinder] Service manager /dev/hwpuddlejumper has appeared
16:22:53 systemd[6379]: Started Alien Keyboard Service.
16:22:54 systemd[6379]: Starting Bridge native data to Alien Dalvik...
16:22:54 invoker[29271]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
16:22:54 invoker[29271]: Failed to read 'BINDER_DEV' from environment variables for template 'env:BINDER_DEV'
16:22:54 invoker[29271]: Failed to read 'HWBINDER_DEV' from environment variables for template 'env:HWBINDER_DEV'
16:22:54 invoker[29271]: Failed to read 'APKD_PACKAGE_BLACKLIST_PATH' from environment variables for template 'env:APKD_PACKAGE_BLACKLIST_PATH'
16:22:54 invoker[29271]: Failed to read 'APKD_NOTIF_BLACKLIST_PATH' from environment variables for template 'env:APKD_NOTIF_BLACKLIST_PATH'
16:22:54 systemd[1]: selinux: Unknown permission status for class system
16:22:54 systemd[6379]: Started Bridge native data to Alien Dalvik.
16:22:54 systemd[6379]: Started Bridge native data to Alien Dalvik.
16:22:55 invoker[29294]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
16:22:55 invoker[29294]: Failed to read 'BINDER_DEV' from environment variables for template 'env:BINDER_DEV'
16:22:55 invoker[29294]: Failed to read 'HWBINDER_DEV' from environment variables for template 'env:HWBINDER_DEV'
16:22:55 invoker[29294]: Failed to read 'APKD_PACKAGE_BLACKLIST_PATH' from environment variables for template 'env:APKD_PACKAGE_BLACKLIST_PATH'
16:22:55 invoker[29294]: Failed to read 'APKD_NOTIF_BLACKLIST_PATH' from environment variables for template 'env:APKD_NOTIF_BLACKLIST_PATH'
16:22:55 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=44 --signal=6 --name=android.hardwar disabled
16:22:55 systemd[1]: selinux: Unknown permission status for class system
16:22:55 invoker[29294]: Starting AlienMediaBrowserService
16:22:55 invoker[29294]: Failed to read 'ANDROID_DATA_ROOT' from environment variables for template 'env:ANDROID_DATA_ROOT'
16:22:55 invoker[29294]: Failed to read 'BINDER_DEV' from environment variables for template 'env:BINDER_DEV'
16:22:55 invoker[29294]: Failed to read 'HWBINDER_DEV' from environment variables for template 'env:HWBINDER_DEV'
16:22:55 invoker[29294]: Failed to read 'APKD_PACKAGE_BLACKLIST_PATH' from environment variables for template 'env:APKD_PACKAGE_BLACKLIST_PATH'
16:22:55 invoker[29294]: Failed to read 'APKD_NOTIF_BLACKLIST_PATH' from environment variables for template 'env:APKD_NOTIF_BLACKLIST_PATH'
16:22:55 [29272]: [D] unknown:0 - Starting bridge for "default" ("android.hardware.graphics.allocator@4.0::IAllocator", "android.hardware.graphics.allocator@3.0::IAllocator", "android.hardware.graphics.allocator@2.0::IAllocator") on /dev/hwpuddlejumper from /dev/hwbinder
16:22:55 [29272]: [D] unknown:0 - Starting bridge for "default" ("android.hardware.power@1.0::IPower") on /dev/hwpuddlejumper from /dev/hwbinder
16:22:55 [29272]: [D] unknown:0 - Starting bridge for "legacy/0" ("android.hardware.camera.provider@2.4::ICameraProvider") on /dev/hwpuddlejumper from /dev/hwbinder
16:22:55 droid-hal-init: Control message: Could not find 'android.hardware.graphics.allocator@2.0::IAllocator/default' for ctl.interface_start from pid: 3253 (/system/bin/hwservicemanager)
16:22:55 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=42 --signal=6 --name=android.hardwar disabled
16:22:55 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=63 --signal=6 --name=android.hardwar disabled
16:22:58 invoker[7297]: WARNING: An inactive plugin is misbehaving - tried to show a window!
16:22:58 invoker[7297]: WARNING: requestActivate() called for  QQuickView(0x3b630370)  which has Qt::WindowDoesNotAcceptFocus set.
16:22:59 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=166 --signal=6 --name=android.hardwar disabled
16:22:59 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=167 --signal=6 --name=android.hardwar disabled
16:22:59 kernel: binder: undelivered death notification, b400006e5cde0530
16:22:59 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=178 --signal=6 --name=android.hardwar disabled
16:22:59 kernel: binder: undelivered death notification, b400007079395fb0
16:22:59 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=208 --signal=6 --name=android.hardwar disabled
16:23:03 kernel: rpmh_rsc_send_data: 56 callbacks suppressed
16:23:04 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=276 --signal=6 --name=android.hardwar disabled
16:23:04 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=280 --signal=6 --name=android.hardwar disabled
16:23:04 kernel: binder: 29627:29627 transaction failed 29189/-22, size 32-0 line 3103
16:23:04 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=295 --signal=6 --name=android.hardwar disabled
16:23:08 kernel: rpmh_rsc_send_data: 90 callbacks suppressed
16:23:09 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=333 --signal=6 --name=android.hardwar disabled
16:23:09 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=336 --signal=6 --name=android.hardwar disabled
16:23:09 kernel: binder: undelivered death notification, b40000772e00bf30
16:23:09 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=345 --signal=6 --name=android.hardwar disabled
16:23:09 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=355 --signal=6 --name=android.hardwar disabled
16:23:12 ofonod[3388]: imsradio0 > 4 registrationChanged
16:23:12 ofonod[3388]:   0000: 76 65 6e 64 6f 72 2e 71  74 69 2e 68 61 72 64 77    vendor.q ti.hardw
16:23:12 ofonod[3388]:   0010: 61 72 65 2e 72 61 64 69  6f 2e 69 6d 73 40 31 2e    are.radi o.ims@1.
16:23:12 ofonod[3388]:   0020: 30 3a 3a 49 49 6d 73 52  61 64 69 6f 49 6e 64 69    0::IImsR adioIndi [REDACTED]
16:23:13 kernel: rpmh_rsc_send_data: 35 callbacks suppressed
16:23:14 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=369 --signal=6 --name=android.hardwar disabled
16:23:14 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=372 --signal=6 --name=android.hardwar disabled
16:23:14 kernel: binder: undelivered death notification, b4000075a460c2d0
16:23:14 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=381 --signal=6 --name=android.hardwar disabled
16:23:14 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=382 --signal=6 --name=android.hardwar disabled
16:23:18 kernel: rpmh_rsc_send_data: 69 callbacks suppressed
16:23:19 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=405 --signal=6 --name=android.hardwar disabled
16:23:19 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=408 --signal=6 --name=android.hardwar disabled
16:23:19 kernel: binder: undelivered death notification, b4000070378b3b90
16:23:19 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=409 --signal=6 --name=android.hardwar disabled
16:23:19 kernel: Core dump to |/usr/sbin/rich-core-dumper --pid=422 --signal=6 --name=android.hardwar disabled
16:23:19 kernel: binder: undelivered death notification, b400006efd232bc0

Note that there are lots of aw882xx_smartpa lines, that’s likely because I received a Whisperfish message during logging. Also the dominating qcom_rpmh, selinux and binder: 3388:3388 lines were cut - and its still quite long.

direc85 · 8 October 2023 21:23

Thanks for @mal for hinting me about tombstones and reminding me about logcat. Here’s my log dump which consists of logcat output, journalctl output and /home/.android/tombstones/* crash dump files, feel free to have a look.

We’re quite far from my comfort zone, but I think this is linked to X10III losing audio which 1) did happen to me before I stopped and started app support, and 2) /system/bin/hw/android.hardware.audio.service shows up a lot in the tombstones.

I tested yesterday and Bluetooth audio works, as well as ringtone plays and even call audio works. I did multiple pulseaudio restarts in the initial process, however.

elkiaer · 9 October 2023 07:39

Hei

In another case in here it was mentioned, when having the GPS active it will cause the no sound.

I Have tried to not have the GPS on and for me it seems the issue will not occur in this state.
Yesterday I put the GPS on and this morning I did not have any sound.
If this is the issue (seems strange for me if it is) I do not know, but it could be.

Maybe anyone could try to test this this.

Speedy-10 · 10 October 2023 14:42

Hi, I observed the same. Therefor I start and stop android-support every day new (per systemd):
`
[root@Xperia10III defaultuser]# vi /usr/local/bin/phone-wake-up

#!/bin/bash
#Datei /usr/local/bin/phone-wake-up
#start Android App Support
dbus-send --system --dest=com.jolla.apkd.control --print-reply /com/jolla/apkd com.jolla.apkd.control.controlService boolean:true
`
(Disabling in the evening analog.)

ziellos · 10 October 2023 18:45

Restarting App support is what I do, too, but my script calls systemctl start/stop aliendalvik.service.
Is your method more stable? Doesn’t it do the same internally?

Speedy-10 · 11 October 2023 13:59

I can’t tell that. The line above I was told in another issue.

direc85 · 11 October 2023 17:07

Right, the clock is ticking for me to restart my phone because I’ll be needing the banking apps soon. I guess I have at least until the end of the week.

Has anyone found anything interesting in the logs? (I didn’t, other than what I already wrote.) Is there something else I could provide logs about? I’d like to know if this is because of the sound issue, for example…

direc85 · 16 October 2023 10:25

I had to finally reboot my phone to re-enable Android support, so I can’t provide any logs any more (until it happens again).

My plan is to restart Android app support once per week or so to see if that helps keeping it in working order. I’m also interested to test if the audio-dies-and-video-freezes bug affects this.

ziellos · 2 November 2023 08:01

The issue happened again today, and guess what: 24 days of uptime.
If there is anything I can do to investigate: let me know.
Otherwise I will have to reboot soon.

ziellos · 2 November 2023 09:08

All I could find was the following:

[root@Xperia home]# systemctl status lxc@multi-user.service  
● lxc@multi-user.service - LXC Container: multi-user
   Loaded: loaded (/usr/lib/systemd/system/lxc@.service; disabled; vendor preset: enabled)
   Active: failed (Result: exit-code) since Thu 2023-11-02 10:03:06 CET; 5s ago
     Docs: man:lxc-start
           man:lxc
  Process: 8162 ExecStart=/usr/bin/lxc-start -F -n multi-user (code=exited, status=1/FAILURE)
 Main PID: 8162 (code=exited, status=1/FAILURE)

Nov 02 10:03:06 Xperia systemd[1]: Started LXC Container: multi-user.
Nov 02 10:03:06 Xperia lxc-start[8162]: lxc-start: multi-user: tools/lxc_start.c: main: 268 No container config specified
Nov 02 10:03:06 Xperia systemd[1]: lxc@multi-user.service: Main process exited, code=exited, status=1/FAILURE
Nov 02 10:03:06 Xperia systemd[1]: lxc@multi-user.service: Failed with result 'exit-code'.

direc85 · 2 November 2023 09:30

I’m currently at 15 days and I have restarted Android app support every 3-4 days or so. So far so good.

Have you had the sound dies bug during that time?

ziellos · 2 November 2023 09:42

No, that bug didn’t occur this time.

To me the “No container config specified” error message seems to indicate that some config files disapperated. Could it be that some mount isn’t available anymore?

To be honest, I don’t know where to look…

direc85 · 11 November 2023 16:58

After 25 days - pretty much on the dot - sound stopped working. I grabbed logs but I’ve not gone through them yet. After restarting pulseaudio a few times and testing sound, restarting Android app support doesn’t work anymore. This time the AAS had “uptime” of about 5 days, I’ve been restarting it every once in a while.

It does look like they are linked, according to today. I did have a long-ish Bluetooth headset music listening session before I noticed that, but so I have had many times before.

Also, GPS was not used during the 25 days, it remained disabled the whole time.

nas · 13 November 2023 11:59

Today I had the exact same thing with uptime 25 or 29 days - not sure exactly. I had Phone app stuck and I restarted Home Screen from Settings → Utilities. Android apps weren’t starting after that, and android support showed that it failed to restart.

Xperia 10 iii , build 4.5.0.24

@direc85 I too have sound problems too some times, I do a
systemctl --user restart pulseaudio from times to times, sound starts working again without android support restart

yura.beznos · 13 November 2023 15:44

Android app support not able to start after a long uptime on Xperia XA2 Plus also.