An Android app fails to start

General
#1

I already created this topic in together.jolla.com but I will repeat the question here:
I am using Xperia 10/SFOS 3.3.0.16 (Rokua). I want to run Android app “Collins Bird Guide” which works fine on my Android. I can download and install it from Aptoide. Starting the app in SFOS always gives immediatelly an error saying “Failed to download expansion pack. Please uninstall and re-install the app”. I have uninstalled and re-installed the app but the result is the same. I checked the logs (journalctl) and found following rows appearing after starting the application:

***Aug 22 20:56:19 Xperia10-DualSIM systemd-udevd[21464]: conflicting device node ‘/dev/mapper/sailfish-home’ found, link to ‘/dev/dm-1’ will not be created ***

Aug 22 20:56:19 Xperia10-DualSIM systemd-udevd[21463]: inotify_add_watch(9, /dev/dm-3, 10) failed: No such file or directory

Aug 22 20:56:21 Xperia10-DualSIM invoker[5678]: WARNING: An inactive plugin is misbehaving - tried to show a window!

Aug 22 20:56:21 Xperia10-DualSIM invoker[5678]: WARNING: requestActivate() called for QQuickView(0x1866e38) which has Qt::WindowDoesNotAcceptFocus set.

Aug 22 20:56:45 Xperia10-DualSIM apkd[4855]: Launching com.natureguides.birdguide

Aug 22 20:56:45 Xperia10-DualSIM lipstick[5409]: [D] unknown:0 - Specified Desktop file does not exist “/usr/share/applications/system_server.desktop”

Aug 22 20:56:45 Xperia10-DualSIM invoker[5678]: WARNING: An inactive plugin is misbehaving - tried to show a window!

Aug 22 20:56:45 Xperia10-DualSIM invoker[5678]: WARNING: requestActivate() called for QQuickView(0x1866e38) which has Qt::WindowDoesNotAcceptFocus set.

?? Is there some solution to fix the above application errors. What should be used instead of “/usr/share/applications/system_server.desktop”

#2

Only a single journal entry is related to your Android app:

Aug 22 20:56:45 Xperia10-DualSIM apkd[4855]: Launching com.natureguides.birdguide

For analysing why an Android app fails to start or run properly, take a look at the Android logcat log. This is documented somewhere at TJC.

#3

There are more messages related to this. I tried to just read the logs. I am not an android or SFOS specialist so I did not even try reading android catlog logs but maybe I have to try it.

Each time I launch the application the following rows appear in the journal log:

Aug 26 09:34:20 Xperia10-DualSIM apkd[4855]: Launching com.natureguides.birdguide

Aug 26 09:34:21 Xperia10-DualSIM lipstick[5409]: [D] unknown:0 - Specified Desktop file does not exist “/usr/share/applications/system_server.desktop”

Aug 26 09:34:21 Xperia10-DualSIM invoker[5678]: WARNING: An inactive plugin is misbehaving - tried to show a window!

Aug 26 09:34:21 Xperia10-DualSIM invoker[5678]: WARNING: requestActivate() called for QQuickView(0x1866e38) which has Qt::WindowDoesNotAcceptFocus set.

Aug 26 09:34:23 Xperia10-DualSIM lipstick[5409]: [W] unknown:0 - WindowPixmapItem does not have a source texture, cover will be dropped…

#4

I return to my question once more. I cannot solve the problem myself and would like to get some help on the issue. I did view the logs during the start of the app. There are separate logs for SFOS and the Android included in this message. The logs are taken in two separate SSH-sessions after restarting the Xperia 10 because it seems to give a little more info on the first start. What actually happens is: The app starts, shows an error message in a popup window and after closing the message it minimizes to the desktop.

;#######################################################
SFOS-log: journalctl --follow

Sep 09 22:00:06 Xperia10-DualSIM apkd[5085]: Launching com.natureguides.birdguide
Sep 09 22:00:06 Xperia10-DualSIM lipstick[5698]: [D] unknown:0 - Specified Desktop file does not exist “/usr/share/applications/system_server.desktop”
Sep 09 22:00:07 Xperia10-DualSIM droid-hal-init: Received control message ‘interface_start’ for ‘android.hardware.configstore@1.0::ISurfaceFlingerConfigs/default_alien’ from pid: 3002 (/system/bin/hwservicemanager)
Sep 09 22:00:07 Xperia10-DualSIM droid-hal-init: Could not find service hosting interface android.hardware.configstore@1.0::ISurfaceFlingerConfigs/default_alien
Sep 09 22:00:07 Xperia10-DualSIM droid-hal-init: Received control message ‘interface_start’ for ‘android.hardware.graphics.mapper@2.0::IMapper/default_alien’ from pid: 3002 (/system/bin/hwservicemanager)
Sep 09 22:00:07 Xperia10-DualSIM droid-hal-init: Could not find service hosting interface android.hardware.graphics.mapper@2.0::IMapper/default_alien
Sep 09 22:00:12 Xperia10-DualSIM healthd: battery l=77 v=3989 t=30.0 h=2 st=2 c=171 chg=u
Sep 09 22:00:32 Xperia10-DualSIM lipstick[5698]: [W] unknown:0 - WindowPixmapItem does not have a source texture, cover will be dropped…
Sep 09 22:00:35 Xperia10-DualSIM kernel: NOHZ: local_softirq_pending 02

