firefly-rk3399-Industry版本对usb挂载口是否有什么限制
请问你们的firefly-rk3399-Industry版本对usb挂载口是否有什么限制？我们的外接数据模块挂载的ttyUSB1-2两个口都有777权限，但是通过ril下发AT指令到Modem就是不成功。我们尝试在adb shell里用echo -e "AT+CNMP?\r\n" > /dev/ttyUSB1发送指令也没反应。以下是完整的radio.log：
09-24 10:28:12.154 D/RILD (300): **RIL Daemon Started**
09-24 10:28:12.154 D/RILD (300): **RILd param count=3**
09-24 10:28:12.179 W/RILD (300): RIL_SAP_Init not defined or exported in /system/lib64/libreference-ril.so: undefined symbol: RIL_SAP_Init
09-24 10:28:12.179 D/RILD (300): RIL_Init argc = 5 clientId = 0
09-24 10:28:12.179 D/RILC (300): Quectel RIL Version: Quectel_Android_RIL_SR01A40V36
09-24 10:28:12.179 D/RILC (300): :
09-24 10:28:12.179 E/RILC (300): Unsupport Android Version 71 by Quectel Now!!!!
09-24 10:28:12.179 D/RILC (300): Android Version: 71, RIL_VERSION: 12 / 12
09-24 10:28:12.179 D/RILC (300): :
09-24 10:28:12.179 D/RILC (300): :
09-24 10:28:12.179 D/RILC (300): : [-d /dev/ttyUSB1]
09-24 10:28:12.179 D/RILC (300): selinux maybe set Enforcing mode, use command getenforce to check
09-24 10:28:12.179 D/RILC (300): selinux maybe set Enforcing mode, use command "setenforce 0" to disable
09-24 10:28:12.180 I/RILC (300): Opening tty device /dev/ttyUSB1
09-24 10:28:12.180 I/RILC (300): clientID = 0
09-24 10:28:12.180 D/RILC (300): you designate /dev/ttyUSB1 as AT port
09-24 10:28:12.180 D/RILC (300): i guess you designate a usb port as AT port
09-24 10:28:12.181 D/RILC (300): and the usb devices's idVendor = 2c7c, idProduct = 6026
09-24 10:28:12.181 D/RILD (300): RIL_Init rilInit completed
09-24 10:28:12.181 I/RILC (300): SIM_COUNT: 1
09-24 10:28:12.181 E/RILC (300): RIL_register: RIL version 12
09-24 10:28:12.181 I/RILC (300): s_registerCalled flag set, 1
09-24 10:28:12.181 I/RILC (300): Start to listen RIL_SOCKET_1
09-24 10:28:12.181 D/RILD (300): RIL_Init RIL_register completed
09-24 10:28:12.181 D/RILD (300): RIL_register_socket completed
09-24 10:28:12.181 D/RILD (300): RIL_Init starting sleep loop
09-24 10:28:12.192 I/RILC (300): mainLoop Start
09-24 10:28:12.192 D/MUXD (300): MUXD 3062:gsm0710muxd(): gsm0710muxd serialname = /dev/ttyUSB1, gsm0710muxd_pid = 0
09-24 10:28:12.199 D/MUXD (323): MUXD 2906:main(): Quectel CMUX Revision: Quectel_Linux_CMUX_SR01A07V01
09-24 10:28:12.199 V/MUXD (323): MUXD 2907:main(): Enter
09-24 10:28:12.199 V/MUXD (323): MUXD 3009:main(): gsm0710muxd $Rev: 1 $ starting
09-24 10:28:12.199 D/MUXD (323): MUXD 3013:main(): Called with following options:
09-24 10:28:12.199 D/MUXD (323): MUXD 3014:main(): -d: Fork, get a daemon
09-24 10:28:12.199 D/MUXD (323): MUXD 3015:main(): -v: Set verbose logging level. 0 (Silent) - 7 (Debug)
09-24 10:28:12.200 D/MUXD (323): MUXD 3016:main(): -s <serial port name>: Serial port device to connect to
09-24 10:28:12.200 D/MUXD (323): MUXD 3017:main(): -c <hardware flow control>:Hardware flow control
09-24 10:28:12.200 D/MUXD (323): MUXD 3018:main(): -t <timeout>: reset modem after this number of seconds of silence
09-24 10:28:12.200 D/MUXD (323): MUXD 3019:main(): -P <pin-code>: PIN code to unlock SIM [-1]
09-24 10:28:12.200 D/MUXD (323): MUXD 3020:main(): -p <number>: use ping and reset modem after this number of unanswered pings
09-24 10:28:12.200 D/MUXD (323): MUXD 3021:main(): -b <baudrate>: mode baudrate
09-24 10:28:12.200 D/MUXD (323): MUXD 3022:main(): -m <modem>: Mode (basic, advanced)
09-24 10:28:12.200 D/MUXD (323): MUXD 3023:main(): -f <framsize>: Frame size
09-24 10:28:12.200 D/MUXD (323): MUXD 3024:main(): -n <number of ports>: Number of virtual ports to create, must be in range 1-4
09-24 10:28:12.200 D/MUXD (323): MUXD 3025:main(): -o <output log to file>: Output log to /tmp/gsm0710muxd.log
09-24 10:28:12.200 V/MUXD (323): MUXD 2612:watchdog(): Enter
09-24 10:28:12.200 V/MUXD (323): MUXD 2615:watchdog(): Serial state is 0
09-24 10:28:12.200 V/MUXD (323): MUXD 2390:open_serial_device(): Enter
09-24 10:28:12.202 D/MUXD (323): MUXD 2397:open_serial_device(): Opened serial port
09-24 10:28:12.203 D/MUXD (323): MUXD 2414:open_serial_device(): Configured serial device
09-24 10:28:12.203 V/MUXD (323): MUXD 2418:open_serial_device(): Switched Mux state to 1
09-24 10:28:12.203 D/MUXD (323): MUXD 2623:watchdog(): Watchdog started
09-24 10:28:12.203 D/MUXD (323): MUXD 2433:start_muxer(): Configuring modem
09-24 10:28:12.203 V/MUXD (323): MUXD 1690:chat(): Enter
09-24 10:28:12.203 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: 41 54 0d 0a....- ........AT..
09-24 10:28:12.203 V/MUXD (323): MUXD 1697:chat(): Wrote 4 bytes
09-24 10:28:12.208 V/MUXD (323): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:13.083 V/MUXD (323): MUXD 1716:chat(): Selected 1
09-24 10:28:13.188 V/MUXD (323): MUXD 1723:chat(): Read 13 bytes from serial device
09-24 10:28:13.188 V/MUXD (323): MUXD 752:syslogdump(): <s 00000000: 0d 0a 2b 43 45 52 45 47-3a 20 30 0d 0a.....+CEREG: 0..
09-24 10:28:13.286 V/MUXD (323): MUXD 1716:chat(): Selected 1
09-24 10:28:13.387 V/MUXD (323): MUXD 1723:chat(): Read 15 bytes from serial device
09-24 10:28:13.387 V/MUXD (323): MUXD 752:syslogdump(): <s 00000000: 0d 0a 2a 41 54 52 45 41-44 59 3a 20 31 0d 0a...*ATREADY: 1..
09-24 10:28:13.416 V/MUXD (323): MUXD 1716:chat(): Selected 0
09-24 10:28:13.416 W/MUXD (323): MUXD 2438:start_muxer(): Modem does not respond to AT commands, trying close mux mode
09-24 10:28:13.416 V/MUXD (323): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:13.416 V/MUXD (323): MUXD 786:write_frame(): Sending frame to channel 0
09-24 10:28:13.416 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: f9 03 c3 01 40 f9..- ........щ.├.@щ
09-24 10:28:13.416 V/MUXD (323): MUXD 889:write_frame(): Leave
09-24 10:28:13.416 V/MUXD (323): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:13.416 V/MUXD (323): MUXD 786:write_frame(): Sending frame to channel 0
09-24 10:28:13.416 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: f9 03 ef 05 c3 01 f2 f9- ........щ.№.├.Ыщ
09-24 10:28:13.416 V/MUXD (323): MUXD 889:write_frame(): Leave
09-24 10:28:13.416 V/MUXD (323): MUXD 1690:chat(): Enter
09-24 10:28:13.416 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: 41 54 0d 0a....- ........AT..
09-24 10:28:13.416 V/MUXD (323): MUXD 1697:chat(): Wrote 4 bytes
09-24 10:28:13.419 V/MUXD (323): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:13.419 V/MUXD (323): MUXD 1716:chat(): Selected 1
09-24 10:28:13.520 V/MUXD (323): MUXD 1723:chat(): Read 18 bytes from serial device
09-24 10:28:13.520 V/MUXD (323): MUXD 752:syslogdump(): <s 00000000: f9 c3 40 f9 f9 ef c3 f2-f9 41 54 0d 0d 0a 4f 4bщ├@щщ№├ЫщAT...OK
09-24 10:28:13.520 V/MUXD (323): MUXD 752:syslogdump(): <s 00000010: 0d 0a......- ..........
09-24 10:28:13.520 V/MUXD (323): MUXD 1728:chat(): Received OK
09-24 10:28:13.520 V/MUXD (323): MUXD 1690:chat(): Enter
09-24 10:28:13.520 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: 41 54 0d 0a....- ........AT..
09-24 10:28:13.520 V/MUXD (323): MUXD 1697:chat(): Wrote 4 bytes
09-24 10:28:13.520 V/MUXD (323): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:13.520 V/MUXD (323): MUXD 1716:chat(): Selected 1
09-24 10:28:13.621 V/MUXD (323): MUXD 1723:chat(): Read 9 bytes from serial device
09-24 10:28:13.621 V/MUXD (323): MUXD 752:syslogdump(): <s 00000000: 41 54 0d 0d 0a 4f 4b 0d-0a.......AT...OK..
09-24 10:28:13.621 V/MUXD (323): MUXD 1728:chat(): Received OK
09-24 10:28:13.621 V/MUXD (323): MUXD 1690:chat(): Enter
09-24 10:28:13.621 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: 41 54 2b 49 46 43 3d 30-2c 30 0d 0a....AT+IFC=0,0..
09-24 10:28:13.621 V/MUXD (323): MUXD 1697:chat(): Wrote 12 bytes
09-24 10:28:13.621 V/MUXD (323): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:13.622 V/MUXD (323): MUXD 1716:chat(): Selected 1
09-24 10:28:13.722 V/MUXD (323): MUXD 1723:chat(): Read 17 bytes from serial device
09-24 10:28:13.722 V/MUXD (323): MUXD 752:syslogdump(): <s 00000000: 41 54 2b 49 46 43 3d 30-2c 30 0d 0d 0a 4f 4b 0dAT+IFC=0,0...OK.
09-24 10:28:13.722 V/MUXD (323): MUXD 752:syslogdump(): <s 00000010: 0a.......- .........
09-24 10:28:13.722 V/MUXD (323): MUXD 1728:chat(): Received OK
09-24 10:28:13.722 V/MUXD (323): MUXD 1690:chat(): Enter
09-24 10:28:13.722 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: 41 54 5a 0d 0a...- ........ATZ..
09-24 10:28:13.722 V/MUXD (323): MUXD 1697:chat(): Wrote 5 bytes
09-24 10:28:13.723 V/MUXD (323): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:13.723 V/MUXD (323): MUXD 1716:chat(): Selected 1
09-24 10:28:13.823 V/MUXD (323): MUXD 1723:chat(): Read 10 bytes from serial device
09-24 10:28:13.823 V/MUXD (323): MUXD 752:syslogdump(): <s 00000000: 41 54 5a 0d 0d 0a 4f 4b-0d 0a......ATZ...OK..
09-24 10:28:13.823 V/MUXD (323): MUXD 1728:chat(): Received OK
09-24 10:28:13.823 V/MUXD (323): MUXD 1690:chat(): Enter
09-24 10:28:13.823 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: 41 54 45 30 0d 0a..- ........ATE0..
09-24 10:28:13.823 V/MUXD (323): MUXD 1697:chat(): Wrote 6 bytes
09-24 10:28:13.824 V/MUXD (323): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:13.824 V/MUXD (323): MUXD 1716:chat(): Selected 1
09-24 10:28:13.924 V/MUXD (323): MUXD 1723:chat(): Read 11 bytes from serial device
09-24 10:28:13.924 V/MUXD (323): MUXD 752:syslogdump(): <s 00000000: 41 54 45 30 0d 0d 0a 4f-4b 0d 0a.....ATE0...OK..
09-24 10:28:13.924 V/MUXD (323): MUXD 1728:chat(): Received OK
09-24 10:28:13.925 D/MUXD (323): MUXD 2508:start_muxer(): ================Time:10:28:13
09-24 10:28:13.925 D/MUXD (323): MUXD 2523:start_muxer(): Starting mux mode
09-24 10:28:13.925 V/MUXD (323): MUXD 1690:chat(): Enter
09-24 10:28:13.925 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: 41 54 2b 43 4d 55 58 3d-30 2c 30 2c 35 2c 35 31AT+CMUX=0,0,5,51
09-24 10:28:13.925 V/MUXD (323): MUXD 752:syslogdump(): >s 00000010: 32 0d 0a.....- ........2..
09-24 10:28:13.925 V/MUXD (323): MUXD 1697:chat(): Wrote 19 bytes
09-24 10:28:13.925 V/MUXD (323): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:13.936 V/MUXD (323): MUXD 1716:chat(): Selected 1
09-24 10:28:14.036 V/MUXD (323): MUXD 1723:chat(): Read 6 bytes from serial device
09-24 10:28:14.036 V/MUXD (323): MUXD 752:syslogdump(): <s 00000000: 0d 0a 4f 4b 0d 0a..- ..........OK..
09-24 10:28:14.036 V/MUXD (323): MUXD 1728:chat(): Received OK
09-24 10:28:14.036 V/MUXD (323): MUXD 2526:start_muxer(): Switched Mux state to 2
09-24 10:28:14.037 D/MUXD (323): MUXD 2527:start_muxer(): Waiting for mux-mode
09-24 10:28:14.187 D/MUXD (323): MUXD 2531:start_muxer(): Init control channel
09-24 10:28:14.187 V/MUXD (323): MUXD 2800:create_thread(): Enter
09-24 10:28:14.187 V/MUXD (323): MUXD 2650:watchdog(): Thread is running and listening on /dev/ttyUSB1
09-24 10:28:14.187 V/MUXD (323): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:14.187 V/MUXD (323): MUXD 786:write_frame(): Sending frame to channel 0
09-24 10:28:14.188 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: f9 03 3f 01 1c f9..- ........щ.?..щ
09-24 10:28:14.188 V/MUXD (323): MUXD 889:write_frame(): Leave
09-24 10:28:14.188 D/MUXD (323): MUXD 2656:watchdog(): Allocating logical channel 1/3
09-24 10:28:14.188 V/MUXD (323): MUXD 1137:c_alloc_channel(): Enter
09-24 10:28:14.188 V/MUXD (323): MUXD 1143:c_alloc_channel(): Found free channel 1 fd -1 on /dev/ptmx
09-24 10:28:14.188 V/MUXD (323): MUXD 2216:poll_thread_serial(): Enter
09-24 10:28:14.188 V/MUXD (323): MUXD 1152:c_alloc_channel(): PTS: /dev/pts/1
09-24 10:28:14.189 V/MUXD (323): MUXD 1160:c_alloc_channel(): Create/data/misc/rild/ Dir Success
09-24 10:28:14.271 V/MUXD (323): MUXD 1175:c_alloc_channel(): =====================================
09-24 10:28:14.271 V/MUXD (323): MUXD 1176:c_alloc_channel(): Create link /data/misc/rild/1 Success
09-24 10:28:14.271 V/MUXD (323): MUXD 1194:c_alloc_channel(): New channel properties: number: 1 fd: 8 device: /dev/ptmx
09-24 10:28:14.271 V/MUXD (323): MUXD 2800:create_thread(): Enter
09-24 10:28:14.271 V/MUXD (323): MUXD 1203:c_alloc_channel(): Thread is running and listening on /dev/pts/1
09-24 10:28:14.271 V/MUXD (323): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:14.271 V/MUXD (323): MUXD 786:write_frame(): Sending frame to channel 1
09-24 10:28:14.271 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: f9 07 3f 01 de f9..- ........щ.?.ящ
09-24 10:28:14.271 V/MUXD (323): MUXD 889:write_frame(): Leave
09-24 10:28:14.271 D/MUXD (323): MUXD 1207:c_alloc_channel(): Connecting /dev/pts/1 to virtual channel 1 for watchdog_init on /dev/ttyUSB1
09-24 10:28:14.271 V/MUXD (323): MUXD 2832:poll_thread(): Enter
09-24 10:28:14.281 D/MUXD (323): MUXD 2656:watchdog(): Allocating logical channel 2/3
09-24 10:28:14.281 V/MUXD (323): MUXD 1137:c_alloc_channel(): Enter
09-24 10:28:14.281 V/MUXD (323): MUXD 1143:c_alloc_channel(): Found free channel 2 fd -1 on /dev/ptmx
09-24 10:28:14.281 V/MUXD (323): MUXD 1152:c_alloc_channel(): PTS: /dev/pts/2
09-24 10:28:14.281 V/MUXD (323): MUXD 1160:c_alloc_channel(): Create/data/misc/rild/ Dir Success
09-24 10:28:14.346 V/MUXD (323): MUXD 1175:c_alloc_channel(): =====================================
09-24 10:28:14.346 V/MUXD (323): MUXD 1176:c_alloc_channel(): Create link /data/misc/rild/2 Success
09-24 10:28:14.346 V/MUXD (323): MUXD 1194:c_alloc_channel(): New channel properties: number: 2 fd: 10 device: /dev/ptmx
09-24 10:28:14.346 V/MUXD (323): MUXD 2800:create_thread(): Enter
09-24 10:28:14.346 V/MUXD (323): MUXD 1203:c_alloc_channel(): Thread is running and listening on /dev/pts/2
09-24 10:28:14.346 V/MUXD (323): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:14.346 V/MUXD (323): MUXD 786:write_frame(): Sending frame to channel 2
09-24 10:28:14.346 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: f9 0b 3f 01 59 f9..- ........щ.?.Yщ
09-24 10:28:14.346 V/MUXD (323): MUXD 889:write_frame(): Leave
09-24 10:28:14.346 D/MUXD (323): MUXD 1207:c_alloc_channel(): Connecting /dev/pts/2 to virtual channel 2 for watchdog_init on /dev/ttyUSB1
09-24 10:28:14.347 V/MUXD (323): MUXD 2832:poll_thread(): Enter
09-24 10:28:14.356 D/MUXD (323): MUXD 2656:watchdog(): Allocating logical channel 3/3
09-24 10:28:14.356 V/MUXD (323): MUXD 1137:c_alloc_channel(): Enter
09-24 10:28:14.356 V/MUXD (323): MUXD 1143:c_alloc_channel(): Found free channel 3 fd -1 on /dev/ptmx
09-24 10:28:14.357 V/MUXD (323): MUXD 1152:c_alloc_channel(): PTS: /dev/pts/3
09-24 10:28:14.357 V/MUXD (323): MUXD 1160:c_alloc_channel(): Create/data/misc/rild/ Dir Success
09-24 10:28:14.383 V/MUXD (323): MUXD 1175:c_alloc_channel(): =====================================
09-24 10:28:14.383 V/MUXD (323): MUXD 1176:c_alloc_channel(): Create link /data/misc/rild/3 Success
09-24 10:28:14.383 V/MUXD (323): MUXD 1194:c_alloc_channel(): New channel properties: number: 3 fd: 11 device: /dev/ptmx
09-24 10:28:14.383 V/MUXD (323): MUXD 2800:create_thread(): Enter
09-24 10:28:14.383 V/MUXD (323): MUXD 1203:c_alloc_channel(): Thread is running and listening on /dev/pts/3
09-24 10:28:14.383 V/MUXD (323): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:14.383 V/MUXD (323): MUXD 2832:poll_thread(): Enter
09-24 10:28:14.383 V/MUXD (323): MUXD 786:write_frame(): Sending frame to channel 3
09-24 10:28:14.384 V/MUXD (323): MUXD 752:syslogdump(): >s 00000000: f9 0f 3f 01 9b f9..- ........щ.?.Џщ
09-24 10:28:14.384 V/MUXD (323): MUXD 889:write_frame(): Leave
09-24 10:28:14.384 D/MUXD (323): MUXD 1207:c_alloc_channel(): Connecting /dev/pts/3 to virtual channel 3 for watchdog_init on /dev/ttyUSB1
09-24 10:28:14.394 D/MUXD (323): MUXD 2670:watchdog(): Multiplexing started..
09-24 10:28:14.394 D/MUXD (323): MUXD 3028:main(): GSM0710 buffer. Stored 0
09-24 10:28:14.394 D/MUXD (323): MUXD 3029:main(): Frames received/dropped: 0/0
09-24 10:28:15.195 D/MUXD (300): MUXD 3088:gsm0710muxd(): gsm0710muxd gsm0710muxd_pid = 323
09-24 10:28:15.196 D/RILC (300): open /data/misc/rild/1
09-24 10:28:15.196 D/RILC (300): fd = 5
09-24 10:28:15.196 D/RILC (300): open device /dev/ttyUSB1 correctly
09-24 10:28:15.196 E/ATC (300): at_open s_tild_reader = 522554713168
09-24 10:28:15.197 I/RILC (300): :oldState=1, newState=0
09-24 10:28:15.198 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:15.199 W/MUXD (323): MUXD 1060:pseudo_device_read(): Write to a channel which wasn't acked to be open.
09-24 10:28:15.199 W/MUXD (323): MUXD 2847:poll_thread(): Device read function returned error
09-24 10:28:15.199 E/MUXD (323): MUXD 2875:poll_thread(): Device polling thread terminated
09-24 10:28:15.320 D/TelephonyManager(304): /proc/cmdline=earlycon=uart8250,mmio32,0xff1a0000 swiotlb=1 console=ttyFIQ0 androidboot.baseband=N/A androidboot.selinux=permissive androidboot.hardware=rk30board androidboot.console=ttyFIQ0 init=/init mtdparts=rk29xxnand:0x00002000@0x00002000(uboot),0x00002000@0x00004000(trust),0x00002000@0x00006000(misc),0x00008000@0x00008000(resource),0x0000C000@0x00010000(kernel),0x00010000@0x0001C000(boot),0x00020000@0x0002C000(recovery),0x00038000@0x0004C000(backup),0x00040000@0x00084000(cache),0x00300000@0x000C4000(system),0x00100000@0x003C4000(oem),0x00008000@0x004C4000(metadata),0x00000040@0x004CC000(verity_mode),0x00002000@0x004CC040(reserved),0x00000400@0x004CE040(frp),-@0x004CE440(userdata) storagemedia=emmc androidboot.oem_unlocked=0 uboot_logo=0x02000000@0x7dc00000 loader.timestamp=2020-11-24_20:58:52 SecureBootCheckOk=0 androidboot.mode=emmc
09-24 10:28:15.699 E/ATC (300): warnning - moderm no response, retry ATE0Q0V1
09-24 10:28:15.699 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:16.317 D/TelephonyManager(303): /proc/cmdline=earlycon=uart8250,mmio32,0xff1a0000 swiotlb=1 console=ttyFIQ0 androidboot.baseband=N/A androidboot.selinux=permissive androidboot.hardware=rk30board androidboot.console=ttyFIQ0 init=/init mtdparts=rk29xxnand:0x00002000@0x00002000(uboot),0x00002000@0x00004000(trust),0x00002000@0x00006000(misc),0x00008000@0x00008000(resource),0x0000C000@0x00010000(kernel),0x00010000@0x0001C000(boot),0x00020000@0x0002C000(recovery),0x00038000@0x0004C000(backup),0x00040000@0x00084000(cache),0x00300000@0x000C4000(system),0x00100000@0x003C4000(oem),0x00008000@0x004C4000(metadata),0x00000040@0x004CC000(verity_mode),0x00002000@0x004CC040(reserved),0x00000400@0x004CE040(frp),-@0x004CE440(userdata) storagemedia=emmc androidboot.oem_unlocked=0 uboot_logo=0x02000000@0x7dc00000 loader.timestamp=2020-11-24_20:58:52 SecureBootCheckOk=0 androidboot.mode=emmc
09-24 10:28:16.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:17.503 E/ATC (300): warnning - moderm no response, retry ATE0Q0V1
09-24 10:28:17.503 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:18.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:19.395 D/MUXD (323): MUXD 3028:main(): GSM0710 buffer. Stored 0
09-24 10:28:19.395 D/MUXD (323): MUXD 3029:main(): Frames received/dropped: 0/0
09-24 10:28:19.500 E/ATC (300): warnning - moderm no response, retry ATE0Q0V1
09-24 10:28:19.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:20.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:21.500 E/ATC (300): warnning - moderm no response, retry ATE0Q0V1
09-24 10:28:21.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:22.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:23.500 E/ATC (300): warnning - moderm no response, retry ATE0Q0V1
09-24 10:28:23.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:24.395 D/MUXD (323): MUXD 3028:main(): GSM0710 buffer. Stored 0
09-24 10:28:24.395 D/MUXD (323): MUXD 3029:main(): Frames received/dropped: 0/0
09-24 10:28:24.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:25.500 E/ATC (300): warnning - moderm no response, retry ATE0Q0V1
09-24 10:28:25.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:26.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:27.500 E/ATC (300): warnning - moderm no response, retry ATE0Q0V1
09-24 10:28:27.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:28.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:29.395 D/MUXD (323): MUXD 3028:main(): GSM0710 buffer. Stored 0
09-24 10:28:29.395 D/MUXD (323): MUXD 3029:main(): Frames received/dropped: 0/0
09-24 10:28:29.500 E/ATC (300): warnning - moderm no response, retry ATE0Q0V1
09-24 10:28:29.500 D/ATC (300): AT> ATE0Q0V1
09-24 10:28:30.500 D/ATC (300): AT> ATI
09-24 10:28:32.129 D/TDC (751): updateOrInsert: inserting: Modem { uuid=modem, state=0, rilModel=0, rat={}, maxActiveVoiceCall=1, maxActiveDataCall=1, maxStandby=1 }
09-24 10:28:32.129 D/TDC (751): updateOrInsert: inserting: Sim { uuid=sim, modemUuid=modem, state=0 }
09-24 10:28:32.234 D/CdmaSSM (751): subscriptionSource from settings: 0
09-24 10:28:32.234 I/PhoneFactory(751): Cdma Subscription set to 0
09-24 10:28:32.243 I/PhoneFactory(751): Network Mode set to 0
09-24 10:28:32.247 D/RILJ (751): RIL(context, preferredNetworkType=0 cdmaSubscription=0)
09-24 10:28:32.258 D/RILJ (751): Starting RILReceiver0
09-24 10:28:32.270 I/RILJ (751): (0) Connected to 'rild' socket
09-24 10:28:32.286 D/RILJ (751): > GET_HARDWARE_CONFIG
09-24 10:28:32.312 I/PhoneFactory(751): Creating SubscriptionController
09-24 10:28:32.320 D/SubscriptionController(751): init by Context
09-24 10:28:32.328 D/UiccController(751): Creating UiccController
09-24 10:28:32.334 D/TelephonyTester(751): register for intent action=com.android.internal.telephony.action_detached
09-24 10:28:32.334 D/TelephonyTester(751): register for intent action=com.android.internal.telephony.action_attached
09-24 10:28:32.357 D/Phone (751): mDoesRilSendMultipleCallRing=true
09-24 10:28:32.357 D/Phone (751): mCallRingDelay=3000
09-24 10:28:32.375 D/RILJ (751): > RIL_REQUEST_START_LCE
09-24 10:28:32.400 E/RIL_ImsSms(751): getFormat should never be called from here!
09-24 10:28:32.401 D/SMSDispatcher(751): SMSDispatcher: ctor mSmsCapable=false format=unknown mSmsSendDisabled=true
09-24 10:28:32.401 D/RIL_ImsSms(751): ImsSMSDispatcher created
09-24 10:28:32.407 D/SMSDispatcher(751): SMSDispatcher: ctor mSmsCapable=false format=3gpp2 mSmsSendDisabled=true
09-24 10:28:32.407 D/CdmaSMSDispatcher(751): CdmaSMSDispatcher created
09-24 10:28:32.438 D/GsmInboundSmsHandler(751): created InboundSmsHandler
09-24 10:28:32.458 D/CdmaInboundSmsHandler(751): created InboundSmsHandler
09-24 10:28:32.468 D/SMSDispatcher(751): SMSDispatcher: ctor mSmsCapable=false format=3gpp mSmsSendDisabled=true
09-24 10:28:32.468 D/GsmSMSDispatcher(751): GsmSMSDispatcher created
09-24 10:28:32.476 D/GsmInboundSmsHandler(751): StartupState.processMessage:6
09-24 10:28:32.476 D/GsmInboundSmsHandler(751): entering Idle state
09-24 10:28:32.477 D/CdmaInboundSmsHandler(751): StartupState.processMessage:6
09-24 10:28:32.477 D/CdmaInboundSmsHandler(751): entering Idle state
09-24 10:28:32.486 D/IccCardProxy(751): ctor: ci=com.android.internal.telephony.RIL@e7e22c8 phoneId=0
09-24 10:28:32.486 D/CdmaSSM (751): subscriptionSource from settings: 0
09-24 10:28:32.486 D/CdmaSSM (751): cdmaSSM constructor: 0
09-24 10:28:32.486 D/IccCardProxy(751): update icc_operator_numeric=
09-24 10:28:32.487 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.numeric value:propVal=
09-24 10:28:32.490 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.iso-country value:propVal=
09-24 10:28:32.491 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.alpha value:propVal=
09-24 10:28:32.493 E/IccCardProxy(751): setExternalState: set mPhoneId=0 mExternalState=NOT_READY
09-24 10:28:32.493 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.sim.state value: NOT_READY propVal=NOT_READY
09-24 10:28:32.498 D/IccCardProxy(751): broadcastIccStateChangedIntent intent ACTION_SIM_STATE_CHANGED value=NOT_READY reason=null for mPhoneId=0
09-24 10:28:32.508 D/RILJ (751): setPhoneType=1 old value=0
09-24 10:28:32.508 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.current.phone-type value: 1 propVal=1
09-24 10:28:32.510 D/IccCardProxy(751): Setting radio tech UMTS
09-24 10:28:32.510 D/IccCardProxy(751): updateQuietMode: 3GPP subscription -> newQuietMode=false
09-24 10:28:32.510 D/IccCardProxy(751): updateQuietMode: no changes don't setExternalState
09-24 10:28:32.510 D/IccCardProxy(751): updateQuietMode: QuietMode is false (app_type=1 cdmaSource=-1)
09-24 10:28:32.519 W/CarrierConfigManager(751): Error getting config for subId -1 ICarrierConfigLoader is null
09-24 10:28:32.522 D/TelephonyRegistry(631): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
09-24 10:28:32.551 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.network.type value: Unknown propVal=Unknown
09-24 10:28:32.551 D/TelephonyRegistry(631): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
09-24 10:28:32.552 D/RILJ (751): > SIGNAL_STRENGTH
09-24 10:28:32.560 D/SST (751): notifyDataRegStateRilRadioTechnologyChanged: drs=1 rat=0
09-24 10:28:32.560 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.network.type value: Unknown propVal=Unknown
09-24 10:28:32.564 D/DCT (751): DCT.constructor
09-24 10:28:32.573 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:32.574 D/TelephonyRegistry(631): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
09-24 10:28:32.576 D/Dcc (751): E ctor
09-24 10:28:32.577 D/Dcc (751): X ctor
09-24 10:28:32.579 D/SubscriptionController(751): asked for default subId=-1
09-24 10:28:32.582 D/DcTesterDeacativateAll(751): register for intent action=com.android.internal.telephony.dataconnection.action_deactivate_all
09-24 10:28:32.582 D/DcTesterDeacativateAll(751): register for intent action=com.android.internal.telephony.action_detached
09-24 10:28:32.591 D/DcTesterFailBrinupAll(751): register for intent action=com.android.internal.telephony.dataconnection.action_fail_bringup
09-24 10:28:32.591 D/DcTesterFailBrinupAll(751): register for intent action=com.android.internal.telephony.action_detached
09-24 10:28:32.591 D/DcTesterFailBrinupAll(751): register for intent action=com.android.internal.telephony.action_attached
09-24 10:28:32.593 D/SST (751): notifyDataRegStateRilRadioTechnologyChanged: drs=1 rat=0
09-24 10:28:32.594 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.network.type value: Unknown propVal=Unknown
09-24 10:28:32.594 D/DCT (751): update sub = 0
09-24 10:28:32.594 D/DCT (751): update(): Active DDS, register for all events now!
09-24 10:28:32.596 D/GsmCdmaPhone(751): updateCurrentCarrierInProvider: mSubId = 0 currentDds = -1 operatorNumeric = null
09-24 10:28:32.599 D/DCT (751): initApnContexts: E
09-24 10:28:32.599 D/DCT (751): initApnContexts: skipping unknown type=1
09-24 10:28:32.600 D/DCT (751): initApnContexts: apnContext={mApnType=default mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
09-24 10:28:32.601 D/DCT (751): initApnContexts: apnContext={mApnType=mms mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
09-24 10:28:32.601 D/DCT (751): initApnContexts: apnContext={mApnType=supl mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
09-24 10:28:32.601 D/DCT (751): initApnContexts: apnContext={mApnType=dun mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
09-24 10:28:32.601 D/DCT (751): initApnContexts: apnContext={mApnType=hipri mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
09-24 10:28:32.602 D/DCT (751): initApnContexts: apnContext={mApnType=fota mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
09-24 10:28:32.602 D/DCT (751): initApnContexts: apnContext={mApnType=ims mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
09-24 10:28:32.602 D/DCT (751): initApnContexts: apnContext={mApnType=cbs mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
09-24 10:28:32.602 D/DCT (751): initApnContexts: skipping unknown type=7
09-24 10:28:32.602 D/DCT (751): initApnContexts: skipping unknown type=9
09-24 10:28:32.666 D/GsmCdmaPhone(751): GsmCdmaPhone: constructor: sub = 0
09-24 10:28:32.667 I/PhoneFactory(751): Creating Phone with type = 1 sub = 0
09-24 10:28:32.667 I/PhoneFactory(751): defaultSmsApplication: NONE
09-24 10:28:32.676 I/PhoneFactory(751): Creating SubInfoRecordUpdater
09-24 10:28:32.676 D/SubscriptionInfoUpdater(751): Constructor invoked
09-24 10:28:32.795 D/TelephonyRegistry(631): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
09-24 10:28:32.801 D/TelephonyRegistry(631): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
09-24 10:28:32.807 D/PhoneSwitcher(751): PhoneSwitcher started
09-24 10:28:32.811 D/ProxyController(751): Constructor - Enter
09-24 10:28:32.819 D/ProxyController(751): clearTransaction
09-24 10:28:32.819 D/ProxyController(751): clearTransaction: phoneId=0 status=IDLE
09-24 10:28:32.819 D/ProxyController(751): Constructor - Exit
09-24 10:28:32.821 D/TelephonyNetworkFactory(751): Registering NetworkFactory
09-24 10:28:32.829 D/CallManager(751): registerPhone(GSM Handler (com.android.internal.telephony.GsmCdmaPhone) {f03d1a4})
09-24 10:28:32.931 D/TelephonyRegistry(631): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
09-24 10:28:33.044 D/TelephonyRegistry(631): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
09-24 10:28:33.123 D/RILJ (751): > RIL_REQUEST_SET_TTY_MODE : 0
09-24 10:28:33.163 D/TelephonyRegistry(631): listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
09-24 10:28:33.167 D/SubscriptionController(751): asked for default subId=-1
09-24 10:28:33.194 D/UiccController(751): EVENT_RADIO_UNAVAILABLE, dispose card
09-24 10:28:33.196 D/RILJ (751): > GET_CURRENT_CALLS
09-24 10:28:33.198 D/GsmSMSDispatcher(751): GsmSMSDispatcher: subId = 0 slotId = 0
09-24 10:28:33.198 E/IccCardProxy(751): setExternalState: !override and newstate unchanged from NOT_READY
09-24 10:28:33.198 E/IccCardProxy(751): setExternalState: !override and newstate unchanged from NOT_READY
09-24 10:28:33.199 D/GsmCdmaPhone(751): Event EVENT_RADIO_OFF_OR_NOT_AVAILABLE Received
09-24 10:28:33.199 E/IccCardProxy(751): setExternalState: !override and newstate unchanged from NOT_READY
09-24 10:28:33.199 D/SST (751): mDeviceShuttingDown=false, mDesiredPowerState=true, getRadioState=RADIO_UNAVAILABLE, mPowerOffDelayNeed=true, mAlarmSwitch=false, mRadioDisabledByCarrier=false
09-24 10:28:33.203 D/SST (751): useDataRegStateForDataOnlyDevice: VoiceRegState=1 DataRegState=1
09-24 10:28:33.204 D/SST (751): Poll ServiceState done:oldSS= newSS= oldMaxDataCalls=1 mNewMaxDataCalls=1 oldReasonDataDenied=-1 mNewReasonDataDenied=-1
09-24 10:28:33.205 D/SST (751): updateSpnDisplay: radio is on but out of service, set plmn='No service'
09-24 10:28:33.208 D/SST (751): updateSpnDisplay: changed sending intent rule=0 showPlmn='true' plmn='No service' showSpn='false' spn='' dataSpn='' subId='0'
09-24 10:28:33.219 D/SubscriptionController(751): + text:No service subId:0
09-24 10:28:33.223 D/TelephonyRegistry(631): systemRunning register for intents
09-24 10:28:33.243 D/SubscriptionController(751): notifySubscriptionInfoChanged:
09-24 10:28:33.244 D/TelephonyRegistry(631): notifySubscriptionInfoChanged: first invocation mRecords.size=12
09-24 10:28:33.252 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.operator.alpha value:propVal=
09-24 10:28:33.253 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.operator.numeric value:propVal=
09-24 10:28:33.254 D/SST (751): operatorNumeric is null
09-24 10:28:33.254 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.operator.iso-country value:propVal=
09-24 10:28:33.256 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.operator.isroaming value: false propVal=false
09-24 10:28:33.259 D/SST (751): Broadcasting ServiceState : 1 1 voice home data home null null null null null nullUnknown Unknown CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false
09-24 10:28:33.259 D/DefaultPhoneNotifier(751): nofityServiceState: mRegistry=com.android.internal.telephony.ITelephonyRegistry$Stub$Proxy@1fceec5 ss=1 1 voice home data home null null null null null nullUnknown Unknown CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false sender=Handler (com.android.internal.telephony.GsmCdmaPhone) {f03d1a4} phondId=0 subId=0
09-24 10:28:33.266 D/SubscriptionController(751): - subId invalid
09-24 10:28:33.272 D/TelephonyManager(631): getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
09-24 10:28:33.285 D/SST (751): mDeviceShuttingDown=false, mDesiredPowerState=true, getRadioState=RADIO_UNAVAILABLE, mPowerOffDelayNeed=true, mAlarmSwitch=false, mRadioDisabledByCarrier=false
09-24 10:28:33.295 D/SST (751): useDataRegStateForDataOnlyDevice: VoiceRegState=1 DataRegState=1
09-24 10:28:33.296 D/SST (751): Poll ServiceState done:oldSS= newSS= oldMaxDataCalls=1 mNewMaxDataCalls=1 oldReasonDataDenied=-1 mNewReasonDataDenied=-1
09-24 10:28:33.299 D/DCT (751): onRadioOffOrNotAvailable: is off and clean up all connections
09-24 10:28:33.299 D/DCT (751): cleanUpAllConnections: tearDown=false reason=radioTurnedOff
09-24 10:28:33.299 D/GsmCdmaPhone(751): getDataConnectionState apnType=default ret=DISCONNECTED
09-24 10:28:33.302 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.307 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.325 D/DCT (751): cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=default mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
09-24 10:28:33.325 D/GsmCdmaPhone(751): getDataConnectionState apnType=mms ret=DISCONNECTED
09-24 10:28:33.331 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.334 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.444 D/DCT (751): cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=mms mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
09-24 10:28:33.444 D/GsmCdmaPhone(751): getDataConnectionState apnType=cbs ret=DISCONNECTED
09-24 10:28:33.449 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.453 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.465 D/DCT (751): cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=cbs mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
09-24 10:28:33.465 D/GsmCdmaPhone(751): getDataConnectionState apnType=fota ret=DISCONNECTED
09-24 10:28:33.471 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.478 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.483 D/DCT (751): cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=fota mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
09-24 10:28:33.484 D/GsmCdmaPhone(751): getDataConnectionState apnType=supl ret=DISCONNECTED
09-24 10:28:33.486 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.488 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.498 D/DCT (751): cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=supl mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
09-24 10:28:33.499 D/GsmCdmaPhone(751): getDataConnectionState apnType=dun ret=DISCONNECTED
09-24 10:28:33.500 E/ATC (300): warnning - moderm no response, retry ATI
09-24 10:28:33.500 D/ATC (300): AT> ATI
09-24 10:28:33.501 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.504 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.518 D/DCT (751): cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=dun mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
09-24 10:28:33.520 D/GsmCdmaPhone(751): getDataConnectionState apnType=hipri ret=DISCONNECTED
09-24 10:28:33.528 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.530 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.534 D/DCT (751): cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=hipri mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
09-24 10:28:33.534 D/GsmCdmaPhone(751): getDataConnectionState apnType=ims ret=DISCONNECTED
09-24 10:28:33.540 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.547 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.552 D/DCT (751): cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=ims mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
09-24 10:28:33.553 D/DCT (751): stopNetStatPoll
09-24 10:28:33.555 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.561 D/DCT (751): cleanUpConnection: mDisconnectPendingCount = 0
09-24 10:28:33.562 D/DCT (751): isDataAllowed: No - Not attached - defaultDataSelected= false - SIM not loaded
09-24 10:28:33.565 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.568 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.581 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.585 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.598 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.600 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.611 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.613 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.620 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.624 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.634 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.639 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.652 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.655 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.671 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.675 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.682 D/DCT (751): onDataConnectionDetached: stop polling and notify detached
09-24 10:28:33.683 D/DCT (751): stopNetStatPoll
09-24 10:28:33.684 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.687 D/DCT (751): notifyDataConnection: reason=dataDetached
09-24 10:28:33.689 D/DCT (751): isDataAllowed: No - Not attached - defaultDataSelected= false - SIM not loaded
09-24 10:28:33.692 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.697 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.705 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.708 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.718 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.720 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.729 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.731 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.738 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.742 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.752 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.756 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.765 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.768 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.778 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.782 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.787 D/DCT (751): onRoamingOff
09-24 10:28:33.787 D/DCT (751): setDataProfilesAsNeeded mSetDataProfileStatus: 0
09-24 10:28:33.789 D/DCT (751): notifyDataConnection: reason=roamingOff
09-24 10:28:33.790 D/DCT (751): isDataAllowed: No - Not attached - defaultDataSelected= false - SIM not loaded
09-24 10:28:33.795 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.798 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.806 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.809 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.817 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.820 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.834 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.837 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.847 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.849 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.858 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.862 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.871 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.873 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.885 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.887 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.893 D/DCT (751): setupDataOnConnectableApns: nwTypeChanged hipri: mms: supl: cbs: dun: fota: ims: default:
09-24 10:28:33.894 D/TelephonyNetworkFactory(751): onSubIdChange -1->0
09-24 10:28:33.895 D/TelephonyNetworkFactory(751): got request NetworkRequest [ REQUEST id=1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
09-24 10:28:33.897 D/DebugService(751): DebugService DebugService:
09-24 10:28:33.903 D/GsmSMSDispatcher(751): GsmSMSDispatcher: subId = 0 slotId = 0
09-24 10:28:33.903 E/IccCardProxy(751): setExternalState: !override and newstate unchanged from NOT_READY
09-24 10:28:33.904 D/PhoneSwitcher(751): evaluating due to subChanged default 0->-1
09-24 10:28:33.906 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:33.908 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:33.909 D/SST (751): SubscriptionListener.onSubscriptionInfoChanged
09-24 10:28:33.912 D/Phone (751): getCallForwardingIndicatorFromSharedPref: for subId 0= -1
09-24 10:28:33.913 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.914 D/PhoneFactory(751): calculatePreferredNetworkType: phoneSubId = 0 networkType = 0
09-24 10:28:33.914 D/Phone (751): setPreferredNetworkType: Abort, unknown RAF: 1 101902
09-24 10:28:33.915 D/Phone (751): setNetworkSelectionModeAutomatic, querying current mode
09-24 10:28:33.915 D/RILJ (751): > QUERY_NETWORK_SELECTION_MODE
09-24 10:28:33.919 D/TelephonyManager(751): setTelephonyProperty: success phoneId=0 property=gsm.network.type value: Unknown propVal=Unknown
09-24 10:28:33.920 D/SST (751): updateSpnDisplay: radio is on but out of service, set plmn='No service'
09-24 10:28:33.923 D/Phone (751): getStoredVoiceMessageCount: from preference for subId 0= 0
09-24 10:28:33.923 D/GsmCdmaPhone(751): updateVoiceMail countVoiceMessages = 0 subId 0
09-24 10:28:33.924 D/Phone (751): setVoiceMessageCount: Storing Voice Mail Count = 0 for mVmCountKey = vm_count_key0 in preferences.
09-24 10:28:33.925 D/DCT (751): SubscriptionListener.onSubscriptionInfoChanged
09-24 10:28:33.935 D/DCT (751): onRecordsLoadedOrSubIdChanged: createAllApnList
09-24 10:28:33.935 D/DCT (751): createAllApnList: selection=numeric = ''
09-24 10:28:33.947 D/DCT (751): createAllApnList: No APN found for carrier:
09-24 10:28:33.947 D/DCT (751): createAllApnList: X mAllApnSettings=[]
09-24 10:28:33.947 D/DCT (751): setDataProfilesAsNeeded mSetDataProfileStatus: 0
09-24 10:28:33.947 D/DCT (751): setInitialApn: E mPreferredApn=null
09-24 10:28:33.947 D/DCT (751): setInitialAttachApn: X There in no available apn
09-24 10:28:33.948 D/DCT (751): setupDataOnConnectableApns: simLoaded hipri: mms: supl: cbs: dun: fota: ims: default:
09-24 10:28:33.970 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.977 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:33.978 D/SubscriptionController(751): - subId=0 subList=null subInfo=null
09-24 10:28:33.981 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.984 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.988 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.992 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.996 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:33.998 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.051 D/SubscriptionInfoUpdater(751): +
09-24 10:28:34.051 D/SubscriptionInfoUpdater(751): Action: android.intent.action.SIM_STATE_CHANGED
09-24 10:28:34.051 D/SubscriptionInfoUpdater(751): slotId: 0
09-24 10:28:34.051 D/SubscriptionInfoUpdater(751): simStatus: NOT_READY
09-24 10:28:34.051 D/SubscriptionInfoUpdater(751): Ignoring simStatus: NOT_READY
09-24 10:28:34.051 D/SubscriptionInfoUpdater(751): -
09-24 10:28:34.054 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.057 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.061 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.064 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.066 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.068 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.071 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.073 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.075 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.078 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.080 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.083 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.085 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.087 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.089 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.091 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.094 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.096 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.098 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.100 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.102 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.106 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.108 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.111 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.114 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.115 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.117 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.118 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.119 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.120 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.122 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.123 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.125 D/PhoneSwitcher(751): evaluating due to netRequest
09-24 10:28:34.131 D/TelephonyNetworkFactory(751): not acting - isApp=false, isAct=false NetworkRequest [ REQUEST id=1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ]
09-24 10:28:34.136 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.141 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:34.143 D/SubscriptionController(751): - subId=0 subList=null subInfo=null
09-24 10:28:34.146 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.150 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.153 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.156 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.159 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.163 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:34.396 D/MUXD (323): MUXD 3028:main(): GSM0710 buffer. Stored 0
09-24 10:28:34.396 D/MUXD (323): MUXD 3029:main(): Frames received/dropped: 0/0
09-24 10:28:34.885 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:34.887 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:34.890 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:34.896 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:34.980 D/SubscriptionController(751): asked for default subId=-1
09-24 10:28:35.062 D/SubscriptionController(751): - subId invalid
09-24 10:28:35.065 D/TelephonyManager(763): getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
09-24 10:28:35.098 D/SubscriptionController(751): - subId invalid
09-24 10:28:35.102 D/TelephonyManager(763): getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
09-24 10:28:35.123 D/SubscriptionController(751): - subId invalid
09-24 10:28:35.126 D/TelephonyManager(763): getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
09-24 10:28:35.260 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:35.424 D/GsmCellBroadcastHandler(751): mWakeLock released
09-24 10:28:35.452 D/CellBroadcastHandler(751): mWakeLock released
09-24 10:28:35.473 D/CdmaServiceCategoryProgramHandler(751): mWakeLock released
09-24 10:28:35.479 D/CdmaInboundSmsHandler(751): IdleState.processMessage:5
09-24 10:28:35.479 D/CdmaInboundSmsHandler(751): Idle state processing message type 5
09-24 10:28:35.479 D/GsmInboundSmsHandler(751): IdleState.processMessage:5
09-24 10:28:35.480 D/GsmInboundSmsHandler(751): Idle state processing message type 5
09-24 10:28:35.481 D/GsmInboundSmsHandler(751): mWakeLock released
09-24 10:28:35.482 D/CdmaInboundSmsHandler(751): mWakeLock released
09-24 10:28:35.935 D/SubscriptionController(751): - subId invalid
09-24 10:28:35.992 D/TelephonyManager(763): getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
09-24 10:28:36.247 D/WAP PUSH(751): Received broadcast android.intent.action.USER_UNLOCKED
09-24 10:28:36.253 D/WAP PUSH(751): Received broadcast android.intent.action.USER_UNLOCKED
09-24 10:28:36.257 D/SmsBroadcastUndelivered(751): Received broadcast android.intent.action.USER_UNLOCKED
09-24 10:28:36.258 D/SmsBroadcastUndelivered(751): scanning raw table for undelivered messages
09-24 10:28:36.258 D/SubscriptionInfoUpdater(751): +
09-24 10:28:36.259 D/SubscriptionInfoUpdater(751): Action: android.intent.action.USER_UNLOCKED
09-24 10:28:36.259 D/SubscriptionInfoUpdater(751): Broadcasting intent ACTION_SIM_STATE_CHANGED for mCardIndex: 0
09-24 10:28:36.306 D/SubscriptionInfoUpdater(751): -
09-24 10:28:36.312 D/SubscriptionInfoUpdater(751): +
09-24 10:28:36.312 D/SubscriptionInfoUpdater(751): Action: android.intent.action.SIM_STATE_CHANGED
09-24 10:28:36.312 D/SubscriptionInfoUpdater(751): slotId: 0
09-24 10:28:36.312 D/SubscriptionInfoUpdater(751): simStatus: NOT_READY
09-24 10:28:36.312 D/SubscriptionInfoUpdater(751): Ignoring simStatus: NOT_READY
09-24 10:28:36.319 D/SubscriptionInfoUpdater(751): -
09-24 10:28:36.361 D/SubscriptionController(751): Sub Controller not ready
09-24 10:28:36.362 D/SubscriptionController(751): - subId invalid
09-24 10:28:36.383 D/TelephonyManager(763): getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
09-24 10:28:36.402 D/SmsBroadcastUndelivered(751): finished scanning raw table in 143 ms
09-24 10:28:36.501 I/RILC (300): AT channel timeout; closing
09-24 10:28:36.501 E/ATC (300): at_close
09-24 10:28:36.501 E/ATC (300): at_close s_tild_reader = 522554713168
09-24 10:28:36.501 I/RILC (300): :oldState=0, newState=1
09-24 10:28:36.529 D/SubscriptionController(751): - subId invalid
09-24 10:28:36.540 D/TelephonyManager(763): getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
09-24 10:28:37.501 I/RILC (300): Re-opening after close
09-24 10:28:37.502 D/MUXD (300): MUXD 3062:gsm0710muxd(): gsm0710muxd serialname = /dev/ttyUSB1, gsm0710muxd_pid = 323
09-24 10:28:37.504 D/ATC (300): atchannel: read error I/O error
09-24 10:28:37.504 E/ATC (300): readerLoop pthread_self = 522554713168 exit
09-24 10:28:37.508 D/MUXD (300): MUXD 3067:gsm0710muxd(): kill gsm0710muxd finished!
09-24 10:28:37.509 D/MUXD ( 1287): MUXD 2906:main(): Quectel CMUX Revision: Quectel_Linux_CMUX_SR01A07V01
09-24 10:28:37.509 V/MUXD ( 1287): MUXD 2907:main(): Enter
09-24 10:28:37.509 V/MUXD ( 1287): MUXD 3009:main(): gsm0710muxd $Rev: 1 $ starting
09-24 10:28:37.509 D/MUXD ( 1287): MUXD 3013:main(): Called with following options:
09-24 10:28:37.509 D/MUXD ( 1287): MUXD 3014:main(): -d: Fork, get a daemon
09-24 10:28:37.509 D/MUXD ( 1287): MUXD 3015:main(): -v: Set verbose logging level. 0 (Silent) - 7 (Debug)
09-24 10:28:37.509 D/MUXD ( 1287): MUXD 3016:main(): -s <serial port name>: Serial port device to connect to
09-24 10:28:37.509 D/MUXD ( 1287): MUXD 3017:main(): -c <hardware flow control>:Hardware flow control
09-24 10:28:37.509 D/MUXD ( 1287): MUXD 3018:main(): -t <timeout>: reset modem after this number of seconds of silence
09-24 10:28:37.510 D/MUXD ( 1287): MUXD 3019:main(): -P <pin-code>: PIN code to unlock SIM [-1]
09-24 10:28:37.510 D/MUXD ( 1287): MUXD 3020:main(): -p <number>: use ping and reset modem after this number of unanswered pings
09-24 10:28:37.510 D/MUXD ( 1287): MUXD 3021:main(): -b <baudrate>: mode baudrate
09-24 10:28:37.510 D/MUXD ( 1287): MUXD 3022:main(): -m <modem>: Mode (basic, advanced)
09-24 10:28:37.510 D/MUXD ( 1287): MUXD 3023:main(): -f <framsize>: Frame size
09-24 10:28:37.510 D/MUXD ( 1287): MUXD 3024:main(): -n <number of ports>: Number of virtual ports to create, must be in range 1-4
09-24 10:28:37.510 D/MUXD ( 1287): MUXD 3025:main(): -o <output log to file>: Output log to /tmp/gsm0710muxd.log
09-24 10:28:37.510 V/MUXD ( 1287): MUXD 2612:watchdog(): Enter
09-24 10:28:37.510 V/MUXD ( 1287): MUXD 2615:watchdog(): Serial state is 0
09-24 10:28:37.510 V/MUXD ( 1287): MUXD 2390:open_serial_device(): Enter
09-24 10:28:37.511 D/MUXD ( 1287): MUXD 2397:open_serial_device(): Opened serial port
09-24 10:28:37.511 D/MUXD ( 1287): MUXD 2414:open_serial_device(): Configured serial device
09-24 10:28:37.511 V/MUXD ( 1287): MUXD 2418:open_serial_device(): Switched Mux state to 1
09-24 10:28:37.511 D/MUXD ( 1287): MUXD 2623:watchdog(): Watchdog started
09-24 10:28:37.511 D/MUXD ( 1287): MUXD 2433:start_muxer(): Configuring modem
09-24 10:28:37.511 V/MUXD ( 1287): MUXD 1690:chat(): Enter
09-24 10:28:37.511 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000000: 41 54 0d 0a....- ........AT..
09-24 10:28:37.512 V/MUXD ( 1287): MUXD 1697:chat(): Wrote 4 bytes
09-24 10:28:37.512 V/MUXD ( 1287): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:37.512 V/MUXD ( 1287): MUXD 1716:chat(): Selected 1
09-24 10:28:37.613 V/MUXD ( 1287): MUXD 1723:chat(): Read 6 bytes from serial device
09-24 10:28:37.613 V/MUXD ( 1287): MUXD 752:syslogdump(): <s 00000000: 0d 0a 4f 4b 0d 0a..- ..........OK..
09-24 10:28:37.613 V/MUXD ( 1287): MUXD 1728:chat(): Received OK
09-24 10:28:37.613 V/MUXD ( 1287): MUXD 1690:chat(): Enter
09-24 10:28:37.613 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000000: 41 54 2b 49 46 43 3d 30-2c 30 0d 0a....AT+IFC=0,0..
09-24 10:28:37.613 V/MUXD ( 1287): MUXD 1697:chat(): Wrote 12 bytes
09-24 10:28:37.613 V/MUXD ( 1287): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:37.614 V/MUXD ( 1287): MUXD 1716:chat(): Selected 1
09-24 10:28:37.715 V/MUXD ( 1287): MUXD 1723:chat(): Read 6 bytes from serial device
09-24 10:28:37.715 V/MUXD ( 1287): MUXD 752:syslogdump(): <s 00000000: 0d 0a 4f 4b 0d 0a..- ..........OK..
09-24 10:28:37.715 V/MUXD ( 1287): MUXD 1728:chat(): Received OK
09-24 10:28:37.715 V/MUXD ( 1287): MUXD 1690:chat(): Enter
09-24 10:28:37.715 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000000: 41 54 5a 0d 0a...- ........ATZ..
09-24 10:28:37.715 V/MUXD ( 1287): MUXD 1697:chat(): Wrote 5 bytes
09-24 10:28:37.715 V/MUXD ( 1287): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:37.716 V/MUXD ( 1287): MUXD 1716:chat(): Selected 1
09-24 10:28:37.816 V/MUXD ( 1287): MUXD 1723:chat(): Read 6 bytes from serial device
09-24 10:28:37.816 V/MUXD ( 1287): MUXD 752:syslogdump(): <s 00000000: 0d 0a 4f 4b 0d 0a..- ..........OK..
09-24 10:28:37.816 V/MUXD ( 1287): MUXD 1728:chat(): Received OK
09-24 10:28:37.816 V/MUXD ( 1287): MUXD 1690:chat(): Enter
09-24 10:28:37.816 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000000: 41 54 45 30 0d 0a..- ........ATE0..
09-24 10:28:37.816 V/MUXD ( 1287): MUXD 1697:chat(): Wrote 6 bytes
09-24 10:28:37.817 V/MUXD ( 1287): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:37.817 V/MUXD ( 1287): MUXD 1716:chat(): Selected 1
09-24 10:28:37.917 V/MUXD ( 1287): MUXD 1723:chat(): Read 11 bytes from serial device
09-24 10:28:37.918 V/MUXD ( 1287): MUXD 752:syslogdump(): <s 00000000: 41 54 45 30 0d 0d 0a 4f-4b 0d 0a.....ATE0...OK..
09-24 10:28:37.918 V/MUXD ( 1287): MUXD 1728:chat(): Received OK
09-24 10:28:37.919 D/MUXD ( 1287): MUXD 2508:start_muxer(): ================Time:10:28:37
09-24 10:28:37.920 D/MUXD ( 1287): MUXD 2523:start_muxer(): Starting mux mode
09-24 10:28:37.920 V/MUXD ( 1287): MUXD 1690:chat(): Enter
09-24 10:28:37.920 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000000: 41 54 2b 43 4d 55 58 3d-30 2c 30 2c 35 2c 35 31AT+CMUX=0,0,5,51
09-24 10:28:37.920 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000010: 32 0d 0a.....- ........2..
09-24 10:28:37.920 V/MUXD ( 1287): MUXD 1697:chat(): Wrote 19 bytes
09-24 10:28:37.920 V/MUXD ( 1287): MUXD 1705:chat(): CHAT++++++++++++
09-24 10:28:37.921 V/MUXD ( 1287): MUXD 1716:chat(): Selected 1
09-24 10:28:38.021 V/MUXD ( 1287): MUXD 1723:chat(): Read 6 bytes from serial device
09-24 10:28:38.021 V/MUXD ( 1287): MUXD 752:syslogdump(): <s 00000000: 0d 0a 4f 4b 0d 0a..- ..........OK..
09-24 10:28:38.021 V/MUXD ( 1287): MUXD 1728:chat(): Received OK
09-24 10:28:38.021 V/MUXD ( 1287): MUXD 2526:start_muxer(): Switched Mux state to 2
09-24 10:28:38.021 D/MUXD ( 1287): MUXD 2527:start_muxer(): Waiting for mux-mode
09-24 10:28:38.172 D/MUXD ( 1287): MUXD 2531:start_muxer(): Init control channel
09-24 10:28:38.172 V/MUXD ( 1287): MUXD 2800:create_thread(): Enter
09-24 10:28:38.172 V/MUXD ( 1287): MUXD 2650:watchdog(): Thread is running and listening on /dev/ttyUSB1
09-24 10:28:38.172 V/MUXD ( 1287): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:38.172 V/MUXD ( 1287): MUXD 786:write_frame(): Sending frame to channel 0
09-24 10:28:38.172 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000000: f9 03 3f 01 1c f9..- ........щ.?..щ
09-24 10:28:38.173 V/MUXD ( 1287): MUXD 889:write_frame(): Leave
09-24 10:28:38.173 D/MUXD ( 1287): MUXD 2656:watchdog(): Allocating logical channel 1/3
09-24 10:28:38.173 V/MUXD ( 1287): MUXD 1137:c_alloc_channel(): Enter
09-24 10:28:38.173 V/MUXD ( 1287): MUXD 1143:c_alloc_channel(): Found free channel 1 fd -1 on /dev/ptmx
09-24 10:28:38.173 V/MUXD ( 1287): MUXD 1152:c_alloc_channel(): PTS: /dev/pts/1
09-24 10:28:38.173 V/MUXD ( 1287): MUXD 1160:c_alloc_channel(): Create/data/misc/rild/ Dir Success
09-24 10:28:38.174 V/MUXD ( 1287): MUXD 2216:poll_thread_serial(): Enter
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 1175:c_alloc_channel(): =====================================
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 1176:c_alloc_channel(): Create link /data/misc/rild/1 Success
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 1194:c_alloc_channel(): New channel properties: number: 1 fd: 12 device: /dev/ptmx
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 2800:create_thread(): Enter
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 1203:c_alloc_channel(): Thread is running and listening on /dev/pts/1
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 2832:poll_thread(): Enter
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 786:write_frame(): Sending frame to channel 1
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000000: f9 07 3f 01 de f9..- ........щ.?.ящ
09-24 10:28:38.212 V/MUXD ( 1287): MUXD 889:write_frame(): Leave
09-24 10:28:38.212 D/MUXD ( 1287): MUXD 1207:c_alloc_channel(): Connecting /dev/pts/1 to virtual channel 1 for watchdog_init on /dev/ttyUSB1
09-24 10:28:38.223 D/MUXD ( 1287): MUXD 2656:watchdog(): Allocating logical channel 2/3
09-24 10:28:38.223 V/MUXD ( 1287): MUXD 1137:c_alloc_channel(): Enter
09-24 10:28:38.223 V/MUXD ( 1287): MUXD 1143:c_alloc_channel(): Found free channel 2 fd -1 on /dev/ptmx
09-24 10:28:38.223 V/MUXD ( 1287): MUXD 1152:c_alloc_channel(): PTS: /dev/pts/2
09-24 10:28:38.223 V/MUXD ( 1287): MUXD 1160:c_alloc_channel(): Create/data/misc/rild/ Dir Success
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 1175:c_alloc_channel(): =====================================
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 1176:c_alloc_channel(): Create link /data/misc/rild/2 Success
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 1194:c_alloc_channel(): New channel properties: number: 2 fd: 14 device: /dev/ptmx
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 2800:create_thread(): Enter
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 1203:c_alloc_channel(): Thread is running and listening on /dev/pts/2
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 786:write_frame(): Sending frame to channel 2
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000000: f9 0b 3f 01 59 f9..- ........щ.?.Yщ
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 2832:poll_thread(): Enter
09-24 10:28:38.238 V/MUXD ( 1287): MUXD 889:write_frame(): Leave
09-24 10:28:38.238 D/MUXD ( 1287): MUXD 1207:c_alloc_channel(): Connecting /dev/pts/2 to virtual channel 2 for watchdog_init on /dev/ttyUSB1
09-24 10:28:38.249 D/MUXD ( 1287): MUXD 2656:watchdog(): Allocating logical channel 3/3
09-24 10:28:38.249 V/MUXD ( 1287): MUXD 1137:c_alloc_channel(): Enter
09-24 10:28:38.249 V/MUXD ( 1287): MUXD 1143:c_alloc_channel(): Found free channel 3 fd -1 on /dev/ptmx
09-24 10:28:38.249 V/MUXD ( 1287): MUXD 1152:c_alloc_channel(): PTS: /dev/pts/3
09-24 10:28:38.249 V/MUXD ( 1287): MUXD 1160:c_alloc_channel(): Create/data/misc/rild/ Dir Success
09-24 10:28:38.304 V/MUXD ( 1287): MUXD 1175:c_alloc_channel(): =====================================
09-24 10:28:38.304 V/MUXD ( 1287): MUXD 1176:c_alloc_channel(): Create link /data/misc/rild/3 Success
09-24 10:28:38.304 V/MUXD ( 1287): MUXD 1194:c_alloc_channel(): New channel properties: number: 3 fd: 15 device: /dev/ptmx
09-24 10:28:38.304 V/MUXD ( 1287): MUXD 2800:create_thread(): Enter
09-24 10:28:38.304 V/MUXD ( 1287): MUXD 1203:c_alloc_channel(): Thread is running and listening on /dev/pts/3
09-24 10:28:38.304 V/MUXD ( 1287): MUXD 778:write_frame(): write_frame Enter
09-24 10:28:38.304 V/MUXD ( 1287): MUXD 786:write_frame(): Sending frame to channel 3
09-24 10:28:38.304 V/MUXD ( 1287): MUXD 752:syslogdump(): >s 00000000: f9 0f 3f 01 9b f9..- ........щ.?.Џщ
09-24 10:28:38.304 V/MUXD ( 1287): MUXD 889:write_frame(): Leave
09-24 10:28:38.304 D/MUXD ( 1287): MUXD 1207:c_alloc_channel(): Connecting /dev/pts/3 to virtual channel 3 for watchdog_init on /dev/ttyUSB1
09-24 10:28:38.306 V/MUXD ( 1287): MUXD 2832:poll_thread(): Enter
09-24 10:28:38.316 D/MUXD ( 1287): MUXD 2670:watchdog(): Multiplexing started..
09-24 10:28:38.316 D/MUXD ( 1287): MUXD 3028:main(): GSM0710 buffer. Stored 0
09-24 10:28:38.316 D/MUXD ( 1287): MUXD 3029:main(): Frames received/dropped: 0/0
09-24 10:28:38.509 D/MUXD (300): MUXD 3088:gsm0710muxd(): gsm0710muxd gsm0710muxd_pid = 1287
09-24 10:28:38.509 D/RILC (300): open /data/misc/rild/1
09-24 10:28:38.510 D/RILC (300): fd = 5
09-24 10:28:38.510 D/RILC (300): open device /dev/ttyUSB1 correctly
09-24 10:28:38.510 E/ATC (300): at_open s_tild_reader = 522554713168
09-24 10:28:39.503 D/ATC (300): AT> ATI
09-24 10:28:39.504 W/MUXD ( 1287): MUXD 1060:pseudo_device_read(): Write to a channel which wasn't acked to be open.
09-24 10:28:39.504 W/MUXD ( 1287): MUXD 2847:poll_thread(): Device read function returned error
09-24 10:28:39.504 E/MUXD ( 1287): MUXD 2875:poll_thread(): Device polling thread terminated
09-24 10:28:40.432 D/SubscriptionController(751): asked for default subId=-1
09-24 10:28:40.463 D/GsmCdmaPhone(751): mBroadcastReceiver: action android.telephony.action.CARRIER_CONFIG_CHANGED
09-24 10:28:40.486 D/ImsManager(751): updateVolteFeatureValue: available = false, enabled = true, nonTTY = true
09-24 10:28:40.488 E/ImsManager(751): updateImsServiceConfig:
09-24 10:28:40.488 E/ImsManager(751): com.android.ims.ImsException: Service is unavailable, code = 106
09-24 10:28:40.488 E/ImsManager(751): at com.android.ims.ImsManager.checkAndThrowExceptionIfServiceUnavailable(ImsManager.java:1316)
09-24 10:28:40.488 E/ImsManager(751): at com.android.ims.ImsManager.getConfigInterface(ImsManager.java:1198)
09-24 10:28:40.488 E/ImsManager(751): at com.android.ims.ImsManager.updateVolteFeatureValue(ImsManager.java:780)
09-24 10:28:40.488 E/ImsManager(751): at com.android.ims.ImsManager.updateImsServiceConfig(ImsManager.java:740)
09-24 10:28:40.488 E/ImsManager(751): at com.android.internal.telephony.GsmCdmaPhone.handleMessage(GsmCdmaPhone.java:2084)
09-24 10:28:40.488 E/ImsManager(751): at android.os.Handler.dispatchMessage(Handler.java:102)
09-24 10:28:40.488 E/ImsManager(751): at android.os.Looper.loop(Looper.java:154)
09-24 10:28:40.488 E/ImsManager(751): at android.app.ActivityThread.main(ActivityThread.java:6141)
09-24 10:28:40.488 E/ImsManager(751): at java.lang.reflect.Method.invoke(Native Method)
09-24 10:28:40.488 E/ImsManager(751): at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:912)
09-24 10:28:40.488 E/ImsManager(751): at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:802)
09-24 10:28:40.493 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:40.494 D/GsmCdmaPhone(751): broadcastEmergencyCallStateChanges = false
09-24 10:28:40.496 E/GsmCdmaPhone(751): Invalid cdma_roaming_mode settings: -1
09-24 10:28:40.496 D/EriManager(751): loadEriFileFromXml: check for alternate file
09-24 10:28:40.498 D/EriManager(751): loadEriFileFromXml: no alternate file
09-24 10:28:40.509 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:40.510 D/EriManager(751): eriFile = eri.xml
09-24 10:28:40.540 D/EriManager(751): loadEriFileFromXml: eri parsing successful, file loaded. ver = 1357, # of entries = 176
09-24 10:28:40.541 D/GsmCdmaPhone(751): ERI read, notify registrants
09-24 10:28:40.560 I/CSST (751): reading time to delay notification: -1
09-24 10:28:40.564 D/SubscriptionController(751): - no sims, returning default phoneId=0
09-24 10:28:42.504 E/ATC (300): warnning - moderm no response, retry ATI
09-24 10:28:42.504 D/ATC (300): AT> ATI
09-24 10:28:43.317 D/MUXD ( 1287): MUXD 3028:main(): GSM0710 buffer. Stored 0
09-24 10:28:43.317 D/MUXD ( 1287): MUXD 3029:main(): Frames received/dropped: 0/0
时间紧迫，麻烦尽快答复我们，谢谢。 没有特殊的限制，请检查是否输入命令的问题导致
页:
[1]