[R&D] VOLTE for non-stock roms for XT1254 on Verizon

[translucentfocus]

Great thanks for adding those (plus the others from before) in. Hopefully a new build will give us different results for those of us that didn't achieve success initially. @translucentfocus do you mind going through your steps for installation, setting up, and making sure that volte is working on the most recent build? i want to be sure i followed the same procedure.

Not at all; it was a standard procedure. Reset stock rom in TWRP. Install LOS with root and pico gapps. Boot up. Went through setup wizard and never enabled wifi; it hung for while configuring the radio, but eventually came through. Like @NepoRood said, make sure you enable data from the phone settings dial *#*#4636#*#* from the phone app (top-right menu button). BTW, VoLTE worked for me without even having to do an initial reboot.

 

[translucentfocus]

what about using a different phone app for DTMF? does that help?

---------- Post added at 11:06 AM ---------- Previous post was at 11:03 AM ----------



I add those they seems to me extra loging, if they don't help on making things working they may on understanding way...

---------- Post added at 11:08 AM ---------- Previous post was at 11:06 AM ----------

Anyone else besides @translucentfocus manage to achieve success?

those not achieving success check the running service, use the kernel app log section to see it it has a shears option that can be used...

and produce some logs so we can cross check against working...

Would this help? Here's two logs, one for STOCK VoLTE outgoing call right after boot, and me navigating a DTMF call. (I pressed lots of extra buttons in the phone app figuring that would send more DTMF codes. The other log is for an incoming VoLTE call

 

[koftheworld]

Not at all; it was a standard procedure. Reset stock rom in TWRP. Install LOS with root and pico gapps. Boot up. Went through setup wizard and never enabled wifi; it hung for while configuring the radio, but eventually came through. Like @NepoRood said, make sure you enable data from the phone settings. BTW, VoLTE worked for me without even having to do an initial reboot.

Did you use flash the modem clear and newest radio files beforehand? what radio/boot package are you on? That's good to know re: reboot.

 

[translucentfocus]

Did you use flash the modem clear and newest radio files beforehand? what radio/boot package are you on? That's good to know re: reboot.

I didn't do any of that. Baseband screenshot attached. (I'm on stock right now due to work/dumping logs)

 

[koftheworld]

Ok I will do further checks on those..

let me know when you can, the behavior after removing the imssettings/ from vendor/app..

:good:

i put in another pull request for 2 additional stock lte files that i found as i was browsing through the structure. let me know.

 

[koftheworld]

I didn't do any of that. Baseband screenshot attached. (I'm on stock right due to work/dumping logs)

maybe that is why i didn't have volte. i went from cf's 1.0.7, flashed the clear modem and newest radio, and then installed los. i'll have to try later today after work. hopefully we'll have a new build by then to test too. btw, thank you for submitting so many logs and testing along the way. it's help a lot.

 

[fgl27]

i put in another pull request for 2 additional stock lte files that i found as i was browsing through the structure. let me know.

com.verizon.hardware.* are all present already they are common files, they are not volte only.

 

[koftheworld]

com.verizon.hardware.* are all present already they are common files, they are not volte only.

oh ok didn't realize it. i'll hold off on proposing any more changes until after an updated build is run.

 

[NepoRood]

New build is uploaded, get it Here

Also, @bhb27, it threw me this error, around 52 seconds in

ninja: error: 'vendor/motorola/quark/proprietary/vendor/app/ims/lib/arm/libimscamera_jni.so', needed by '/media/nepo/common/LOS14.1/target/product/quark/system/app/ims/lib/arm/libimscamera_jni.so', missing and no known rule to make it

I moved the app/ims folder to vendor/app/ims, and build completed...

 

[koftheworld]

New build is uploaded, get it Here

Also, @bhb27, it threw me this error, around 52 seconds in

ninja: error: 'vendor/motorola/quark/proprietary/vendor/app/ims/lib/arm/libimscamera_jni.so', needed by '/media/nepo/common/LOS14.1/target/product/quark/system/app/ims/lib/arm/libimscamera_jni.so', missing and no known rule to make it

