I am noticing intermittent ICE negotiation failures. So far tested iOS 10.2/T-mobile, iOS 10.2/Apple’s nat64 and iOS 9.3/Apple’s nat64. The client library outputs: !TURN socket destroy request and proceeds to cancel all the ICE process. Looks like there is work in progress fixing known IPv6 issues, so hopefully this log will help: 11:18:39.999 pjsua_media.c .Call 0: initializing media.. 11:18:39.999 icetp00 ..Creating ICE stream transport with 2 component(s) 11:18:39.999 icetp00 ...Failed creating STUN transport #0 for comp 1: Not found (PJ_ENOTFOUND) 11:18:40.000 icetp00 ...Comp 1/0: host candidate [2607:fb90:284d:4413:90ad:bd11:fd27:9f0a]:52133 (tpid=65) added 11:18:40.000 icetp00 ...Comp 1/1: host candidate [2607:fb90:284d:4413:182a:429a:3342:b7ee]:52133 (tpid=65) added 11:18:40.000 tcprel0x12a171 ...TURN client session created 11:18:40.000 tcprel0x12a171 ...Resolving turn.voxox.com with DNS A 11:18:40.000 tcprel0x12a171 ...State changed Null --> Resolving 11:18:40.000 icetp00 ...TURN client state changed Null --> Resolving 11:18:40.002 tcprel0x12a171 ...Error setting TURN server: gethostbyname() has returned error (PJ_ERESOLVE) 11:18:40.002 tcprel0x12a171 ...State changed Resolving --> Deallocated 11:18:40.002 icetp00 ...TURN client state changed Resolving --> Deallocated 11:18:40.002 icetp00 ....TURN allocation failed: gethostbyname() has returned error (PJ_ERESOLVE) 11:18:40.002 icetp00 .....ICE operation complete (op=0/initialization): gethostbyname() has returned error (PJ_ERESOLVE) 11:18:40.002 tcprel0x12a171 ...Request to shutdown in state Deallocated, cause:0 11:18:40.002 tcprel0x12a171 ...State changed Deallocated --> Destroying 11:18:40.002 icetp00 ...Failed creating TURN transport #0 for comp 1: gethostbyname() has returned error (PJ_ERESOLVE) 11:18:40.002 tcprel0x12a181 ...TURN client session created 11:18:40.002 tcprel0x12a181 ...Resolving turn.voxox.com with DNS A 11:18:40.002 tcprel0x12a181 ...State changed Null --> Resolving 11:18:40.002 icetp00 ...TURN client state changed Null --> Resolving 11:18:40.003 tcprel0x12a181 ...State changed Resolving --> Resolved 11:18:40.003 icetp00 ...TURN client state changed Resolving --> Resolved 11:18:40.003 tcprel0x12a181 ...Connecting to [2607:7700:0:15::451a:b7eb]:443 11:18:40.003 icetp00 ...Comp 1/2: TURN relay candidate (tpid=129) waiting for allocation 11:18:40.003 tcprel0x12a186 ...TURN client session created 11:18:40.003 tcprel0x12a186 ...Resolving turn.voxox.com with DNS A 11:18:40.003 tcprel0x12a186 ...State changed Null --> Resolving 11:18:40.003 icetp00 ...TURN client state changed Null --> Resolving 11:18:40.004 tcprel0x12a186 ...State changed Resolving --> Resolved 11:18:40.004 icetp00 ...TURN client state changed Resolving --> Resolved 11:18:40.004 tcprel0x12a186 ...Connecting to [2607:7700:0:15::451a:b7eb]:443 11:18:40.004 icetp00 ...Comp 1/3: TURN relay candidate (tpid=130) waiting for allocation 11:18:40.004 icetp00 ...Failed creating STUN transport #0 for comp 2: Not found (PJ_ENOTFOUND) 11:18:40.005 icetp00 ...Comp 2/0: host candidate [2607:fb90:284d:4413:90ad:bd11:fd27:9f0a]:56300 (tpid=65) added 11:18:40.005 icetp00 ...Comp 2/1: host candidate [2607:fb90:284d:4413:182a:429a:3342:b7ee]:56300 (tpid=65) added 11:18:40.005 tcprel0x12a18e ...TURN client session created 11:18:40.005 tcprel0x12a18e ...Resolving turn.voxox.com with DNS A 11:18:40.005 tcprel0x12a18e ...State changed Null --> Resolving 11:18:40.005 icetp00 ...TURN client state changed Null --> Resolving 11:18:40.007 tcprel0x12a18e ...Error setting TURN server: gethostbyname() has returned error (PJ_ERESOLVE) 11:18:40.007 tcprel0x12a18e ...State changed Resolving --> Deallocated 11:18:40.007 icetp00 ...TURN client state changed Resolving --> Deallocated 11:18:40.007 icetp00 ....TURN allocation failed: gethostbyname() has returned error (PJ_ERESOLVE) 11:18:40.007 tcprel0x12a18e ...Request to shutdown in state Deallocated, cause:0 11:18:40.007 tcprel0x12a18e ...State changed Deallocated --> Destroying 11:18:40.007 icetp00 ...Failed creating TURN transport #0 for comp 2: gethostbyname() has returned error (PJ_ERESOLVE) 11:18:40.007 tcprel0x12a18f ...TURN client session created 11:18:40.007 tcprel0x12a18f ...Resolving turn.voxox.com with DNS A 11:18:40.007 tcprel0x12a18f ...State changed Null --> Resolving 11:18:40.007 icetp00 ...TURN client state changed Null --> Resolving 11:18:40.008 tcprel0x12a18f ...State changed Resolving --> Resolved 11:18:40.008 icetp00 ...TURN client state changed Resolving --> Resolved 11:18:40.008 tcprel0x12a18f ...Connecting to [2607:7700:0:15::451a:b7eb]:443 11:18:40.008 icetp00 ...Comp 2/2: TURN relay candidate (tpid=129) waiting for allocation 11:18:40.008 tcprel0x12a192 ...TURN client session created 11:18:40.008 tcprel0x12a192 ...Resolving turn.voxox.com with DNS A 11:18:40.008 tcprel0x12a192 ...State changed Null --> Resolving 11:18:40.008 icetp00 ...TURN client state changed Null --> Resolving 11:18:40.009 tcprel0x12a192 ...State changed Resolving --> Resolved 11:18:40.009 icetp00 ...TURN client state changed Resolving --> Resolved 11:18:40.009 tcprel0x12a192 ...Connecting to [2607:7700:0:15::451a:b7eb]:443 11:18:40.009 icetp00 ...Comp 2/3: TURN relay candidate (tpid=130) waiting for allocation 11:18:40.009 icetp00 ...ICE stream transport 0x12a81b828 created 11:18:40.009 pjsua_media.c ..Media index 0 selected for audio call 0 11:18:40.009 tcprel0x12a171 !TURN socket destroy request, ref_cnt=39 11:18:40.009 tcprel0x12a171 TURN session destroy request, ref_cnt=38 11:18:40.009 tcprel0x12a171 STUN session 0x12a17ea28 destroy request, ref_cnt=39 11:18:40.009 icetp00 ICE stream transport 0x12a81b828 destroy request.. 11:18:40.009 stuntp0x1741fa .STUN sock 0x12a88c228 request, ref_cnt=35 11:18:40.009 stuntp0x1741fa .STUN session 0x12a90d828 destroy request, ref_cnt=35 11:18:40.009 stuntp0x1743e6 .STUN sock 0x12a8b0c28 request, ref_cnt=32 11:18:40.009 stuntp0x1743e6 .STUN session 0x12a83b628 destroy request, ref_cnt=32 11:18:40.009 tcprel0x12a181 .Request to shutdown in state Resolved, cause:0 11:18:40.009 tcprel0x12a181 .State changed Resolved --> Destroying 11:18:40.009 icetp00 .TURN client state changed Resolved --> Destroying 11:18:40.009 icetp00 ..Comp 1/2: TURN resolving (tpid=129) failed 11:18:40.009 tcprel0x12a186 .Request to shutdown in state Resolved, cause:0 11:18:40.009 tcprel0x12a186 .State changed Resolved --> Destroying 11:18:40.009 icetp00 .TURN client state changed Resolved --> Destroying 11:18:40.009 icetp00 ..Comp 1/3: TURN resolving (tpid=130) failed 11:18:40.009 stuntp0x1703e1 .STUN sock 0x12a189628 request, ref_cnt=33 11:18:40.009 stuntp0x1703e1 .STUN session 0x12a18a828 destroy request, ref_cnt=33 11:18:40.009 stuntp0x1703e9 .STUN sock 0x12a18b428 request, ref_cnt=30 11:18:40.009 stuntp0x1703e9 .STUN session 0x12a18da28 destroy request, ref_cnt=30 11:18:40.009 tcprel0x12a18f .Request to shutdown in state Resolved, cause:0 11:18:40.009 tcprel0x12a18f .State changed Resolved --> Destroying 11:18:40.009 icetp00 .TURN client state changed Resolved --> Destroying 11:18:40.009 icetp00 ..Comp 2/2: TURN resolving (tpid=129) failed 11:18:40.009 tcprel0x12a192 .Request to shutdown in state Resolved, cause:0 11:18:40.009 tcprel0x12a192 .State changed Resolved --> Destroying 11:18:40.009 icetp00 .TURN client state changed Resolved --> Destroying 11:18:40.009 icetp00 ..Comp 2/3: TURN resolving (tpid=130) failed 11:18:40.010 pjsua_media.c Call 0: media transport initialization complete: gethostbyname() has returned error (PJ_ERESOLVE) 11:18:40.010 pjsua_media.c Call 0: cleaning up provisional media, prov_med_cnt=1, med_cnt=0 11:18:40.010 dlg0x12a8d54a8 .Session count dec to 2 by mod-pjsua 11:18:40.010 pjsua_call.c Error initializing media channel: gethostbyname() has returned error (PJ_ERESOLVE) [status=70018] 11:18:40.010 AKSIPUserAgent Call 0 is DISCONNECTED [reason = 480 (Media init error: gethostbyname() has returned error (PJ_ERESOLVE))] Alex. |
_______________________________________________ Visit our blog: http://blog.pjsip.org pjsip mailing list pjsip@xxxxxxxxxxxxxxx http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org
