Closed replytoashish008 closed 4 years ago
@replytoashish008 ,
I am wondering where the code is at the point that you are entering the background? My initial thought is that this is happening before beginBackgroundTask
is called, but I am not sure if that is the case or not.
I am wondering if you were to create the background task right at the beginning while you were creating your options, if that would fair better.
It may also be helpful to see the entire log for the run. What is above really isn't covering anything related to room connection as far as I can tell. Further, enabling debug logging could definitely show us more info.
You can do that by calling:
TwilioVideo.setLogLevel(.debug)
in your application.
If you don't feel comfortable sharing your logs publicly, you can email them to support@twilio.com and mention my name in the email and they will get routed over to me.
Thank you,
Ryan
@paynerc
here is the full log:-
2019-10-18 14:11:24.863606+0530 HomeApp[1460:1446355] WARN:TwilioVideo:[Platform]:Current sample rate (44100.00) is not the preferred rate (48000.00)
2019-10-18 14:11:24.912612+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Core]:Adding audio track ...
2019-10-18 14:11:24.917828+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Core]:Creating a video track ...
"Video track created"
2019-10-18 14:11:24.918720+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Start capturing with device: <AVCaptureFigVideoDevice: 0x1093d4af0 [Front Camera][com.apple.avfoundation.avcapturedevice.built-in_video:1]> format: <<TVIVideoFormat: 0x280072200> size: {640, 480}, fps: 30, format: 420f>.
2019-10-18 14:11:24.918859+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Prepare for capturing.
2019-10-18 14:11:24.918924+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Platform]:Starting capture pipeline ...
2019-10-18 14:11:24.924462+0530 HomeApp[1460:1448085] DEBUG:TwilioVideo:[Platform]:Adding device input: <AVCaptureDeviceInput: 0x28003c4a0 [Front Camera]>
2019-10-18 14:11:24.925342+0530 HomeApp[1460:1448085] INFO:TwilioVideo:[Platform]:Best device format was: <AVCaptureDeviceFormat: 0x280261410 'vide'/'420f' 640x 480, { 2- 30 fps}, HRSI:3088x2320, fov:54.201, max zoom:145.00 (upscales @4.82), ISO:23.0-2208.0, SS:0.000013-0.500000, supports wide color>
2019-10-18 14:11:24.930808+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Core]:Creating dedicated notifier queue ...
2019-10-18 14:11:24.930952+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Core]:AppleReachability::AppleReachability()
2019-10-18 14:11:24.931072+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Core]:Creating zeroAddrReachability
2019-10-18 14:11:24.933048+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Core]:Creating SIP stack ...
2019-10-18 14:11:24.933210+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Core]:Adding IPv4/IPv6 TLS transports ...
2019-10-18 14:11:24.933272+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]
2019-10-18 14:11:24.933316+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Creating TLS transport for domain interface= port=0
2019-10-18 14:11:24.933436+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]
2019-10-18 14:11:24.934947+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Creating TLS transport for domain interface= port=0
2019-10-18 14:11:24.935122+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl::RoomSignalingImpl()
2019-10-18 14:11:24.935171+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Core]:Starting SIP signaling stack worker thread ...
2019-10-18 14:11:24.936332+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:Starting StackThread runloop.
2019-10-18 14:11:24.936422+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Core]:Querying SIP edge (mobile-endpoint.twilio.com) IP addresses ...
2019-10-18 14:11:24.936461+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Core]:Looking up: mobile-endpoint.twilio.com
"Attempting to connect to room Optional(\"785fcacb-4b35-49ba-86c1-86ad82b8544f\")"
2019-10-18 14:11:24.949857+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Did move to window with size: {375, 667}.
Metal content scale factor is now: 2.000
2019-10-18 14:11:24.950538+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Did move to window with size: {375, 667}.
Metal content scale factor is now: 2.000
2019-10-18 14:11:25.031207+0530 HomeApp[1460:1448085] INFO:TwilioVideo:[Platform]:... Finished starting capture.
2019-10-18 14:11:25.031341+0530 HomeApp[1460:1448085] INFO:TwilioVideo:[Platform]:Session interrupted with reason: 1
2019-10-18 14:11:25.032330+0530 HomeApp[1460:1448085] INFO:TwilioVideo:[Platform]:... Capture pipeline did stop.
2019-10-18 14:11:25.032398+0530 HomeApp[1460:1448085] DEBUG:TwilioVideo:[Platform]:Teardown video output pipeline ...
2019-10-18 14:11:25.032443+0530 HomeApp[1460:1448085] DEBUG:TwilioVideo:[Platform]:Finished video output pipeline teardown.
2019-10-18 14:11:25.032483+0530 HomeApp[1460:1448085] DEBUG:TwilioVideo:[Platform]:Video pipeline did finish running
2019-10-18 14:11:25.032520+0530 HomeApp[1460:1448085] DEBUG:TwilioVideo:[Platform]:No background task to stop.
2019-10-18 14:11:25.032578+0530 HomeApp[1460:1448085] INFO:TwilioVideo:[Platform]:Session stopped running
2019-10-18 14:11:25.036831+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Application will enter foreground.
2019-10-18 14:11:25.053613+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Platform]:Manually restarting interrupted session.
2019-10-18 14:11:25.056618+0530 HomeApp[1460:1448551] INFO:TwilioVideo:[Platform]:Session interrupted with reason: 1
2019-10-18 14:11:25.056708+0530 HomeApp[1460:1448551] INFO:TwilioVideo:[Platform]:... Capture pipeline did stop.
2019-10-18 14:11:25.056754+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Platform]:Teardown video output pipeline ...
2019-10-18 14:11:25.056797+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Platform]:Finished video output pipeline teardown.
2019-10-18 14:11:25.057578+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Platform]:Video pipeline did finish running
2019-10-18 14:11:25.058474+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Platform]:No background task to stop.
2019-10-18 14:11:25.058593+0530 HomeApp[1460:1448551] INFO:TwilioVideo:[Platform]:Session stopped running
2019-10-18 14:11:25.094968+0530 HomeApp[1460:1446355] provider:didActivateAudioSession:
2019-10-18 14:11:25.117021+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:Address family: 2: 34.203.250.241
2019-10-18 14:11:25.117349+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:Address family: 2: 34.203.250.241
2019-10-18 14:11:25.117566+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Core]:Setting SIP edge (outbound proxy) to: sips:34.203.250.241:443
2019-10-18 14:11:25.117800+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Core]:DNS resolution was successful
2019-10-18 14:11:25.118015+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:Sending a ping message ...
2019-10-18 14:11:25.118354+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:Socket created in ../resiprocate-1.8/resip/stack/TcpBaseTransport.cxx at line 223, transport: 1, socket: 37
2019-10-18 14:11:25.118687+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 34.203.250.241:443 TLS target domain=unspecified mFlowKey=37 ]
2019-10-18 14:11:25.119154+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Creating TLS connection for domain [ V4 34.203.250.241:443 TLS target domain=unspecified mFlowKey=0 ] on 37
"provider:didActivateAudioSession:"
2019-10-18 14:11:25.376891+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: TLS handshake starting (client mode)
2019-10-18 14:11:25.377156+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: TLS connected
{
code = 1004;
message = "Operation executed successfully";
}
Optional(["message": Operation executed successfully, "code": 1004])
nil
deinit /handler/status/785fcacb-4b35-49ba-86c1-86ad82b8544f
2019-10-18 14:11:25.924816+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: TLS connected
2019-10-18 14:11:25.924922+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: TLS sessions set up with TLSv1.2 TLSv1/SSLv3 AES256-GCM-SHA384
2019-10-18 14:11:25.924922+0530 HomeApp[1460:1447904] DEBUG:TwilioVideo:[Core]:Parsing 'video' endpoint config: {"video":{"network_traversal_service":{"ttl":14400,"date_created":"Fri, 18 Oct 2019 08:41:25 +0000","date_updated":"Fri, 18 Oct 2019 08:41:25 +0000","ice_servers":[{"urls":"turn:global.turn.twilio.com:3478?transport=udp","username":"f35516125b5c6e67bc5bf91afd3cef3bda632d019e7cf3a61b7e8ca014180854","credential":"9K4YG/GdQUufFdpq9X2740Xi/MAUs+fgaRWtYC65vhI="},{"urls":"turns:global.turn.twilio.com:443?transport=tcp","username":"f35516125b5c6e67bc5bf91afd3cef3bda632d019e7cf3a61b7e8ca014180854","credential":"9K4YG/GdQUufFdpq9X2740Xi/MAUs+fgaRWtYC65vhI="}]}}}
2019-10-18 14:11:25.924981+0530 HomeApp[1460:1448513] INFO:TwilioVideo2019-10-18 14:11:25.925051+0530 HomeApp[1460:1448064] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl: State transition successful: kInit -> kConnecting
:[Signaling]:RESIP::SIP: subjectAltName of TLS session cert contains DNS
Via: SIP/2.0/TLS 127.0.0.1;branch=z9hG4bK-524287-1---770ce5bfbb001b16;rport
Max-Forwards: 70
Contact: sip:a264E8c546fd04a2b2e5a34cb1Dabdc0@127.0.0.1;transport=tls;ob
To: sip:orchestrator@mobile-endpoint.twilio.com
From: "a264E8c546fd04a2b2e5a34cb1Dabdc0"sip:a264E8c546fd04a2b2e5a34cb1Dabdc0@mobile-endpoint.twilio.com;tag=164ecfd1
Call-ID: EeXOhFvxl6_rsWYApmRARg..
CSeq: 1 INVITE
Session-Expires: 120
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY
Content-Type: application/room-signaling+json
Supported: timer, outbound, path, gruu, room-signaling
User-Agent: TwilioVideo SDK
X-Twilio-AccessToken: eyJjdHkiOiJ0d2lsaW8tZnBhO3Y9MSIsInR5cCI6IkpXVCIsImFsZyI6IkhTMjU2In0.eyJpc3MiOiJTSzk1NTNhMWUzNDM0ZTMzYWM1ZDk5YjVkNDVhODVkMWRiIiwiZXhwIjoxNTcxMzg4NTI0LCJncmFudHMiOnsidm9pY2UiOnsiaW5jb21pbmciOnsiYWxsb3ciOnRydWV9LCJvdXRnb2luZyI6eyJhcHBsaWNhdGlvbl9zaWQiOiJBUDIwZDY5OGMzMjk5OWQxZjk1NTU1Y2Q3MTA0OWQ0YjgzIn0sInB1c2hfY3JlZGVudGlhbF9zaWQiOiJDUmJiMDM3NjM1MjA3NjhkMDg3NjJkNzUwN2EwYmNjMTQ3IiwiZW5kcG9pbnRfaWQiOm51bGx9LCJpZGVudGl0eSI6IjM0YTRiY2U0LTkwMzktNDNjNS1hYzM5LWE2YzdhNzQ3YmRmYiIsInZpZGVvIjp7fX0sImp0aSI6IlNLOTU1M2ExZTM0MzRlMzNhYzVkOTliNWQ0NWE4NWQxZGItMTU3MTM4NzkwNCIsInN1YiI6IkFDOTliOTQ1MDJiYWVkZTliZTFmNmU1YWFiMWFjZDI0MDQifQ.0VxmUYvxi9y8BjNBnbYAOIsTkhFAH4jFlN3nXx5xmu4
Content-Length: 4246
{"format":"planb","ice_servers":"success","media_signaling":null,"name":"785fcacb-4b35-49ba-86c1-86ad82b8544f","participant":{"revision":2,"tracks":[{"enabled":true,"id":"cf726c4F20aa8eBafF4C820FEc4f3840","kind":"audio","name":"cf726c4F20aa8eBafF4C820FEc4f3840","priority":"medium"},{"enabled":true,"id":"f5D08cB02EA10482FC6a197Eb3d66F3b","kind":"video","name":"Camera","priority":"medium"}]},"peer_connections":[{"description":{"revision":1,"sdp":"v=0\r\no=- 4301739951799831412 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE audio video\r\na=msid-semantic: WMS 4b393E137A32D5BA2D24F3dDD7BdCa4A\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:j8Jw\r\na=ice-pwd:IC/0RYt2zhAg6UOziaJ4ZjAU\r\na=ice-options:trickle\r\na=fingerprint:sha-256 68:E3:2E:8F:79:2B:A2:D1:CE:14:91:10:D8:A7:27:02:36:CC:4D:36:3D:3A:03:31:21:C6:A6:4C:97:95:CA:41\r\na=setup:actpass\r\na=mid:audio\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:2190225432 cname:Dz9c3P9ireoFnHXc\r\na=ssrc:2190225432 msid:4b393E137A32D5BA2D24F3dDD7BdCa4A cf726c4F20aa8eBafF4C820FEc4f3840\r\na=ssrc:2190225432 mslabel:4b393E137A32D5BA2D24F3dDD7BdCa4A\r\na=ssrc:2190225432 label:cf726c4F20aa8eBafF4C820FEc4f3840\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 102 125 127\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:j8Jw\r\na=ice-pwd:IC/0RYt2zhAg6UOziaJ4ZjAU\r\na=ice-options:trickle\r\na=fingerprint:sha-256 68:E3:2E:8F:79:2B:A2:D1:CE:14:91:10:D8:A7:27:02:36:CC:4D:36:3D:3A:03:31:21:C6:A6:4C:97:95:CA:41\r\na=setup:actpass\r\na=mid:video\r\na=extmap:2 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:4 urn:3gpp:video-orientation\r\na=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=sendrecv\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:100 H264/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:102 red/90000\r\na=rtpmap:125 rtx/90000\r\na=fmtp:125 apt=102\r\na=rtpmap:127 ulpfec/90000\r\na=ssrc-group:FID 797927974 962777578\r\na=ssrc:797927974 cname:Dz9c3P9ireoFnHXc\r\na=ssrc:797927974 msid:4b393E137A32D5BA2D24F3dDD7BdCa4A f5D08cB02EA10482FC6a197Eb3d66F3b\r\na=ssrc:797927974 mslabel:4b393E137A32D5BA2D24F3dDD7BdCa4A\r\na=ssrc:797927974 label:f5D08cB02EA10482FC6a197Eb3d66F3b\r\na=ssrc:962777578 cname:Dz9c3P9ireoFnHXc\r\na=ssrc:962777578 msid:4b393E137A32D5BA2D24F3dDD7BdCa4A f5D08cB02EA10482FC6a197Eb3d66F3b\r\na=ssrc:962777578 mslabel:4b393E137A32D5BA2D24F3dDD7BdCa4A\r\na=ssrc:962777578 label:f5D08cB02EA10482FC6a197Eb3d66F3b\r\n","type":"offer"},"id":"fC6bBB8Cdb6BeaceD4C06558CA1F99BF"}],"publisher":{"hw_device_arch":"arm64","hw_device_manufacturer":"Apple","hw_device_model":"iPhone9,3","name":"twilio-video-ios","platform_name":"iOS","platform_version":"12.4","sdk_version":"2.10.1"},"type":"connect","version":2} 2019-10-18 14:11:25.933695+0530 HomeApp[1460:1448513] WARN:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Can't find matching transport [ V4 127.0.0.1:0 TLS target domain=unspecified mFlowKey=0 ] onMessagingDirectChannelStateChanged FCM Direct Channel Established: true 2019-10-18 14:11:26.349137+0530 HomeApp[1460:1448064] INFO:TwilioVideo:[Platform]:Session interruption ended 2019-10-18 14:11:26.349237+0530 HomeApp[1460:1448064] INFO:TwilioVideo:[Platform]:Session started running 2019-10-18 14:11:26.350801+0530 HomeApp[1460:1448064] DEBUG:TwilioVideo:[Platform]:Video pipeline will start running. 2019-10-18 14:11:26.351029+0530 HomeApp[1460:1448064] DEBUG:TwilioVideo:[Platform]:Video device will start running: <AVCaptureFigVideoDevice: 0x1093d4af0 [Front Camera][com.apple.avfoundation.avcapturedevice.built-in_video:1]>. 2019-10-18 14:11:26.354400+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Preparing renderer for frame size: {640, 480}, view bounds: {{0, 9}, {55, 74}} 2019-10-18 14:11:26.354521+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Detected a pixel format change. Preparing the rendering pipeline. Old = none, New = 420f 2019-10-18 14:11:26.609380+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]: Receiving incoming SIP message from infra SIP/2.0 100 Giving a try
Via: SIP/2.0/TLS 127.0.0.1;received=122.15.80.89;branch=z9hG4bK-524287-1---770ce5bfbb001b16;rport=41816
To: sip:orchestrator@mobile-endpoint.twilio.com
From: "a264E8c546fd04a2b2e5a34cb1Dabdc0"sip:a264E8c546fd04a2b2e5a34cb1Dabdc0@mobile-endpoint.twilio.com;tag=164ecfd1
Call-ID: EeXOhFvxl6_rsWYApmRARg..
CSeq: 1 INVITE
Server: Twilio Gateway
Content-Length: 0 2019-10-18 14:11:26.609507+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:Process INVITE response with code 100 2019-10-18 14:11:26.713432+0530 HomeApp[1460:1448064] [BoringSSL] nw_protocol_boringssl_get_output_frames(1301) [C26.1:2][0x109358830] get output frames failed, state 8196 2019-10-18 14:11:26.713662+0530 HomeApp[1460:1448064] [BoringSSL] nw_protocol_boringssl_get_output_frames(1301) [C26.1:2][0x109358830] get output frames failed, state 8196 2019-10-18 14:11:26.714571+0530 HomeApp[1460:1448064] TIC Read Status [26:0x0]: 1:57 2019-10-18 14:11:26.714624+0530 HomeApp[1460:1448064] TIC Read Status [26:0x0]: 1:57 2019-10-18 14:11:26.786709+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]: Receiving incoming SIP message from infra SIP/2.0 200 OK
Via: SIP/2.0/TLS 127.0.0.1;received=122.15.80.89;branch=z9hG4bK-524287-1---770ce5bfbb001b16;rport=41816
Record-Route: <sip:172.18.16.168:5060;r2=on;transport=udp;ftag=164ecfd1;lr>
Record-Route: <sip:34.203.250.241:443;r2=on;transport=tls;ftag=164ecfd1;lr>
Require: timer
Contact:
To: sip:orchestrator@mobile-endpoint.twilio.com;tag=07036265_6772d868_3e5e74ce-aa79-47e9-b3a6-b2a22eb0c916
From: "a264E8c546fd04a2b2e5a34cb1Dabdc0" sip:a264E8c546fd04a2b2e5a34cb1Dabdc0@mobile-endpoint.twilio.com;tag=164ecfd1
Call-ID: EeXOhFvxl6_rsWYApmRARg..
CSeq: 1 INVITE
Session-Expires: 120;refresher=uac
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE
Content-Type: application/room-signaling+json
Server: Twilio
Supported: room-signaling
X-Twilio-CallSid: CAb615e9ce8b48ce65927071a94ba06a98
X-Twilio-LegInfo: external
X-Twilio-TlsVerify: true
X-Twilio-EdgeHost: ec2-34-203-250-241.compute-1.amazonaws.com
Content-Length: 3206
{"version":2,"type":"connected","peer_connections":[{"id":"fC6bBB8Cdb6BeaceD4C06558CA1F99BF","description":{"type":"answer","sdp":"v=0\r\no=- 3780376886 3780376886 IN IP4 0.0.0.0\r\ns=VM84a52fe47a093c4bd5c57433ad8daea4\r\nt=0 0\r\na=ice-lite\r\na=msid-semantic: WMS *\r\na=group:BUNDLE audio video\r\nm=audio 19746 UDP/TLS/RTP/SAVPF 111 0\r\nc=IN IP4 34.203.254.81\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=rtcp:19746 IN IP4 34.203.254.81\r\na=rtpmap:111 opus/48000/2\r\na=rtpmap:0 PCMU/8000\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=candidate:2 1 UDP 2013266430 34.203.254.81 19746 typ host\r\na=candidate:2 2 UDP 2013266429 34.203.254.81 19443 typ host\r\na=rtcp-mux\r\na=setup:active\r\na=mid:audio\r\na=recvonly\r\na=ice-ufrag:m1Ko\r\na=ice-pwd:xXHzwoMWK2bpGKymf7gZqW\r\na=fingerprint:sha-256 D3:C0:26:88:31:9A:27:28:61:EB:04:0D:B2:82:E4:46:75:1C:A3:65:C0:D0:2F:8D:6C:62:A5:20:16:D8:47:B7\r\nm=video 19746 UDP/TLS/RTP/SAVPF 96 100 102 127\r\nc=IN IP4 34.203.254.81\r\na=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=rtcp:19746 IN IP4 34.203.254.81\r\na=rtpmap:96 VP8/90000\r\na=rtpmap:100 H264/90000\r\na=rtpmap:102 red/90000\r\na=rtpmap:127 ulpfec/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=candidate:2 1 UDP 2013266430 34.203.254.81 19746 typ host\r\na=candidate:2 2 UDP 2013266429 34.203.254.81 19443 typ host\r\na=rtcp-mux\r\na=setup:active\r\na=mid:video\r\na=recvonly\r\na=ice-ufrag:m1Ko\r\na=ice-pwd:xXHzwoMWK2bpGKymf7gZqW\r\na=fingerprint:sha-256 D3:C0:26:88:31:9A:27:28:61:EB:04:0D:B2:82:E4:46:75:1C:A3:65:C0:D0:2F:8D:6C:62:A5:20:16:D8:47:B7\r\n","revision":1}}],"sid":"RM5c4cd1866c11c1c66ecfa1bd24ced825","name":"785fcacb-4b35-49ba-86c1-86ad82b8544f","participant":{"sid":"PA9ff707de1a90697d55349113758c65b9","identity":"34a4bce4-9039-43c5-ac39-a6c7a747bdfb","tracks":[{"kind":"audio","priority":"standard","id":"cf726c4F20aa8eBafF4C820FEc4f3840","enabled":true,"sid":"MTb99042e9c735145f700c3c58370318b6","name":"cf726c4F20aa8eBafF4C820FEc4f3840","state":"ready"},{"kind":"video","priority":"standard","id":"f5D08cB02EA10482FC6a197Eb3d66F3b","enabled":true,"sid":"MTac697e40b838f50841542817bed619d3","name":"Camera","state":"ready"}],"revision":2,"state":"connected"},"participants":[],"recording":{"enabled":false,"revision":1},"subscribed":{"revision":0,"tracks":[]},"published":{"revision":1,"tracks":[{"kind":"audio","priority":"standard","id":"cf726c4F20aa8eBafF4C820FEc4f3840","enabled":true,"sid":"MTb99042e9c735145f700c3c58370318b6","name":"cf726c4F20aa8eBafF4C820FEc4f3840","state":"ready"},{"kind":"video","priority":"standard","id":"f5D08cB02EA10482FC6a197Eb3d66F3b","enabled":true,"sid":"MTac697e40b838f50841542817bed619d3","name":"Camera","state":"ready"}]},"session":"99b94502baede9be1f6e5aab1acd24045c4cd1866c11c1c66ecfa1bd24ced8259ff707de1a90697d55349113758c65b9b615e9ce8b48ce65927071a94ba06a98","options":{"media_region":"us1","signaling_region":"us1","session_timeout":30}} 2019-10-18 14:11:26.787434+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:Process INVITE response with code 200 2019-10-18 14:11:26.790948+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Core]:SipCall::updateContact set host to ec2-34-203-250-241.compute-1.amazonaws.com 2019-10-18 14:11:26.791238+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Core]:SipSignalingStackImpl::UpdateHost 2019-10-18 14:11:26.791406+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]: Sending outgoing SIP message ACK sip:172.18.54.60:5060 SIP/2.0
Via: SIP/2.0/TLS 127.0.0.1;branch=z9hG4bK-524287-1---f2ec99b893807990;rport
Max-Forwards: 70
Route: <sip:34.203.250.241:443;r2=on;transport=tls;ftag=164ecfd1;lr>
Route: <sip:172.18.16.168:5060;r2=on;transport=udp;ftag=164ecfd1;lr>
To: sip:orchestrator@mobile-endpoint.twilio.com;tag=07036265_6772d868_3e5e74ce-aa79-47e9-b3a6-b2a22eb0c916
From: "a264E8c546fd04a2b2e5a34cb1Dabdc0"sip:a264E8c546fd04a2b2e5a34cb1Dabdc0@mobile-endpoint.twilio.com;tag=164ecfd1
Call-ID: EeXOhFvxl6_rsWYApmRARg..
CSeq: 1 ACK
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY
Supported: timer, outbound, path, gruu, room-signaling
User-Agent: TwilioVideo SDK
Content-Length: 0 2019-10-18 14:11:26.793158+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Signaling]:RESIP::SIP: SipMessage::getContents: got content type (application/room-signaling+json) that is not known, returning as opaque application/octet-stream 2019-10-18 14:11:26.793932+0530 HomeApp[1460:1448513] WARN:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Can't find matching transport [ V4 127.0.0.1:0 TLS target domain=unspecified mFlowKey=0 ] 2019-10-18 14:11:26.796745+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:AppleReachability::onConnectionEstablished, socket_ipv6 = -1, socket_ipv4 = 37 2019-10-18 14:11:26.798414+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:AppleReachability started listening on address pair on 37 socket 2019-10-18 14:11:26.798509+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Canceling reconnect retry timer. 2019-10-18 14:11:26.800083+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:onAccepted 2019-10-18 14:11:26.800192+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl: State transition successful: kConnecting -> kConnected 2019-10-18 14:11:26.800747+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:PeerConnection state: Waiting -> Updating 2019-10-18 14:11:26.800814+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Process remote answer at revision 1. 2019-10-18 14:11:26.800864+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Process remote sdp for: fC6bBB8Cdb6BeaceD4C06558CA1F99BF revision is: 1. "Attempting to disconnect from room Optional(\"785fcacb-4b35-49ba-86c1-86ad82b8544f\")" 2019-10-18 14:11:26.804041+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Stop capturing. 2019-10-18 14:11:26.804131+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Platform]:Stopping capture pipeline ... 2019-10-18 14:11:26.808948+0530 HomeApp[1460:1447904] EndCallAction transaction request successful https://sandbox.ask-fast.com/qring/group/ Background task ended. 2019-10-18 14:11:26.815150+0530 HomeApp[1460:1447278] DEBUG:TwilioVideo:[Core]:Applying local description to: fC6bBB8Cdb6BeaceD4C06558CA1F99BF rev: 1 2019-10-18 14:11:26.820389+0530 HomeApp[1460:1446355] provider:performEndCallAction: 2019-10-18 14:11:26.852293+0530 HomeApp[1460:1447280] DEBUG:TwilioVideo:[Core]:Network Name: en0, Cost: 10, Type : Wifi, Preference : 127, Active : 1, id: 1, prefix : 10.184.0.0 and key : en0%10.184.0.0/21 2019-10-18 14:11:26.852373+0530 HomeApp[1460:1447280] DEBUG:TwilioVideo:[Core]:Network Name: en2, Cost: 10, Type : Wifi, Preference : 126, Active : 1, id: 2, prefix : 169.254.0.0 and key : en2%169.254.0.0/16 2019-10-18 14:11:26.852413+0530 HomeApp[1460:1447280] DEBUG:TwilioVideo:[Core]:Network Name: pdp_ip1, Cost: 900, Type : Cellular, Preference : 125, Active : 1, id: 7, prefix : 2401:4900:3521:7a82:: and key : pdp_ip1%2401:4900:3521:7a82::/64 2019-10-18 14:11:26.852449+0530 HomeApp[1460:1447280] DEBUG:TwilioVideo:[Core]:Network Name: ipsec2, Cost: 10, Type : VPN, Preference : 124, Active : 1, id: 3, prefix : 2401:4900:3521:7a82:: and key : ipsec2%2401:4900:3521:7a82::/64 2019-10-18 14:11:26.852483+0530 HomeApp[1460:1447280] DEBUG:TwilioVideo:[Core]:Network Name: ipsec3, Cost: 10, Type : VPN, Preferenc2019-10-18 14:11:26.862524+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:onSetSessionLocalDescription: fC6bBB8Cdb6BeaceD4C06558CA1F99BF e : 123, Active : 1, id: 4, prefix : 2401:4900:3521:7a82:: and key : ipsec3%2401:4900:3521:7a82::/64 2019-10-18 14:11:26.862642+0530 HomeApp[1460:1447280] DEBUG:TwilioVideo:[Core]:Network Name: lo0, Cost: 0, Type : Loopback, Preference : 122, Active : 1, id: 6, prefix : ::1 and key : lo0%::1/128 2019-10-18 14:11:26.862680+0530 HomeApp[1460:1447280] DEBUG:TwilioVideo:[Core]:Network Name: lo0, Cost: 0, Type : Loopback, Preference : 121, Active : 1, id: 5, prefix : 127.0.0.0 and key : lo0%127.0.0.0/8 2019-10-18 14:11:26.862715+0530 HomeApp[1460:1447280] DEBUG:TwilioVideo:[Core]:New preferred network reported by manager: key: en0%10.184.0.0/21, type: Wifi, cost: 10, id: 1 2019-10-18 14:11:26.869232+0530 HomeApp[1460:1447278] DEBUG:TwilioVideo:[Core]:Applying remote description to: fC6bBB8Cdb6BeaceD4C06558CA1F99BF rev: 1 2019-10-18 14:11:26.925835+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Done processing onSetSessionLocalDescription: fC6bBB8Cdb6BeaceD4C06558CA1F99BF 2019-10-18 14:11:26.925976+0530 HomeApp[1460:1448551] INFO:TwilioVideo:[Core]:Ice Gathering... 2019-10-18 14:11:26.926023+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:ICE connection state transitioned from New -> Checking 2019-10-18 14:11:26.926064+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:PeerConnection fC6bBB8Cdb6BeaceD4C06558CA1F99BF Ice connection state transitioned from New -> Checking 2019-10-18 14:11:26.926112+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Room Media state changed to Checking 2019-10-18 14:11:26.926156+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:onSetSessionRemoteDescription: fC6bBB8Cdb6BeaceD4C06558CA1F99BF 2019-10-18 14:11:26.931247+0530 HomeApp[1460:1448551] INFO:TwilioVideo:[Core]:Queue ICE candidate revision: 1 for PeerConnection: fC6bBB8Cdb6BeaceD4C06558CA1F99BF. 2019-10-18 14:11:26.931343+0530 HomeApp[1460:1448551] INFO:TwilioVideo:[Core]:Publish ICE candidate revision: 1 for PeerConnection: fC6bBB8Cdb6BeaceD4C06558CA1F99BF. 2019-10-18 14:11:26.932750+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Updating -> Open 2019-10-18 14:11:26.935532+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]: Sending outgoing SIP message INFO sip:172.18.54.60:5060 SIP/2.0
Via: SIP/2.0/TLS 127.0.0.1;branch=z9hG4bK-524287-1---29b9b3d6047da698;rport
Max-Forwards: 70
Route: <sip:34.203.250.241:443;r2=on;transport=tls;ftag=164ecfd1;lr>
Route: <sip:172.18.16.168:5060;r2=on;transport=udp;ftag=164ecfd1;lr>
To: sip:orchestrator@mobile-endpoint.twilio.com;tag=07036265_6772d868_3e5e74ce-aa79-47e9-b3a6-b2a22eb0c916
From: "a264E8c546fd04a2b2e5a34cb1Dabdc0"sip:a264E8c546fd04a2b2e5a34cb1Dabdc0@mobile-endpoint.twilio.com;tag=164ecfd1
Call-ID: EeXOhFvxl6_rsWYApmRARg..
CSeq: 2 INFO
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY
Content-Type: application/room-signaling+json
Supported: timer, outbound, path, gruu, room-signaling
User-Agent: TwilioVideo SDK
Event: room-signaling
Info-Package: room-signaling
Content-Length: 443
{"participant":{"revision":2,"tracks":[{"enabled":true,"id":"cf726c4F20aa8eBafF4C820FEc4f3840","kind":"audio","name":"cf726c4F20aa8eBafF4C820FEc4f3840","priority":"medium"},{"enabled":true,"id":"f5D08cB02EA10482FC6a197Eb3d66F3b","kind":"video","name":"Camera","priority":"medium"}]},"peer_connections":[{"ice":{"candidates":[],"complete":true,"revision":1,"ufrag":"j8Jw"},"id":"fC6bBB8Cdb6BeaceD4C06558CA1F99BF"}],"type":"update","version":2} 2019-10-18 14:11:26.938452+0530 HomeApp[1460:1448513] WARN:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Can't find matching transport [ V4 127.0.0.1:0 TLS target domain=unspecified mFlowKey=0 ] nw_protocol_boringssl_get_output_frames(1301) [C27.1:2][0x1094d3d40] get output frames failed, state 8196 2019-10-18 14:11:27.014098+0530 HomeApp[1460:1448551] [BoringSSL] nw_protocol_boringssl_get_output_frames(1301) [C27.1:2][0x1094d3d40] get output frames failed, state 8196 2019-10-18 14:11:27.014333+0530 HomeApp[1460:1448551] TIC Read Status [27:0x0]: 1:57 2019-10-18 14:11:27.014359+0530 HomeApp[1460:1448551] TIC Read Status [27:0x0]: 1:57 onMessagingDirectChannelStateChanged FCM Direct Channel Established: false 2019-10-18 14:11:27.040869+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Platform]:Closing connection to 785fcacb-4b35-49ba-86c1-86ad82b8544f after entering the background. The SDK will attempt to recover upon returning to the foreground. 2019-10-18 14:11:27.050389+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Network Lost event from ExternalReachability. 2019-10-18 14:11:27.077094+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Network Lost event from Reachability - Terminate Connection. 2019-10-18 14:11:27.090779+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Connection is terminated. 2019-10-18 14:11:27.091066+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl::onConnectionTerminated 2019-10-18 14:11:27.091219+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl: completed insightspublisher->stop() 2019-10-18 14:11:27.091351+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:Canceling reconnect retry timer. 2019-10-18 14:11:27.091524+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:onConnectionsClosed - Setting SipCall state to kConnectionLost 2019-10-18 14:11:27.092280+0530 HomeApp[1460:1448513] INFO:TwilioVideo:[Signaling]:RESIP::TRANSACTION: Sending ConnectionTerminated [ V4 34.203.250.241:443 TLS target domain=unspecified mFlowKey=37 ] to TUs 2019-10-18 14:11:27.092400+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:Ignoring onConnectionTerminated in state = 3 2019-10-18 14:11:27.094539+0530 HomeApp[1460:1448551] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl: State transition successful: kConnected -> kSyncing 2019-10-18 14:11:27.147957+0530 HomeApp[1460:1447904] INFO:TwilioVideo:[Core]:Ice Gathering Complete. 2019-10-18 14:11:27.197214+0530 HomeApp[1460:1448085] INFO:TwilioVideo:[Platform]:... Capture pipeline did stop. 2019-10-18 14:11:27.197328+0530 HomeApp[1460:1448085] DEBUG:TwilioVideo:[Platform]:Teardown video output pipeline ... 2019-10-18 14:11:27.197635+0530 HomeApp[1460:1448085] DEBUG:TwilioVideo:[Platform]:Finished video output pipeline teardown. 2019-10-18 14:11:27.197837+0530 HomeApp[1460:1448085] DEBUG:TwilioVideo:[Platform]:Video pipeline did finish running 2019-10-18 14:11:27.198159+0530 HomeApp[1460:1448085] INFO:TwilioVideo:[Platform]:Session stopped running 2019-10-18 14:11:27.199516+0530 HomeApp[1460:1446355] INFO:TwilioVideo:[Core]:Reconnecting to Room: 785fcacb-4b35-49ba-86c1-86ad82b8544f caused by failure: Signaling connection disconnected 2019-10-18 14:11:27.201347+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Did move to window with size: {0, 0}. Metal content scale factor is now: 0.000 2019-10-18 14:11:27.201940+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:Did move to window with size: {0, 0}. Metal content scale factor is now: 0.000 2019-10-18 14:11:27.246560+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl: State transition successful: kSyncing -> kDisconnecting 2019-10-18 14:11:27.246861+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl::~RoomSignalingImpl() 2019-10-18 14:11:27.246888+0530 HomeApp[1460:1448064] DEBUG:TwilioVideo:[Core]:Disconnecting from RM5c4cd1866c11c1c66ecfa1bd24ced825. 2019-10-18 14:11:27.251207+0530 HomeApp[1460:1447278] INFO:TwilioVideo:[Core]:Closed Webrtc PeerConnection fC6bBB8Cdb6BeaceD4C06558CA1F99BF 2019-10-18 14:11:27.251859+0530 HomeApp[1460:1448064] INFO:TwilioVideo:[Core]:PeerConnectionSignaling with id fC6bBB8Cdb6BeaceD4C06558CA1F99BF destroyed 2019-10-18 14:11:27.252053+0530 HomeApp[1460:1448064] DEBUG:TwilioVideo:[Core]:Closed all Peer Connections 2019-10-18 14:11:27.252232+0530 HomeApp[1460:1448064] DEBUG:TwilioVideo:[Core]:Canceling disconnect timer. 2019-10-18 14:11:27.252424+0530 HomeApp[1460:1448064] INFO:TwilioVideo:[Core]:Shutdown and join the signaling stack's thread. 2019-10-18 14:11:27.252592+0530 HomeApp[1460:1448513] DEBUG:TwilioVideo:[Core]:Shutting down StackThread runloop. 2019-10-18 14:11:27.252869+0530 HomeApp[1460:1448064] DEBUG:TwilioVideo:[Core]:AppleReachability::~AppleReachability() 2019-10-18 14:11:27.253323+0530 HomeApp[1460:1448064] WARN:TwilioVideo:[Signaling]:RESIP::TRANSACTION: On shutdown, there are Client TransactionStates remaining! 2019-10-18 14:11:27.253830+0530 HomeApp[1460:1448064] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl: State transition successful: kDisconnecting -> kDisconnected 2019-10-18 14:11:27.254693+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:-[TVILocalVideoTrack dealloc] 2019-10-18 14:11:27.259743+0530 HomeApp[1460:1447904] Execution of the command buffer was aborted due to an error during execution. Insufficient Permission (to submit GPU work from background) (IOAF code 6) 2019-10-18 14:11:27.314206+0530 HomeApp[1460:1447904] Execution of the command buffer was aborted due to an error during execution. Insufficient Permission (to submit GPU work from background) (IOAF code 6) 2019-10-18 14:11:27.314547+0530 HomeApp[1460:1447904] Execution of the command buffer was aborted due to an error during execution. Insufficient Permission (to submit GPU work from background) (IOAF code 6) 2019-10-18 14:11:27.314751+0530 HomeApp[1460:1447904] Execution of the command buffer was aborted due to an error during execution. Insufficient Permission (to submit GPU work from background) (IOAF code 6) 2019-10-18 14:11:27.325733+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:~CoreVideoSource 2019-10-18 14:11:27.327132+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:-[TVIVideoTrack dealloc] 2019-10-18 14:11:27.327384+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:-[TVILocalAudioTrack dealloc] 2019-10-18 14:11:27.328489+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:-[TVIAudioTrack dealloc] 2019-10-18 14:11:27.329281+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:-[TVIMetalRenderer dealloc] 2019-10-18 14:11:27.330582+0530 HomeApp[1460:1446355] DEBUG:TwilioVideo:[Platform]:-[TVIMetalRenderer dealloc]
@replytoashish008,
I just noticed while looking at the logs that you are using 2.10.1
. In 2.x
, unless you are already connected to the room and audio is flowing, the signaling connection is closed as soon as you enter the background since we had an issue when the OS would background the application and the signaling connection was open but no media was flowing.
This behavior has changed with the 3.0.0
release. I tested this out locally with the Quickstart by adding a background task similar to what you have shown and the room will connect while the application is in the background.
I would suggest updating to 3.0.0
and you should be good to go. Let me know if you have any further questions.
Ryan
@replytoashish008,
I was just checking in to see if upgrading to 3.0.0
solved your issue and we can close out this issue?
Thanks!
Ryan
@paynerc Upgrading to 3.0.0 is not resolving my issue. it is still there.
@replytoashish008,
Can you send another debug log now that you are running 3.x?
Thank you,
Ryan
@paynerc after a move to Twilio3x
2019-12-05 12:25:45.349937+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Platform]:Did move to window with size: {375, 667}.
Metal content scale factor is now: 2.000
2019-12-05 12:25:45.350200+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Platform]:Did move to window with size: {375, 667}.
Metal content scale factor is now: 2.000
2019-12-05 12:25:45.979605+0530 HomeApp[1137:80536] provider:performStartCallAction:
uuid = Optional(D784E033-8009-432E-A93C-CC9FAD645C1B)
"provider:performStartCallAction:"
2019-12-05 12:25:45.984473+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Creating peer connection signaling thread ...
2019-12-05 12:25:45.985901+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Creating peer connection worker thread ...
2019-12-05 12:25:45.986049+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Creating peer connection networking thread ...
2019-12-05 12:25:45.986432+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Creating peer connection factory ...
2019-12-05 12:25:46.008455+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Done creating peer connection factory.
2019-12-05 12:25:46.009302+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Adding audio track ...
2019-12-05 12:25:46.012619+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Creating a video track ...
"Video track created"
2019-12-05 12:25:46.022743+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Platform]:Start capturing with device: <AVCaptureFigVideoDevice: 0x10e2aa300 [Front Camera][com.apple.avfoundation.avcapturedevice.built-in_video:1]> format: <<TVIVideoFormat: 0x2820bcfe0> size: {640, 480}, fps: 30, format: 420f>.
2019-12-05 12:25:46.022890+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Platform]:Prepare for capturing.
2019-12-05 12:25:46.023102+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Platform]:Starting capture pipeline ...
2019-12-05 12:25:46.035112+0530 HomeApp[1137:81324] DEBUG:TwilioVideo:[Platform]:Adding device input: <AVCaptureDeviceInput: 0x2820b0ae0 [Front Camera]>
2019-12-05 12:25:46.036210+0530 HomeApp[1137:81324] INFO:TwilioVideo:[Platform]:Best device format was: <AVCaptureDeviceFormat: 0x2822cac20 'vide'/'420f' 640x 480, { 2- 60 fps}, fov:54.400, max zoom:60.00 (upscales @2.00), ISO:34.0-2176.0, SS:0.000013-0.500000, supports HDR>
2019-12-05 12:25:46.159041+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Creating dedicated notifier queue ...
2019-12-05 12:25:46.160630+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Core]:AppleReachability::AppleReachability()
2019-12-05 12:25:46.160753+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Core]:Creating zeroAddrReachability
2019-12-05 12:25:46.166527+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Creating SIP stack ...
2019-12-05 12:25:46.167352+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Adding IPv4/IPv6 TLS transports ...
2019-12-05 12:25:46.167475+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]
2019-12-05 12:25:46.167628+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Creating TLS transport for domain interface= port=0
2019-12-05 12:25:46.168078+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]
2019-12-05 12:25:46.168248+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Creating TLS transport for domain interface= port=0
2019-12-05 12:25:46.169120+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl::RoomSignalingImpl()
2019-12-05 12:25:46.169372+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Core]:Starting SIP signaling stack worker thread ...
2019-12-05 12:25:46.171576+0530 HomeApp[1137:81628] DEBUG:TwilioVideo:[Core]:Starting StackThread runloop.
"Attempting to connect to room Optional(\"65e4b750-f20c-4e90-b4a7-f1440ee3e7d8\")"
2019-12-05 12:25:46.188855+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Core]:Querying SIP edge (mobile-endpoint.twilio.com) IP addresses ...
2019-12-05 12:25:46.205547+0530 HomeApp[1137:81324] INFO:TwilioVideo:[Platform]:... Finished starting capture.
2019-12-05 12:25:46.206059+0530 HomeApp[1137:81324] INFO:TwilioVideo:[Platform]:Session interrupted with reason: 1
2019-12-05 12:25:46.206177+0530 HomeApp[1137:81324] INFO:TwilioVideo:[Platform]:... Capture pipeline did stop.
2019-12-05 12:25:46.206234+0530 HomeApp[1137:81324] DEBUG:TwilioVideo:[Platform]:Teardown video output pipeline ...
2019-12-05 12:25:46.206313+0530 HomeApp[1137:81324] DEBUG:TwilioVideo:[Platform]:Finished video output pipeline teardown.
2019-12-05 12:25:46.206895+0530 HomeApp[1137:81324] DEBUG:TwilioVideo:[Platform]:Video pipeline did finish running
2019-12-05 12:25:46.207179+0530 HomeApp[1137:81324] DEBUG:TwilioVideo:[Platform]:No background task to stop.
2019-12-05 12:25:46.207580+0530 HomeApp[1137:81324] INFO:TwilioVideo:[Platform]:Session stopped running
2019-12-05 12:25:46.209715+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Core]:Looking up: mobile-endpoint.twilio.com
2019-12-05 12:25:46.321207+0530 HomeApp[1137:81628] DEBUG:TwilioVideo:[Core]:Address family: 2: 34.203.250.241
2019-12-05 12:25:46.321335+0530 HomeApp[1137:81628] DEBUG:TwilioVideo:[Core]:Address family: 2: 34.203.250.241
2019-12-05 12:25:46.321474+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Core]:Setting SIP edge (outbound proxy) to: sips:34.203.250.241:443
2019-12-05 12:25:46.321790+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Core]:DNS resolution was successful
2019-12-05 12:25:46.321908+0530 HomeApp[1137:81628] DEBUG:TwilioVideo:[Core]:Sending a ping message ...
2019-12-05 12:25:46.323137+0530 HomeApp[1137:81628] DEBUG:TwilioVideo:[Core]:Socket created in ../resiprocate-1.8/resip/stack/TcpBaseTransport.cxx at line 223, transport: 1, socket: 49
2019-12-05 12:25:46.323531+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 34.203.250.241:443 TLS target domain=unspecified mFlowKey=49 ]
2019-12-05 12:25:46.323652+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: Creating TLS connection for domain [ V4 34.203.250.241:443 TLS target domain=unspecified mFlowKey=0 ] on 49
2019-12-05 12:25:46.326966+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: TLS handshake starting (client mode)
2019-12-05 12:25:46.327202+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: TLS connected
onMessagingDirectChannelStateChanged
FCM Direct Channel Established: false
2019-12-05 12:25:46.666174+0530 HomeApp[1137:80536] INFO:TwilioVideo:[Platform]:Terminating connection attempt after entering the background.
2019-12-05 12:25:46.666382+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl: State transition successful: kInit -> kDisconnecting
2019-12-05 12:25:46.672959+0530 HomeApp[1137:81551] DEBUG:TwilioVideo:[Core]:RoomSignalingImpl: State transition failure: kDisconnecting -> kConnecting
2019-12-05 12:25:46.686037+0530 HomeApp[1137:81551] DEBUG:TwilioVideo:[Core]:Disconnecting from .
2019-12-05 12:25:46.686127+0530 HomeApp[1137:81551] DEBUG:TwilioVideo:[Core]:Closed all Peer Connections
2019-12-05 12:25:46.686411+0530 HomeApp[1137:81551] DEBUG:TwilioVideo:[Core]:Canceling disconnect timer.
2019-12-05 12:25:46.711268+0530 HomeApp[1137:80536] Can't end BackgroundTask: no background task exists with identifier 63 (0x3f), or it may have already been ended. Break in UIApplicationEndBackgroundTaskError() to debug.
2019-12-05 12:25:47.187949+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: TLS connected
2019-12-05 12:25:47.188425+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Signaling]:RESIP::TRANSPORT: TLS sessions set up with TLSv1.2 TLSv1/SSLv3 AES256-GCM-SHA384
2019-12-05 12:25:47.188759+0530 HomeApp[1137:81628] INFO:TwilioVideo:[Signaling]:RESIP::SIP: subjectAltName of TLS session cert contains DNS
Will attempt to recover by breaking constraint <NSLayoutConstraint:0x2803fa6c0 UITextField:0x10e182050.width == 245 (active)>
Make a symbolic breakpoint at UIViewAlertForUnsatisfiableConstraints to catch this in the debugger. The methods in the UIConstraintBasedLayoutDebugging category on UIView listed in <UIKitCore/UIView.h> may also be helpful. 2019-12-05 12:26:17.194228+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Platform]:Did move to window with size: {0, 0}. Metal content scale factor is now: 0.000 2019-12-05 12:26:17.196154+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Platform]:Did move to window with size: {0, 0}. Metal content scale factor is now: 0.000 2019-12-05 12:26:17.228277+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Platform]:-[TVIMetalRenderer dealloc] 2019-12-05 12:26:17.229700+0530 HomeApp[1137:80536] DEBUG:TwilioVideo:[Platform]:-[TVIMetalRenderer dealloc]
@replytoashish008,
I apologize for the delay in getting back to you on this issue. Are you still experiencing thing issue? If you are, can you please send updated log information so I can troubleshoot it?
Thank you,
Ryan
@paynerc
Thank you, Ryan. The issue is resolved after the Twilio 3.2.3 update.
Description
while connecting to the room, if the user moves to the background. Twilio get didDisconnectWithError. or unable to join a room.
[Description of the issue]
Steps to Reproduce
Code
Expected Behavior
A Users should able to connect to the room even if the app enters the background.
Actual Behavior
Call get disconnect without error description when the app enter the background while connecting to the room.
Reproduces How Often
almost every time
Logs
2019-10-16 16:44:50.855761+0530 HomeApp[1055:1034260] [BoringSSL] nw_protocol_boringssl_get_output_frames(1301) [C19.1:2][0x10b309270] get output frames failed, state 8196 2019-10-16 16:44:50.856037+0530 HomeApp[1055:1034260] [BoringSSL] nw_protocol_boringssl_get_output_frames(1301) [C19.1:2][0x10b309270] get output frames failed, state 8196 2019-10-16 16:44:50.856423+0530 HomeApp[1055:1034260] TIC Read Status [19:0x0]: 1:57 2019-10-16 16:44:50.856472+0530 HomeApp[1055:1034260] TIC Read Status [19:0x0]: 1:57 onMessagingDirectChannelStateChanged FCM Direct Channel Established: false 2019-10-16 16:44:51.321660+0530 HomeApp[1055:1034260] [BoringSSL] nw_protocol_boringssl_get_output_frames(1301) [C20.1:2][0x10b3bd650] get output frames failed, state 8196 2019-10-16 16:44:51.321836+0530 HomeApp[1055:1034260] [BoringSSL] nw_protocol_boringssl_get_output_frames(1301) [C20.1:2][0x10b3bd650] get output frames failed, state 8196 2019-10-16 16:44:51.322083+0530 HomeApp[1055:1034260] TIC Read Status [20:0x0]: 1:57 2019-10-16 16:44:51.322109+0530 HomeApp[1055:1034260] TIC Read Status [20:0x0]: 1:57
Versions
All relevant version information for the issue.
Video iOS SDK
2.10.1
Xcode
11.1
iOS Version
[e.g. 12.4]
iOS Device
[e.g. iPhone 6]