I moved the app/ims folder to vendor/app/ims, and build completed...

probably was my fault in one of my pulls sorry.

 

[NepoRood]

probably was my fault in one of my pulls sorry.

No apology needed, lol. A PR has to be merged by the owner, just because you request, doesn't mean it was merged (that make sense?)

The culprit seems to be here

That path doesn't actually exist

 

[koftheworld]

No apology needed, lol. A PR has to be merged by the owner, just because you request, doesn't mean it was merged (that make sense?)

The culprit seems to be here

That path doesn't actually exist

yup. on my end in stock it is located in system/vendor/app/ims/lib/arm and system/vendor/lib. thank you btw for the new build. i'll try it out tonight. @bhb27 i made a pull request with changes to this call and one other that need to be in system/vendor.

 

[TheSt33v]

Tried the latest build. Still no dice. I followed TF's instructions exactly. I'll upload logs in a few minutes.

Looks to me like the action starts at 16:52:39.132 in radio.txt.

 

[koftheworld]

Tried the latest build. Still no dice. I followed TF's instructions exactly. I'll upload logs in a few minutes.

Looks to me like the action starts at 16:52:39.132 in radio.txt.

no volte here either. i'll take a look at the logs and see if what they say. apn settings don't seem right going in, but that shouldn't be a decider.

 

[koftheworld]

Tried the latest build. Still no dice. I followed TF's instructions exactly. I'll upload logs in a few minutes.

Looks to me like the action starts at 16:52:39.132 in radio.txt.

you notice this gem?

16:53:05.811 E/GsmCdmaPhone( 4988): [GsmCdmaPhone] NullPointerExceptionjava.lang.NullPointerException: Attempt to invoke interface method 'boolean org.codeaurora.internal.IExtTelephony.isEmergencyNumber(java.lang.String)' on a null object reference
04-07 16:53:05.813 D/GsmCdmaPhone( 4988): [GsmCdmaPhone] imsUseEnabled=false, useImsForEmergency=false, useImsForUt=false, isUt=false, imsPhone=Handler (com.android.internal.telephony.imsphone.ImsPhone) {c3b3295}, [B]imsPhone.isVolteEnabled()=false[/B], imsPhone.isVowifiEnabled()=false, imsPhone.isVideoEnabled()=false, imsPhone.getServiceState().getState()=3, useImsPrefer=false
04-07

 

[translucentfocus]

New build is uploaded, get it Here

Thanks! I tested it, and although it was much more difficult to get VoLTE to work thene previous builds (mainly because there is no IMS Settings I can use to crash the phone app which seemed to help restart things working), I did manage to make 1 outgoing call successfully and then browsed to several different websites. When VoLTE is working, incoming calls do not work at all as usual.

Update: Not sure if this is even relevant, but I can disable the 4G Voice toggle, yet the 'VoLTE Provisioned' toggle stays enabled... I'm guessing this is the way it is supposed to work, but just in case, I thought I'd add them

