App is not always ringing for call

[greenraze13]

Issue

Pre-submission Checklist

• [yes ] I have verified that the issue occurs with the latest release and is not marked as a known issue in the CHANGELOG.md.
• [ yes] I reviewed the Common Issues and open GitHub issues and verified that this report represents a potentially new issue.
• [ yes] I am not sharing any Personally Identifiable Information (PII)
  or sensitive account information (API keys, credentials, etc.) when reporting this issue.

Description

User becomes online using the register function. When the app is closed and the phone is locked, calls are received successfully. However, if the user stays online and the app is killed while the phone is locked for a while (possibly an hour or more), the phone stops ringing, and the app does not open in Android.
Once the app is reopened and then closed again, the issue is resolved, and calls start ringing as expected.

I have a example sid
Twilio showing Queued status for this call
CAe1ef0aea3346cfa9702beb8c339ecfdc

Reproduction Steps

1. Go online on the device using the register function of the SDK.
2. Kill the app and lock the phone.
3. Test the call; it will be received normally.
4. Stay online for a while, possibly around 1 hour.
5. After that, calls are not received by the app.
6. Open the app and then try the call again; the call will be received.

Expected Behavior

If the app remains online for a while, possibly for 1 hour or more, calls should be received. The user will go online once and usually never go offline on the same day or for more extended periods, meaning they should receive calls at all times.

Actual Behavior

If the app remains online for a while, possibly for 1 hour or more, calls are not received when the app is killed and the phone is locked.

Reproduction Frequency

Yes

Screenshots

No

Software and Device Information

SDK version : 1.3.0
also tested with SDK version : 1.2.1

• Tested on multiple device
• Galaxy S9 with android 15

Additional Context

My app is live, and my users are complaining that their devices are not ringing. However, they can see the missed calls in the missed call list. This doesn't happen every time, but it is occurring intermittently, especially with users who go online and then never reopen the app.
I have noticed that both Android and iOS users have reported this issue, but I have only tested it on Android so far.

[greenraze13]

I have Checked the logs there is FATAL Exception on incoming call

ava.lang.RuntimeException: Unable to start service com.twiliovoicereactnative.VoiceService@1aa6fd with Intent { act=ACTION_FOREGROUND_AND_DEPRIORITIZE_INCOMING_CALL_NOTIFICATION cmp=com.linguists/com.twiliovoicereactnative.VoiceService (has extras) }: java.lang.NullPointerException
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:4781)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at android.app.ActivityThread.-$$Nest$mhandleServiceArgs(Unknown Source:0)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2247)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at android.os.Handler.dispatchMessage(Handler.java:106)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at android.os.Looper.loopOnce(Looper.java:201)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at android.os.Looper.loop(Looper.java:288)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at android.app.ActivityThread.main(ActivityThread.java:8046)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at java.lang.reflect.Method.invoke(Native Method)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:703)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:911)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: Caused by: java.lang.NullPointerException
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at java.util.Objects.requireNonNull(Objects.java:207)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.getCallRecord(VoiceService.java:390)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.onStartCommand(VoiceService.java:135)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:4763)
01-09 11:20:07.637 15384 15384 E AndroidRuntime: ... 9 more
01-09 11:20:16.587 15596 15596 E AndroidRuntime: FATAL EXCEPTION: main
01-09 11:20:16.587 15596 15596 E AndroidRuntime: Process: com.linguists, PID: 15596
01-09 11:20:16.587 15596 15596 E AndroidRuntime: java.lang.RuntimeException: Unable to start service com.twiliovoicereactnative.VoiceService@42f457 with Intent { act=ACTION_FOREGROUND_AND_DEPRIORITIZE_INCOMING_CALL_NOTIFICATION cmp=com.linguists/com.twiliovoicereactnative.VoiceService (has extras) }: java.lang.NullPointerException
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:4781)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at android.app.ActivityThread.-$$Nest$mhandleServiceArgs(Unknown Source:0)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2247)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at android.os.Handler.dispatchMessage(Handler.java:106)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at android.os.Looper.loopOnce(Looper.java:201)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at android.os.Looper.loop(Looper.java:288)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at android.app.ActivityThread.main(ActivityThread.java:8046)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at java.lang.reflect.Method.invoke(Native Method)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:703)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:911)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: Caused by: java.lang.NullPointerException
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at java.util.Objects.requireNonNull(Objects.java:207)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.getCallRecord(VoiceService.java:390)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.onStartCommand(VoiceService.java:135)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:4763)
01-09 11:20:16.587 15596 15596 E AndroidRuntime: ... 9 more
01-09 11:20:24.229 15692 15692 E AndroidRuntime: FATAL EXCEPTION: main
01-09 11:20:24.229 15692 15692 E AndroidRuntime: Process: com.linguists, PID: 15692
01-09 11:20:24.229 15692 15692 E AndroidRuntime: java.lang.RuntimeException: Unable to start service com.twiliovoicereactnative.VoiceService@45cd62 with Intent { act=ACTION_FOREGROUND_AND_DEPRIORITIZE_INCOMING_CALL_NOTIFICATION cmp=com.linguists/com.twiliovoicereactnative.VoiceService (has extras) }: java.lang.NullPointerException
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:4781)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at android.app.ActivityThread.-$$Nest$mhandleServiceArgs(Unknown Source:0)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2247)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at android.os.Handler.dispatchMessage(Handler.java:106)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at android.os.Looper.loopOnce(Looper.java:201)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at android.os.Looper.loop(Looper.java:288)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at android.app.ActivityThread.main(ActivityThread.java:8046)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at java.lang.reflect.Method.invoke(Native Method)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:703)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:911)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: Caused by: java.lang.NullPointerException
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at java.util.Objects.requireNonNull(Objects.java:207)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.getCallRecord(VoiceService.java:390)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.onStartCommand(VoiceService.java:135)
01-09 11:20:24.229 15692 15692 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:4763)

