Hello, When trying to send packets from icedemo peer to peer using relay it gives PJNATH_EINSTUNMSGLEN after sending few successful packets. Turn/Stun Server: TurnServer project from SourceForge PJSIP client version: 3021 I added log to see the actual length and expected one and in all message it seems different. 14:11:44.718 stun_msg.c Invalid stun length; actual=12, exp=20 Please let me know what could be the possible cause of this problem. Thanks Sarun.M.N. Log: 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\Research\WyseNAT\PJSIP\pjsip-apps\bin\sample-debug-i386-Win32-vc8-Debug.exe', Symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\ntdll.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\kernel32.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\ws2_32.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\msvcrt.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\ws2help.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\advapi32.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\rpcrt4.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\secur32.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\ole32.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\gdi32.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\user32.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\imm32.dll', No symbols loaded. 14:07:59.984 os_core_win32. pjlib 1.5 for win32 initialized 14:07:59.984 pjlib select() I/O Queue created (003728A4) 14:08:01.843 icedemo Creating ICE stream transport with 1 component(s) 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\mswsock.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\hnetcfg.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\wshtcpip.dll', No symbols loaded. 14:08:01.890 icedemo Comp 1: srflx candidate starts Binding discovery 14:08:01.890 stuntp00373350 TX 36 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Binding request Hdr: length=16, magic=2112a442, tsx_id=6784482372ae3d6c00015f90 Attributes: SOFTWARE: length=10, value="pjnath-1.5" --- end of STUN message --- 14:08:01.890 stuntsx0037ACF STUN client transaction created 14:08:01.890 stuntsx0037ACF STUN sending message (transmit count=1) 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\wshbth.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\setupapi.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\dnsapi.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\winrnr.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\wldap32.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\Program Files\Bonjour\mdnsNSP.dll', No symbols loaded. 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\iphlpapi.dll', No symbols loaded. 14:08:02.046 stuntsx0037ACF STUN sending message (transmit count=2) 'sample-debug-i386-Win32-vc8-Debug.exe': Loaded 'C:\WINDOWS\system32\rasadhlp.dll', No symbols loaded. 14:08:02.078 icedemo Comp 1: host candidate 192.168.0.110:1160 added 14:08:02.078 icedemo Comp 1: host candidate 192.168.174.1:1160 added 14:08:02.078 icedemo Comp 1: host candidate 192.168.80.1:1160 added 14:08:02.078 tcprel0037B028 TURN client session created 14:08:02.078 tcprel0037B028 Resolving 10.100.104.27 with DNS A 14:08:02.078 tcprel0037B028 State changed Null --> Resolving 14:08:02.078 icedemo TURN client state changed Null --> Resolving 14:08:02.078 tcprel0037B028 State changed Resolving --> Resolved 14:08:02.078 icedemo TURN client state changed Resolving --> Resolved 14:08:02.078 tcprel0037B028 Connecting to 10.100.104.27:3478 14:08:02.078 icedemo Comp 1: TURN relay candidate waiting for allocation 14:08:02.078 icedemo ICE stream transport created 14:08:02.078 icedemo.c ICE instance successfully created 14:08:02.250 stuntsx0037ACF STUN sending message (transmit count=3) 14:08:02.250 tcprel0037B028 TCP connected 14:08:02.250 tcprel0037B028 State changed Resolved --> Allocating 14:08:02.250 icedemo TURN client state changed Resolved --> Allocating 14:08:02.250 tcprel0037B028 TX 44 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Allocate request Hdr: length=24, magic=2112a442, tsx_id=000005dc18be678400294823 Attributes: REQUESTED-TRANSPORT: length=4, value=285212672 (0x11000000) SOFTWARE: length=10, value="pjnath-1.5" --- end of STUN message --- 14:08:02.250 stuntsx0037F08 STUN client transaction created 14:08:02.250 stuntsx0037F08 STUN sending message (transmit count=8) 14:08:02.250 tcprel0037B028 STUN msg_decode() sucess: Success 14:08:02.250 tcprel0037B028 RX 140 bytes STUN message from 10.100.104.27:3478: --- begin STUN message --- STUN Allocate error response Hdr: length=120, magic=2112a442, tsx_id=000005dc18be678400294823 Attributes: ERROR-CODE: length=20, err_code=401, reason="Unauthorized" REALM: length=12, value="sip.wyse.com" NONCE: length=48, value="4b1844be0000000091401de2b4bb8274f3f4d89393f5dd88" SOFTWARE: length=14, value="TurnServer 0.3" FINGERPRINT: length=4, value=212040967 (0xca37d07) --- end of STUN message --- 14:08:02.250 tcprel0037B028 Retrying request with new authentication 14:08:02.250 tcprel0037B028 TX 152 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Allocate request Hdr: length=132, magic=2112a442, tsx_id=000005dc4ae13d6c00294824 Attributes: REQUESTED-TRANSPORT: length=4, value=285212672 (0x11000000) SOFTWARE: length=10, value="pjnath-1.5" USERNAME: length=11, value="snandakumar" REALM: length=12, value="sip.wyse.com" NONCE: length=48, value="4b1844be0000000091401de2b4bb8274f3f4d89393f5dd88" MESSAGE-INTEGRITY: length=20, data=70d54bbbc8fcf12e00e2141c2fe9daa4faa41903 --- end of STUN message --- 14:08:02.250 stuntsx0037F2C STUN client transaction created 14:08:02.250 stuntsx0037F2C STUN sending message (transmit count=8) 14:08:02.250 tcprel0037B028 STUN msg_decode() sucess: Success 14:08:02.265 tcprel0037B028 RX 104 bytes STUN message from 10.100.104.27:3478: --- begin STUN message --- STUN Allocate success response Hdr: length=84, magic=2112a442, tsx_id=000005dc4ae13d6c00294824 Attributes: XOR-RELAYED-ADDRESS: length=8, IPv4 addr=10.100.104.27:63113 LIFETIME: length=4, value=1800 (0x708) XOR-MAPPED-ADDRESS: length=8, IPv4 addr=10.100.7.217:1163 SOFTWARE: length=14, value="TurnServer 0.3" MESSAGE-INTEGRITY: length=20, data=5beff43e1edc12381c6adb0ca0bebd83f9366260 FINGERPRINT: length=4, value=2569936351 (0x992e1ddf) --- end of STUN message --- 14:08:02.265 tcprel0037B028 State changed Allocating --> Ready 14:08:02.265 icedemo TURN client state changed Allocating --> Ready 14:08:02.265 icedemo Comp 1: TURN allocation complete, relay address is 10.100.104.27:63113 14:08:02.656 stuntsx0037ACF STUN sending message (transmit count=4) 14:08:03.468 stuntsx0037ACF STUN sending message (transmit count=5) 14:08:04.250 stuntsx0037F08 STUN client transaction destroyed 14:08:04.265 stuntsx0037F2C STUN client transaction destroyed 14:08:05.078 stuntsx0037ACF STUN sending message (transmit count=6) 14:08:05.609 icedemo ICE session created, comp_cnt=1, role is Controlling agent 14:08:05.609 icedemo ICE nomination type set to aggressive 14:08:05.609 icedemo Disabling STUN Indication logging for component 1 14:08:05.609 icedemo Candidate 0 of comp 0 is not added (pending) 14:08:05.609 icedemo Candidate 0 added: comp_id=1, type=host, foundation=Hc0a8006e, addr=192.168.0.110:1160, base=192.168.0.110:1160, prio=0x7effffff (2130706431) 14:08:05.609 icedemo Candidate 1 added: comp_id=1, type=host, foundation=Hc0a8ae01, addr=192.168.174.1:1160, base=192.168.174.1:1160, prio=0x7effffff (2130706431) 14:08:05.609 icedemo Candidate 2 added: comp_id=1, type=host, foundation=Hc0a85001, addr=192.168.80.1:1160, base=192.168.80.1:1160, prio=0x7effffff (2130706431) 14:08:05.609 icedemo Candidate 3 added: comp_id=1, type=relay, foundation=Ra64681b, addr=10.100.104.27:63113, base=10.100.104.27:63113, prio=0xffffff (16777215) 14:08:05.609 icedemo.c ICE session created 14:08:08.281 stuntsx0037ACF STUN sending message (transmit count=7) 14:08:09.890 stuntsx0037ACF STUN timeout waiting for response 14:08:09.890 stuntp00373350 Session failed because STUN Binding request failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:08:09.890 icedemo STUN binding request failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:08:09.890 icedemo.c ICE initialization failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:08:11.890 stuntsx0037ACF STUN client transaction destroyed 14:08:17.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc2cd672ae00294825 Attributes: DATA: length=0, data= --- end of STUN message --- 14:08:32.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc69525f9000294826 Attributes: DATA: length=0, data= --- end of STUN message --- 14:08:47.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc16496df100294827 Attributes: DATA: length=0, data= --- end of STUN message --- 14:09:02.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc5af141bb00294828 Attributes: DATA: length=0, data= --- end of STUN message --- 14:09:17.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc26e901eb00294829 Attributes: DATA: length=0, data= --- end of STUN message --- 14:09:32.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc0bb32ea60029482a Attributes: DATA: length=0, data= --- end of STUN message --- 14:09:47.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc12db153c0029482b Attributes: DATA: length=0, data= --- end of STUN message --- 14:10:02.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc7e87390c0029482c Attributes: DATA: length=0, data= --- end of STUN message --- 14:10:17.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc0f3e00990029482d Attributes: DATA: length=0, data= --- end of STUN message --- 14:10:32.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc0124305e0029482e Attributes: DATA: length=0, data= --- end of STUN message --- 14:10:47.265 tcprel0037B028 TX 24 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Send indication Hdr: length=4, magic=2112a442, tsx_id=000005dc440d491c0029482f Attributes: DATA: length=0, data= --- end of STUN message --- 14:10:53.015 icedemo.c Done, 2 remote candidate(s) added 14:10:55.187 icedemo.c Starting ICE negotiation.. 14:10:55.187 icedemo Checklist created: 14:10:55.187 icedemo 0: [1] 192.168.0.110:1160-->10.100.7.25:1083 (not nominated, state=Frozen) 14:10:55.187 icedemo 1: [1] 192.168.174.1:1160-->10.100.7.25:1083 (not nominated, state=Frozen) 14:10:55.187 icedemo 2: [1] 192.168.80.1:1160-->10.100.7.25:1083 (not nominated, state=Frozen) 14:10:55.187 icedemo 3: [1] 192.168.174.1:1160-->10.100.104.27:54576 (not nominated, state=Frozen) 14:10:55.187 icedemo 4: [1] 192.168.0.110:1160-->10.100.104.27:54576 (not nominated, state=Frozen) 14:10:55.187 icedemo 5: [1] 192.168.80.1:1160-->10.100.104.27:54576 (not nominated, state=Frozen) 14:10:55.187 icedemo 6: [1] 10.100.104.27:63113-->10.100.7.25:1083 (not nominated, state=Frozen) 14:10:55.187 icedemo 7: [1] 10.100.104.27:63113-->10.100.104.27:54576 (not nominated, state=Frozen) 14:10:55.187 tcprel0037B028 TX 168 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN CreatePermission request Hdr: length=148, magic=2112a442, tsx_id=000005dc7e87390c00294830 Attributes: XOR-PEER-ADDRESS: length=8, IPv4 addr=10.100.7.25:1083 XOR-PEER-ADDRESS: length=8, IPv4 addr=10.100.104.27:54576 SOFTWARE: length=10, value="pjnath-1.5" USERNAME: length=11, value="snandakumar" REALM: length=12, value="sip.wyse.com" NONCE: length=48, value="4b1844be0000000091401de2b4bb8274f3f4d89393f5dd88" MESSAGE-INTEGRITY: length=20, data=346de3d95f5f5ba298d0aeaaf81b85bf584d2fb3 --- end of STUN message --- 14:10:55.187 stuntsx0037FD5 STUN client transaction created 14:10:55.187 stuntsx0037FD5 STUN sending message (transmit count=8) 14:10:55.187 icedemo Starting ICE check.. 14:10:55.187 icedemo Check 0: [1] 192.168.0.110:1160-->10.100.7.25:1083: state changed from Frozen to Waiting 14:10:55.187 icedemo Check 1: [1] 192.168.174.1:1160-->10.100.7.25:1083: state changed from Frozen to Waiting 14:10:55.187 icedemo Check 2: [1] 192.168.80.1:1160-->10.100.7.25:1083: state changed from Frozen to Waiting 14:10:55.187 icedemo Check 6: [1] 10.100.104.27:63113-->10.100.7.25:1083: state changed from Frozen to Waiting 14:10:55.187 icedemo.c ICE negotiation started 14:10:55.187 tcprel0037B028 STUN msg_decode() sucess: Success 14:10:55.203 tcprel0037B028 RX 72 bytes STUN message from 10.100.104.27:3478: --- begin STUN message --- STUN CreatePermission success response Hdr: length=52, magic=2112a442, tsx_id=000005dc7e87390c00294830 Attributes: SOFTWARE: length=14, value="TurnServer 0.3" MESSAGE-INTEGRITY: length=20, data=f40843737447b5fab927c5d2d7ae251f831e2ae6 FINGERPRINT: length=4, value=655367305 (0x27101c89) --- end of STUN message --- 14:10:55.203 icedemo Checklist: state changed from Idle to Running 14:10:55.203 icedemo Starting checklist periodic check 14:10:55.203 icedemo Sending connectivity check for check 0: [1] 192.168.0.110:1160-->10.100.7.25:1083 14:10:55.203 stuse0037EB38 TX 116 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding request Hdr: length=96, magic=2112a442, tsx_id=000005dc4d064db700294831 Attributes: PRIORITY: length=4, value=1862270975 (0x6effffff) USE-CANDIDATE: length=0 ICE-CONTROLLING: length=8, data=5af141bb26e901eb SOFTWARE: length=10, value="pjnath-1.5" USERNAME: length=17, value="0bb32ea6:0bb32ea6" MESSAGE-INTEGRITY: length=20, data=24be2445b885cc580ac06f1eca1302cccc50fa76 FINGERPRINT: length=4, value=4115071559 (0xf5470247) --- end of STUN message --- 14:10:55.203 stuntsx0037AB0 STUN client transaction created 14:10:55.203 stuntsx0037AB0 STUN sending message (transmit count=1) 14:10:55.203 icedemo Check 0: [1] 192.168.0.110:1160-->10.100.7.25:1083: state changed from Waiting to In Progress 14:10:55.234 icedemo Starting checklist periodic check 14:10:55.234 icedemo Sending connectivity check for check 1: [1] 192.168.174.1:1160-->10.100.7.25:1083 14:10:55.234 stuse0037EB38 TX 116 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding request Hdr: length=96, magic=2112a442, tsx_id=000005dc154754de00294832 Attributes: PRIORITY: length=4, value=1862270975 (0x6effffff) USE-CANDIDATE: length=0 ICE-CONTROLLING: length=8, data=5af141bb26e901eb SOFTWARE: length=10, value="pjnath-1.5" USERNAME: length=17, value="0bb32ea6:0bb32ea6" MESSAGE-INTEGRITY: length=20, data=feaf34b6405e4d0400ef6e8eb1d00c746cf457ac FINGERPRINT: length=4, value=3683383703 (0xdb8bf997) --- end of STUN message --- 14:10:55.234 stuntsx00C11E4 STUN client transaction created 14:10:55.234 stuntsx00C11E4 STUN sending message (transmit count=1) 14:10:55.234 icedemo Check 1: [1] 192.168.174.1:1160-->10.100.7.25:1083: state changed from Waiting to In Progress 14:10:55.265 icedemo Starting checklist periodic check 14:10:55.265 icedemo Sending connectivity check for check 2: [1] 192.168.80.1:1160-->10.100.7.25:1083 14:10:55.265 stuse0037EB38 TX 116 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding request Hdr: length=96, magic=2112a442, tsx_id=000005dc39b32d1200294833 Attributes: PRIORITY: length=4, value=1862270975 (0x6effffff) USE-CANDIDATE: length=0 ICE-CONTROLLING: length=8, data=5af141bb26e901eb SOFTWARE: length=10, value="pjnath-1.5" USERNAME: length=17, value="0bb32ea6:0bb32ea6" MESSAGE-INTEGRITY: length=20, data=9df9254f7c2de268a55269615ae656c0c3431e10 FINGERPRINT: length=4, value=1591777305 (0x5ee09819) --- end of STUN message --- 14:10:55.265 stuntsx00C126A STUN client transaction created 14:10:55.265 stuntsx00C126A STUN sending message (transmit count=1) 14:10:55.265 icedemo Check 2: [1] 192.168.80.1:1160-->10.100.7.25:1083: state changed from Waiting to In Progress 14:10:55.296 icedemo Starting checklist periodic check 14:10:55.296 icedemo Sending connectivity check for check 6: [1] 10.100.104.27:63113-->10.100.7.25:1083 14:10:55.296 stuse0037EB38 TX 116 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding request Hdr: length=96, magic=2112a442, tsx_id=000005dc074d4dc800294834 Attributes: PRIORITY: length=4, value=1862270975 (0x6effffff) USE-CANDIDATE: length=0 ICE-CONTROLLING: length=8, data=5af141bb26e901eb SOFTWARE: length=10, value="pjnath-1.5" USERNAME: length=17, value="0bb32ea6:0bb32ea6" MESSAGE-INTEGRITY: length=20, data=26396acfc600b5d1f591829c5b424e371c7a2695 FINGERPRINT: length=4, value=1712258950 (0x660eff86) --- end of STUN message --- 14:10:55.296 stuntsx00C12F1 STUN client transaction created 14:10:55.296 stuntsx00C12F1 STUN sending message (transmit count=1) 14:10:55.296 icedemo Check 6: [1] 10.100.104.27:63113-->10.100.7.25:1083: state changed from Waiting to In Progress 14:10:55.296 tcprel0037B028 STUN msg_decode() sucess: Success 14:10:55.312 tcprel0037B028 RX 116 bytes STUN message from 10.100.104.27:3478: --- begin STUN message --- STUN Data indication Hdr: length=96, magic=2112a442, tsx_id=4d41a6267b8a5c16336e8ac4 Attributes: XOR-PEER-ADDRESS: length=8, IPv4 addr=10.100.7.25:1083 DATA: length=80, data=0101003c2112a442dc050000c84d4d0734482900002000080001d79b2b76cc598022000c706a6e6174682d312e35000000080014ddba449b30a8f1b2e398e1bbf044012ef0d13fc6802800049f61b57a --- end of STUN message --- 14:10:55.312 stuse0037EB38 STUN msg_decode() sucess: Success 14:10:55.312 stuse0037EB38 RX 80 bytes STUN message from 10.100.7.25:1083: --- begin STUN message --- STUN Binding success response Hdr: length=60, magic=2112a442, tsx_id=000005dc074d4dc800294834 Attributes: XOR-MAPPED-ADDRESS: length=8, IPv4 addr=10.100.104.27:63113 SOFTWARE: length=12, value="pjnath-1.5" MESSAGE-INTEGRITY: length=20, data=ddba449b30a8f1b2e398e1bbf044012ef0d13fc6 FINGERPRINT: length=4, value=2673980794 (0x9f61b57a) --- end of STUN message --- 14:10:55.312 icedemo Check 6: [1] 10.100.104.27:63113-->10.100.7.25:1083 (nominated): connectivity check SUCCESS 14:10:55.312 icedemo Check 6: [1] 10.100.104.27:63113-->10.100.7.25:1083: state changed from In Progress to Succeeded 14:10:55.312 icedemo Check 7: [1] 10.100.104.27:63113-->10.100.104.27:54576: state changed from Frozen to Waiting 14:10:55.312 icedemo Check 6 is successful and nominated 14:10:55.312 icedemo Cancelling check 0: [1] 192.168.0.110:1160-->10.100.7.25:1083 (In Progress) 14:10:55.312 icedemo Check 0: [1] 192.168.0.110:1160-->10.100.7.25:1083: state changed from In Progress to Failed 14:10:55.312 icedemo Cancelling check 1: [1] 192.168.174.1:1160-->10.100.7.25:1083 (In Progress) 14:10:55.312 icedemo Check 1: [1] 192.168.174.1:1160-->10.100.7.25:1083: state changed from In Progress to Failed 14:10:55.312 icedemo Cancelling check 2: [1] 192.168.80.1:1160-->10.100.7.25:1083 (In Progress) 14:10:55.312 icedemo Check 2: [1] 192.168.80.1:1160-->10.100.7.25:1083: state changed from In Progress to Failed 14:10:55.312 icedemo Check 3: [1] 192.168.174.1:1160-->10.100.104.27:54576 to be failed because state is Frozen 14:10:55.312 icedemo Check 3: [1] 192.168.174.1:1160-->10.100.104.27:54576: state changed from Frozen to Failed 14:10:55.312 icedemo Check 4: [1] 192.168.0.110:1160-->10.100.104.27:54576 to be failed because state is Frozen 14:10:55.312 icedemo Check 4: [1] 192.168.0.110:1160-->10.100.104.27:54576: state changed from Frozen to Failed 14:10:55.312 icedemo Check 5: [1] 192.168.80.1:1160-->10.100.104.27:54576 to be failed because state is Frozen 14:10:55.312 icedemo Check 5: [1] 192.168.80.1:1160-->10.100.104.27:54576: state changed from Frozen to Failed 14:10:55.312 icedemo Check 7: [1] 10.100.104.27:63113-->10.100.104.27:54576 to be failed because state is Waiting 14:10:55.312 icedemo Check 7: [1] 10.100.104.27:63113-->10.100.104.27:54576: state changed from Waiting to Failed 14:10:55.312 icedemo ICE process complete, status=Success 14:10:55.312 icedemo Valid list 14:10:55.312 icedemo 0: [1] 10.100.104.27:63113-->10.100.7.25:1083 (nominated, state=Succeeded) 14:10:55.312 icedemo ICE negotiation success after 0s:125 14:10:55.312 tcprel0037B028 TX 164 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN ChannelBind request Hdr: length=144, magic=2112a442, tsx_id=000005dc428b26a600294835 Attributes: CHANNEL-NUMBER: length=4, chnum=16384 (0x4000) XOR-PEER-ADDRESS: length=8, IPv4 addr=10.100.7.25:1083 SOFTWARE: length=10, value="pjnath-1.5" USERNAME: length=11, value="snandakumar" REALM: length=12, value="sip.wyse.com" NONCE: length=48, value="4b1844be0000000091401de2b4bb8274f3f4d89393f5dd88" MESSAGE-INTEGRITY: length=20, data=8db488e8b9e66cd5d9cff50cca629857fe70305e --- end of STUN message --- 14:10:55.312 stuntsx00C1377 STUN client transaction created 14:10:55.312 stuntsx00C1377 STUN sending message (transmit count=8) 14:10:55.312 icedemo Disabling STUN Indication logging for component 1 14:10:55.328 icedemo Comp 1: sending from relay candidate 10.100.104.27:63113 to host candidate 10.100.7.25:1083 14:10:55.328 icedemo.c ICE negotiation successful 14:10:55.328 tcprel0037B028 STUN msg_decode() sucess: Success 14:10:55.328 tcprel0037B028 RX 72 bytes STUN message from 10.100.104.27:3478: --- begin STUN message --- STUN ChannelBind success response Hdr: length=52, magic=2112a442, tsx_id=000005dc428b26a600294835 Attributes: SOFTWARE: length=14, value="TurnServer 0.3" MESSAGE-INTEGRITY: length=20, data=ce7aeb95d0b68bdac1c0c6b3d69ca8d520e9c0a1 FINGERPRINT: length=4, value=3976741508 (0xed084284) --- end of STUN message --- 14:10:55.328 icedemo Starting checklist periodic check 14:10:57.203 stuntsx0037FD5 STUN client transaction destroyed 14:10:57.312 stuntsx00C12F1 STUN client transaction destroyed 14:10:57.312 stuntsx0037AB0 STUN client transaction destroyed 14:10:57.312 stuntsx00C126A STUN client transaction destroyed 14:10:57.312 stuntsx00C11E4 STUN client transaction destroyed 14:10:57.328 stuntsx00C1377 STUN client transaction destroyed 14:10:57.562 stuse0037EB38 STUN msg_decode() sucess: Success 14:10:57.562 stuse0037EB38 RX 112 bytes STUN message from 10.100.7.25:1083: --- begin STUN message --- STUN Binding request Hdr: length=92, magic=2112a442, tsx_id=00000a080124305e0029482a Attributes: PRIORITY: length=4, value=1862270975 (0x6effffff) ICE-CONTROLLED: length=8, data=5af141bb26e901eb SOFTWARE: length=12, value="pjnath-1.5" USERNAME: length=17, value="0bb32ea6:0bb32ea6" MESSAGE-INTEGRITY: length=20, data=3b95a6fbb554df92d94730f6edcec8bbed8c4f40 FINGERPRINT: length=4, value=2848339757 (0xa9c6372d) --- end of STUN message --- 14:10:57.562 stuse0037EB38 TX 80 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding success response Hdr: length=60, magic=2112a442, tsx_id=00000a080124305e0029482a Attributes: XOR-MAPPED-ADDRESS: length=8, IPv4 addr=10.100.7.25:1083 SOFTWARE: length=10, value="pjnath-1.5" MESSAGE-INTEGRITY: length=20, data=bc7e6739500c8e893c302c0b7470d16c03c5940a FINGERPRINT: length=4, value=3858696677 (0xe5ff09e5) --- end of STUN message --- 14:10:57.562 icedemo Triggered check for check 6 not performed because it's completed 14:10:57.562 icedemo Check 6 is successful and nominated 14:11:07.562 stuse0037EB38 Response cache deleted 14:11:16.312 stuse0037EB38 TX 20 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=000005dc7a5a767d00294837 Attributes: --- end of STUN message --- 14:11:19.656 stuse0037EB38 STUN msg_decode() sucess: Success 14:11:19.656 stuse0037EB38 RX 20 bytes STUN message from 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=00000a080f3e00990029482e Attributes: --- end of STUN message --- 14:11:19.656 icedemo Received Binding Indication keep-alive for component 1 14:11:27.812 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:27.812 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:30.781 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:30.781 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:32.156 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:32.156 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:33.046 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:33.046 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:33.937 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:33.937 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:36.625 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:36.625 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:38.281 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:38.281 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:38.312 stuse0037EB38 TX 20 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=000005dc63cb6bfc0029483a Attributes: --- end of STUN message --- 14:11:39.218 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:39.218 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:40.093 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:40.109 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:40.921 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:40.921 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:41.750 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:41.750 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:42.468 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:42.484 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:43.281 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:43.281 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:43.656 stuse0037EB38 STUN msg_decode() sucess: Success 14:11:43.656 stuse0037EB38 RX 20 bytes STUN message from 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=00000a08154754de00294831 Attributes: --- end of STUN message --- 14:11:43.656 icedemo Received Binding Indication keep-alive for component 1 14:11:44.718 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:44.718 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:45.859 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:45.859 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:46.687 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:46.687 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:48.093 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:48.093 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:56.406 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:56.515 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:57.609 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:57.609 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:58.437 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:58.437 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:59.156 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:59.156 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:11:59.890 stun_msg.c Invalid stun length; actual=12, exp=20 14:11:59.906 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:12:00.625 stun_msg.c Invalid stun length; actual=12, exp=20 14:12:00.625 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:12:01.390 stun_msg.c Invalid stun length; actual=12, exp=20 14:12:01.390 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:12:02.140 stun_msg.c Invalid stun length; actual=12, exp=20 14:12:02.140 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:12:02.312 stuse0037EB38 TX 20 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=000005dc6b89030a0029483d Attributes: --- end of STUN message --- 14:12:02.875 stun_msg.c Invalid stun length; actual=12, exp=20 14:12:02.875 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:12:04.468 stun_msg.c Invalid stun length; actual=12, exp=20 14:12:04.468 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:12:05.312 stun_msg.c Invalid stun length; actual=12, exp=20 14:12:05.312 icedemo.c Component 1: received 12 bytes data from 10.100.7.25:1083: ""hello 1234"" 14:12:07.656 stuse0037EB38 STUN msg_decode() sucess: Success 14:12:07.656 stuse0037EB38 RX 20 bytes STUN message from 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=00000a08644366bb00294833 Attributes: --- end of STUN message --- 14:12:07.656 icedemo Received Binding Indication keep-alive for component 1 14:12:23.312 stuse0037EB38 TX 20 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=000005dc0120759a0029483f Attributes: --- end of STUN message --- 14:12:31.671 stuse0037EB38 STUN msg_decode() sucess: Success 14:12:31.671 stuse0037EB38 RX 20 bytes STUN message from 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=00000a084509123800294839 Attributes: --- end of STUN message --- 14:12:31.671 icedemo Received Binding Indication keep-alive for component 1 14:12:32.843 stun_msg.c Invalid stun length; actual=14, exp=20 14:12:32.843 icedemo.c Component 1: received 14 bytes data from 10.100.7.25:1083: ""helllo 98765"" 14:12:35.046 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:12:37.265 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:12:38.921 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:12:43.359 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:12:45.312 stuse0037EB38 TX 20 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=000005dc6b365cfd00294841 Attributes: --- end of STUN message --- 14:12:47.171 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:12:48.515 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:12:53.671 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) The thread 'Win32 Thread' (0x14f4) has exited with code 0 (0x0). 14:13:09.312 stuse0037EB38 TX 20 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=000005dc5f490ddc00294844 Attributes: --- end of STUN message --- 14:13:09.890 stuntp00373350 TX 36 bytes STUN message to 10.100.104.27:3478: --- begin STUN message --- STUN Binding request Hdr: length=16, magic=2112a442, tsx_id=6784482372ae3d6c00025f90 Attributes: SOFTWARE: length=10, value="pjnath-1.5" --- end of STUN message --- 14:13:09.890 stuntsx0037A8B STUN client transaction created 14:13:09.890 stuntsx0037A8B STUN sending message (transmit count=1) 14:13:10.000 stuntsx0037A8B STUN sending message (transmit count=2) 14:13:10.203 stuntsx0037A8B STUN sending message (transmit count=3) 14:13:10.609 stuntsx0037A8B STUN sending message (transmit count=4) 14:13:11.421 stuntsx0037A8B STUN sending message (transmit count=5) 14:13:13.031 stuntsx0037A8B STUN sending message (transmit count=6) 14:13:16.234 stuntsx0037A8B STUN sending message (transmit count=7) 14:13:17.671 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:13:17.843 stuntsx0037A8B STUN timeout waiting for response 14:13:17.843 stuntp00373350 Session failed because STUN Binding request failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:13:17.843 icedemo STUN binding request failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:13:19.843 stuntsx0037A8B STUN client transaction destroyed 14:13:21.265 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:13:31.312 stuse0037EB38 TX 20 bytes STUN message to 10.100.7.25:1083: --- begin STUN message --- STUN Binding indication Hdr: length=0, magic=2112a442, tsx_id=000005dc49442e4000294846 Attributes: --- end of STUN message --- 14:13:32.937 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:13:35.343 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:13:36.765 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) 14:13:38.671 tcprel0037B028 STUN msg_decode() error: Invalid STUN message length (PJNATH_EINSTUNMSGLEN) The program '[1500] sample-debug-i386-Win32-vc8-Debug.exe: Native' has exited with code 0 (0x0).