While I don't know what exactly got it to work for the 1 call I managed to make successfully, here are some of the things I did in no particular order that I recall:
-I had rebooted the phone
-Tried calls (phone wouldn't connect a call at all)
-Switched Preferred Network from Global to LTE/CDMA
-Enabled & disabled VoLTE in the *#4636#* menu
-Tried calls (phone still wouldn't connect a call at all)
-Enabled Data Roaming (I always do this because I figure it's one less thing that could get in the way... maybe this is one of the differences that is making mine work sometimes?)
-Enabled and disabled Airplane mode
-Eventually, I tried a call and it went through--to my surprise; I then kept the phone call going and proceeded to browse to many different websites

My apologies that I didn't do a better job of keeping track of exactly what I did... and I also didn't save logs from this test... I'll probably have to move back to stock for tomorrow as not being able to receive any calls at all is a problem for me. I'm going to try getting some logs of it working tonight, if possible ... but it takes me so long to get a successful VoLTE call sometimes after boot (jumping through hoops like above) that I'm afraid the logs may not be as useful.

 

[koftheworld]

Tried the latest build. Still no dice. I followed TF's instructions exactly. I'll upload logs in a few minutes.

Looks to me like the action starts at 16:52:39.132 in radio.txt.

noticed some entries in your log like:
01-03 13:30:53.472 1833 1833 W SystemConfig: org.xmlpull.v1.XmlPullParserException: Unexpected start tag in /system/etc/permissions/qcom_ims.xml: found exceptions, expected 'permissions' or 'config'
01-03 13:30:54.166 1833 1833 W PackageManager: Library not found: /system/framework/rcsimssettings.jar
01-03 13:30:54.287 1833 1833 W PackageManager: Library not found: /system/framework/com.verizon.ims.jar
01-03 13:30:54.920 1833 1833 W PackageManager: Library not found: /system/framework/com.motorola.ims.rcsmanager.jar
01-03 13:30:57.517 1833 2043 W PackageManager: Failed to parse /system/app/ims: Missing base APK in /system/app/ims
01-03 13:30:58.511 1833 1833 W PackageManager: Failed to migrate com.qualcomm.qti.rcsimsbootstraputil: Failed to execute migrate_app_data [null, com.qualcomm.qti.rcsimsbootstraputil, 0, 2]: -1

Does this mean we are not pulling in everything that we need (such as those .jar files) from stock?

 

[translucentfocus]

IMSRegistration Stock ROM

So, I've started reading my own logs, and found that in my radio log of the stock ROM, there are several lines that say isImsRegistered=false, so I looked through until I found the first time it says isImsRegistered=true... I think the logs below show the phone successfully registering IMS with Verizon, but I need someone smarter than I to look and see if they can get anything useful from it.

Stock ROM Radio Log

04-07 09:33:44.095 D/PhoneBase( 4126): isImsRegistered =false
04-07 09:33:44.095 D/RIL_ImsSms( 4126): IMS is NOT registered!
04-07 09:33:44.096 D/DCT     ( 4126): [0]getDataOnRoamingEnabled: phoneSubId=1 isDataRoamingEnabled=true
04-07 09:33:44.097 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.097 D/DCT     ( 4126): [0]handleMessage msg={ when=-28ms what=270376 arg1=1 obj=NONE target=com.android.internal.telephony.dataconnection.DcTracker }
04-07 09:33:44.097 D/DCT     ( 4126): [0]overall state is CONNECTED
04-07 09:33:44.098 D/DCT     ( 4126): [0]overall state is CONNECTED
04-07 09:33:44.098 D/CDMALTEPhone( 4126): getDataConnectionState apnType=default ret=CONNECTED
04-07 09:33:44.099 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.099 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.099 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.099 D/DCT     ( 4126): [0]setActivity = NONE
04-07 09:33:44.099 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.100 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.101 D/CDMALTEPhone( 4126): getDataConnectionState apnType=default ret=CONNECTED
04-07 09:33:44.102 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.103 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.103 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.104 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.104 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.105 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.106 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.106 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.106 D/CdmaSST ( 4126): [CdmaLteSST] SST.getAllCellInfo(): return last, back to back calls
04-07 09:33:44.106 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.106 D/CdmaSST ( 4126): [CdmaLteSST] SST.getAllCellInfo(): X size=3 list=[CellInfoLte:{mRegistered=YES mTimeStampType=oem_ril mTimeStamp=25185448530ns CellIdentityLte:{ mMcc=311 mMnc=480 mCi=43687939 mPci=131 mTac=43528} CellSignalStrengthLte: ss=19 rsrp=-105 rsrq=-11 rssnr=2147483647 cqi=2147483647 ta=2147483647}, CellInfoLte:{mRegistered=NO mTimeStampType=oem_ril mTimeStamp=25185448530ns CellIdentityLte:{ mMcc=2147483647 mMnc=2147483647 mCi=2147483647 mPci=223 mTac=2147483647} CellSignalStrengthLte: ss=15 rsrp=-102 rsrq=-10 rssnr=2147483647 cqi=2147483647 ta=2147483647}, CellInfoLte:{mRegistered=NO mTimeStampType=oem_ril mTimeStamp=25185448530ns CellIdentityLte:{ mMcc=2147483647 mMnc=2147483647 mCi=2147483647 mPci=58 mTac=2147483647} CellSignalStrengthLte: ss=14 rsrp=-111 rsrq=-17 rssnr=2147483647 cqi=2147483647 ta=2147483647}]
04-07 09:33:44.106 D/CdmaSST ( 4126): [CdmaLteSST] SST.getAllCellInfo(): return last, back to back calls
04-07 09:33:44.106 D/CdmaSST ( 4126): [CdmaLteSST] SST.getAllCellInfo(): X size=3 list=[CellInfoLte:{mRegistered=YES mTimeStampType=oem_ril mTimeStamp=25185448530ns CellIdentityLte:{ mMcc=311 mMnc=480 mCi=43687939 mPci=131 mTac=43528} CellSignalStrengthLte: ss=19 rsrp=-105 rsrq=-11 rssnr=2147483647 cqi=2147483647 ta=2147483647}, CellInfoLte:{mRegistered=NO mTimeStampType=oem_ril mTimeStamp=25185448530ns CellIdentityLte:{ mMcc=2147483647 mMnc=2147483647 mCi=2147483647 mPci=223 mTac=2147483647} CellSignalStrengthLte: ss=15 rsrp=-102 rsrq=-10 rssnr=2147483647 cqi=2147483647 ta=2147483647}, CellInfoLte:{mRegistered=NO mTimeStampType=oem_ril mTimeStamp=25185448530ns CellIdentityLte:{ mMcc=2147483647 mMnc=2147483647 mCi=2147483647 mPci=58 mTac=2147483647} CellSignalStrengthLte: ss=14 rsrp=-111 rsrq=-17 rssnr=2147483647 cqi=2147483647 ta=2147483647}]
04-07 09:33:44.108 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.110 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.110 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.112 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.113 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.113 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.114 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.115 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.119 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.119 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.119 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.128 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.128 D/RILJ    ( 4126): [3876]> RIL_REQUEST_GSM_SET_BROADCAST_CONFIG with 2 configs :  [SUB0]
04-07 09:33:44.128 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4352,4354] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.128 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4356,4356] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.129 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.131 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.139 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.140 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.143 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.143 D/RILJ    ( 4126): [3876]< RIL_REQUEST_GSM_SET_BROADCAST_CONFIG  [SUB0]
04-07 09:33:44.144 D/RILJ    ( 4126): [3877]> RIL_REQUEST_GSM_BROADCAST_ACTIVATION [SUB0]
04-07 09:33:44.145 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.145 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.148 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.151 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.151 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.152 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.152 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.152 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.154 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.156 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.156 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.157 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.157 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.157 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.157 D/RILJ    ( 4126): [3877]< RIL_REQUEST_GSM_BROADCAST_ACTIVATION  [SUB0]
04-07 09:33:44.159 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.159 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.162 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.164 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.167 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.167 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.167 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.168 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.168 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.168 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.169 D/RILJ    ( 4126): [3878]> RIL_REQUEST_GSM_SET_BROADCAST_CONFIG with 3 configs :  [SUB0]
04-07 09:33:44.169 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4352,4354] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.169 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4356,4356] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.169 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4371,4372] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.171 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.173 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.173 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.173 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.174 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.174 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.176 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.180 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.180 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.181 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.181 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.181 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.183 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.185 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.185 D/RILJ    ( 4126): [3878]< RIL_REQUEST_GSM_SET_BROADCAST_CONFIG  [SUB0]
04-07 09:33:44.185 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.185 D/RILJ    ( 4126): [3879]> RIL_REQUEST_GSM_BROADCAST_ACTIVATION [SUB0]
04-07 09:33:44.186 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.186 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.186 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.189 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.191 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.191 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.193 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.193 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.194 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.196 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.197 D/RILJ    ( 4126): [3879]< RIL_REQUEST_GSM_BROADCAST_ACTIVATION  [SUB0]
04-07 09:33:44.197 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.198 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.198 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.198 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.199 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.200 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.202 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.202 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.202 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.202 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.203 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.204 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.206 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.206 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.207 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.207 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.207 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.208 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.209 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.209 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.211 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.212 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.212 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.212 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.213 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.213 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.213 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.213 D/RILJ    ( 4126): [3880]> RIL_REQUEST_CDMA_SET_BROADCAST_CONFIG with 1 configs :  [SUB0]
04-07 09:33:44.213 D/RILJ    ( 4126): CdmaSmsBroadcastConfigInfo: Id [4097, 4097] ENABLED [SUB0]
04-07 09:33:44.217 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.218 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.219 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.219 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.219 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.219 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.221 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.223 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.223 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.223 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.224 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.224 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.226 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.227 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.227 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.228 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.228 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.228 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.230 D/RILJ    ( 4126): [3880]< RIL_REQUEST_CDMA_SET_BROADCAST_CONFIG  [SUB0]
04-07 09:33:44.230 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.230 D/RILJ    ( 4126): [3881]> RIL_REQUEST_CDMA_BROADCAST_ACTIVATION [SUB0]
04-07 09:33:44.231 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.232 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.232 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.233 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.233 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.234 D/DctController( 4126): [TNF 1]passScoreCheck: Unsupported APN default
04-07 09:33:44.235 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.237 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.237 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.237 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.237 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.238 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.240 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.241 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.241 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.242 D/SubscriptionController( 4126): [getActiveSubInfoCount]+
04-07 09:33:44.242 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.242 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.243 D/RILJ    ( 4126): [3881]< RIL_REQUEST_CDMA_BROADCAST_ACTIVATION  [SUB0]
04-07 09:33:44.244 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.244 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.244 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.246 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.246 D/SubscriptionController( 4126): [getActiveSubInfoCount]- count: 1
04-07 09:33:44.246 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.249 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.250 D/RILJ    ( 4126): [3882]> RIL_REQUEST_GSM_SET_BROADCAST_CONFIG with 4 configs :  [SUB0]
04-07 09:33:44.250 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4352,4354] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.250 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4356,4356] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.250 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4371,4372] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.250 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4373,4378] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.267 D/RILJ    ( 4126): [3882]< RIL_REQUEST_GSM_SET_BROADCAST_CONFIG  [SUB0]
04-07 09:33:44.267 D/RILJ    ( 4126): [3883]> RIL_REQUEST_GSM_BROADCAST_ACTIVATION [SUB0]
04-07 09:33:44.276 D/SubscriptionController( 4126): [getActiveSubIdList] simInfoSet=[0=1]
04-07 09:33:44.276 D/SubscriptionController( 4126): [getActiveSubIdList] X subIdArr.length=1
04-07 09:33:44.278 D/SubscriptionController( 4126): [getActiveSubIdList] simInfoSet=[0=1]
04-07 09:33:44.279 D/SubscriptionController( 4126): [getActiveSubIdList] X subIdArr.length=1
04-07 09:33:44.281 D/RILJ    ( 4126): [3883]< RIL_REQUEST_GSM_BROADCAST_ACTIVATION  [SUB0]
04-07 09:33:44.283 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.297 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.299 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.300 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.301 D/RILJ    ( 4126): [3884]> RIL_REQUEST_CDMA_SET_BROADCAST_CONFIG with 2 configs :  [SUB0]
04-07 09:33:44.301 D/RILJ    ( 4126): CdmaSmsBroadcastConfigInfo: Id [4097, 4097] ENABLED [SUB0]
04-07 09:33:44.301 D/RILJ    ( 4126): CdmaSmsBroadcastConfigInfo: Id [4098, 4098] ENABLED [SUB0]
04-07 09:33:44.317 D/RILJ    ( 4126): [3884]< RIL_REQUEST_CDMA_SET_BROADCAST_CONFIG  [SUB0]
04-07 09:33:44.318 D/RILJ    ( 4126): [3885]> RIL_REQUEST_CDMA_BROADCAST_ACTIVATION [SUB0]
04-07 09:33:44.330 D/RILJ    ( 4126): [3885]< RIL_REQUEST_CDMA_BROADCAST_ACTIVATION  [SUB0]
04-07 09:33:44.341 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.341 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.344 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.346 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.347 D/RILJ    ( 4126): [3886]> RIL_REQUEST_GSM_SET_BROADCAST_CONFIG with 4 configs :  [SUB0]
04-07 09:33:44.347 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4352,4354] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.347 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4356,4356] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.347 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4371,4378] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.347 D/RILJ    ( 4126): SmsBroadcastConfigInfo: Id [4379,4379] Code [0,255] ENABLED [SUB0]
04-07 09:33:44.363 D/RILJ    ( 4126): [3886]< RIL_REQUEST_GSM_SET_BROADCAST_CONFIG  [SUB0]
04-07 09:33:44.363 D/RILJ    ( 4126): [3887]> RIL_REQUEST_GSM_BROADCAST_ACTIVATION [SUB0]
04-07 09:33:44.377 D/RILJ    ( 4126): [3887]< RIL_REQUEST_GSM_BROADCAST_ACTIVATION  [SUB0]
04-07 09:33:44.382 D/SubscriptionController( 4126): [getActiveSubInfoList]+
04-07 09:33:44.382 D/SubscriptionController( 4126): selection:sim_id>=0 null
04-07 09:33:44.384 D/SubscriptionController( 4126): [getSubInfoRecord] id:1 iccid:891480000XXXXXXXXXXX simSlotIndex:0 displayName:Verizon Wireless nameSource:0 iconTint:-16746133 dataRoaming:0 mcc:311 mnc:480 countIso:us
04-07 09:33:44.386 D/SubscriptionController( 4126): [getActiveSubInfoList]- 1 infos return
04-07 09:33:44.387 D/RILJ    ( 4126): [3888]> RIL_REQUEST_CDMA_SET_BROADCAST_CONFIG with 3 configs :  [SUB0]
04-07 09:33:44.387 D/RILJ    ( 4126): CdmaSmsBroadcastConfigInfo: Id [4097, 4097] ENABLED [SUB0]
04-07 09:33:44.387 D/RILJ    ( 4126): CdmaSmsBroadcastConfigInfo: Id [4098, 4098] ENABLED [SUB0]
04-07 09:33:44.387 D/RILJ    ( 4126): CdmaSmsBroadcastConfigInfo: Id [4099, 4099] ENABLED [SUB0]
04-07 09:33:44.402 W/SignalStrength( 4126): slotId received from ril is 0
04-07 09:33:44.403 V/RILJ    ( 4126): [UNSL]< UNSOL_OEM_HOOK_RAW 01030004200000000100040403a09a02840000000000000000000000000000000000000000000000 [SUB0]
04-07 09:33:44.403 D/RILJ    ( 4126): Oem ID in RIL_UNSOL_OEM_HOOK_RAW is (weird Unicode was here)
04-07 09:33:44.403 D/RILJ    ( 4126): External OEM message, to be notified
04-07 09:33:44.406 D/RILJ    ( 4126): [3888]< RIL_REQUEST_CDMA_SET_BROADCAST_CONFIG  [SUB0]
04-07 09:33:44.412 D/RILJ    ( 4126): [UNSL]< UNSOL_RESPONSE_IMS_NETWORK_STATE_CHANGED [SUB0]
04-07 09:33:44.419 D/RILJ    ( 4126): [UNSL]< UNSOL_RESPONSE_IMS_NETWORK_STATE_CHANGED [SUB0]
04-07 09:33:44.442 D/ImsManager( 4126): registrationConnected :: imsRadioTech=14
04-07 09:33:44.442 D/ImsPhoneCallTracker( 4126): [ImsPhoneCallTracker] onImsConnected imsradiotech=14
04-07 09:33:44.442 D/PHONE   ( 4126): [ServiceState] setVoiceRegState=0
04-07 09:33:44.442 E/PHONE   ( 4126): [ServiceState] setState deprecated use setVoiceRegState()
04-07 09:33:44.443 D/PHONE   ( 4126): [ServiceState] setDataRegState=0
04-07 09:33:44.443 D/PHONE   ( 4126): [ServiceState] setDataRadioTechnology=14
04-07 09:33:44.443 D/ImsPhone( 4126): updateDataServiceState: defSs = 1 0 1 voice home data home Verizon Wireless Verizon Wireless 311480 Verizon Wireless Verizon Wireless 311480  Unknown LTE CSS not supported 0 0 RoamInd=1 DefRoamInd=0 EmergOnly=false IsDataRoamingFromRegistration=false video Unknown imsSs = 0 0 3 voice home data home null null null null null null  Unknown LTE CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false video Unknown
04-07 09:33:44.443 D/ImsPhone( 4126): notifyImsRegistrationState: true
04-07 09:33:44.443 D/PhoneBase( 4126): isImsRegistered =true

 