[greenraze13]

@bobiechen-twilio Hi, can you please check this? Your help will be appreciated.

[greenraze13]

I have checked in more detail

VoiceService.java

private static CallRecordDatabase.CallRecord getCallRecord(final UUID uuid) {
Log.d("VoiceService", "Fetching CallRecord for UUID: " + uuid);
try {
CallRecordDatabase.CallRecord callRecord = getCallRecordDatabase().get(new CallRecordDatabase.CallRecord(uuid));
return Objects.requireNonNull(callRecord, "CallRecord is null for UUID: " + uuid);
} catch (NullPointerException e) {
Log.e("VoiceService", "Failed to fetch CallRecord: " + e.getMessage());
return null; // or handle accordingly
}
}

the logs are like that

01-09 15:51:56.583 9266 9266 D VoiceService: Fetching CallRecord for UUID: 8ab544a0-5336-41b5-8aed-479bd18c83a3

01-09 15:51:56.584 9266 9266 E VoiceService: Failed to fetch CallRecord: CallRecord is null for UUID: 8ab544a0-5336-41b5-8aed-479bd18c83a3

here SDK is crashing

How I can solve that ?

[mhuynh5757]

Hi @greenraze13 thanks for bringing this to our attention. I'll let our Android teammate know and we'll dig into this together. Apologies for the delays, our team was on the holiday break.

[greenraze13]

@mhuynh5757 Thanks, I will be waiting for your response.

[greenraze13]

@mhuynh5757 Hi , Any update ?

[mhuynh5757]

Hi @greenraze13 sorry for the delayed response. I took a look into our codepaths that would potentially cause this issue and the problem is not immediately clear. Do you have other apps on your devices that also use the Twilio Voice React Native SDK? Also, do you have the crash logs for iOS as well? Thanks, again sorry for the delays.

[greenraze13]

@mhuynh5757 Thanks for your reply

Do you have other apps on your devices that also use the Twilio Voice React Native SDK?
I am not sure, maybe my workers are using another app. But can you please explain how this is related? If another application is using the Twilio SDK, could that cause an issue in my app?

Sentry shows these devices

Galaxy Note8
Galaxy S20
Galaxy A20e
Galaxy A20

[SM-N950F]
26.32%

[SM-G980F]
21.05%

[SM-A205U]
15.79%

[SM-A202F]
15.79%

[SM-M317F]
10.53%

[SM-G973F]
10.53%

do you have the crash logs for iOS as well
No I have no logs.Sentry only showing this issue on android devices

