Exceptions when starting the EC25 manually

Darran_Kelinske · April 22, 2020, 6:02pm

I am starting the EC25 manually using the following command:

/vendor/bin/hw/rild -l /vendor/lib64/libril-qc-qmi-1.so -l /vendor/lib64/libreference-ril.so &

When I do that I get the following error messages in the logs:

04-22 06:29:01.939 D/use-Rlog/RLOG-RIL RILC( 3639): QuectelCheckAndroidFrameworkSettingCallback
04-22 06:29:01.939 E/use-Rlog/RLOG-RIL RILC( 3639): Quectel Check: do you install /data/data/com.android.phone ?
04-22 06:29:01.939 E/use-Rlog/RLOG-RIL RILC( 3639): Quectel Check: do you install /system/priv-app/TeleService/TeleService.apk ?
04-22 06:29:01.939 E/use-Rlog/RLOG-RIL RILC( 3639): Quectel Check: do you install /data/data/com.android.providers.telephony ?
04-22 06:29:01.939 E/use-Rlog/RLOG-RIL RILC( 3639): Quectel Check: do you install /system/priv-app/TelephonyProvider/TelephonyProvider.apk ?
04-22 06:29:01.939 D/use-Rlog/RLOG-RIL RILC( 3639): [ro.radio.noril]: [yes]
04-22 06:29:01.939 E/use-Rlog/RLOG-RIL RILC( 3639): Quectel Check:  ro.radio.ril must be no, check files /*.prop or /system/*/prop
04-22 06:29:02.102 D/use-Rlog/RLOG-RIL RILC( 3639): settings get global mobile_data 1
04-22 06:29:02.154 D/use-Rlog/RLOG-RIL RILC( 3639): settings get global data_roaming 0
04-22 06:29:02.154 D/use-Rlog/RLOG-RIL RILC( 3639): [persist.radio.multisim.config]: [ssss]

Do I need to fix all of these for the EC25 to work properly on Android 9?

Kyson · April 23, 2020, 12:34am

Dear Darran,
Please refer to the following document to integrate the RIL into Android system. After integration completed, if it still have issues, please help to catch debug logs. Thanks!
Quectel_Android_RIL_Driver_User_Guide_V1.8.pdf (336.9 KB)

Darran_Kelinske · April 23, 2020, 12:43am

How do I get the debug logs?

Kyson · April 23, 2020, 12:52am

Please check the above document that i have send to you. Thanks!

Darran_Kelinske · April 23, 2020, 3:27am

This is the log after booting:

daz@daz:~/Downloads$ adb logcat -b radio -v time
01-02 10:38:12.317 D/RILD    (  606): **RIL Daemon Started**
01-02 10:38:12.317 D/RILD    (  606): **RILd param count=3**
01-02 10:38:12.352 D/RILD    (  606): RIL_Init argc = 3 clientId = 0
01-02 10:38:12.353 D/use-Rlog/RLOG-RIL RILC(  606): Quectel RIL Version: Quectel_Android_RIL_Driver_V2.9.6
01-02 10:38:12.353 D/use-Rlog/RLOG-RIL RILC(  606): libquectel-ril build info: On branch master Your branch is behind 'origin/master' by 12 commits, and can be fast-forwarded. 2456106f3e94775ab344a2254b6d65816e7c9e32 04/14/20_13:17:15 
01-02 10:38:12.353 D/use-Rlog/RLOG-RIL RILC(  606): Compiled date: Apr 14 2020 time: 13:17:19
01-02 10:38:12.353 E/use-Rlog/RLOG-RIL RIL_READ_CONF(  606): '/system/etc/ql-ril.conf' not exist.
01-02 10:38:12.359 D/use-Rlog/RLOG-RIL RILC(  606): t-prop_value is 1
01-02 10:38:12.359 D/use-Rlog/RLOG-RIL RILC(  606): before_dot is 9
01-02 10:38:12.359 D/use-Rlog/RLOG-RIL RILC(  606): [ro.build.version.release]: [9.0]
01-02 10:38:12.359 D/use-Rlog/RLOG-RIL RILC(  606): Android Version: 90, RIL_VERSION: 12 / 12
01-02 10:38:12.359 D/use-Rlog/RLOG-RIL RILC(  606): [ro.build.description]: [msm8937_64-user 8.1.0 OPM1.171019.019 236 release-keys]
01-02 10:38:12.359 D/use-Rlog/RLOG-RIL RILC(  606): [ro.hardware]: [qcom]
01-02 10:38:12.359 D/use-Rlog/RLOG-RIL RILC(  606): selinux maybe set Enforcing mode, use command getenforce to check
01-02 10:38:12.359 D/use-Rlog/RLOG-RIL RILC(  606): selinux maybe set Enforcing mode, use command "setenforce 0" to disable
01-02 10:38:12.359 I/use-Rlog/RLOG-RIL RILC(  606): clientID = 0
01-02 10:38:12.360 I/use-Rlog/RLOG-RIL RILC(  606): mainLoop Start
01-02 10:38:12.363 E/use-Rlog/RLOG-RIL RILU(  606): cannot find ttyname for AT Port
04-22 16:24:30.248 D/TelephonyManager(  618): No /proc/cmdline exception=java.io.FileNotFoundException: /proc/cmdline (Permission denied)
04-22 16:24:30.248 D/TelephonyManager(  618): /proc/cmdline=
04-22 16:24:30.392 D/use-Rlog/RLOG-RIL RILU(  606): find quectel module /sys/bus/usb/devices/1-1.1 idVendor=2c7c idProduct=0125
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): find /sys/bus/usb/devices/1-1.1:1.2/ttyUSB2
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): ttyAT = ttyUSB2
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): find /sys/bus/usb/devices/1-1.1:1.3/ttyUSB3
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): ttyPPP = ttyUSB3
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): find /sys/bus/usb/devices/1-1.1:1.0/ttyUSB0
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): ttyDM = ttyUSB0
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): find /sys/bus/usb/devices/1-1.1:1.1/ttyUSB1
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): ttyGPS = ttyUSB1
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): find /sys/bus/usb/devices/1-1.1:1.4/net/wwan0
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): usbnet_adapter = wwan0
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): find /sys/bus/usb/devices/1-1.1:1.4/usbmisc/cdc-wdm0
04-22 16:24:31.393 D/use-Rlog/RLOG-RIL RILU(  606): qmichannel = cdc-wdm0
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): Cannot access file:/dev/cdc-wdm0, errno = 2(No such file or directory)
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): patch for 'system/core/init/devices.cpp' is required!
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): ***********************************************************************************************
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): diff --git a/system/core/init/devices.cpp b/system/core/init/devices.cpp
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): index ada1e28..812c60d 100755
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): --- a/system/core/init/devices.cpp
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): +++ b/system/core/init/devices.cpp
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): @@ -407,6 +407,10 @@ void DeviceHandler::HandleDeviceEvent(const Uevent& uevent) {
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606):          int device_id = uevent.minor % 128 + 1;
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606):          devpath = StringPrintf("/dev/bus/usb/%03d/%03d", bus_id, device_id);
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606):          }
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): +#if 1 //add by quectel for mknod /dev/cdc-wdm0
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): +       } else if (uevent.subsystem == "usbmisc" && !uevent.device_name.empty()) {
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): +        devpath = "/dev/" + uevent.device_name;
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): +#endif
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606):      } else if (StartsWith(uevent.subsystem, "usb")) {
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606):          // ignore other USB events
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606):          return;
04-22 16:24:31.394 E/use-Rlog/RLOG-RIL RILU(  606): ***********************************************************************************************
04-22 16:24:31.394 D/use-Rlog/RLOG-RIL RILU(  606): NDIS = wwan0
04-22 16:24:31.394 D/use-Rlog/RLOG-RIL RILU(  606): netcard driver: qmi_wwan, driver version: 22-Aug-2005
04-22 16:24:31.394 D/use-Rlog/RLOG-RIL RILU(  606): qmiDev = 
04-22 16:24:31.395 D/use-Rlog/RLOG-RIL RILU(  606): for miss qmi dev. have to use qcrmcall_mode = 1
04-22 16:24:31.395 D/use-Rlog/RLOG-RIL RILC(  606): quectel at port is /dev/ttyUSB2
04-22 16:24:31.397 D/use-Rlog/RLOG-RIL RILC(  606): open device /dev/ttyUSB2 correctly
04-22 16:24:31.397 E/use-Rlog/RLOG-RIL ATC(  606): at_open s_tild_reader = 538925671664
04-22 16:24:31.397 D/use-Rlog/RLOG-RIL ATC(  606): AT> ATE0Q0V1
04-22 16:24:31.398 D/use-Rlog/RLOG-RIL ATC(  606): AT< ATE0Q0V1
04-22 16:24:31.400 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.900 D/use-Rlog/RLOG-RIL ATC(  606): AT> ATI
04-22 16:24:31.901 D/use-Rlog/RLOG-RIL ATC(  606): AT< Quectel
04-22 16:24:31.901 D/use-Rlog/RLOG-RIL ATC(  606): AT< EC25
04-22 16:24:31.901 D/use-Rlog/RLOG-RIL ATC(  606): AT< Revision: EC25AFAR05A04M4G
04-22 16:24:31.902 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.902 D/use-Rlog/RLOG-RIL RILC(  606): ql_product_version is EC25AFAR05A04M4G
04-22 16:24:31.902 D/use-Rlog/RLOG-RIL RILC(  606): Quectel Product Revision: EC25AFAR05A04M4G
04-22 16:24:31.902 D/use-Rlog/RLOG-RIL RILC(  606): exec_module_OMA_command Skip OMA asked by the customer
04-22 16:24:31.902 D/use-Rlog/RLOG-RIL RILC(  606): 9X07
04-22 16:24:31.902 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSUB;+CVERSION
04-22 16:24:31.903 D/use-Rlog/RLOG-RIL ATC(  606): AT< SubEdition: V06
04-22 16:24:31.903 D/use-Rlog/RLOG-RIL ATC(  606): AT< VERSION: EC25AFAR05A04M4G
04-22 16:24:31.903 D/use-Rlog/RLOG-RIL ATC(  606): AT< Aug 18 2017 17:10:17
04-22 16:24:31.903 D/use-Rlog/RLOG-RIL ATC(  606): AT< Authors: QCT
04-22 16:24:31.903 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.903 D/use-Rlog/RLOG-RIL ATC(  606): AT> ATE0Q0V1
04-22 16:24:31.907 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.907 D/use-Rlog/RLOG-RIL ATC(  606): AT> ATS0=0
04-22 16:24:31.908 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.909 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+QURCCFG="URCPORT","usbat"
04-22 16:24:31.910 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.910 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT&D2
04-22 16:24:31.911 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.911 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CMEE=1
04-22 16:24:31.912 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.912 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CREG=2
04-22 16:24:31.914 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.915 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGREG=2
04-22 16:24:31.916 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.916 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT^DSCI=1
04-22 16:24:31.917 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.917 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qdai=5
04-22 16:24:31.919 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:31.919 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CFUN?
04-22 16:24:31.921 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CFUN: 1
04-22 16:24:31.921 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.921 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CPIN?
04-22 16:24:31.924 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CPIN: READY
04-22 16:24:31.924 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.924 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CFUN?
04-22 16:24:31.926 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CFUN: 1
04-22 16:24:31.926 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.926 I/use-Rlog/RLOG-RIL RILC(  606): [setRadioState]:oldState=1, newState=2
04-22 16:24:31.926 W/RILC    (  606): RIL_onUnsolicitedResponse called before RIL_register
04-22 16:24:31.926 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CPIN?
04-22 16:24:31.926 D/RILD    (  606): RIL_Init rilInit completed
04-22 16:24:31.926 I/RILC    (  606): SIM_COUNT: 1
04-22 16:24:31.926 E/RILC    (  606): RIL_register: RIL version 12
04-22 16:24:31.926 I/RILC    (  606): s_registerCalled flag set, 1
04-22 16:24:31.926 D/RILC    (  606): registerService: starting android::hardware::radio::V1_1::IRadio slot1
04-22 16:24:31.927 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CPIN: READY
04-22 16:24:31.927 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.927 I/use-Rlog/RLOG-RIL RILC(  606): [setRadioState]:oldState=2, newState=4
04-22 16:24:31.929 I/RILC    (  606): RILHIDL called registerService
04-22 16:24:31.929 E/RILC    (  606): radioStateChangedInd: radioService[0]->mRadioIndication == NULL
04-22 16:24:31.929 D/RILD    (  606): RIL_Init RIL_register completed
04-22 16:24:31.929 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+QINISTAT
04-22 16:24:31.929 D/RILD    (  606): RIL_register_socket started
04-22 16:24:31.930 D/RIL_UIM_SOCKET(  606): Adding socket with id: 0
04-22 16:24:31.930 I/RILC    (  606): RIL_register_socket: calling registerService
04-22 16:24:31.930 D/RIL_SAP (  606): registerService: starting ISap slot1 for slotId 0
04-22 16:24:31.930 D/use-Rlog/RLOG-RIL ATC(  606): AT< +QINISTAT: 7
04-22 16:24:31.930 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.930 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSMS=1
04-22 16:24:31.931 D/RIL_SAP (  606): registerService: started ISap slot1 status 0
04-22 16:24:31.931 D/RILD    (  606): RIL_register_socket completed
04-22 16:24:31.931 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CSMS: 1,1,1
04-22 16:24:31.931 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.932 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CNMI=1,2,2,1,0
04-22 16:24:31.938 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.939 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CMGF=0
04-22 16:24:31.940 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.940 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CCWA=1
04-22 16:24:31.941 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.941 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CMOD=0
04-22 16:24:31.942 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.942 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CMUT=0
04-22 16:24:31.943 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:31.943 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSSN=0,1
04-22 16:24:31.944 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.944 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+COLP=0
04-22 16:24:31.945 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.945 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSCS="UCS2"
04-22 16:24:31.946 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.946 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CUSD=1
04-22 16:24:31.947 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.947 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGEREP=0
04-22 16:24:31.948 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.949 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGEREP=0
04-22 16:24:31.950 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.950 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CTZU=1
04-22 16:24:31.959 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.959 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CTZR=2
04-22 16:24:31.961 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.961 E/RILC    (  606): simStatusChangedInd: radioService[0]->mRadioIndication == NULL
04-22 16:24:31.961 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSQ
04-22 16:24:31.963 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CSQ: 22,99
04-22 16:24:31.963 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.963 E/RILC    (  606): currentSignalStrengthInd: radioService[0]->mRadioIndication == NULL
04-22 16:24:31.963 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGREG?
04-22 16:24:31.964 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CGREG: 2,1,"7879","0F18F02",7
04-22 16:24:31.964 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:31.964 E/RILC    (  606): networkStateChangedInd: radioService[0]->mRadioIndication == NULL
04-22 16:24:33.935 D/TelephonyManager(  620): No /proc/cmdline exception=java.io.FileNotFoundException: /proc/cmdline (Permission denied)
04-22 16:24:33.935 D/TelephonyManager(  620): /proc/cmdline=
04-22 16:24:33.989 E/TelephonyManager( 1157): IMSI error: Subscriber Info is null
04-22 16:24:35.251 D/TelephonyRegistry( 1157): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
04-22 16:24:35.627 D/TelephonyRegistry( 1157): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
04-22 16:24:36.170 W/CarrierConfigManager( 1339): Error getting config for subId -1 ICarrierConfigLoader is null
04-22 16:24:36.217 D/TelephonyRegistry( 1157): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
04-22 16:24:36.474 D/TelephonyRegistry( 1157): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
04-22 16:24:36.575 D/TelephonyRegistry( 1157): systemRunning register for intents
04-22 16:24:36.585 D/TelephonyRegistry( 1157): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
04-22 16:24:37.167 D/TelephonyRegistry( 1157): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
04-22 16:24:37.193 D/TelephonyRegistry( 1157): notifyCellLocationForSubscriber: subId=0 cellLocation=Bundle[{}]
04-22 16:24:37.348 D/TelephonyRegistry( 1157): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
04-22 16:24:41.969 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="aprstlevel"
04-22 16:24:41.986 D/use-Rlog/RLOG-RIL ATC(  606): AT< +QCFG: "ApRstLevel",1
04-22 16:24:41.986 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:41.986 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="modemrstlevel",0
04-22 16:24:42.009 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:42.009 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:42.011 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:43.012 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:43.016 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:44.017 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:44.019 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:45.020 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:45.022 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:46.025 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:46.029 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:46.822 I/ImsManager( 1576): Creating ImsService
04-22 16:24:46.822 W/MmTelFeatureConnection( 1576): create: binder is null! Slot Id: 0
04-22 16:24:47.031 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:47.032 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:48.033 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:48.035 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:49.037 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:49.040 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:50.043 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:50.047 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:51.049 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:51.053 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:51.965 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSQ
04-22 16:24:51.968 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CSQ: 16,99
04-22 16:24:51.968 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:51.968 E/RILC    (  606): currentSignalStrengthInd: radioService[0]->mRadioIndication == NULL
04-22 16:24:51.968 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGREG?
04-22 16:24:51.971 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CGREG: 2,1,"7879","0F18F02",7
04-22 16:24:51.971 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:24:52.053 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:52.056 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:53.058 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:53.062 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:54.063 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:54.066 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:55.069 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:55.074 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:56.076 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:56.080 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:57.083 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:57.088 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:58.091 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:58.094 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:24:59.097 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:24:59.102 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:25:00.105 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:25:00.108 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:25:01.111 D/use-Rlog/RLOG-RIL ATC(  606): AT> at+qcfg="dbgctl",1
04-22 16:25:01.116 D/use-Rlog/RLOG-RIL ATC(  606): AT< ERROR
04-22 16:25:02.119 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+QSCLK=1
04-22 16:25:02.128 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:25:11.972 D/use-Rlog/RLOG-RIL RILC(  606): QuectelCheckAndroidFrameworkSettingCallback
04-22 16:25:11.973 E/use-Rlog/RLOG-RIL RILC(  606): Quectel Check: do you install /data/data/com.android.phone ?
04-22 16:25:11.973 E/use-Rlog/RLOG-RIL RILC(  606): Quectel Check: do you install /system/priv-app/TeleService/TeleService.apk ?
04-22 16:25:11.973 E/use-Rlog/RLOG-RIL RILC(  606): Quectel Check: do you install /data/data/com.android.providers.telephony ?
04-22 16:25:11.973 E/use-Rlog/RLOG-RIL RILC(  606): Quectel Check: do you install /system/priv-app/TelephonyProvider/TelephonyProvider.apk ?
04-22 16:25:11.973 D/use-Rlog/RLOG-RIL RILC(  606): [ro.radio.noril]: [no]
04-22 16:25:12.063 D/use-Rlog/RLOG-RIL RILC(  606): settings get global mobile_data 1
04-22 16:25:12.113 D/use-Rlog/RLOG-RIL RILC(  606): settings get global data_roaming 0
04-22 16:25:12.113 D/use-Rlog/RLOG-RIL RILC(  606): [persist.radio.multisim.config]: [ssss]
04-22 16:25:12.113 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSQ
04-22 16:25:12.118 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CSQ: 16,99
04-22 16:25:12.118 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:25:12.118 E/RILC    (  606): currentSignalStrengthInd: radioService[0]->mRadioIndication == NULL
04-22 16:25:12.118 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGREG?
04-22 16:25:12.121 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CGREG: 2,1,"7879","0F18F02",7
04-22 16:25:12.121 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:25:32.143 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSQ
04-22 16:25:32.150 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CSQ: 16,99
04-22 16:25:32.150 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:25:32.150 E/RILC    (  606): currentSignalStrengthInd: radioService[0]->mRadioIndication == NULL
04-22 16:25:32.150 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGREG?
04-22 16:25:32.155 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CGREG: 2,1,"7879","0F18F02",7
04-22 16:25:32.155 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:25:52.165 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSQ
04-22 16:25:52.169 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CSQ: 16,99
04-22 16:25:52.169 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:25:52.169 E/RILC    (  606): currentSignalStrengthInd: radioService[0]->mRadioIndication == NULL
04-22 16:25:52.169 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGREG?
04-22 16:25:52.173 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CGREG: 2,1,"7879","0F18F02",7
04-22 16:25:52.173 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:26:12.195 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSQ
04-22 16:26:12.200 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CSQ: 19,99
04-22 16:26:12.200 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:26:12.200 E/RILC    (  606): currentSignalStrengthInd: radioService[0]->mRadioIndication == NULL
04-22 16:26:12.200 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGREG?
04-22 16:26:12.204 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CGREG: 2,1,"7879","0F18F02",7
04-22 16:26:12.204 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:26:32.225 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSQ
04-22 16:26:32.228 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CSQ: 19,99
04-22 16:26:32.229 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:26:32.229 E/RILC    (  606): currentSignalStrengthInd: radioService[0]->mRadioIndication == NULL
04-22 16:26:32.229 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGREG?
04-22 16:26:32.234 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CGREG: 2,1,"7879","0F18F02",7
04-22 16:26:32.234 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:26:52.253 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CSQ
04-22 16:26:52.257 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CSQ: 16,99
04-22 16:26:52.257 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK
04-22 16:26:52.257 E/RILC    (  606): currentSignalStrengthInd: radioService[0]->mRadioIndication == NULL
04-22 16:26:52.257 D/use-Rlog/RLOG-RIL ATC(  606): AT> AT+CGREG?
04-22 16:26:52.261 D/use-Rlog/RLOG-RIL ATC(  606): AT< +CGREG: 2,1,"7879","0F18F02",7
04-22 16:26:52.261 D/use-Rlog/RLOG-RIL ATC(  606): AT< OK