;#######################################################
Android log: /system/bin/logcat -v time
…
09-09 19:00:06.438 I/RQBalance-PowerHAL-Hints( 3259): Setting performance mode
09-09 19:00:07.123 I/RQBalance-PowerHAL-Hints( 3259): Setting balanced mode
09-09 19:00:32.679 E/RQBalance-PowerHAL-HALEXT( 3259): Unexpected argument. Bailing out.
09-09 19:00:32.679 D/RQBalance-PowerHAL-HALEXT( 3259): Arguments: 3
09-09 19:00:32.679 D/RQBalance-PowerHAL-HALEXT( 3259): Arg[0]: 0x101
09-09 19:00:32.679 D/RQBalance-PowerHAL-HALEXT( 3259): Arg[1]: 0x20e
09-09 19:00:32.679 D/RQBalance-PowerHAL-HALEXT( 3259): Arg[2]: 0x30e
09-09 19:00:32.679 D/RQBalance-PowerHAL-HALEXT( 3259): Arg[3]: 0x0
09-09 19:00:32.681 D/ACDB-LOADER( 5779): ACDB -> send_audio_cal, acdb_id = 101, path = 0, app id = 0x11130, sample rate = 48000
09-09 19:00:32.681 D/ACDB-LOADER( 5779): ACDB -> send_asm_topology
09-09 19:00:32.681 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> send_adm_topology
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> send_audtable
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> AUDIO_SET_AUDPROC_CAL
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> send_audvoltable
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_VOL_STEP_TABLE_SIZE
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0
09-09 19:00:32.683 D/ ( 5779): Failed to fetch the lookup information of the device 00000065
09-09 19:00:32.683 E/ACDB-LOADER( 5779): Error: ACDB AudProc vol returned = -19
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> AUDIO_SET_VOL_CAL cal type = 12
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_SIZE
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> send_audstrmtable
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 40
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> send_afe_topology
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 101, Topology Id 1025d
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> send_afe_cal
09-09 19:00:32.683 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE_SIZE
09-09 19:00:32.684 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE
09-09 19:00:32.684 D/ACDB-LOADER( 5779): ACDB -> AUDIO_SET_AFE_CAL
09-09 19:00:32.684 D/ACDB-LOADER( 5779): ACDB -> send_hw_delay : acdb_id = 101 path = 0
09-09 19:00:32.684 D/ACDB-LOADER( 5779): ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
09-09 19:00:32.684 D/hardware_info( 5779): hw_info_append_hw_type : device_name = speaker
09-09 19:00:32.684 D/audio_hw_primary( 5779): enable_snd_device: snd_device(2: speaker)
09-09 19:00:32.684 D/audio_route( 5779): Apply path: speaker
09-09 19:00:32.698 D/audio_hw_primary( 5779): enable_audio_route: usecase(0) apply and update mixer path: deep-buffer-playback
09-09 19:00:32.698 D/audio_route( 5779): Apply path: deep-buffer-playback
09-09 19:00:33.188 E/msm8916_platform( 5779): ramp_speaker_gain: Could not get ctl for mixer cmd - Left Speaker Gain or Right Speaker Gain, not applying speaker gain ramp
09-09 19:00:33.189 D/audio_hw_primary( 5779): out_write: retry previous failed cal level set
09-09 19:00:35.673 E/RQBalance-PowerHAL-HALEXT( 3259): Unexpected argument. Bailing out.
09-09 19:00:35.673 D/RQBalance-PowerHAL-HALEXT( 3259): Arguments: 3
09-09 19:00:35.673 D/RQBalance-PowerHAL-HALEXT( 3259): Arg[0]: 0x101
09-09 19:00:35.673 D/RQBalance-PowerHAL-HALEXT( 3259): Arg[1]: 0x20e
09-09 19:00:35.673 D/RQBalance-PowerHAL-HALEXT( 3259): Arg[2]: 0x30e
09-09 19:00:35.673 D/RQBalance-PowerHAL-HALEXT( 3259): Arg[3]: 0x0
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> send_audio_cal, acdb_id = 101, path = 0, app id = 0x11130, sample rate = 48000
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> send_asm_topology
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> send_adm_topology
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> send_audtable
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> AUDIO_SET_AUDPROC_CAL
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> send_audvoltable
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_VOL_STEP_TABLE_SIZE
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0
09-09 19:00:35.673 D/ ( 5779): Failed to fetch the lookup information of the device 00000065
09-09 19:00:35.673 E/ACDB-LOADER( 5779): Error: ACDB AudProc vol returned = -19
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> AUDIO_SET_VOL_CAL cal type = 12
09-09 19:00:35.673 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_SIZE
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> send_audstrmtable
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 40
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> send_afe_topology
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 101, Topology Id 1025d
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> send_afe_cal
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE_SIZE
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> AUDIO_SET_AFE_CAL
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> send_hw_delay : acdb_id = 101 path = 0
09-09 19:00:35.674 D/ACDB-LOADER( 5779): ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
09-09 19:00:35.674 D/audio_hw_primary( 5779): enable_audio_route: usecase(1) apply and update mixer path: low-latency-playback
09-09 19:00:35.674 D/audio_route( 5779): Apply path: low-latency-playback
09-09 19:00:35.700 E/msm8916_platform( 5779): ramp_speaker_gain: Could not get ctl for mixer cmd - Left Speaker Gain or Right Speaker Gain, not applying speaker gain ramp
09-09 19:00:35.700 D/audio_hw_primary( 5779): out_write: retry previous failed cal level set

;#######################################################