NullPointerException
Attempt to invoke virtual method 'java.util.UUID com.twiliovoicereactnative.g$a.p()' on a null object reference
RuntimeException
Unable to start service com.twiliovoicereactnative.VoiceService@3b3d3b1 with Intent { act=ACTION_FOREGROUND_AND_DEPRIORITIZE_INCOMING_CALL_NOTIFICATION cmp=com.linguists/com.twiliovoicereactnative.VoiceService (has extras) }: java.lang.NullPointerException: Attempt to invoke virtual method 'java.util.UUID com.twiliovoicereactnative.g$a.p()' on a null object reference

com.twiliovoicereactnative.VoiceService in p at line 13
com.twiliovoicereactnative.VoiceService in onStartCommand at line 302
android.app.ActivityThread in handleServiceArgs at line 4784
android.app.ActivityThread in access$2000 at line 301
android.app.ActivityThread$H in handleMessage at line 2204
android.os.Handler in dispatchMessage at line 106
android.os.Looper in loop at line 246
android.app.ActivityThread in main at line 8633
java.lang.reflect.Method in invoke
com.android.internal.os.RuntimeInit$MethodAndArgsCaller in run at line 602
com.android.internal.os.ZygoteInit in main at line 1130

[mhuynh5757]

My teammates and I had suspicions that the underlying native cache might potentially have conflicts if the SDK were used in multiple apps, but that seems very unlikely at this point. The error that you posted seems different than the last one, let me discuss with my teammate. Sorry for not being able to resolve this issue sooner, thank you for your patience and understanding!

[greenraze13]

@mhuynh5757 Hi Any update . Thanks

[mhuynh5757]

Hey @greenraze13 no update at this time, sorry. Are you still encountering this issue?

[greenraze13]

@mhuynh5757 Yes still facing that issue

These are today logs

