Closed danhd123 closed 3 years ago
FYI, here's a log when the audio and disconnection work as expected (haven't verified whether CMSampleBuffer
s from the ARSession
are being delivered during the call):
2021-03-31 17:22:06.248297-0700 ARKit-Twilio-Sandbox[9039:8452338] Metal API Validation Enabled
incoming!
2021-03-31 17:22:06.394326-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](0x16d453000): API Call create
2021-03-31 17:22:06.394548-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](0x16d453000): Creating peer connection signaling thread ...
2021-03-31 17:22:06.394938-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](0x16d453000): Creating peer connection worker thread ...
2021-03-31 17:22:06.395309-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](0x16d453000): Creating peer connection networking thread ...
2021-03-31 17:22:06.395801-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](0x16d453000): Creating media engine ...
2021-03-31 17:22:06.396539-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](0x16d453000): Done creating media engine.
2021-03-31 17:22:06.396762-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](0x16d453000): Creating peer connection factory ...
2021-03-31 17:22:06.398815-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](0x16d453000): Done creating peer connection factory.
2021-03-31 17:22:06.399663-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call handleMessage
2021-03-31 17:22:06.399923-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Creating dedicated notifier queue ...
2021-03-31 17:22:06.400673-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Selected edge - roaming Selected region - gll
2021-03-31 17:22:06.400994-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Initializing SIP stack ...
registerd for remote notifications
2021-03-31 17:22:06.405033-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Adding IPv4/IPv6 TLS transports ...
2021-03-31 17:22:06.406095-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Signaling](Thread 0x0x1074054f0): RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]
2021-03-31 17:22:06.406203-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: incoming
2021-03-31 17:22:06.407693-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Signaling](Thread 0x0x1074054f0): RESIP::TRANSPORT: Creating TLS transport for domain interface= port=0
2021-03-31 17:22:06.464174-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Signaling](Thread 0x0x1074054f0): RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]
2021-03-31 17:22:06.464462-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Signaling](Thread 0x0x1074054f0): RESIP::TRANSPORT: Creating TLS transport for domain interface= port=0
2021-03-31 17:22:06.465471-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Initializing DUM ...
2021-03-31 17:22:06.467619-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Starting SIP stack thread ...
2021-03-31 17:22:06.468741-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Starting DUM thread ...
2021-03-31 17:22:06.501058-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getSignalingThread
2021-03-31 17:22:06.502181-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getNetworkingThread
2021-03-31 17:22:06.503312-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): initialize
2021-03-31 17:22:06.504610-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Starting the Network Manager
2021-03-31 17:22:06.505246-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): AppleReachability::AppleReachability()
2021-03-31 17:22:06.563654-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Creating zeroAddrReachability
2021-03-31 17:22:06.566789-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:06.633955-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): listen
2021-03-31 17:22:06.635303-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:06.637288-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getTo
2021-03-31 17:22:06.638532-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getCallSid
2021-03-31 17:22:06.700032-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:06.702321-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getTo
2021-03-31 17:22:06.704756-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getCallSid
2021-03-31 17:22:06.710809-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): ListenerImpl
2021-03-31 17:22:06.715064-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Started listen no-op timer for 39869 ms
2021-03-31 17:22:06.716730-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): twilio::voice::AsyncDnsResolver::AsyncDnsResolver(std::weak_ptr<DnsResolverObserver>)
2021-03-31 17:22:06.716909-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: listen
2021-03-31 17:22:06.717110-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): AsyncDnsResolver resolving hostname = chunderm.gll.twilio.com
2021-03-31 17:22:06.722563-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getCallSid
2021-03-31 17:22:06.727345-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getTo
2021-03-31 17:22:06.728669-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getCallerInfo
2021-03-31 17:22:06.729747-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): isVerified
2021-03-31 17:22:06.730396-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): AsyncDnsResolver Resolved ip_addr = 54.85.119.44, error = 0
2021-03-31 17:22:06.730726-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): virtual void twilio::voice::SipClient::onResolveResult(twilio::voice::AsyncDnsResolver *, std::string, int)
2021-03-31 17:22:06.731021-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): onResolveResult for listen, hostname = sip:chunderm.gll.twilio.com:443;transport=tls, ip_addr = 54.85.119.44
2021-03-31 17:22:06.731246-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): New SipCall ..., this=0x10b812c00, handle = 1
2021-03-31 17:22:06.731399-0700 ARKit-Twilio-Sandbox[9039:8452563] INFO:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Setting outbound proxy to: sip:54.85.119.44:443;transport=tls
2021-03-31 17:22:06.732865-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:06.733231-0700 ARKit-Twilio-Sandbox[9039:8452571] DEBUG:Twilio:[Core](0x16dae3000): Socket created in resip/stack/TcpBaseTransport.cxx at line 223, transport: 1, socket: 27
2021-03-31 17:22:06.734313-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 54.85.119.44:443 TLS target domain=54.85.119.44 mFlowKey=27 ]
2021-03-31 17:22:06.734777-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::TRANSPORT: Creating TLS connection for domain [ V4 54.85.119.44:443 TLS target domain=54.85.119.44 mFlowKey=0 ] on 27
2021-03-31 17:22:06.736115-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call createAudioSource
2021-03-31 17:22:06.736896-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call createAudioTrack
2021-03-31 17:22:06.737245-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Adding audio track ...
2021-03-31 17:22:06.763271-0700 ARKit-Twilio-Sandbox[9039:8452568] INFO:Twilio:[Platform](0x16d9cb000): Background task started for registration/unregistration, identifer = 1
2021-03-31 17:22:06.763713-0700 ARKit-Twilio-Sandbox[9039:8452568] DEBUG:Twilio:[Platform](0x16d9cb000): Register for push notifications.
2021-03-31 17:22:06.825190-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::TRANSPORT: TLS handshake starting (client mode)
2021-03-31 17:22:06.825649-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::TRANSPORT: TLS connected
2021-03-31 17:22:06.988873-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:06.989051-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getTo
2021-03-31 17:22:06.989804-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call accept
2021-03-31 17:22:06.991581-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getNetworkingThread
2021-03-31 17:22:06.994408-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): NetworkManager
2021-03-31 17:22:06.995675-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): accept
2021-03-31 17:22:06.999540-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getWebRtcTrack
2021-03-31 17:22:07.005117-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call RTCIceServers
2021-03-31 17:22:07.005546-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call RTCIceServer
2021-03-31 17:22:07.006986-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Creating peer connection ...
2021-03-31 17:22:07.057170-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::TRANSPORT: TLS connected
2021-03-31 17:22:07.057452-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::TRANSPORT: TLS sessions set up with TLSv1.2 TLSv1/SSLv3 ECDHE-RSA-AES128-GCM-SHA256
2021-03-31 17:22:07.057658-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::SIP: subjectAltName of TLS session cert contains DNS <*.twilio.com>
2021-03-31 17:22:07.057803-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::SIP: subjectAltName of TLS session cert contains DNS <twilio.com>
2021-03-31 17:22:07.068854-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::TRANSPORT: TLS handshake done for peer *.twilio.com, twilio.com
2021-03-31 17:22:07.074674-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Adding local stream to peer connection ...
2021-03-31 17:22:07.077336-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Create initial local offer
2021-03-31 17:22:07.078704-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): onCreateSessionLocalDescription
2021-03-31 17:22:07.079432-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): OnSignalingChange
2021-03-31 17:22:07.079505-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Signaling state: have-local-offer
2021-03-31 17:22:07.079619-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received signaling-state event: have-local-offer
2021-03-31 17:22:07.079680-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.079719-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.081673-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Platform](Thread 0x0x1074054f0): -[TVOCallInvite dealloc]
2021-03-31 17:22:07.081690-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: accepted-by-local
2021-03-31 17:22:07.081869-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.081883-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): onSetSessionLocalDescription
2021-03-31 17:22:07.081991-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.082353-0700 ARKit-Twilio-Sandbox[9039:8452565] DEBUG:Twilio:[Core](MediaFactoryImpl::networking 0x0x281131700): GetNetworks
2021-03-31 17:22:07.082403-0700 ARKit-Twilio-Sandbox[9039:8452565] DEBUG:Twilio:[Core](MediaFactoryImpl::networking 0x0x281131700): GetNetworks
2021-03-31 17:22:07.084235-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Platform](Thread 0x0x1074054f0): -[TVOCallerInfo dealloc]
2021-03-31 17:22:07.085220-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): ~CallInviteImpl
2021-03-31 17:22:07.086033-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): ~CallerInfoImpl
2021-03-31 17:22:07.086792-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Platform](Thread 0x0x1074054f0): -[TVOLocalAudioTrack dealloc]
2021-03-31 17:22:07.086957-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Platform](Thread 0x0x1074054f0): -[TVOAudioTrack dealloc]
2021-03-31 17:22:07.092881-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Local SDP - v=0
o=- 3689954706932560771 2 IN IP4 127.0.0.x
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS 4f0D04a1Fd7483fDd3b623AFBa0f4962
m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:JoVT
a=ice-pwd:sdBYeSV5xeL/U2fjFkXrhdHX
a=ice-options:trickle
a=fingerprint:sha-256 9F:63:63:0A:9A:22:1F:98:B1:2C:14:8C:D2:18:CA:28:C4:E4:02:AD:D6:AB:DF:E3:90:2D:14:5F:BE:E2:AA:7D
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=sendrecv
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:112 telephone-event/32000
a=rtpmap:113 telephone-event/16000
a=rtpmap:126 telephone-event/8000
a=ssrc:2364348938 cname:J6jVel3UQVPnhrAC
a=ssrc:2364348938 msid:4f0D04a1Fd7483fDd3b623AFBa0f4962 4a6f2c8F3fa669fD713BEfc4bD37Cd72
a=ssrc:2364348938 mslabel:4f0D04a1Fd7483fDd3b623AFBa0f4962
a=ssrc:2364348938 label:4a6f2c8F3fa669fD713BEfc4bD37Cd72
2021-03-31 17:22:07.093399-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): twilio::voice::AsyncDnsResolver::AsyncDnsResolver(std::weak_ptr<DnsResolverObserver>)
2021-03-31 17:22:07.093457-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): AsyncDnsResolver resolving hostname = chunderm.gll.twilio.com
2021-03-31 17:22:07.093615-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Gathered ICE candidate: candidate:949675038 1 udp 2122262783 2603:3024:e3d:e000:ccdb:7711:9e1b:4360 63739 typ host generation 0 ufrag JoVT network-id 2 network-cost 10, id: wOcMHBmj
2021-03-31 17:22:07.093721-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Gathered ICE candidate: candidate:2055211565 1 udp 2122194687 10.1.10.x 50370 typ host generation 0 ufrag JoVT network-id 1 network-cost 10, id: Y0fnyzlJ
2021-03-31 17:22:07.093821-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Gathered ICE candidate: candidate:733621180 1 udp 2122131711 2607:fb90:30d8:d61f:183f:18a4:cb82:abab 50563 typ host generation 0 ufrag JoVT network-id 10 network-cost 900, id: HPcVOYy1
2021-03-31 17:22:07.094743-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Gathered ICE candidate: candidate:2257706897 1 udp 2122066175 2607:fc20:30eb:fa03:3546:65b3:8240:5a69 50731 typ host generation 0 ufrag JoVT network-id 11 network-cost 900, id: ZBWllxu+
2021-03-31 17:22:07.094840-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Gathered ICE candidate: candidate:2667736941 1 udp 2121998079 192.0.0.1 51563 typ host generation 0 ufrag JoVT network-id 9 network-cost 900, id: +yxekEKS
2021-03-31 17:22:07.094930-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Gathered ICE candidate: candidate:2412779662 1 udp 2121935103 2607:fc20:30eb:fa03:32:3dbe:e63e:1dfc 61463 typ host generation 0 ufrag JoVT network-id 3 network-cost 50, id: OUzUw7sl
2021-03-31 17:22:07.095020-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Gathered ICE candidate: candidate:2412779662 1 udp 2121869567 2607:fc20:30eb:fa03:32:3dbe:e63e:1dfc 51846 typ host generation 0 ufrag JoVT network-id 4 network-cost 50, id: 8Z+ibRNd
2021-03-31 17:22:07.098461-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received ICE event.
Group: ice-gathering-state
Name: gathering
2021-03-31 17:22:07.098638-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.098748-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.100191-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.100345-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.101511-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.101563-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.102011-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.102055-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.102397-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.102437-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.154784-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.154949-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.208087-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.208143-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.209847-0700 ARKit-Twilio-Sandbox[9039:8452571] WARN:Twilio:[Signaling](0x16dae3000): RESIP::TRANSPORT: Can't find matching transport [ V4 10.1.10.x:0 TLS target domain=unspecified mFlowKey=0 ]
2021-03-31 17:22:07.211541-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.211717-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.269914-0700 ARKit-Twilio-Sandbox[9039:8452572] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 100 tid=31372de77e934872 cseq=1 INVITE / 1 from(wire)
2021-03-31 17:22:07.271467-0700 ARKit-Twilio-Sandbox[9039:8452572] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 410 tid=31372de77e934872 cseq=1 INVITE contact=172.25.63.x:10193 / 1 from(wire)
2021-03-31 17:22:07.271815-0700 ARKit-Twilio-Sandbox[9039:8452572] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Failure: error response: SipResp: 410 tid=31372de77e934872 cseq=1 INVITE contact=172.25.63.x:10193 / 1 from(wire)
2021-03-31 17:22:07.273915-0700 ARKit-Twilio-Sandbox[9039:8452572] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Transition UAC_Start -> InviteSession::Terminated
2021-03-31 17:22:07.274128-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 1
2021-03-31 17:22:07.274338-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 1 to SipCall
2021-03-31 17:22:07.274550-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): virtual void twilio::voice::SipCall::onFailure(resip::ClientInviteSessionHandle, const resip::SipMessage &)
2021-03-31 17:22:07.274853-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): Received incoming SIP message from infra:
SIP/2.0 410 Gone
Via: SIP/2.0/TLS 10.1.10.x;received=98.234.28.232;branch=z9hG4bK-524287-1---31372de77e934872;rport=65039
Contact: <sip:172.25.63.x:10193>
To: <sip:chunderm.gll.twilio.com:443;transport=tls>;tag=47096565_6772d868_0b92a080-4c6e-48eb-8abf-eda21dc62485
From: <sip:VoiceSDK@chunderm.gll.twilio.com>;tag=48402372
Call-ID: jM9SSr4jFMa4rxbEZvoNfw..
CSeq: 1 INVITE
Server: Twilio
X-Twilio-CallSid: CAae1fbd354b32c1fd155ae7ad9cb19187
X-Twilio-EdgeHost: ec2-54-85-119-44.compute-1.amazonaws.com
X-Twilio-EdgeRegion: us1
X-Twilio-Zone: US_EAST_VIRGINIA
Content-Length: 0
2021-03-31 17:22:07.275387-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): onFailure
2021-03-31 17:22:07.275998-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 1
2021-03-31 17:22:07.276258-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 1 to SipCall
2021-03-31 17:22:07.276423-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): onTerminated: reason 0, this=0x10b812c00
2021-03-31 17:22:07.276837-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): Received incoming SIP message from infra:
SIP/2.0 410 Gone
Via: SIP/2.0/TLS 10.1.10.x;received=98.234.28.232;branch=z9hG4bK-524287-1---31372de77e934872;rport=65039
Contact: <sip:172.25.63.x:10193>
To: <sip:chunderm.gll.twilio.com:443;transport=tls>;tag=47096565_6772d868_0b92a080-4c6e-48eb-8abf-eda21dc62485
From: <sip:VoiceSDK@chunderm.gll.twilio.com>;tag=48402372
Call-ID: jM9SSr4jFMa4rxbEZvoNfw..
CSeq: 1 INVITE
Server: Twilio
X-Twilio-CallSid: CAae1fbd354b32c1fd155ae7ad9cb19187
X-Twilio-EdgeHost: ec2-54-85-119-44.compute-1.amazonaws.com
X-Twilio-EdgeRegion: us1
X-Twilio-Zone: US_EAST_VIRGINIA
Content-Length: 0
2021-03-31 17:22:07.277157-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): onTerminated
2021-03-31 17:22:07.277351-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): raiseCancelCallInvite
2021-03-31 17:22:07.277364-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): ~SipCall(): 0x10b812c00
2021-03-31 17:22:07.282610-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): AsyncDnsResolver Resolved ip_addr = 54.85.119.44, error = 0
2021-03-31 17:22:07.282652-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): virtual void twilio::voice::SipClient::onResolveResult(twilio::voice::AsyncDnsResolver *, std::string, int)
2021-03-31 17:22:07.282696-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): onResolveResult for connect, hostname = sip:chunderm.gll.twilio.com:443;transport=tls, ip_addr = 54.85.119.44
2021-03-31 17:22:07.282733-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): New SipCall ..., this=0x10802c000, handle = 4
2021-03-31 17:22:07.282764-0700 ARKit-Twilio-Sandbox[9039:8452563] INFO:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Setting outbound proxy to: sip:54.85.119.44:443;transport=tls
2021-03-31 17:22:07.356300-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Gathered ICE candidate: candidate:2577501377 1 udp 1685987071 98.234.28.232 50370 typ srflx raddr 10.1.10.x rport 50370 generation 0 ufrag JoVT network-id 1 network-cost 10, id: wU79ZC2H
2021-03-31 17:22:07.356586-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.356625-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.370154-0700 ARKit-Twilio-Sandbox[9039:8452572] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 100 tid=0038f3edf78ce46a cseq=1 INVITE / 1 from(wire)
2021-03-31 17:22:07.404175-0700 ARKit-Twilio-Sandbox[9039:8452566] DEBUG:Twilio:[Platform](0x16d70f000): Completed registration request.
2021-03-31 17:22:07.404250-0700 ARKit-Twilio-Sandbox[9039:8452566] DEBUG:Twilio:[Platform](0x16d70f000): Registration location: https://ers.twilio.com/v1/registrations/7b53227256c034d6e4680a3ac86dd5a0
2021-03-31 17:22:07.404311-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside register:deviceToken:completion:, registered for Twilio push notifications.
2021-03-31 17:22:07.404779-0700 ARKit-Twilio-Sandbox[9039:8452571] WARN:Twilio:[Signaling](0x16dae3000): RESIP::TRANSPORT: Can't find matching transport [ V4 10.1.10.x:0 TLS target domain=unspecified mFlowKey=0 ]
2021-03-31 17:22:07.404846-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Ending registration/unregistration background task, identifier = 1
2021-03-31 17:22:07.405196-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Registration/unregistration background task ended
2021-03-31 17:22:07.405818-0700 ARKit-Twilio-Sandbox[9039:8452572] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 410 tid=0038f3edf78ce46a cseq=1 INVITE contact=172.25.14.x:10193 / 1 from(wire)
2021-03-31 17:22:07.406255-0700 ARKit-Twilio-Sandbox[9039:8452572] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Failure: error response: SipResp: 410 tid=0038f3edf78ce46a cseq=1 INVITE contact=172.25.14.x:10193 / 1 from(wire)
2021-03-31 17:22:07.406733-0700 ARKit-Twilio-Sandbox[9039:8452572] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Transition UAC_Start -> InviteSession::Terminated
2021-03-31 17:22:07.406996-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 4
2021-03-31 17:22:07.407398-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 4 to SipCall
2021-03-31 17:22:07.408112-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): virtual void twilio::voice::SipCall::onFailure(resip::ClientInviteSessionHandle, const resip::SipMessage &)
2021-03-31 17:22:07.408571-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): Received incoming SIP message from infra:
SIP/2.0 410 Call Canceled
Via: SIP/2.0/TLS 10.1.10.x;received=98.234.28.232;branch=z9hG4bK-524287-1---0038f3edf78ce46a;rport=65039
Contact: <sip:172.25.14.x:10193>
To: <sip:chunderm.gll.twilio.com:443;transport=tls>;tag=65382284_6772d868_1994d3a0-16e4-441c-91d5-2b50c7aabf8b
From: <sip:VoiceSDK@chunderm.gll.twilio.com>;tag=d0fe80bf
Call-ID: n9XnUxHeyHB1yN-XgPI8GA..
CSeq: 1 INVITE
Server: Twilio
X-Twilio-CallSid: CAae1fbd354b32c1fd155ae7ad9cb19187
X-Twilio-EdgeHost: ec2-54-85-119-44.compute-1.amazonaws.com
X-Twilio-EdgeRegion: us1
X-Twilio-Zone: US_EAST_VIRGINIA
Content-Length: 0
2021-03-31 17:22:07.409166-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): onFailure
2021-03-31 17:22:07.409603-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 4
2021-03-31 17:22:07.409702-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: error
2021-03-31 17:22:07.409941-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): OnIceConnectionChange
2021-03-31 17:22:07.409990-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.410194-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.412123-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Call State: Disconnected ICE connection state: closed
2021-03-31 17:22:07.413062-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): OnConnectionChange
2021-03-31 17:22:07.413064-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received ICE event.
Group: ice-connection-state
Name: closed
2021-03-31 17:22:07.413191-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.413315-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.414178-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 4 to SipCall
2021-03-31 17:22:07.414385-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): onTerminated: reason 0, this=0x10802c000
2021-03-31 17:22:07.415069-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): Received incoming SIP message from infra:
SIP/2.0 410 Call Canceled
Via: SIP/2.0/TLS 10.1.10.x;received=98.234.28.232;branch=z9hG4bK-524287-1---0038f3edf78ce46a;rport=65039
Contact: <sip:172.25.14.x:10193>
To: <sip:chunderm.gll.twilio.com:443;transport=tls>;tag=65382284_6772d868_1994d3a0-16e4-441c-91d5-2b50c7aabf8b
From: <sip:VoiceSDK@chunderm.gll.twilio.com>;tag=d0fe80bf
Call-ID: n9XnUxHeyHB1yN-XgPI8GA..
CSeq: 1 INVITE
Server: Twilio
X-Twilio-CallSid: CAae1fbd354b32c1fd155ae7ad9cb19187
X-Twilio-EdgeHost: ec2-54-85-119-44.compute-1.amazonaws.com
X-Twilio-EdgeRegion: us1
X-Twilio-Zone: US_EAST_VIRGINIA
Content-Length: 0
2021-03-31 17:22:07.415649-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): onTerminated
2021-03-31 17:22:07.416077-0700 ARKit-Twilio-Sandbox[9039:8452572] DEBUG:Twilio:[Core](0x16db6f000): ~SipCall(): 0x10802c000
2021-03-31 17:22:07.416158-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Call State: Disconnected PeerConnection state: closed
2021-03-31 17:22:07.416545-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): OnSignalingChange
2021-03-31 17:22:07.416580-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received Peer Connection State event.
Group: pc-connection-state
Name: closed
2021-03-31 17:22:07.416737-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.416956-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.417237-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Signaling state: closed
2021-03-31 17:22:07.417717-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received signaling-state event: closed
2021-03-31 17:22:07.417816-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.417958-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:07.418524-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Invoking onConnectFailure.
2021-03-31 17:22:07.897728-0700 ARKit-Twilio-Sandbox[9039:8452338] AVAudioSession setAggregatedIOPreference:error: Error Domain=NSOSStatusErrorDomain Code=560030580 "(null)"
2021-03-31 17:22:08.571192-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): dealloc.
2021-03-31 17:22:08.571280-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): willDestroy
2021-03-31 17:22:08.571357-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Stopping the Network Manager
2021-03-31 17:22:08.571402-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): ~NetworkMonitor
2021-03-31 17:22:08.571594-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Shutting down DUM thread ...
2021-03-31 17:22:09.427089-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Shutting down SIP stack thread ...
2021-03-31 17:22:09.428954-0700 ARKit-Twilio-Sandbox[9039:8452571] INFO:Twilio:[Signaling](0x16dae3000): RESIP::SIP: Shutting down stack thread
2021-03-31 17:22:09.430830-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): Deleting previously cached listen set ids
2021-03-31 17:22:09.431293-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): virtual twilio::voice::AsyncDnsResolver::~AsyncDnsResolver()
2021-03-31 17:22:09.431751-0700 ARKit-Twilio-Sandbox[9039:8452563] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281131700): virtual twilio::voice::AsyncDnsResolver::~AsyncDnsResolver()
2021-03-31 17:22:09.435847-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Completing pending and in-progress AsyncInvoker calls.
2021-03-31 17:22:09.437203-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): ~NetworkManager
2021-03-31 17:22:09.438007-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): -[TVOMediaFactory dealloc]
2021-03-31 17:22:09.438411-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): ~CallImpl
2021-03-31 17:22:09.438786-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): ~ListenerImpl
2021-03-31 17:22:09.439034-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Clearing pending calls from async_invoker.
2021-03-31 17:22:09.439272-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Waiting for any in-progress calls in async_invoker to complete.
2021-03-31 17:22:09.443537-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): ~RtcMonitor
2021-03-31 17:22:09.447212-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): AppleReachability::~AppleReachability()
2021-03-31 17:22:09.448284-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Core](0x10317b8c0): ~AudioMediaFactoryImpl
incoming!
2021-03-31 17:22:10.486517-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call create
2021-03-31 17:22:10.487137-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Creating peer connection signaling thread ...
2021-03-31 17:22:10.488043-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Creating peer connection worker thread ...
2021-03-31 17:22:10.488671-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Creating peer connection networking thread ...
2021-03-31 17:22:10.489343-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Creating media engine ...
2021-03-31 17:22:10.491867-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Done creating media engine.
2021-03-31 17:22:10.493131-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Creating peer connection factory ...
2021-03-31 17:22:10.495932-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Done creating peer connection factory.
2021-03-31 17:22:10.496918-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call handleMessage
2021-03-31 17:22:10.497247-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Creating dedicated notifier queue ...
2021-03-31 17:22:10.497932-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: incoming
2021-03-31 17:22:10.498022-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Selected edge - roaming Selected region - gll
2021-03-31 17:22:10.499079-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Initializing SIP stack ...
2021-03-31 17:22:10.512126-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Adding IPv4/IPv6 TLS transports ...
2021-03-31 17:22:10.512841-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Signaling](Thread 0x0x1074054f0): RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]
2021-03-31 17:22:10.513774-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Signaling](Thread 0x0x1074054f0): RESIP::TRANSPORT: Creating TLS transport for domain interface= port=0
2021-03-31 17:22:10.514817-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Signaling](Thread 0x0x1074054f0): RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]
2021-03-31 17:22:10.515368-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Signaling](Thread 0x0x1074054f0): RESIP::TRANSPORT: Creating TLS transport for domain interface= port=0
2021-03-31 17:22:10.516286-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Initializing DUM ...
2021-03-31 17:22:10.516697-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Starting SIP stack thread ...
2021-03-31 17:22:10.517343-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Starting DUM thread ...
2021-03-31 17:22:10.517853-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getSignalingThread
2021-03-31 17:22:10.518400-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getNetworkingThread
2021-03-31 17:22:10.518630-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): initialize
2021-03-31 17:22:10.519257-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Starting the Network Manager
2021-03-31 17:22:10.519831-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): AppleReachability::AppleReachability()
2021-03-31 17:22:10.520679-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Creating zeroAddrReachability
2021-03-31 17:22:10.523675-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:10.525168-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): listen
2021-03-31 17:22:10.525436-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:10.526134-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getTo
2021-03-31 17:22:10.526550-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getCallSid
2021-03-31 17:22:10.527299-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:10.527642-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getTo
2021-03-31 17:22:10.528028-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getCallSid
2021-03-31 17:22:10.528458-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): ListenerImpl
2021-03-31 17:22:10.529005-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Started listen no-op timer for 39994 ms
2021-03-31 17:22:10.529272-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): twilio::voice::AsyncDnsResolver::AsyncDnsResolver(std::weak_ptr<DnsResolverObserver>)
2021-03-31 17:22:10.529325-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: listen
2021-03-31 17:22:10.529399-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AsyncDnsResolver resolving hostname = chunderm.gll.twilio.com
2021-03-31 17:22:10.530724-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getCallSid
2021-03-31 17:22:10.531666-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getTo
2021-03-31 17:22:10.532416-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AsyncDnsResolver Resolved ip_addr = 54.85.119.44, error = 0
2021-03-31 17:22:10.532498-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): virtual void twilio::voice::SipClient::onResolveResult(twilio::voice::AsyncDnsResolver *, std::string, int)
2021-03-31 17:22:10.532549-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getCallerInfo
2021-03-31 17:22:10.532579-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): onResolveResult for listen, hostname = sip:chunderm.gll.twilio.com:443;transport=tls, ip_addr = 54.85.119.44
2021-03-31 17:22:10.532645-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): New SipCall ..., this=0x1078f5c00, handle = 1
2021-03-31 17:22:10.532709-0700 ARKit-Twilio-Sandbox[9039:8452704] INFO:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Setting outbound proxy to: sip:54.85.119.44:443;transport=tls
2021-03-31 17:22:10.533038-0700 ARKit-Twilio-Sandbox[9039:8452707] DEBUG:Twilio:[Core](0x16d79b000): Socket created in resip/stack/TcpBaseTransport.cxx at line 223, transport: 1, socket: 27
2021-03-31 17:22:10.533266-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::TRANSPORT: Connection::Connection: new connection created to who: [ V4 54.85.119.44:443 TLS target domain=54.85.119.44 mFlowKey=27 ]
2021-03-31 17:22:10.533378-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::TRANSPORT: Creating TLS connection for domain [ V4 54.85.119.44:443 TLS target domain=54.85.119.44 mFlowKey=0 ] on 27
2021-03-31 17:22:10.534937-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): isVerified
2021-03-31 17:22:10.535159-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:10.535944-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call createAudioSource
2021-03-31 17:22:10.536476-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call createAudioTrack
2021-03-31 17:22:10.536938-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Adding audio track ...
2021-03-31 17:22:10.622586-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::TRANSPORT: TLS handshake starting (client mode)
2021-03-31 17:22:10.624006-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::TRANSPORT: TLS connected
2021-03-31 17:22:10.811575-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::TRANSPORT: TLS connected
2021-03-31 17:22:10.811690-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::TRANSPORT: TLS sessions set up with TLSv1.2 TLSv1/SSLv3 ECDHE-RSA-AES128-GCM-SHA256
2021-03-31 17:22:10.811773-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::SIP: subjectAltName of TLS session cert contains DNS <*.twilio.com>
2021-03-31 17:22:10.811832-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::SIP: subjectAltName of TLS session cert contains DNS <twilio.com>
2021-03-31 17:22:10.812009-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::TRANSPORT: TLS handshake done for peer *.twilio.com, twilio.com
2021-03-31 17:22:10.903620-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 100 tid=5e7a0578bd62af43 cseq=1 INVITE / 1 from(wire)
2021-03-31 17:22:10.913404-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 180 tid=5e7a0578bd62af43 cseq=1 INVITE contact=172.25.73.x:10193 / 1 from(wire)
2021-03-31 17:22:10.913572-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Transition UAC_Start -> UAC_Early
2021-03-31 17:22:10.913657-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 1
2021-03-31 17:22:10.913724-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 1 to SipCall
2021-03-31 17:22:10.914082-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onNewSession:
SIP/2.0 180 Ringing
Via: SIP/2.0/TLS 10.1.10.x;received=98.234.28.232;branch=z9hG4bK-524287-1---5e7a0578bd62af43;rport=65040
Record-Route: <sip:172.18.17.x:10193;r2=on;transport=udp;ftag=2c1e0d8e;lr>
Record-Route: <sip:54.85.119.44:443;r2=on;transport=tls;ftag=2c1e0d8e;lr>
Contact: <sip:172.25.73.x:10193>
To: <sip:chunderm.gll.twilio.com:443;transport=tls>;tag=16431140_6772d868_246088f0-3db4-4ff3-84e0-422afcbfdeda
From: <sip:VoiceSDK@chunderm.gll.twilio.com>;tag=2c1e0d8e
Call-ID: CESkWi_OljTxxZtTz92LXQ..
CSeq: 1 INVITE
Server: Twilio
X-Twilio-CallSid: CA3717478402dc6c0a52e09f51af09f548
X-Twilio-EdgeHost: ec2-54-85-119-44.compute-1.amazonaws.com
X-Twilio-EdgeRegion: us1
X-Twilio-Zone: US_EAST_VIRGINIA
Content-Length: 0
2021-03-31 17:22:10.914187-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onNewCall
2021-03-31 17:22:10.914278-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: UAC_Early: startStaleCallTimer
2021-03-31 17:22:10.914342-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 1
2021-03-31 17:22:10.914402-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 1 to SipCall
2021-03-31 17:22:10.914755-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onProvisional:
SIP/2.0 180 Ringing
Via: SIP/2.0/TLS 10.1.10.x;received=98.234.28.232;branch=z9hG4bK-524287-1---5e7a0578bd62af43;rport=65040
Record-Route: <sip:172.18.17.x:10193;r2=on;transport=udp;ftag=2c1e0d8e;lr>
Record-Route: <sip:54.85.119.44:443;r2=on;transport=tls;ftag=2c1e0d8e;lr>
Contact: <sip:172.25.73.x:10193>
To: <sip:chunderm.gll.twilio.com:443;transport=tls>;tag=16431140_6772d868_246088f0-3db4-4ff3-84e0-422afcbfdeda
From: <sip:VoiceSDK@chunderm.gll.twilio.com>;tag=2c1e0d8e
Call-ID: CESkWi_OljTxxZtTz92LXQ..
CSeq: 1 INVITE
Server: Twilio
X-Twilio-CallSid: CA3717478402dc6c0a52e09f51af09f548
X-Twilio-EdgeHost: ec2-54-85-119-44.compute-1.amazonaws.com
X-Twilio-EdgeRegion: us1
X-Twilio-Zone: US_EAST_VIRGINIA
Content-Length: 0
2021-03-31 17:22:10.914831-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onRinging
2021-03-31 17:22:10.914995-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AppleReachability started listening to the address pair on socket -1 27.
2021-03-31 17:22:10.915294-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AppleReachability::onConnectionEstablished, socket_fd = 27
2021-03-31 17:22:10.916211-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AppleReachability started listening on address pair on 27 socket
2021-03-31 17:22:11.002524-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: listening
2021-03-31 17:22:11.006046-0700 ARKit-Twilio-Sandbox[9039:8452379] AVAudioSession setAggregatedIOPreference:error: Error Domain=NSOSStatusErrorDomain Code=560030580 "(null)"
2021-03-31 17:22:11.614314-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getFrom
2021-03-31 17:22:11.615275-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getTo
2021-03-31 17:22:11.616240-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call accept
2021-03-31 17:22:11.617573-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getNetworkingThread
2021-03-31 17:22:11.618764-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): NetworkManager
2021-03-31 17:22:11.619574-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): accept
2021-03-31 17:22:11.620558-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): SipClient::disconnect
2021-03-31 17:22:11.620651-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): virtual void twilio::voice::SipCall::DisconnectCommand::executeCommand()
2021-03-31 17:22:11.620789-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: UAC_Early: cancel
2021-03-31 17:22:11.620837-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: UAC_Early: startCancelTimer
2021-03-31 17:22:11.620883-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Transition UAC_Early -> UAC_Cancelled
2021-03-31 17:22:11.622269-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call getWebRtcTrack
2021-03-31 17:22:11.624691-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call RTCIceServers
2021-03-31 17:22:11.626088-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): API Call RTCIceServer
2021-03-31 17:22:11.626607-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Creating peer connection ...
2021-03-31 17:22:11.627694-0700 ARKit-Twilio-Sandbox[9039:8452379] INFO:Twilio:[Core](Thread 0x0x1074054f0): Adding local stream to peer connection ...
2021-03-31 17:22:11.628567-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): Create initial local offer
2021-03-31 17:22:11.629148-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): onCreateSessionLocalDescription
2021-03-31 17:22:11.629514-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnSignalingChange
2021-03-31 17:22:11.629570-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Signaling state: have-local-offer
2021-03-31 17:22:11.629704-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received signaling-state event: have-local-offer
2021-03-31 17:22:11.630141-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): onSetSessionLocalDescription
2021-03-31 17:22:11.630273-0700 ARKit-Twilio-Sandbox[9039:8452706] DEBUG:Twilio:[Core](MediaFactoryImpl::networking 0x0x281111380): GetNetworks
2021-03-31 17:22:11.630336-0700 ARKit-Twilio-Sandbox[9039:8452706] DEBUG:Twilio:[Core](MediaFactoryImpl::networking 0x0x281111380): GetNetworks
2021-03-31 17:22:11.631142-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Local SDP - v=0
o=- 260387055006348785 2 IN IP4 127.0.0.x
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS 39eA9a07e2b85EA7DA06aaFdf540D4F4
m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:aAtX
a=ice-pwd:Q14PB7J9YTURCT1PQsa+4/dT
a=ice-options:trickle
a=fingerprint:sha-256 72:BD:F1:0C:4F:74:AD:48:EB:D3:C7:32:A0:CE:A9:03:27:4E:BB:64:9E:DF:B7:DE:BB:9A:98:20:54:A6:3B:62
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=sendrecv
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:112 telephone-event/32000
a=rtpmap:113 telephone-event/16000
a=rtpmap:126 telephone-event/8000
a=ssrc:1047450204 cname:Ca+lsp4ZmGwQH2pR
a=ssrc:1047450204 msid:39eA9a07e2b85EA7DA06aaFdf540D4F4 7ea0b8DBf8C91de292BFdeAFF5FCab99
a=ssrc:1047450204 mslabel:39eA9a07e2b85EA7DA06aaFdf540D4F4
a=ssrc:1047450204 label:7ea0b8DBf8C91de292BFdeAFF5FCab99
2021-03-31 17:22:11.633319-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): twilio::voice::AsyncDnsResolver::AsyncDnsResolver(std::weak_ptr<DnsResolverObserver>)
2021-03-31 17:22:11.633362-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AsyncDnsResolver resolving hostname = chunderm.gll.twilio.com
2021-03-31 17:22:11.633443-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received ICE event.
Group: ice-gathering-state
Name: gathering
2021-03-31 17:22:11.633467-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Gathered ICE candidate: candidate:949675038 1 udp 2122262783 2603:3024:e3d:e000:ccdb:7711:9e1b:4360 49672 typ host generation 0 ufrag aAtX network-id 2 network-cost 10, id: g2T+8PXI
2021-03-31 17:22:11.633556-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Gathered ICE candidate: candidate:2055211565 1 udp 2122194687 10.1.10.x 56462 typ host generation 0 ufrag aAtX network-id 1 network-cost 10, id: FzDPl4po
2021-03-31 17:22:11.634631-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Gathered ICE candidate: candidate:733621180 1 udp 2122131711 2607:fb90:30d8:d61f:183f:18a4:cb82:abab 61029 typ host generation 0 ufrag aAtX network-id 10 network-cost 900, id: wmQ9pnkq
2021-03-31 17:22:11.636332-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Gathered ICE candidate: candidate:2257706897 1 udp 2122066175 2607:fc20:30eb:fa03:3546:65b3:8240:5a69 51509 typ host generation 0 ufrag aAtX network-id 11 network-cost 900, id: f9gp18ov
2021-03-31 17:22:11.637191-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Gathered ICE candidate: candidate:2667736941 1 udp 2121998079 192.0.0.1 59965 typ host generation 0 ufrag aAtX network-id 9 network-cost 900, id: TkaT3+JD
2021-03-31 17:22:11.638633-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Gathered ICE candidate: candidate:2412779662 1 udp 2121935103 2607:fc20:30eb:fa03:32:3dbe:e63e:1dfc 52189 typ host generation 0 ufrag aAtX network-id 3 network-cost 50, id: CNU4iBJO
2021-03-31 17:22:11.642664-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Gathered ICE candidate: candidate:2412779662 1 udp 2121869567 2607:fc20:30eb:fa03:32:3dbe:e63e:1dfc 58178 typ host generation 0 ufrag aAtX network-id 4 network-cost 50, id: 0zp2D4Bc
2021-03-31 17:22:11.645375-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: accepted-by-local
2021-03-31 17:22:11.645427-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.645469-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.646801-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Platform](Thread 0x0x1074054f0): -[TVOCallInvite dealloc]
2021-03-31 17:22:11.647490-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Platform](Thread 0x0x1074054f0): -[TVOCallerInfo dealloc]
2021-03-31 17:22:11.648168-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): ~CallInviteImpl
2021-03-31 17:22:11.648806-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Core](Thread 0x0x1074054f0): ~CallerInfoImpl
2021-03-31 17:22:11.649870-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Platform](Thread 0x0x1074054f0): -[TVOLocalAudioTrack dealloc]
2021-03-31 17:22:11.650696-0700 ARKit-Twilio-Sandbox[9039:8452379] DEBUG:Twilio:[Platform](Thread 0x0x1074054f0): -[TVOAudioTrack dealloc]
2021-03-31 17:22:11.653234-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AsyncDnsResolver Resolved ip_addr = 54.85.119.44, error = 0
2021-03-31 17:22:11.653280-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): virtual void twilio::voice::SipClient::onResolveResult(twilio::voice::AsyncDnsResolver *, std::string, int)
2021-03-31 17:22:11.653333-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): onResolveResult for connect, hostname = sip:chunderm.gll.twilio.com:443;transport=tls, ip_addr = 54.85.119.44
2021-03-31 17:22:11.653369-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): New SipCall ..., this=0x10805e400, handle = 4
2021-03-31 17:22:11.653401-0700 ARKit-Twilio-Sandbox[9039:8452704] INFO:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Setting outbound proxy to: sip:54.85.119.44:443;transport=tls
2021-03-31 17:22:11.693471-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Gathered ICE candidate: candidate:2577501377 1 udp 1685987071 98.234.28.232 56462 typ srflx raddr 10.1.10.x rport 56462 generation 0 ufrag aAtX network-id 1 network-cost 10, id: IabRqkdj
2021-03-31 17:22:11.693639-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.693681-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.705110-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 200 tid=5e7a0578bd62af43 cseq=1 CANCEL / 1 from(wire)
2021-03-31 17:22:11.706228-0700 ARKit-Twilio-Sandbox[9039:8452707] WARN:Twilio:[Signaling](0x16d79b000): RESIP::TRANSPORT: Can't find matching transport [ V4 10.1.10.x:0 TLS target domain=unspecified mFlowKey=0 ]
2021-03-31 17:22:11.706401-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 487 tid=5e7a0578bd62af43 cseq=1 INVITE contact=172.25.73.x:10193 / 1 from(wire)
2021-03-31 17:22:11.706475-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Transition UAC_Cancelled -> InviteSession::Terminated
2021-03-31 17:22:11.706509-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 1
2021-03-31 17:22:11.706585-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 1 to SipCall
2021-03-31 17:22:11.706621-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onTerminated: reason 5, this=0x1078f5c00
2021-03-31 17:22:11.706659-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onTerminated
2021-03-31 17:22:11.707028-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): raiseCancelCallInvite
2021-03-31 17:22:11.707046-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): ~SipCall(): 0x1078f5c00
2021-03-31 17:22:11.718055-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Gathered ICE candidate: candidate:3717173733 1 udp 1685790463 172.56.39.224 47320 typ srflx raddr 192.0.0.1 rport 59965 generation 0 ufrag aAtX network-id 9 network-cost 900, id: WMGFFy4K
2021-03-31 17:22:11.718214-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.718256-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.736290-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received ICE event.
Group: ice-gathering-state
Name: complete
2021-03-31 17:22:11.736357-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.736398-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.737482-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 100 tid=6cbc506bbc345c9b cseq=1 INVITE / 1 from(wire)
2021-03-31 17:22:11.778616-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipResp: 200 tid=6cbc506bbc345c9b cseq=1 INVITE contact=172.25.63.x:10193 / 1 from(wire)
2021-03-31 17:22:11.778785-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Transition UAC_Start -> InviteSession::Connected
2021-03-31 17:22:11.778930-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Sending SipReq: ACK 172.25.63.x:10193 tid=33ecbf787516ace2 cseq=1 ACK contact=VoiceSDK / 1 from(tu)
2021-03-31 17:22:11.779033-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 4
2021-03-31 17:22:11.779076-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 4 to SipCall
2021-03-31 17:22:11.779720-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onNewSession:
SIP/2.0 200 OK
Via: SIP/2.0/TLS 10.1.10.x;received=98.234.28.232;branch=z9hG4bK-524287-1---6cbc506bbc345c9b;rport=65040
Record-Route: <sip:172.18.17.x:10193;r2=on;transport=udp;ftag=bc18feb4;lr>
Record-Route: <sip:54.85.119.44:443;r2=on;transport=tls;ftag=bc18feb4;lr>
Contact: <sip:172.25.63.x:10193>
To: <sip:chunderm.gll.twilio.com:443;transport=tls>;tag=38718390_6772d868_3ca9ba90-25df-4f7b-8279-b916f8c2ff28
From: <sip:VoiceSDK@chunderm.gll.twilio.com>;tag=bc18feb4
Call-ID: VVaIoOScR0A1_9aRSr5qjw..
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE
Content-Type: application/sdp
Server: Twilio
X-Twilio-CallSid: CA3717478402dc6c0a52e09f51af09f548
X-Twilio-EdgeHost: ec2-54-85-119-44.compute-1.amazonaws.com
X-Twilio-EdgeRegion: us1
X-Twilio-Zone: US_EAST_VIRGINIA
Content-Length: 1042
v=0
o=root 280559275 280559275 IN IP4 172.18.148.x
s=Twilio Media Gateway
c=IN IP4 34.203.251.4
t=0 0
a=group:BUNDLE audio
a=ice-lite
m=audio 16282 RTP/SAVPF 111 0 126
a=rtpmap:111 opus/48000/2
a=rtpmap:0 PCMU/8000
a=rtpmap:126 telephone-event/8000
a=fmtp:126 0-16
a=ptime:20
a=maxptime:20
a=ice-ufrag:6910ea1e2a3ad1b64d32e66212807838
a=ice-pwd:04eebd9025d33bfb69be504b18d464fa
a=candidate:H22cbfb04 1 UDP 2130706431 34.203.251.4 16282 typ host
a=end-of-candidates
a=connection:new
a=setup:active
a=fingerprint:sha-256 A5:B0:63:0E:C2:BB:CE:CB:BA:A5:0A:AA:FD:EB:58:CC:67:AA:49:E5:33:8F:80:EA:D8:CC:AC:B0:50:E8:15:A2
a=mid:audio
a=msid:2fbc71d8-f715-4dba-be1f-954efbd09665 47967d99-0827-45c0-8365-165bc32b0a9d
a=ssrc:1479287219 msid:2fbc71d8-f715-4dba-be1f-954efbd09665 47967d99-0827-45c0-8365-165bc32b0a9d
a=ssrc:1479287219 mslabel:2fbc71d8-f715-4dba-be1f-954efbd09665
a=ssrc:1479287219 label:47967d99-0827-45c0-8365-165bc32b0a9d
a=rtcp-mux
a=ssrc:1479287219 cname:64887f7c-dc85-4777-bc14-240429c0f619
a=sendrecv
2021-03-31 17:22:11.782941-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onNewCall
2021-03-31 17:22:11.782995-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 4
2021-03-31 17:22:11.783057-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.783072-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 4 to SipCall
2021-03-31 17:22:11.783100-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.783780-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onAnswer:
SIP/2.0 200 OK
Via: SIP/2.0/TLS 10.1.10.x;received=98.234.28.232;branch=z9hG4bK-524287-1---6cbc506bbc345c9b;rport=65040
Record-Route: <sip:172.18.17.x:10193;r2=on;transport=udp;ftag=bc18feb4;lr>
Record-Route: <sip:54.85.119.44:443;r2=on;transport=tls;ftag=bc18feb4;lr>
Contact: <sip:172.25.63.x:10193>
To: <sip:chunderm.gll.twilio.com:443;transport=tls>;tag=38718390_6772d868_3ca9ba90-25df-4f7b-8279-b916f8c2ff28
From: <sip:VoiceSDK@chunderm.gll.twilio.com>;tag=bc18feb4
Call-ID: VVaIoOScR0A1_9aRSr5qjw..
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE
Content-Type: application/sdp
Server: Twilio
X-Twilio-CallSid: CA3717478402dc6c0a52e09f51af09f548
X-Twilio-EdgeHost: ec2-54-85-119-44.compute-1.amazonaws.com
X-Twilio-EdgeRegion: us1
X-Twilio-Zone: US_EAST_VIRGINIA
Content-Length: 1042
v=0
o=root 280559275 280559275 IN IP4 172.18.148.x
s=Twilio Media Gateway
c=IN IP4 34.203.251.4
t=0 0
a=group:BUNDLE audio
a=ice-lite
m=audio 16282 RTP/SAVPF 111 0 126
a=rtpmap:111 opus/48000/2
a=rtpmap:0 PCMU/8000
a=rtpmap:126 telephone-event/8000
a=fmtp:126 0-16
a=ptime:20
a=maxptime:20
a=ice-ufrag:6910ea1e2a3ad1b64d32e66212807838
a=ice-pwd:04eebd9025d33bfb69be504b18d464fa
a=candidate:H22cbfb04 1 UDP 2130706431 34.203.251.4 16282 typ host
a=end-of-candidates
a=connection:new
a=setup:active
a=fingerprint:sha-256 A5:B0:63:0E:C2:BB:CE:CB:BA:A5:0A:AA:FD:EB:58:CC:67:AA:49:E5:33:8F:80:EA:D8:CC:AC:B0:50:E8:15:A2
a=mid:audio
a=msid:2fbc71d8-f715-4dba-be1f-954efbd09665 47967d99-0827-45c0-8365-165bc32b0a9d
a=ssrc:1479287219 msid:2fbc71d8-f715-4dba-be1f-954efbd09665 47967d99-0827-45c0-8365-165bc32b0a9d
a=ssrc:1479287219 mslabel:2fbc71d8-f715-4dba-be1f-954efbd09665
a=ssrc:1479287219 label:47967d99-0827-45c0-8365-165bc32b0a9d
a=rtcp-mux
a=ssrc:1479287219 cname:64887f7c-dc85-4777-bc14-240429c0f619
a=sendrecv
2021-03-31 17:22:11.785864-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): Notify signaling observer of onAnswer
2021-03-31 17:22:11.785912-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onAnswer
2021-03-31 17:22:11.785960-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 4
2021-03-31 17:22:11.785972-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AppleReachability started listening to the address pair on socket -1 27.
2021-03-31 17:22:11.786002-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 4 to SipCall
2021-03-31 17:22:11.786028-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AppleReachability::onConnectionEstablished, socket_fd = 27
2021-03-31 17:22:11.786463-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): AppleReachability started listening on address pair on 27 socket
2021-03-31 17:22:11.786735-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onConnected:
SIP/2.0 200 OK
Via: SIP/2.0/TLS 10.1.10.x;received=98.234.28.232;branch=z9hG4bK-524287-1---6cbc506bbc345c9b;rport=65040
Record-Route: <sip:172.18.17.x:10193;r2=on;transport=udp;ftag=bc18feb4;lr>
Record-Route: <sip:54.85.119.44:443;r2=on;transport=tls;ftag=bc18feb4;lr>
Contact: <sip:172.25.63.x:10193>
To: <sip:chunderm.gll.twilio.com:443;transport=tls>;tag=38718390_6772d868_3ca9ba90-25df-4f7b-8279-b916f8c2ff28
From: <sip:VoiceSDK@chunderm.gll.twilio.com>;tag=bc18feb4
Call-ID: VVaIoOScR0A1_9aRSr5qjw..
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE
Content-Type: application/sdp
Server: Twilio
X-Twilio-CallSid: CA3717478402dc6c0a52e09f51af09f548
X-Twilio-EdgeHost: ec2-54-85-119-44.compute-1.amazonaws.com
X-Twilio-EdgeRegion: us1
X-Twilio-Zone: US_EAST_VIRGINIA
Content-Length: 1042
v=0
o=root 280559275 280559275 IN IP4 172.18.148.x
s=Twilio Media Gateway
c=IN IP4 34.203.251.4
t=0 0
a=group:BUNDLE audio
a=ice-lite
m=audio 16282 RTP/SAVPF 111 0 126
a=rtpmap:111 opus/48000/2
a=rtpmap:0 PCMU/8000
a=rtpmap:126 telephone-event/8000
a=fmtp:126 0-16
a=ptime:20
a=maxptime:20
a=ice-ufrag:6910ea1e2a3ad1b64d32e66212807838
a=ice-pwd:04eebd9025d33bfb69be504b18d464fa
a=candidate:H22cbfb04 1 UDP 2130706431 34.203.251.4 16282 typ host
a=end-of-candidates
a=connection:new
a=setup:active
a=fingerprint:sha-256 A5:B0:63:0E:C2:BB:CE:CB:BA:A5:0A:AA:FD:EB:58:CC:67:AA:49:E5:33:8F:80:EA:D8:CC:AC:B0:50:E8:15:A2
a=mid:audio
a=msid:2fbc71d8-f715-4dba-be1f-954efbd09665 47967d99-0827-45c0-8365-165bc32b0a9d
a=ssrc:1479287219 msid:2fbc71d8-f715-4dba-be1f-954efbd09665 47967d99-0827-45c0-8365-165bc32b0a9d
a=ssrc:1479287219 mslabel:2fbc71d8-f715-4dba-be1f-954efbd09665
a=ssrc:1479287219 label:47967d99-0827-45c0-8365-165bc32b0a9d
a=rtcp-mux
a=ssrc:1479287219 cname:64887f7c-dc85-4777-bc14-240429c0f619
a=sendrecv
2021-03-31 17:22:11.786785-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Peer connection state = new, Ice connection state = new
2021-03-31 17:22:11.786824-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): setRemoteDescription
2021-03-31 17:22:11.789108-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.789357-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.790134-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnSignalingChange
2021-03-31 17:22:11.792087-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Signaling state: stable
2021-03-31 17:22:11.792804-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received signaling-state event: stable
2021-03-31 17:22:11.792898-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:11.793017-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.092926-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnIceConnectionChange
2021-03-31 17:22:12.093001-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Call State: Connecting ICE connection state: checking
2021-03-31 17:22:12.093088-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received ICE event.
Group: ice-connection-state
Name: checking
2021-03-31 17:22:12.093156-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.093205-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.109647-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnConnectionChange
2021-03-31 17:22:12.109662-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: accepted-by-remote
2021-03-31 17:22:12.109723-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Call State: Connecting PeerConnection state: connecting
2021-03-31 17:22:12.109734-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.109775-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): onSetSessionRemoteDescription
2021-03-31 17:22:12.109788-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.109823-0700 ARKit-Twilio-Sandbox[9039:8452704] INFO:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Looking up: 34.203.251.4
2021-03-31 17:22:12.110513-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received Peer Connection State event.
Group: pc-connection-state
Name: connecting
2021-03-31 17:22:12.110667-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.110824-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.112610-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Address family: 2: 34.203.251.4
2021-03-31 17:22:12.112747-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Address family: 2: 34.203.251.4
2021-03-31 17:22:12.181280-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Selected Local ICE candidate: Cand[:2577501377:1:udp:1685987071:98.234.28.232:56462:stun:10.1.10.x:56462:aAtX:Q14PB7J9YTURCT1PQsa+4/dT:1:10:0], id: IabRqkdj
2021-03-31 17:22:12.181430-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Selected Remote ICE candidate: Cand[:H22cbfb04:1:udp:2130706431:34.203.251.4:16282:local::0:6910ea1e2a3ad1b64d32e66212807838:04eebd9025d33bfb69be504b18d464fa:0:0:0], id: yH0l0xn1
2021-03-31 17:22:12.181825-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.181922-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.382207-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnConnectionChange
2021-03-31 17:22:12.382567-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Call State: Connecting PeerConnection state: connected
2021-03-31 17:22:12.383063-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received Peer Connection State event.
Group: pc-connection-state
Name: connected
2021-03-31 17:22:12.383477-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.383673-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:12.382791-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): void twilio::voice::CallImpl::handlePeerConnectionStateConnected()
2021-03-31 17:22:12.383936-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Set connected state: kPeerConnectionConnected received
2021-03-31 17:22:12.391573-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Invoking onConnected.
2021-03-31 17:22:12.391554-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): setupNetworkMonitor
2021-03-31 17:22:12.391757-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): setupIceActivityMonitor
2021-03-31 17:22:12.392224-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnIceConnectionChange
2021-03-31 17:22:12.392405-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Call State: Connected ICE connection state: connected
2021-03-31 17:22:12.392538-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnIceConnectionChange
2021-03-31 17:22:12.392644-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Call State: Connected ICE connection state: completed
2021-03-31 17:22:12.393129-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): void twilio::voice::CallImpl::handleIceConnectionCompleted()
2021-03-31 17:22:12.394891-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Starting the media monitor - call getPeerConnectionStats
2021-03-31 17:22:13.301885-0700 ARKit-Twilio-Sandbox[9039:8452338] AVAudioSession setAggregatedIOPreference:error: Error Domain=NSOSStatusErrorDomain Code=560030580 "(null)"
2021-03-31 17:22:14.565620-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: connected
2021-03-31 17:22:14.565714-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.565753-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.566806-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received ICE event.
Group: ice-connection-state
Name: connected
2021-03-31 17:22:14.566850-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.566881-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.567412-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received ICE event.
Group: ice-connection-state
Name: completed
2021-03-31 17:22:14.567580-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.567678-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.568255-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.568327-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.573934-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.573992-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.574249-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:14.574282-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:15.403440-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:15.403738-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.410700-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.410993-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.733801-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Got: SipReq: BYE VoiceSDK@98.234.28.232:65040 tid=0924.dbeb78d6.0 cseq=1 BYE / 1 from(wire)
2021-03-31 17:22:16.734313-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Handling in-dialog request: SipReq: BYE VoiceSDK@98.234.28.232:65040 tid=0924.dbeb78d6.0 cseq=1 BYE / 1 from(wire)
2021-03-31 17:22:16.734979-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Received SipReq: BYE VoiceSDK@98.234.28.232:65040 tid=0924.dbeb78d6.0 cseq=1 BYE / 1 from(wire)
2021-03-31 17:22:16.736213-0700 ARKit-Twilio-Sandbox[9039:8452708] INFO:Twilio:[Signaling](0x16db6f000): RESIP::DUM: Transition InviteSession::Connected -> InviteSession::Terminated
2021-03-31 17:22:16.736461-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): mapToSipCall handle id is 4
2021-03-31 17:22:16.736697-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): casting handle id 4 to SipCall
2021-03-31 17:22:16.736909-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onTerminated: reason 4, this=0x10805e400
2021-03-31 17:22:16.737386-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): onTerminated
2021-03-31 17:22:16.738195-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): void twilio::media::IceConnectionActivityMonitor::stop()
2021-03-31 17:22:16.738195-0700 ARKit-Twilio-Sandbox[9039:8452708] DEBUG:Twilio:[Core](0x16db6f000): ~SipCall(): 0x10805e400
2021-03-31 17:22:16.738276-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Platform](0x10317b8c0): Inside publishConnectionEvent:, event name: disconnected-by-remote
2021-03-31 17:22:16.738455-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.738610-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.745789-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnIceConnectionChange
2021-03-31 17:22:16.746189-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Call State: Disconnected ICE connection state: closed
2021-03-31 17:22:16.746300-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnConnectionChange
2021-03-31 17:22:16.746345-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received ICE event.
Group: ice-connection-state
Name: closed
2021-03-31 17:22:16.746370-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Call State: Disconnected PeerConnection state: closed
2021-03-31 17:22:16.746436-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): OnSignalingChange
2021-03-31 17:22:16.746446-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.746499-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Signaling state: closed
2021-03-31 17:22:16.746519-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.747610-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received Peer Connection State event.
Group: pc-connection-state
Name: closed
2021-03-31 17:22:16.747811-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.748005-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.748993-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): Received signaling-state event: closed
2021-03-31 17:22:16.749239-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.749489-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): API Call getSid
2021-03-31 17:22:16.950035-0700 ARKit-Twilio-Sandbox[9039:8452705] [avas] AVAudioSession_iOS.mm:1149 Deactivating an audio session that has running I/O. All I/O should be stopped or paused prior to deactivating the audio session.
2021-03-31 17:22:16.956598-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Invoking onDisconnected.
2021-03-31 17:22:17.142638-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): dealloc.
2021-03-31 17:22:17.142716-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): willDestroy
2021-03-31 17:22:17.142784-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Stopping the Network Manager
2021-03-31 17:22:17.142837-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): ~NetworkMonitor
2021-03-31 17:22:17.142883-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): void twilio::media::IceConnectionActivityMonitor::stop()
2021-03-31 17:22:17.143094-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Shutting down DUM thread ...
2021-03-31 17:22:17.743604-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Shutting down SIP stack thread ...
2021-03-31 17:22:17.744446-0700 ARKit-Twilio-Sandbox[9039:8452707] INFO:Twilio:[Signaling](0x16d79b000): RESIP::SIP: Shutting down stack thread
2021-03-31 17:22:17.745757-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): Deleting previously cached listen set ids
2021-03-31 17:22:17.746342-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): virtual twilio::voice::AsyncDnsResolver::~AsyncDnsResolver()
2021-03-31 17:22:17.747130-0700 ARKit-Twilio-Sandbox[9039:8452704] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling 0x0x281111380): virtual twilio::voice::AsyncDnsResolver::~AsyncDnsResolver()
2021-03-31 17:22:17.747896-0700 ARKit-Twilio-Sandbox[9039:8452704] WARN:Twilio:[Signaling](MediaFactoryImpl::signaling 0x0x281111380): RESIP::TRANSACTION: On shutdown, there are Client TransactionStates remaining!
2021-03-31 17:22:17.752575-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Completing pending and in-progress AsyncInvoker calls.
2021-03-31 17:22:17.753589-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): ~NetworkManager
2021-03-31 17:22:17.757146-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Platform](0x10317b8c0): -[TVOMediaFactory dealloc]
2021-03-31 17:22:17.757488-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): ~CallImpl
2021-03-31 17:22:17.757685-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): ~ListenerImpl
2021-03-31 17:22:17.757872-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Clearing pending calls from async_invoker.
2021-03-31 17:22:17.758080-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Waiting for any in-progress calls in async_invoker to complete.
2021-03-31 17:22:17.761077-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): ~RtcMonitor
2021-03-31 17:22:17.761438-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): ~IceConnectionActivityMonitor
2021-03-31 17:22:17.761720-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): Waiting for any in-progress calls in async_invoker to complete.
2021-03-31 17:22:17.768450-0700 ARKit-Twilio-Sandbox[9039:8452338] DEBUG:Twilio:[Core](0x10317b8c0): AppleReachability::~AppleReachability()
2021-03-31 17:22:17.769206-0700 ARKit-Twilio-Sandbox[9039:8452338] INFO:Twilio:[Core](0x10317b8c0): ~AudioMediaFactoryImpl
Thanks @danhd123 for providing great details and the SDK log. Sorry that we couldn't get back to you sooner but I have created an internal ticket for tracking this issue. We will take a deeper look and get back to you.
Hi @danhd123
Sorry we couldn't get back to you sooner. Although we haven't got a solution but still would like to provide an update:
I have tried adding ARKit and the ARSessionDelegate
& ARSCNViewDelegate
protocol methods in the quickstart, as well as the ARConfiguration
setup that you provided, but since there is no audio data rendering sample code provided I am not sure how to observe the audio issues you mentioned. All I did was connect a few Voice SDK calls and observed both the call audio and debugging messages and all worked well. Would it be possible for you to share more detail about how audio data (from ARKit callback) is processed and rendered in your app so we can reproduce in our dev env or a quick video clip showing the audio issue?
I noticed that in CallManager.swift
CallKit is not used to integrate with the Voice SDK calls. I am assuming this an intentional decision but wondering if the audio issue would be any different with CallKit. Also the default audio device of the Voice iOS SDK configures AVAudioSession with the setup that works best for the VoIP call purposes. It might also be useful to check if the application needs to "reset" the AVAudioSession configuration to best fit the ARKit usage once the Voice SDK call is done.
Best, -bobie
Sure. _handleAudioSampleBufferOutput
calls, among other things:
private func _process(_ sampleBuffer: CMSampleBuffer) -> Float {
var buffer: CMBlockBuffer? = nil
let convenianceBuffer = AudioBuffer(mNumberChannels: 1, mDataByteSize: 0, mData: nil)
var audioBufferList = AudioBufferList(mNumberBuffers: 1,
mBuffers: convenianceBuffer)
CMSampleBufferGetAudioBufferListWithRetainedBlockBuffer(
sampleBuffer,
bufferListSizeNeededOut: nil,
bufferListOut: &audioBufferList,
bufferListSize: MemoryLayout<AudioBufferList>.size(ofValue: audioBufferList),
blockBufferAllocator: nil,
blockBufferMemoryAllocator: nil,
flags: UInt32(kCMSampleBufferFlag_AudioBufferList_Assure16ByteAlignment),
blockBufferOut: &buffer
)
let abl = UnsafeMutableAudioBufferListPointer(&audioBufferList)
guard let firstBuffer = abl.first else { return 0 }
let originRawPtr = firstBuffer.mData
let ptrDataSize = Int(firstBuffer.mDataByteSize)
// From raw pointer to typed Int16 pointer
let buffPtrInt16 = originRawPtr?.bindMemory(to: Int16.self, capacity: ptrDataSize)
// From pointer typed Int16 to pointer of [Int16]
// So we can iterate on it simply
let unsafePtrByteSize = ptrDataSize/Int16.bitWidth
let samples = UnsafeMutableBufferPointer<Int16>(start: buffPtrInt16,
count: unsafePtrByteSize)
// Average of each sample squared, then root squared
let sumOfSquaredSamples = samples.map(Float.init).reduce(0) { $0 + $1*$1 }
let averageOfSomething = sqrt(sumOfSquaredSamples / Float(samples.count))
return averageOfSomething
}
This gets the average audio power of the sample buffer. Monitoring the output of this we have a metering view, the core of which looks like this:
import UIKit
private let kMaxPower: Float = 400
class AudioMeterView: UIView {
var power: Float? { didSet { _didSetPower() } }
init(_ audioMeter: AudioMeter) {
self.audioMeter = audioMeter
super.init(frame: .zero)
backgroundColor = .clear
}
// MARK: - UIView
override func draw(_ rect: CGRect) {
super.draw(rect)
let color: UIColor
let fillFrame: CGRect
let effectivePower: Float?
if let power = power, power > 1 {
effectivePower = power
} else {
effectivePower = nil
}
if let effectivePower = effectivePower {
let fraction = simd_clamp(effectivePower / kMaxPower, 0, 1).ft_CGFloat
let fillFrameHeight = rect.height * fraction
let fillFrameY = rect.height - fillFrameHeight
fillFrame = CGRect(x: 0, y: fillFrameY, width: rect.width, height: fillFrameHeight)
let red: CGFloat = 1 - fraction
color = UIColor(red: red, green: 1, blue: 0, alpha: 1)
} else {
color = .gray
fillFrame = rect
}
let fillPath = UIBezierPath(rect: fillFrame.insetBy(dx: 3, dy: 3))
color.setFill()
fillPath.fill()
let borderFrame = rect.insetBy(dx: 1, dy: 1)
let borderPath = UIBezierPath(rect: borderFrame)
color.setStroke()
borderPath.stroke()
}
private func _didSetPower() {
setNeedsDisplay()
}
}
We are also sometimes recording audio and video (using a similar video recorder to RosyWriter, but in Swift). In these videos, there is no audio once the call starts, and it doesn't resume afterwards.
You are correct that we are not using CallKit, and it would not be possible to use CallKit in this application. In production, the app runs in Autonomous Single App Mode, but that doesn't seem to have anything to do with the bug, as it happens in development, where the phones are not locked to a single application.
Hi @danhd123
I've tried the code snippet you provided but we don't seem to observe the audio issue in our environment. I have also reviewed the code snippet in the original issue description and it seems that the application also calls the DefaultAudioDevice.DefaultAVAudioSessionConfigurationBlock()
when the call has ended. Please note that this configuration block sets the sample rate and buffer duration for the Voice SDK's purpose, which might not be optimal for your application use case.
Thanks, that's a good catch. I'll try fixing that, but it may take a few days to get an update on this issue as I'm on another project currently, and not scheduled to go back to this one for a few days.
Description
I've got an ARSession, the configuration for which is an
ARFaceTrackingConfiguration
and has.providesAudioData
set totrue
. When it was set tofalse
, Twilio Voice worked fine, but once it was set totrue
, Twilio breaks.Background: I'm building, in part, an AR video recorder, and I need to record audio and video. Previously, I was getting audio via a separate
AVCaptureSession
(.sessionPreset = .medium
,.usesApplicationAudioSession = true
,.automaticallyConfiguresApplicationAudioSession = true
), but this produces increasing (and unacceptable) desyncs with theARSession
'sCVPixelBuffer
s after a random time (somwhere between a minute and 90 minutes), so I switched to.providesAudioData
, which seems to prevent the desyncs. Problem: this broke Twilio voice calls in a particularly bad way.Once
.providesAudioData
is set totrue
, several things happen when a call is connected.(least importantly during the call, much more important after), connecting the call causes some stuttering in the
ARSCNView
. This continues until the app is restarted.The call causes the
ARSession
to fail with an error that "A required sensor failed." only if I've explicitly touched theAVAudioSession.sharedInstance()
previously, and then somewhat unreliably (~50%). If not, I don't get this error. I'm gettingAVAudioSession setAggregatedIOPreference:error: Error Domain=NSOSStatusErrorDomain Code=560030580 "(null)"
560030580
is the four-char code!act
. I also receive.mediaServicesWereLost
and.mediaServicesWereReset
notifications ~20% of the time. I'm also seeingAVAudioSession
!ini
andwhat
4CC errors occasionally, but I forget under which circumstances those occur, and I don't think I've seen them in my reduced test case app.My sound effects players (eg, for call pickup and hang up), which play fine when
.providesAudioData = false
, don't play. The device outputs popping intermittent audio after some seconds, possibly before, definitely after the call has been terminated on the remote side, maybe 1/10th of a second at a time with gaps of maybe 1/4 second, starting during the call and long after the call has been terminated. Sometimes it comes from the receiver, sometimes from the speaker. Occasionally it sounds like it might have been sounds that were present during the call, like it's ultra-slowly draining an audio buffer. This is the real problem. Worse, it's not 100% reproducible. In attempting to reduce to a test case, I actually had ~5 launches in a row where everything worked as expected. Then it started behaving as at had previously.After the call and the audio is played out, the audio sample buffers delivered by ARKit are all empty (no audio data), and about half the time ARKit fails to deliver any more audio sample buffers at all, even after pausing and rerunning the session with the original configuration.
The
(TVO)CallDelegate
methodcallDidDisconnect(call: error:)
never gets called, with or without an error. I'm totally at a loss for this one. It's wrecking my state machine and causing further downstream problems if I try to make another call to the same device. This list of things going wrong was much longer and weirder until I realized this was happening, so I removed everything downstream of this.After the call is terminated, but before the audio is finished playing, I'll get two of these logs (log level set to
.info
):ERROR:Twilio:[Signaling](0x16bbef000): RESIP::TRANSPORT: Got TLS read ret=0 error=5 error:00000005:invalid library (0):OPENSSL_internal:Diffie-Hellman routines - intermediate certificates may be missing from local PEM file
. I suspect this is not related, but I wanted to be thorough.Steps to Reproduce
ARFaceTrackingConfiguration
with.providesAudioData = true
.callInvite.accept(options: AcceptOptions(callInvite: callInvite), delegate: self)
Code
ARViewController.swift:
CallManager.swift, important bits:
Expected Behavior
I expect the call to connect and eventually end normally (with all attendant delegate callbacks), to be able to hear the other side of the connection in real time, and for my ARSession not to start stuttering every couple seconds for the remainder of the app's lifetime. A nice to have would be for the microphone input to also continue to be delivered to my
ARSessionDelegate
during the call, but it definitely should resume delivery afterward. If this isn't possible, pointing me at a reference implementation of a non-default(TVO)AudioDevice
that I can pipe audioCMSampleBuffer
s to and play received audio through the speaker for the duration of the call would be nice as a workaround. 🙂 I'm trying to port the relevant code from the AudioDeviceExample project to Swift and adapt it to takeCMSampleBuffer
s, but it's far from straightforward. Any help would be much appreciated.Actual Behavior
See Description
Reproduces How Often
Logs
In a sample project with a nearly minimal ARKit view controller, set to .debug
A different run, in my main project with significantly more logging, but also doing more things with the audio session and
MPVolumeView
which could be confounding things. The route changes that keep happening over and over are only observations, not triggered from my code except once the very beginning of the call, AFAICT.Versions
All relevant version information for the issue.
Voice iOS SDK
6.2.2 via CocoaPods
Xcode
Xcode 12.4
iOS Version
iOS 12.4.1, iOS 13.3.1, iOS 14.2
iOS Device
iPhone XR, iPhone 12