{"action":"created","issue":{"url":"https://api.github.com/repos/BelledonneCommunications/linphone-iphone/issues/297","repository_url":"https://api.github.com/repos/BelledonneCommunications/linphone-iphone","labels_url":"https://api.github.com/repos/BelledonneCommunications/linphone-iphone/issues/297/labels{/name}","comments_url":"https://api.github.com/repos/BelledonneCommunications/linphone-iphone/issues/297/comments","events_url":"https://api.github.com/repos/BelledonneCommunications/linphone-iphone/issues/297/events","html_url":"https://github.com/BelledonneCommunications/linphone-iphone/issues/297","id":253765017,"number":297,"title":"Push Notifications Not Received if User Force Closes App","user":{"login":"iJasonWade","id":12824479,"avatar_url":"https://avatars0.githubusercontent.com/u/12824479?v=4","gravatar_id":"","url":"https://api.github.com/users/iJasonWade","html_url":"https://github.com/iJasonWade","followers_url":"https://api.github.com/users/iJasonWade/followers","following_url":"https://api.github.com/users/iJasonWade/following{/other_user}","gists_url":"https://api.github.com/users/iJasonWade/gists{/gist_id}","starred_url":"https://api.github.com/users/iJasonWade/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/iJasonWade/subscriptions","organizations_url":"https://api.github.com/users/iJasonWade/orgs","repos_url":"https://api.github.com/users/iJasonWade/repos","events_url":"https://api.github.com/users/iJasonWade/events{/privacy}","received_events_url":"https://api.github.com/users/iJasonWade/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"assignees":[],"milestone":null,"comments":3,"created_at":"2017-08-29T18:46:27Z","updated_at":"2017-08-30T12:07:33Z","closed_at":null,"author_association":"NONE","body":"We are receiving the push notifications just fine if the user puts the app in the background and/or has the screen locked. The issue is that if the user force closes the app, looking at our logs the only difference seems to be that the network is down and the linphone core cannot complete the subscribes.\r\n\r\n**Logs when user force closes the app**\r\n\r\n`2017-08-29 13:29:23.550028-0500 ePhoneGO[1335:466530] [Notice] lib/SIP network reachability state is now [DOWN]\r\n2017-08-29 13:29:23.550594-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher [0x17414c3f0] stopped.\r\n2017-08-29 13:29:23.550981-0500 ePhoneGO[1335:466530] [Notice] lib/Destroying op [0x14fdab4a0] of type [SalOpRegister]\r\n2017-08-29 13:29:23.551607-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher [0x17414c3f0] stopped.\r\n2017-08-29 13:29:23.552122-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Transaction [0x170361380] deleted\r\n2017-08-29 13:29:23.552644-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Transaction [0x17017e300] deleted\r\n2017-08-29 13:29:23.563939-0500 ePhoneGO[1335:466530] [Notice] lib/Proxy config [0x14fd21d00] for identity [\"REDACTED\" <sip:REDACTED>] moving from state [LinphoneRegistrationOk] to [LinphoneRegistrationNone] on core [0x1500c4a00]\r\n2017-08-29 13:29:23.574497-0500 ePhoneGO[1335:466530] [Notice] lib/Linphone core [0x1500c4a00] notified [registration_state_changed]\r\n2017-08-29 13:29:23.582194-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Listening point destroying [1] channels\r\n2017-08-29 13:29:23.582365-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel 0x106444000: state DISCONNECTED\r\n2017-08-29 13:29:23.582542-0500 ePhoneGO[1335:466530] [Notice] belle-sip/refresher [0x174146250] has channel [0x106444000] in state [DISCONNECTED], reporting error\r\nRegistration impossible (network down))\r\n2017-08-29 13:29:23.582779-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher[0x174146250]: scheduling next timer in 500 ms for purpose [retry]\r\n2017-08-29 13:29:23.583031-0500 ePhoneGO[1335:466530] [Notice] lib/Subscribe refresher  [503] reason [io error]\r\n13:292017-08-29 13:29:23.584203-0500 ePhoneGO[1335:466530] [Notice] lib/LinphoneEvent [0x1742ebb80] moving to subscription state LinphoneSubscriptionOutgoingProgress\r\n2017-08-29 13:29:23.588090-0500 ePhoneGO[1335:466530] [Notice] belle-sip/refresher [0x17414c550] has channel [0x106444000] in state [DISCONNECTED], reporting error\r\n2017-08-29 13:29:23.588289-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher[0x17414c550]: scheduling next timer in 500 ms for purpose [retry]\r\n2017-08-29 13:29:23.588470-0500 ePhoneGO[1335:466530] [Notice] lib/Subscribe refresher  [503] reason [io error]\r\n2017-08-29 13:29:23.588635-0500 ePhoneGO[1335:466530] [Notice] lib/LinphoneEvent [0x1702fa600] moving to subscription state LinphoneSubscriptionOutgoingProgress\r\n2017-08-29 13:29:23.588801-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x170368400] channel state changed to [DISCONNECTED]\r\n2017-08-29 13:29:23.589037-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x174375000] channel state changed to [DISCONNECTED]\r\n2017-08-29 13:29:23.591234-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x1741395a0] channel state changed to [DISCONNECTED]\r\n2017-08-29 13:29:23.591519-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Changing [server] [NOTIFY] transaction [0x1741395a0], from state [COMPLETED] to [TERMINATED]\r\n2017-08-29 13:29:23.591742-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Server NOTIFY transaction [0x1741395a0] terminated\r\n2017-08-29 13:29:23.592093-0500 ePhoneGO[1335:466530] [Notice] lib/Unhandled transaction terminated [0x1741395a0]\r\n2017-08-29 13:29:23.592369-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x1741395a0]: ending transaction background task with id=[5a].\r\n2017-08-29 13:29:23.592793-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x174136620] channel state changed to [DISCONNECTED]\r\n2017-08-29 13:29:23.593840-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Changing [server] [NOTIFY] transaction [0x174136620], from state [COMPLETED] to [TERMINATED]\r\n2017-08-29 13:29:23.594021-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Server NOTIFY transaction [0x174136620] terminated\r\n2017-08-29 13:29:23.595938-0500 ePhoneGO[1335:466530] [Notice] lib/Unhandled transaction terminated [0x174136620]\r\n2017-08-29 13:29:23.596071-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x174136620]: ending transaction background task with id=[5c].\r\n2017-08-29 13:29:23.596414-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Changing [server] [NOTIFY] transaction [0x170137fc0], from state [COMPLETED] to [TERMINATED]\r\n2017-08-29 13:29:23.596538-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Server NOTIFY transaction [0x170137fc0] terminated\r\n2017-08-29 13:29:23.596654-0500 ePhoneGO[1335:466530] [Notice] lib/Unhandled transaction terminated [0x170137fc0]\r\n2017-08-29 13:29:23.597062-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x170137fc0]: ending transaction background task with id=[62].\r\n2017-08-29 13:29:23.600342-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x170138060] channel state changed to [DISCONNECTED]\r\n2017-08-29 13:29:23.600518-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Changing [server] [NOTIFY] transaction [0x170138060], from state [COMPLETED] to [TERMINATED]\r\n2017-08-29 13:29:23.600898-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Server NOTIFY transaction [0x170138060] terminated\r\n2017-08-29 13:29:23.601085-0500 ePhoneGO[1335:466530] [Notice] lib/Unhandled transaction terminated [0x170138060]\r\n2017-08-29 13:29:23.601235-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x170138060]: ending transaction background task with id=[64].\r\n2017-08-29 13:29:23.601460-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Transaction [0x1741395a0] deleted\r\n2017-08-29 13:29:23.601868-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Transaction [0x174136620] deleted\r\n2017-08-29 13:29:23.604216-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Transaction [0x170137fc0] deleted\r\n2017-08-29 13:29:23.604414-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Channel [0x106444000] destroyed\r\n2017-08-29 13:29:23.604651-0500 ePhoneGO[1335:466530] [Notice] lib/Media network reachability state is now [DOWN]\r\n2017-08-29 13:29:23.605091-0500 ePhoneGO[1335:466530] [Notice] lib/ms_filter_unlink: MSFilePlayer:0x170135c20,0-->MSDtmfGen:0x1701370c0,0\r\n2017-08-29 13:29:23.605278-0500 ePhoneGO[1335:466530] [Notice] lib/ms_filter_unlink: MSDtmfGen:0x1701370c0,0-->MSResample:0x1701366c0,0\r\n2017-08-29 13:29:23.605513-0500 ePhoneGO[1335:466530] [Notice] lib/ms_filter_unlink: MSResample:0x1701366c0,0-->MSAUWrite:0x170136c60,0\r\n2017-08-29 13:29:23.606969-0500 ePhoneGO[1335:467189] [Notice] lib/Ring MSTicker thread exiting`\r\n\r\n**Logs when the app is simply closed and in the background**\r\n\r\n`2017-08-29 13:28:33.868082-0500 ePhoneGO[1335:466530] [Notice] lib/SIP network reachability state is now [DOWN]\r\n2017-08-29 13:28:33.868369-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher [0x170149480] stopped.\r\n2017-08-29 13:28:33.868698-0500 ePhoneGO[1335:466530] [Notice] lib/Destroying op [0x14fe6bcc0] of type [SalOpRegister]\r\n2017-08-29 13:28:33.868906-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher [0x170149480] stopped.\r\n2017-08-29 13:28:33.869366-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Transaction [0x174178480] deleted\r\n2017-08-29 13:28:33.869581-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Transaction [0x17016f180] deleted\r\n2017-08-29 13:28:33.869835-0500 ePhoneGO[1335:466530] [Notice] lib/Proxy config [0x14fd21d00] for identity [\"REDACTED\" <sip:REDACTED>] moving from state [LinphoneRegistrationOk] to [LinphoneRegistrationNone] on core [0x1500c4a00]\r\n2017-08-29 13:28:33.875703-0500 ePhoneGO[1335:466530] [Notice] lib/Linphone core [0x1500c4a00] notified [registration_state_changed]\r\n2017-08-29 13:28:33.919336-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Listening point destroying [1] channels\r\n2017-08-29 13:28:33.919761-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel 0x106c80000: state DISCONNECTED\r\n2017-08-29 13:28:33.920030-0500 ePhoneGO[1335:466530] [Notice] belle-sip/refresher [0x174146250] has channel [0x106c80000] in state [DISCONNECTED], reporting error\r\n2017-08-29 13:28:33.920319-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher[0x174146250]: scheduling next timer in 500 ms for purpose [retry]\r\n2017-08-29 13:28:33.920507-0500 ePhoneGO[1335:466530] [Notice] lib/Subscribe refresher  [503] reason [io error]\r\n2017-08-29 13:28:33.920642-0500 ePhoneGO[1335:466530] [Notice] lib/LinphoneEvent [0x1742ebb80] moving to subscription state LinphoneSubscriptionOutgoingProgress\r\nonRegister():668 - New registration state: Linpho2017-08-29 13:28:33.920782-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x174371040] channel state changed to [DISCONNECTED]\r\n2017-08-29 13:28:33.920915-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x174137fc0] channel state changed to [DISCONNECTED]\r\n2017-08-29 13:28:33.922036-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Channel [0x106c80000] destroyed\r\n2017-08-29 13:28:33.922454-0500 ePhoneGO[1335:466530] [Notice] lib/Media network reachability state is now [DOWN]\r\nn2017-08-29 13:28:33.923227-0500 ePhoneGO[1335:466530] [Notice] lib/ms_filter_unlink: MSFilePlayer:0x174135a40,0-->MSDtmfGen:0x174135b80,0\r\neRegistrationNone (message: Registration impos2017-08-29 13:28:33.923406-0500 ePhoneGO[1335:466530] [Notice] lib/ms_filter_unlink: MSDtmfGen:0x174135b80,0-->MSResample:0x174135cc0,0\r\nsible (network down))\r\n2017-08-29 13:28:33.923534-0500 ePhoneGO[1335:466530] [Notice] lib/ms_filter_unlink: MSResample:0x174135cc0,0-->MSAUWrite:0x174135c20,0\r\n2017-08-29 13:28:33.924108-0500 ePhoneGO[1335:466655] [Notice] lib/Ring MSTicker thread exiting\r\n2017-08-29 13:28:35.135916-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Background task belle-sip transaction(0x17016ec40) started. Remaining time 178.7 secs\r\n2017-08-29 13:28:35.137698-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x17016ec40]: starting transaction background task with id=[44].\r\n2017-08-29 13:28:35.139900-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Dialog [0x1743c1950]: now updated by transaction [0x17016ec40].\r\n2017-08-29 13:28:35.141291-0500 ePhoneGO[1335:466530] [Notice] belle-sip/belle_sip_dialog_schedule_expiration() dialog=0x1743c1950 expires_value=3600\r\n2017-08-29 13:28:35.142799-0500 ePhoneGO[1335:466530] [Notice] belle-sip/belle_sip_client_transaction_send_request(): waiting channel to be ready\r\n2017-08-29 13:28:35.148986-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Background task belle-sip send channel started. Remaining time 178.7 secs\r\n2017-08-29 13:28:35.150341-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000]: starting send background task with id=[45].\r\n2017-08-29 13:28:35.151302-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000]: starting resolution of REDACTED\r\n2017-08-29 13:28:35.152853-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel 0x106444000: state RES_IN_PROGRESS\r\n2017-08-29 13:28:35.154617-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x17016ec40] channel state changed to [RES_IN_PROGRESS]\r\n2017-08-29 13:28:35.158505-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Resolver is using DNS server(s):\r\n2017-08-29 13:28:35.160241-0500 ePhoneGO[1335:466530] [Notice] belle-sip/\tREDACTED\r\n2017-08-29 13:28:35.162074-0500 ePhoneGO[1335:466530] [Notice] belle-sip/\tREDACTED\r\n2017-08-29 13:28:35.165071-0500 ePhoneGO[1335:466530] [Notice] belle-sip/resolver_process_data dns_res_check() in progress\r\n2017-08-29 13:28:35.166338-0500 ePhoneGO[1335:466530] [Notice] belle-sip/DNS resolution awaiting response, queued to main loop\r\n2017-08-29 13:28:35.260242-0500 ePhoneGO[1335:466530] [Notice] belle-sip/No SRV result for [_sip._udp.REDACTED], trying A/AAAA.\r\n2017-08-29 13:28:35.269589-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Resolver is using DNS server(s):\r\n2017-08-29 13:28:35.271169-0500 ePhoneGO[1335:466530] [Notice] belle-sip/\tREDACTED\r\n2017-08-29 13:28:35.272286-0500 ePhoneGO[1335:466530] [Notice] belle-sip/\tREDACTED\r\n2017-08-29 13:28:35.274690-0500 ePhoneGO[1335:466530] [Notice] belle-sip/resolver_process_data dns_res_check() in progress\r\n2017-08-29 13:28:35.277559-0500 ePhoneGO[1335:466530] [Notice] belle-sip/DNS resolution awaiting response, queued to main loop\r\n2017-08-29 13:28:35.281812-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Resolver is using DNS server(s):\r\n2017-08-29 13:28:35.290491-0500 ePhoneGO[1335:466530] [Notice] belle-sip/\tREDACTED\r\n2017-08-29 13:28:35.292118-0500 ePhoneGO[1335:466530] [Notice] belle-sip/\tREDACTED\r\n2017-08-29 13:28:35.294553-0500 ePhoneGO[1335:466530] [Notice] belle-sip/resolver_process_data dns_res_check() in progress\r\n2017-08-29 13:28:35.296107-0500 ePhoneGO[1335:466530] [Notice] belle-sip/DNS resolution awaiting response, queued to main loop\r\n2017-08-29 13:28:35.364655-0500 ePhoneGO[1335:466530] [Notice] belle-sip/REDACTED resolved to REDACTED\r\n2017-08-29 13:28:35.366420-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel 0x106444000: state RES_DONE\r\n2017-08-29 13:28:35.367863-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x17016ec40] channel state changed to [RES_DONE]\r\n2017-08-29 13:28:35.369429-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel 0x106444000: state CONNECTING\r\n2017-08-29 13:28:35.370735-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x17016ec40] channel state changed to [CONNECTING]\r\n2017-08-29 13:28:35.371682-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Trying to connect to [UDP://::ffff:REDACTED]\r\n2017-08-29 13:28:35.373513-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Channel has local address REDACTED\r\n2017-08-29 13:28:35.374793-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel 0x106444000: state READY\r\n2017-08-29 13:28:35.376447-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x17016ec40] channel state changed to [READY]\r\n2017-08-29 13:28:35.377407-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Changing [client] [SUBSCRIBE] transaction [0x17016ec40], from state [INIT] to [TRYING]\r\n2017-08-29 13:28:35.379503-0500 ePhoneGO[1335:466530] [Notice] channel [0x106444000]: message sent to [UDP://REDACTED], size: [664] bytes\r\n2017-08-29 13:28:35.380922-0500 ePhoneGO[1335:466530] [Notice] \tSUBSCRIBE sip:REDACTED;verified SIP/2.0\r\n2017-08-29 13:28:35.381693-0500 ePhoneGO[1335:466530] [Notice] \tVia: SIP/2.0/UDP REDACTED;branch=z9hG4bK.MHDvKXu1W;rport\r\n2017-08-29 13:28:35.382543-0500 ePhoneGO[1335:466530] [Notice] \tFrom: \"REDACTED\" <sip:REDACTED>;tag=S2sffA5cO\r\n2017-08-29 13:28:35.383785-0500 ePhoneGO[1335:466530] [Notice] \tTo: <sip:REDACTED>;tag=vCtysuyMgB0MZAR2E75421\r\n2017-08-29 13:28:35.384928-0500 ePhoneGO[1335:466530] [Notice] \tCSeq: 21 SUBSCRIBE\r\n2017-08-29 13:28:35.385732-0500 ePhoneGO[1335:466530] [Notice] \tCall-ID: 4~3nAjvWA-\r\n2017-08-29 13:28:35.386909-0500 ePhoneGO[1335:466530] [Notice] \tMax-Forwards: 70\r\n2017-08-29 13:28:35.387156-0500 ePhoneGO[1335:466530] [Notice] \tSupported: replaces, outbound\r\n2017-08-29 13:28:35.389492-0500 ePhoneGO[1335:466530] [Notice] \tEvent: as-feature-event\r\n2017-08-29 13:28:35.390610-0500 ePhoneGO[1335:466530] [Notice] \tExpires: 3600\r\n2017-08-29 13:28:35.391665-0500 ePhoneGO[1335:466530] [Notice] \tContact: <sip:REDACTED;transport=udp>;+sip.instance=\"<urn:uuid:3204b7c7-528b-499b-9eb9-1797553cdb2d>\"\r\n2017-08-29 13:28:35.391884-0500 ePhoneGO[1335:466530] [Notice] \tContent-Type: application/as-feature-event\r\n2017-08-29 13:28:35.392496-0500 ePhoneGO[1335:466530] [Notice] \tAccept: application/x-as-feature-event+xml\r\n2017-08-29 13:28:35.393792-0500 ePhoneGO[1335:466530] [Notice] \tUser-Agent: REDACTED 1.1 iPhone 6 iOS 10.3.3/ (belle-sip/1.6.3)\r\n2017-08-29 13:28:35.395191-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000]: ending send background task with id=[45].\r\n2017-08-29 13:28:35.498595-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Background task belle-sip recv channel started. Remaining time 178.4 secs\r\n2017-08-29 13:28:35.500175-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000]: starting recv background task with id=[46].\r\n2017-08-29 13:28:35.502546-0500 ePhoneGO[1335:466530] [Notice] channel [0x106444000]: received [369] new bytes from [UDP://sproxyp01.hs.cs.gcp01.esihs.net:5060]:\r\n2017-08-29 13:28:35.503749-0500 ePhoneGO[1335:466530] [Notice] \tSIP/2.0 200 OK\r\n2017-08-29 13:28:35.504348-0500 ePhoneGO[1335:466530] [Notice] \tVia: SIP/2.0/UDP 10.100.33.89:53391;branch=z9hG4bK.MHDvKXu1W;rport=64725;received=192.64.95.2\r\n2017-08-29 13:28:35.504956-0500 ePhoneGO[1335:466530] [Notice] \tTo: <sip:REDACTED>;tag=vCtysuyMgB0MZAR2E75421\r\n2017-08-29 13:28:35.505473-0500 ePhoneGO[1335:466530] [Notice] \tFrom: \"REDACTED\" <sip:REDACTED>;tag=S2sffA5cO\r\n2017-08-29 13:28:35.505987-0500 ePhoneGO[1335:466530] [Notice] \tCall-ID: 4~3nAjvWA-\r\n2017-08-29 13:28:35.506541-0500 ePhoneGO[1335:466530] [Notice] \tCSeq: 21 SUBSCRIBE\r\n2017-08-29 13:28:35.507385-0500 ePhoneGO[1335:466530] [Notice] \tContact: <sip:REDACTED;verified>\r\n2017-08-29 13:28:35.507898-0500 ePhoneGO[1335:466530] [Notice] \tExpires: 3600\r\n2017-08-29 13:28:35.508619-0500 ePhoneGO[1335:466530] [Notice] \tContent-Length: 0\r\n2017-08-29 13:28:35.514607-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000] [369] bytes parsed\r\n2017-08-29 13:28:35.515514-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000]: discovered public ip and port are [REDACTED]\r\n2017-08-29 13:28:35.516699-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Found transaction matching response.\r\n2017-08-29 13:28:35.517404-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Changing [client] [SUBSCRIBE] transaction [0x17016ec40], from state [TRYING] to [COMPLETED]\r\n2017-08-29 13:28:35.518055-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Dialog [0x1743c1950]: now updated by transaction [0x17016ec40].\r\n2017-08-29 13:28:35.518714-0500 ePhoneGO[1335:466530] [Notice] belle-sip/belle_sip_dialog_schedule_expiration() dialog=0x1743c1950 expires_value=3600\r\n2017-08-29 13:28:35.519766-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher [0x174146250]: contact address [REDACTED] does not match channel address[REDACTED] on channel [0x106444000]\r\n2017-08-29 13:28:35.520346-0500 ePhoneGO[1335:466530] [Notice] belle-sip/belle_sip_refresher_start(): refresher [0x174146250] is resubmitting request because contact sent was not correct in original request.\r\n2017-08-29 13:28:35.524338-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Background task belle-sip transaction(0x170368400) started. Remaining time 178.4 secs\r\n2017-08-29 13:28:35.525011-0500 ePhoneGO[1335:466530] [Notice] belle-sip/transaction [0x170368400]: starting transaction background task with id=[47].\r\n2017-08-29 13:28:35.526028-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Dialog [0x1743c1950]: now updated by transaction [0x170368400].\r\n2017-08-29 13:28:35.526608-0500 ePhoneGO[1335:466530] [Notice] belle-sip/belle_sip_dialog_schedule_expiration() dialog=0x1743c1950 expires_value=3600\r\n2017-08-29 13:28:35.527759-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Changing [client] [SUBSCRIBE] transaction [0x170368400], from state [INIT] to [TRYING]\r\n2017-08-29 13:28:35.529089-0500 ePhoneGO[1335:466530] [Notice] channel [0x106444000]: message sent to [UDP://REDACTED], size: [663] bytes\r\n2017-08-29 13:28:35.529584-0500 ePhoneGO[1335:466530] [Notice] \tSUBSCRIBE sip:REDACTED;verified SIP/2.0\r\n2017-08-29 13:28:35.530150-0500 ePhoneGO[1335:466530] [Notice] \tVia: SIP/2.0/UDP REDACTED;branch=z9hG4bK.Wj4j~WdsE;rport\r\n2017-08-29 13:28:35.530770-0500 ePhoneGO[1335:466530] [Notice] \tFrom: \"REDACTED\" <sip:REDACTED>;tag=S2sffA5cO\r\n2017-08-29 13:28:35.531810-0500 ePhoneGO[1335:466530] [Notice] \tTo: <sip:REDACTED>;tag=vCtysuyMgB0MZAR2E75421\r\n2017-08-29 13:28:35.532261-0500 ePhoneGO[1335:466530] [Notice] \tCSeq: 22 SUBSCRIBE\r\n2017-08-29 13:28:35.532750-0500 ePhoneGO[1335:466530] [Notice] \tCall-ID: 4~3nAjvWA-\r\n2017-08-29 13:28:35.533347-0500 ePhoneGO[1335:466530] [Notice] \tMax-Forwards: 70\r\n2017-08-29 13:28:35.534023-0500 ePhoneGO[1335:466530] [Notice] \tSupported: replaces, outbound\r\n2017-08-29 13:28:35.534567-0500 ePhoneGO[1335:466530] [Notice] \tEvent: as-feature-event\r\n2017-08-29 13:28:35.535373-0500 ePhoneGO[1335:466530] [Notice] \tExpires: 3600\r\n2017-08-29 13:28:35.535871-0500 ePhoneGO[1335:466530] [Notice] \tContact: <sip:REDACTED;transport=udp>;+sip.instance=\"<urn:uuid:3204b7c7-528b-499b-9eb9-1797553cdb2d>\"\r\n2017-08-29 13:28:35.536690-0500 ePhoneGO[1335:466530] [Notice] \tContent-Type: application/as-feature-event\r\n2017-08-29 13:28:35.537309-0500 ePhoneGO[1335:466530] [Notice] \tAccept: application/x-as-feature-event+xml\r\n2017-08-29 13:28:35.538020-0500 ePhoneGO[1335:466530] [Notice] \tUser-Agent: REDACTED 1.1 iPhone 6 iOS 10.3.3/ (belle-sip/1.6.3)\r\n2017-08-29 13:28:35.538755-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000]: ending recv background task with id=[46].\r\n2017-08-29 13:28:35.917507-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Background task belle-sip recv channel started. Remaining time 178.0 secs\r\n2017-08-29 13:28:35.918708-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000]: starting recv background task with id=[48].\r\n2017-08-29 13:28:35.920677-0500 ePhoneGO[1335:466530] [Notice] channel [0x106444000]: received [369] new bytes from [UDP://sproxyp01.hs.cs.gcp01.esihs.net:5060]:\r\n2017-08-29 13:28:35.921275-0500 ePhoneGO[1335:466530] [Notice] \tSIP/2.0 200 OK\r\n2017-08-29 13:28:35.922290-0500 ePhoneGO[1335:466530] [Notice] \tVia: SIP/2.0/UDP REDACTED;branch=z9hG4bK.Wj4j~WdsE;rport=64725;received=REDACTED\r\n2017-08-29 13:28:35.923103-0500 ePhoneGO[1335:466530] [Notice] \tTo: <sip:REDACTED>;tag=vCtysuyMgB0MZAR2E75421\r\n2017-08-29 13:28:35.924283-0500 ePhoneGO[1335:466530] [Notice] \tFrom: \"REDACTED\" <sip:REDACTED>;tag=S2sffA5cO\r\n2017-08-29 13:28:35.925186-0500 ePhoneGO[1335:466530] [Notice] \tCall-ID: 4~3nAjvWA-\r\n2017-08-29 13:28:35.926024-0500 ePhoneGO[1335:466530] [Notice] \tCSeq: 22 SUBSCRIBE\r\n2017-08-29 13:28:35.926767-0500 ePhoneGO[1335:466530] [Notice] \tContact: <sip:REDACTED;verified>\r\n2017-08-29 13:28:35.927730-0500 ePhoneGO[1335:466530] [Notice] \tExpires: 3600\r\n2017-08-29 13:28:35.928619-0500 ePhoneGO[1335:466530] [Notice] \tContent-Length: 0\r\n2017-08-29 13:28:35.937470-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000] [369] bytes parsed\r\n2017-08-29 13:28:35.938607-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Found transaction matching response.\r\n2017-08-29 13:28:35.940371-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Changing [client] [SUBSCRIBE] transaction [0x170368400], from state [TRYING] to [COMPLETED]\r\n2017-08-29 13:28:35.941551-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Dialog [0x1743c1950]: now updated by transaction [0x170368400].\r\n2017-08-29 13:28:35.943072-0500 ePhoneGO[1335:466530] [Notice] belle-sip/belle_sip_dialog_schedule_expiration() dialog=0x1743c1950 expires_value=3600\r\n2017-08-29 13:28:35.955229-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher [0x174146250]:  has no contact for request [0x1700fd200].\r\n2017-08-29 13:28:35.957086-0500 ePhoneGO[1335:466530] [Notice] belle-sip/Refresher[0x174146250]: scheduling next timer in 3240000 ms for purpose [normal refresh]\r\n2017-08-29 13:28:35.959735-0500 ePhoneGO[1335:466530] [Notice] lib/Subscribe refresher  [200] reason [OK]\r\n2017-08-29 13:28:35.961709-0500 ePhoneGO[1335:466530] [Notice] lib/op [0x14fd64410] : set_or_update_dialog() current=[0x1743c1950] new=[0x1743c1950]\r\n2017-08-29 13:28:35.964841-0500 ePhoneGO[1335:466530] [Notice] lib/LinphoneEvent [0x1742ebb80] moving to subscription state LinphoneSubscriptionActive\r\n2017-08-29 13:28:35.966466-0500 ePhoneGO[1335:466530] [Notice] belle-sip/channel [0x106444000]: ending recv background task with id=[48].`"},"comment":{"url":"https://api.github.com/repos/BelledonneCommunications/linphone-iphone/issues/comments/325970576","html_url":"https://github.com/BelledonneCommunications/linphone-iphone/issues/297#issuecomment-325970576","issue_url":"https://api.github.com/repos/BelledonneCommunications/linphone-iphone/issues/297","id":325970576,"user":{"login":"iJasonWade","id":12824479,"avatar_url":"https://avatars0.githubusercontent.com/u/12824479?v=4","gravatar_id":"","url":"https://api.github.com/users/iJasonWade","html_url":"https://github.com/iJasonWade","followers_url":"https://api.github.com/users/iJasonWade/followers","following_url":"https://api.github.com/users/iJasonWade/following{/other_user}","gists_url":"https://api.github.com/users/iJasonWade/gists{/gist_id}","starred_url":"https://api.github.com/users/iJasonWade/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/iJasonWade/subscriptions","organizations_url":"https://api.github.com/users/iJasonWade/orgs","repos_url":"https://api.github.com/users/iJasonWade/repos","events_url":"https://api.github.com/users/iJasonWade/events{/privacy}","received_events_url":"https://api.github.com/users/iJasonWade/received_events","type":"User","site_admin":false},"created_at":"2017-08-30T12:07:33Z","updated_at":"2017-08-30T12:07:33Z","author_association":"NONE","body":"The main log I see is: `New registration state: LinphoneRegistrationNone (message: Registration impossible (network down))`\r\nWe have verified that on the FlexiSip side the push is sent out but the client iOS device sends back a 503 in this scenario"}}
{"actor":{"display_login":"iJasonWade"}}