6447 6447 E AndroidRuntime: FATAL EXCEPTION: main
03-15 15:30:42.420 6447 6447 E AndroidRuntime: Process: com.linguists, PID: 6447
03-15 15:30:42.420 6447 6447 E AndroidRuntime: java.lang.RuntimeException: Unable to start service com.twiliovoicereactnative.VoiceService@466f692 with Intent { act=ACTION_FOREGROUND_AND_DEPRIORITIZE_INCOMING_CALL_NOTIFICATION cmp=com.linguists/com.twiliovoicereactnative.VoiceService (has extras) }: java.lang.NullPointerException: Attempt to invoke virtual method 'java.util.UUID com.twiliovoicereactnative.CallRecordDatabase$CallRecord.getUuid()' on a null object reference
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5100)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at android.app.ActivityThread.-$$Nest$mhandleServiceArgs(Unknown Source:0)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2432)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at android.os.Handler.dispatchMessage(Handler.java:107)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at android.os.Looper.loopOnce(Looper.java:232)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at android.os.Looper.loop(Looper.java:317)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at android.app.ActivityThread.main(ActivityThread.java:8592)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at java.lang.reflect.Method.invoke(Native Method)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:580)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:878)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: Caused by: java.lang.NullPointerException: Attempt to invoke virtual method 'java.util.UUID com.twiliovoicereactnative.CallRecordDatabase$CallRecord.getUuid()' on a null object reference
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.foregroundAndDeprioritizeIncomingCallNotification(VoiceService.java:329)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.onStartCommand(VoiceService.java:141)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5082)
03-15 15:30:42.420 6447 6447 E AndroidRuntime: ... 9 more
03-15 15:30:44.432 9947 9947 E AndroidRuntime: FATAL EXCEPTION: main
03-15 15:30:44.432 9947 9947 E AndroidRuntime: Process: com.linguists, PID: 9947
03-15 15:30:44.432 9947 9947 E AndroidRuntime: java.lang.RuntimeException: Unable to start service com.twiliovoicereactnative.VoiceService@429d216 with Intent { act=ACTION_FOREGROUND_AND_DEPRIORITIZE_INCOMING_CALL_NOTIFICATION cmp=com.linguists/com.twiliovoicereactnative.VoiceService (has extras) }: java.lang.NullPointerException: Attempt to invoke virtual method 'java.util.UUID com.twiliovoicereactnative.CallRecordDatabase$CallRecord.getUuid()' on a null object reference
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5100)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at android.app.ActivityThread.-$$Nest$mhandleServiceArgs(Unknown Source:0)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2432)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at android.os.Handler.dispatchMessage(Handler.java:107)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at android.os.Looper.loopOnce(Looper.java:232)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at android.os.Looper.loop(Looper.java:317)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at android.app.ActivityThread.main(ActivityThread.java:8592)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at java.lang.reflect.Method.invoke(Native Method)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:580)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:878)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: Caused by: java.lang.NullPointerException: Attempt to invoke virtual method 'java.util.UUID com.twiliovoicereactnative.CallRecordDatabase$CallRecord.getUuid()' on a null object reference
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.foregroundAndDeprioritizeIncomingCallNotification(VoiceService.java:329)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at com.twiliovoicereactnative.VoiceService.onStartCommand(VoiceService.java:141)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5082)
03-15 15:30:44.432 9947 9947 E AndroidRuntime: ... 9 more
--------- beginning of system
03-15 15:32:06.954 1146 1637 I Lyric : zuma_votf_c2serv.cc:19: chip revision = 9, votf ip = GSE, register block id = 6
03-15 15:32:06.954 1146 1637 I Lyric : zuma_votf_c2serv.cc:23: num write dma = 0, num read dma = 2, process lines = 1
03-15 15:32:06.955 1146 1636 I Lyric : zuma_fe_resetter.cc:157: FrontEnd: SW Reset done: rstctl_status = 0x0
03-15 15:32:06.955 1146 1636 I Lyric : zuma_lmp_driver.cc:382: Configuring global system CSRs
03-15 15:32:06.955 1146 1636 I Lyric : zuma_csis_core_driver.cc:196: CSIS Core: ebuf_free_byte_size: 0x75800, ebuf_fifo_intf0_ctl0: 0x967b5c2e
03-15 15:32:06.955 1146 1636 I Lyric : per_camera_barrier_impl.cc:47: Blocker for FeControllerBlockCam8ToStartExp0#0 acquired
03-15 15:32:06.955 1146 1637 I Lyric : slc_driver.cc:58: SLC setup: pid = 5 : StrItf_YUVP_to_TNR : 3 ways (buf fd 243)
03-15 15:32:06.955 1146 1638 I Lyric : rgbp_driver.cc:449: Ip version: 0x1050001
03-15 15:32:06.955 1146 1638 I Lyric : mcfp_driver.cc:802: Ip version: 0x10050000
03-15 15:32:06.955 1146 1638 I Lyric : yuvp_driver.cc:529: Ip version: 0x1050001
03-15 15:32:06.955 1146 1638 I Lyric : zuma_be_core_driver_impl.cc:542: Successfully powered up BeCore block.
03-15 15:32:06.957 1146 1636 I Lyric : motion_sensor_controller_proxy.cc:290: MotionSensor context requests resource change to normal.
03-15 15:32:06.957 1146 10621 I Lyric : binder_sensor_vsync_reader.cc:40: Enabled BinderSensorVsyncReader, cam_vsync_id: 2
03-15 15:32:06.958 1146 1625 I Lyric : camera_powerhal.cc:172: SetPowerBoost Trigger CAMERA_LAUNCH_EXTENDED for 2000ms
03-15 15:32:06.958 1146 1625 I Lyric : multicam_node.cc:1289: ********************dpm: put camera: 8 on NORMAL, frame number: 0, zoom ratio: 1.00 ********************
03-15 15:32:06.958 1146 1625 I Lyric : multicam_node.cc:1467: ******************** new lead: camera: 8(FrontVirtual), frame number: 0, zoom ratio: 1.00 *******************
03-15 15:32:06.958 1146 1625 I Lyric : multicam_node.cc:2319: ====== Multicam status update ======
03-15 15:32:06.958 1146 1625 I Lyric : multicam_node.cc:2320: Frame number: 0, Lead cam: 8, Zoom ratio: 1.00, APP requested streams: (0 -> 32768, 8)
03-15 15:32:06.958 1146 1625 I Lyric : multicam_node.cc:2327: cam: 8, dpm_mode: Normal, activation_flags: None
03-15 15:32:06.958 1146 1625 I Lyric : retiming_node.cc:1308: ******** dpm: cam8_retiming change DPM mode from -1 to 0, is virtual transition: 0, framework request id 0, frame number: 0
03-15 15:32:06.958 1146 1627 I Lyric : multicam_node.cc:2319: ====== Multicam status update ======
03-15 15:32:06.958 1146 1627 I Lyric : multicam_node.cc:2320: Frame number: 1, Lead cam: 8, Zoom ratio: 1.00, APP requested streams: (0 -> 32768, 8)
03-15 15:32:06.958 1146 1627 I Lyric : multicam_node.cc:2327: cam: 8, dpm_mode: Undefined, activation_flags: None
03-15 15:32:06.961 1146 1637 I Lyric : slc_driver.cc:58: SLC setup: pid = 10 : GTNR iVOTF : 4 ways (buf fd 313)
03-15 15:32:06.961 1146 1637 I Lyric : slc_driver.cc:58: SLC setup: pid = 6 : GTNR Prev : 2 ways (buf fd 319)
03-15 15:32:06.961 1146 1637 W Lyric : zuma_gtnr_merge_driver_context.cc:138: Zuma GTNR Merge context is inherently async, but the requirement is set to sync. This will be ignored.
03-15 15:32:06.962 1146 1637 I Lyric : zuma_gtnr_merge_driver.cc:410: Successfully performed GTNR Merge SW reset.
03-15 15:32:06.962 1146 1637 I Lyric : sequence_helper.cc:221: Ip version: 0x0, common ctrl version: 0x55
03-15 15:32:06.962 1146 1637 I Lyric : zuma_gtnr_merge_driver.cc:203: Successfully powered up GTNR Merge device.
03-15 15:32:06.962 1146 1637 I Lyric : zuma_gtnr_merge_driver.cc:215: [CMU GTNR MERGE] pll_con0_mux(0x1cc00600): 16
03-15 15:32:06.962 1146 1637 I Lyric : tnr_merge_node.cc:411: Starting node cam8_tnr_merge with Pipeline (ID: (instance_id: 394341), IPs: [ RawToYuv TnrMerge ], Max Outputs: 1, IPConnections: [ {producer: RawToYuv, consumer: TnrMerge, connection_type: votf}, ) on camera 8
03-15 15:32:06.964 1146 1636 I Lyric : mesh_warp_node.cc:352: Starting node cam8_preview_mesh_warp with Pipeline (ID: (instance_id: 394342), IPs: [ MeshWarp Scaler ], Max Outputs: 1, IPConnections: [ {producer: MeshWarp, consumer: Scaler, connection_type: votf}, ) on camera 8
03-15 15:32:06.964 1146 1636 I Lyric : tuning_node.cc:728: The Tuning configuration deadline = {.config_deadline_frame_offset = -1, sof_to_config_deadline = -4.32ms}
03-15 15:32:06.964 1146 1636 I Lyric : generic_actuator_controller.cc:449: AF LTC: act-front-normal is enabled with software LTC
03-15 15:32:06.964 1146 1636 W Lyric : auto_focus_node.cc:678: cam8_af failed to acquire eco context: UNAVAILABLE: Not able to create valid eco proxy client
03-15 15:32:06.964 1146 1636 I Lyric : auto_focus_node.cc:737: cam8_af dcc data type: Normal
03-15 15:32:06.964 1146 1636 I Lyric : auto_focus_node.cc:757: The isp_input_raw_image_size = {width: 1720, height: 1224}; camera_id = 8; camera_app_1p_enabled = 0; session id = 7931248115272554496
03-15 15:32:06.964 1146 1636 I Lyric : external_auto_focus_controller_proxy.cc:422: ExternalAutoFocusControllerProxy [3A Coordinator]: Creating context for camera 8; long query warning threshold 250us; long set focus warning threshold 500us
03-15 15:32:06.964 1146 1636 I G3ACoordinator: coordinator.cc:913: AF data manager has already been registered.
03-15 15:32:06.964 1146 1636 W G3ACoordinator: coordinator.cc:930: Fail to register AF kCrossInstanceSharingPipelineId to the data manager.
03-15 15:32:06.964 1146 1636 I G3ACoordinator: af_engine.cc:257: [1, 8] AfEngine
03-15 15:32:06.964 1146 1637 I Lyric : slc_driver.cc:58: SLC setup: pid = 9 : StrItf_GDC0_to_MCSC : 3 ways (buf fd 324)
03-15 15:32:06.964 1146 1636 I G3ACoordinator: rls_manager.cc:419: RlsManager::Configure(): Clients for mode: 2 is unavailable
03-15 15:32:06.964 1146 1636 I G3ACoordinator: af_output_parser_lyric.cc:1416: TOF sensor power configuration -> 2
03-15 15:32:06.964 1146 1636 E G3ACoordinator: af_session_config_parser_lyric.cc:83: static_camera_model has no value unexpectedly.
03-15 15:32:06.964 1146 1636 I G3ACoordinator: af_engine.cc:285: InitializeAlgorithm
03-15 15:32:06.964 1146 1636 I G3ACoordinator: af_engine.cc:324: [1, 8] AfEngine_1_8 initialized
03-15 15:32:06.964 1146 1636 E G3ACoordinator: tuning_manager.h:62: Tuning key not found: Usecase: 0 Sensor mode: -1 Camera position: 2 Camera lens_type: 5 Camera spectrum: -1 Partial Usecases: {}
03-15 15:32:06.964 1146 1636 E G3ACoordinator: tuning_manager.h:62: Tuning key not found: Usecase: 0 Sensor mode: -1 Camera position: 2 Camera lens_type: 5 Camera spectrum: -1 Partial Usecases: {}
03-15 15:32:06.964 1146 1636 E G3ACoordinator: tuning_manager.h:62: Tuning key not found: Usecase: 0 Sensor mode: -1 Camera position: 2 Camera lens_type: 5 Camera spectrum: -1 Partial Usecases: {}
03-15 15:32:06.964 1146 1637 I Lyric : gdc_driver.cc:185: GDC Version: 16.5.0
03-15 15:32:06.964 1146 1637 I Lyric : mcsc_driver.cc:439: [CMU MCSC] pll_con0_mux(0x1d000610): 16
03-15 15:32:06.964 1146 1637 I Lyric : common_scaler_driver.cc:504: zuma-mcsc: successfully powered up, version: 16.5.0
03-15 15:32:06.964 1146 1637 I Lyric : scaler_node.cc:328: Starting node cam8_preview_scaler with Pipeline (ID: (instance_id: 394342), IPs: [ MeshWarp Scaler ], Max Outputs: 1, IPConnections: [ {producer: MeshWarp, consumer: Scaler, connection_type: votf}, ) on camera 8
03-15 15:32:06.964 1146 1637 I G3ACoordinator: awb_session_config_parser_lyric.cc:245: sensor_mode ptr [0xb400007161e81e18], sobek_enabled [false], post_process [0]
03-15 15:32:06.964 1146 1637 I G3ACoordinator: awb_session_config_parser_lyric.cc:285: Map sensor mode property: sobek_enabled [false], hdr_mode [0]
03-15 15:32:06.964 1146 1637 I G3ACoordinator: awb_session_config_parser_lyric.cc:210: Map sensor name [TAOTIE_FRONT_VIRTUAL] binning_factor [2 2] mode_index [3] to GCam sensor ID [3]
03-15 15:32:06.964 1146 1637 I G3ACoordinator: awb_session_config_parser_lyric.cc:145: Fail to read calibration data for camera[front normal]]. Use the default LED decision in tuning!
03-15 15:32:06.964 1146 1637 I G3ACoordinator: awb_engine.cc:408: Load lut file /apex/com.google.pixel.camera.hal/etc/camera/ghawb_para_lut_front_normal.binarypb
03-15 15:32:06.964 1146 1637 I G3ACoordinator: awb_engine.cc:461: Camera 8 Initial decision [1.6203332 1 2.0331297] CCT [3834] CCM [1.7444954 -0.6614475 -0.08304783, -0.2938606 1.521373 -0.22751236, -0.05308731 -0.7755066 1.828594, 0 0 0, 1->1]
03-15 15:32:06.965 1146 1636 I G3ACoordinator: ae_session_config_parser_lyric.cc:170: ispfe_raw_input_resolution [width:1720, height:1224]
03-15 15:32:06.965 1146 1636 I G3ACoordinator: ae_session_config_parser_lyric.cc:223: Map sensor mode property: sobek_enabled = false, g3a_session_info.hdr_mode = 0, sensor_mode_property 1
03-15 15:32:06.965 1146 1636 I G3ACoordinator: ae_session_config_parser_lyric.cc:235: Map sensor name [TAOTIE_FRONT_VIRTUAL], binning_factor [2, 2] and mode_index [3] to GCam sensor ID [3]
03-15 15:32:06.965 1146 1636 I G3ACoordinator: rls_manager.cc:443: RlsManager::PollAls(): Als is not supported
03-15 15:32:06.965 1146 1636 I G3ACoordinator: ae_engine.cc:258: session_type 0
03-15 15:32:06.965 1146 1636 I G3ACoordinator: ae_engine.cc:2405: GABC fatp_enable_ = 0
03-15 15:32:06.965 1146 1636 I G3ACoordinator: ae_engine.cc:332: [0, 8] AeEngine_0_8 initialized
03-15 15:32:06.965 1146 10631 I Lyric : direct_channel_motion_sensor_reader.cc:264: Enabled DirectChannelMotionSensorReader, type: gyroscope
03-15 15:32:06.965 1146 1636 W Lyric : auto_exposure_node.cc:1034: cam8_ae failed to acquire eco context: UNAVAILABLE: Not able to create valid eco proxy client
03-15 15:32:06.965 1146 1944 I Lyric : spectral_driver.cc:321: Flicker sampling has been enabled.
03-15 15:32:06.965 1146 10633 I Lyric : lwis_event_thread.cc:204: LwEv:TnrAl event thread is starting . . .
03-15 15:32:06.965 1146 1636 E Lyric : auto_exposure_node.cc:1070: cam8_ae: Failed to get tof context: INTERNAL: attempt to acquire context with no clients
03-15 15:32:06.966 1146 1636 I G3ACoordinator: auto_scene_detection_engine.cc:477: [9, 8] AutoSceneDetectionEngine_9_8 initialized
03-15 15:32:06.966 1146 10596 I Lyric : three_a_request_node.cc:637: cam8_three_a_request: updating 3A max FPS to 30
03-15 15:32:06.966 1146 10587 I Lyric : conditional_dpm_node.cc:66: cam8_conditional_dpm turn to dpm mode: Normal, framte number: 0
03-15 15:32:06.966 1146 1636 I Lyric : sensor_node.cc:424: SensorNode starting for sensor: TAOTIEFRONT, camera: Front
03-15 15:32:06.966 1146 1636 I Lyric : whi_rectiface_controller.cc:64: camera_hal_id 7 active array 3840 2736
03-15 15:32:06.966 1146 10591 W Lyric : auto_exposure_node.cc:801: 2 calls in 2379 sec(s): cam8_ae, failed to fetch display data on frame id: 0, status: INTERNAL: No cached display data.
03-15 15:32:06.966 1146 1636 I Lyric : face_detection_engine_face_ssd.cc:238: Face Detection: FaceDetectionEngineFaceSsd::Initialize::CreateDetector takes 12.614us
03-15 15:32:06.966 1146 10594 W Lyric : external_auto_focus_controller_proxy.cc:724: 3 calls in 685 sec(s): ExternalAutoFocusControllerProxy: camera_id = 8; The total number of frames missing CDAF, HW PDAF, SW PDAF and Sparse PDAF statistics is 1
03-15 15:32:06.966 1146 10594 W Lyric : external_auto_focus_controller_proxy.cc:1186: 3 calls in 685 sec(s): Not able to get shutter notification timestamp
03-15 15:32:06.966 1146 10594 E Lyric : external_auto_focus_controller_proxy.cc:1406: 3 calls in 685 sec(s): Not able to get shutter notification timestamp. No actuator data is collected.
03-15 15:32:06.966 1146 1636 I Lyric : common_front_end.h:63: The following contexts are now linked: CAM 8 CAM 7
03-15 15:32:06.966 1146 10591 I G3ACoordinator: ae_input_parser_lyric.cc:1871: Starter frame: rel_frame_num [-3]
03-15 15:32:06.966 1146 1636 I Lyric : unified_zuma_front_end_controller.cc:1000: [CAM7] Ready to acquire driver context for sensor TAOTIEFRONT, mode_index 3, csi-ch 6, at (3840x2736). With a normal lens at front position.

Can you give me an idea of why this issue might happen? Maybe I can check it on my side accordingly. The use case is very simple: I just call the Android device, it starts ringing, and on one click to accept, the app crashes. Sometimes it crashes automatically, or when the call is canceled.

[mhuynh5757]

Hey @greenraze13 thanks for the follow-up. We're working on trying to pinpoint the root cause on this one, but we don't have a timeline yet. I suspect it may have something to with multiple threads and race conditions. But nothing concrete yet. Apologies for the inconvenience.