Kyson · April 23, 2020, 5:08am

What is your issue now ? The module cannot dial up successful ? From the log you provided, the module already register on network successful. Thanks!

Darran_Kelinske · April 28, 2020, 6:09pm

The answer is yes. All of the exceptions need to be fixed. I am currently trying to fix this issue:

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): Cannot access file:/dev/cdc-wdm0, errno = 2(No such file or directory)

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): patch for 'system/core/init/devices.cpp' is required!

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): ***********************************************************************************************

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): diff --git a/system/core/init/devices.cpp b/system/core/init/devices.cpp

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): index ada1e28..812c60d 100755

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): --- a/system/core/init/devices.cpp

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): +++ b/system/core/init/devices.cpp

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): @@ -407,6 +407,10 @@ void DeviceHandler::HandleDeviceEvent(const Uevent& uevent) {

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619):          int device_id = uevent.minor % 128 + 1;

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619):          devpath = StringPrintf("/dev/bus/usb/%03d/%03d", bus_id, device_id);

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619):          }

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): +#if 1 //add by quectel for mknod /dev/cdc-wdm0

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): +       } else if (uevent.subsystem == "usbmisc" && !uevent.device_name.empty()) {

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): +        devpath = "/dev/" + uevent.device_name;

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): +#endif

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619):      } else if (StartsWith(uevent.subsystem, "usb")) {

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619):          // ignore other USB events

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619):          return;

