I have asked the customer to do unlock the iPhone as soon as it is turned on, and they are supposed to do so, but we have received additional questions about this behavior.
Because APNs push and local push are mixed up in my logs, I am a bit confused and unable to answer the customer yet now.
For this reason, I have separated APNs push and local push, and arranged the app and iPhone operations in chronological order.
Looking at this, could you please answer the following three questions?
[Question 1]
If my arrange the app and iPhone operations in chronological order, they would look like this. Do you agree with me on this?
[Question 2]
In this log, should I suspect that the inability to receive APNs PUSH at point 5 is due to a network problem?
There was no problem with the network, and the iPhone was not unlocked, so did it simply discard the PUSH received from the network?
(From what I understand, I assume it's the latter.)
[Question 3]
I believe that the sysdiagnose log at points 8 and 10 is a log for local push incoming calls, but do you know why they are failing?
/////////////////////////////////////////
For LOCAL PUSH INFO: point1(My app log info)
Stop Extention (Moved to Wi-Fi out of area)
2024-08-08 19:34:16.813+0900
For APNS PUSH INFO: point2(sysdiagnose log info)
An update had been installed and the device had not been unlocked. Note messages like:
2024-08-08 19:34:55.328+0900 routined[38:1637] [DATA PROTECTION] unlocked since boot, NO
2024-08-08 19:34:56.329+0900 atc[47:2598] [Default] Device locked, waiting for first unlock.
2024-08-08 19:34:58.530+0900 carkitd[153:2177] [DNDWD] Not fetching vehicle state - device has not unlocked since boot.
2024-08-08 19:34:59.066+0900 sharingd[68:3068] [AutoUnlock] ### Device not unlocked on start up after retry (state: <private>)
2024-08-08 19:35:00.546+0900 itunesstored[202:3664] [StoreServices] SSXPCServer: Invalidating connection; device has not been unlocked since boot
For APNS PUSH INFO: point3(sysdiagnose log info)
and then later
2024-08-09 09:27:25.112+0900 sharingd[68:1354] [PairedUnlock] Transitioned out of lock screen.
2024-08-09 09:27:25.112+0900 sharingd[68:1354] [PairedUnlock] Transitioned out of lock screen.
For APNS PUSH INFO: point4(sysdiagnose log info)
apsd launch, but is unable to register properly because of the protection state
2024-08-08 19:34:47.734+0900 apsd[134:1862] [xpc] Requesting container lookup; class = 12, identifier = <private>, group_identifier = <private>, create = 1, temp = 0, euid = 501, uid = 501
2024-08-08 19:34:47.734+0900 apsd[134:1862] [xpc] Query; euid = 501, uid = 501, class = 12, identifier = [(null)](0), flags = 900000003
2024-08-08 19:34:47.738+0900 apsd[134:1862] [unspecified] container_query_get_single_result: success
2024-08-08 19:34:47.738+0900 apsd[134:1862] [sandbox] Consumed sandbox extension; path = [<private>], handle = 1
2024-08-08 19:34:47.738+0900 apsd[134:1862] [unspecified] container_system_path_for_identifier: success
2024-08-08 19:34:47.754+0900 apsd[134:1862] [connectionServer] Attempt to set push wake topics without dark wake enabled: ()
2024-08-08 19:34:47.761+0900 apsd[134:1862] [connectionServer] Attempt to set push wake topics without dark wake enabled: ()
2024-08-08 19:34:47.766+0900 apsd[134:1862] [connectionServer] Attempt to set push wake topics without dark wake enabled: ()
2024-08-08 19:34:47.770+0900 apsd[134:1862] [connectionServer] Attempt to set push wake topics without dark wake enabled: ()
For APNS PUSH INFO: point5(My push server log info)
sent VoIP push 144 times.
from
2024-08-08 19:56:25.046+0900
to
2024-08-09 09:27:01.206+0900
For LOCAL PUSH INFO: point6(My app log info)
Start Extention (Moved to Wi-Fi in area)
2024-08-09 09:27:29.415+0900
For LOCAL PUSH INFO: point7(My app log info)
My app log info: Incomming call
2024-08-09 09:54:39.209+0900
For LOCAL PUSH INFO??: point8(sysdiagnose log info)
If you search for "failed to report", the cause is fairly clear.
The messages occur in alternating pairs between callservicesd and nesessionmanager:
2024-08-09 09:54:39.235+0900 nesessionmanager[228:62692] [] NESMAppPushSession[LocalPushDefaultConfiguration:7EC72C65-84F2-4167-A654-11B9CEEE5953] failed to report incoming call to CallKit, error: Error Domain=NSCocoaErrorDomain Code=4099 "The connection to service named com.apple.callkit.networkextension.messagecontrollerhost was invalidated from this process." UserInfo={NSDebugDescription=The connection to service named com.apple.callkit.networkextension.messagecontrollerhost was invalidated from this process.}
2024-08-09 09:54:39.239+0900 callservicesd[133:62552] [Default] Application <bundle id> will not be launched because it failed to report an incoming call too many times (or repeatedly crashed.)
For LOCAL PUSH INFO: point9(My app log info)
My app log info: Incomming call
2024-08-09 11:39:12.336+0900
For LOCAL PUSH INFO??: point10(sysdiagnose log info)
Later message don't pair up quite as clearly, but I think that's mainly because callservicesd is logging slightly differently
2024-08-09 11:39:12.367+0900 callservicesd[133:88002] [Default] VoIP push for app <bundle id> dropped on the floor
Best Regards,