Actions
Issue #1954
openAdd support for the I9300 modem
Status:
In Progress
Priority:
Normal
Assignee:
Category:
Telephony and mobile data
Target version:
Start date:
08/25/2020
Due date:
% Done:
33%
Estimated time:
(Total: 0.00 h)
Resolution:
Device:
Galaxy S 3 (I9300)
Grant:
Porting Replicant to Android 9 (NLnet Foundation)
Type of work:
C programming
Updated by Denis 'GNUtoo' Carikli about 6 years ago
This enables to track the progress in more details and not spam the PortingToAndroid9 wiki page.
Updated by Denis 'GNUtoo' Carikli about 6 years ago
Here's how the log looks on Replicant 6.0:
D use-Rlog/RLOG-RIL-IPC: Starting i9300 modem boot D use-Rlog/RLOG-RIL-IPC: Opened modem image device D use-Rlog/RLOG-RIL-IPC: Mapped modem image data to memory D use-Rlog/RLOG-RIL-IPC: Opened modem boot device D use-Rlog/RLOG-RIL-IPC: Opened modem link device D use-Rlog/RLOG-RIL-IPC: Turned the modem off D use-Rlog/RLOG-RIL-IPC: Turned the modem on D use-Rlog/RLOG-RIL-IPC: Waited for link connected D use-Rlog/RLOG-RIL-IPC: Wrote ATAT in ASCII D use-Rlog/RLOG-RIL-IPC: Reading boot ACK failed D use-Rlog/RLOG-RIL-IPC: Sending XMM626 HSIC PSI failed E use-Rlog/RLOG-RIL-IPC: Booting IPC FMT client failed E use-Rlog/RLOG-RIL: Opening IPC FMT client failed D use-Rlog/RLOG-RIL: Closed IPC FMT client D use-Rlog/RLOG-RIL-IPC: Starting i9300 modem boot D use-Rlog/RLOG-RIL-IPC: Opened modem image device D use-Rlog/RLOG-RIL-IPC: Mapped modem image data to memory D use-Rlog/RLOG-RIL-IPC: Opened modem boot device D use-Rlog/RLOG-RIL-IPC: Opened modem link device D use-Rlog/RLOG-RIL-IPC: Turned the modem off D use-Rlog/RLOG-RIL-IPC: Turned the modem on D use-Rlog/RLOG-RIL-IPC: Waited for link connected D use-Rlog/RLOG-RIL-IPC: Wrote ATAT in ASCII D use-Rlog/RLOG-RIL-IPC: Read chip id (0x16) D use-Rlog/RLOG-RIL-IPC: Wrote PSI header D use-Rlog/RLOG-RIL-IPC: Wrote PSI, CRC is 0xc8 D use-Rlog/RLOG-RIL-IPC: Wrote PSI CRC (0xc8) D use-Rlog/RLOG-RIL-IPC: Read PSI CRC ACK D use-Rlog/RLOG-RIL-IPC: Read PSI ACK D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC PSI D use-Rlog/RLOG-RIL-IPC: Wrote EBL size D use-Rlog/RLOG-RIL-IPC: Wrote EBL, CRC is 0xb5 D use-Rlog/RLOG-RIL-IPC: Wrote EBL CRC (0xb5) D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC EBL D use-Rlog/RLOG-RIL-IPC: Read port config D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC port config D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC SEC start D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC firmware D use-Rlog/RLOG-RIL-IPC: Checked nv_data path D use-Rlog/RLOG-RIL-IPC: Checked nv_data md5 path D use-Rlog/RLOG-RIL-IPC: Calculated nv_data md5: 50e04bd1ae13445977f3aec356ada08d D use-Rlog/RLOG-RIL-IPC: Read nv_data md5: 50e04bd1ae13445977f3aec356ada08d D use-Rlog/RLOG-RIL-IPC: Checked nv_data backup path D use-Rlog/RLOG-RIL-IPC: Loaded nv_data D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC nv_data D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC HW reset D use-Rlog/RLOG-RIL-IPC: Waiting for host wake failed D use-Rlog/RLOG-RIL-IPC: Waited for host wake D use-Rlog/RLOG-RIL-IPC: Waited for link connected D use-Rlog/RLOG-RIL: Opened IPC FMT client D use-Rlog/RLOG-RIL-IPC: D use-Rlog/RLOG-RIL-IPC: xmm626_sec_modem_fmt_recv: Received FMT message D use-Rlog/RLOG-RIL-IPC: xmm626_sec_modem_fmt_recv: Message: aseq=0xff, command=IPC_PWR_PHONE_PWR_UP, type=IPC_TYPE_NOTI, size=0 D use-Rlog/RLOG-RIL: Updating RIL radio state to 0 [...]
On Replicant 9.0 we have:
# adb shell # /system/bin/xmm6262_boot0.sh # exit # adb logcat -b radio [...] D use-Rlog/RLOG-RIL-IPC: Starting generic modem boot D use-Rlog/RLOG-RIL-IPC: Opened modem image device D use-Rlog/RLOG-RIL-IPC: Mapped modem image data to memory D use-Rlog/RLOG-RIL-IPC: Turned the modem off D use-Rlog/RLOG-RIL-IPC: Turned the modem on D use-Rlog/RLOG-RIL-IPC: Opened modem boot device D use-Rlog/RLOG-RIL-IPC: Wrote ATAT in ASCII D use-Rlog/RLOG-RIL-IPC: Read chip id (0x16) D use-Rlog/RLOG-RIL-IPC: Wrote PSI header D use-Rlog/RLOG-RIL-IPC: Wrote PSI, CRC is 0xc8 D use-Rlog/RLOG-RIL-IPC: Wrote PSI CRC (0xc8) D use-Rlog/RLOG-RIL-IPC: Read PSI CRC ACK D use-Rlog/RLOG-RIL-IPC: Read PSI ACK D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC PSI D use-Rlog/RLOG-RIL-IPC: Wrote EBL size D use-Rlog/RLOG-RIL-IPC: Wrote EBL, CRC is 0xb5 D use-Rlog/RLOG-RIL-IPC: Wrote EBL CRC (0xb5) D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC EBL D use-Rlog/RLOG-RIL-IPC: Read port config D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC port config D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC SEC start D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC firmware D use-Rlog/RLOG-RIL-IPC: ipc_nv_data_path_check: nv_data path: /efs/nv_data.bin D use-Rlog/RLOG-RIL-IPC: ipc_nv_data_path_check: nv_data size: 2097152 D use-Rlog/RLOG-RIL-IPC: Checked nv_data path D use-Rlog/RLOG-RIL-IPC: Checked nv_data md5 path D use-Rlog/RLOG-RIL-IPC: Calculated nv_data md5: 50e04bd1ae13445977f3aec356ada08d D use-Rlog/RLOG-RIL-IPC: Read nv_data md5: 50e04bd1ae13445977f3aec356ada08d D use-Rlog/RLOG-RIL-IPC: Checked nv_data backup path D use-Rlog/RLOG-RIL-IPC: Loaded nv_data D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC nv_data D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC SEC end D use-Rlog/RLOG-RIL-IPC: Sent XMM626 HSIC HW reset D use-Rlog/RLOG-RIL-IPC: Waited for host wake D use-Rlog/RLOG-RIL-IPC: Waited for link connected E use-Rlog/RLOG-RIL-IPC: Opening IPC FMT client failed E use-Rlog/RLOG-RIL: Opening IPC FMT client failed [...]
Under Parabola with the same kernel and ipc-test we get a similar log.
After some investigations, loading the modem firmware has been validated.
However after the firmware has been loaded, the software stack is supposed to reset the HSIC link and after some messing with the modem GPIOs through sysfs, new USB descriptors are supposed to appear. Right now they don't appear.
Updated by Denis 'GNUtoo' Carikli about 6 years ago
TODO:
- Investigate how the XMM626_SEC_HOSTWAKE_PATH is supposed to work. I also modified the driver to remove the IRQ related to that as it was conflicting with other uses of that GPIO.
- Investigate what exactly the other modem sysfs nodes are supposed to do.
- Investigate more the EHCI reset hack
Also note that this modem code has not been validated either on older kernel versions.
Updated by Denis 'GNUtoo' Carikli about 6 years ago
The modem finally booted:
[root@u-boot-i9300 ~]# ipc-test ENTER ipc_client_create ipc_client_create: IPC_CLIENT_TYPE_FMT ipc_client_create: complete ipc_client_create: return client main: ipc_client_create(IPC_CLIENT_TYPE_FMT) => 0 main: ipc_client_log_callback_register(client, log_callback, NULL) => 0 main: ipc_client_data_create(client) => 0 Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub [ipc] Starting generic modem boot [ipc] Opened modem image device [ipc] Mapped modem image data to memory [ipc] Turned the modem off generic_boot: xmm626_sec_modem_power(NULL, 1) = 0 generic_boot: xmm626_sec_modem_hci_power(1) = 0 [ipc] Turned the modem on Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub [ipc] Opened modem boot device Bus 001 Device 002: ID 058b:0041 Infineon Technologies Flash Loader utility Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub [ipc] Wrote ATAT in ASCII [ipc] Read chip id (0x16) [ipc] Wrote PSI header [ipc] Wrote PSI, CRC is 0xc8 [ipc] Wrote PSI CRC (0xc8) [ipc] Read PSI CRC ACK [ipc] Read PSI ACK [ipc] Sent XMM626 HSIC PSI [ipc] Wrote EBL size [ipc] Wrote EBL, CRC is 0xb5 [ipc] Wrote EBL CRC (0xb5) [ipc] Sent XMM626 HSIC EBL [ipc] Read port config [ipc] Sent XMM626 HSIC port config Bus 001 Device 002: ID 058b:0041 Infineon Technologies Flash Loader utility Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub [ipc] Sent XMM626 HSIC SEC start [ipc] Sent XMM626 HSIC firmware Bus 001 Device 002: ID 058b:0041 Infineon Technologies Flash Loader utility Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub [ipc] ipc_nv_data_path_check: nv_data path: /efs/nv_data.bin [ipc] ipc_nv_data_path_check: nv_data size: 2097152 [ipc] Checked nv_data path [ipc] Checked nv_data md5 path [ipc] Calculated nv_data md5: 50e04bd1ae13445977f3aec356ada08d [ipc] Read nv_data md5: 50e04bd1ae13445977f3aec356ada08d [ipc] Checked nv_data backup path [ipc] Loaded nv_data [ipc] Sent XMM626 HSIC nv_data [ipc] Sent XMM626 HSIC SEC end Bus 001 Device 002: ID 058b:0041 Infineon Technologies Flash Loader utility Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub [ipc] Sent XMM626 HSIC HW reset Bus 001 Device 002: ID 058b:0041 Infineon Technologies Flash Loader utility Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub xmm626_sec_modem_link_get_hostwake_wait: i=0 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=1 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=2 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=3 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=4 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=5 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=6 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=7 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=8 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=9 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 [ipc] Waited for host wake Bus 001 Device 002: ID 058b:0041 Infineon Technologies Flash Loader utility Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub [ipc] Turned off the modem xmm626_sec_modem_link_get_hostwake_wait: i=0 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=1 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=2 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=3 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=4 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=5 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=6 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=7 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=8 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 xmm626_sec_modem_link_get_hostwake_wait: i=9 read(/sys/devices/platform/xmm6262/hostwake) => status: 0 [ipc] Waited for host wake [ipc] Turned the modem on Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub [ipc] generic_boot complete Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub [root@u-boot-i9300 ~]# lsusb Bus 001 Device 002: ID 1519:0020 Comneon HSIC Device Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
And the corresponding dmesg:
[root@u-boot-i9300 ~]# dmesg [ 26.085293] max77693-muic max77693-muic: external connector is attached(chg_type:0x1, prev_chg_type:0x1) [ 26.094458] max77693-muic max77693-muic: CONTROL1 : 0x09, CONTROL2 : 0x04, state : attached [ 31.270306] gpiohack xmm6262: new state: 0 [ 31.273001] exynos-ehci 12580000.ehci: Powering off EHCI [ 31.280444] exynos-ehci 12580000.ehci: remove, state 1 [ 31.284112] usb usb1: USB disconnect, device number 1 [ 31.295131] exynos-ehci 12580000.ehci: USB bus 1 deregistered [ 31.350218] gpiohack xmm6262: link active? 0 [ 31.353441] gpiohack xmm6262: new state: 1 [ 31.625132] gpiohack xmm6262: pda active => 1 [ 31.628647] exynos-ehci 12580000.ehci: Powering on EHCI [ 31.633557] exynos-ehci 12580000.ehci: EHCI Host Controller [ 31.639063] exynos-ehci 12580000.ehci: new USB bus registered, assigned bus number 1 [ 31.647179] exynos-ehci 12580000.ehci: irq 58, io mem 0x12580000 [ 31.674509] exynos-ehci 12580000.ehci: USB 2.0 started, EHCI 1.00 [ 31.679459] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.03 [ 31.687484] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 31.694655] usb usb1: Product: EHCI Host Controller [ 31.699461] usb usb1: Manufacturer: Linux 5.3.0-rc8+ ehci_hcd [ 31.705222] usb usb1: SerialNumber: 12580000.ehci [ 31.712308] hub 1-0:1.0: USB hub found [ 31.714653] hub 1-0:1.0: 3 ports detected [ 31.770222] gpiohack xmm6262: link active? 1 [ 32.084589] usb 1-2: new high-speed USB device number 2 using exynos-ehci [ 32.286228] usb 1-2: config 1 interface 0 altsetting 0 endpoint 0x81 has an invalid bInterval 255, changing to 11 [ 32.295155] usb 1-2: New USB device found, idVendor=058b, idProduct=0041, bcdDevice= 0.00 [ 32.303184] usb 1-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 32.317235] xmm6262_boot 1-2:1.0: ENTER xmm6262_probe [ 32.321529] xmm6262_boot 1-2:1.0: Loaded XMM6262 boot serial device! [ 32.325300] ENTER xmm6262_boot_open [ 32.332038] xmm6262_boot 1-2:1.0: xmm6262_boot_open: got dev [ 32.336375] xmm6262_boot 1-2:1.0: xmm6262_boot_open: return 0; [ 39.166477] xmm6262_boot 1-2:1.0: nonzero bulk read status: -71 [ 39.228235] gpiohack xmm6262: phone_active: cp_reset=1, phone_active=1, cp_dump=0 [ 39.234260] gpiohack xmm6262: BOOTING [ 44.425149] exynos-ehci 12580000.ehci: Powering off EHCI [ 44.429111] exynos-ehci 12580000.ehci: remove, state 1 [ 44.434106] usb usb1: USB disconnect, device number 1 [ 44.442866] usb 1-2: USB disconnect, device number 2 [ 44.451825] exynos-ehci 12580000.ehci: USB bus 1 deregistered [ 44.508775] gpiohack xmm6262: link active? 0 [ 49.609709] gpiohack xmm6262: pda active => 1 [ 49.612682] exynos-ehci 12580000.ehci: Powering on EHCI [ 49.620936] exynos-ehci 12580000.ehci: EHCI Host Controller [ 49.625160] exynos-ehci 12580000.ehci: new USB bus registered, assigned bus number 1 [ 49.633296] exynos-ehci 12580000.ehci: irq 58, io mem 0x12580000 [ 49.664542] exynos-ehci 12580000.ehci: USB 2.0 started, EHCI 1.00 [ 49.669652] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.03 [ 49.677442] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 49.684628] usb usb1: Product: EHCI Host Controller [ 49.689455] usb usb1: Manufacturer: Linux 5.3.0-rc8+ ehci_hcd [ 49.695295] usb usb1: SerialNumber: 12580000.ehci [ 49.700947] hub 1-0:1.0: USB hub found [ 49.703867] hub 1-0:1.0: 3 ports detected [ 49.758596] gpiohack xmm6262: link active? 1 [ 50.074668] usb 1-2: new high-speed USB device number 2 using exynos-ehci [ 50.291938] usb 1-2: New USB device found, idVendor=1519, idProduct=0020, bcdDevice=12.74 [ 50.298812] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 50.305913] usb 1-2: Product: HSIC Device [ 50.309758] usb 1-2: Manufacturer: Comneon [ 50.313847] usb 1-2: SerialNumber: 0123456789
libsamsung-ipc: 5a1f981743c0df1d1686632e8897d455b9da18a9
Linux: f72efb034be201930826bf5b04239f9cf3c529f8
Updated by Denis 'GNUtoo' Carikli about 6 years ago
We now can receive samsung-ipc messages:
[ipc] generic_boot complete Bus 001 Device 002: ID 1519:0020 Comneon HSIC Device Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub main: ipc_client_boot(client) => 0 main: ipc_client_power_on(client) => 0 [ipc] ipc_client_open: client: 0x487598 client->handlers: 0x4875b8 client->handlers->open: 0xb6ed0090 [ipc] ipc_client_open: ->open(client->handlers->transport_data:0x4875f8, client->type:(nil) generic_open: data: 0x4875f8, type: (nil) ENTER xmm626_sec_modem_open xmm626_sec_modem_open: type: 0 generic_open: return 0; main: ipc_client_open(client) => 0 poll returns 1 [ipc] [ipc] xmm626_sec_modem_fmt_recv: Received FMT message [ipc] xmm626_sec_modem_fmt_recv: Message: aseq=0xff, command=IPC_PWR_PHONE_PWR_UP, type=IPC_TYPE_NOTI, size=0 poll returns 1 [ipc] [ipc] xmm626_sec_modem_fmt_recv: Received FMT message [ipc] xmm626_sec_modem_fmt_recv: Message: aseq=0x00, command=IPC_MISC_ME_IMSI, type=IPC_TYPE_NOTI, size=16
Updated by dl lud over 5 years ago
- Target version changed from Replicant 9.0 to replicant 10.0
Updated by Denis 'GNUtoo' Carikli about 5 years ago
- Target version changed from replicant 10.0 to Replicant 11.0 0001
Updated by Denis 'GNUtoo' Carikli about 5 years ago
- Grant set to Porting Replicant to Android 9 (NLnet Foundation)
Updated by Denis 'GNUtoo' Carikli about 5 years ago
Now that the modem has been validated to work (with ugly code, blocking bugs, etc which is not usable as-is), most of actual focus of the work is to clean that up and integrating it in the various components.
Updated by Denis 'GNUtoo' Carikli almost 5 years ago
Right now under Replicant 11 I have:
console:/ # logcat -b radio
--------- beginning of radio
01-10 17:57:37.583 283 283 D RILD : **RIL Daemon Started**
01-10 17:57:37.583 283 283 D RILD : **RILd param count=6**
01-10 17:57:37.626 287 287 D RILD : **RIL Daemon Started**
01-10 17:57:37.627 287 287 D RILD : **RILd param count=1**
01-10 17:57:37.653 283 283 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:57:37.653 283 283 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:57:37.700 287 287 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:57:37.701 287 287 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:57:37.956 287 287 D RILD : RIL_Init rilInit completed
01-10 17:57:37.956 287 287 I RILC : SIM_COUNT: 1
01-10 17:57:37.957 287 287 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:57:37.957 287 287 D RILD : RIL_Init RIL_register completed
01-10 17:57:37.957 287 287 D RILD : RIL_register_socket completed
01-10 17:57:41.721 206 206 D TelephonyManager: /proc/cmdline= console=ram loglevel=8 sec_debug.level=0 sec_watchdog.sec_pet=5 androidboot.debug_level=0xXXXX sec_log=0x100000@0x46000000 s3cfb.bootloaderfb=XXXXXXXXXX lcdtype=XX consoleblank=0 lpcharge=0 lpj=XXXXXXX vmalloc=144m oops=panic pmic_info=65 cordon=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX connie=GT-I9300_XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX androidboot.emmc_checksum=3 androidboot.odin_download=1 androidboot.bootloader=XXXXXXXXXXXX androidboot.serialno=XXXXXXXXXXXXXXXX snd_soc_core.pmdown_time=1000 root=PARTLABEL=SYSTEM init=/init rootwait console=ttySAC2,115200 loglevel=8 androidboot.hardware=smdk4x12 androidboot.selinux=permissive enforcing=0 exynosdrm.pixel_order=1
01-10 17:57:42.357 351 351 D RILD : **RIL Daemon Started**
01-10 17:57:42.357 351 351 D RILD : **RILd param count=1**
01-10 17:57:42.365 351 351 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:57:42.365 351 351 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:57:42.623 351 351 D RILD : RIL_Init rilInit completed
01-10 17:57:42.623 351 351 I RILC : SIM_COUNT: 1
01-10 17:57:42.623 351 351 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:57:42.623 351 351 D RILD : RIL_Init RIL_register completed
01-10 17:57:42.623 351 351 D RILD : RIL_register_socket completed
01-10 17:57:47.356 408 408 D RILD : **RIL Daemon Started**
01-10 17:57:47.356 408 408 D RILD : **RILd param count=1**
01-10 17:57:47.365 408 408 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:57:47.365 408 408 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:57:47.622 408 408 D RILD : RIL_Init rilInit completed
01-10 17:57:47.623 408 408 I RILC : SIM_COUNT: 1
01-10 17:57:47.623 408 408 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:57:47.623 408 408 D RILD : RIL_Init RIL_register completed
01-10 17:57:47.623 408 408 D RILD : RIL_register_socket completed
01-10 17:57:52.368 508 508 D RILD : **RIL Daemon Started**
01-10 17:57:52.368 508 508 D RILD : **RILd param count=1**
01-10 17:57:52.378 508 508 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:57:52.378 508 508 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:57:52.634 508 508 D RILD : RIL_Init rilInit completed
01-10 17:57:52.634 508 508 I RILC : SIM_COUNT: 1
01-10 17:57:52.634 508 508 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:57:52.634 508 508 D RILD : RIL_Init RIL_register completed
01-10 17:57:52.634 508 508 D RILD : RIL_register_socket completed
01-10 17:57:52.714 364 364 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:52.864 364 449 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:52.931 364 364 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:53.060 364 364 D TelephonyRegistry: systemRunning register for intents
01-10 17:57:53.610 364 389 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:53.893 364 379 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:54.635 654 654 D TelephonyComponentFactory: validated paths: null
01-10 17:57:54.635 654 654 D TelephonyComponentFactory: Total components injected: 0
01-10 17:57:54.637 654 654 D TDC : updateOrInsert: inserting: Modem { uuid=modem, state=0, rilModel=0, rat={}, maxActiveVoiceCall=1, maxActiveDataCall=1, maxStandby=1 }
01-10 17:57:54.638 654 654 D TDC : updateOrInsert: inserting: Sim { uuid=sim, modemUuid=modem, state=0 }
01-10 17:57:54.672 654 654 E PersistAtomsStorage: cannot load/parse PersistAtoms
01-10 17:57:54.672 654 654 E PersistAtomsStorage: java.nio.file.NoSuchFileException: /data/user_de/0/com.android.phone/files/persist_atoms.pb
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at sun.nio.fs.UnixFileSystemProvider.newByteChannel(UnixFileSystemProvider.java:214)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at java.nio.file.Files.newByteChannel(Files.java:361)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at java.nio.file.Files.newByteChannel(Files.java:407)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at java.nio.file.Files.readAllBytes(Files.java:3152)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at com.android.internal.telephony.metrics.PersistAtomsStorage.loadAtomsFromFile(PersistAtomsStorage.java:132)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at com.android.internal.telephony.metrics.PersistAtomsStorage.<init>(PersistAtomsStorage.java:60)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at com.android.internal.telephony.metrics.MetricsCollector.<init>(MetricsCollector.java:88)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at com.android.internal.telephony.PhoneFactory.makeDefaultPhone(PhoneFactory.java:149)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at com.android.internal.telephony.PhoneFactory.makeDefaultPhones(PhoneFactory.java:105)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at com.android.phone.PhoneGlobals.onCreate(PhoneGlobals.java:339)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at com.android.phone.PhoneApp.onCreate(PhoneApp.java:39)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at android.app.Instrumentation.callApplicationOnCreate(Instrumentation.java:1192)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at android.app.ActivityThread.handleBindApplication(ActivityThread.java:6712)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at android.app.ActivityThread.access$1300(ActivityThread.java:237)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1913)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at android.os.Handler.dispatchMessage(Handler.java:106)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at android.os.Looper.loop(Looper.java:223)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at android.app.ActivityThread.main(ActivityThread.java:7656)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at java.lang.reflect.Method.invoke(Native Method)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:592)
01-10 17:57:54.672 654 654 E PersistAtomsStorage: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:947)
01-10 17:57:54.676 654 654 D PersistAtomsStorage: created new PersistAtoms
01-10 17:57:54.682 654 654 D MetricsCollector: registered
01-10 17:57:54.687 654 654 D CdmaSSM : subscriptionSource from settings: 0
01-10 17:57:54.689 654 654 I PhoneFactory: Cdma Subscription set to 0
01-10 17:57:54.690 654 654 I PhoneFactory: Network Mode set to 0
01-10 17:57:54.691 654 654 D RILJ : RIL: init preferredNetworkType=0 cdmaSubscription=0) [PHONEnull]
01-10 17:57:54.741 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:57:54.741 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:57:54.741 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:57:54.742 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:57:54.744 654 654 E RILJ : IOemHook service is not on the device HAL: java.util.NoSuchElementException [PHONE0]
01-10 17:57:54.744 654 654 D RILJ : Radio HAL version: -1.-1 [PHONE0]
01-10 17:57:54.744 654 654 D UiccController: Creating UiccController
01-10 17:57:54.745 654 654 D UiccController: config_num_physical_slots = 1
01-10 17:57:54.766 654 654 I PhoneFactory: Creating SubscriptionController
01-10 17:57:54.854 364 364 D TelephonyRegistry: notifyCellLocationForSubscriber: subId=0 cellLocation=null
01-10 17:57:54.885 654 673 D SubscriptionController: [getActiveSubscriptionInfo]- subId=0 subList=[] subInfo=null
01-10 17:57:54.888 654 654 D SubscriptionController: [SubscriptionController] init by Context
01-10 17:57:54.911 654 654 I PhoneFactory: Creating Phone with type = 1 phoneId = 0
01-10 17:57:54.913 654 654 D TelephonyTester: register for intent action=com.android.internal.telephony.action_detached
01-10 17:57:54.913 654 654 D TelephonyTester: register for intent action=com.android.internal.telephony.action_attached
01-10 17:57:54.913 654 654 D TelephonyTester: register for intent action=com.android.internal.telephony.TestServiceState
01-10 17:57:54.913 654 654 D TelephonyTester: register for intent action=com.android.internal.telephony.TestChangeNumber
01-10 17:57:54.937 654 654 D Phone : mDoesRilSendMultipleCallRing=true
01-10 17:57:54.937 654 654 D Phone : mCallRingDelay=3000
01-10 17:57:54.996 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:57:54.996 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:57:55.002 654 654 D SmsDispatchersController: SmsDispatchersController created
01-10 17:57:55.037 654 654 E ImsSmsDispatcher [0]: Failed to get sms format. Error: getSmsFormat()
01-10 17:57:55.037 654 654 D SMSDispatcher: SMSDispatcher: ctor mSmsCapable=true format=unknown mSmsSendDisabled=false
01-10 17:57:55.038 654 654 W FeatureConnector: [ImsSmsDispatcher, 0] connect: not supported.
01-10 17:57:55.051 654 654 D SMSDispatcher: SMSDispatcher: ctor mSmsCapable=true format=3gpp2 mSmsSendDisabled=false
01-10 17:57:55.051 654 654 D CdmaSMSDispatcher: CdmaSMSDispatcher created
01-10 17:57:55.060 654 654 D GsmInboundSmsHandler: created InboundSmsHandler
01-10 17:57:55.076 654 818 D GsmInboundSmsHandler: StartupState.enter: entering StartupState
01-10 17:57:55.087 654 654 D CdmaInboundSmsHandler: created InboundSmsHandler
01-10 17:57:55.096 654 819 D CdmaInboundSmsHandler: StartupState.enter: entering StartupState
01-10 17:57:55.100 654 654 D SMSDispatcher: SMSDispatcher: ctor mSmsCapable=true format=3gpp mSmsSendDisabled=false
01-10 17:57:55.101 654 654 D GsmSMSDispatcher: GsmSMSDispatcher created
01-10 17:57:55.102 654 818 D GsmInboundSmsHandler: StartupState.processMessage: processing EVENT_START_ACCEPTING_SMS
01-10 17:57:55.104 654 819 D CdmaInboundSmsHandler: StartupState.processMessage: processing EVENT_START_ACCEPTING_SMS
01-10 17:57:55.104 654 819 D CdmaInboundSmsHandler: IdleState.enter: entering IdleState
01-10 17:57:55.104 654 818 D GsmInboundSmsHandler: IdleState.enter: entering IdleState
01-10 17:57:55.104 654 819 D CdmaInboundSmsHandler: IdleState.processMessage: processing EVENT_RELEASE_WAKELOCK
01-10 17:57:55.105 654 818 D GsmInboundSmsHandler: IdleState.processMessage: processing EVENT_RELEASE_WAKELOCK
01-10 17:57:55.107 654 818 D GsmInboundSmsHandler: IdleState.processMessage: EVENT_RELEASE_WAKELOCK: mWakeLock released
01-10 17:57:55.108 654 819 D CdmaInboundSmsHandler: IdleState.processMessage: EVENT_RELEASE_WAKELOCK: mWakeLock released
01-10 17:57:55.109 654 654 D CdmaSSM : subscriptionSource from settings: 0
01-10 17:57:55.109 654 654 D CdmaSSM : cdmaSSM constructor: 0
01-10 17:57:55.114 654 654 D GsmCdmaPhone: [0] Precise phone type 1
01-10 17:57:55.114 654 654 D RILJ : setPhoneType=1 old value=0 [PHONE0]
01-10 17:57:55.120 654 654 D CarrierActionAgent: [0]Creating CarrierActionAgent
01-10 17:57:55.121 654 654 W CarrierConfigManager: Error getting config for subId -1 ICarrierConfigLoader is null
01-10 17:57:55.123 654 654 D TransportManager: operates in legacy mode.
01-10 17:57:55.131 654 654 W CarrierConfigManager: Error getting config for subId -1 ICarrierConfigLoader is null
01-10 17:57:55.133 364 378 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:55.137 654 654 D NRM-C-0 : registerForNetworkRegistrationInfoChanged
01-10 17:57:55.186 654 654 D NewNitzStateMachineImpl: handleNetworkUnavailable(): mLatestNitzSignal was already null. Nothing to do.
01-10 17:57:55.192 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:57:55.192 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:57:55.192 654 654 D SST : [0] notifyVoiceRegStateRilRadioTechnologyChanged: vrs=1 rat=0
01-10 17:57:55.198 364 675 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:55.340 654 654 E EmergencyNumberTracker: Initialize ota emergency database file input failure: java.io.FileNotFoundException: /data/misc/emergencynumberdb/emergency_number_db: open failed: ENOENT (No such file or directory)
01-10 17:57:55.341 654 654 W CarrierConfigManager: Error getting config for subId -1 ICarrierConfigLoader is null
01-10 17:57:55.504 654 654 D EmergencyNumberTracker: asset emergency database is loaded. Ver: 1 Phone Id: 0
01-10 17:57:55.506 654 654 E EmergencyNumberTracker: Cache ota emergency database IOException: java.io.FileNotFoundException: /data/misc/emergencynumberdb/emergency_number_db: open failed: ENOENT (No such file or directory)
01-10 17:57:55.506 654 654 D EmergencyNumberTracker: Using Asset Emergency database. Version: 1
01-10 17:57:55.507 364 378 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:55.525 498 498 W CarrierConfigManager: Error getting config for subId -1 ICarrierConfigLoader is null
01-10 17:57:55.528 654 654 D SubscriptionController: Valid row not present in db
01-10 17:57:55.530 654 654 D SubscriptionController: getSubscriptionProperty Query value = null
01-10 17:57:55.539 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:55.582 364 378 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:55.603 654 654 D m.android.phon: DCT.constructor
01-10 17:57:55.620 654 654 D Dcc-C-0 : E ctor
01-10 17:57:55.621 654 654 D Dcc-C-0 : X ctor
01-10 17:57:55.621 654 654 D DcTesterDeacativateAll: register for intent action=com.android.internal.telephony.dataconnection.action_deactivate_all
01-10 17:57:55.621 654 654 D DcTesterDeacativateAll: register for intent action=com.android.internal.telephony.action_detached
01-10 17:57:55.626 654 654 D DcTesterFailBrinupAll: register for intent action=com.android.internal.telephony.dataconnection.action_fail_bringup
01-10 17:57:55.626 654 654 D DcTesterFailBrinupAll: register for intent action=com.android.internal.telephony.action_detached
01-10 17:57:55.626 654 654 D DcTesterFailBrinupAll: register for intent action=com.android.internal.telephony.action_attached
01-10 17:57:55.630 654 654 W CarrierConfigManager: Error getting config for subId -1 ICarrierConfigLoader is null
01-10 17:57:55.631 654 654 D DCT-C-0 : initApnContexts: E
01-10 17:57:55.631 654 654 W ApnConfigTypeRepository: carrier config is null
01-10 17:57:55.632 654 654 D DCT-C-0 : initApnContexts: apnContext=hipri
01-10 17:57:55.632 654 654 D DCT-C-0 : initApnContexts: apnContext=fota
01-10 17:57:55.633 654 654 D DCT-C-0 : initApnContexts: apnContext=ims
01-10 17:57:55.633 654 654 D DCT-C-0 : initApnContexts: apnContext=cbs
01-10 17:57:55.634 654 654 D DCT-C-0 : initApnContexts: apnContext=ia
01-10 17:57:55.634 654 654 D DCT-C-0 : initApnContexts: apnContext=emergency
01-10 17:57:55.634 654 654 D DCT-C-0 : initApnContexts: apnContext=mcx
01-10 17:57:55.634 654 654 D DCT-C-0 : initApnContexts: apnContext=xcap
01-10 17:57:55.635 654 654 D DCT-C-0 : initApnContexts: apnContext=default
01-10 17:57:55.635 654 654 D DCT-C-0 : initApnContexts: apnContext=mms
01-10 17:57:55.635 654 654 D DCT-C-0 : initApnContexts: apnContext=supl
01-10 17:57:55.636 654 654 D DCT-C-0 : initApnContexts: apnContext=dun
01-10 17:57:55.669 654 654 D DCT-C-0 : Adding emergency APN : [ApnSettingV7] Emergency, 0, null, sos, null, null, null, null, null, 0, emergency, IPV4V6, IPV4V6, false, 0, false, 0, 0, 0, 0, null, null, false, 135168, 0, -1, -1
01-10 17:57:55.672 654 654 D CarrierResolver: Creating CarrierResolver[0]
01-10 17:57:55.675 654 654 D SST : [0] notifyVoiceRegStateRilRadioTechnologyChanged: vrs=1 rat=0
01-10 17:57:55.680 654 654 I GsmCdmaPhone: [0] updateTtyMode ttyMode=0
01-10 17:57:55.680 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:57:55.680 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:57:55.683 654 654 I GsmCdmaPhone: [0] updateUiTtyMode ttyMode=0
01-10 17:57:55.683 654 654 D GsmCdmaPhone: [0] GsmCdmaPhone: constructor: sub = 0
01-10 17:57:55.743 654 654 I PhoneFactory: defaultSmsApplication: com.android.messaging
01-10 17:57:55.746 654 654 I PhoneFactory: Creating SubInfoRecordUpdater
01-10 17:57:55.751 654 654 D SubscriptionInfoUpdater: Constructor invoked
01-10 17:57:55.766 654 654 I PhoneFactory: IMS is not supported on this device, skipping ImsResolver.
01-10 17:57:55.769 364 676 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:55.772 654 654 D PhoneSwitcher: PhoneSwitcher started
01-10 17:57:55.775 654 654 D ProxyController: Constructor - Enter
01-10 17:57:55.783 654 654 D ProxyController: clearTransaction
01-10 17:57:55.783 654 654 D ProxyController: clearTransaction: phoneId=0 status=IDLE
01-10 17:57:55.784 654 654 D ProxyController: Constructor - Exit
01-10 17:57:55.802 364 676 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:55.802 654 654 D TelephonyNetworkFactory[0]: Registering NetworkFactory
01-10 17:57:55.809 654 654 D CallManager: registerPhone(GSM Handler (com.android.internal.telephony.GsmCdmaPhone) {5dd2b4d})
01-10 17:57:55.843 364 675 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:55.844 654 654 D CallManager: registerForMmiComplete
01-10 17:57:55.846 654 654 D NewNitzStateMachineImpl: handleAirplaneModeChanged(true): mLatestNitzSignal was already null. Nothing to do.
01-10 17:57:55.855 364 675 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:57:55.863 654 654 D UiccController: handleMessage: Received RADIO_UNAVAILABLE for phoneId 0
01-10 17:57:55.863 654 654 D UiccController: EVENT_RADIO_UNAVAILABLE, dispose card
01-10 17:57:56.080 654 654 D Phone : config LCE service failed: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE
01-10 17:57:56.080 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:57:56.081 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:57:56.081 654 654 D GsmSMSDispatcher: GsmSMSDispatcher: subId = -1 slotId = 0
01-10 17:57:56.081 654 654 D GsmCdmaPhone: [0] Event EVENT_RADIO_OFF_OR_NOT_AVAILABLE Received
01-10 17:57:56.082 654 654 D GsmCdmaPhone: [0] EVENT EVENT_RADIO_STATE_CHANGED
01-10 17:57:56.082 654 654 D GsmCdmaPhone: handleRadioPowerStateChange, state= 2
01-10 17:57:56.083 654 654 D SST : [0] EVENT_ICC_CHANGED: SIM absent
01-10 17:57:56.083 654 654 D SST : [0] cancelAllNotifications: mPrevSubId=-1
01-10 17:57:56.089 654 654 D NRM-C-0 : Trying to bind com.android.phone for transport WWAN
01-10 17:57:56.093 654 654 D SST : [0] mDeviceShuttingDown=false, mDesiredPowerState=false, getRadioState=2, mPowerOffDelayNeed=true, mAlarmSwitch=false, mRadioDisabledByCarrier=false
01-10 17:57:56.094 654 654 D SST : [0] pollState: modemTriggered=true
01-10 17:57:56.094 654 654 D NewNitzStateMachineImpl: handleNetworkUnavailable(): mLatestNitzSignal was already null. Nothing to do.
01-10 17:57:56.095 654 654 D SST : [0] Poll ServiceState done: oldSS=[{mVoiceRegState=1(OUT_OF_SERVICE), mDataRegState=1(OUT_OF_SERVICE), mChannelNumber=-1, duplexMode()=0, mCellBandwidths=[], mOperatorAlphaLong=null, mOperatorAlphaShort=null, isManualNetworkSelection=false(automatic), getRilVoiceRadioTechnology=0(Unknown), getRilDataRadioTechnology=0(Unknown), mCssIndicator=unsupported, mNetworkId=-1, mSystemId=-1, mCdmaRoamingIndicator=-1, mCdmaDefaultRoamingIndicator=-1, mIsEmergencyOnly=false, isUsingCarrierAggregation=false, mLteEarfcnRsrpBoost=0, mNetworkRegistrationInfos=[NetworkRegistrationInfo{ domain=CS transportType=WWAN registrationState=UNKNOWN roamingType=NOT_ROAMING accessNetworkTechnology=UNKNOWN rejectCause=0 emergencyEnabled=false availableServices=[] cellIdentity=null voiceSpecificInfo=null dataSpecificInfo=null nrState=NONE rRplmn=}, NetworkRegistrationInfo{ domain=PS transportType=WWAN registrationState=UNKNOWN roamingType=NOT_ROAMING accessNetworkTechnology=UNKNOWN rejectCause=0 emergencyEnabled=false availableServices=[] cellIdentity=null voiceSpecificInfo=null dataSpecificInfo=null nrState=NONE rRplmn=}], mNrFrequencyRange=0, mOperatorAlphaLongRaw=null, mOperatorAlphaShortRaw=null, mIsDataRoamingFromRegistration=false, mIsIwlanPreferred=false}] newSS=[{mVoiceRegState=1(OUT_OF_SERVICE), mDataRegState=1(OUT_OF_SERVICE), mChannelNumber=-1, duplexMode()=0, mCellBandwidths=[], mOperatorAlphaLong=null, mOperatorAlphaShort=null, isManualNetworkSelection=false(automatic), getRilVoiceRadioTechnology=0(Unknown), getRilDataRadioTechnology=0(Unknown), mCssIndicator=unsupported, mNetworkId=-1, mSystemId=-1, mCdmaRoamingIndicator=-1, mCdmaDefaultRoamingIndicator=-1, mIsEmergencyOnly=false, isUsingCarrierAggregation=false, mLteEarfcnRsrpBoost=0, mNetworkRegistrationInfos=[NetworkRegistrationInfo{ domain=CS transportType=WWAN registrationState=UNKNOWN roamingType=NOT_ROAMING accessNetworkTechnology=UNKNOWN rejectCause=0 emergencyEnabled=false availableServices=[] cellIdentity=null voiceSpecificInfo=null dataSpecificInfo=null nrState=NONE rRplmn=}, NetworkRegistrationInfo{ domain=PS transportType=WWAN registrationState=UNKNOWN roamingType=NOT_ROAMING accessNetworkTechnology=UNKNOWN rejectCause=0 emergencyEnabled=false availableServices=[] cellIdentity=null voiceSpecificInfo=null dataSpecificInfo=null nrState=NONE rRplmn=}], mNrFrequencyRange=0, mOperatorAlphaLongRaw=null, mOperatorAlphaShortRaw=null, mIsDataRoamingFromRegistration=false, mIsIwlanPreferred=false}] oldMaxDataCalls=1 mNewMaxDataCalls=1 oldReasonDataDenied=-1 mNewReasonDataDenied=-1
01-10 17:57:56.099 654 654 D SST : [0] pollStateDone: hasRegistered = false hasDeregistered = false hasDataAttached = {1=false} hasDataDetached = {1=false} hasDataRegStateChanged = {1=false} hasRilVoiceRadioTechnologyChanged = false hasRilDataRadioTechnologyChanged = {1=false} hasDataTransportPreferenceChanged = false hasChanged = false hasVoiceRoamingOn = false hasVoiceRoamingOff = false hasDataRoamingOn =false hasDataRoamingOff = false hasLocationChanged = false has4gHandoff = false hasMultiApnSupport = false hasLostMultiApnSupport = false hasCssIndicatorChanged = false hasNrFrequencyRangeChanged = false hasNrStateChanged = false hasAirplaneModeOnlChanged = false
01-10 17:57:56.099 654 654 D SST : [0] mDeviceShuttingDown=false, mDesiredPowerState=false, getRadioState=2, mPowerOffDelayNeed=true, mAlarmSwitch=false, mRadioDisabledByCarrier=false
01-10 17:57:56.100 654 654 D SST : [0] pollState: modemTriggered=true
01-10 17:57:56.100 654 654 D NewNitzStateMachineImpl: handleNetworkUnavailable(): mLatestNitzSignal was already null. Nothing to do.
01-10 17:57:56.102 654 654 D SST : [0] Poll ServiceState done: oldSS=[{mVoiceRegState=1(OUT_OF_SERVICE), mDataRegState=1(OUT_OF_SERVICE), mChannelNumber=-1, duplexMode()=0, mCellBandwidths=[], mOperatorAlphaLong=null, mOperatorAlphaShort=null, isManualNetworkSelection=false(automatic), getRilVoiceRadioTechnology=0(Unknown), getRilDataRadioTechnology=0(Unknown), mCssIndicator=unsupported, mNetworkId=-1, mSystemId=-1, mCdmaRoamingIndicator=-1, mCdmaDefaultRoamingIndicator=-1, mIsEmergencyOnly=false, isUsingCarrierAggregation=false, mLteEarfcnRsrpBoost=0, mNetworkRegistrationInfos=[NetworkRegistrationInfo{ domain=CS transportType=WWAN registrationState=UNKNOWN roamingType=NOT_ROAMING accessNetworkTechnology=UNKNOWN rejectCause=0 emergencyEnabled=false availableServices=[] cellIdentity=null voiceSpecificInfo=null dataSpecificInfo=null nrState=NONE rRplmn=}, NetworkRegistrationInfo{ domain=PS transportType=WWAN registrationState=UNKNOWN roamingType=NOT_ROAMING accessNetworkTechnology=UNKNOWN rejectCause=0 emergencyEnabled=false availableServices=[] cellIdentity=null voiceSpecificInfo=null dataSpecificInfo=null nrState=NONE rRplmn=}], mNrFrequencyRange=0, mOperatorAlphaLongRaw=null, mOperatorAlphaShortRaw=null, mIsDataRoamingFromRegistration=false, mIsIwlanPreferred=false}] newSS=[{mVoiceRegState=1(OUT_OF_SERVICE), mDataRegState=1(OUT_OF_SERVICE), mChannelNumber=-1, duplexMode()=0, mCellBandwidths=[], mOperatorAlphaLong=null, mOperatorAlphaShort=null, isManualNetworkSelection=false(automatic), getRilVoiceRadioTechnology=0(Unknown), getRilDataRadioTechnology=0(Unknown), mCssIndicator=unsupported, mNetworkId=-1, mSystemId=-1, mCdmaRoamingIndicator=-1, mCdmaDefaultRoamingIndicator=-1, mIsEmergencyOnly=false, isUsingCarrierAggregation=false, mLteEarfcnRsrpBoost=0, mNetworkRegistrationInfos=[NetworkRegistrationInfo{ domain=CS transportType=WWAN registrationState=UNKNOWN roamingType=NOT_ROAMING accessNetworkTechnology=UNKNOWN rejectCause=0 emergencyEnabled=false availableServices=[] cellIdentity=null voiceSpecificInfo=null dataSpecificInfo=null nrState=NONE rRplmn=}, NetworkRegistrationInfo{ domain=PS transportType=WWAN registrationState=UNKNOWN roamingType=NOT_ROAMING accessNetworkTechnology=UNKNOWN rejectCause=0 emergencyEnabled=false availableServices=[] cellIdentity=null voiceSpecificInfo=null dataSpecificInfo=null nrState=NONE rRplmn=}], mNrFrequencyRange=0, mOperatorAlphaLongRaw=null, mOperatorAlphaShortRaw=null, mIsDataRoamingFromRegistration=false, mIsIwlanPreferred=false}] oldMaxDataCalls=1 mNewMaxDataCalls=1 oldReasonDataDenied=-1 mNewReasonDataDenied=-1
01-10 17:57:56.103 654 654 D SST : [0] pollStateDone: hasRegistered = false hasDeregistered = false hasDataAttached = {1=false} hasDataDetached = {1=false} hasDataRegStateChanged = {1=false} hasRilVoiceRadioTechnologyChanged = false hasRilDataRadioTechnologyChanged = {1=false} hasDataTransportPreferenceChanged = false hasChanged = false hasVoiceRoamingOn = false hasVoiceRoamingOff = false hasDataRoamingOn =false hasDataRoamingOff = false hasLocationChanged = false has4gHandoff = false hasMultiApnSupport = false hasLostMultiApnSupport = false hasCssIndicatorChanged = false hasNrFrequencyRangeChanged = false hasNrStateChanged = false hasAirplaneModeOnlChanged = false
01-10 17:57:56.107 654 654 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:57:56.114 654 654 D SubscriptionController: Valid row not present in db
01-10 17:57:56.116 654 654 D SubscriptionController: getSubscriptionProperty Query value = null
01-10 17:57:56.116 654 654 I CSST : isNrSupported: carrierConfigEnabled: true, AccessFamilySupported: false, isNrNetworkTypeAllowed: true
01-10 17:57:56.116 654 654 I CSST : PrefNetworkNotification: sendMessage() w/values: ,false,-1,false,false
01-10 17:57:56.118 654 654 I CSST : canceling notifications: 1000
01-10 17:57:56.118 654 654 D Phone : isWifiCallingEnabled =false
01-10 17:57:56.118 654 654 D CSST : isPhoneRegisteredForWifiCalling: false
01-10 17:57:56.118 654 654 D Phone : isWifiCallingEnabled =false
01-10 17:57:56.118 654 654 I CSST : EmergencyNetworkNotification: sendMessage() w/values: ,-1,false,false
01-10 17:57:56.120 654 654 I CSST : canceling notifications: 1001
01-10 17:57:56.126 654 654 D SubscriptionController: Valid row not present in db
01-10 17:57:56.128 654 654 D SubscriptionController: getSubscriptionProperty Query value = null
01-10 17:57:56.128 654 654 I CSST : isNrSupported: carrierConfigEnabled: true, AccessFamilySupported: false, isNrNetworkTypeAllowed: true
01-10 17:57:56.128 654 654 I CSST : PrefNetworkNotification: sendMessage() w/values: ,false,-1,false,false
01-10 17:57:56.129 654 654 I CSST : canceling notifications: 1000
01-10 17:57:56.129 654 654 D Phone : isWifiCallingEnabled =false
01-10 17:57:56.129 654 654 D CSST : isPhoneRegisteredForWifiCalling: false
01-10 17:57:56.129 654 654 D Phone : isWifiCallingEnabled =false
01-10 17:57:56.129 654 654 I CSST : EmergencyNetworkNotification: sendMessage() w/values: ,-1,false,false
01-10 17:57:56.131 654 654 I CSST : canceling notifications: 1001
01-10 17:57:56.131 654 654 D EmergencyNumberTracker: updateRadioEmergencyNumberListAndNotify(): receiving []
01-10 17:57:56.131 654 654 D NetworkTypeController: [0] DefaultState: process EVENT_INITIALIZE
01-10 17:57:56.135 654 654 D NetworkTypeController: [0] mOverrideTimerRules: {connected={mState=connected, mOverrideType=NR_NSA, mPrimaryTimers={}, mSecondaryTimers={}}, not_restricted_rrc_con={mState=not_restricted_rrc_con, mOverrideType=NR_NSA, mPrimaryTimers={}, mSecondaryTimers={}}, legacy={mState=legacy, mOverrideType=NONE, mPrimaryTimers={}, mSecondaryTimers={}}, not_restricted_rrc_idle={mState=not_restricted_rrc_idle, mOverrideType=NR_NSA, mPrimaryTimers={}, mSecondaryTimers={}}, restricted={mState=restricted, mOverrideType=NONE, mPrimaryTimers={}, mSecondaryTimers={}}, connected_mmwave={mState=connected_mmwave, mOverrideType=NR_NSA, mPrimaryTimers={}, mSecondaryTimers={}}}
01-10 17:57:56.136 654 654 D NetworkTypeController: [0] DefaultState: process EVENT_UPDATE
01-10 17:57:56.136 654 654 D NetworkTypeController: [0] Entering LegacyState
01-10 17:57:56.136 654 654 D DisplayInfoController: TelephonyDisplayInfo[0] changed from null to TelephonyDisplayInfo {network=UNKNOWN, override=NONE}
01-10 17:57:56.151 654 654 D DCT-C-0 : onDataEnabledChanged: enable=true, enabledChangedReason=0
01-10 17:57:56.152 654 654 D DCT-C-0 : setupDataOnAllConnectableApns: dataEnabled hipri:[state=IDLE,enabled=false] mcx:[state=IDLE,enabled=false] xcap:[state=IDLE,enabled=false] fota:[state=IDLE,enabled=false] ims:[state=IDLE,enabled=false] cbs:[state=IDLE,enabled=false] ia:[state=IDLE,enabled=false] emergency:[state=IDLE,enabled=false] mms:[state=IDLE,enabled=false] supl:[state=IDLE,enabled=false] dun:[state=IDLE,enabled=false] default:[state=IDLE,enabled=false]
01-10 17:57:56.152 654 654 D DCT-C-0 : onDataEnabledOverrideRulesChanged
01-10 17:57:56.154 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.158 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.161 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.164 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.166 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.170 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.172 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.176 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.178 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.182 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.185 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.189 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.191 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.196 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.198 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.206 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.208 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.212 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.214 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.217 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.220 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.233 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.235 654 654 D DCT-C-0 : shouldRestrictDataForEcbm: isInEcm=false isInImsEcm=false
01-10 17:57:56.238 654 654 D SubscriptionController: [getActiveSubscriptionInfo]- subId=-1 subList=[] subInfo=null
01-10 17:57:56.239 654 654 D DCT-C-0 : onRadioOffOrNotAvailable: is off and clean up all connections
01-10 17:57:56.240 654 654 D DCT-C-0 : cleanUpAllConnectionsInternal: detach=false reason=radioTurnedOff
01-10 17:57:56.244 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=xcap mState=IDLE mWaitingApns={null priority=3} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.246 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=mcx mState=IDLE mWaitingApns={null priority=3} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.248 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=fota mState=IDLE mWaitingApns={null priority=2} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.250 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=supl mState=IDLE mWaitingApns={null priority=2} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.253 654 654 D DCT-C-0 : return new LinkProperties
01-10 17:57:56.257 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=emergency mState=IDLE mWaitingApns={null priority=2} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.259 364 676 D TelephonyRegistry: onDataConnectionStateChanged(DISCONNECTED, UNKNOWN) subId=-1, phoneId=0
01-10 17:57:56.261 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=hipri mState=IDLE mWaitingApns={null priority=3} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.264 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=ims mState=IDLE mWaitingApns={null priority=2} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.267 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=default mState=IDLE mWaitingApns={null priority=0} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.269 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=mms mState=IDLE mWaitingApns={null priority=2} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.271 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=cbs mState=IDLE mWaitingApns={null priority=2} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.273 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=ia mState=IDLE mWaitingApns={null priority=2} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.281 654 654 D DCT-C-0 : cleanUpConnectionInternal: X detach=false reason=radioTurnedOff apnContext={mApnType=dun mState=IDLE mWaitingApns={null priority=2} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false} dc=null
01-10 17:57:56.281 654 654 D DCT-C-0 : stopNetStatPoll
01-10 17:57:56.283 654 654 D DCT-C-0 : cleanUpAllConnectionsInternal: mDisconnectPendingCount = 0
01-10 17:57:56.283 654 654 D DCT-C-0 : onDataConnectionDetached: stop polling and notify detached
01-10 17:57:56.283 654 654 D DCT-C-0 : stopNetStatPoll
01-10 17:57:56.284 654 654 D DCT-C-0 : get all active apn types
01-10 17:57:56.284 654 654 D DCT-C-0 : onDataRoamingOff
01-10 17:57:56.287 654 654 D DCT-C-0 : get all active apn types
01-10 17:57:56.287 654 654 D CarrierResolver: handleMessage: 2
01-10 17:57:56.288 654 654 D GsmCdmaPhone: [0] onVoiceRegStateOrRatChanged
01-10 17:57:56.288 654 654 D GsmCdmaPhone: [0] getCsCallRadioTech, current vrs=1, vrat=0
01-10 17:57:56.288 654 654 D GsmCdmaPhone: [0] getCsCallRadioTech, result calcVrat=0
01-10 17:57:56.288 654 654 D DCT-C-0 : onDataEnabledChanged: enable=true, enabledChangedReason=0
01-10 17:57:56.288 654 654 D DCT-C-0 : setupDataOnAllConnectableApns: dataEnabled hipri:[state=IDLE,enabled=false] mcx:[state=IDLE,enabled=false] xcap:[state=IDLE,enabled=false] fota:[state=IDLE,enabled=false] ims:[state=IDLE,enabled=false] cbs:[state=IDLE,enabled=false] ia:[state=IDLE,enabled=false] emergency:[state=IDLE,enabled=false] mms:[state=IDLE,enabled=false] supl:[state=IDLE,enabled=false] dun:[state=IDLE,enabled=false] default:[state=IDLE,enabled=false]
01-10 17:57:56.289 654 654 D PhoneSwitcher: No active subscriptions: resetting preferred phone to 0 for emergency
01-10 17:57:56.290 654 654 D PhoneSwitcher: evaluating due to EVENT_PRECISE_CALL_STATE_CHANGED phone[0] 0->-1
01-10 17:57:56.291 654 654 D PhoneSwitcher: activate 0
01-10 17:57:56.291 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:57:56.295 654 654 D PhoneSwitcher: logDataSwitchEvent subId -1 state 1 reason 2
01-10 17:57:56.308 654 654 D TelephonyNetworkFactory[0]: got request NetworkRequest [ TRACK_DEFAULT id=9, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1073 AdministratorUids: [] RequestorUid: 1073 RequestorPackageName: com.android.networkstack] ] with score 0 and providerId -1
01-10 17:57:56.309 654 654 D TelephonyNetworkFactory[0]: got request NetworkRequest [ REQUEST id=11, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN AdministratorUids: [] RequestorUid: 1073 RequestorPackageName: com.android.networkstack] ] with score 0 and providerId -1
01-10 17:57:56.309 654 654 D TelephonyNetworkFactory[0]: got request NetworkRequest [ TRACK_DEFAULT id=13, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10092 AdministratorUids: [] RequestorUid: 10092 RequestorPackageName: com.android.systemui] ] with score 0 and providerId -1
01-10 17:57:56.309 654 654 D TelephonyNetworkFactory[0]: got request NetworkRequest [ REQUEST id=1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN AdministratorUids: [] RequestorUid: 1000 RequestorPackageName: android] ] with score 0 and providerId -1
01-10 17:57:56.309 654 654 D TelephonyNetworkFactory[0]: got request NetworkRequest [ TRACK_DEFAULT id=14, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10092 AdministratorUids: [] RequestorUid: 10092 RequestorPackageName: com.android.systemui] ] with score 0 and providerId -1
01-10 17:57:56.310 654 654 D TelephonyNetworkFactory[0]: got request NetworkRequest [ TRACK_DEFAULT id=8, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1000 AdministratorUids: [] RequestorUid: 1000 RequestorPackageName: android] ] with score 0 and providerId -1
01-10 17:57:56.310 654 654 D TelephonyNetworkFactory[0]: got request NetworkRequest [ BACKGROUND_REQUEST id=2, [ Transports: CELLULAR Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN AdministratorUids: [] RequestorUid: 1000 RequestorPackageName: android] ] with score 0 and providerId -1
01-10 17:57:56.312 654 654 D DebugService: DebugService DebugService:
01-10 17:57:56.317 654 654 D GsmSMSDispatcher: GsmSMSDispatcher: subId = -1 slotId = 0
01-10 17:57:56.318 654 654 D SST : [0] EVENT_ICC_CHANGED: SIM absent
01-10 17:57:56.318 654 654 D SST : [0] cancelAllNotifications: mPrevSubId=-1
01-10 17:57:56.318 654 654 D CarrierResolver: handleMessage: 2
01-10 17:57:56.318 654 654 D GsmCdmaCallTracker: Event EVENT_POLL_CALLS_RESULT Received
01-10 17:57:56.319 654 654 D GsmCdmaCallTracker: [0] update phone state, old=IDLE new=IDLE
01-10 17:57:56.323 654 654 D NetworkService: network service created
01-10 17:57:56.324 654 654 D NetworkTypeController: [0] LegacyState: process EVENT_DATA_RAT_CHANGED
01-10 17:57:56.325 654 654 D DCT-C-0 : reevaluateUnmeteredConnections
01-10 17:57:56.329 654 654 D DataService: Data service created
01-10 17:57:56.331 654 654 D TelephonyNetworkFactory[0]: got request NetworkRequest [ TRACK_DEFAULT id=17, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1001 AdministratorUids: [] RequestorUid: 1001 RequestorPackageName: com.android.phone] ] with score 0 and providerId 0
01-10 17:57:56.334 654 654 D DcRequest: Cellular request confirmed: NetworkRequest [ TRACK_DEFAULT id=9, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1073 AdministratorUids: [] RequestorUid: 1073 RequestorPackageName: com.android.networkstack] ]
01-10 17:57:56.335 654 654 D PhoneSwitcher: evaluating due to netRequest
01-10 17:57:56.336 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:57:56.339 654 654 D PhoneSwitcher: Added DcRequest, size: 1
01-10 17:57:56.351 654 654 D DcRequest: Cellular request confirmed: NetworkRequest [ REQUEST id=11, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN AdministratorUids: [] RequestorUid: 1073 RequestorPackageName: com.android.networkstack] ]
01-10 17:57:56.351 654 654 D PhoneSwitcher: evaluating due to netRequest
01-10 17:57:56.351 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:57:56.353 654 654 D PhoneSwitcher: Added DcRequest, size: 2
01-10 17:57:56.361 654 654 D DcRequest: Cellular request confirmed: NetworkRequest [ TRACK_DEFAULT id=13, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10092 AdministratorUids: [] RequestorUid: 10092 RequestorPackageName: com.android.systemui] ]
01-10 17:57:56.361 654 654 D PhoneSwitcher: evaluating due to netRequest
01-10 17:57:56.362 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:57:56.364 654 654 D PhoneSwitcher: Added DcRequest, size: 3
01-10 17:57:56.368 654 654 D DcRequest: Cellular request confirmed: NetworkRequest [ REQUEST id=1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN AdministratorUids: [] RequestorUid: 1000 RequestorPackageName: android] ]
01-10 17:57:56.368 654 654 D PhoneSwitcher: evaluating due to netRequest
01-10 17:57:56.369 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:57:56.371 654 654 D PhoneSwitcher: Added DcRequest, size: 4
01-10 17:57:56.380 654 654 D DcRequest: Cellular request confirmed: NetworkRequest [ TRACK_DEFAULT id=14, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10092 AdministratorUids: [] RequestorUid: 10092 RequestorPackageName: com.android.systemui] ]
01-10 17:57:56.381 654 654 D PhoneSwitcher: evaluating due to netRequest
01-10 17:57:56.381 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:57:56.383 654 654 D PhoneSwitcher: Added DcRequest, size: 5
01-10 17:57:56.385 654 654 D DcRequest: Cellular request confirmed: NetworkRequest [ TRACK_DEFAULT id=8, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1000 AdministratorUids: [] RequestorUid: 1000 RequestorPackageName: android] ]
01-10 17:57:56.385 654 654 D PhoneSwitcher: evaluating due to netRequest
01-10 17:57:56.385 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:57:56.387 654 654 D PhoneSwitcher: Added DcRequest, size: 6
01-10 17:57:56.389 654 654 D DcRequest: Cellular request confirmed: NetworkRequest [ BACKGROUND_REQUEST id=2, [ Transports: CELLULAR Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN AdministratorUids: [] RequestorUid: 1000 RequestorPackageName: android] ]
01-10 17:57:56.390 654 654 D PhoneSwitcher: evaluating due to netRequest
01-10 17:57:56.390 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:57:56.392 654 654 D PhoneSwitcher: Added DcRequest, size: 7
01-10 17:57:56.393 654 654 D TelephonyNetworkFactory[0]: onNeedNetworkFor NetworkRequest [ TRACK_DEFAULT id=9, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1073 AdministratorUids: [] RequestorUid: 1073 RequestorPackageName: com.android.networkstack] ] shouldApply false
01-10 17:57:56.394 654 654 D TelephonyNetworkFactory[0]: onNeedNetworkFor NetworkRequest [ REQUEST id=11, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN AdministratorUids: [] RequestorUid: 1073 RequestorPackageName: com.android.networkstack] ] shouldApply false
01-10 17:57:56.395 654 654 D TelephonyNetworkFactory[0]: onNeedNetworkFor NetworkRequest [ TRACK_DEFAULT id=13, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10092 AdministratorUids: [] RequestorUid: 10092 RequestorPackageName: com.android.systemui] ] shouldApply false
01-10 17:57:56.395 654 654 D TelephonyNetworkFactory[0]: onNeedNetworkFor NetworkRequest [ REQUEST id=1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN AdministratorUids: [] RequestorUid: 1000 RequestorPackageName: android] ] shouldApply false
01-10 17:57:56.396 654 654 D TelephonyNetworkFactory[0]: onNeedNetworkFor NetworkRequest [ TRACK_DEFAULT id=14, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10092 AdministratorUids: [] RequestorUid: 10092 RequestorPackageName: com.android.systemui] ] shouldApply false
01-10 17:57:56.397 654 654 D TelephonyNetworkFactory[0]: onNeedNetworkFor NetworkRequest [ TRACK_DEFAULT id=8, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1000 AdministratorUids: [] RequestorUid: 1000 RequestorPackageName: android] ] shouldApply false
01-10 17:57:56.397 654 654 D TelephonyNetworkFactory[0]: onNeedNetworkFor NetworkRequest [ BACKGROUND_REQUEST id=2, [ Transports: CELLULAR Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN AdministratorUids: [] RequestorUid: 1000 RequestorPackageName: android] ] shouldApply false
01-10 17:57:56.398 654 654 D NRM-C-0 : service ComponentInfo{com.android.phone/com.android.internal.telephony.CellularNetworkService} for transport WWAN is now connected.
01-10 17:57:56.401 654 654 D DSM-C-0 : onServiceConnected
01-10 17:57:56.403 654 845 D CellularDataService: Cellular data service created for slot 0
01-10 17:57:56.432 654 654 D DcRequest: Cellular request confirmed: NetworkRequest [ TRACK_DEFAULT id=17, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1001 AdministratorUids: [] RequestorUid: 1001 RequestorPackageName: com.android.phone] ]
01-10 17:57:56.433 654 654 D PhoneSwitcher: evaluating due to netRequest
01-10 17:57:56.434 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:57:56.436 654 654 D PhoneSwitcher: Added DcRequest, size: 8
01-10 17:57:56.440 654 654 D TelephonyNetworkFactory[0]: onNeedNetworkFor NetworkRequest [ TRACK_DEFAULT id=17, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1001 AdministratorUids: [] RequestorUid: 1001 RequestorPackageName: com.android.phone] ] shouldApply false
01-10 17:57:57.243 654 823 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:57:57.366 851 851 D RILD : **RIL Daemon Started**
01-10 17:57:57.366 851 851 D RILD : **RILd param count=1**
01-10 17:57:57.374 851 851 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:57:57.374 851 851 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:57:57.629 851 851 D RILD : RIL_Init rilInit completed
01-10 17:57:57.629 851 851 I RILC : SIM_COUNT: 1
01-10 17:57:57.629 851 851 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:57:57.629 851 851 D RILD : RIL_Init RIL_register completed
01-10 17:57:57.629 851 851 D RILD : RIL_register_socket completed
01-10 17:57:59.188 654 654 D ONSNetworkScanCtlr: init called
01-10 17:57:59.211 364 422 D TelephonyRegistry: listen ooscl: hasNotifyOpptSubInfoChangedOccurred==false no callback
01-10 17:57:59.212 654 654 D ONSProfileSelector: ONSProfileSelector init complete
01-10 17:57:59.228 654 654 D ONS : service is enable state true
01-10 17:57:59.796 654 654 D WAP PUSH: Received broadcast android.intent.action.USER_UNLOCKED
01-10 17:57:59.805 654 654 D WAP PUSH: Received broadcast android.intent.action.USER_UNLOCKED
01-10 17:57:59.805 654 654 D SmsBroadcastUndelivered: Received broadcast android.intent.action.USER_UNLOCKED
01-10 17:57:59.806 654 654 D SmsDispatchersController: Received broadcast android.intent.action.USER_UNLOCKED
01-10 17:57:59.829 654 915 D SmsBroadcastUndelivered: scanning raw table for undelivered messages
01-10 17:58:00.089 654 915 D SmsBroadcastUndelivered: finished scanning raw table in 259 ms
01-10 17:58:00.276 654 672 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:58:00.284 654 672 I chatty : uid=1001(radio) Binder:654_1 identical 1 line
01-10 17:58:00.293 654 672 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:58:00.944 654 835 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:58:00.947 654 835 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:58:01.307 654 654 D DcRequest: Cellular request confirmed: NetworkRequest [ TRACK_DEFAULT id=17, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1001 AdministratorUids: [] RequestorUid: 1001 RequestorPackageName: com.android.phone] ]
01-10 17:58:01.308 654 654 D PhoneSwitcher: evaluating due to netReleased
01-10 17:58:01.308 654 654 D PhoneSwitcher: notifyPreferredDataSubIdChanged to -1
01-10 17:58:01.315 654 654 D PhoneSwitcher: Removed DcRequest, size: 7
01-10 17:58:01.316 654 654 D TelephonyNetworkFactory[0]: onReleaseNetworkFor NetworkRequest [ TRACK_DEFAULT id=17, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 1001 AdministratorUids: [] RequestorUid: 1001 RequestorPackageName: com.android.phone] ] applied false
01-10 17:58:01.335 654 872 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:58:01.343 654 872 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:58:02.384 1220 1220 D RILD : **RIL Daemon Started**
01-10 17:58:02.385 1220 1220 D RILD : **RILd param count=1**
01-10 17:58:02.400 1220 1220 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:02.401 1220 1220 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:02.666 1220 1220 D RILD : RIL_Init rilInit completed
01-10 17:58:02.666 1220 1220 I RILC : SIM_COUNT: 1
01-10 17:58:02.666 1220 1220 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:02.666 1220 1220 D RILD : RIL_Init RIL_register completed
01-10 17:58:02.666 1220 1220 D RILD : RIL_register_socket completed
01-10 17:58:02.846 364 944 D TelephonyRegistry: listen oscl: mHasNotifySubscriptionInfoChangedOccurred==false no callback
01-10 17:58:07.377 1566 1566 D RILD : **RIL Daemon Started**
01-10 17:58:07.378 1566 1566 D RILD : **RILd param count=1**
01-10 17:58:07.386 1566 1566 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:07.386 1566 1566 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:07.641 1566 1566 D RILD : RIL_Init rilInit completed
01-10 17:58:07.641 1566 1566 I RILC : SIM_COUNT: 1
01-10 17:58:07.641 1566 1566 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:07.641 1566 1566 D RILD : RIL_Init RIL_register completed
01-10 17:58:07.641 1566 1566 D RILD : RIL_register_socket completed
01-10 17:58:12.382 1586 1586 D RILD : **RIL Daemon Started**
01-10 17:58:12.383 1586 1586 D RILD : **RILd param count=1**
01-10 17:58:12.390 1586 1586 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:12.391 1586 1586 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:12.647 1586 1586 D RILD : RIL_Init rilInit completed
01-10 17:58:12.647 1586 1586 I RILC : SIM_COUNT: 1
01-10 17:58:12.647 1586 1586 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:12.647 1586 1586 D RILD : RIL_Init RIL_register completed
01-10 17:58:12.647 1586 1586 D RILD : RIL_register_socket completed
01-10 17:58:14.707 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:58:14.708 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:58:14.712 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:58:14.712 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:58:14.713 654 654 D NetworkTypeController: [0] LegacyState: process EVENT_PHYSICAL_CHANNEL_CONFIG_NOTIF_CHANGED
01-10 17:58:14.714 654 654 D NetworkTypeController: [0] DefaultState: process EVENT_PHYSICAL_CHANNEL_CONFIG_NOTIF_CHANGED
01-10 17:58:14.714 654 654 D NetworkTypeController: [0] mIsPhysicalChannelConfigOn changed to: false
01-10 17:58:14.823 654 654 D DCT-C-0 : screen off
01-10 17:58:14.824 654 654 D DCT-C-0 : stopNetStatPoll
01-10 17:58:26.921 1617 1617 D RILD : **RIL Daemon Started**
01-10 17:58:26.921 1617 1617 D RILD : **RILd param count=1**
01-10 17:58:26.937 1617 1617 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:26.938 1617 1617 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:26.981 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:58:26.982 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:58:26.982 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:58:26.982 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:58:26.983 654 654 E RILJ : getRadioProxy: mRadioProxy for slot1 is disabled [PHONE0]
01-10 17:58:26.984 654 654 E RILJ : getRadioProxy: mRadioProxy == null [PHONE0]
01-10 17:58:26.984 654 654 D NetworkTypeController: [0] LegacyState: process EVENT_PHYSICAL_CHANNEL_CONFIG_NOTIF_CHANGED
01-10 17:58:26.985 654 654 D NetworkTypeController: [0] DefaultState: process EVENT_PHYSICAL_CHANNEL_CONFIG_NOTIF_CHANGED
01-10 17:58:26.985 654 654 D NetworkTypeController: [0] mIsPhysicalChannelConfigOn changed to: true
01-10 17:58:27.045 654 654 D DCT-C-0 : screen on
01-10 17:58:27.046 654 654 D DCT-C-0 : stopNetStatPoll
01-10 17:58:27.193 1617 1617 D RILD : RIL_Init rilInit completed
01-10 17:58:27.193 1617 1617 I RILC : SIM_COUNT: 1
01-10 17:58:27.193 1617 1617 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:27.193 1617 1617 D RILD : RIL_Init RIL_register completed
01-10 17:58:27.193 1617 1617 D RILD : RIL_register_socket completed
01-10 17:58:30.046 654 673 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:58:30.054 654 673 D SubscriptionController: [getSlotIndex]- subId invalid
01-10 17:58:31.913 1746 1746 D RILD : **RIL Daemon Started**
01-10 17:58:31.914 1746 1746 D RILD : **RILd param count=1**
01-10 17:58:31.920 1746 1746 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:31.920 1746 1746 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:32.181 1746 1746 D RILD : RIL_Init rilInit completed
01-10 17:58:32.182 1746 1746 I RILC : SIM_COUNT: 1
01-10 17:58:32.182 1746 1746 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:32.182 1746 1746 D RILD : RIL_Init RIL_register completed
01-10 17:58:32.182 1746 1746 D RILD : RIL_register_socket completed
01-10 17:58:36.934 1758 1758 D RILD : **RIL Daemon Started**
01-10 17:58:36.934 1758 1758 D RILD : **RILd param count=1**
01-10 17:58:36.941 1758 1758 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:36.941 1758 1758 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:37.201 1758 1758 D RILD : RIL_Init rilInit completed
01-10 17:58:37.202 1758 1758 I RILC : SIM_COUNT: 1
01-10 17:58:37.202 1758 1758 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:37.202 1758 1758 D RILD : RIL_Init RIL_register completed
01-10 17:58:37.202 1758 1758 D RILD : RIL_register_socket completed
01-10 17:58:41.953 1772 1772 D RILD : **RIL Daemon Started**
01-10 17:58:41.953 1772 1772 D RILD : **RILd param count=1**
01-10 17:58:41.959 1772 1772 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:41.959 1772 1772 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:42.218 1772 1772 D RILD : RIL_Init rilInit completed
01-10 17:58:42.219 1772 1772 I RILC : SIM_COUNT: 1
01-10 17:58:42.219 1772 1772 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:42.219 1772 1772 D RILD : RIL_Init RIL_register completed
01-10 17:58:42.219 1772 1772 D RILD : RIL_register_socket completed
01-10 17:58:46.954 1783 1783 D RILD : **RIL Daemon Started**
01-10 17:58:46.954 1783 1783 D RILD : **RILd param count=1**
01-10 17:58:46.961 1783 1783 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:46.961 1783 1783 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:47.221 1783 1783 D RILD : RIL_Init rilInit completed
01-10 17:58:47.221 1783 1783 I RILC : SIM_COUNT: 1
01-10 17:58:47.221 1783 1783 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:47.221 1783 1783 D RILD : RIL_Init RIL_register completed
01-10 17:58:47.221 1783 1783 D RILD : RIL_register_socket completed
01-10 17:58:51.957 1795 1795 D RILD : **RIL Daemon Started**
01-10 17:58:51.957 1795 1795 D RILD : **RILd param count=1**
01-10 17:58:51.966 1795 1795 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:51.966 1795 1795 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:52.220 1795 1795 D RILD : RIL_Init rilInit completed
01-10 17:58:52.221 1795 1795 I RILC : SIM_COUNT: 1
01-10 17:58:52.221 1795 1795 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:52.221 1795 1795 D RILD : RIL_Init RIL_register completed
01-10 17:58:52.221 1795 1795 D RILD : RIL_register_socket completed
01-10 17:58:56.974 1807 1807 D RILD : **RIL Daemon Started**
01-10 17:58:56.974 1807 1807 D RILD : **RILd param count=1**
01-10 17:58:56.981 1807 1807 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:58:56.981 1807 1807 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:58:57.239 1807 1807 D RILD : RIL_Init rilInit completed
01-10 17:58:57.240 1807 1807 I RILC : SIM_COUNT: 1
01-10 17:58:57.240 1807 1807 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:58:57.240 1807 1807 D RILD : RIL_Init RIL_register completed
01-10 17:58:57.240 1807 1807 D RILD : RIL_register_socket completed
01-10 17:59:01.980 1818 1818 D RILD : **RIL Daemon Started**
01-10 17:59:01.980 1818 1818 D RILD : **RILd param count=1**
01-10 17:59:01.987 1818 1818 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:59:01.987 1818 1818 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:59:02.247 1818 1818 D RILD : RIL_Init rilInit completed
01-10 17:59:02.247 1818 1818 I RILC : SIM_COUNT: 1
01-10 17:59:02.247 1818 1818 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:59:02.247 1818 1818 D RILD : RIL_Init RIL_register completed
01-10 17:59:02.247 1818 1818 D RILD : RIL_register_socket completed
01-10 17:59:06.996 1829 1829 D RILD : **RIL Daemon Started**
01-10 17:59:06.996 1829 1829 D RILD : **RILd param count=1**
01-10 17:59:07.003 1829 1829 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/hw/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-10 17:59:07.003 1829 1829 D RILD : RIL_Init argc = 5 clientId = 0
01-10 17:59:07.261 1829 1829 D RILD : RIL_Init rilInit completed
01-10 17:59:07.261 1829 1829 I RILC : SIM_COUNT: 1
01-10 17:59:07.261 1829 1829 E RILC : RIL_register: RIL_RadioFunctions * null
01-10 17:59:07.261 1829 1829 D RILD : RIL_Init RIL_register completed
01-10 17:59:07.262 1829 1829 D RILD : RIL_register_socket completed
I've not yet pushed all the patches (in my GNUtoo namespace).
That was obtained by doing an eng build and running the following commands on the serial port:
su dmesg -n1 logcat -b radioThe UART is needed because:
- As I understand, this kernel rebase on top of v5.10-rc2, completely breaks USB (and so adb) when the HSIC bus is reset.
- We don't have enough permissions to get the radio logs on the device
Updated by Denis 'GNUtoo' Carikli almost 5 years ago
TODO:
- Double check the libsamsung-ril path (/system/vendor/lib/hw/libsamsung-ril.so vs /system/vendor/lib/libsamsung-ril.so)
Updated by Denis 'GNUtoo' Carikli almost 5 years ago
It doesn't print anything because ALOG* has been replaced by RLOG*. In libsamsung-ril we have:
samsung-ril.h:#define RIL_LOGD ALOGD
Replacing the prints with RLOGD works. After fixing that the next step would be to fix the RIL_RadioFunctions being NULL and check if all the data structures from samsung-ril.h are fine.
Updated by Denis 'GNUtoo' Carikli almost 5 years ago
With the replicant-11-i9300-modem, and an UART adapter and the following commands:
su stop ril-daemon echo 0 > /sys/devices/platform/xmm6262/modem_power echo 0 > /sys/devices/platform/soc/12580000.ehci/ehci_power /system/bin/xmm6262_boot0.sh logcat -b radio -c /vendor/bin/hw/rild & logcat -b radio
I get:
console:/ # /vendor/bin/hw/rild &
[1] 1526
console:/ # [ 176.794361] gpiohack xmm6262: new state: 0
[ 176.847644] gpiohack xmm6262: link active? 0
[ 176.851227] gpiohack xmm6262: new state: 1
generic_boot: xm[ 177.119863] gpiohack xmm6262: pda active => 1
[ 177.124380] exynos-ehci 12580000.ehci: Powering on EHCI
[ 177.130015] exynos-ehci 12580000.ehci: EHCI Host Controller
[ 177.134538] exynos-ehci 12580000.ehci: new USB bus registered, assigned bus number 1
m626_kernel_linux_modem_power(NULL, 1) = 0[ 177.146405] exynos-ehci 12580000.ehci: irq 66, io mem 0x12580000
[ 177.178945] exynos-ehci 12580000.ehci: USB 2.0 started, EHCI 1.00
[ 177.184235] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[ 177.192020] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 177.199127] usb usb1: Product: EHCI Host Controller
[ 177.203941] usb usb1: Manufacturer: Linux 5.10.0-rc2+ ehci_hcd
[ 177.209848] usb usb1: SerialNumber: 12580000.ehci
l[ 177.216315] hub 1-0:1.0: USB hub found
[ 177.218630] hub 1-0:1.0: 3 ports detected
[ 177.274142] gpiohack xmm6262: link active? 1
generic_boot: xmm626_kernel_linux_modem_hci_power(1) = 0
Bus 001 Device 001: ID 1d6b:0002
og[ 177.589041] usb 1-2: new high-speed USB device number 2 using exynos-ehci
c[ 177.790872] usb 1-2: config 1 interface 0 altsetting 0 endpoint 0x81 has an invalid bInterval 255, changing to 11
[ 177.799954] usb 1-2: New USB device found, idVendor=058b, idProduct=0041, bcdDevice= 0.00
[ 177.807872] usb 1-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 177.815554] type=1400 audit(1611058657.180:356): avc: denied { mknod } for comm="kdevtmpfs" capability=27 scontext=u:r:kernel:s0 tcontext=u:r:kernel:s0 tclass=capability permissive=1
[ 177.831669] xmm6262_boot 1-2:1.0: Loaded XMM6262 boot serial device!
aBus 001 Device 001: ID 1d6b:0002
Bus 001 Device 002: ID 058b:0041
t -b radio
[ 179.436363] logd: logdr: UID=0 GID=0 PID=1530 b tail=0 logMask=2 pid=0 start=0ns timeout=0ns
--------- beginning of radio
01-19 12:17:36.157 1526 1526 D RILD : **RIL Daemon Started**
01-19 12:17:36.157 1526 1526 D RILD : **RILd param count=1**
01-19 12:17:36.165 1526 1526 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/libsamsung-ril.so: undefined symbol: RIL_SAP_Init
01-19 12:17:36.165 1526 1526 D RILD : RIL_Init argc = 5 clientId = 0
01-19 12:17:36.165 1526 1526 D RIL : libsamsung-ril: Started from RIL_Init
01-19 12:17:36.165 1526 1526 D RIL : Created IPC FMT client
01-19 12:17:36.165 1526 1526 D RIL : Created IPC RFS client
01-19 12:17:36.165 1526 1526 D RIL : Created SRS client
01-19 12:17:36.165 1526 1526 D RIL-IPC : Starting generic modem boot
01-19 12:17:36.165 1526 1526 D RIL-IPC : Mapped modem image data to memory
01-19 12:17:36.222 1526 1526 D RIL-IPC : Turned the modem off
01-19 12:17:36.648 1526 1526 D RIL-IPC : Turned the modem on
01-19 12:17:37.203 1526 1526 D RIL-IPC : Opened modem boot device
01-19 12:17:37.252 1526 1526 D RIL-IPC : Wrote ATAT in ASCII
01-19 12:17:37.353 1526 1526 D RIL-IPC : Read chip id (0x16)
01-19 12:17:37.353 1526 1526 D RIL-IPC : Wrote PSI header
01-19 12:17:37.356 1526 1526 D RIL-IPC : Wrote PSI, CRC is 0xc8
01-19 12:17:37.356 1526 1526 D RIL-IPC : Wrote PSI CRC (0xc8)
01-19 12:17:37.457 1526 1526 D RIL-IPC : Read PSI CRC ACK
01-19 12:17:37.457 1526 1526 D RIL-IPC : Read PSI ACK
01-19 12:17:37.457 1526 1526 D RIL-IPC : Sent XMM626 HSIC PSI
01-19 12:17:37.457 1526 1526 D RIL-IPC : Wrote EBL size
01-19 12:17:37.468 1526 1526 D RIL-IPC : Wrote EBL, CRC is 0xb5
01-19 12:17:37.468 1526 1526 D RIL-IPC : Wrote EBL CRC (0xb5)
01-19 12:17:38.470 1526 1526 D RIL-IPC : Sent XMM626 HSIC EBL
01-19 12:17:38.471 1526 1526 D RIL-IPC : Read port config
01-19 12:17:39.472 1526 1526 D RIL-IPC : Sent XMM626 HSIC port config
Bus 001 Device 001: ID 1d6b:0002
Bus 001 Device 002: ID 058b:0041
01-19 12:17:40.539 1526 1526 D RIL-IPC : Sent XMM626 HSIC SEC start
01-19 12:17:41.739 1526 1526 D RIL-IPC : Sent XMM626 HSIC firmware
Bus 001 Device 001: ID 1d6b:0002
Bus 001 De[ 182.412890] SELinux: Context u:object_r:efs_device_file:s0 is not valid (left unmapped).
vice 002: ID 058b:0041
01-19 12:17:41.784 1526 1526 D RIL-IPC : Checked nv_data path
01-19 12:17:41.792 1526 1526 D RIL-IPC : Checked nv_data md5 path
01-19 12:17:41.838 1526 1526 D RIL-IPC : Calculated nv_data md5: 2ddb7fb2b0f7078c5d7c4647cfa885fc
01-19 12:17:41.839 1526 1526 D RIL-IPC : Read nv_data md5: 2ddb7fb2b0f7078c5d7c4647cfa885fc
01-19 12:17:41.839 1526 1526 D RIL-IPC : Checked nv_data backup path
01-19 12:17:41.847 1526 1526 D RIL-IPC : Loaded nv_data
01-19 12:17:42.888 1526 1526 D RIL-IPC : Sent XMM626 HSIC nv_data
01-19 12:17:43.891 1526 1526 D RIL-IPC : Sent XMM626 HSIC SEC end
Bus 001 Device 001: ID 1d6b:0002
Bus 001 Device 002: ID 058b:0041
01-19 12:17:43.953 1526 1526 D RIL-IPC : Sent XMM626 HSIC HW reset
Bus 001 Device 001: ID 1d6b:0002
Bus 001 Device 002: ID 058b:0041
[ 184.714923] xmm6262_boot 1-2:1.0: nonzero bulk read status: -71
[ 184.770863] gpiohack xmm6262: phone_active: cp_reset=1, phone_active=1, cp_dump=0
[ 184.776914] gpiohack xmm6262: BOOTING
01-19 12:17:49.298 1526 1526 D RIL-IPC : Waited for host wake
Bus 001 Device 001: ID 1d6b:00[ 189.989947] exynos-ehci 12580000.ehci: Powering off EHCI
[ 189.994853] exynos-ehci 12580000.ehci: remove, state 1
[ 190.000015] usb usb1: USB disconnect, device number 1
[ 190.005009] usb 1-2: USB disconnect, device number 2
02
Bus 001 Device 002: ID 058b:0041
[ 190.015302] exynos-ehci 12580000.ehci: USB bus 1 deregistered
[ 190.070700] gpiohack xmm6262: link active? 0
01-19 12:17:49.445 1526 1526 D RIL-IPC : Turned off the modem
01-19 12:17:54.502 1526 1526 D RIL-IPC : Waited for host wake
[ 195.195392] gpiohack xmm6262: pda active => 1
[ 195.198403] exynos-ehci 12580000.ehci: Powering on EHCI
[ 195.203931] exynos-ehci 12580000.ehci: EHCI Host Controller
[ 195.209087] exynos-ehci 12580000.ehci: new USB bus registered, assigned bus number 1
[ 195.217443] exynos-ehci 12580000.ehci: irq 66, io mem 0x12580000
[ 195.248979] exynos-ehci 12580000.ehci: USB 2.0 started, EHCI 1.00
[ 195.254513] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[ 195.262069] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 195.269207] usb usb1: Product: EHCI Host Controller
[ 195.274011] usb usb1: Manufacturer: Linux 5.10.0-rc2+ ehci_hcd
[ 195.279881] usb usb1: SerialNumber: 12580000.ehci
[ 195.286471] hub 1-0:1.0: USB hub found
[ 195.288807] hub 1-0:1.0: 3 ports detected
[ 195.344683] gpiohack xmm6262: link active? 1
01-19 12:17:54.719 1526 1526 D RIL-IPC : Turned the modem on
Bus 001 Device 001: ID 1d6b:0002
01-19 12:17:54.776 1526 1526 D RIL-IPC : Wait for the modem to come up again
[ 195.659105] usb 1-2: new high-speed USB device number 2 using exynos-ehci
[ 195.866728] usb 1-2: New USB device found, idVendor=1519, idProduct=0020, bcdDevice=12.74
[ 195.873632] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 195.880701] usb 1-2: Product: HSIC Device
[ 195.884593] usb 1-2: Manufacturer: Comneon
[ 195.888707] usb 1-2: SerialNumber: 0123456789
Bus 001 Device 001: ID 1d6b:0002
Bus 001 Device 002: ID 1519:0020
01-19 12:18:04.840 1526 1526 D RIL-IPC : generic_boot complete
ENTER xmm626_kernel_linux_modem_open
xmm626_kernel_linux_modem_open[ 212.524616] samsung_ipc modem: Processed 11 bytes
[ 212.528632] samsung_ipc modem: Processed 38 bytes
[ 212.533307] samsung_ipc modem: Processed 40 bytes
[ 212.537976] samsung_ipc modem: Processed 21 bytes
[ 212.542687] samsung_ipc modem: Processed 22 bytes
[ 212.547378] samsung_ipc modem: Processed 89 bytes
: type: 0
ENTER xmm626_kernel_linux_modem_open
01-19 12:18:11.894 1526 1526 D RIL :[ 212.560966] type=1400 audit(1611058691.930:357): avc: denied { confidentiality } for comm="rild" lockdown_reason="use of tracefs" scontext=u:r:su:s0 tcontext=u:r:su:s0 tclass=lockdown permissive=1
Opened IPC FMT client
01-19 12:18:11.895 1526 1526 D RIL : Started IPC FMT client loop
xmm626_kernel_linux_modem_open: type: 1
01-19 12:18:11.925 1526 1526 D RIL : Opened IPC RFS client
01-19 12:18:11.926 1526 1526 D RIL : Started IPC RFS client loop
01-19 12:18:11.926 1526 1542 E RIL : IPC RFS client loop failed
01-19 12:18:11.926 1526 1526 D RIL-SRS : Started SRS server loop
01-19 12:18:11.927 1526 1543 E RIL-SRS : SRS client is not available
01-19 12:18:11.927 1526 1526 D RIL : Opened SRS client
01-19 12:18:11.927 1526 1543 E RIL-SRS : SRS server loop failed
01-19 12:18:11.927 1526 1526 D RIL : Started SRS client loop
01-19 12:18:11.928 1526 1526 D RILD : RIL_Init rilInit completed
01-19 12:18:11.928 1526 1541 E RIL : IPC FMT client loop failed
01-19 12:18:11.928 1526 1526 I RILC : SIM_COUNT: 1
01-19 12:18:11.928 1526 1526 E RILC : RIL_register: RIL version 12
01-19 12:18:11.928 1526 1526 I RILC : s_registerCalled flag set, 1
01-19 12:18:11.929 1526 1526 D RILC : registerService: starting android::hardware::radio::V1_1::IRadio slot1
01-19 12:18:11.942 1526 1526 I RILC : RILHIDL called registerService
01-19 12:18:11.942 1526 1526 D RILD : RIL_Init RIL_register completed
01-19 12:18:11.942 1526 1526 D RILD : RIL_register_socket completed
01-19 12:18:11.976 1526 1542 E RIL-IPC : Powering off IPC RFS client failed
01-19 12:18:11.977 1526 1542 E RIL : Closing IPC RFS client failed
ENTER xmm626_kernel_linux_modem_open
01-19 12:18:11.978 1526 1541 D RIL : Closed IPC FMT client
xmm626_kernel_linux_modem_open: type: 0
01-19 12:18:12.009 1526 1541 D RIL : Updating RIL radio state to 0
01-19 12:18:12.015 1526 1541 E RILC : radioStateChangedInd: radioService[0]->mRadioIndication == NULL
01-19 12:18:12.015 1526 1541 E RILC : callStateChangedInd: radioService[0]->mRadioIndication == NULL
01-19 12:18:12.015 1526 1541 E RILC : networkStateChangedInd: radioService[0]->mRadioIndication == NULL
01-19 12:18:12.016 1526 1541 E RILC : dataCallListChangedInd: radioService[0]->mRadioIndication == NULL
01-19 12:18:12.016 1526 1541 E RILC : simStatusChangedInd: radioService[0]->mRadioIndication == NULL
01-19 12:18:12.016 1526 1541 D RIL : Opened IPC FMT client
01-19 12:18:12.027 1526 1542 E RIL-IPC : Powering off IPC RFS client failed
01-19 12:18:12.027 1526 1542 E RIL : Closing IPC RFS client failed
01-19 12:18:12.078 1526 1542 E RIL-IPC : Powering off IPC RFS client failed
01-19 12:18:12.078 1526 1542 E RIL : Closing IPC RFS client failed
01-19 12:18:12.129 1526 1542 E RIL-IPC : Powering off IPC RFS client failed
01-19 12:18:12.129 1526 1542 E RIL : Closing IPC RFS client failed
01-19 12:18:12.129 1526 1542 E RIL-IPC : Powering off IPC RFS client failed
01-19 12:18:12.129 1526 1542 E RIL : Closing IPC RFS client failed
01-19 12:18:12.130 1526 1542 D RIL : Destroyed IPC RFS client
01-19 12:18:12.130 1526 1542 D RIL : Stopped IPC RFS client loop
[ 213.114980] type=1400 audit(1611058692.480:358): avc: denied { ioctl } for comm="android.ui" path="/dev/dri/renderD129" dev="tmpfs" ino=239 ioctlcmd=0x6444 scontext=u:r:system_server:s0 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1
[ 213.152577] type=1400 audit(1611058692.520:359): avc: denied { ioctl } for comm="android.ui" path="/dev/dri/renderD129" dev="tmpfs" ino=239 ioctlcmd=0x6444 scontext=u:r:system_server:s0 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1
[ 213.376501] wm8994-codec wm8994-codec: FIFO error
[ 214.357230] type=1400 audit(1611058693.720:360): avc: denied { ioctl } for comm="RenderThread" path="/dev/dri/renderD129" dev="tmpfs" ino=239 ioctlcmd=0x6409 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1 app=com.android.launcher3
[ 214.396113] type=1400 audit(1611058693.760:361): avc: denied { ioctl } for comm="allocator@2.0-s" path="/dev/dri/card0" dev="tmpfs" ino=328 ioctlcmd=0x64b2 scontext=u:r:hal_graphics_allocator_default:s0 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1
[ 214.466225] type=1400 audit(1611058693.830:362): avc: denied { map } for comm="GrallocUploadTh" path="/dev/dri/renderD129" dev="tmpfs" ino=239 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1 app=com.android.launcher3
[ 214.508578] type=1400 audit(1611058693.830:363): avc: denied { read write } for comm="GrallocUploadTh" path="/dev/dri/renderD129" dev="tmpfs" ino=239 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1 app=com.android.launcher3
[ 214.795675] type=1400 audit(1611058694.160:364): avc: denied { confidentiality } for comm="main" lockdown_reason="use of tracefs" scontext=u:r:zygote:s0 tcontext=u:r:zygote:s0 tclass=lockdown permissive=1
[ 214.848131] type=1400 audit(1611058694.210:365): avc: denied { ioctl } for comm="RenderThread" path="/dev/dri/renderD129" dev="tmpfs" ino=239 ioctlcmd=0x64c1 scontext=u:r:platform_app:s0:c512,c768 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1 app=com.android.systemui
[ 214.878107] type=1400 audit(1611058694.210:367): avc: denied { ioctl } for comm="composer@2.1-se" path="/dev/dri/card0" dev="tmpfs" ino=328 ioctlcmd=0x64af scontext=u:r:hal_graphics_composer_default:s0 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1
[ 215.064110] audit: audit_lost=58 audit_rate_limit=5 audit_backlog_limit=64
[ 215.069681] audit: rate limit exceeded
01-19 12:18:14.720 622 648 D SubscriptionController: [getSlotIndex]- subId invalid
[ 219.480169] type=1400 audit(1611058698.840:372): avc: denied { ioctl } for comm="GrallocUploadTh" path="/dev/dri/renderD129" dev="tmpfs" ino=239 ioctlcmd=0x64c0 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1 app=com.android.launcher3
[ 252.439493] type=1400 audit(1611058731.800:373): avc: denied { read } for comm="storaged" name="stat" dev="sysfs" ino=38360 scontext=u:r:storaged:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 252.457872] type=1400 audit(1611058731.800:374): avc: denied { open } for comm="storaged" path="/sys/devices/platform/soc/12530000.sdhci/mmc_host/mmc0/mmc0:5048/block/mmcblk0/stat" dev="sysfs" ino=38360 scontext=u:r:storaged:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 252.482615] type=1400 audit(1611058731.800:375): avc: denied { getattr } for comm="storaged" path="/sys/devices/platform/soc/12530000.sdhci/mmc_host/mmc0/mmc0:5048/block/mmcblk0/stat" dev="sysfs" ino=38360 scontext=u:r:storaged:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 255.951797] type=1400 audit(1611058735.320:376): avc: denied { read } for comm="health@2.1-serv" name="present" dev="sysfs" ino=40336 scontext=u:r:hal_health_default:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 255.965336] healthd: battery l=94 v=3917 t=22.0 h=2 st=3 c=2097 fc=1056500 cc=9 chg=
[ 255.970462] type=1400 audit(1611058735.320:377): avc: denied { open } for comm="health@2.1-serv" path="/sys/devices/platform/i2c-gpio-1/i2c-11/11-0066/max77693-charger/power_supply/max77693-charger/present" dev="sysfs" ino=40336 scontext=u:r:hal_health_default:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 256.006597] type=1400 audit(1611058735.320:378): avc: denied { getattr } for comm="health@2.1-serv" path="/sys/devices/platform/i2c-gpio-1/i2c-11/11-0066/max77693-charger/power_supply/max77693-charger/present" dev="sysfs" ino=40336 scontext=u:r:hal_health_default:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 260.687965] type=1400 audit(1611058740.050:379): avc: denied { map } for comm="surfaceflinger" path="/dev/dri/renderD129" dev="tmpfs" ino=239 scontext=u:r:surfaceflinger:s0 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1
[ 260.707285] type=1400 audit(1611058740.050:380): avc: denied { read write } for comm="surfaceflinger" path="/dev/dri/renderD129" dev="tmpfs" ino=239 scontext=u:r:surfaceflinger:s0 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1
[ 316.052663] healthd: battery l=94 v=3907 t=22.0 h=2 st=3 c=2097 fc=1056500 cc=9 chg=
[ 372.442549] type=1400 audit(1611058851.810:381): avc: denied { read } for comm="storaged" name="stat" dev="sysfs" ino=38360 scontext=u:r:storaged:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 372.459549] type=1400 audit(1611058851.810:382): avc: denied { open } for comm="storaged" path="/sys/devices/platform/soc/12530000.sdhci/mmc_host/mmc0/mmc0:5048/block/mmcblk0/stat" dev="sysfs" ino=38360 scontext=u:r:storaged:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 372.484107] type=1400 audit(1611058851.810:383): avc: denied { getattr } for comm="storaged" path="/sys/devices/platform/soc/12530000.sdhci/mmc_host/mmc0/mmc0:5048/block/mmcblk0/stat" dev="sysfs" ino=38360 scontext=u:r:storaged:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 376.136324] healthd: battery l=94 v=3900 t=22.0 h=2 st=3 c=2097 fc=1056500 cc=9 chg=
[ 436.224750] healthd: battery l=94 v=3898 t=22.0 h=2 st=3 c=2097 fc=1056500 cc=10 chg=
[ 496.313330] healthd: battery l=94 v=3893 t=22.0 h=2 st=3 c=2097 fc=1056500 cc=10 chg=
[ 556.400655] healthd: battery l=94 v=3893 t=22.0 h=2 st=3 c=2097 fc=1056500 cc=11 chg=
Beside the known TODO like creating the nodes created by /system/bin/xmm6262_boot0.sh automatically and maybe some selinux rules for rild too, we still have some more integration to do as the IPC/RFS/SRS clients end up failing.
Updated by Denis 'GNUtoo' Carikli almost 5 years ago
Here's the new commands I use:
su stop ril-daemon echo 0 > /sys/devices/platform/xmm6262/modem_power echo 0 > /sys/devices/platform/soc/12580000.ehci/ehci_power /system/bin/xmm6262_boot0 logcat -b radio -c dd if=/sdcard/RADIO.img of=/dev/block/mmcblk2p7 sync /vendor/bin/hw/rild & logcat -b radio
For some reasons I'm also having corruption from the RADIO.img image.
So I need to restore a backup for the boot to succeed.
mmcblk2p7 is not supposed to be modified by the modem or libsamsung-ril / libsamsung-ipc so I still need to look at why it happens.
Updated by Denis 'GNUtoo' Carikli almost 5 years ago
And here's the new log after the bootstrap:
ENTER xmm626_kernel_linux_modem_open 01-20 04:17:55.939 1675 1675 D RIL : Opened IPC FMT client 01-20 04:17:55.939 1675 1675 D RIL : Started IPC FMT client loop 01-20 04:17:55.945 1675 1692 E RIL : eventfd_recv: eventfd_read error 11: Try again 01-20 04:17:55.945 1675 1692 E RIL-IPC : Receiving IPC FMT data with eventfd_recv failed with error -1 xmm626_kernel_linux_modem_open: type: 1 01-20 04:17:55.970 1675 1675 D RIL : Opened IPC RFS client 01-20 04:17:55.970 1675 1675 D RIL : Started IPC RFS client loop 01-20 04:17:55.971 1675 1693 E RIL : eventfd_recv: eventfd_read error 11: Try again 01-20 04:17:55.971 1675 1693 E RIL-IPC : Receiving IPC RFS data with eventfd_recv failed with error -1 01-20 04:17:55.971 1675 1693 E RIL : IPC RFS client loop failed with error -1 01-20 04:17:55.971 1675 1694 E RIL-SRS : SRS client is not available 01-20 04:17:55.972 1675 1694 E RIL-SRS : SRS server loop failed 01-20 04:17:55.971 1675 1675 D RIL-SRS : Started SRS server loop 01-20 04:17:55.972 1675 1675 D RIL : Opened SRS client 01-20 04:17:55.973 1675 1675 D RIL : Started SRS client loop 01-20 04:17:55.973 1675 1675 D RILD : RIL_Init rilInit completed 01-20 04:17:55.973 1675 1692 E RIL : IPC FMT client loop failed with error -1 01-20 04:17:55.973 1675 1675 I RILC : SIM_COUNT: 1 01-20 04:17:55.974 1675 1675 E RILC : RIL_register: RIL version 12 01-20 04:17:55.974 1675 1675 I RILC : s_registerCalled flag set, 1 01-20 04:17:55.975 1675 1675 D RILC : registerService: starting android::hardware::radio::V1_1::IRadio slot1 01-20 04:17:55.986 1675 1675 I RILC : RILHIDL called registerService 01-20 04:17:55.987 1675 1675 D RILD : RIL_Init RIL_register completed 01-20 04:17:55.987 1675 1675 D RILD : RIL_register_socket completed 01-20 04:17:56.021 1675 1693 E RIL-IPC : Powering off IPC RFS client failed 01-20 04:17:56.022 1675 1693 E RIL : Closing IPC RFS client failed ENTER xmm626_kernel_linux_modem_open 01-20 04:17:56.024 1675 1692 D RIL : Closed IPC FMT client xmm626_kernel_linux_modem_open: type: 0 01-20 04:17:56.054 1675 1692 D RIL : Updating RIL radio state to 0 01-20 04:17:56.059 1675 1692 E RILC : radioStateChangedInd: radioService[0]->mRadioIndication == NULL 01-20 04:17:56.059 1675 1692 E RILC : callStateChangedInd: radioService[0]->mRadioIndication == NULL 01-20 04:17:56.059 1675 1692 E RILC : networkStateChangedInd: radioService[0]->mRadioIndication == NULL 01-20 04:17:56.060 1675 1692 E RILC : dataCallListChangedInd: radioService[0]->mRadioIndication == NULL 01-20 04:17:56.060 1675 1692 E RILC : simStatusChangedInd: radioService[0]->mRadioIndication == NULL 01-20 04:17:56.060 1675 1692 D RIL : Opened IPC FMT client 01-20 04:17:56.072 1675 1693 E RIL-IPC : Powering off IPC RFS client failed 01-20 04:17:56.072 1675 1693 E RIL : Closing IPC RFS client failed 01-20 04:17:56.123 1675 1693 E RIL-IPC : Powering off IPC RFS client failed 01-20 04:17:56.123 1675 1693 E RIL : Closing IPC RFS client failed 01-20 04:17:56.173 1675 1693 E RIL-IPC : Powering off IPC RFS client failed 01-20 04:17:56.174 1675 1693 E RIL : Closing IPC RFS client failed 01-20 04:17:56.174 1675 1693 E RIL-IPC : Powering off IPC RFS client failed 01-20 04:17:56.174 1675 1693 E RIL : Closing IPC RFS client failed 01-20 04:17:56.175 1675 1693 D RIL : Destroyed IPC RFS client 01-20 04:17:56.175 1675 1693 D RIL : Stopped IPC RFS client loop [ 520.598288] healthd: battery l=74 v=3557 t=22.0 h=2 st=3 c=2097 fc=1000000 cc=8 chg= [ 580.652151] healthd: battery l=74 v=3548 t=22.0 h=2 st=3 c=2097 fc=1000000 cc=8 chg= [ 608.333382] healthd: battery l=74 v=3543 t=22.0 h=2 st=3 c=2097 fc=1000000 cc=9 chg=
Updated by Denis 'GNUtoo' Carikli almost 5 years ago
The error is EAGAIN.
We probably need to standardize more the libsamsung-ipc API as this stuff is exported as-is from the kernel without any abstraction in between.
The issue is that depending on the device and kernel, the upper library (libsamsung-ril, oFono, etc) might need to handle things differently.
However doing too much in libsamsung-ipc is not a good idea either as we want to be able to use it in a wide variety of frameworks, OS and so on. So it should be up to the upper library (like libsamsung-ril) to use the threading / blocking model that suits its needs.
Another option would be to add a compilation option to only support the upstream kernel: this way the kernel won't do strange things and we can be sure of the API.
Updated by Denis 'GNUtoo' Carikli almost 5 years ago
Here's the current log:
--------- beginning of radio 01-20 04:55:32.756 618 641 D SubscriptionController: [getActiveSubscriptionInfoForSimSlotIndex]+ slotIndex=0 subId=null 01-20 04:57:25.924 1836 1836 D RILD : **RIL Daemon Started** 01-20 04:57:25.924 1836 1836 D RILD : **RILd param count=1** 01-20 04:57:25.933 1836 1836 W RILD : RIL_SAP_Init not defined or exported in /system/vendor/lib/libsamsung-ril.so: undefined symbol: RIL_SAP_Init 01-20 04:57:25.933 1836 1836 D RILD : RIL_Init argc = 5 clientId = 0 01-20 04:57:25.933 1836 1836 D RIL : libsamsung-ril: Started from RIL_Init 01-20 04:57:25.933 1836 1836 D RIL : Created IPC FMT client 01-20 04:57:25.933 1836 1836 D RIL : Created IPC RFS client 01-20 04:57:25.933 1836 1836 D RIL : Created SRS client 01-20 04:57:25.933 1836 1836 D RIL-IPC : Starting generic modem boot 01-20 04:57:25.934 1836 1836 D RIL-IPC : Mapped modem image data to memory 01-20 04:57:25.985 1836 1836 D RIL-IPC : Turned the modem off 01-20 04:57:26.350 1836 1836 D RIL-IPC : Turned the modem on 01-20 04:57:26.882 1836 1836 D RIL-IPC : Opened modem boot device 01-20 04:57:26.946 1836 1836 D RIL-IPC : Wrote ATAT in ASCII 01-20 04:57:27.047 1836 1836 D RIL-IPC : Read chip id (0x16) 01-20 04:57:27.047 1836 1836 D RIL-IPC : Wrote PSI header 01-20 04:57:27.055 1836 1836 D RIL-IPC : Wrote PSI, CRC is 0xc8 01-20 04:57:27.056 1836 1836 D RIL-IPC : Wrote PSI CRC (0xc8) 01-20 04:57:27.157 1836 1836 D RIL-IPC : Read PSI CRC ACK 01-20 04:57:27.158 1836 1836 D RIL-IPC : Read PSI ACK 01-20 04:57:27.159 1836 1836 D RIL-IPC : Sent XMM626 HSIC PSI 01-20 04:57:27.159 1836 1836 D RIL-IPC : Wrote EBL size 01-20 04:57:27.172 1836 1836 D RIL-IPC : Wrote EBL, CRC is 0xb5 01-20 04:57:27.172 1836 1836 D RIL-IPC : Wrote EBL CRC (0xb5) 01-20 04:57:28.174 1836 1836 D RIL-IPC : Sent XMM626 HSIC EBL 01-20 04:57:28.174 1836 1836 D RIL-IPC : Read port config 01-20 04:57:29.176 1836 1836 D RIL-IPC : Sent XMM626 HSIC port config Bus 001 Device 001: ID 1d6b:0002 Bus 001 Device 002: ID 058b:0041 01-20 04:57:30.247 1836 1836 D RIL-IPC : Sent XMM626 HSIC SEC start 01-20 04:57:31.404 1836 1836 D RIL-IPC : Sent XMM626 HSIC firmware Bus 001 Device 001: ID 1d6b:0002 Bus 001 Device 002: ID 058b:0041 01-20 04:57:31.448 1836 1836 D RIL-IPC : Checked nv_data path 01-20 04:57:31.448 1836 1836 D RIL-IPC : Checked nv_data md5 path 01-20 04:57:31.468 1836 1836 D RIL-IPC : Calculated nv_data md5: 2ddb7fb2b0f7078c5d7c4647cfa885fc 01-20 04:57:31.469 1836 1836 D RIL-IPC : Read nv_data md5: 2ddb7fb2b0f7078c5d7c4647cfa885fc 01-20 04:57:31.469 1836 1836 D RIL-IPC : Checked nv_data backup path 01-20 04:57:31.478 1836 1836 D RIL-IPC : Loaded nv_data 01-20 04:57:32.517 1836 1836 D RIL-IPC : Sent XMM626 HSIC nv_data 01-20 04:57:33.520 1836 1836 D RIL-IPC : Sent XMM626 HSIC SEC end Bus 001 Device 001: ID 1d6b:0002 Bus 001 Device 002: ID 058b:0041 01-20 04:57:33.588 1836 1836 D RIL-IPC : Sent XMM626 HSIC HW reset Bus 001 Device 001: ID 1d6b:0002 Bus 001 Device 002: ID 058b:0041 01-20 04:57:38.932 1836 1836 D RIL-IPC : Waited for host wake Bus 001 Device 001: ID 1d6b:0002 Bus 001 Device 002: ID 058b:0041 01-20 04:57:39.051 1836 1836 D RIL-IPC : Turned off the modem 01-20 04:57:44.113 1836 1836 D RIL-IPC : Waited for host wake 01-20 04:57:44.257 1836 1836 D RIL-IPC : Turned the modem on Bus 001 Device 001: ID 1d6b:0002 01-20 04:57:44.317 1836 1836 D RIL-IPC : Wait for the modem to come up again Bus 001 Device 001: ID 1d6b:0002 Bus 001 Device 002: ID 1519:0020 01-20 04:57:54.373 1836 1836 D RIL-IPC : generic_boot complete ENTER xmm626_kernel_linux_modem_open xmm626_kernel_linux_modem_open: type: 0 01-20 04:58:01.433 1836 1836 D RIL : Opened IPC FMT client ENTER xmm626_kernel_linux_modem_open 01-20 04:58:01.433 1836 1836 D RIL : Started IPC FMT client loop xmm626_kernel_linux_modem_open: type: 1 01-20 04:58:01.464 1836 1836 D RIL : Opened IPC RFS client 01-20 04:58:01.465 1836 1836 D RIL : Started IPC RFS client loop 01-20 04:58:01.465 1836 1836 D RIL-SRS : Started SRS server loop 01-20 04:58:01.466 1836 1854 E RIL-SRS : SRS client is not available 01-20 04:58:01.466 1836 1836 D RIL : Opened SRS client 01-20 04:58:01.466 1836 1854 E RIL-SRS : SRS server loop failed 01-20 04:58:01.466 1836 1836 D RIL : Started SRS client loop 01-20 04:58:01.468 1836 1836 D RILD : RIL_Init rilInit completed 01-20 04:58:01.468 1836 1836 I RILC : SIM_COUNT: 1 01-20 04:58:01.468 1836 1836 E RILC : RIL_register: RIL version 12 01-20 04:58:01.469 1836 1836 I RILC : s_registerCalled flag set, 1 01-20 04:58:01.469 1836 1836 D RILC : registerService: starting android::hardware::radio::V1_1::IRadio slot1 01-20 04:58:01.478 1836 1836 I RILC : RILHIDL called registerService 01-20 04:58:01.479 1836 1836 D RILD : RIL_Init RIL_register completed 01-20 04:58:01.479 1836 1836 D RILD : RIL_register_socket completed
Updated by Denis 'GNUtoo' Carikli almost 5 years ago
I probably need to add more logging to make sure it's not always doing EAGAIN.
Updated by Denis 'GNUtoo' Carikli over 4 years ago
I've written a minimal test with libsamsung-ipc to retrieve the device IMEI.
If I run it multiple times (due to crashes), the test works fine in Replicant 6.0, but not in Replicant 11.
Updated by Denis 'GNUtoo' Carikli over 4 years ago
The thing is that the bootstrap works fine and (few) messages get exchanged but nothing happens. So I need to look why the message aout the IMEI doesn't reach the test utility.
Updated by Denis 'GNUtoo' Carikli over 4 years ago
With the 5.10.0-rc2+ with Parabola I can bring the USB back, it's just de-configured. Re-running my usb-otg.sh scripts that does the setup brings everything back.Though we'd better find a way to rescan the HSIC bus without forcing the de-configuration of the OTG port as well.
We now have an ugly workaround that needs to be rewritten later on.
Updated by Denis 'GNUtoo' Carikli over 4 years ago
Status with libsamsung-ipc test utilities (both in GNU/Linux and Android) and rild (In replicant 11):
- The modem boots fine
- It receive few messages after that
- It hangs there (it doesn't register to the network etc)
ipc-modem has the same issue:
- Under Replicant 6 it registers fine to the network
- Under Parabola1 it doesn't.
1 Here there are no annoying Android security systems, everything runs as root.
Updated by _I3^ RELATIVISM over 4 years ago
- Type of work C programming, Reverse engineering (no code nor documentation available) added
Updated by _I3^ RELATIVISM over 4 years ago
- Type of work deleted (
Reverse engineering (no code nor documentation available))
Actions