04-28 01:18:16.555 E/use-Rlog/RLOG-RIL RILU(  619): ***********************************************************************************************

Darran_Kelinske · April 28, 2020, 7:51pm

fixed that with this patch

Darran_Kelinske · April 28, 2020, 7:59pm

Now I am getting an LTE symbol ontop of the mobile network icon but mobile internet doesn’t work.

I noticed this in the log:

04-28 14:49:55.480 D/QtiDCT  ( 2917): [0]Reconnect alarm. Previous state was IDLE
04-28 14:49:55.481 D/QtiDCT  ( 2917): [0]onDataReconnect: currSubId = 1 phoneSubId=1
04-28 14:49:55.482 D/QtiDCT  ( 2917): [0]onDataReconnect: mState=IDLE reason=LOST_CONNECTION apnType=default apnContext={mApnType=default mState=CONNECTED mWaitingApns={[[ApnSettingV5] T-Mobile US LTE, 1193, 310260, fast.t-mobile.com, , http://mms.msg.eng.t-mobile.com/mms/wapenc, , , , -1, default | supl | mms | ia, IPV6, IPV4V6, true, 0, 0, 0, false, 0, 0, 0, 1440, , , false, 0, 0]} mApnSetting={[ApnSettingV5] T-Mobile US LTE, 1193, 310260, fast.t-mobile.com, , http://mms.msg.eng.t-mobile.com/mms/wapenc, , , , -1, default | supl | mms | ia, IPV6, IPV4V6, true, 0, 0, 0, false, 0, 0, 0, 1440, , , false, 0, 0} mReason=connected mDataEnabled=true mDependencyMet=true} mDataConnectionAsyncChannels={0=DC-1, 1=DC-2}
04-28 14:49:55.482 D/QtiDCT  ( 2917): [0]onDataReconnect: apnContext state=CONNECTED
04-28 14:49:55.483 D/QtiDCT  ( 2917): [0]onDataReconnect: keep associated
04-28 14:49:55.484 D/QtiDCT  ( 2917): [0]onTrySetupData: apnContext={mApnType=default mState=CONNECTED mWaitingApns={[[ApnSettingV5] T-Mobile US LTE, 1193, 310260, fast.t-mobile.com, , http://mms.msg.eng.t-mobile.com/mms/wapenc, , , , -1, default | supl | mms | ia, IPV6, IPV4V6, true, 0, 0, 0, false, 0, 0, 0, 1440, , , false, 0, 0]} mApnSetting={[ApnSettingV5] T-Mobile US LTE, 1193, 310260, fast.t-mobile.com, , http://mms.msg.eng.t-mobile.com/mms/wapenc, , , , -1, default | supl | mms | ia, IPV6, IPV4V6, true, 0, 0, 0, false, 0, 0, 0, 1440, , , false, 0, 0} mReason=LOST_CONNECTION mDataEnabled=true mDependencyMet=true}
04-28 14:49:55.487 D/QtiDCT  ( 2917): [0]isEmergency: result=false
04-28 14:49:55.488 D/QtiDCT  ( 2917): [0]trySetupData for APN type default, reason: LOST_CONNECTION. Data disallowed, reasons: APN_NOT_CONNECTABLE
04-28 14:49:55.489 D/QtiDCT  ( 2917): [0]isEmergency: result=false
04-28 14:49:55.520 I/chatty  ( 2917): uid=1001(radio) com.android.phone identical 6 lines
04-28 14:49:55.525 D/QtiDCT  ( 2917): [0]isEmergency: result=false
04-28 14:49:55.529 D/QtiDCT  ( 2917): [0]trySetupData failed. apnContext = [type=default, mState=CONNECTED, apnEnabled=true, mDependencyMet=true]

This is the entire log:

https://pastebin.com/45EVZ5Kh

Kyson · April 29, 2020, 1:21am

Dear Darran,
For your issues, it is better to discuss in one topic. Thanks!