[translucentfocus]

DTMF Related Logs from Working Stock ROM

I know that this isn't really a high priority right now, but since I noticed it, I wanted to go ahead and post it... I just grabbed the first DTMF because they all look so similar in both the radio and logcat logs.

Radio Log for one key press

04-07 09:34:27.062 D/ImsPhoneCallTracker( 4126): [ImsPhoneCallTracker] startDtmf
04-07 09:34:27.128 D/ImsPhoneCallTracker( 4126): [ImsPhoneCallTracker] stopDtmf

Logcat for one key press

04-07 09:34:27.060  1387  3195 I Telecom : Call: Send playDtmfTone to connection service for call [83140645, ACTIVE, com.android.phone/com.android.services.telephony.TelephonyConnectionService, tel:***********, A, ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, [6a4fde638efeb9098818d713759f7e38de3c2e72], UserHandle{0}, childs(0), has_parent(false), [[Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_DOWNGRADE_TO_VOICE_LOCAL CAPABILITY_SUPPORTS_DOWNGRADE_TO_VOICE_REMOTE]], -1, 1], [4e6a9d2a9f4256c121c4877d47f0f155eb5db497], 1
04-07 09:34:27.061  1387  3195 I Telecom : Event: Call 2: START_DTMF, [356a192b7913b04c54574d18c28d46e6395428ab]
04-07 09:34:27.062  4126  4126 I chatty  : uid=1001(radio) com.android.phone expire 18 lines
04-07 09:34:27.063   343   714 D audio_hw_primary: out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: routing=1
04-07 09:34:27.074   343   714 D audio_hw_primary: select_devices: out_snd_device(28: voice-handset) in_snd_device(0: none)
04-07 09:34:27.074   343   714 D msm8974_platform: platform_send_audio_calibration: sending audio calibration for snd_device(28) acdb_id(7)
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> send_audio_cal, acdb_id = 7, path =  0
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> send_adm_topology
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> send_asm_topology
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> send_audtable
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> AUDIO_SET_AUDPROC_CAL
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> send_audvoltable
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> AUDIO_SET_AUDPROC_VOL_CAL
04-07 09:34:27.074   343   714 D ACDB-LOADER: ACDB -> AUDIO_SET_AFE_CAL
04-07 09:34:27.074   343   714 D audio_hw_primary: enable_audio_route: apply and update mixer path: low-latency-playback
04-07 09:34:27.126  1387  3822 I Telecom : Call: Send stopDtmfTone to connection service for call [83140645, ACTIVE, com.android.phone/com.android.services.telephony.TelephonyConnectionService, tel:***********, A, ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, [6a4fde638efeb9098818d713759f7e38de3c2e72], UserHandle{0}, childs(0), has_parent(false), [[Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_DOWNGRADE_TO_VOICE_LOCAL CAPABILITY_SUPPORTS_DOWNGRADE_TO_VOICE_REMOTE]], -1, 1], [4e6a9d2a9f4256c121c4877d47f0f155eb5db497], 1
04-07 09:34:27.126  1387  3822 I Telecom : Event: Call 2: STOP_DTMF, null

