[Sony Xperia 1 V] HD2Owner:
hi

On the japanese variant of xperia 1 V (SO-51D) there is a fm-radio app present.

I downloaded and compared the japanese firmware (SO-51D_NTT DoCoMo JP_67.1.B.2.154) with the european firmware (XQ-DQ54_Customized EU-UK_67.1.A.2.220).

To my surprise, the fmradio app is present in both firmwares under system_ext_a.img->app->Radio4->Radio4.apk.

On the european firmware, it does not appear in applist so i thought why not try to install/enable it.

following command makes the app appear in the applist:

adb shell pm install-existing com.sonymobile.fmradio

When starting it, it crashes. when headphones are not connected it starts with a warning about headphone needs to be connected.

When connecting the headphones, the app does not crash and lets me change output (speaker/headphones).

As soon as i enable the radio with the button, it crashes again.

View attachment 6083057

So i had a look at the logs but i am not very experienced so i am hoping someone is maybe able to help to identify the issue.

I hope it's not a hardware issue but merely another apk or service missing.

here is the log content:

03-22 14:43:00.858 10381 14380 14380 E FMR : QcomMain.enableRadio() FMService /QcomMain enableRadio is called mService = com.sonymobile.fmradio.qcom.FMRadioService$ServiceStub@fbab090
03-22 14:43:00.858 10381 14380 14380 E FMR : QcomMain.enableRadio() FMService /QcomMain enableRadio isFmOn = false
03-22 14:43:00.858 10381 14380 14380 E FMR : QcomMain.isAntennaAvailable() FMService /QcomMain isAntennaAvailable ret = true
03-22 14:43:00.859 10381 14380 14380 E FMRadio : FmReceiver constructor
03-22 14:43:00.859 10381 14380 14380 D FmReceiverJNI: init native called
03-22 14:43:00.859 10381 14380 14380 I android_hardware_fm: Init native called
03-22 14:43:00.859 10381 14380 14380 I android_hardware_fm: Initializing the FM HAL module & registering the JNI callback functions...
03-22 14:43:00.859 10381 14380 14380 D radio_helium: ++hal_init
03-22 14:43:00.859 10381 14380 14380 E radio_helium: hal_init:HAL is still available wait for last hal session to close
03-22 14:43:01.080 gps 3470 5074 D LOWI-9.0.1.28: [LOWI-Scan] wait_event:Wait done with Cmd 103
03-22 14:43:01.080 gps 3470 5074 D LOWI-9.0.1.28: [LOWI-Scan] do_listen_events: Rcvd valid Netlink Cmd 0 Err 0
03-22 14:43:01.080 wifi 1676 5072 I WifiHAL : event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xd
03-22 14:43:01.080 1000 2376 2376 I cnss-daemon: nl80211 response handler invoked
03-22 14:43:01.081 1000 2376 2376 I cnss-daemon: nl80211_response_handler: cmd 103, vendorID 4980, subcmd 13 received
03-22 14:43:01.155 1041 1620 2796 D vui_dmgr_client: start: before read
03-22 14:43:01.155 1041 1620 2796 E vui_dmgr_client: start: Going to connect.
03-22 14:43:01.155 1041 1620 2796 E vui_dmgr_client: start: failed to connect to server -1:Connection refused, retry
03-22 14:43:01.262 10232 8699 8833 I Finsky:background: [40] nbv.run(365): Stats for Executor: bgExecutor pds@662e476[Running, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 1]
03-22 14:43:01.286 10232 8699 8833 I Finsky:background: [40] nbv.run(365): Stats for Executor: LightweightExecutor pds@2fb6a77[Running, pool size = 4, active threads = 0, queued tasks = 0, completed tasks = 9]
03-22 14:43:03.111 1000 2376 2376 I cnss-daemon: nl80211 response handler invoked
03-22 14:43:03.111 1000 2376 2376 I cnss-daemon: nl80211_response_handler: cmd 103, vendorID 4980, subcmd 13 received
03-22 14:43:03.112 gps 3470 5074 D LOWI-9.0.1.28: [LOWI-Scan] wait_event:Wait done with Cmd 103
03-22 14:43:03.112 wifi 1676 5072 I WifiHAL : event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xd
03-22 14:43:03.112 gps 3470 5074 D LOWI-9.0.1.28: [LOWI-Scan] do_listen_events: Rcvd valid Netlink Cmd 0 Err 0
03-22 14:43:03.155 1041 1620 2796 D vui_dmgr_client: start: before read
03-22 14:43:03.156 1041 1620 2796 E vui_dmgr_client: start: Going to connect.
03-22 14:43:03.156 1041 1620 2796 E vui_dmgr_client: start: failed to connect to server -1:Connection refused, retry
03-22 14:43:03.325 root 1651 1731 I android.hardware.thermal@2.0-service.qti-v2: thermal_sample_event: TZ:101 temp:40939
03-22 14:43:03.325 root 1651 1731 I android.hardware.thermal@2.0-service.qti-v2: Sensor Name:skintemp: 40.939. prev severity:0. cur severity:0 New severity:0
03-22 14:43:03.860 10381 14380 14380 E radio_helium: hal_init:FM Hci close is stuck kiiling the fm process
03-22 14:43:03.891 1000 1845 2330 E BpTransactionCompletedListener: Failed to transact (-32)
03-22 14:43:03.898 1002 1623 1623 E vendor.qti.hardware.fm@1.0-FmHci: FmIoctlDeathRecipient::serviceDied - Fm service died
03-22 14:43:03.898 1002 1623 1623 D vendor.qti.hardware.fm@1.0-FmHci: close:
03-22 14:43:03.898 1000 2184 11244 I MediaSessionStack: removeSession | record: com.sonymobile.fmradio/com.sonymobile.fmradio.qcom.FMRadioService (userId=0)
03-22 14:43:03.898 1000 2184 3968 I WindowManager: WIN DEATH: Window{b22f46e u0 com.sonymobile.fmradio/com.sonymobile.fmradio.ui.FmRadioActivity}
03-22 14:43:03.899 1000 2184 3968 W InputManager-JNI: Input channel object 'b22f46e com.sonymobile.fmradio/com.sonymobile.fmradio.ui.FmRadioActivity (client)' was disposed without first being removed with the input manager!
03-22 14:43:03.899 1000 2184 6775 I MediaSessionStack: removeSession | record: com.sonymobile.fmradio/Tag (userId=0)
03-22 14:43:03.899 1000 2184 6724 I ActivityManager: Process com.sonymobile.fmradio (pid 14380) has died: fg TOP
03-22 14:43:03.900 1000 2184 2869 I libprocessgroup: Successfully killed process cgroup uid 10381 pid 14380 in 0ms
03-22 14:43:03.900 1000 2184 10653 I ImeTracker: com.sonymobile.fmradio:89b17ac5: onRequestHide at ORIGIN_SERVER_HIDE_INPUT reason HIDE_REMOVE_CLIENT
03-22 14:43:03.900 1000 2184 10653 I ImeTracker: com.sonymobile.fmradio:89b17ac5: onCancelled at PHASE_SERVER_SHOULD_HIDE
03-22 14:43:03.900 1000 2184 3713 V MediaSessionRecord: Removing dead callback in pushSessionDestroyed, this=com.sonymobile.fmradio/Tag (userId=0), callback package=com.sonymobile.fmradio, exception=android.os.DeadObjectException
03-22 14:43:03.901 root 1427 1427 I Zygote : Process 14380 exited due to signal 9 (Killed)
03-22 14:43:03.904 1002 1623 14551 E vendor.qti.hardware.fm@1.0-FmHci: ioctl opcode 0x4c02 failed ret -1
03-22 14:43:03.904 1002 1623 14551 E vendor.qti.hardware.fm@1.0-FmHci: Exiting fmDoIoctlThread Worker thread
03-22 14:43:03.904 1002 1623 1623 D vendor.qti.hardware.fm@1.0-FmHci: Skipping unlink call, service died.
03-22 14:43:03.904 1002 1623 1623 D vendor.qti.hardware.fm@1.0-FmHci: close done for ioctl based hal
03-22 14:43:03.913 1000 2184 6724 D OomAdjuster: Not killing cached processes
03-22 14:43:03.914 1000 2184 3328 D CompatibilityChangeReporter: Compat change id reported: 265195908; UID 10381; state: ENABLED
03-22 14:43:03.915 1000 2184 6724 W ActivityTaskManager: Force removing ActivityRecord{d6108b9 u0 com.sonymobile.fmradio/.ui.FmRadioActivity t2609}: app died, no saved state
03-22 14:43:03.922 1000 1845 1845 E BpTransactionCompletedListener: Failed to transact (-32)
03-22 14:43:03.925 10328 3756 3783 E SingleInstanceRemoteListener: Failed remote call on null listener
03-22 14:43:03.927 10328 3756 3783 V WindowManagerShell: Transition requested: android.os.BinderProxy@5bae910 TransitionRequestInfo { type = 2, triggerTask = TaskInfo{userId=0 taskId=2609 displayId=0 isRunning=true baseIntent=Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.sonymobile.fmradio/.ui.FmRadioActivity } baseActivity=ComponentInfo{com.sonymobile.fmradio/com.sonymobile.fmradio.ui.FmRadioActivity} topActivity=ComponentInfo{com.sonymobile.fmradio/com.sonymobile.fmradio.ui.FmRadioActivity} origActivity=null realActivity=ComponentInfo{com.sonymobile.fmradio/com.sonymobile.fmradio.ui.FmRadioActivity} numActivities=1 lastActiveTime=75305 supportsMultiWindow=false resizeMode=0 isResizeable=false minWidth=-1 minHeight=-1 defaultMinSize=220 token=WCT{android.window.IWindowContainerToken$Stub$Proxy@5f84c09} topActivityType=1 pictureInPictureParams=null shouldDockBigOverlays=false launchIntoPipHostTaskId=-1 lastParentTaskIdBeforePip=-1 displayCutoutSafeInsets=null topActivityInfo=ActivityInfo{9d94e0e com.sonymobile.fmradio.ui.FmRadioActivity} launchCookies=[android.os.BinderProxy@bc0960] positionInParent=Point(0, 0) parentTaskId=-1 isFocused=true isVisible=true isVisibleRequested=true isSleeping=false topActivityInSizeCompat=false topActivityEligibleForLetterboxEducation= false topActivityLetterboxed= false isFromDoubleTap= false topActivityLetterboxVerticalPosition= -1 topActivityLetterboxHorizontalPosition= -1 topActivityLetterboxWidth=-1 topActivityLetterboxHeight=-1 locusId=null displayAreaFeatureId=1 cameraCompatControlState=hidden}, remoteTransition = null, displayChange = null, flags = 0 }
03-22 14:43:03.927 10490 7494 7494 I FEED : 1
03-22 14:43:03.927 10490 7494 7494 I FEED : 2
03-22 14:43:03.928 1000 2184 6724 W ProcessStats: Tracking association SourceState{68032c7 com.google.android.gms/10252 BFgs #5786} whose proc state 4 is better than process ProcessState{e02dd12 com.google.process.gservices/10252 pkg=com.google.android.gsf} proc state 6 (291 skipped)
03-22 14:43:03.928 1000 2184 2857 W ActivityManager: setHasOverlayUi called on unknown pid: 14380
03-22 14:43:03.928 1000 2184 2857 V WindowManager: Unknown focus tokens, dropping reportFocusChanged
03-22 14:43:03.930 1000 1845 1845 I BpBinder: onLastStrongRef automatically unlinking death recipients:
03-22 14:43:03.932 10251 7661 7661 I A : onStart
03-22 14:43:03.934 1000 2184 2855 D DisplayManagerService: Drop pending events for gone uid 10381
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: setPauseUIUpdate parameter `paused` is false
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: fillGapsWithPrediction flags:
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: Hotseat app child is: com.android.launcher3.views.DoubleShadowBubbleTextView{7f3360c VFED..CL. ......ID 0,0-151,241 #1} and isPredictedIcon() evaluates to: false
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: Hotseat app child is: com.android.launcher3.views.DoubleShadowBubbleTextView{64ddb55 VFED..CL. ......ID 151,0-302,241 #2} and isPredictedIcon() evaluates to: false
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: Hotseat app child is: com.android.launcher3.views.DoubleShadowBubbleTextView{b8796a VFED..CL. ......ID 302,0-453,241 #3} and isPredictedIcon() evaluates to: false
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: Hotseat app child is: com.android.launcher3.views.DoubleShadowBubbleTextView{5862e5b VFED..CL. ......ID 453,0-604,241 #4} and isPredictedIcon() evaluates to: false
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: Hotseat app child is: com.android.launcher3.views.DoubleShadowBubbleTextView{1a3a6f8 VFED..CL. ......ID 604,0-755,241 #5} and isPredictedIcon() evaluates to: false
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: Hotseat app child is: com.android.launcher3.views.DoubleShadowBubbleTextView{b5dd7d1 VFED..CL. ......ID 755,0-906,241 #18f} and isPredictedIcon() evaluates to: false
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: Hotseat app child is: com.android.launcher3.views.DoubleShadowBubbleTextView{b5cc236 VFED..CL. ......ID 906,0-1057,241 #4d} and isPredictedIcon() evaluates to: false
03-22 14:43:03.934 10490 7494 7494 D HotseatPredictionController: bindItems to hotseat: []
03-22 14:43:03.935 1041 1620 3708 D AHAL: FM: fm_get_parameters: 231: enter
03-22 14:43:03.935 1041 1620 3708 D AHAL: FM: fm_get_parameters: 238: exit
03-22 14:43:03.935 10251 7661 7661 I A : onResume
03-22 14:43:03.936 1000 2184 3328 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10502; state: DISABLED
03-22 14:43:03.936 1000 2184 2865 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10502; state: ENABLED
03-22 14:43:03.936 1041 1620 3708 D AHAL: FM: fm_get_parameters: 231: enter
03-22 14:43:03.937 1041 1620 3708 D AHAL: FM: fm_get_parameters: 238: exit
03-22 14:43:03.939 1041 1620 3708 D AHAL: FM: fm_get_parameters: 231: enter
03-22 14:43:03.939 1041 1620 3708 D AHAL: FM: fm_get_parameters: 238: exit
03-22 14:43:03.939 1041 1620 3708 I SonyDpLoopback: dp_loopback_get_parameters: 524: Getparameter(DP_LOOPBACK_VOLUME):0.750000
03-22 14:43:03.940 10364 7263 7354 I swiqisystemservice: AidlSwiqiAccessor: setMultiWindowMode +
03-22 14:43:03.940 media 1823 1855 I AidlSwiqi: setMultiWindowMode 0
03-22 14:43:03.940 10364 7263 7354 I swiqisystemservice: AidlSwiqiAccessor: setMultiWindowMode -
03-22 14:43:03.940 10364 7263 7354 I swiqisystemservice: AidlSwiqiAccessor: setIsEffectWhiteList +
03-22 14:43:03.940 media 1823 1855 I AidlSwiqi: setIsEffectWhiteList 0
03-22 14:43:03.940 10364 7263 7354 I swiqisystemservice: AidlSwiqiAccessor: setIsEffectWhiteList -
03-22 14:43:03.941 10327 6721 14726 I android_os_HwBinder: HwBinder: Starting thread pool for getting: vendor.semc.hardware.display@2.0::IDisplay/default
03-22 14:43:03.944 root 1427 1427 D Zygote : Forked child process 14901
03-22 14:43:03.947 10490 7494 7494 D BaseDepthController: mSurface is null and mCurrentBlur is: 23
03-22 14:43:03.950 10305 5773 14725 I android_os_HwBinder: HwBinder: Starting thread pool for getting: vendor.semc.system.idd@1.0::IIdd/default
03-22 14:43:03.952 10305 5773 14725 I android_os_HwBinder: HwBinder: Starting thread pool for getting: vendor.semc.system.idd@1.0::IIdd/default
03-22 14:43:03.953 1041 1620 3708 D AHAL: FM: fm_get_parameters: 231: enter
03-22 14:43:03.953 1041 1620 3708 D AHAL: FM: fm_get_parameters: 238: exit
03-22 14:43:03.955 1041 1620 3708 D AHAL: FM: fm_get_parameters: 231: enter
03-22 14:43:03.955 1041 1620 3708 D AHAL: FM: fm_get_parameters: 238: exit
03-22 14:43:03.956 1041 1620 3708 D AHAL: FM: fm_get_parameters: 231: enter
03-22 14:43:03.956 1041 1620 3708 D AHAL: FM: fm_get_parameters: 238: exit
03-22 14:43:03.956 1041 1620 3708 I SonyDpLoopback: dp_loopback_get_parameters: 524: Getparameter(DP_LOOPBACK_VOLUME):0.750000
03-22 14:43:03.957 10490 7494 7785 E OpenGLRenderer: Unable to match the desired swap behavior.
03-22 14:43:03.962 1041 1620 3708 D AHAL: FM: fm_get_parameters: 231: enter
03-22 14:43:03.962 1041 1620 3708 D AHAL: FM: fm_get_parameters: 238: exit
03-22 14:43:03.963 10502 14901 14901 E acy.fontmanager: Not starting debugger since process cannot load the jdwp agent.
03-22 14:43:03.964 1041 1620 3708 D AHAL: FM: fm_get_parameters: 231: enter
03-22 14:43:03.964 1041 1620 3708 D AHAL: FM: fm_get_parameters: 238: exit
03-22 14:43:03.964 1041 1620 3708 D AHAL: FM: fm_get_parameters: 231: enter
03-22 14:43:03.964 1041 1620 3708 D AHAL: FM: fm_get_parameters: 238: exit
03-22 14:43:03.965 1041 1620 3708 I SonyDpLoopback: dp_loopback_get_parameters: 524: Getparameter(DP_LOOPBACK_VOLUME):0.750000

please let me/us know if you understand what the issue could be. thank you.