More logcat for DTMF (maybe a different DTMF keypress?)

04-07 09:34:43.728  1387  3822 I Telecom : Call: Send playDtmfTone to connection service for call [83140645, ACTIVE, com.android.phone/com.android.services.telephony.TelephonyConnectionService, tel:***********, A, ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, [6a4fde638efeb9098818d713759f7e38de3c2e72], UserHandle{0}, childs(0), has_parent(false), [[Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_DOWNGRADE_TO_VOICE_LOCAL CAPABILITY_SUPPORTS_DOWNGRADE_TO_VOICE_REMOTE]], -1, 1], [4e6a9d2a9f4256c121c4877d47f0f155eb5db497], 1
04-07 09:34:43.728  1387  3822 I Telecom : Event: Call 2: START_DTMF, [ac3478d69a3c81fa62e60f5c3696165a4e5e6ac4]
04-07 09:34:43.729  4126  4126 I Telephony: CdmaConnection: sending dtmf digit directly
04-07 09:34:43.729  4126  4126 I ImsCall : startDtmf :: code=5 ImsCall=[ImsCall objId:128552731 onHold:N mute:N updateRequest:NONE merging:N merge action pending:N merged:N multiParty:N confHost:N buried term:N session:[ImsCallSession objId:222160170 state:ESTABLISHED callId:1] transientSession:null]
04-07 09:34:43.729  4126  4126 D ImsSenderRxr: [0075]> DTMF_START[SUB0]
04-07 09:34:43.729  4126  4126 D ImsSenderRxr: Message data: [0, 0, 0, 15, 11, 13, 75, 0, 0, 0, 16, 1, 24, 14, 32, 0, 10, 1, 53][SUB0]
04-07 09:34:43.738  6928  9564 I DeviceReconnectManager: Attempting to call listener.onReconnect
04-07 09:34:43.740   343   714 D audio_hw_primary: out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: routing=1
04-07 09:34:43.742  6928  9564 D Checkin : publish the event [tag = MOT_CCE event name = LOG]
04-07 09:34:43.742  4126  4898 V ImsSenderRxr: Read packet: 12 bytes
04-07 09:34:43.742  4126  4898 V ImsSenderRxr: processResponse[SUB0]
04-07 09:34:43.742  4126  4898 D ImsSenderRxr: Response data: [11, 13, 72, 0, 0, 0, 16, 2, 24, 15, 32, 0][SUB0]
04-07 09:34:43.742  4126  4898 D ImsSenderRxr:  Tag 72 2 15 0[SUB0]
04-07 09:34:43.742  4126  4898 D ImsSenderRxr: [0072]< DTMF_STOP [SUB0]

 

[translucentfocus]

noticed some entries in your log like:
01-03 13:30:53.472 1833 1833 W SystemConfig: org.xmlpull.v1.XmlPullParserException: Unexpected start tag in /system/etc/permissions/qcom_ims.xml: found exceptions, expected 'permissions' or 'config'
01-03 13:30:54.166 1833 1833 W PackageManager: Library not found: /system/framework/rcsimssettings.jar
01-03 13:30:54.287 1833 1833 W PackageManager: Library not found: /system/framework/com.verizon.ims.jar
01-03 13:30:54.920 1833 1833 W PackageManager: Library not found: /system/framework/com.motorola.ims.rcsmanager.jar
01-03 13:30:57.517 1833 2043 W PackageManager: Failed to parse /system/app/ims: Missing base APK in /system/app/ims
01-03 13:30:58.511 1833 1833 W PackageManager: Failed to migrate com.qualcomm.qti.rcsimsbootstraputil: Failed to execute migrate_app_data [null, com.qualcomm.qti.rcsimsbootstraputil, 0, 2]: -1

Does this mean we are not pulling in everything that we need (such as those .jar files) from stock?

It would seem that way to me... the Moto X Pure has them on their latest build of Lineage OS... and VoLTE works.